builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1461329248.75 results: success (0) buildid: 20160419230832 builduid: 1ab072c2db0d4b259b7f46e10e93ac05 revision: d42b25947fc26a0f9eab98d631465b72769553bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:28.748589) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:28.749039) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-04-22 05:47:28.749317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-04-22 05:47:36.153086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-22 05:47:36.153427) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j0JeRqySaq/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-22 05:47:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.3M=0.001s 2016-04-22 05:47:36 (17.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142489 ========= master_lag: 6.84 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-22 05:47:43.133264) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:43.135887) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j0JeRqySaq/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.027491 ========= master_lag: 0.73 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:43.890426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 05:47:43.892842) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d42b25947fc26a0f9eab98d631465b72769553bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d42b25947fc26a0f9eab98d631465b72769553bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j0JeRqySaq/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-22 05:47:43,972 truncating revision to first 12 chars 2016-04-22 05:47:43,972 Setting DEBUG logging. 2016-04-22 05:47:43,973 attempt 1/10 2016-04-22 05:47:43,973 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d42b25947fc2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 05:47:45,028 unpacking tar archive at: fx-team-d42b25947fc2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.301125 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 05:47:45.270300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:45.270647) ========= script_repo_revision: d42b25947fc26a0f9eab98d631465b72769553bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:45.271044) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:45.271319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:45.332371) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-22 05:47:45.332720) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j0JeRqySaq/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:47:45 INFO - MultiFileLogger online at 20160422 05:47:45 in /builds/slave/test 05:47:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:47:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:47:45 INFO - {'append_to_log': False, 05:47:45 INFO - 'base_work_dir': '/builds/slave/test', 05:47:45 INFO - 'blob_upload_branch': 'Fx-Team', 05:47:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:47:45 INFO - 'branch': 'Fx-Team', 05:47:45 INFO - 'buildbot_json_path': 'buildprops.json', 05:47:45 INFO - 'config_files': ('talos/mac_config.py',), 05:47:45 INFO - 'default_actions': ('clobber', 05:47:45 INFO - 'read-buildbot-config', 05:47:45 INFO - 'download-and-extract', 05:47:45 INFO - 'populate-webroot', 05:47:45 INFO - 'create-virtualenv', 05:47:45 INFO - 'install', 05:47:45 INFO - 'run-tests'), 05:47:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:47:45 INFO - 'download_minidump_stackwalk': True, 05:47:45 INFO - 'download_symbols': 'ondemand', 05:47:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:47:45 INFO - 'tooltool.py': '/tools/tooltool.py', 05:47:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:47:45 INFO - '/tools/misc-python/virtualenv.py')}, 05:47:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:47:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:47:45 INFO - 'installer_path': 'installer.exe', 05:47:45 INFO - 'log_level': 'info', 05:47:45 INFO - 'log_name': 'talos', 05:47:45 INFO - 'log_to_console': True, 05:47:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:47:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:47:45 INFO - 'opt_config_files': (), 05:47:45 INFO - 'pip_index': False, 05:47:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:45 INFO - 'cmd': ('bash', 05:47:45 INFO - '-c', 05:47:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:45 INFO - 'enabled': True, 05:47:45 INFO - 'halt_on_failure': False, 05:47:45 INFO - 'name': 'check_screen_resolution'},), 05:47:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:45 INFO - 'cmd': ('bash', 05:47:45 INFO - '-c', 05:47:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:45 INFO - 'enabled': True, 05:47:45 INFO - 'halt_on_failure': False, 05:47:45 INFO - 'name': 'check_screen_resolution'},), 05:47:45 INFO - 'run_cmd_checks_enabled': True, 05:47:45 INFO - 'sps_profile': False, 05:47:45 INFO - 'sps_profile_interval': 0, 05:47:45 INFO - 'suite': 'dromaeojs', 05:47:45 INFO - 'system_bits': '32', 05:47:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:47:45 INFO - 'title': 't-yosemite-r7-0018', 05:47:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:47:45 INFO - 'use_talos_json': True, 05:47:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:47:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:47:45 INFO - 'work_dir': 'build'} 05:47:45 INFO - ##### 05:47:45 INFO - ##### Running clobber step. 05:47:45 INFO - ##### 05:47:45 INFO - Running pre-action listener: _resource_record_pre_action 05:47:45 INFO - Running main action method: clobber 05:47:45 INFO - rmtree: /builds/slave/test/build 05:47:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:47:47 INFO - Running post-action listener: _resource_record_post_action 05:47:47 INFO - ##### 05:47:47 INFO - ##### Running read-buildbot-config step. 05:47:47 INFO - ##### 05:47:47 INFO - Running pre-action listener: _resource_record_pre_action 05:47:47 INFO - Running main action method: read_buildbot_config 05:47:47 INFO - Using buildbot properties: 05:47:47 INFO - { 05:47:47 INFO - "project": "", 05:47:47 INFO - "product": "firefox", 05:47:47 INFO - "script_repo_revision": "production", 05:47:47 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:47:47 INFO - "repository": "", 05:47:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:47:47 INFO - "buildid": "20160419230832", 05:47:47 INFO - "pgo_build": "False", 05:47:47 INFO - "basedir": "/builds/slave/test", 05:47:47 INFO - "buildnumber": 755, 05:47:47 INFO - "slavename": "t-yosemite-r7-0018", 05:47:47 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:47:47 INFO - "platform": "yosemite_r7", 05:47:47 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:47:47 INFO - "branch": "fx-team", 05:47:47 INFO - "repo_path": "integration/fx-team", 05:47:47 INFO - "revision": "d42b25947fc26a0f9eab98d631465b72769553bd", 05:47:47 INFO - "stage_platform": "macosx64", 05:47:47 INFO - "builduid": "1ab072c2db0d4b259b7f46e10e93ac05", 05:47:47 INFO - "slavebuilddir": "test" 05:47:47 INFO - } 05:47:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:47:47 INFO - Running post-action listener: _resource_record_post_action 05:47:47 INFO - ##### 05:47:47 INFO - ##### Running download-and-extract step. 05:47:47 INFO - ##### 05:47:47 INFO - Running pre-action listener: _resource_record_pre_action 05:47:47 INFO - Running main action method: download_and_extract 05:47:47 INFO - mkdir: /builds/slave/test/build/tests 05:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:47 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:47:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:47:49 INFO - Downloaded 1199 bytes. 05:47:49 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:49 INFO - Using the following test package requirements: 05:47:49 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:47:49 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:47:49 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:47:49 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'jsshell-mac.zip'], 05:47:49 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:47:49 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:47:49 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:47:49 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:47:49 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:47:49 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:49 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:47:49 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:49 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:47:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:51 INFO - Downloaded 18095480 bytes. 05:47:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:47:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:47:54 INFO - Return code: 0 05:47:54 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:54 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:47:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:55 INFO - Downloaded 18095480 bytes. 05:47:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:47:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:47:59 INFO - Return code: 0 05:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:59 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:47:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:47:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:47:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:48:00 INFO - Downloaded 10845952 bytes. 05:48:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:48:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:48:00 INFO - Return code: 0 05:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:00 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:48:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:48:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:48:03 INFO - Downloaded 110044454 bytes. 05:48:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:03 INFO - mkdir: /builds/slave/test/properties 05:48:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:48:03 INFO - Writing to file /builds/slave/test/properties/build_url 05:48:03 INFO - Contents: 05:48:03 INFO - build_url:https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:04 INFO - Running post-action listener: _resource_record_post_action 05:48:04 INFO - Running post-action listener: set_extra_try_arguments 05:48:04 INFO - ##### 05:48:04 INFO - ##### Running populate-webroot step. 05:48:04 INFO - ##### 05:48:04 INFO - Running pre-action listener: _resource_record_pre_action 05:48:04 INFO - Running main action method: populate_webroot 05:48:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:48:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:48:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:48:04 INFO - '--tppagecycles', 05:48:04 INFO - '7'], 05:48:04 INFO - 'tests': ['tsvgm']}}, 05:48:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:48:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:48:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:48:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:48:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['damp', 'tps']}, 05:48:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'tests': ['damp', 'tps']}, 05:48:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['dromaeo_dom']}, 05:48:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:48:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['a11yr', 05:48:04 INFO - 'ts_paint', 05:48:04 INFO - 'tpaint', 05:48:04 INFO - 'sessionrestore', 05:48:04 INFO - 'sessionrestore_no_auto_restore', 05:48:04 INFO - 'tabpaint']}, 05:48:04 INFO - 'other-e10s': {'tests': ['a11yr', 05:48:04 INFO - 'ts_paint', 05:48:04 INFO - 'tpaint', 05:48:04 INFO - 'sessionrestore', 05:48:04 INFO - 'sessionrestore_no_auto_restore', 05:48:04 INFO - 'tabpaint']}, 05:48:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:48:04 INFO - 'ts_paint', 05:48:04 INFO - 'tpaint', 05:48:04 INFO - 'sessionrestore', 05:48:04 INFO - 'sessionrestore_no_auto_restore', 05:48:04 INFO - 'tabpaint']}, 05:48:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:48:04 INFO - 'ts_paint', 05:48:04 INFO - 'tpaint', 05:48:04 INFO - 'sessionrestore', 05:48:04 INFO - 'sessionrestore_no_auto_restore', 05:48:04 INFO - 'tabpaint']}, 05:48:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['a11yr', 05:48:04 INFO - 'ts_paint', 05:48:04 INFO - 'tpaint', 05:48:04 INFO - 'sessionrestore', 05:48:04 INFO - 'sessionrestore_no_auto_restore', 05:48:04 INFO - 'tabpaint']}, 05:48:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['a11yr', 05:48:04 INFO - 'ts_paint', 05:48:04 INFO - 'tpaint', 05:48:04 INFO - 'sessionrestore', 05:48:04 INFO - 'sessionrestore_no_auto_restore', 05:48:04 INFO - 'tabpaint']}, 05:48:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['tsvgx', 05:48:04 INFO - 'tsvgr_opacity', 05:48:04 INFO - 'tart', 05:48:04 INFO - 'tscrollx', 05:48:04 INFO - 'cart']}, 05:48:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:48:04 INFO - 'tsvgr_opacity', 05:48:04 INFO - 'tart', 05:48:04 INFO - 'tscrollx', 05:48:04 INFO - 'cart']}, 05:48:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'talos_options': ['--disable-e10s'], 05:48:04 INFO - 'tests': ['tp5o']}, 05:48:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'tests': ['tp5o']}, 05:48:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'talos_options': ['--disable-e10s', 05:48:04 INFO - '--xperf_path', 05:48:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:04 INFO - 'tests': ['tp5n']}, 05:48:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:04 INFO - 'talos_options': ['--xperf_path', 05:48:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:04 INFO - 'tests': ['tp5n']}}, 05:48:04 INFO - 'talos.zip': {'path': '', 05:48:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:48:04 INFO - Running post-action listener: _resource_record_post_action 05:48:04 INFO - ##### 05:48:04 INFO - ##### Running create-virtualenv step. 05:48:04 INFO - ##### 05:48:04 INFO - Running pre-action listener: _resource_record_pre_action 05:48:04 INFO - Running main action method: create_virtualenv 05:48:04 INFO - Creating virtualenv /builds/slave/test/build/venv 05:48:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:48:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:48:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:48:05 INFO - Using real prefix '/tools/python27' 05:48:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:48:05 INFO - Installing distribute.............................................................................................................................................................................................done. 05:48:08 INFO - Installing pip.................done. 05:48:08 INFO - Return code: 0 05:48:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:09 INFO - 'HOME': '/Users/cltbld', 05:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:09 INFO - 'LOGNAME': 'cltbld', 05:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:09 INFO - 'MOZ_NO_REMOTE': '1', 05:48:09 INFO - 'NO_EM_RESTART': '1', 05:48:09 INFO - 'PAGER': '/bin/cat', 05:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:09 INFO - 'PWD': '/builds/slave/test', 05:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:09 INFO - 'SHELL': '/bin/bash', 05:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:09 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:09 INFO - 'USER': 'cltbld', 05:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:09 INFO - 'XPC_FLAGS': '0x0', 05:48:09 INFO - 'XPC_SERVICE_NAME': '0', 05:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:48:09 INFO - Cleaning up... 05:48:09 INFO - Return code: 0 05:48:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:09 INFO - 'HOME': '/Users/cltbld', 05:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:09 INFO - 'LOGNAME': 'cltbld', 05:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:09 INFO - 'MOZ_NO_REMOTE': '1', 05:48:09 INFO - 'NO_EM_RESTART': '1', 05:48:09 INFO - 'PAGER': '/bin/cat', 05:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:09 INFO - 'PWD': '/builds/slave/test', 05:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:09 INFO - 'SHELL': '/bin/bash', 05:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:09 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:09 INFO - 'USER': 'cltbld', 05:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:09 INFO - 'XPC_FLAGS': '0x0', 05:48:09 INFO - 'XPC_SERVICE_NAME': '0', 05:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:09 INFO - Downloading/unpacking psutil>=0.7.1 05:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:48:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:12 INFO - Installing collected packages: psutil 05:48:12 INFO - Running setup.py install for psutil 05:48:12 INFO - building 'psutil._psutil_osx' extension 05:48:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:48:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:48:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:48:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:48:13 INFO - building 'psutil._psutil_posix' extension 05:48:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:48:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:48:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:48:13 INFO - ^ 05:48:13 INFO - 1 warning generated. 05:48:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:48:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:13 INFO - Successfully installed psutil 05:48:13 INFO - Cleaning up... 05:48:13 INFO - Return code: 0 05:48:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:13 INFO - 'HOME': '/Users/cltbld', 05:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:13 INFO - 'LOGNAME': 'cltbld', 05:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:13 INFO - 'MOZ_NO_REMOTE': '1', 05:48:13 INFO - 'NO_EM_RESTART': '1', 05:48:13 INFO - 'PAGER': '/bin/cat', 05:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:13 INFO - 'PWD': '/builds/slave/test', 05:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:13 INFO - 'SHELL': '/bin/bash', 05:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:13 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:13 INFO - 'USER': 'cltbld', 05:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:13 INFO - 'XPC_FLAGS': '0x0', 05:48:13 INFO - 'XPC_SERVICE_NAME': '0', 05:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:48:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:48:16 INFO - Installing collected packages: mozsystemmonitor 05:48:16 INFO - Running setup.py install for mozsystemmonitor 05:48:16 INFO - Successfully installed mozsystemmonitor 05:48:16 INFO - Cleaning up... 05:48:16 INFO - Return code: 0 05:48:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:16 INFO - 'HOME': '/Users/cltbld', 05:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:16 INFO - 'LOGNAME': 'cltbld', 05:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:16 INFO - 'MOZ_NO_REMOTE': '1', 05:48:16 INFO - 'NO_EM_RESTART': '1', 05:48:16 INFO - 'PAGER': '/bin/cat', 05:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:16 INFO - 'PWD': '/builds/slave/test', 05:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:16 INFO - 'SHELL': '/bin/bash', 05:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:16 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:16 INFO - 'USER': 'cltbld', 05:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:16 INFO - 'XPC_FLAGS': '0x0', 05:48:16 INFO - 'XPC_SERVICE_NAME': '0', 05:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:16 INFO - Downloading/unpacking blobuploader==1.2.4 05:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:19 INFO - Downloading blobuploader-1.2.4.tar.gz 05:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:48:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:48:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:20 INFO - Downloading docopt-0.6.1.tar.gz 05:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:48:20 INFO - Installing collected packages: blobuploader, requests, docopt 05:48:20 INFO - Running setup.py install for blobuploader 05:48:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:48:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:48:20 INFO - Running setup.py install for requests 05:48:21 INFO - Running setup.py install for docopt 05:48:21 INFO - Successfully installed blobuploader requests docopt 05:48:21 INFO - Cleaning up... 05:48:21 INFO - Return code: 0 05:48:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:21 INFO - 'HOME': '/Users/cltbld', 05:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:21 INFO - 'LOGNAME': 'cltbld', 05:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:21 INFO - 'MOZ_NO_REMOTE': '1', 05:48:21 INFO - 'NO_EM_RESTART': '1', 05:48:21 INFO - 'PAGER': '/bin/cat', 05:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:21 INFO - 'PWD': '/builds/slave/test', 05:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:21 INFO - 'SHELL': '/bin/bash', 05:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:21 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:21 INFO - 'USER': 'cltbld', 05:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:21 INFO - 'XPC_FLAGS': '0x0', 05:48:21 INFO - 'XPC_SERVICE_NAME': '0', 05:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-IAinoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-9KSk6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nbKhPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-s1nX5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eF5OJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-9ZLk1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-yPAD6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-gfE0zX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-QSdRKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-JHu_Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-j2lbrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eIfzrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:22 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-kkQ95O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:23 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-Q151K3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:23 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-O3EEPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:23 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-96Hfiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:23 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-cCwbiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:48:23 INFO - Running setup.py install for manifestparser 05:48:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:48:23 INFO - Running setup.py install for mozcrash 05:48:23 INFO - Running setup.py install for mozdebug 05:48:23 INFO - Running setup.py install for mozdevice 05:48:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Running setup.py install for mozfile 05:48:24 INFO - Running setup.py install for mozhttpd 05:48:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Running setup.py install for mozinfo 05:48:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Running setup.py install for mozInstall 05:48:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:48:24 INFO - Running setup.py install for mozleak 05:48:24 INFO - Running setup.py install for mozlog 05:48:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:48:25 INFO - Running setup.py install for moznetwork 05:48:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:48:25 INFO - Running setup.py install for mozprocess 05:48:25 INFO - Running setup.py install for mozprofile 05:48:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:48:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:48:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:48:25 INFO - Running setup.py install for mozrunner 05:48:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:48:25 INFO - Running setup.py install for mozscreenshot 05:48:25 INFO - Running setup.py install for moztest 05:48:25 INFO - Running setup.py install for mozversion 05:48:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:48:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:48:26 INFO - Cleaning up... 05:48:26 INFO - Return code: 0 05:48:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:48:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:26 INFO - 'HOME': '/Users/cltbld', 05:48:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:26 INFO - 'LOGNAME': 'cltbld', 05:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:26 INFO - 'MOZ_NO_REMOTE': '1', 05:48:26 INFO - 'NO_EM_RESTART': '1', 05:48:26 INFO - 'PAGER': '/bin/cat', 05:48:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:26 INFO - 'PWD': '/builds/slave/test', 05:48:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:26 INFO - 'SHELL': '/bin/bash', 05:48:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:26 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:26 INFO - 'USER': 'cltbld', 05:48:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:26 INFO - 'XPC_FLAGS': '0x0', 05:48:26 INFO - 'XPC_SERVICE_NAME': '0', 05:48:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nSJL2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-tkv_IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-ff_Pez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-Sl9GEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-uCO_0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-pSD0eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-9CXPFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-zaNgPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-Uxeesh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-d7VfkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-MT7NR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-RM9V5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-JEOhdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-zNaeYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-VNQbSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:27 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nnLia6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:28 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-TpVUFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:48:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:30 INFO - Downloading blessings-1.6.tar.gz 05:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:48:30 INFO - Installing collected packages: blessings 05:48:30 INFO - Running setup.py install for blessings 05:48:30 INFO - Successfully installed blessings 05:48:30 INFO - Cleaning up... 05:48:30 INFO - Return code: 0 05:48:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:48:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:31 INFO - Reading from file tmpfile_stdout 05:48:31 INFO - Current package versions: 05:48:31 INFO - blessings == 1.6 05:48:31 INFO - blobuploader == 1.2.4 05:48:31 INFO - docopt == 0.6.1 05:48:31 INFO - manifestparser == 1.1 05:48:31 INFO - mozInstall == 1.12 05:48:31 INFO - mozcrash == 0.17 05:48:31 INFO - mozdebug == 0.1 05:48:31 INFO - mozdevice == 0.48 05:48:31 INFO - mozfile == 1.2 05:48:31 INFO - mozhttpd == 0.7 05:48:31 INFO - mozinfo == 0.9 05:48:31 INFO - mozleak == 0.1 05:48:31 INFO - mozlog == 3.1 05:48:31 INFO - moznetwork == 0.27 05:48:31 INFO - mozprocess == 0.22 05:48:31 INFO - mozprofile == 0.28 05:48:31 INFO - mozrunner == 6.11 05:48:31 INFO - mozscreenshot == 0.1 05:48:31 INFO - mozsystemmonitor == 0.0 05:48:31 INFO - moztest == 0.7 05:48:31 INFO - mozversion == 1.4 05:48:31 INFO - psutil == 3.1.1 05:48:31 INFO - requests == 1.2.3 05:48:31 INFO - wsgiref == 0.1.2 05:48:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:31 INFO - 'HOME': '/Users/cltbld', 05:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:31 INFO - 'LOGNAME': 'cltbld', 05:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:31 INFO - 'MOZ_NO_REMOTE': '1', 05:48:31 INFO - 'NO_EM_RESTART': '1', 05:48:31 INFO - 'PAGER': '/bin/cat', 05:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:31 INFO - 'PWD': '/builds/slave/test', 05:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:31 INFO - 'SHELL': '/bin/bash', 05:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:31 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:31 INFO - 'USER': 'cltbld', 05:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:31 INFO - 'XPC_FLAGS': '0x0', 05:48:31 INFO - 'XPC_SERVICE_NAME': '0', 05:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:48:31 INFO - Cleaning up... 05:48:31 INFO - Return code: 0 05:48:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:31 INFO - 'HOME': '/Users/cltbld', 05:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:31 INFO - 'LOGNAME': 'cltbld', 05:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:31 INFO - 'MOZ_NO_REMOTE': '1', 05:48:31 INFO - 'NO_EM_RESTART': '1', 05:48:31 INFO - 'PAGER': '/bin/cat', 05:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:31 INFO - 'PWD': '/builds/slave/test', 05:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:31 INFO - 'SHELL': '/bin/bash', 05:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:31 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:31 INFO - 'USER': 'cltbld', 05:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:31 INFO - 'XPC_FLAGS': '0x0', 05:48:31 INFO - 'XPC_SERVICE_NAME': '0', 05:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:31 INFO - Downloading/unpacking jsonschema 05:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:48:34 INFO - Downloading/unpacking functools32 (from jsonschema) 05:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:48:34 INFO - warning: no files found matching '*.txt' 05:48:34 INFO - no previously-included directories found matching 'build' 05:48:34 INFO - no previously-included directories found matching 'dist' 05:48:34 INFO - no previously-included directories found matching '.git*' 05:48:34 INFO - Installing collected packages: jsonschema, functools32 05:48:34 INFO - Running setup.py install for functools32 05:48:34 INFO - warning: no files found matching '*.txt' 05:48:34 INFO - no previously-included directories found matching 'build' 05:48:34 INFO - no previously-included directories found matching 'dist' 05:48:34 INFO - no previously-included directories found matching '.git*' 05:48:34 INFO - Successfully installed jsonschema functools32 05:48:34 INFO - Cleaning up... 05:48:34 INFO - Return code: 0 05:48:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e2e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefc24a42b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:34 INFO - 'HOME': '/Users/cltbld', 05:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:34 INFO - 'LOGNAME': 'cltbld', 05:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:34 INFO - 'MOZ_NO_REMOTE': '1', 05:48:34 INFO - 'NO_EM_RESTART': '1', 05:48:34 INFO - 'PAGER': '/bin/cat', 05:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:34 INFO - 'PWD': '/builds/slave/test', 05:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:34 INFO - 'SHELL': '/bin/bash', 05:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:48:34 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:34 INFO - 'USER': 'cltbld', 05:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:34 INFO - 'XPC_FLAGS': '0x0', 05:48:34 INFO - 'XPC_SERVICE_NAME': '0', 05:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:35 INFO - Downloading/unpacking flake8 05:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:48:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:48:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:48:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:48:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:48:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:48:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:48:38 INFO - no previously-included directories found matching 'docs/_build' 05:48:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:48:38 INFO - Running setup.py install for pep8 05:48:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:48:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:48:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:48:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:48:38 INFO - no previously-included directories found matching 'docs/_build' 05:48:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:48:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:48:39 INFO - Cleaning up... 05:48:39 INFO - Return code: 0 05:48:39 INFO - Running post-action listener: _resource_record_post_action 05:48:39 INFO - Running post-action listener: _start_resource_monitoring 05:48:39 INFO - Starting resource monitoring. 05:48:39 INFO - ##### 05:48:39 INFO - ##### Running install step. 05:48:39 INFO - ##### 05:48:39 INFO - Running pre-action listener: _resource_record_pre_action 05:48:39 INFO - Running main action method: install 05:48:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:39 INFO - Reading from file tmpfile_stdout 05:48:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:48:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:48:39 INFO - Reading from file tmpfile_stdout 05:48:39 INFO - Output received: 05:48:39 INFO - Usage: mozinstall [options] installer 05:48:39 INFO - Options: 05:48:39 INFO - -h, --help show this help message and exit 05:48:39 INFO - -d DEST, --destination=DEST 05:48:39 INFO - Directory to install application into. [default: 05:48:39 INFO - "/builds/slave/test"] 05:48:39 INFO - --app=APP Application being installed. [default: firefox] 05:48:39 INFO - mkdir: /builds/slave/test/build/application 05:48:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:49:12 INFO - Reading from file tmpfile_stdout 05:49:12 INFO - Output received: 05:49:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:49:12 INFO - Running post-action listener: _resource_record_post_action 05:49:12 INFO - ##### 05:49:12 INFO - ##### Running run-tests step. 05:49:12 INFO - ##### 05:49:12 INFO - Running pre-action listener: _resource_record_pre_action 05:49:12 INFO - Running main action method: run_tests 05:49:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:49:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:49:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:49:12 INFO - 2016-04-22 05:49:12.910 screenresolution[1641:7654] starting screenresolution argv=screenresolution get 05:49:12 INFO - 2016-04-22 05:49:12.913 screenresolution[1641:7654] Display 0: 1600x1200x32@60 05:49:12 INFO - 2016-04-22 05:49:12.919 screenresolution[1642:7656] starting screenresolution argv=screenresolution list 05:49:12 INFO - Available Modes on Display 0 05:49:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:49:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:49:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:49:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:49:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:49:13 INFO - Intel Iris: 05:49:13 INFO - Chipset Model: Intel Iris 05:49:13 INFO - Type: GPU 05:49:13 INFO - Bus: Built-In 05:49:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:49:13 INFO - Vendor: Intel (0x8086) 05:49:13 INFO - Device ID: 0x0a2e 05:49:13 INFO - Revision ID: 0x0009 05:49:13 INFO - Displays: 05:49:13 INFO - Display: 05:49:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:49:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:49:13 INFO - Display Serial Number: 1600x1200 60 05:49:13 INFO - Main Display: Yes 05:49:13 INFO - Mirror: Off 05:49:13 INFO - Online: Yes 05:49:13 INFO - Rotation: Supported 05:49:13 INFO - Return code: 0 05:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:49:13 INFO - Python 2.7.3 05:49:13 INFO - Return code: 0 05:49:13 INFO - grabbing minidump binary from tooltool 05:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:13 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbde00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ae6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cb8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:49:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:49:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:49:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:49:13 INFO - Return code: 0 05:49:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:49:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:49:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:49:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:49:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:49:14 INFO - Return code: 0 05:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j0JeRqySaq/Render', 05:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:14 INFO - 'HOME': '/Users/cltbld', 05:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:14 INFO - 'LOGNAME': 'cltbld', 05:49:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:49:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:14 INFO - 'MOZ_NO_REMOTE': '1', 05:49:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:49:14 INFO - 'NO_EM_RESTART': '1', 05:49:14 INFO - 'PAGER': '/bin/cat', 05:49:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:14 INFO - 'PWD': '/builds/slave/test', 05:49:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:14 INFO - 'SHELL': '/bin/bash', 05:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners', 05:49:14 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:14 INFO - 'USER': 'cltbld', 05:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:14 INFO - 'XPC_FLAGS': '0x0', 05:49:14 INFO - 'XPC_SERVICE_NAME': '0', 05:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:49:14 INFO - mozversion application_buildid: 20160419230832 05:49:14 INFO - mozversion application_changeset: d42b25947fc26a0f9eab98d631465b72769553bd 05:49:14 INFO - mozversion application_display_name: Nightly 05:49:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:49:14 INFO - mozversion application_name: Firefox 05:49:14 INFO - mozversion application_remotingname: firefox 05:49:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:49:14 INFO - mozversion application_vendor: Mozilla 05:49:14 INFO - mozversion application_version: 48.0a1 05:49:14 INFO - mozversion platform_buildid: 20160419230832 05:49:14 INFO - mozversion platform_changeset: d42b25947fc26a0f9eab98d631465b72769553bd 05:49:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:49:14 INFO - mozversion platform_version: 48.0a1 05:49:14 INFO - using testdate: 1461329354 05:49:14 INFO - actual date: 1461329354 05:49:14 INFO - starting webserver on 'localhost:49278' 05:49:14 INFO - SUITE-START | Running 2 tests 05:49:14 INFO - TEST-START | dromaeo_css 05:49:14 INFO - operating with platform_type : mac_ 05:49:14 INFO - Initialising browser for dromaeo_css test... 05:49:14 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpRPuFmQ/profile http://localhost:49278/getInfo.html) 05:49:17 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 05:49:17 INFO - PROCESS | 1660 | colorDepth:24 05:49:17 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 05:49:17 INFO - PROCESS | 1660 | __metrics 05:49:17 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:49:17 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:49:17 INFO - TEST-INFO | 1660: exit 0 05:49:17 INFO - Browser initialized. 05:49:17 INFO - Running cycle 1/1 for dromaeo_css test... 05:49:17 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpRPuFmQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:29 INFO - PROCESS | 1662 | RSS: Main: 237723648 05:49:29 INFO - PROCESS | 1662 | 05:50:21 INFO - PROCESS | 1662 | 1461329421335 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:52:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 05:52:32 INFO - PROCESS | 1662 | RSS: Main: 360865792 05:52:32 INFO - PROCESS | 1662 | 05:55:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 05:55:36 INFO - PROCESS | 1662 | RSS: Main: 373137408 05:55:36 INFO - PROCESS | 1662 | 05:58:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 05:58:39 INFO - PROCESS | 1662 | RSS: Main: 372273152 05:58:39 INFO - PROCESS | 1662 | 06:01:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 06:01:41 INFO - PROCESS | 1662 | RSS: Main: 371449856 06:01:41 INFO - PROCESS | 1662 | 06:04:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 06:04:45 INFO - PROCESS | 1662 | RSS: Main: 342712320 06:04:45 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 06:07:48 INFO - PROCESS | 1662 | RSS: Main: 384499712 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | __start_tp_report 06:07:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 06:07:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 06:07:48 INFO - PROCESS | 1662 | |i|pagename|runs| 06:07:48 INFO - PROCESS | 1662 | |0;dojo.html;3152.3380090181063;3405.8297085145737;3442.8278586070705;3458.4985177149297;3466.6186719460857;5556.9719601873685;5599.440055994399;5621.494065534106;5634.971825140874;5650.660960342378;5117.925235700498;5584.6090773645865;5603.579731520143;5736.397678243787;5752.108423194401;4690.413698287714;5049.823256186016;5083.542481176693;5099.923501147486;5234.659747116444;5152.892128192438;5169.922451163236;5181.0933086709865;5203.505666961632;5239.240310155025;4143.8964025899295;5059.2916991621205;5130.1791713326065;5147.613928955306;5148.6910785353;5113.079645663779;5124;5131.102057140023;5131.974340128313;5139.408967968694;5090.211017292326;5125.051865404875;5132.512411320956;5160.56135228506;5167.586593072545;5496.340439147328;5535.6955367454775;5611.270534830446;5657.179708942181;5670.262865827457;6200.968995154995;6213.036979268176;6225.097360882647;6232.376762323776;6264.404881536247;6129.083728908201;6262.091996660504;6285.497160227171;6297.023961285962;6303;5791.739371728282;5807.796727114615;5836.445537673914;5876.2067120938555;5886.676232807194;5219.19102539107;6252.843678908063;6271.592346497463;6279.529035322369;6285.8742825143245;5929.703514824241;6063.514918806573;6089;6141.293751218578;6146.108814221912;6139.185504090456;6172.938270617326;6234.1583886175695;6234.189555357774;6251.4061164189325;5167.457416971239;6139;6142.109394137825;6184.6598437085495;6210.285817130997;5404.216388623628;6128.927437698385;6270.404311590391;6274.152989346471;6280.717367718464;4843.636727245471;4877.926831097537;4923.138450771101;4955.677880937799;4962.801487940515;4335.046289816235;4389.912201755947;4393.7363758174615;4394.055278115221;4424.911501769947;5671.347795003545;5684.431556844283;5690.317161940594;5695.515881150066;5709.800156994568;5249.238860365225;5528.645481856971;6170.136180934673;6175.876482470415;6309.14826498426;5295.4969277918535;5777.220075289866;5779.682117483579;5787.305523337142;5809.593328467051;5467.753951072212;5476.945230547723;5485;5487.122060470306;5519.061759500894;6229.906551401733;6251.187345645128;6310.842228944313;6320.968395157995;6340.587861788969;4973.502649734997;6226.1905952225925;6277.811665650038;6296.244450665949;6299.338569450142;6298.7480500781285;6333.8733225336155;6335.58818676803;6340.0489926509545;6349.396807303298;6053.878922421615;6102.7253773579405;6106.46066718015;6132.294322189813;6177.938220617736;6217.533684973555;6243.875122497616;6248.500119990481;6250.49996000328;6258.092576576462;5988;6027.186329845415;6029.8191054268445;6033.456988871023;6048.848778780566;5851.21008663825;5948.524118070631;6017.428344307284;6019.699015049143;6059.394060593905;5904.439078287556;5905.291365036223;5917.6745279010065;5923.437273459187;5924.081767326072;5946.226990491208;6026.035834266497;6082.087686847009;6082.2093127893095;6092.543059270483;5552.167174923794;5881.7647294107755;5953.166556682155;5971.193888824953;5996.8500787478915 06:07:48 INFO - PROCESS | 1662 | |1;ext.html;484.5300058942824;521.1271120872539;535.2747027777359;586.6157666728292;618.1253526260342;29169.416611667755;37477.437838432445;38097.619023809755;38813.02967425809;38825.83522494323;41532.377014344806;42253.78873105638;42287.57712422875;42334;42675.35986960198;8411.747647570552;9331.160195582414;9542.952285238563;9546.618135274615;9550.379225350365;11283.323000619948;11614.128940329467;11617.302961822275;11627.593034243802;11642.835716428332;11291.774164516704;11472.426378681073;11476.655700328962;11479;11480.311181329105;11378.975892169745;11394.1454390921;11424.086073114131;11430.199886007982;11436.714082147888;11305.886941130566;11441.54233830654;11444.599439019594;11648.359340236284;11655.825162622568;40368.798156009325;40814;40815;40842.387364189264;40919;3257.4950882612998;3279.8032118072992;3403.3873902697505;3577.338192434408;3836.3478208704587;23107.537849242923;28944.855275723698;28991.710082899113;28996.710032899613;29154.416911661647;2686.194141757465;2701.6082668013223;2768.7369699878477;2785.3175971886817;2808.3961948181036;402069;488802;493190;493932.530337346;495254;60815.69592152099;61490;61716.69141654263;61723.38276617266;61743;64457.355426446076;64801.02798457933;64918.42948637776;64924;65002;16174.676506469805;16693.499195024167;16739.24673389676;16764.580885477717;16808.831911680725;12339.07456940733;12363.876361236453;12364.938175309066;12386;12397.194182378116;16514.75227871583;16752.24614892333;16769.161541922855;16809.41167059147;16812;5718.828435146952;5824.767009319579;6495.025746138117;6614.280287125382;6683.899741503881;970.2238209432426;985.2315194148575;986.413084214891;1022.1158697726413;1149.6953307373553;29433.411331773674;29573.55639665407;29957;30220.395592088036;30373.088807335815;1042.3537406807827;1140.6920131564432;1162.9360385178727;1170.8302296167178;1178.1988247991449;15175;15187.468438604596;15308.617284567752;15345.355055341375;15459.304331305118;623137;633085;633355;635771;636876;58941;61287.08069378963;61384.693076534335;61390.693046534485;61660.07509887355;1331.8202042724108;1376.566381589796;1379.9379027943767;1510.0486693383098;1579.3603590545847;23634;30376.240593985327;30486.390272194876;30987.69012309848;31105.68894311028;50650;50996.490035099174;51504;51623.483765161865;51686.224706630186;394441;397893;398239;398348;398619;33223.66776332302;36500.08749781277;37032.889013329645;38419.6158038416;39663.80168099256;34679;40972.97567560835;42829;43409.78295108631;43725;34894.476582851785;36411.81794091013;36470.63529364672;37319.81340093282;37410.25179496449;35931.82034089813;35957.820210898775;36375;36601.81699091488;36840;36344.27311453809;36370.8181459091;37138.44292335671;37267;37507;26657;30281;31096;32251.354972899942;32626.673733262363;24025.399365016016;24421.51156976886;24566.877165614056;24687.135950241656;24919.50160996806 06:07:48 INFO - PROCESS | 1662 | |2;jquery.html;395.74869957576936;396.62320795244517;397.640135677212;397.72358211043303;402.2819267607322;13694;19524.70712939307;20533.178672853086;20535.87052712102;21143.894280528577;16229.675406491862;17648.117594120275;18559.164837582306;20426.489337766525;20790.68813967788;5208.635395522315;5448.972755136228;6061.484773794233;6071.514278857703;6072.817815465543;5709.514691251248;6052.667103309316;6086.482648974842;6099.57302988791;6100.877982440348;3916.921661566767;4151.4395556599775;5803.448672376117;6119.847003824918;6136.263648362203;6028.457438830483;6101.023836186211;6118.388161183868;6121.234845644294;6123.234595675518;5759.452851979056;5951.2858456985;5980.312264089598;5991.460768530809;5998.220231369894;13128.34358282082;16950.830491695047;22999;26223.34441638955;26223.868880655664;2052.3842847145947;2068.6586713192305;2089.112127345872;2104.8737075775502;2127.5213077057692;8919.42023768453;9667.27495437838;9699.127078562964;9997.80004399908;10324.380537167794;1714.8285171482876;1730.6192637619704;1735.9045252511107;1759.1995641982867;1787.3744189533688;110710;111443;119791;122099.38950305192;126025.99999999908;10386.584536618602;10958.397288149068;11714.355710436008;11729;11913.821292680617;15155.393784248727;15236.23818809055;15265.236738163047;15269.312880920384;15270.847291527165;7171.601537700099;8403.285720713808;8583.0129535103;8607.698730544525;8710.782230444289;6888.380045795902;8423.747287581382;8446.57767111642;8555.914440855511;8568.314534837198;4191.664666826647;4274.995376086617;4613.538646135359;4620.838270660511;4664.626829853603;1495.820501539822;1720.7094678990757;1909.3603642779824;1983.6925276582153;2005.4585261979184;816.3673153306116;895.8387490251818;930.2046750028628;1026.3255469039673;1031.947413638085;19596.412107636792;20549.48626284325;20556.383308500768;20660.483487912923;20895.37313880586;945.1163162443138;972.4359871274644;1006.954687039085;1034.7257976636197;1070.6466865934224;7582.469227154046;10502.264841461178;10643.308184967953;10908.072813810757;11235.438228088562;500222;547367;556188;591996;598070;135468;139123.60876391106;139287.30356348155;143074;145509.54490455173;1012.7974405119006;1019.5819713917258;1036.5024788101603;1064.776396956648;1068.4657671164418;14127.223002734949;14292.42830286777;17867.01731404785;18259.634807304046;18547.44357669272;48424.51575484341;49136.508634913196;49760.25359619681;50963.74518127386;53273.20090198728;69716.65141674259;71449;72958;74162.88755668777;77383.4523309542;6375.458086062737;7134.928650713634;7488.775336739906;7571.3942884568005;7799.220077992155;8383.874241886255;10536.420496872439;10938.398388088413;11966.34185119792;12228.571999979958;9355.4386736796;11181.944090279496;11590.188686791844;11678.591249306233;11680.649580512598;10746.677599672023;10820.4048777318;11075.224734268524;11287.7742445149;11322.32066076038;10428.582856685647;10501.212409069503;11030.44847757625;11668.474918628512;11732.12009099304;7441.962790186014;7445.10658720957;7455.217202193909;7504.737334193277;7587.848243034998;9465.952670236606;10103.24225683062;10360.171186305228;10361.533730982275;10423.114035307084 06:07:48 INFO - PROCESS | 1662 | |3;mootools.html;1388.0769288423198;1589.6502769390738;1603.4468108502567;1846.870719049667;1969.478088306599;7781.338586220171;8520.488770673765;8727.301815854733;8735.650573977042;8745.91254087459;8395.958020209906;8589.01226358969;8592.269657079156;8609;8610.138986101387;2373.572756903752;2404.7234568024637;2407.4703565215714;2408.614621660535;2409.6024305902533;2347.366211122996;2385.42749740062;2388.1283331584023;2388.79695225906;2398.2085911649115;2374.252110585169;2390.3187591536416;2394.2218778896927;2395.0719670665108;2416.6858308419883;2347.859128452298;2370.6207006878903;2372.9644055339186;2381.2499062795164;2395.916142934989;2321.849079809807;2351.6237402015595;2356.8468049576895;2359.2214569192124;2360.386299562109;8448.493090414595;8610.956945215297;8616.224539791387;8679.522626255553;8699.52152631605;5143.254228136937;5295.841124766224;5301.973490132563;5348.465153484659;5350.491703288181;3433.141714571357;3461.2039230977016;3543.255916257589;3549.09498077991;3555.8044307563073;1008.8284991551454;1027.5684212630713;1028.275066078413;1038.0709265207672;1038.781855810273;11167.162462815322;11452.82820757681;11457.197996140274;11463.197576169672;11472.942635286854;3942.586028467027;4364.825406983685;4389.29771236607;4574.108048930427;4596.172688916027;9056.411333263317;9075.092490751003;9081.364304498753;9083.636654533744;9106.134917182857;5179.974100129475;5183.170692689152;5185.0148471790235;5192.506711862367;5219.556337711261;4322.524522302544;4478.843240486567;4688.2733176357715;4827.879303017453;4849.660523763373;3688.4651725499652;3698.5746639136305;3783.5459744830246;3799.7910114943393;3815.9427608585893;1063.106990128296;1162.9127815413879;1180.7992641251008;1187.8574571051356;1205.6865215043977;628.3025841316136;758.852023855343;876.4171825735941;884.274894586433;948.3456415073578;5239.764210610531;5598.804041858515;5660.433956604306;5734.913976290359;5976.880462390728;976.3946353260882;1020.2348238820884;1040.6513817871098;1055.6885718712997;1057.4448414582253;3666.2484190740834;3709.109813644706;3799.5630502492013;3835.2329534092737;3887.1448281378052;10015.849762253572;10629.734256643645;10808.729781755519;10810.351378917132;10816.91830816912;10620.734481638021;10755.946220269005;10757.462126893624;10767.246292759432;10770.56917723298;7814.843703125905;7983.68065277394;7993.280604745484;8109.959450202711;8145.6741730331305;5551.361593416808;5573.526250268773;5639.379668236573;5692.48767610917;5802.796902108406;9757.609695612096;9767.316287859781;9855;10002.649907253211;10013.499325033865;9292.907070929205;9845.538615346164;10015.749606259611;10047.145992590711;10086.899131008597;7221.458390620619;7325.6337183141695;7388.963055184689;7392.482526223328;7398.926010740039;7918.3665306776475;8267.586620669063;8317.500949943023;8319.001719793663;8333.916660833556;6329.240491141092;7278.6724597392185;7357.22749111357;7642;7819.569923654253;6440.645764482999;6997.825054373681;7468.365188958999;7526.24737526243;7633.427492937941;6155.938440615537;6196.659183744757;6661.933380666132;6761.357671021246;6764.255931847394;4873.585745211697;4881.243407271879;4890.119778439915;4958.57852082577;4970.3290055843445;4252.723572967747;5016.322796422436;5045.192769156917;5057.443681195139;5095.388553373619 06:07:48 INFO - PROCESS | 1662 | |4;prototype.html;290.1397356836978;320.83637344954076;323.22426177174793;330.47124600638966;331.37370370000696;4902.852914412569;5213.426523082468;5906.907222163892;6021.2774467063955;6025.367336429677;5247.449017853128;5421.99999999999;5762.654240745558;5837.919984802815;5898.439648233421;3988.3419235826127;4432.667549933752;4497.887552811173;4508.706934049293;4525.637948964075;3946.3291240489043;4494.123645889036;4511.571400716943;4530.592246697796;4535.795889184995;3450.2742833161774;3581.6019601203147;3740.756850804689;4491.236489796726;4507.72953622782;3514.7012503937194;4196.832126714959;4472.284434490466;4488.079943611552;4495.46054473462;3511.5961664408665;3909.960900390988;3910;3912.197999410114;3918.5689574146822;4782.13921494131;5566.248556444909;5716.79991200306;5749.166370876242;5792.420757924216;4705.482396936334;4851.975740121312;4989.027139707759;5085.313482679828;5276.736163191864;6323.430891219812;6330;6347.619142851444;6375.553711240215;6384.936150638527;4207.926978620463;4359.237133501624;4360.869173924787;4383.890402739957;4391.714538554984;5073.061483625541;5753.769849206069;6613.8081605939105;6688.665566721645;6698.229703584101;4396.230659634615;4414.448193975753;4436.778161091933;4444.911101777946;4452.354408610733;4034.65705415043;4205.3481710334945;4327.6105150536605;4516;4527.366168736379;4578.153041687232;4999.800007999639;5204.713740744295;5238.5023422774775;5262.526372626406;5529.834104976857;5839.883202335929;6204.441600255998;6391.96804015977;6395.584287021329;1282.2562913510142;1509.411329581464;1519.7188520123811;1522.885783566237;1566.5065504366316;936.5598168860624;1012.3773879064322;1019.37308555238;1040.4745603470199;1045.7124290820086;4878.317035615017;5277.176710459962;5610.631932756351;5938.257717785277;5953.940460595425;6537.542372033912;6552.967235163889;6577.506686998494;6577.704003319862;6583.111279977237;5765.8558536036935;5905.557083218776;5928.87351403232;5944.4352786485215;5972.611780234271;2863.8687718351134;3425.8588382261087;3432.502287168347;3441;3464;6613.66931653425;6805.3931416349415;6824.590524568443;6826.829329266808;6836.769381511276;5262.868428289246;6715.597064176165;6716.361945615159;6718.0930574372815;6721.1598550181225;6015.6691381974415;6590.846601844658;6779.830504237433;6791.147717958568;6795.864082718318;6516.543841931019;6528.584423763238;6537.346265373425;6557.96721016411;6567;6306.983046853021;6350.301466838551;6436.935630643634;6466.159399278252;6471.4822814175695;5804.970975145097;6717.596944183169;6784.59292442455;6785.728570857111;6813.981598953086;4398.208322501852;4428.800703968264;4439.378487011885;4453.7105088170465;4455.68810183284;6257.812265632038;6258.280297765815;6488.994205898093;6502.154719886385;6507.275571973336;6125;6220.502359811295;6369.522285828489;6384.201974753156;6389.265234497906;5865;6317.399847014526;6338.334474880256;6388.87222255543;6400.23197216337;4825.30033145199;5449.64577302474;5912.201852749824;6075.392460753889;6244.125822384961;6326;6379.4896408286295;6383.648269971408;6390.8815957208235;6393.392627700361;6011.639301641915;6235.688215589112;6254.343293954252;6257.186565746424;6287.874242515032 06:07:48 INFO - PROCESS | 1662 | |5;yui.html;654.4077609763164;716.9641517924102;737.3474475089544;737.7971057959064;745.0500611720058;14059.929700351497;14747.77878331826;15254.237288135604;15287.541373758791;15343.156126413043;11805.822912656316;14031.29843507823;14075.788863167061;14106.294685265748;14116.505922292721;723.330918900018;931.16660588773;939.5490164720939;947.7820101376653;948.3788118782209;748.7154881145157;912.5209265135791;948.4072454715803;949.1931857920781;953.2897990996682;977.9608815647366;988.614440368257;988.658912675126;988.7874107066979;990.8117457683018;754.7660225330166;960.8654788329569;978.0806042320232;979.4319294808994;982.8132654795565;703.4477934821141;740.1894925057054;741.269849198539;742.3059439424155;743.4981387563428;15060;15087.698246035128;15088.924555377263;15136.470223542123;15153.393864245418;13183.934080329633;13198.802017969738;13208.405621746948;13319.800202996963;13477.797833032513;9896.90103098967;9944.850827237597;9987.151092157175;10066;10193.490325483695;3476.1309672581856;3507.9649203508147;3556.466530020492;3567.8572857085687;3579.2662504186837;93569;94119.52940235255;94509;94728;96844;9796.942642045107;9840;9891.802163956681;10157.288989770794;10263.692089237335;22994.42513937165;23127.53744925092;23166;23205.3038408848;23374.8831255846;7500.287472690085;7609.124950630638;7649.426293028045;7761.223877612194;7820.608969551614;4051.858184963512;4115.73247738896;4116.320807066861;4122.91754164915;4180.268453020709;3569.1077230692326;3679.35611268027;3679.448082787603;3681.834317455721;3683.208110256308;622.3807311724863;635.5582869905371;641.4291280760127;642.3640595810208;651.4202359899693;504.2335649812338;544.8692313844649;550.7796881247533;585.1281590430314;586.6597373523347;7663.540187588763;7705.807354816062;7900.446968712135;8020.919790802017;8332.958335208285;520.8359366799446;526.4735264735265;529.4282175250797;585.2274997003914;586.5190096775565;3683.2817600567632;3731.776093434403;3782.6028267032116;3791.7914514701956;3859.845606175778;94728.52635736777;97234;97424.51287743516;98343.50828245813;99538.50230748944;75018;77389.22610773933;77650.22349776543;77895.2210477888;77995.61002194953;69338.65330673415;73408.63295683594;75702.7316338264;75769;76824;7369.96315018443;7680.5391676499585;7709.575973321521;7775.494592851446;7813.257740514642;36400;45488.77255613701;46439.14243430225;46842.76578617085;47301.52698472971;51347.71630709262;51409.48590514047;53237.7338113307;62894;63081.36918630755;10726.302790318605;10834.729131771768;10860.674179774303;10990.505427255634;11566.537338506701;12055.397230138633;12148.392580371123;12235.143539952118;12294.81557776652;12496.500139994298;11501.19491635611;11516;11643.88356116428;11680.649580512598;11919.344436055753;11323.490442930253;11743.941280293544;11851.644450666494;12008.579699710468;12049.939750301193;10727.678169654922;10972.396518191576;10978.12175026012;11327.263727857662;11371.829422558503;9382.390144640576;9517.571709272961;9662.420254784736;9729.173020293072;9923;9264.814703706023;9634.277429192978;9763.365381250196;9836.950815245878;9933.701988940344 06:07:48 INFO - PROCESS | 1662 | __end_tp_report 06:07:48 INFO - PROCESS | 1662 | __start_cc_report 06:07:48 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1179 06:07:48 INFO - PROCESS | 1662 | __end_cc_report 06:07:48 INFO - PROCESS | 1662 | __startTimestamp1461330468566__endTimestamp 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | ------- Summary: start ------- 06:07:48 INFO - PROCESS | 1662 | Number of tests: 6 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5671.26 Median:5881.76 stddev:654.26 (11.1%) stddev-sans-first:625.72 06:07:48 INFO - PROCESS | 1662 | Values: 3152.3 3405.8 3442.8 3458.5 3466.6 5557.0 5599.4 5621.5 5635.0 5650.7 5117.9 5584.6 5603.6 5736.4 5752.1 4690.4 5049.8 5083.5 5099.9 5234.7 5152.9 5169.9 5181.1 5203.5 5239.2 4143.9 5059.3 5130.2 5147.6 5148.7 5113.1 5124.0 5131.1 5132.0 5139.4 5090.2 5125.1 5132.5 5160.6 5167.6 5496.3 5535.7 5611.3 5657.2 5670.3 6201.0 6213.0 6225.1 6232.4 6264.4 6129.1 6262.1 6285.5 6297.0 6303.0 5791.7 5807.8 5836.4 5876.2 5886.7 5219.2 6252.8 6271.6 6279.5 6285.9 5929.7 6063.5 6089.0 6141.3 6146.1 6139.2 6172.9 6234.2 6234.2 6251.4 5167.5 6139.0 6142.1 6184.7 6210.3 5404.2 6128.9 6270.4 6274.2 6280.7 4843.6 4877.9 4923.1 4955.7 4962.8 4335.0 4389.9 4393.7 4394.1 4424.9 5671.3 5684.4 5690.3 5695.5 5709.8 5249.2 5528.6 6170.1 6175.9 6309.1 5295.5 5777.2 5779.7 5787.3 5809.6 5467.8 5476.9 5485.0 5487.1 5519.1 6229.9 6251.2 6310.8 6321.0 6340.6 4973.5 6226.2 6277.8 6296.2 6299.3 6298.7 6333.9 6335.6 6340.0 6349.4 6053.9 6102.7 6106.5 6132.3 6177.9 6217.5 6243.9 6248.5 6250.5 6258.1 5988.0 6027.2 6029.8 6033.5 6048.8 5851.2 5948.5 6017.4 6019.7 6059.4 5904.4 5905.3 5917.7 5923.4 5924.1 5946.2 6026.0 6082.1 6082.2 6092.5 5552.2 5881.8 5953.2 5971.2 5996.9 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:64596.62 Median:35944.82 stddev:136428.27 (379.5%) stddev-sans-first:136726.06 06:07:48 INFO - PROCESS | 1662 | Values: 484.5 521.1 535.3 586.6 618.1 29169.4 37477.4 38097.6 38813.0 38825.8 41532.4 42253.8 42287.6 42334.0 42675.4 8411.7 9331.2 9543.0 9546.6 9550.4 11283.3 11614.1 11617.3 11627.6 11642.8 11291.8 11472.4 11476.7 11479.0 11480.3 11379.0 11394.1 11424.1 11430.2 11436.7 11305.9 11441.5 11444.6 11648.4 11655.8 40368.8 40814.0 40815.0 40842.4 40919.0 3257.5 3279.8 3403.4 3577.3 3836.3 23107.5 28944.9 28991.7 28996.7 29154.4 2686.2 2701.6 2768.7 2785.3 2808.4 402069.0 488802.0 493190.0 493932.5 495254.0 60815.7 61490.0 61716.7 61723.4 61743.0 64457.4 64801.0 64918.4 64924.0 65002.0 16174.7 16693.5 16739.2 16764.6 16808.8 12339.1 12363.9 12364.9 12386.0 12397.2 16514.8 16752.2 16769.2 16809.4 16812.0 5718.8 5824.8 6495.0 6614.3 6683.9 970.2 985.2 986.4 1022.1 1149.7 29433.4 29573.6 29957.0 30220.4 30373.1 1042.4 1140.7 1162.9 1170.8 1178.2 15175.0 15187.5 15308.6 15345.4 15459.3 623137.0 633085.0 633355.0 635771.0 636876.0 58941.0 61287.1 61384.7 61390.7 61660.1 1331.8 1376.6 1379.9 1510.0 1579.4 23634.0 30376.2 30486.4 30987.7 31105.7 50650.0 50996.5 51504.0 51623.5 51686.2 394441.0 397893.0 398239.0 398348.0 398619.0 33223.7 36500.1 37032.9 38419.6 39663.8 34679.0 40973.0 42829.0 43409.8 43725.0 34894.5 36411.8 36470.6 37319.8 37410.3 35931.8 35957.8 36375.0 36601.8 36840.0 36344.3 36370.8 37138.4 37267.0 37507.0 26657.0 30281.0 31096.0 32251.4 32626.7 24025.4 24421.5 24566.9 24687.1 24919.5 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33643.13 Median:11500.12 stddev:94221.28 (819.3%) stddev-sans-first:94452.52 06:07:48 INFO - PROCESS | 1662 | Values: 395.7 396.6 397.6 397.7 402.3 13694.0 19524.7 20533.2 20535.9 21143.9 16229.7 17648.1 18559.2 20426.5 20790.7 5208.6 5449.0 6061.5 6071.5 6072.8 5709.5 6052.7 6086.5 6099.6 6100.9 3916.9 4151.4 5803.4 6119.8 6136.3 6028.5 6101.0 6118.4 6121.2 6123.2 5759.5 5951.3 5980.3 5991.5 5998.2 13128.3 16950.8 22999.0 26223.3 26223.9 2052.4 2068.7 2089.1 2104.9 2127.5 8919.4 9667.3 9699.1 9997.8 10324.4 1714.8 1730.6 1735.9 1759.2 1787.4 110710.0 111443.0 119791.0 122099.4 126026.0 10386.6 10958.4 11714.4 11729.0 11913.8 15155.4 15236.2 15265.2 15269.3 15270.8 7171.6 8403.3 8583.0 8607.7 8710.8 6888.4 8423.7 8446.6 8555.9 8568.3 4191.7 4275.0 4613.5 4620.8 4664.6 1495.8 1720.7 1909.4 1983.7 2005.5 816.4 895.8 930.2 1026.3 1031.9 19596.4 20549.5 20556.4 20660.5 20895.4 945.1 972.4 1007.0 1034.7 1070.6 7582.5 10502.3 10643.3 10908.1 11235.4 500222.0 547367.0 556188.0 591996.0 598070.0 135468.0 139123.6 139287.3 143074.0 145509.5 1012.8 1019.6 1036.5 1064.8 1068.5 14127.2 14292.4 17867.0 18259.6 18547.4 48424.5 49136.5 49760.3 50963.7 53273.2 69716.7 71449.0 72958.0 74162.9 77383.5 6375.5 7134.9 7488.8 7571.4 7799.2 8383.9 10536.4 10938.4 11966.3 12228.6 9355.4 11181.9 11590.2 11678.6 11680.6 10746.7 10820.4 11075.2 11287.8 11322.3 10428.6 10501.2 11030.4 11668.5 11732.1 7442.0 7445.1 7455.2 7504.7 7587.8 9466.0 10103.2 10360.2 10361.5 10423.1 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5585.77 Median:4877.41 stddev:3125.16 (64.1%) stddev-sans-first:3118.01 06:07:48 INFO - PROCESS | 1662 | Values: 1388.1 1589.7 1603.4 1846.9 1969.5 7781.3 8520.5 8727.3 8735.7 8745.9 8396.0 8589.0 8592.3 8609.0 8610.1 2373.6 2404.7 2407.5 2408.6 2409.6 2347.4 2385.4 2388.1 2388.8 2398.2 2374.3 2390.3 2394.2 2395.1 2416.7 2347.9 2370.6 2373.0 2381.2 2395.9 2321.8 2351.6 2356.8 2359.2 2360.4 8448.5 8611.0 8616.2 8679.5 8699.5 5143.3 5295.8 5302.0 5348.5 5350.5 3433.1 3461.2 3543.3 3549.1 3555.8 1008.8 1027.6 1028.3 1038.1 1038.8 11167.2 11452.8 11457.2 11463.2 11472.9 3942.6 4364.8 4389.3 4574.1 4596.2 9056.4 9075.1 9081.4 9083.6 9106.1 5180.0 5183.2 5185.0 5192.5 5219.6 4322.5 4478.8 4688.3 4827.9 4849.7 3688.5 3698.6 3783.5 3799.8 3815.9 1063.1 1162.9 1180.8 1187.9 1205.7 628.3 758.9 876.4 884.3 948.3 5239.8 5598.8 5660.4 5734.9 5976.9 976.4 1020.2 1040.7 1055.7 1057.4 3666.2 3709.1 3799.6 3835.2 3887.1 10015.8 10629.7 10808.7 10810.4 10816.9 10620.7 10755.9 10757.5 10767.2 10770.6 7814.8 7983.7 7993.3 8110.0 8145.7 5551.4 5573.5 5639.4 5692.5 5802.8 9757.6 9767.3 9855.0 10002.6 10013.5 9292.9 9845.5 10015.7 10047.1 10086.9 7221.5 7325.6 7389.0 7392.5 7398.9 7918.4 8267.6 8317.5 8319.0 8333.9 6329.2 7278.7 7357.2 7642.0 7819.6 6440.6 6997.8 7468.4 7526.2 7633.4 6155.9 6196.7 6661.9 6761.4 6764.3 4873.6 4881.2 4890.1 4958.6 4970.3 4252.7 5016.3 5045.2 5057.4 5095.4 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5123.78 Median:5758.21 stddev:1613.13 (28.0%) stddev-sans-first:1576.33 06:07:48 INFO - PROCESS | 1662 | Values: 290.1 320.8 323.2 330.5 331.4 4902.9 5213.4 5906.9 6021.3 6025.4 5247.4 5422.0 5762.7 5837.9 5898.4 3988.3 4432.7 4497.9 4508.7 4525.6 3946.3 4494.1 4511.6 4530.6 4535.8 3450.3 3581.6 3740.8 4491.2 4507.7 3514.7 4196.8 4472.3 4488.1 4495.5 3511.6 3910.0 3910.0 3912.2 3918.6 4782.1 5566.2 5716.8 5749.2 5792.4 4705.5 4852.0 4989.0 5085.3 5276.7 6323.4 6330.0 6347.6 6375.6 6384.9 4207.9 4359.2 4360.9 4383.9 4391.7 5073.1 5753.8 6613.8 6688.7 6698.2 4396.2 4414.4 4436.8 4444.9 4452.4 4034.7 4205.3 4327.6 4516.0 4527.4 4578.2 4999.8 5204.7 5238.5 5262.5 5529.8 5839.9 6204.4 6392.0 6395.6 1282.3 1509.4 1519.7 1522.9 1566.5 936.6 1012.4 1019.4 1040.5 1045.7 4878.3 5277.2 5610.6 5938.3 5953.9 6537.5 6553.0 6577.5 6577.7 6583.1 5765.9 5905.6 5928.9 5944.4 5972.6 2863.9 3425.9 3432.5 3441.0 3464.0 6613.7 6805.4 6824.6 6826.8 6836.8 5262.9 6715.6 6716.4 6718.1 6721.2 6015.7 6590.8 6779.8 6791.1 6795.9 6516.5 6528.6 6537.3 6558.0 6567.0 6307.0 6350.3 6436.9 6466.2 6471.5 5805.0 6717.6 6784.6 6785.7 6814.0 4398.2 4428.8 4439.4 4453.7 4455.7 6257.8 6258.3 6489.0 6502.2 6507.3 6125.0 6220.5 6369.5 6384.2 6389.3 5865.0 6317.4 6338.3 6388.9 6400.2 4825.3 5449.6 5912.2 6075.4 6244.1 6326.0 6379.5 6383.6 6390.9 6393.4 6011.6 6235.7 6254.3 6257.2 6287.9 06:07:48 INFO - PROCESS | 1662 | 06:07:48 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:18527.19 Median:586.59 stddev:26663.78 (4545.6%) stddev-sans-first:26704.81 06:07:48 INFO - PROCESS | 1662 | Values: 654.4 717.0 737.3 737.8 745.1 14059.9 14747.8 15254.2 15287.5 15343.2 11805.8 14031.3 14075.8 14106.3 14116.5 723.3 931.2 939.5 947.8 948.4 748.7 912.5 948.4 949.2 953.3 978.0 988.6 988.7 988.8 990.8 754.8 960.9 978.1 979.4 982.8 703.4 740.2 741.3 742.3 743.5 15060.0 15087.7 15088.9 15136.5 15153.4 13183.9 13198.8 13208.4 13319.8 13477.8 9896.9 9944.9 9987.2 10066.0 10193.5 3476.1 3508.0 3556.5 3567.9 3579.3 93569.0 94119.5 94509.0 94728.0 96844.0 9796.9 9840.0 9891.8 10157.3 10263.7 22994.4 23127.5 23166.0 23205.3 23374.9 7500.3 7609.1 7649.4 7761.2 7820.6 4051.9 4115.7 4116.3 4122.9 4180.3 3569.1 3679.4 3679.4 3681.8 3683.2 622.4 635.6 641.4 642.4 651.4 504.2 544.9 550.8 585.1 586.7 7663.5 7705.8 7900.4 8020.9 8333.0 520.8 526.5 529.4 585.2 586.5 3683.3 3731.8 3782.6 3791.8 3859.8 94728.5 97234.0 97424.5 98343.5 99538.5 75018.0 77389.2 77650.2 77895.2 77995.6 69338.7 73408.6 75702.7 75769.0 76824.0 7370.0 7680.5 7709.6 7775.5 7813.3 36400.0 45488.8 46439.1 46842.8 47301.5 51347.7 51409.5 53237.7 62894.0 63081.4 10726.3 10834.7 10860.7 10990.5 11566.5 12055.4 12148.4 12235.1 12294.8 12496.5 11501.2 11516.0 11643.9 11680.6 11919.3 11323.5 11743.9 11851.6 12008.6 12049.9 10727.7 10972.4 10978.1 11327.3 11371.8 9382.4 9517.6 9662.4 9729.2 9923.0 9264.8 9634.3 9763.4 9837.0 9933.7 06:07:48 INFO - PROCESS | 1662 | -------- Summary: end -------- 06:07:48 INFO - PROCESS | 1662 | 06:07:49 INFO - TEST-INFO | 1662: exit 0 06:07:49 INFO - TEST-OK | dromaeo_css | took 1114425ms 06:07:49 INFO - TEST-START | kraken 06:07:49 INFO - operating with platform_type : mac_ 06:07:49 INFO - Initialising browser for kraken test... 06:07:49 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpVcI8lT/profile http://localhost:49278/getInfo.html) 06:07:51 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 06:07:51 INFO - PROCESS | 1672 | colorDepth:24 06:07:51 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 06:07:51 INFO - PROCESS | 1672 | __metrics 06:07:51 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:07:51 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:07:52 INFO - TEST-INFO | 1672: exit 0 06:07:52 INFO - Browser initialized. 06:07:52 INFO - Running cycle 1/1 for kraken test... 06:07:52 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpVcI8lT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:04 INFO - PROCESS | 1674 | RSS: Main: 237289472 06:08:04 INFO - PROCESS | 1674 | 06:08:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 06:08:07 INFO - PROCESS | 1674 | RSS: Main: 285409280 06:08:07 INFO - PROCESS | 1674 | 06:08:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 06:08:10 INFO - PROCESS | 1674 | RSS: Main: 298491904 06:08:10 INFO - PROCESS | 1674 | 06:08:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 06:08:15 INFO - PROCESS | 1674 | RSS: Main: 387694592 06:08:15 INFO - PROCESS | 1674 | 06:08:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 06:08:17 INFO - PROCESS | 1674 | RSS: Main: 285995008 06:08:17 INFO - PROCESS | 1674 | 06:08:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:08:20 INFO - PROCESS | 1674 | RSS: Main: 290365440 06:08:20 INFO - PROCESS | 1674 | 06:08:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 06:08:23 INFO - PROCESS | 1674 | RSS: Main: 328777728 06:08:23 INFO - PROCESS | 1674 | 06:08:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 06:08:27 INFO - PROCESS | 1674 | RSS: Main: 329654272 06:08:27 INFO - PROCESS | 1674 | 06:08:30 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 06:08:30 INFO - PROCESS | 1674 | RSS: Main: 327012352 06:08:30 INFO - PROCESS | 1674 | 06:08:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:32 INFO - PROCESS | 1674 | RSS: Main: 319561728 06:08:32 INFO - PROCESS | 1674 | 06:08:34 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:35 INFO - PROCESS | 1674 | RSS: Main: 327393280 06:08:35 INFO - PROCESS | 1674 | 06:08:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:38 INFO - PROCESS | 1674 | RSS: Main: 329732096 06:08:38 INFO - PROCESS | 1674 | 06:08:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:41 INFO - PROCESS | 1674 | RSS: Main: 333918208 06:08:41 INFO - PROCESS | 1674 | 06:08:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:45 INFO - PROCESS | 1674 | RSS: Main: 331743232 06:08:45 INFO - PROCESS | 1674 | 06:08:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 06:08:47 INFO - PROCESS | 1674 | RSS: Main: 396132352 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | __start_tp_report 06:08:47 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 06:08:47 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 06:08:47 INFO - PROCESS | 1674 | |i|pagename|runs| 06:08:47 INFO - PROCESS | 1674 | |0;ai-astar;112;111;113;110;113;112;119;111;112;113 06:08:47 INFO - PROCESS | 1674 | |1;audio-beat-detection;156;127;143;169;119;143;137;114;171;122 06:08:47 INFO - PROCESS | 1674 | |2;audio-dft;189;187;184;186;187;181;183;184;184;184 06:08:47 INFO - PROCESS | 1674 | |3;audio-fft;76;73;86;93;57;58;58;79;86;55 06:08:47 INFO - PROCESS | 1674 | |4;audio-oscillator;89;95;134;78;79;80;121;80;77;131 06:08:47 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;88;84;86;84;91;83;93;86;87;84 06:08:47 INFO - PROCESS | 1674 | |6;imaging-darkroom;92;108;149;103;93;93;97;92;92;92 06:08:47 INFO - PROCESS | 1674 | |7;imaging-desaturate;82;88;83;101;140;134;85;87;85;85 06:08:47 INFO - PROCESS | 1674 | |8;json-parse-financial;58;60;59;58;60;58;56;78;84;88 06:08:47 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;63;66;73;73;69;69;67;66;67;66 06:08:47 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;83;82;83;82;81;82;81;81;79;82 06:08:47 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;141;138;137;146;151;151;144;136;176;140 06:08:47 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;170;171;171;170;169;173;180;168;166;173 06:08:47 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;69;68;69;68;71;69;67;69;68;69 06:08:47 INFO - PROCESS | 1674 | __end_tp_report 06:08:47 INFO - PROCESS | 1674 | __start_cc_report 06:08:47 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1952 06:08:47 INFO - PROCESS | 1674 | __end_cc_report 06:08:47 INFO - PROCESS | 1674 | __startTimestamp1461330527098__endTimestamp 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | ------- Summary: start ------- 06:08:47 INFO - PROCESS | 1674 | Number of tests: 14 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.50 stddev:2.46 (2.2%) stddev-sans-first:2.60 06:08:47 INFO - PROCESS | 1674 | Values: 112.0 111.0 113.0 110.0 113.0 112.0 119.0 111.0 112.0 113.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:143.00 stddev:20.26 (14.2%) stddev-sans-first:20.66 06:08:47 INFO - PROCESS | 1674 | Values: 156.0 127.0 143.0 169.0 119.0 143.0 137.0 114.0 171.0 122.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.00 stddev:2.33 (1.3%) stddev-sans-first:1.94 06:08:47 INFO - PROCESS | 1674 | Values: 189.0 187.0 184.0 186.0 187.0 181.0 183.0 184.0 184.0 184.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:72.10 Median:77.50 stddev:14.16 (18.3%) stddev-sans-first:14.95 06:08:47 INFO - PROCESS | 1674 | Values: 76.0 73.0 86.0 93.0 57.0 58.0 58.0 79.0 86.0 55.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:79.50 stddev:23.16 (29.1%) stddev-sans-first:24.41 06:08:47 INFO - PROCESS | 1674 | Values: 89.0 95.0 134.0 78.0 79.0 80.0 121.0 80.0 77.0 131.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:86.50 stddev:3.27 (3.8%) stddev-sans-first:3.43 06:08:47 INFO - PROCESS | 1674 | Values: 88.0 84.0 86.0 84.0 91.0 83.0 93.0 86.0 87.0 84.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:92.00 stddev:17.72 (19.3%) stddev-sans-first:18.48 06:08:47 INFO - PROCESS | 1674 | Values: 92.0 108.0 149.0 103.0 93.0 93.0 97.0 92.0 92.0 92.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:85.00 stddev:21.78 (25.6%) stddev-sans-first:22.41 06:08:47 INFO - PROCESS | 1674 | Values: 82.0 88.0 83.0 101.0 140.0 134.0 85.0 87.0 85.0 85.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:60.00 stddev:12.31 (20.5%) stddev-sans-first:12.73 06:08:47 INFO - PROCESS | 1674 | Values: 58.0 60.0 59.0 58.0 60.0 58.0 56.0 78.0 84.0 88.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:3.18 (4.7%) stddev-sans-first:2.83 06:08:47 INFO - PROCESS | 1674 | Values: 63.0 66.0 73.0 73.0 69.0 69.0 67.0 66.0 67.0 66.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:1.17 (1.4%) stddev-sans-first:1.13 06:08:47 INFO - PROCESS | 1674 | Values: 83.0 82.0 83.0 82.0 81.0 82.0 81.0 81.0 79.0 82.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:145.00 stddev:11.83 (8.2%) stddev-sans-first:12.41 06:08:47 INFO - PROCESS | 1674 | Values: 141.0 138.0 137.0 146.0 151.0 151.0 144.0 136.0 176.0 140.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:171.00 stddev:3.78 (2.2%) stddev-sans-first:3.99 06:08:47 INFO - PROCESS | 1674 | Values: 170.0 171.0 171.0 170.0 169.0 173.0 180.0 168.0 166.0 173.0 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.06 (1.5%) stddev-sans-first:1.12 06:08:47 INFO - PROCESS | 1674 | Values: 69.0 68.0 69.0 68.0 71.0 69.0 67.0 69.0 68.0 69.0 06:08:47 INFO - PROCESS | 1674 | -------- Summary: end -------- 06:08:47 INFO - PROCESS | 1674 | 06:08:47 INFO - TEST-INFO | 1674: exit 0 06:08:47 INFO - TEST-OK | kraken | took 58794ms 06:08:47 INFO - SUITE-END | took 1173s 06:08:48 INFO - Completed test suite (00:19:33) 06:08:48 INFO - javascript benchmark 06:08:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3152.3380090181063, 3405.8297085145737, 3442.8278586070705, 3458.4985177149297, 3466.6186719460857, 5556.9719601873685, 5599.440055994399, 5621.494065534106, 5634.971825140874, 5650.660960342378, 5117.925235700498, 5584.6090773645865, 5603.579731520143, 5736.397678243787, 5752.108423194401, 4690.413698287714, 5049.823256186016, 5083.542481176693, 5099.923501147486, 5234.659747116444, 5152.892128192438, 5169.922451163236, 5181.0933086709865, 5203.505666961632, 5239.240310155025, 4143.8964025899295, 5059.2916991621205, 5130.1791713326065, 5147.613928955306, 5148.6910785353, 5113.079645663779, 5124.0, 5131.102057140023, 5131.974340128313, 5139.408967968694, 5090.211017292326, 5125.051865404875, 5132.512411320956, 5160.56135228506, 5167.586593072545, 5496.340439147328, 5535.6955367454775, 5611.270534830446, 5657.179708942181, 5670.262865827457, 6200.968995154995, 6213.036979268176, 6225.097360882647, 6232.376762323776, 6264.404881536247, 6129.083728908201, 6262.091996660504, 6285.497160227171, 6297.023961285962, 6303.0, 5791.739371728282, 5807.796727114615, 5836.445537673914, 5876.2067120938555, 5886.676232807194, 5219.19102539107, 6252.843678908063, 6271.592346497463, 6279.529035322369, 6285.8742825143245, 5929.703514824241, 6063.514918806573, 6089.0, 6141.293751218578, 6146.108814221912, 6139.185504090456, 6172.938270617326, 6234.1583886175695, 6234.189555357774, 6251.4061164189325, 5167.457416971239, 6139.0, 6142.109394137825, 6184.6598437085495, 6210.285817130997, 5404.216388623628, 6128.927437698385, 6270.404311590391, 6274.152989346471, 6280.717367718464, 4843.636727245471, 4877.926831097537, 4923.138450771101, 4955.677880937799, 4962.801487940515, 4335.046289816235, 4389.912201755947, 4393.7363758174615, 4394.055278115221, 4424.911501769947, 5671.347795003545, 5684.431556844283, 5690.317161940594, 5695.515881150066, 5709.800156994568, 5249.238860365225, 5528.645481856971, 6170.136180934673, 6175.876482470415, 6309.14826498426, 5295.4969277918535, 5777.220075289866, 5779.682117483579, 5787.305523337142, 5809.593328467051, 5467.753951072212, 5476.945230547723, 5485.0, 5487.122060470306, 5519.061759500894, 6229.906551401733, 6251.187345645128, 6310.842228944313, 6320.968395157995, 6340.587861788969, 4973.502649734997, 6226.1905952225925, 6277.811665650038, 6296.244450665949, 6299.338569450142, 6298.7480500781285, 6333.8733225336155, 6335.58818676803, 6340.0489926509545, 6349.396807303298, 6053.878922421615, 6102.7253773579405, 6106.46066718015, 6132.294322189813, 6177.938220617736, 6217.533684973555, 6243.875122497616, 6248.500119990481, 6250.49996000328, 6258.092576576462, 5988.0, 6027.186329845415, 6029.8191054268445, 6033.456988871023, 6048.848778780566, 5851.21008663825, 5948.524118070631, 6017.428344307284, 6019.699015049143, 6059.394060593905, 5904.439078287556, 5905.291365036223, 5917.6745279010065, 5923.437273459187, 5924.081767326072, 5946.226990491208, 6026.035834266497, 6082.087686847009, 6082.2093127893095, 6092.543059270483, 5552.167174923794, 5881.7647294107755, 5953.166556682155, 5971.193888824953, 5996.8500787478915], "value": 5631.001708081359, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.5300058942824, 521.1271120872539, 535.2747027777359, 586.6157666728292, 618.1253526260342, 29169.416611667755, 37477.437838432445, 38097.619023809755, 38813.02967425809, 38825.83522494323, 41532.377014344806, 42253.78873105638, 42287.57712422875, 42334.0, 42675.35986960198, 8411.747647570552, 9331.160195582414, 9542.952285238563, 9546.618135274615, 9550.379225350365, 11283.323000619948, 11614.128940329467, 11617.302961822275, 11627.593034243802, 11642.835716428332, 11291.774164516704, 11472.426378681073, 11476.655700328962, 11479.0, 11480.311181329105, 11378.975892169745, 11394.1454390921, 11424.086073114131, 11430.199886007982, 11436.714082147888, 11305.886941130566, 11441.54233830654, 11444.599439019594, 11648.359340236284, 11655.825162622568, 40368.798156009325, 40814.0, 40815.0, 40842.387364189264, 40919.0, 3257.4950882612998, 3279.8032118072992, 3403.3873902697505, 3577.338192434408, 3836.3478208704587, 23107.537849242923, 28944.855275723698, 28991.710082899113, 28996.710032899613, 29154.416911661647, 2686.194141757465, 2701.6082668013223, 2768.7369699878477, 2785.3175971886817, 2808.3961948181036, 402069.0, 488802.0, 493190.0, 493932.530337346, 495254.0, 60815.69592152099, 61490.0, 61716.69141654263, 61723.38276617266, 61743.0, 64457.355426446076, 64801.02798457933, 64918.42948637776, 64924.0, 65002.0, 16174.676506469805, 16693.499195024167, 16739.24673389676, 16764.580885477717, 16808.831911680725, 12339.07456940733, 12363.876361236453, 12364.938175309066, 12386.0, 12397.194182378116, 16514.75227871583, 16752.24614892333, 16769.161541922855, 16809.41167059147, 16812.0, 5718.828435146952, 5824.767009319579, 6495.025746138117, 6614.280287125382, 6683.899741503881, 970.2238209432426, 985.2315194148575, 986.413084214891, 1022.1158697726413, 1149.6953307373553, 29433.411331773674, 29573.55639665407, 29957.0, 30220.395592088036, 30373.088807335815, 1042.3537406807827, 1140.6920131564432, 1162.9360385178727, 1170.8302296167178, 1178.1988247991449, 15175.0, 15187.468438604596, 15308.617284567752, 15345.355055341375, 15459.304331305118, 623137.0, 633085.0, 633355.0, 635771.0, 636876.0, 58941.0, 61287.08069378963, 61384.693076534335, 61390.693046534485, 61660.07509887355, 1331.8202042724108, 1376.566381589796, 1379.9379027943767, 1510.0486693383098, 1579.3603590545847, 23634.0, 30376.240593985327, 30486.390272194876, 30987.69012309848, 31105.68894311028, 50650.0, 50996.490035099174, 51504.0, 51623.483765161865, 51686.224706630186, 394441.0, 397893.0, 398239.0, 398348.0, 398619.0, 33223.66776332302, 36500.08749781277, 37032.889013329645, 38419.6158038416, 39663.80168099256, 34679.0, 40972.97567560835, 42829.0, 43409.78295108631, 43725.0, 34894.476582851785, 36411.81794091013, 36470.63529364672, 37319.81340093282, 37410.25179496449, 35931.82034089813, 35957.820210898775, 36375.0, 36601.81699091488, 36840.0, 36344.27311453809, 36370.8181459091, 37138.44292335671, 37267.0, 37507.0, 26657.0, 30281.0, 31096.0, 32251.354972899942, 32626.673733262363, 24025.399365016016, 24421.51156976886, 24566.877165614056, 24687.135950241656, 24919.50160996806], "value": 19920.19681533618, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.74869957576936, 396.62320795244517, 397.640135677212, 397.72358211043303, 402.2819267607322, 13694.0, 19524.70712939307, 20533.178672853086, 20535.87052712102, 21143.894280528577, 16229.675406491862, 17648.117594120275, 18559.164837582306, 20426.489337766525, 20790.68813967788, 5208.635395522315, 5448.972755136228, 6061.484773794233, 6071.514278857703, 6072.817815465543, 5709.514691251248, 6052.667103309316, 6086.482648974842, 6099.57302988791, 6100.877982440348, 3916.921661566767, 4151.4395556599775, 5803.448672376117, 6119.847003824918, 6136.263648362203, 6028.457438830483, 6101.023836186211, 6118.388161183868, 6121.234845644294, 6123.234595675518, 5759.452851979056, 5951.2858456985, 5980.312264089598, 5991.460768530809, 5998.220231369894, 13128.34358282082, 16950.830491695047, 22999.0, 26223.34441638955, 26223.868880655664, 2052.3842847145947, 2068.6586713192305, 2089.112127345872, 2104.8737075775502, 2127.5213077057692, 8919.42023768453, 9667.27495437838, 9699.127078562964, 9997.80004399908, 10324.380537167794, 1714.8285171482876, 1730.6192637619704, 1735.9045252511107, 1759.1995641982867, 1787.3744189533688, 110710.0, 111443.0, 119791.0, 122099.38950305192, 126025.99999999908, 10386.584536618602, 10958.397288149068, 11714.355710436008, 11729.0, 11913.821292680617, 15155.393784248727, 15236.23818809055, 15265.236738163047, 15269.312880920384, 15270.847291527165, 7171.601537700099, 8403.285720713808, 8583.0129535103, 8607.698730544525, 8710.782230444289, 6888.380045795902, 8423.747287581382, 8446.57767111642, 8555.914440855511, 8568.314534837198, 4191.664666826647, 4274.995376086617, 4613.538646135359, 4620.838270660511, 4664.626829853603, 1495.820501539822, 1720.7094678990757, 1909.3603642779824, 1983.6925276582153, 2005.4585261979184, 816.3673153306116, 895.8387490251818, 930.2046750028628, 1026.3255469039673, 1031.947413638085, 19596.412107636792, 20549.48626284325, 20556.383308500768, 20660.483487912923, 20895.37313880586, 945.1163162443138, 972.4359871274644, 1006.954687039085, 1034.7257976636197, 1070.6466865934224, 7582.469227154046, 10502.264841461178, 10643.308184967953, 10908.072813810757, 11235.438228088562, 500222.0, 547367.0, 556188.0, 591996.0, 598070.0, 135468.0, 139123.60876391106, 139287.30356348155, 143074.0, 145509.54490455173, 1012.7974405119006, 1019.5819713917258, 1036.5024788101603, 1064.776396956648, 1068.4657671164418, 14127.223002734949, 14292.42830286777, 17867.01731404785, 18259.634807304046, 18547.44357669272, 48424.51575484341, 49136.508634913196, 49760.25359619681, 50963.74518127386, 53273.20090198728, 69716.65141674259, 71449.0, 72958.0, 74162.88755668777, 77383.4523309542, 6375.458086062737, 7134.928650713634, 7488.775336739906, 7571.3942884568005, 7799.220077992155, 8383.874241886255, 10536.420496872439, 10938.398388088413, 11966.34185119792, 12228.571999979958, 9355.4386736796, 11181.944090279496, 11590.188686791844, 11678.591249306233, 11680.649580512598, 10746.677599672023, 10820.4048777318, 11075.224734268524, 11287.7742445149, 11322.32066076038, 10428.582856685647, 10501.212409069503, 11030.44847757625, 11668.474918628512, 11732.12009099304, 7441.962790186014, 7445.10658720957, 7455.217202193909, 7504.737334193277, 7587.848243034998, 9465.952670236606, 10103.24225683062, 10360.171186305228, 10361.533730982275, 10423.114035307084], "value": 9261.490593169361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.0769288423198, 1589.6502769390738, 1603.4468108502567, 1846.870719049667, 1969.478088306599, 7781.338586220171, 8520.488770673765, 8727.301815854733, 8735.650573977042, 8745.91254087459, 8395.958020209906, 8589.01226358969, 8592.269657079156, 8609.0, 8610.138986101387, 2373.572756903752, 2404.7234568024637, 2407.4703565215714, 2408.614621660535, 2409.6024305902533, 2347.366211122996, 2385.42749740062, 2388.1283331584023, 2388.79695225906, 2398.2085911649115, 2374.252110585169, 2390.3187591536416, 2394.2218778896927, 2395.0719670665108, 2416.6858308419883, 2347.859128452298, 2370.6207006878903, 2372.9644055339186, 2381.2499062795164, 2395.916142934989, 2321.849079809807, 2351.6237402015595, 2356.8468049576895, 2359.2214569192124, 2360.386299562109, 8448.493090414595, 8610.956945215297, 8616.224539791387, 8679.522626255553, 8699.52152631605, 5143.254228136937, 5295.841124766224, 5301.973490132563, 5348.465153484659, 5350.491703288181, 3433.141714571357, 3461.2039230977016, 3543.255916257589, 3549.09498077991, 3555.8044307563073, 1008.8284991551454, 1027.5684212630713, 1028.275066078413, 1038.0709265207672, 1038.781855810273, 11167.162462815322, 11452.82820757681, 11457.197996140274, 11463.197576169672, 11472.942635286854, 3942.586028467027, 4364.825406983685, 4389.29771236607, 4574.108048930427, 4596.172688916027, 9056.411333263317, 9075.092490751003, 9081.364304498753, 9083.636654533744, 9106.134917182857, 5179.974100129475, 5183.170692689152, 5185.0148471790235, 5192.506711862367, 5219.556337711261, 4322.524522302544, 4478.843240486567, 4688.2733176357715, 4827.879303017453, 4849.660523763373, 3688.4651725499652, 3698.5746639136305, 3783.5459744830246, 3799.7910114943393, 3815.9427608585893, 1063.106990128296, 1162.9127815413879, 1180.7992641251008, 1187.8574571051356, 1205.6865215043977, 628.3025841316136, 758.852023855343, 876.4171825735941, 884.274894586433, 948.3456415073578, 5239.764210610531, 5598.804041858515, 5660.433956604306, 5734.913976290359, 5976.880462390728, 976.3946353260882, 1020.2348238820884, 1040.6513817871098, 1055.6885718712997, 1057.4448414582253, 3666.2484190740834, 3709.109813644706, 3799.5630502492013, 3835.2329534092737, 3887.1448281378052, 10015.849762253572, 10629.734256643645, 10808.729781755519, 10810.351378917132, 10816.91830816912, 10620.734481638021, 10755.946220269005, 10757.462126893624, 10767.246292759432, 10770.56917723298, 7814.843703125905, 7983.68065277394, 7993.280604745484, 8109.959450202711, 8145.6741730331305, 5551.361593416808, 5573.526250268773, 5639.379668236573, 5692.48767610917, 5802.796902108406, 9757.609695612096, 9767.316287859781, 9855.0, 10002.649907253211, 10013.499325033865, 9292.907070929205, 9845.538615346164, 10015.749606259611, 10047.145992590711, 10086.899131008597, 7221.458390620619, 7325.6337183141695, 7388.963055184689, 7392.482526223328, 7398.926010740039, 7918.3665306776475, 8267.586620669063, 8317.500949943023, 8319.001719793663, 8333.916660833556, 6329.240491141092, 7278.6724597392185, 7357.22749111357, 7642.0, 7819.569923654253, 6440.645764482999, 6997.825054373681, 7468.365188958999, 7526.24737526243, 7633.427492937941, 6155.938440615537, 6196.659183744757, 6661.933380666132, 6761.357671021246, 6764.255931847394, 4873.585745211697, 4881.243407271879, 4890.119778439915, 4958.57852082577, 4970.3290055843445, 4252.723572967747, 5016.322796422436, 5045.192769156917, 5057.443681195139, 5095.388553373619], "value": 4500.163381336619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.1397356836978, 320.83637344954076, 323.22426177174793, 330.47124600638966, 331.37370370000696, 4902.852914412569, 5213.426523082468, 5906.907222163892, 6021.2774467063955, 6025.367336429677, 5247.449017853128, 5421.99999999999, 5762.654240745558, 5837.919984802815, 5898.439648233421, 3988.3419235826127, 4432.667549933752, 4497.887552811173, 4508.706934049293, 4525.637948964075, 3946.3291240489043, 4494.123645889036, 4511.571400716943, 4530.592246697796, 4535.795889184995, 3450.2742833161774, 3581.6019601203147, 3740.756850804689, 4491.236489796726, 4507.72953622782, 3514.7012503937194, 4196.832126714959, 4472.284434490466, 4488.079943611552, 4495.46054473462, 3511.5961664408665, 3909.960900390988, 3910.0, 3912.197999410114, 3918.5689574146822, 4782.13921494131, 5566.248556444909, 5716.79991200306, 5749.166370876242, 5792.420757924216, 4705.482396936334, 4851.975740121312, 4989.027139707759, 5085.313482679828, 5276.736163191864, 6323.430891219812, 6330.0, 6347.619142851444, 6375.553711240215, 6384.936150638527, 4207.926978620463, 4359.237133501624, 4360.869173924787, 4383.890402739957, 4391.714538554984, 5073.061483625541, 5753.769849206069, 6613.8081605939105, 6688.665566721645, 6698.229703584101, 4396.230659634615, 4414.448193975753, 4436.778161091933, 4444.911101777946, 4452.354408610733, 4034.65705415043, 4205.3481710334945, 4327.6105150536605, 4516.0, 4527.366168736379, 4578.153041687232, 4999.800007999639, 5204.713740744295, 5238.5023422774775, 5262.526372626406, 5529.834104976857, 5839.883202335929, 6204.441600255998, 6391.96804015977, 6395.584287021329, 1282.2562913510142, 1509.411329581464, 1519.7188520123811, 1522.885783566237, 1566.5065504366316, 936.5598168860624, 1012.3773879064322, 1019.37308555238, 1040.4745603470199, 1045.7124290820086, 4878.317035615017, 5277.176710459962, 5610.631932756351, 5938.257717785277, 5953.940460595425, 6537.542372033912, 6552.967235163889, 6577.506686998494, 6577.704003319862, 6583.111279977237, 5765.8558536036935, 5905.557083218776, 5928.87351403232, 5944.4352786485215, 5972.611780234271, 2863.8687718351134, 3425.8588382261087, 3432.502287168347, 3441.0, 3464.0, 6613.66931653425, 6805.3931416349415, 6824.590524568443, 6826.829329266808, 6836.769381511276, 5262.868428289246, 6715.597064176165, 6716.361945615159, 6718.0930574372815, 6721.1598550181225, 6015.6691381974415, 6590.846601844658, 6779.830504237433, 6791.147717958568, 6795.864082718318, 6516.543841931019, 6528.584423763238, 6537.346265373425, 6557.96721016411, 6567.0, 6306.983046853021, 6350.301466838551, 6436.935630643634, 6466.159399278252, 6471.4822814175695, 5804.970975145097, 6717.596944183169, 6784.59292442455, 6785.728570857111, 6813.981598953086, 4398.208322501852, 4428.800703968264, 4439.378487011885, 4453.7105088170465, 4455.68810183284, 6257.812265632038, 6258.280297765815, 6488.994205898093, 6502.154719886385, 6507.275571973336, 6125.0, 6220.502359811295, 6369.522285828489, 6384.201974753156, 6389.265234497906, 5865.0, 6317.399847014526, 6338.334474880256, 6388.87222255543, 6400.23197216337, 4825.30033145199, 5449.64577302474, 5912.201852749824, 6075.392460753889, 6244.125822384961, 6326.0, 6379.4896408286295, 6383.648269971408, 6390.8815957208235, 6393.392627700361, 6011.639301641915, 6235.688215589112, 6254.343293954252, 6257.186565746424, 6287.874242515032], "value": 4602.602614765345, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.4077609763164, 716.9641517924102, 737.3474475089544, 737.7971057959064, 745.0500611720058, 14059.929700351497, 14747.77878331826, 15254.237288135604, 15287.541373758791, 15343.156126413043, 11805.822912656316, 14031.29843507823, 14075.788863167061, 14106.294685265748, 14116.505922292721, 723.330918900018, 931.16660588773, 939.5490164720939, 947.7820101376653, 948.3788118782209, 748.7154881145157, 912.5209265135791, 948.4072454715803, 949.1931857920781, 953.2897990996682, 977.9608815647366, 988.614440368257, 988.658912675126, 988.7874107066979, 990.8117457683018, 754.7660225330166, 960.8654788329569, 978.0806042320232, 979.4319294808994, 982.8132654795565, 703.4477934821141, 740.1894925057054, 741.269849198539, 742.3059439424155, 743.4981387563428, 15060.0, 15087.698246035128, 15088.924555377263, 15136.470223542123, 15153.393864245418, 13183.934080329633, 13198.802017969738, 13208.405621746948, 13319.800202996963, 13477.797833032513, 9896.90103098967, 9944.850827237597, 9987.151092157175, 10066.0, 10193.490325483695, 3476.1309672581856, 3507.9649203508147, 3556.466530020492, 3567.8572857085687, 3579.2662504186837, 93569.0, 94119.52940235255, 94509.0, 94728.0, 96844.0, 9796.942642045107, 9840.0, 9891.802163956681, 10157.288989770794, 10263.692089237335, 22994.42513937165, 23127.53744925092, 23166.0, 23205.3038408848, 23374.8831255846, 7500.287472690085, 7609.124950630638, 7649.426293028045, 7761.223877612194, 7820.608969551614, 4051.858184963512, 4115.73247738896, 4116.320807066861, 4122.91754164915, 4180.268453020709, 3569.1077230692326, 3679.35611268027, 3679.448082787603, 3681.834317455721, 3683.208110256308, 622.3807311724863, 635.5582869905371, 641.4291280760127, 642.3640595810208, 651.4202359899693, 504.2335649812338, 544.8692313844649, 550.7796881247533, 585.1281590430314, 586.6597373523347, 7663.540187588763, 7705.807354816062, 7900.446968712135, 8020.919790802017, 8332.958335208285, 520.8359366799446, 526.4735264735265, 529.4282175250797, 585.2274997003914, 586.5190096775565, 3683.2817600567632, 3731.776093434403, 3782.6028267032116, 3791.7914514701956, 3859.845606175778, 94728.52635736777, 97234.0, 97424.51287743516, 98343.50828245813, 99538.50230748944, 75018.0, 77389.22610773933, 77650.22349776543, 77895.2210477888, 77995.61002194953, 69338.65330673415, 73408.63295683594, 75702.7316338264, 75769.0, 76824.0, 7369.96315018443, 7680.5391676499585, 7709.575973321521, 7775.494592851446, 7813.257740514642, 36400.0, 45488.77255613701, 46439.14243430225, 46842.76578617085, 47301.52698472971, 51347.71630709262, 51409.48590514047, 53237.7338113307, 62894.0, 63081.36918630755, 10726.302790318605, 10834.729131771768, 10860.674179774303, 10990.505427255634, 11566.537338506701, 12055.397230138633, 12148.392580371123, 12235.143539952118, 12294.81557776652, 12496.500139994298, 11501.19491635611, 11516.0, 11643.88356116428, 11680.649580512598, 11919.344436055753, 11323.490442930253, 11743.941280293544, 11851.644450666494, 12008.579699710468, 12049.939750301193, 10727.678169654922, 10972.396518191576, 10978.12175026012, 11327.263727857662, 11371.829422558503, 9382.390144640576, 9517.571709272961, 9662.420254784736, 9729.173020293072, 9923.0, 9264.814703706023, 9634.277429192978, 9763.365381250196, 9836.950815245878, 9933.701988940344], "value": 6918.428648425781, "unit": "score"}], "name": "dromaeo_css", "value": 7280.1470822392785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 113.0, 110.0, 113.0, 112.0, 119.0, 111.0, 112.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 127.0, 143.0, 169.0, 119.0, 143.0, 137.0, 114.0, 171.0, 122.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 187.0, 184.0, 186.0, 187.0, 181.0, 183.0, 184.0, 184.0, 184.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 86.0, 93.0, 57.0, 58.0, 58.0, 79.0, 86.0, 55.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 134.0, 78.0, 79.0, 80.0, 121.0, 80.0, 77.0, 131.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 86.0, 84.0, 91.0, 83.0, 93.0, 86.0, 87.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 108.0, 149.0, 103.0, 93.0, 93.0, 97.0, 92.0, 92.0, 92.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 83.0, 101.0, 140.0, 134.0, 85.0, 87.0, 85.0, 85.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 58.0, 60.0, 58.0, 56.0, 78.0, 84.0, 88.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 73.0, 73.0, 69.0, 69.0, 67.0, 66.0, 67.0, 66.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 82.0, 81.0, 82.0, 81.0, 81.0, 79.0, 82.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 137.0, 146.0, 151.0, 151.0, 144.0, 136.0, 176.0, 140.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 171.0, 170.0, 169.0, 173.0, 180.0, 168.0, 166.0, 173.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 68.0, 71.0, 69.0, 67.0, 69.0, 68.0, 69.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1492.0, "alertThreshold": 2.0}]} 06:08:48 INFO - Return code: 0 06:08:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:08:48 ERROR - # TBPL SUCCESS # 06:08:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:48 INFO - 2016-04-22 06:08:48.417 screenresolution[1678:10070] starting screenresolution argv=screenresolution get 06:08:48 INFO - 2016-04-22 06:08:48.420 screenresolution[1678:10070] Display 0: 1600x1200x32@60 06:08:48 INFO - 2016-04-22 06:08:48.426 screenresolution[1679:10073] starting screenresolution argv=screenresolution list 06:08:48 INFO - Available Modes on Display 0 06:08:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:48 INFO - Intel Iris: 06:08:48 INFO - Chipset Model: Intel Iris 06:08:48 INFO - Type: GPU 06:08:48 INFO - Bus: Built-In 06:08:48 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:48 INFO - Vendor: Intel (0x8086) 06:08:48 INFO - Device ID: 0x0a2e 06:08:48 INFO - Revision ID: 0x0009 06:08:48 INFO - Displays: 06:08:48 INFO - Display: 06:08:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:48 INFO - Display Serial Number: 1600x1200 60 06:08:48 INFO - Main Display: Yes 06:08:48 INFO - Mirror: Off 06:08:48 INFO - Online: Yes 06:08:48 INFO - Rotation: Supported 06:08:48 INFO - Return code: 0 06:08:48 INFO - Running post-action listener: _resource_record_post_action 06:08:48 INFO - Running post-run listener: _resource_record_post_run 06:08:49 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37926400; Write bytes: 695311360; Read time: 394; Write time: 2032 06:08:49 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377719296; Write bytes: 372266496; Read time: 27983; Write time: 725 06:08:49 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 37004800; Write bytes: 297850368; Read time: 381; Write time: 1261 06:08:49 INFO - Running post-run listener: _upload_blobber_files 06:08:49 INFO - Blob upload gear active. 06:08:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:08:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:08:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:08:50 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:08:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:08:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:50 INFO - (blobuploader) - INFO - Done attempting. 06:08:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:08:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:08:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:51 INFO - (blobuploader) - INFO - Done attempting. 06:08:51 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:51 INFO - Return code: 0 06:08:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/708bca36c7e2f41ff4877ff2c0a0644cf35393b6047589d0b40f94a9d87cb1f2417116bf9a268639c4e205471d618ac3e7d525a52f122639ffebb3dd8ae10e3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/776a617859e3755df0f8b26a1dbacc253c1d54233a9d1282d21500e01f5e0a4412d93ad65ab923dd89dadf1343e68c915fdce158e85500425cec98d342877d2e"} 06:08:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:51 INFO - Contents: 06:08:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/708bca36c7e2f41ff4877ff2c0a0644cf35393b6047589d0b40f94a9d87cb1f2417116bf9a268639c4e205471d618ac3e7d525a52f122639ffebb3dd8ae10e3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/776a617859e3755df0f8b26a1dbacc253c1d54233a9d1282d21500e01f5e0a4412d93ad65ab923dd89dadf1343e68c915fdce158e85500425cec98d342877d2e"} 06:08:51 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:51 INFO - Copying logs to upload dir... 06:08:51 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.684304 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-22 06:08:52.071540) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:08:52.079182) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j0JeRqySaq/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/708bca36c7e2f41ff4877ff2c0a0644cf35393b6047589d0b40f94a9d87cb1f2417116bf9a268639c4e205471d618ac3e7d525a52f122639ffebb3dd8ae10e3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/776a617859e3755df0f8b26a1dbacc253c1d54233a9d1282d21500e01f5e0a4412d93ad65ab923dd89dadf1343e68c915fdce158e85500425cec98d342877d2e"} build_url:https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/708bca36c7e2f41ff4877ff2c0a0644cf35393b6047589d0b40f94a9d87cb1f2417116bf9a268639c4e205471d618ac3e7d525a52f122639ffebb3dd8ae10e3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/776a617859e3755df0f8b26a1dbacc253c1d54233a9d1282d21500e01f5e0a4412d93ad65ab923dd89dadf1343e68c915fdce158e85500425cec98d342877d2e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:08:52.113826) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 06:08:52.114192) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j0JeRqySaq/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.dhs0jetSyl/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005048 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 06:08:52.171663) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:08:52.175022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:08:52.175475) ========= ========= Total master_lag: 7.77 =========