builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0161 starttime: 1467881822.56 results: success (0) buildid: 20160706234023 builduid: c57e29f997e248af8b2750d386449388 revision: b9cc2dcbf4363c8049183110fe79e026fae87ecb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.562030) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.562459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.562788) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.650021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.650319) ========= 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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-07-07 01:57:02-- 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% 9.32M=0.001s 2016-07-07 01:57:02 (9.32 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095207 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.780189) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.780513) ========= 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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.027214 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:02.834777) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 01:57:02.835508) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b9cc2dcbf4363c8049183110fe79e026fae87ecb --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 b9cc2dcbf4363c8049183110fe79e026fae87ecb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-07-07 01:57:02,912 truncating revision to first 12 chars 2016-07-07 01:57:02,912 Setting DEBUG logging. 2016-07-07 01:57:02,912 attempt 1/10 2016-07-07 01:57:02,912 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b9cc2dcbf436?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 01:57:04,129 unpacking tar archive at: fx-team-b9cc2dcbf436/testing/mozharness/ program finished with exit code 0 elapsedTime=1.498496 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 01:57:04.360167) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:04.360950) ========= script_repo_revision: b9cc2dcbf4363c8049183110fe79e026fae87ecb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:04.361647) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:04.361957) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 01:57:04.386905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-07 01:57:04.387338) ========= /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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:04 INFO - MultiFileLogger online at 20160707 01:57:04 in /builds/slave/test 01:57:04 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 01:57:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:57:04 INFO - {'append_to_log': False, 01:57:04 INFO - 'base_work_dir': '/builds/slave/test', 01:57:04 INFO - 'blob_upload_branch': 'Fx-Team', 01:57:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:57:04 INFO - 'branch': 'Fx-Team', 01:57:04 INFO - 'buildbot_json_path': 'buildprops.json', 01:57:04 INFO - 'config_files': ('talos/mac_config.py',), 01:57:04 INFO - 'default_actions': ('clobber', 01:57:04 INFO - 'read-buildbot-config', 01:57:04 INFO - 'download-and-extract', 01:57:04 INFO - 'populate-webroot', 01:57:04 INFO - 'create-virtualenv', 01:57:04 INFO - 'install', 01:57:04 INFO - 'run-tests'), 01:57:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:57:04 INFO - 'download_minidump_stackwalk': True, 01:57:04 INFO - 'download_symbols': 'ondemand', 01:57:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:57:04 INFO - 'tooltool.py': '/tools/tooltool.py', 01:57:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:57:04 INFO - '/tools/misc-python/virtualenv.py')}, 01:57:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:57:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:57:04 INFO - 'installer_path': 'installer.exe', 01:57:04 INFO - 'log_level': 'info', 01:57:04 INFO - 'log_name': 'talos', 01:57:04 INFO - 'log_to_console': True, 01:57:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:57:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:57:04 INFO - 'opt_config_files': (), 01:57:04 INFO - 'pip_index': False, 01:57:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:57:04 INFO - 'cmd': ('bash', 01:57:04 INFO - '-c', 01:57:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:57:04 INFO - 'enabled': True, 01:57:04 INFO - 'halt_on_failure': False, 01:57:04 INFO - 'name': 'check_screen_resolution'},), 01:57:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:57:04 INFO - 'cmd': ('bash', 01:57:04 INFO - '-c', 01:57:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:57:04 INFO - 'enabled': True, 01:57:04 INFO - 'halt_on_failure': False, 01:57:04 INFO - 'name': 'check_screen_resolution'},), 01:57:04 INFO - 'run_cmd_checks_enabled': True, 01:57:04 INFO - 'sps_profile': False, 01:57:04 INFO - 'sps_profile_interval': 0, 01:57:04 INFO - 'suite': 'dromaeojs', 01:57:04 INFO - 'system_bits': '32', 01:57:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:57:04 INFO - 'title': 't-yosemite-r7-0161', 01:57:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:57:04 INFO - 'use_talos_json': True, 01:57:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:57:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:57:04 INFO - 'work_dir': 'build'} 01:57:04 INFO - ##### 01:57:04 INFO - ##### Running clobber step. 01:57:04 INFO - ##### 01:57:04 INFO - Running pre-action listener: _resource_record_pre_action 01:57:04 INFO - Running main action method: clobber 01:57:04 INFO - rmtree: /builds/slave/test/build 01:57:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:57:06 INFO - Running post-action listener: _resource_record_post_action 01:57:06 INFO - ##### 01:57:06 INFO - ##### Finished clobber step (success) 01:57:06 INFO - ##### 01:57:06 INFO - ##### 01:57:06 INFO - ##### Running read-buildbot-config step. 01:57:06 INFO - ##### 01:57:06 INFO - Running pre-action listener: _resource_record_pre_action 01:57:06 INFO - Running main action method: read_buildbot_config 01:57:06 INFO - Using buildbot properties: 01:57:06 INFO - { 01:57:06 INFO - "project": "", 01:57:06 INFO - "product": "firefox", 01:57:06 INFO - "script_repo_revision": "production", 01:57:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:57:06 INFO - "repository": "", 01:57:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:57:06 INFO - "buildid": "20160706234023", 01:57:06 INFO - "pgo_build": "False", 01:57:06 INFO - "basedir": "/builds/slave/test", 01:57:06 INFO - "buildnumber": 65, 01:57:06 INFO - "slavename": "t-yosemite-r7-0161", 01:57:06 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 01:57:06 INFO - "platform": "yosemite_r7", 01:57:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:57:06 INFO - "branch": "fx-team", 01:57:06 INFO - "repo_path": "integration/fx-team", 01:57:06 INFO - "revision": "b9cc2dcbf4363c8049183110fe79e026fae87ecb", 01:57:06 INFO - "stage_platform": "macosx64", 01:57:06 INFO - "builduid": "c57e29f997e248af8b2750d386449388", 01:57:06 INFO - "slavebuilddir": "test" 01:57:06 INFO - } 01:57:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 01:57:06 INFO - Running post-action listener: _resource_record_post_action 01:57:06 INFO - ##### 01:57:06 INFO - ##### Finished read-buildbot-config step (success) 01:57:06 INFO - ##### 01:57:06 INFO - ##### 01:57:06 INFO - ##### Running download-and-extract step. 01:57:06 INFO - ##### 01:57:06 INFO - Running pre-action listener: _resource_record_pre_action 01:57:06 INFO - Running main action method: download_and_extract 01:57:06 INFO - mkdir: /builds/slave/test/build/tests 01:57:06 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')]} 01:57:06 INFO - https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:57:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:57:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:57:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 01:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 01:57:08 INFO - Downloaded 1199 bytes. 01:57:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 01:57:08 INFO - Using the following test package requirements: 01:57:08 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:57:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 01:57:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 01:57:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'jsshell-mac.zip'], 01:57:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 01:57:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:57:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 01:57:08 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 01:57:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 01:57:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:57:08 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 01:57:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:57: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')]} 01:57:08 INFO - https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:57:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:57:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:57:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:57:09 INFO - Downloaded 18804819 bytes. 01:57:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:57:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:57:12 INFO - Return code: 0 01:57:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:57:12 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')]} 01:57:12 INFO - https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:57:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:57:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:57:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:57:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:57:13 INFO - Downloaded 18804819 bytes. 01:57:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:57:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:57:17 INFO - Return code: 0 01:57:17 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')]} 01:57:17 INFO - https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:57:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:57:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:57:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:57:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:57:19 INFO - Downloaded 14403333 bytes. 01:57:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:57:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:57:20 INFO - Return code: 0 01:57:20 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')]} 01:57:20 INFO - https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:57:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:57:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:57:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 01:57:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 01:57:26 INFO - Downloaded 112386880 bytes. 01:57:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:57:26 INFO - mkdir: /builds/slave/test/properties 01:57:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:57:26 INFO - Writing to file /builds/slave/test/properties/build_url 01:57:26 INFO - Contents: 01:57:26 INFO - build_url:https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:57:27 INFO - Running post-action listener: _resource_record_post_action 01:57:27 INFO - Running post-action listener: set_extra_try_arguments 01:57:27 INFO - ##### 01:57:27 INFO - ##### Finished download-and-extract step (success) 01:57:27 INFO - ##### 01:57:27 INFO - ##### 01:57:27 INFO - ##### Running populate-webroot step. 01:57:27 INFO - ##### 01:57:27 INFO - Running pre-action listener: _resource_record_pre_action 01:57:27 INFO - Running main action method: populate_webroot 01:57:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:57:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:57:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:57:27 INFO - '--tppagecycles', 01:57:27 INFO - '7'], 01:57:27 INFO - 'tests': ['tsvgm']}}, 01:57:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:57:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:57:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:57:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:57:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:57:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:57:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['damp', 'tps']}, 01:57:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'tests': ['damp', 'tps']}, 01:57:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['dromaeo_dom']}, 01:57:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:57:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['basic_compositor_video']}, 01:57:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:57:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['a11yr', 01:57:27 INFO - 'ts_paint', 01:57:27 INFO - 'tpaint', 01:57:27 INFO - 'sessionrestore', 01:57:27 INFO - 'sessionrestore_no_auto_restore', 01:57:27 INFO - 'tabpaint']}, 01:57:27 INFO - 'other-e10s': {'tests': ['a11yr', 01:57:27 INFO - 'ts_paint', 01:57:27 INFO - 'tpaint', 01:57:27 INFO - 'sessionrestore', 01:57:27 INFO - 'sessionrestore_no_auto_restore', 01:57:27 INFO - 'tabpaint']}, 01:57:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:57:27 INFO - 'ts_paint', 01:57:27 INFO - 'tpaint', 01:57:27 INFO - 'sessionrestore', 01:57:27 INFO - 'sessionrestore_no_auto_restore', 01:57:27 INFO - 'tabpaint']}, 01:57:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:57:27 INFO - 'ts_paint', 01:57:27 INFO - 'tpaint', 01:57:27 INFO - 'sessionrestore', 01:57:27 INFO - 'sessionrestore_no_auto_restore', 01:57:27 INFO - 'tabpaint']}, 01:57:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['a11yr', 01:57:27 INFO - 'ts_paint', 01:57:27 INFO - 'tpaint', 01:57:27 INFO - 'sessionrestore', 01:57:27 INFO - 'sessionrestore_no_auto_restore', 01:57:27 INFO - 'tabpaint']}, 01:57:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['a11yr', 01:57:27 INFO - 'ts_paint', 01:57:27 INFO - 'tpaint', 01:57:27 INFO - 'sessionrestore', 01:57:27 INFO - 'sessionrestore_no_auto_restore', 01:57:27 INFO - 'tabpaint']}, 01:57:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['tsvgx', 01:57:27 INFO - 'tsvgr_opacity', 01:57:27 INFO - 'tart', 01:57:27 INFO - 'tscrollx', 01:57:27 INFO - 'cart']}, 01:57:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:57:27 INFO - 'tsvgr_opacity', 01:57:27 INFO - 'tart', 01:57:27 INFO - 'tscrollx', 01:57:27 INFO - 'cart']}, 01:57:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'talos_options': ['--disable-e10s'], 01:57:27 INFO - 'tests': ['tp5o']}, 01:57:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'tests': ['tp5o']}, 01:57:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'talos_options': ['--disable-e10s', 01:57:27 INFO - '--xperf_path', 01:57:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:57:27 INFO - 'tests': ['tp5n']}, 01:57:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:27 INFO - 'talos_options': ['--xperf_path', 01:57:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:57:27 INFO - 'tests': ['tp5n']}}, 01:57:27 INFO - 'talos.zip': {'path': '', 01:57:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:57:27 INFO - Running post-action listener: _resource_record_post_action 01:57:27 INFO - ##### 01:57:27 INFO - ##### Finished populate-webroot step (success) 01:57:27 INFO - ##### 01:57:27 INFO - ##### 01:57:27 INFO - ##### Running create-virtualenv step. 01:57:27 INFO - ##### 01:57:27 INFO - Running pre-action listener: _resource_record_pre_action 01:57:27 INFO - Running main action method: create_virtualenv 01:57:27 INFO - Creating virtualenv /builds/slave/test/build/venv 01:57:27 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 01:57:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:57:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:57:27 INFO - Using real prefix '/tools/python27' 01:57:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:57:28 INFO - Installing distribute.............................................................................................................................................................................................done. 01:57:31 INFO - Installing pip.................done. 01:57:31 INFO - Return code: 0 01:57:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:57: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')]} 01:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57: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')]} 01:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57: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', 'pip>=1.5'] in /builds/slave/test/build 01:57: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 pip>=1.5 01:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:31 INFO - 'HOME': '/Users/cltbld', 01:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:31 INFO - 'LOGNAME': 'cltbld', 01:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:31 INFO - 'MOZ_NO_REMOTE': '1', 01:57:31 INFO - 'NO_EM_RESTART': '1', 01:57:31 INFO - 'PAGER': '/bin/cat', 01:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:31 INFO - 'PWD': '/builds/slave/test', 01:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:31 INFO - 'SHELL': '/bin/bash', 01:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:31 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:31 INFO - 'USER': 'cltbld', 01:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:31 INFO - 'XPC_FLAGS': '0x0', 01:57:31 INFO - 'XPC_SERVICE_NAME': '0', 01:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:31 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 01:57:31 INFO - Cleaning up... 01:57:31 INFO - Return code: 0 01:57:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:57: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')]} 01:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57: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')]} 01:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57: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', 'psutil>=3.1.1'] in /builds/slave/test/build 01:57: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 psutil>=3.1.1 01:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:31 INFO - 'HOME': '/Users/cltbld', 01:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:31 INFO - 'LOGNAME': 'cltbld', 01:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:31 INFO - 'MOZ_NO_REMOTE': '1', 01:57:31 INFO - 'NO_EM_RESTART': '1', 01:57:31 INFO - 'PAGER': '/bin/cat', 01:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:31 INFO - 'PWD': '/builds/slave/test', 01:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:31 INFO - 'SHELL': '/bin/bash', 01:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:31 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:31 INFO - 'USER': 'cltbld', 01:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:31 INFO - 'XPC_FLAGS': '0x0', 01:57:31 INFO - 'XPC_SERVICE_NAME': '0', 01:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:31 INFO - Downloading/unpacking psutil>=3.1.1 01:57: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 01:57: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 01:57: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 01:57: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 01:57: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 01:57: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 01:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:57:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:57:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:57:34 INFO - Installing collected packages: psutil 01:57:34 INFO - Running setup.py install for psutil 01:57:34 INFO - building 'psutil._psutil_osx' extension 01:57:34 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 01:57:35 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 01:57:35 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 01:57:35 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 01:57:35 INFO - building 'psutil._psutil_posix' extension 01:57:35 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 01:57:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:57:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:57:35 INFO - ^ 01:57:35 INFO - 1 warning generated. 01:57:35 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 01:57:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:57:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:57:36 INFO - Successfully installed psutil 01:57:36 INFO - Cleaning up... 01:57:36 INFO - Return code: 0 01:57:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:57:36 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')]} 01:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:36 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')]} 01:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:36 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:36 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.3'] in /builds/slave/test/build 01:57:36 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.3 01:57:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:36 INFO - 'HOME': '/Users/cltbld', 01:57:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:36 INFO - 'LOGNAME': 'cltbld', 01:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:36 INFO - 'MOZ_NO_REMOTE': '1', 01:57:36 INFO - 'NO_EM_RESTART': '1', 01:57:36 INFO - 'PAGER': '/bin/cat', 01:57:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:36 INFO - 'PWD': '/builds/slave/test', 01:57:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:36 INFO - 'SHELL': '/bin/bash', 01:57:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:36 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:36 INFO - 'USER': 'cltbld', 01:57:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:36 INFO - 'XPC_FLAGS': '0x0', 01:57:36 INFO - 'XPC_SERVICE_NAME': '0', 01:57:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:57:36 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 01:57:36 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 01:57:36 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 01:57:36 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 01:57:36 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 01:57:36 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 01:57:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:57:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:57:39 INFO - Installing collected packages: mozsystemmonitor 01:57:39 INFO - Running setup.py install for mozsystemmonitor 01:57:39 INFO - Successfully installed mozsystemmonitor 01:57:39 INFO - Cleaning up... 01:57:39 INFO - Return code: 0 01:57:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:57:39 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')]} 01:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:39 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')]} 01:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:39 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:39 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==2.5.1'] in /builds/slave/test/build 01:57:39 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==2.5.1 01:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:39 INFO - 'HOME': '/Users/cltbld', 01:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:39 INFO - 'LOGNAME': 'cltbld', 01:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:39 INFO - 'MOZ_NO_REMOTE': '1', 01:57:39 INFO - 'NO_EM_RESTART': '1', 01:57:39 INFO - 'PAGER': '/bin/cat', 01:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:39 INFO - 'PWD': '/builds/slave/test', 01:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:39 INFO - 'SHELL': '/bin/bash', 01:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:39 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:39 INFO - 'USER': 'cltbld', 01:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:39 INFO - 'XPC_FLAGS': '0x0', 01:57:39 INFO - 'XPC_SERVICE_NAME': '0', 01:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:39 INFO - Downloading/unpacking jsonschema==2.5.1 01:57:39 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 01:57:39 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 01:57:39 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 01:57:39 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 01:57:39 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 01:57:39 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 01:57:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:57:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:57:41 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 01:57:41 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 01:57:41 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 01:57:41 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 01:57:41 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 01:57:41 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 01:57:42 INFO - Downloading functools32-3.2.3-2.tar.gz 01:57:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:57:42 INFO - warning: no files found matching '*.txt' 01:57:42 INFO - no previously-included directories found matching 'build' 01:57:42 INFO - no previously-included directories found matching 'dist' 01:57:42 INFO - no previously-included directories found matching '.git*' 01:57:42 INFO - Installing collected packages: jsonschema, functools32 01:57:42 INFO - Running setup.py install for functools32 01:57:42 INFO - warning: no files found matching '*.txt' 01:57:42 INFO - no previously-included directories found matching 'build' 01:57:42 INFO - no previously-included directories found matching 'dist' 01:57:42 INFO - no previously-included directories found matching '.git*' 01:57:42 INFO - Successfully installed jsonschema functools32 01:57:42 INFO - Cleaning up... 01:57:42 INFO - Return code: 0 01:57:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:57:42 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')]} 01:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:42 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')]} 01:57:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:42 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 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:42 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 01:57:42 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 01:57:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:42 INFO - 'HOME': '/Users/cltbld', 01:57:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:42 INFO - 'LOGNAME': 'cltbld', 01:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:42 INFO - 'MOZ_NO_REMOTE': '1', 01:57:42 INFO - 'NO_EM_RESTART': '1', 01:57:42 INFO - 'PAGER': '/bin/cat', 01:57:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:42 INFO - 'PWD': '/builds/slave/test', 01:57:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:42 INFO - 'SHELL': '/bin/bash', 01:57:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:42 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:42 INFO - 'USER': 'cltbld', 01:57:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:42 INFO - 'XPC_FLAGS': '0x0', 01:57:42 INFO - 'XPC_SERVICE_NAME': '0', 01:57:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:42 INFO - Downloading/unpacking blobuploader==1.2.4 01:57:42 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 01:57:42 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 01:57:42 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 01:57:42 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 01:57:42 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 01:57:42 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 01:57:45 INFO - Downloading blobuploader-1.2.4.tar.gz 01:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:57:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:57:45 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 01:57:45 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 01:57:45 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 01:57:45 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 01:57:45 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 01:57:45 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 01:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:57:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:57:46 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 01:57:46 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 01:57:46 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 01:57:46 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 01:57:46 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 01:57:46 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 01:57:46 INFO - Downloading docopt-0.6.1.tar.gz 01:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:57:46 INFO - Installing collected packages: blobuploader, requests, docopt 01:57:46 INFO - Running setup.py install for blobuploader 01:57:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:57:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:57:47 INFO - Running setup.py install for requests 01:57:47 INFO - Running setup.py install for docopt 01:57:47 INFO - Successfully installed blobuploader requests docopt 01:57:47 INFO - Cleaning up... 01:57:47 INFO - Return code: 0 01:57:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:57: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')]} 01:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57: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')]} 01:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:47 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 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:47 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 01:57:47 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 01:57:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:47 INFO - 'HOME': '/Users/cltbld', 01:57:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:47 INFO - 'LOGNAME': 'cltbld', 01:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:47 INFO - 'MOZ_NO_REMOTE': '1', 01:57:47 INFO - 'NO_EM_RESTART': '1', 01:57:47 INFO - 'PAGER': '/bin/cat', 01:57:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:47 INFO - 'PWD': '/builds/slave/test', 01:57:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:47 INFO - 'SHELL': '/bin/bash', 01:57:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:47 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:47 INFO - 'USER': 'cltbld', 01:57:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:47 INFO - 'XPC_FLAGS': '0x0', 01:57:47 INFO - 'XPC_SERVICE_NAME': '0', 01:57:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-x2N_wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-HNkO6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-PjkxWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-42Nbab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-R41DGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-vmHicz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-gUJCpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-eOqwjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:57:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-_Qid68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-FolPDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-8YdKTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Jp7I_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-FCjcHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-WIiAag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-iUo7MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-nPl6hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:57:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-y4e6cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:57:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:57:49 INFO - Running setup.py install for manifestparser 01:57:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:57:50 INFO - Running setup.py install for mozcrash 01:57:50 INFO - Running setup.py install for mozdebug 01:57:50 INFO - Running setup.py install for mozdevice 01:57:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:57:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:57:50 INFO - Running setup.py install for mozfile 01:57:50 INFO - Running setup.py install for mozhttpd 01:57:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:57:50 INFO - Running setup.py install for mozinfo 01:57:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:57:50 INFO - Running setup.py install for mozInstall 01:57:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Running setup.py install for mozleak 01:57:51 INFO - Running setup.py install for mozlog 01:57:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Running setup.py install for moznetwork 01:57:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Running setup.py install for mozprocess 01:57:51 INFO - Running setup.py install for mozprofile 01:57:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:57:51 INFO - Running setup.py install for mozrunner 01:57:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:57:52 INFO - Running setup.py install for mozscreenshot 01:57:52 INFO - Running setup.py install for moztest 01:57:52 INFO - Running setup.py install for mozversion 01:57:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:57:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:57:52 INFO - Cleaning up... 01:57:52 INFO - Return code: 0 01:57:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:57:52 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')]} 01:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:52 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')]} 01:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:52 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 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:52 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 01:57:52 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 01:57:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:52 INFO - 'HOME': '/Users/cltbld', 01:57:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:52 INFO - 'LOGNAME': 'cltbld', 01:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:52 INFO - 'MOZ_NO_REMOTE': '1', 01:57:52 INFO - 'NO_EM_RESTART': '1', 01:57:52 INFO - 'PAGER': '/bin/cat', 01:57:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:52 INFO - 'PWD': '/builds/slave/test', 01:57:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:52 INFO - 'SHELL': '/bin/bash', 01:57:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:52 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:52 INFO - 'USER': 'cltbld', 01:57:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:52 INFO - 'XPC_FLAGS': '0x0', 01:57:52 INFO - 'XPC_SERVICE_NAME': '0', 01:57:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:57:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-en0q7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:57:52 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)) 01:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:57:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-pfR6f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-reAY04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-cz4582-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-VlOe89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-dSrt0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-qANcLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-AQavXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-UsjjUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ct_XkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-WhbgCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:57:53 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)) 01:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:57:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-41YSa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:57:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 01:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:57:54 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-UyOKZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:57:54 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)) 01:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:57:54 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-cPEPOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:57:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 01:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:57:54 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ONYQb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:57:54 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)) 01:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:57:54 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-r3u5KN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:57:54 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)) 01:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:57:54 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-kPoda8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:57:54 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)) 01:57:54 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)) 01:57:54 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)) 01:57:54 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)) 01:57:54 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)) 01:57:54 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)) 01:57:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:57:54 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 01:57:54 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 01:57:54 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 01:57:54 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 01:57:54 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 01:57:54 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 01:57:57 INFO - Downloading blessings-1.6.tar.gz 01:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:57:57 INFO - Installing collected packages: blessings 01:57:57 INFO - Running setup.py install for blessings 01:57:57 INFO - Successfully installed blessings 01:57:57 INFO - Cleaning up... 01:57:57 INFO - Return code: 0 01:57:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:57:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:57:57 INFO - Reading from file tmpfile_stdout 01:57:57 INFO - Current package versions: 01:57:57 INFO - blessings == 1.6 01:57:57 INFO - blobuploader == 1.2.4 01:57:57 INFO - docopt == 0.6.1 01:57:57 INFO - functools32 == 3.2.3-2 01:57:57 INFO - jsonschema == 2.5.1 01:57:57 INFO - manifestparser == 1.1 01:57:57 INFO - mozInstall == 1.12 01:57:57 INFO - mozcrash == 0.17 01:57:57 INFO - mozdebug == 0.1 01:57:57 INFO - mozdevice == 0.48 01:57:57 INFO - mozfile == 1.2 01:57:57 INFO - mozhttpd == 0.7 01:57:57 INFO - mozinfo == 0.9 01:57:57 INFO - mozleak == 0.1 01:57:57 INFO - mozlog == 3.2 01:57:57 INFO - moznetwork == 0.27 01:57:57 INFO - mozprocess == 0.23 01:57:57 INFO - mozprofile == 0.28 01:57:57 INFO - mozrunner == 6.12 01:57:57 INFO - mozscreenshot == 0.1 01:57:57 INFO - mozsystemmonitor == 0.3 01:57:57 INFO - moztest == 0.7 01:57:57 INFO - mozversion == 1.4 01:57:57 INFO - psutil == 3.1.1 01:57:57 INFO - requests == 1.2.3 01:57:57 INFO - wsgiref == 0.1.2 01:57:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:57:57 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')]} 01:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:57 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')]} 01:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:57 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 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:57 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 01:57:57 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 01:57:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:57 INFO - 'HOME': '/Users/cltbld', 01:57:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:57 INFO - 'LOGNAME': 'cltbld', 01:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:57 INFO - 'MOZ_NO_REMOTE': '1', 01:57:57 INFO - 'NO_EM_RESTART': '1', 01:57:57 INFO - 'PAGER': '/bin/cat', 01:57:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:57 INFO - 'PWD': '/builds/slave/test', 01:57:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:57 INFO - 'SHELL': '/bin/bash', 01:57:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:57 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:57 INFO - 'USER': 'cltbld', 01:57:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:57 INFO - 'XPC_FLAGS': '0x0', 01:57:57 INFO - 'XPC_SERVICE_NAME': '0', 01:57:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 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)) 01:57:57 INFO - Cleaning up... 01:57:57 INFO - Return code: 0 01:57:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:57:57 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')]} 01:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:57 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')]} 01:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:57:57 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 0x103665a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe859db2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, '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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 01:57:57 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 01:57:57 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 01:57:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:57:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:57:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:57:57 INFO - 'HOME': '/Users/cltbld', 01:57:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:57:57 INFO - 'LOGNAME': 'cltbld', 01:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:57:57 INFO - 'MOZ_NO_REMOTE': '1', 01:57:57 INFO - 'NO_EM_RESTART': '1', 01:57:57 INFO - 'PAGER': '/bin/cat', 01:57:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:57:57 INFO - 'PWD': '/builds/slave/test', 01:57:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:57:57 INFO - 'SHELL': '/bin/bash', 01:57:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:57:57 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:57:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:57:57 INFO - 'USER': 'cltbld', 01:57:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:57:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:57:57 INFO - 'XPC_FLAGS': '0x0', 01:57:57 INFO - 'XPC_SERVICE_NAME': '0', 01:57:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:57:58 INFO - Cleaning up... 01:57:58 INFO - Return code: 0 01:57:58 INFO - Running post-action listener: _resource_record_post_action 01:57:58 INFO - Running post-action listener: _start_resource_monitoring 01:57:58 INFO - Starting resource monitoring. 01:57:58 INFO - ##### 01:57:58 INFO - ##### Finished create-virtualenv step (success) 01:57:58 INFO - ##### 01:57:58 INFO - ##### 01:57:58 INFO - ##### Running install step. 01:57:58 INFO - ##### 01:57:58 INFO - Running pre-action listener: _resource_record_pre_action 01:57:58 INFO - Running main action method: install 01:57:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:57:58 INFO - Reading from file tmpfile_stdout 01:57:58 INFO - Detecting whether we're running mozinstall >=1.0... 01:57:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:57:58 INFO - Reading from file tmpfile_stdout 01:57:58 INFO - Output received: 01:57:58 INFO - Usage: mozinstall [options] installer 01:57:58 INFO - Options: 01:57:58 INFO - -h, --help show this help message and exit 01:57:58 INFO - -d DEST, --destination=DEST 01:57:58 INFO - Directory to install application into. [default: 01:57:58 INFO - "/builds/slave/test"] 01:57:58 INFO - --app=APP Application being installed. [default: firefox] 01:57:58 INFO - mkdir: /builds/slave/test/build/application 01:57:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:58:33 INFO - Reading from file tmpfile_stdout 01:58:33 INFO - Output received: 01:58:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:58:33 INFO - Running post-action listener: _resource_record_post_action 01:58:33 INFO - ##### 01:58:33 INFO - ##### Finished install step (success) 01:58:33 INFO - ##### 01:58:33 INFO - ##### 01:58:33 INFO - ##### Running run-tests step. 01:58:33 INFO - ##### 01:58:33 INFO - Running pre-action listener: _resource_record_pre_action 01:58:33 INFO - Running main action method: run_tests 01:58:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:58:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:58:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:58:33 INFO - 2016-07-07 01:58:33.163 screenresolution[1637:7372] starting screenresolution argv=screenresolution get 01:58:33 INFO - 2016-07-07 01:58:33.165 screenresolution[1637:7372] Display 0: 1600x1200x32@60 01:58:33 INFO - 2016-07-07 01:58:33.171 screenresolution[1638:7375] starting screenresolution argv=screenresolution list 01:58:33 INFO - Available Modes on Display 0 01:58:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:58:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:58:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:58:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:58:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:58:33 INFO - Intel Iris: 01:58:33 INFO - Chipset Model: Intel Iris 01:58:33 INFO - Type: GPU 01:58:33 INFO - Bus: Built-In 01:58:33 INFO - VRAM (Dynamic, Max): 1536 MB 01:58:33 INFO - Vendor: Intel (0x8086) 01:58:33 INFO - Device ID: 0x0a2e 01:58:33 INFO - Revision ID: 0x0009 01:58:33 INFO - Displays: 01:58:33 INFO - Display: 01:58:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:58:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:58:33 INFO - Display Serial Number: 1600x1200 60 01:58:33 INFO - Main Display: Yes 01:58:33 INFO - Mirror: Off 01:58:33 INFO - Online: Yes 01:58:33 INFO - Rotation: Supported 01:58:33 INFO - Return code: 0 01:58:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:58:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:58:33 INFO - Python 2.7.3 01:58:33 INFO - Return code: 0 01:58:33 INFO - grabbing minidump binary from tooltool 01:58:33 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')]} 01:58:33 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c9d30>, '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 0x10332edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035c1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:58:33 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 01:58:33 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 01:58:33 INFO - Calling ['/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'] with output_timeout 600 01:58:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:58:33 INFO - Return code: 0 01:58:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:58:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:58:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:58:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:58:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:58:33 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/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 01:58:33 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/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0161 --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 01:58:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vtj3Qe7l4k/Render', 01:58:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:33 INFO - 'HOME': '/Users/cltbld', 01:58:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:33 INFO - 'LOGNAME': 'cltbld', 01:58:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:58:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:58:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:33 INFO - 'MOZ_NO_REMOTE': '1', 01:58:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:58:33 INFO - 'NO_EM_RESTART': '1', 01:58:33 INFO - 'PAGER': '/bin/cat', 01:58:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:33 INFO - 'PWD': '/builds/slave/test', 01:58:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:58:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:33 INFO - 'SHELL': '/bin/bash', 01:58:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LA8fTwsFoS/Listeners', 01:58:33 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 01:58:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:33 INFO - 'USER': 'cltbld', 01:58:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:33 INFO - 'XPC_FLAGS': '0x0', 01:58:33 INFO - 'XPC_SERVICE_NAME': '0', 01:58:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:33 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/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 01:58:33 INFO - mozversion application_buildid: 20160706234023 01:58:33 INFO - mozversion application_changeset: b9cc2dcbf4363c8049183110fe79e026fae87ecb 01:58:33 INFO - mozversion application_display_name: Nightly 01:58:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:58:33 INFO - mozversion application_name: Firefox 01:58:33 INFO - mozversion application_remotingname: firefox 01:58:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:58:33 INFO - mozversion application_vendor: Mozilla 01:58:33 INFO - mozversion application_version: 50.0a1 01:58:33 INFO - mozversion platform_buildid: 20160706234023 01:58:33 INFO - mozversion platform_changeset: b9cc2dcbf4363c8049183110fe79e026fae87ecb 01:58:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:58:33 INFO - mozversion platform_version: 50.0a1 01:58:33 INFO - using testdate: 1467881913 01:58:33 INFO - actual date: 1467881913 01:58:33 INFO - starting webserver on 'localhost:49270' 01:58:33 INFO - SUITE-START | Running 2 tests 01:58:33 INFO - TEST-START | dromaeo_css 01:58:33 INFO - operating with platform_type : mac_ 01:58:33 INFO - Initialising browser for dromaeo_css test... 01:58:33 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpKI0diD/profile http://localhost:49270/getInfo.html) 01:58:35 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 01:58:35 INFO - PROCESS | 1648 | colorDepth:24 01:58:35 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 01:58:35 INFO - PROCESS | 1648 | __metrics 01:58:36 INFO - PROCESS | 1648 | 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" 01:58:36 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:58:36 INFO - TEST-INFO | 1648: exit 0 01:58:36 INFO - Browser initialized. 01:58:36 INFO - Running cycle 1/1 for dromaeo_css test... 01:58:36 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpKI0diD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:58:48 INFO - PROCESS | 1650 | RSS: Main: 243757056 01:58:48 INFO - PROCESS | 1650 | 01:59:40 INFO - PROCESS | 1650 | 1467881980445 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] 02:01:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 02:01:52 INFO - PROCESS | 1650 | RSS: Main: 369991680 02:01:52 INFO - PROCESS | 1650 | 02:04:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 02:04:56 INFO - PROCESS | 1650 | RSS: Main: 367857664 02:04:56 INFO - PROCESS | 1650 | 02:07:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 02:07:59 INFO - PROCESS | 1650 | RSS: Main: 369664000 02:07:59 INFO - PROCESS | 1650 | 02:11:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 02:11:01 INFO - PROCESS | 1650 | RSS: Main: 373645312 02:11:01 INFO - PROCESS | 1650 | 02:14:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 02:14:05 INFO - PROCESS | 1650 | RSS: Main: 335179776 02:14:05 INFO - PROCESS | 1650 | 02:17:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 02:17:09 INFO - PROCESS | 1650 | RSS: Main: 428793856 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | __start_tp_report 02:17:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 02:17:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 02:17:09 INFO - PROCESS | 1650 | |i|pagename|runs| 02:17:09 INFO - PROCESS | 1650 | |0;dojo.html;3366.882159124431;3388.1021529294735;3403.1322012886712;3429.314137172563;3457.0838727737155;4771.642126840492;5708.4291570843;5759.827205183846;5817.331006934204;5846.64920104793;5766.82699519014;5795.362510123872;5887;5891.88216235675;5925.61483503572;5149.382074151088;5171.37943446785;5235.633505654606;5260.053190425722;5343.492368225013;5327.547158491533;5338.439463856297;5357.08929481989;5366.06093933562;5400.324959380077;5160.0969830279555;5214.32213812202;5238.764255608507;5279.472052794709;5303.761330740087;5205.141151709963;5208.739563021833;5224.738763061831;5295.285136506561;5332.973335133338;5194.740262986836;5199.454057323965;5242.449542798027;5292.682439053669;5307.150855863043;5663.0655941769555;5709.143628455723;5768.192453056576;5834.299884013945;5917.142014407887;5334.893302133974;6296.590721603081;6324.652144132071;6357.396047375492;6375.93624063758;5779.227549637307;5851.94664960307;6355.3009168991375;6377.2666143393635;6379.87240255197;4781.760911954389;5817.040188368872;5899.380565040652;5927.999999999957;5982.0727787193055;6231.22109736283;6300.27546832111;6302.369763023661;6308.905366419507;6323.20959880015;6183.5671502994355;6238.532110091671;6249.468795152372;6256.781012664626;6270.372962703785;6156.07658851167;6208.968955155286;6348.77779277723;6368.904466433008;6378.808635740936;6275.497960163176;6277.435030847246;6326.398992095744;6341.746330146742;6349.142865713162;6313.90529142054;6317.368263173646;6329.556931014785;6336.081268216083;6345.460635845912;4904.8038078477175;4983.352164218629;4988.376452943382;4992.151334273182;5037.722925239147;4458.910821783546;4471.709338892962;4500.987277862455;4528;4528.298113792367;5784.913226301609;5801.7389217485315;5803.854903627359;5805.129230615442;5852.882942341214;6175.876482470326;6258.3741625837965;6260.624362538262;6276.372362763687;6316.147320111818;5752.3097228332035;5770;5817.883642327214;5822.534197264209;5855.736491857877;4599.33309670096;5490.396056433789;5505.091659876076;5511.118221084607;5518.53092487135;6239.313675495782;6249.6562689052535;6303.558750887394;6363.331850155667;6372.4583410409705;6333.081703152925;6335.524835637253;6337.556371053982;6361.427471527585;6361.93638063632;6351.872962540631;6358.841028974312;6360.204974378203;6365.713542890674;6375.362463753588;6108.961476549086;6161.229846269216;6196.752129914933;6232.968835155795;6299.716512756845;6301.306856245717;6338.746450142127;6346.746130154742;6347.0479428086155;6373.490120790233;5914.940850591611;6167.383261673976;6181.96909015452;6191.659458729813;6199.659018754011;6032.60788048793;6126.212334160775;6128.380904898417;6131.049687298476;6211.596246243979;6100;6105.8785319909775;6119.146555857468;6120.204373431426;6127.7393367763425;6087.208662873975;6173.351798061319;6207.906881396873;6236.719347629459;6244.625322480666;5942.1383899335215;5993.760249589967;6021.337652858269;6071.635701857903;6099.634021958696 02:17:09 INFO - PROCESS | 1650 | |1;ext.html;498.29740066506236;529.7669025628724;531.3411369901322;539.2935254816191;563.788579282769;30494;32263;32462.67537324626;41726.74819755404;42962.57037429625;44234;44597.885052873695;44616;44820.775896120474;44822.87942801423;9835.311528193011;9990.300678952477;10000.399976001427;10006.649767258148;10010.149137323337;8865.113488651115;9050.049744776803;9772;11957.461914213882;12221.388930553481;12179.756404871898;12233.082518811125;12249.571265005727;12250.387480625977;12289.446974886107;8908;12141.93929030358;12222.572209972608;12226.083043771663;12229.38853057348;11757.35334556599;12072.456739446658;12088.395580220953;12320.691982700504;12325.137240393176;41643.167136657095;41919.37120943188;41921.580784192076;42038;42098.1580368394;3636.054625797285;3684.521012268388;4273.786310684453;4276.166147601219;4465.68740188187;25620.61099431939;32096.518552221503;32973.17567060819;33006.669933300596;33164.1708957278;3320.1035720355367;3356.8153751543655;3377.5102610121394;3425.2635683328203;3432.2277487565098;487618;487687;488911.5554422241;490169;491276;61373;61761.07358389628;61846.76306473845;61880;61898.45253868599;65296.67351663306;65386;65443;65446.67276663682;65473;14524;15048.398064077533;16546.503604891874;16761.580960476087;16831.07429091412;11555.88444115565;11669.066474682006;11711.648650540497;11894.226875253082;12876.291803950873;16697.165141742866;17243.655126897393;17282.567935801453;17309.048002359992;17348.04585747796;5007.6183339577865;8002.1997800219515;8091.838163236703;8152.714654987047;8209.425340226127;937.3813283233034;1021.9080282774586;1047.9685609431729;1062.4315990944963;1083.875354334246;26765.598516022274;29815.65829537676;30325.694569097945;31126.066218013497;31313.373732525222;1052.5105825791045;1081.9242653014371;1097.7036200225893;1136.982400751323;1169.3159501691605;15221.771673424908;15827.841721582867;15862.920685396499;16435.67128657444;16556.58608534772;671319;733621;739987;742022;1006923;59837;60100.69949650224;60488;60498.69750651307;60563.69718151469;1182.3674334231146;1299.2789002103923;1414.575627311823;1446.3636000159895;1461.1817382265965;30064.699353006188;30792.53811192789;31309;32074.198145045626;32492.83753581217;57566.136507951574;58731;58769.11846322394;58787.412125878196;58881.41118588931;613056;635289;636336;637371;638069;24503;27140.371577705402;27779;28141.43717125605;28141.859290703414;46318.30522542097;47407.76296118635;48221.517784821706;49207.29359885098;49265.76835579139;38560.42159367556;39790.60209397985;39950;40280.79859600683;40654.79672601618;38539.036524087125;39371.80314098411;39804.800975994935;40123.398149027205;40423.39364909587;38051.42922856215;38890;39045.63340283076;39108;39178.80410597929;31784.841075794473;33601.79977502812;34460.48309275413;34514.65485345215;34538.82730586331;22817.77182228223;25461.3634659135;25464.490710186063;25695.357616059002;25825.483490330465 02:17:09 INFO - PROCESS | 1650 | |2;jquery.html;380.62698555415693;381.3212481782426;385.21608526648276;397.40191012526145;406.4756464161232;19706.11322490488;21219.045142968564;21832.89083554584;22206.00072996715;22263.109475620975;20548.280810171647;21421.67867481989;21432.678509822366;21482.140714371446;21855.016524256407;5154.149565321727;5473.042217611922;5484.424135465779;5492.642978206423;5497.835064948038;3898.3372826619543;4112.691548133902;5364.570834333244;5845.327787304472;6125.203723515937;6170.259568851744;6171.7531298748;6175.938240617604;6185.938140618604;6201.968990155043;5900;6158.938410615881;6170.784022559189;6177.567570270083;6180.351063138373;3767.5478942526797;3974.3641017437362;5611.523020543259;5972.686009077996;5973.342932277489;13442.327883605878;13836.446542138303;13837.377318020714;15497.535073947687;23944.042238310445;2286.954260914789;2431.0638129524823;2470.036685692581;2852.9301511933027;3046.299351149226;8266.793330166794;9943.900560994442;10141.949290253575;10533.631322903662;10648.520816563274;1821.8633602479867;1836.375632285016;1837.3752924005903;1860.2744929477515;1904.304928701028;127590.08614870692;128385;129564;130456;132142.678573214;11445.828312575319;11550.306981581132;11634.6509604712;11691.181617286707;11865.762684746256;14752.188629625474;14831.84938183979;14864.108153510824;14873.851261487247;14881.851181488262;7320.304571065741;8458.027326857367;8536.487810731376;8626.137386261324;8733;7816.6091695415;7918.366530677532;8556.786080347916;8584.570771461402;8739.694110706096;4534.727916324965;4619.930701039487;4621.052684199731;4655.511171326962;4675.392199014106;1480.015789499992;1769.2407328340873;1827.7715285589302;1863.6086421851292;1903.486058764126;843.0810416645885;923.4182465046978;942.2320808541017;1007.1942446043137;1039.9012093851072;18449.985250811333;21243.256486023223;21647.376446766557;22209.1116355344;22268.331950041524;1017.2218253036433;1017.8574999500075;1026.0765311219814;1027.2244455436098;1041.953112109957;11108.500117494732;11693;11721.472533736003;11801.763964720829;11940.761184776256;541663;554128;554503;560773;562251;134361;134488;136992;138133;138754.91867621994;901.2384535067988;964.7361955837735;969.680005598151;988.4711679251566;993.4635296939572;18724;19794.802051979295;19879.503012424804;19918.402447926586;19944.90027549911;40171.79914100411;57134;57551.71224143852;57987.23082999592;58775;66721;78458.64624061287;80611.98470038062;81471;82929;7970.601469926596;8231.95884020576;8243;8243.093259741543;8453.112423195476;9186.816263674822;13448.258681680192;13636.250006249751;13647.522336718168;13749.450021999008;11622.128340374336;11934.462949167135;12000.219985700902;12056.698582535506;12069.637910862688;11407.973282404624;11418.029467495024;11475.827862582235;11796.587119450778;11812.822807658064;11608.30350178992;11891.524339026342;12002.699932501408;12030.75938481208;12173.2696038238;7168.569885806868;7576.62116894142;8157.469764465291;8305.211004954519;8500.192481714228;11120.944395277971;11799.05023744064;12086.15396922207;12099.213551119501;12132.818007729715 02:17:09 INFO - PROCESS | 1650 | |3;mootools.html;1546.4510098914889;1582.2168026826719;1787.0796539782013;1917.1947781931597;1917.587718640492;8462.830743385128;8650.264727498163;9090.727278181665;9096.909030909705;9099.499527525992;7820.06159260888;8062.193780621935;8069.354451643869;8157.143499932511;8894.688685895995;2149.591577600255;2323.2449453927456;2408.349745568695;2409.662647229389;2410.8432951858163;2387.95224095518;2392.174699728591;2415.130553000918;2415.915442959497;2429.1740808125232;1995.860289779716;2128.5955668423026;2394.7006624171977;2407.6629271901948;2412.0351859256352;2129.1483406637312;2157.557700671359;2158.924437644675;2306.5963456395066;2399.6400539918977;2418.8427752196053;2454.9509009819703;2455.9386015349583;2459.7909177719916;2460.9753902460925;8938.553072346422;8983.955080224623;8987.865182022275;8995.775105622412;8999.010108888016;5332.386775520826;5516.117421212627;5539.113741801292;5540.473655002808;5541.27963364764;3515.9648403515894;3619.2580520993397;3724.0689827543115;3728.6457786510236;3735.701143908481;1070.9892901070968;1079.1205167788228;1081.0810810810826;1083.9620613278498;1106.955721771128;11486.597969071043;11678.591249306233;11688.590899318484;11707.882921170849;11753.412329383582;4770.451398089264;4797.136515427256;4835.44392394872;4930.433000204951;4935.605151587864;10014;10151.746206344753;10152.949235253776;10170.135538479164;10195.235357348229;5399.865003374947;5481.917771233435;5483.698396588226;5484.2596249505805;5487.5884308677005;4916.262560615936;4933.28467372229;4993.350864387679;4997.775100120503;5009.223570346602;3573.857045718142;3694.1160008395373;3933.6656384207663;3950.940735888964;4038.0106873816103;1034.332855308322;1262.9052821038458;1265.4432049898016;1283.2685369339388;1285.0490636928605;758.6282721466441;907.5235501361838;908.3732224765023;952.2144231009445;993.7962717642865;6034.125051867454;6052.697365131814;6148;6158.507319414436;6205.441510264098;940.1538615246333;940.4592359393376;979.6571200079915;1027.8766548014287;1044.195969103801;3897.668698160688;4084.9387259191135;4093.324601440729;4156.937645935313;4168.937465938014;10607.257491975488;10846.78306433867;10951;10963.013328800445;10988.120950323955;10821.783564328669;10920.12638988879;10920.672379828458;10928.945355273172;10945.83581246282;6857.485688573377;8151.5924203790755;8208.425410221227;8242.958785206156;8246.917530824734;4822.541858523435;5870.7945221917025;5990.700464976647;6007.639541627516;6043.607165534227;9797.461139637388;9993.300468967103;10000.2999790014;10043.748906277402;10072.546735396778;8141.959290203511;9846.163076138322;10129.189664826943;10349.586016559555;10365.74085647865;8222.465539739898;8409.369297302605;8426.789330266793;8512.276456501268;8512.914870851213;7489.28851759082;8327.084020757833;8453.95773021131;8508.191721786421;8523.019852717018;7525.58609276495;7586.506877052974;7616.276453736886;7743.806404839924;7746.147923728273;7436.367908727602;7482.96258518704;7483.550986940801;7523.661435235318;7619.047619047619;7010.789676309719;7112.431005519443;7155.177154627285;7161.319674630901;7164.677589508589;4883.730230140261;5076.162433198481;5142.5885929126325;5171.224316352577;5246.632735708617;4933.457319694902;5149.459306772735;5167.638265321541;5340.546053585489;5348.545373643284 02:17:09 INFO - PROCESS | 1650 | |4;prototype.html;285.7670998136517;297.05240283495647;303.2676087249293;313.4139159771228;317.1737623490806;4729.716217026981;5102.708752854407;5218.21726740989;5273.2617433559335;5419.376771671259;5220.738963051851;5323.467653234675;5351.68195718654;5374.946250537493;5387.757550910209;3702.4446333049987;4034.2334956358345;4061.939070913939;4078.4086307485395;4091.713580049398;3166.8416579171067;3860.8031510231776;3900.3759398496245;4087.4890638670167;4097.73364731293;3482.1990942083335;3484.6863782259593;3944.1125746706903;3961.2671655743634;4061.7969101544954;3330.81680507572;4064.410660454247;4068.1863627274515;4069.0641152534786;4075.4701888754566;2947.852607369623;3150.70068343507;3395.947256350539;3538.7876727396183;3563.6436356364416;4719.427911788083;5159.871003224912;5187.42938276793;5196.428392876781;5216.2958000669805;3740.775553466801;4514.367988481616;4653.697509661895;4660.300954856764;4668.323093151508;5193.013327467769;5584.101405522101;5591.944080559183;5598.488408129823;5678.205051292823;3164.8259345735974;3798.5631652360057;3804.1821008483034;3825.3496905526126;3831.961680383188;4817.373741413594;5897.852553686193;5917.289925209003;5928.7332070056955;5968.731407086648;3601.2257364666198;3835.9424608630893;3858.7266202153223;3871.399933010388;3873.9612603874166;3460.8269586520573;3706.8887933362043;3925.823337949799;3938.86213982515;3954.703397245218;4442.178706730225;4493.505714371417;4539.182947069493;4539.863804085882;4546.659000574904;4614.3309220163555;5593.049181639131;5616.9157462638095;5623.718814059281;5625.943740562624;1135.8125909224884;1314.6844757258189;1336.7259711759066;1344.6419116692211;1344.7310537892265;845.6490556419141;903.9774005649912;907.7186072317603;909.377076702461;916.8258030974093;5141.717205553656;5236.162214045735;5240.475952404805;5246.458640417703;5259.684418934876;5794.507466865363;5816.651000939957;5823.359430462646;5831.650100993954;5859.912101318484;5057.797688092509;5150.300400867704;5164.2770012198325;5170.276161337416;5196.506331898464;2658.3354161459633;2995.4907665696883;3008.6239220097486;3024.2026125833518;3030.1515575638855;4877.926831097537;5612.270404847425;5941.1385349125;5960.195373624592;5961.956657584992;5894.852628684317;5956.255468066492;5957.910631340534;5973.80523895214;5993.310769261503;5226.529612334908;5997.580169388189;6033.698315084316;6039.63762174271;6075.392460753889;5521.282233309644;5779.8555036124435;5813.09896965971;5830.76676932918;5872.618279811799;4743.573078422821;5004.374453193351;5442.727863606883;5542.367375496607;5719.799807006735;4675.555822196886;4967.925481117858;6051.485623722033;6063.454289114001;6067.848303792441;2966.821990680566;3241.951370729488;3319.120433085186;3846.9230615388096;3853.556840963325;4804.390529172766;4813.95186048135;5501.752421140978;5607.411221821813;5771.826845194651;4887.633427493023;5359.464053594609;5581.944180558305;5651.880544795702;5654.293213348332;5334.759935802976;5373.623846330719;5635.211070450222;5668.574856885833;5729.197912292366;5245.816396426107;5443.700596467233;5482.218279059242;5503.174523821459;5507.697076660822;4409.316555933835;4861.95138048615;5301.257823904733;5604.907043126715;5612.382637909908;4809.715385192146;5166.4058633257655;5238.947610523998;5475.479829416199;5528.861778455571 02:17:09 INFO - PROCESS | 1650 | |5;yui.html;751.6129193465365;776.2198990015034;782.1669921533568;811.5658122904248;864.3344624639032;15420.228988550569;16334.183290835455;17700.91149544254;18386.988715620635;18423.263069477205;17120.657586848254;17345.39291124811;17347.739783903253;17359.47921562352;17393;721.0374150509076;891.4740303221098;922.6540047482194;923.0907556057278;926.7080869526111;747.0288624787754;866.2550206822159;895.2077411490845;904.4708845325492;910.1808372464768;929.832630126577;935.6912218967724;936.5785396571536;936.8594710793367;938.3337830140608;754.6377738685462;931.0921851195133;931.9813603727921;932.5383934952174;933.6452148183714;724.8803947348681;724.9746258880914;725.0320821703033;726.0416250549224;728.3808762551841;15706.371745130182;15779.05325680463;15782.921085394615;15783.368665253376;15806.13066281354;13992.580222593338;14225.359858806378;14228.786568201382;14244.857551424457;14384.856151438456;13475.66310842227;13518.324083795771;13791.448342066305;13839.446422143103;14177.858221417757;4556.270996640555;4615.100055489181;4640.621411955008;4690.507496712864;4865.148598995197;121946;122584;123247;124726;127350.72649273482;11105;11198;11275;11332.660020199408;11608.187426880037;22113.894305284994;22140.33578992633;22154.88922555377;22165;22169.889150554467;7927.9297294864455;7990.161033091558;8017.158198389086;8172.141925097779;8296.253337199681;4503.369528266046;4514.932276015862;4541.455025396907;4557.361969324297;4594.95405045952;3564.091156755063;3656.597774244831;3677.963220367762;3678.816059197083;3682.8526858925343;654.8297442664846;666.2704338749062;673.531895332739;682.2836022176793;684.4729558240127;508.1158783716307;535.2773755430215;600.2376981233808;628.6322501336678;645.3772109913862;7349.926500735031;8960.820783584291;9147.131022552847;9176.403533770284;9332.4867132307;558.5810642018486;604.8639056212317;629.1530823025754;634.1260733647285;644.8774732800753;4435.2016637005;4489.191945449785;4522.276435770261;4535.501094879561;4577;109633;129910.35044824904;133905.330473347;135301;138014.30992844971;141375;143398;143590.28204858908;144143;144748;126432;127769.3611531955;128150;128941;132700.6729932727;9837;9899.554520046759;9931.205503559553;9973.900260997298;9974.700758977242;60122.79754404975;61822.0726689091;62037.1388858335;62672.37327626848;64349.67825160844;66146;69366;87681;87714;88782;13561.321933903462;13681.247531385869;13688.589342319747;13824.377902994189;14168.92915535457;15104.093754374773;15301.693966120838;15474.922625387251;15511.5346539604;16222.756658650365;14322.641933951318;14350.569482915529;14486;14754.409823606935;14867;14154;14288.35702393374;14330.283485825876;14482.710345793235;14569;13756.931215343859;14087.366068527146;14115.647108822362;14278;14360.066595671247;10718.624848130277;11096.11231101526;11301.491432885705;11371.033462155809;11541.422928853692;10937.617183398543;11399.943000284946;11475.770484590095;11814.409279536161;11845.52617895309 02:17:09 INFO - PROCESS | 1650 | __end_tp_report 02:17:09 INFO - PROCESS | 1650 | __start_cc_report 02:17:09 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1308 02:17:09 INFO - PROCESS | 1650 | __end_cc_report 02:17:09 INFO - PROCESS | 1650 | __startTimestamp1467883029140__endTimestamp 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | ------- Summary: start ------- 02:17:09 INFO - PROCESS | 1650 | Number of tests: 6 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5794.08 Median:6032.61 stddev:656.39 (10.9%) stddev-sans-first:630.19 02:17:09 INFO - PROCESS | 1650 | Values: 3366.9 3388.1 3403.1 3429.3 3457.1 4771.6 5708.4 5759.8 5817.3 5846.6 5766.8 5795.4 5887.0 5891.9 5925.6 5149.4 5171.4 5235.6 5260.1 5343.5 5327.5 5338.4 5357.1 5366.1 5400.3 5160.1 5214.3 5238.8 5279.5 5303.8 5205.1 5208.7 5224.7 5295.3 5333.0 5194.7 5199.5 5242.4 5292.7 5307.2 5663.1 5709.1 5768.2 5834.3 5917.1 5334.9 6296.6 6324.7 6357.4 6375.9 5779.2 5851.9 6355.3 6377.3 6379.9 4781.8 5817.0 5899.4 5928.0 5982.1 6231.2 6300.3 6302.4 6308.9 6323.2 6183.6 6238.5 6249.5 6256.8 6270.4 6156.1 6209.0 6348.8 6368.9 6378.8 6275.5 6277.4 6326.4 6341.7 6349.1 6313.9 6317.4 6329.6 6336.1 6345.5 4904.8 4983.4 4988.4 4992.2 5037.7 4458.9 4471.7 4501.0 4528.0 4528.3 5784.9 5801.7 5803.9 5805.1 5852.9 6175.9 6258.4 6260.6 6276.4 6316.1 5752.3 5770.0 5817.9 5822.5 5855.7 4599.3 5490.4 5505.1 5511.1 5518.5 6239.3 6249.7 6303.6 6363.3 6372.5 6333.1 6335.5 6337.6 6361.4 6361.9 6351.9 6358.8 6360.2 6365.7 6375.4 6109.0 6161.2 6196.8 6233.0 6299.7 6301.3 6338.7 6346.7 6347.0 6373.5 5914.9 6167.4 6182.0 6191.7 6199.7 6032.6 6126.2 6128.4 6131.0 6211.6 6100.0 6105.9 6119.1 6120.2 6127.7 6087.2 6173.4 6207.9 6236.7 6244.6 5942.1 5993.8 6021.3 6071.6 6099.6 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:76381.03 Median:34526.74 stddev:173947.78 (503.8%) stddev-sans-first:174342.45 02:17:09 INFO - PROCESS | 1650 | Values: 498.3 529.8 531.3 539.3 563.8 30494.0 32263.0 32462.7 41726.7 42962.6 44234.0 44597.9 44616.0 44820.8 44822.9 9835.3 9990.3 10000.4 10006.6 10010.1 8865.1 9050.0 9772.0 11957.5 12221.4 12179.8 12233.1 12249.6 12250.4 12289.4 8908.0 12141.9 12222.6 12226.1 12229.4 11757.4 12072.5 12088.4 12320.7 12325.1 41643.2 41919.4 41921.6 42038.0 42098.2 3636.1 3684.5 4273.8 4276.2 4465.7 25620.6 32096.5 32973.2 33006.7 33164.2 3320.1 3356.8 3377.5 3425.3 3432.2 487618.0 487687.0 488911.6 490169.0 491276.0 61373.0 61761.1 61846.8 61880.0 61898.5 65296.7 65386.0 65443.0 65446.7 65473.0 14524.0 15048.4 16546.5 16761.6 16831.1 11555.9 11669.1 11711.6 11894.2 12876.3 16697.2 17243.7 17282.6 17309.0 17348.0 5007.6 8002.2 8091.8 8152.7 8209.4 937.4 1021.9 1048.0 1062.4 1083.9 26765.6 29815.7 30325.7 31126.1 31313.4 1052.5 1081.9 1097.7 1137.0 1169.3 15221.8 15827.8 15862.9 16435.7 16556.6 671319.0 733621.0 739987.0 742022.0 1006923.0 59837.0 60100.7 60488.0 60498.7 60563.7 1182.4 1299.3 1414.6 1446.4 1461.2 30064.7 30792.5 31309.0 32074.2 32492.8 57566.1 58731.0 58769.1 58787.4 58881.4 613056.0 635289.0 636336.0 637371.0 638069.0 24503.0 27140.4 27779.0 28141.4 28141.9 46318.3 47407.8 48221.5 49207.3 49265.8 38560.4 39790.6 39950.0 40280.8 40654.8 38539.0 39371.8 39804.8 40123.4 40423.4 38051.4 38890.0 39045.6 39108.0 39178.8 31784.8 33601.8 34460.5 34514.7 34538.8 22817.8 25461.4 25464.5 25695.4 25825.5 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34329.63 Median:22236.11 stddev:93603.75 (421.0%) stddev-sans-first:93831.62 02:17:09 INFO - PROCESS | 1650 | Values: 380.6 381.3 385.2 397.4 406.5 19706.1 21219.0 21832.9 22206.0 22263.1 20548.3 21421.7 21432.7 21482.1 21855.0 5154.1 5473.0 5484.4 5492.6 5497.8 3898.3 4112.7 5364.6 5845.3 6125.2 6170.3 6171.8 6175.9 6185.9 6202.0 5900.0 6158.9 6170.8 6177.6 6180.4 3767.5 3974.4 5611.5 5972.7 5973.3 13442.3 13836.4 13837.4 15497.5 23944.0 2287.0 2431.1 2470.0 2852.9 3046.3 8266.8 9943.9 10141.9 10533.6 10648.5 1821.9 1836.4 1837.4 1860.3 1904.3 127590.1 128385.0 129564.0 130456.0 132142.7 11445.8 11550.3 11634.7 11691.2 11865.8 14752.2 14831.8 14864.1 14873.9 14881.9 7320.3 8458.0 8536.5 8626.1 8733.0 7816.6 7918.4 8556.8 8584.6 8739.7 4534.7 4619.9 4621.1 4655.5 4675.4 1480.0 1769.2 1827.8 1863.6 1903.5 843.1 923.4 942.2 1007.2 1039.9 18450.0 21243.3 21647.4 22209.1 22268.3 1017.2 1017.9 1026.1 1027.2 1042.0 11108.5 11693.0 11721.5 11801.8 11940.8 541663.0 554128.0 554503.0 560773.0 562251.0 134361.0 134488.0 136992.0 138133.0 138754.9 901.2 964.7 969.7 988.5 993.5 18724.0 19794.8 19879.5 19918.4 19944.9 40171.8 57134.0 57551.7 57987.2 58775.0 66721.0 78458.6 80612.0 81471.0 82929.0 7970.6 8232.0 8243.0 8243.1 8453.1 9186.8 13448.3 13636.3 13647.5 13749.5 11622.1 11934.5 12000.2 12056.7 12069.6 11408.0 11418.0 11475.8 11796.6 11812.8 11608.3 11891.5 12002.7 12030.8 12173.3 7168.6 7576.6 8157.5 8305.2 8500.2 11120.9 11799.1 12086.2 12099.2 12132.8 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5790.43 Median:4933.37 stddev:3222.16 (65.3%) stddev-sans-first:3215.42 02:17:09 INFO - PROCESS | 1650 | Values: 1546.5 1582.2 1787.1 1917.2 1917.6 8462.8 8650.3 9090.7 9096.9 9099.5 7820.1 8062.2 8069.4 8157.1 8894.7 2149.6 2323.2 2408.3 2409.7 2410.8 2388.0 2392.2 2415.1 2415.9 2429.2 1995.9 2128.6 2394.7 2407.7 2412.0 2129.1 2157.6 2158.9 2306.6 2399.6 2418.8 2455.0 2455.9 2459.8 2461.0 8938.6 8984.0 8987.9 8995.8 8999.0 5332.4 5516.1 5539.1 5540.5 5541.3 3516.0 3619.3 3724.1 3728.6 3735.7 1071.0 1079.1 1081.1 1084.0 1107.0 11486.6 11678.6 11688.6 11707.9 11753.4 4770.5 4797.1 4835.4 4930.4 4935.6 10014.0 10151.7 10152.9 10170.1 10195.2 5399.9 5481.9 5483.7 5484.3 5487.6 4916.3 4933.3 4993.4 4997.8 5009.2 3573.9 3694.1 3933.7 3950.9 4038.0 1034.3 1262.9 1265.4 1283.3 1285.0 758.6 907.5 908.4 952.2 993.8 6034.1 6052.7 6148.0 6158.5 6205.4 940.2 940.5 979.7 1027.9 1044.2 3897.7 4084.9 4093.3 4156.9 4168.9 10607.3 10846.8 10951.0 10963.0 10988.1 10821.8 10920.1 10920.7 10928.9 10945.8 6857.5 8151.6 8208.4 8243.0 8246.9 4822.5 5870.8 5990.7 6007.6 6043.6 9797.5 9993.3 10000.3 10043.7 10072.5 8142.0 9846.2 10129.2 10349.6 10365.7 8222.5 8409.4 8426.8 8512.3 8512.9 7489.3 8327.1 8454.0 8508.2 8523.0 7525.6 7586.5 7616.3 7743.8 7746.1 7436.4 7483.0 7483.6 7523.7 7619.0 7010.8 7112.4 7155.2 7161.3 7164.7 4883.7 5076.2 5142.6 5171.2 5246.6 4933.5 5149.5 5167.6 5340.5 5348.5 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4517.68 Median:5165.34 stddev:1421.06 (27.5%) stddev-sans-first:1389.09 02:17:09 INFO - PROCESS | 1650 | Values: 285.8 297.1 303.3 313.4 317.2 4729.7 5102.7 5218.2 5273.3 5419.4 5220.7 5323.5 5351.7 5374.9 5387.8 3702.4 4034.2 4061.9 4078.4 4091.7 3166.8 3860.8 3900.4 4087.5 4097.7 3482.2 3484.7 3944.1 3961.3 4061.8 3330.8 4064.4 4068.2 4069.1 4075.5 2947.9 3150.7 3395.9 3538.8 3563.6 4719.4 5159.9 5187.4 5196.4 5216.3 3740.8 4514.4 4653.7 4660.3 4668.3 5193.0 5584.1 5591.9 5598.5 5678.2 3164.8 3798.6 3804.2 3825.3 3832.0 4817.4 5897.9 5917.3 5928.7 5968.7 3601.2 3835.9 3858.7 3871.4 3874.0 3460.8 3706.9 3925.8 3938.9 3954.7 4442.2 4493.5 4539.2 4539.9 4546.7 4614.3 5593.0 5616.9 5623.7 5625.9 1135.8 1314.7 1336.7 1344.6 1344.7 845.6 904.0 907.7 909.4 916.8 5141.7 5236.2 5240.5 5246.5 5259.7 5794.5 5816.7 5823.4 5831.7 5859.9 5057.8 5150.3 5164.3 5170.3 5196.5 2658.3 2995.5 3008.6 3024.2 3030.2 4877.9 5612.3 5941.1 5960.2 5962.0 5894.9 5956.3 5957.9 5973.8 5993.3 5226.5 5997.6 6033.7 6039.6 6075.4 5521.3 5779.9 5813.1 5830.8 5872.6 4743.6 5004.4 5442.7 5542.4 5719.8 4675.6 4967.9 6051.5 6063.5 6067.8 2966.8 3242.0 3319.1 3846.9 3853.6 4804.4 4814.0 5501.8 5607.4 5771.8 4887.6 5359.5 5581.9 5651.9 5654.3 5334.8 5373.6 5635.2 5668.6 5729.2 5245.8 5443.7 5482.2 5503.2 5507.7 4409.3 4862.0 5301.3 5604.9 5612.4 4809.7 5166.4 5238.9 5475.5 5528.9 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:25713.09 Median:3610.34 stddev:40743.23 (1128.5%) stddev-sans-first:40814.42 02:17:09 INFO - PROCESS | 1650 | Values: 751.6 776.2 782.2 811.6 864.3 15420.2 16334.2 17700.9 18387.0 18423.3 17120.7 17345.4 17347.7 17359.5 17393.0 721.0 891.5 922.7 923.1 926.7 747.0 866.3 895.2 904.5 910.2 929.8 935.7 936.6 936.9 938.3 754.6 931.1 932.0 932.5 933.6 724.9 725.0 725.0 726.0 728.4 15706.4 15779.1 15782.9 15783.4 15806.1 13992.6 14225.4 14228.8 14244.9 14384.9 13475.7 13518.3 13791.4 13839.4 14177.9 4556.3 4615.1 4640.6 4690.5 4865.1 121946.0 122584.0 123247.0 124726.0 127350.7 11105.0 11198.0 11275.0 11332.7 11608.2 22113.9 22140.3 22154.9 22165.0 22169.9 7927.9 7990.2 8017.2 8172.1 8296.3 4503.4 4514.9 4541.5 4557.4 4595.0 3564.1 3656.6 3678.0 3678.8 3682.9 654.8 666.3 673.5 682.3 684.5 508.1 535.3 600.2 628.6 645.4 7349.9 8960.8 9147.1 9176.4 9332.5 558.6 604.9 629.2 634.1 644.9 4435.2 4489.2 4522.3 4535.5 4577.0 109633.0 129910.4 133905.3 135301.0 138014.3 141375.0 143398.0 143590.3 144143.0 144748.0 126432.0 127769.4 128150.0 128941.0 132700.7 9837.0 9899.6 9931.2 9973.9 9974.7 60122.8 61822.1 62037.1 62672.4 64349.7 66146.0 69366.0 87681.0 87714.0 88782.0 13561.3 13681.2 13688.6 13824.4 14168.9 15104.1 15301.7 15474.9 15511.5 16222.8 14322.6 14350.6 14486.0 14754.4 14867.0 14154.0 14288.4 14330.3 14482.7 14569.0 13756.9 14087.4 14115.6 14278.0 14360.1 10718.6 11096.1 11301.5 11371.0 11541.4 10937.6 11399.9 11475.8 11814.4 11845.5 02:17:09 INFO - PROCESS | 1650 | -------- Summary: end -------- 02:17:09 INFO - PROCESS | 1650 | 02:17:09 INFO - TEST-INFO | 1650: exit 0 02:17:09 INFO - TEST-OK | dromaeo_css | took 1116046ms 02:17:09 INFO - TEST-START | kraken 02:17:09 INFO - operating with platform_type : mac_ 02:17:09 INFO - Initialising browser for kraken test... 02:17:09 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpiwdGVZ/profile http://localhost:49270/getInfo.html) 02:17:11 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 02:17:11 INFO - PROCESS | 1660 | colorDepth:24 02:17:11 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 02:17:11 INFO - PROCESS | 1660 | __metrics 02:17:12 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" 02:17:12 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:17:12 INFO - TEST-INFO | 1660: exit 0 02:17:12 INFO - Browser initialized. 02:17:12 INFO - Running cycle 1/1 for kraken test... 02:17:12 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpiwdGVZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:17:24 INFO - PROCESS | 1661 | RSS: Main: 243494912 02:17:24 INFO - PROCESS | 1661 | 02:17:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 02:17:28 INFO - PROCESS | 1661 | RSS: Main: 294285312 02:17:28 INFO - PROCESS | 1661 | 02:17:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 02:17:31 INFO - PROCESS | 1661 | RSS: Main: 303968256 02:17:31 INFO - PROCESS | 1661 | 02:17:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 02:17:35 INFO - PROCESS | 1661 | RSS: Main: 388923392 02:17:35 INFO - PROCESS | 1661 | 02:17:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 02:17:38 INFO - PROCESS | 1661 | RSS: Main: 289792000 02:17:38 INFO - PROCESS | 1661 | 02:17:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:17:41 INFO - PROCESS | 1661 | RSS: Main: 293269504 02:17:41 INFO - PROCESS | 1661 | 02:17:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 02:17:44 INFO - PROCESS | 1661 | RSS: Main: 330493952 02:17:44 INFO - PROCESS | 1661 | 02:17:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 02:17:48 INFO - PROCESS | 1661 | RSS: Main: 334168064 02:17:48 INFO - PROCESS | 1661 | 02:17:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 02:17:51 INFO - PROCESS | 1661 | RSS: Main: 332275712 02:17:51 INFO - PROCESS | 1661 | 02:17:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:17:53 INFO - PROCESS | 1661 | RSS: Main: 311283712 02:17:53 INFO - PROCESS | 1661 | 02:17:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:17:56 INFO - PROCESS | 1661 | RSS: Main: 318713856 02:17:56 INFO - PROCESS | 1661 | 02:17:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:17:59 INFO - PROCESS | 1661 | RSS: Main: 315539456 02:17:59 INFO - PROCESS | 1661 | 02:18:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:18:02 INFO - PROCESS | 1661 | RSS: Main: 324943872 02:18:02 INFO - PROCESS | 1661 | 02:18:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:18:06 INFO - PROCESS | 1661 | RSS: Main: 327675904 02:18:06 INFO - PROCESS | 1661 | 02:18:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 02:18:08 INFO - PROCESS | 1661 | RSS: Main: 371118080 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | __start_tp_report 02:18:08 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 02:18:08 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 02:18:08 INFO - PROCESS | 1661 | |i|pagename|runs| 02:18:08 INFO - PROCESS | 1661 | |0;ai-astar;99;112;110;109;111;110;111;111;111;108 02:18:08 INFO - PROCESS | 1661 | |1;audio-beat-detection;143;113;107;150;126;140;137;128;142;120 02:18:08 INFO - PROCESS | 1661 | |2;audio-dft;184;183;183;182;183;186;179;234;183;183 02:18:08 INFO - PROCESS | 1661 | |3;audio-fft;79;73;59;107;59;58;62;65;95;56 02:18:08 INFO - PROCESS | 1661 | |4;audio-oscillator;82;87;91;68;71;68;95;69;67;99 02:18:08 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;88;85;88;86;87;84;87;85;88;85 02:18:08 INFO - PROCESS | 1661 | |6;imaging-darkroom;94;93;94;93;92;95;94;94;92;93 02:18:08 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;86;87;85;86;97;132;83;85;101 02:18:08 INFO - PROCESS | 1661 | |8;json-parse-financial;55;55;54;54;54;54;54;54;54;54 02:18:08 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;66;66;75;76;71;68;72;101;101;69 02:18:08 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;85;82;82;83;90;102;76;83;86;79 02:18:08 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;137;137;137;135;134;119;132;151;171;135 02:18:08 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;164;176;172;168;167;226;209;165;164;163 02:18:08 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;68;66;67;66;67;66;68;66;65;65 02:18:08 INFO - PROCESS | 1661 | __end_tp_report 02:18:08 INFO - PROCESS | 1661 | __start_cc_report 02:18:08 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2021 02:18:08 INFO - PROCESS | 1661 | __end_cc_report 02:18:08 INFO - PROCESS | 1661 | __startTimestamp1467883088147__endTimestamp 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | ------- Summary: start ------- 02:18:08 INFO - PROCESS | 1661 | Number of tests: 14 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:109.20 Median:111.00 stddev:3.77 (3.4%) stddev-sans-first:1.22 02:18:08 INFO - PROCESS | 1661 | Values: 99.0 112.0 110.0 109.0 111.0 110.0 111.0 111.0 111.0 108.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:138.50 stddev:14.13 (10.2%) stddev-sans-first:14.25 02:18:08 INFO - PROCESS | 1661 | Values: 143.0 113.0 107.0 150.0 126.0 140.0 137.0 128.0 142.0 120.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:188.00 Median:183.00 stddev:16.25 (8.9%) stddev-sans-first:17.18 02:18:08 INFO - PROCESS | 1661 | Values: 184.0 183.0 183.0 182.0 183.0 186.0 179.0 234.0 183.0 183.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:71.30 Median:63.50 stddev:17.44 (27.5%) stddev-sans-first:18.28 02:18:08 INFO - PROCESS | 1661 | Values: 79.0 73.0 59.0 107.0 59.0 58.0 62.0 65.0 95.0 56.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:84.50 stddev:12.55 (14.9%) stddev-sans-first:13.29 02:18:08 INFO - PROCESS | 1661 | Values: 82.0 87.0 91.0 68.0 71.0 68.0 95.0 69.0 67.0 99.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.45 02:18:08 INFO - PROCESS | 1661 | Values: 88.0 85.0 88.0 86.0 87.0 84.0 87.0 85.0 88.0 85.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.97 (1.0%) stddev-sans-first:1.00 02:18:08 INFO - PROCESS | 1661 | Values: 94.0 93.0 94.0 93.0 92.0 95.0 94.0 94.0 92.0 93.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:85.50 stddev:15.00 (17.5%) stddev-sans-first:15.65 02:18:08 INFO - PROCESS | 1661 | Values: 85.0 86.0 87.0 85.0 86.0 97.0 132.0 83.0 85.0 101.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.33 02:18:08 INFO - PROCESS | 1661 | Values: 55.0 55.0 54.0 54.0 54.0 54.0 54.0 54.0 54.0 54.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:70.00 stddev:13.34 (19.1%) stddev-sans-first:13.60 02:18:08 INFO - PROCESS | 1661 | Values: 66.0 66.0 75.0 76.0 71.0 68.0 72.0 101.0 101.0 69.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:83.00 stddev:7.13 (8.6%) stddev-sans-first:7.56 02:18:08 INFO - PROCESS | 1661 | Values: 85.0 82.0 82.0 83.0 90.0 102.0 76.0 83.0 86.0 79.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:137.00 stddev:13.69 (10.0%) stddev-sans-first:14.50 02:18:08 INFO - PROCESS | 1661 | Values: 137.0 137.0 137.0 135.0 134.0 119.0 132.0 151.0 171.0 135.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.40 Median:170.00 stddev:21.88 (12.9%) stddev-sans-first:22.66 02:18:08 INFO - PROCESS | 1661 | Values: 164.0 176.0 172.0 168.0 167.0 226.0 209.0 165.0 164.0 163.0 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.07 (1.6%) stddev-sans-first:0.97 02:18:08 INFO - PROCESS | 1661 | Values: 68.0 66.0 67.0 66.0 67.0 66.0 68.0 66.0 65.0 65.0 02:18:08 INFO - PROCESS | 1661 | -------- Summary: end -------- 02:18:08 INFO - PROCESS | 1661 | 02:18:08 INFO - TEST-INFO | 1661: exit 0 02:18:08 INFO - TEST-OK | kraken | took 59176ms 02:18:08 INFO - SUITE-END | took 1175s 02:18:09 INFO - Completed test suite (00:19:35) 02:18:09 INFO - javascript benchmark 02:18:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3366.882159124431, 3388.1021529294735, 3403.1322012886712, 3429.314137172563, 3457.0838727737155, 4771.642126840492, 5708.4291570843, 5759.827205183846, 5817.331006934204, 5846.64920104793, 5766.82699519014, 5795.362510123872, 5887.0, 5891.88216235675, 5925.61483503572, 5149.382074151088, 5171.37943446785, 5235.633505654606, 5260.053190425722, 5343.492368225013, 5327.547158491533, 5338.439463856297, 5357.08929481989, 5366.06093933562, 5400.324959380077, 5160.0969830279555, 5214.32213812202, 5238.764255608507, 5279.472052794709, 5303.761330740087, 5205.141151709963, 5208.739563021833, 5224.738763061831, 5295.285136506561, 5332.973335133338, 5194.740262986836, 5199.454057323965, 5242.449542798027, 5292.682439053669, 5307.150855863043, 5663.0655941769555, 5709.143628455723, 5768.192453056576, 5834.299884013945, 5917.142014407887, 5334.893302133974, 6296.590721603081, 6324.652144132071, 6357.396047375492, 6375.93624063758, 5779.227549637307, 5851.94664960307, 6355.3009168991375, 6377.2666143393635, 6379.87240255197, 4781.760911954389, 5817.040188368872, 5899.380565040652, 5927.999999999957, 5982.0727787193055, 6231.22109736283, 6300.27546832111, 6302.369763023661, 6308.905366419507, 6323.20959880015, 6183.5671502994355, 6238.532110091671, 6249.468795152372, 6256.781012664626, 6270.372962703785, 6156.07658851167, 6208.968955155286, 6348.77779277723, 6368.904466433008, 6378.808635740936, 6275.497960163176, 6277.435030847246, 6326.398992095744, 6341.746330146742, 6349.142865713162, 6313.90529142054, 6317.368263173646, 6329.556931014785, 6336.081268216083, 6345.460635845912, 4904.8038078477175, 4983.352164218629, 4988.376452943382, 4992.151334273182, 5037.722925239147, 4458.910821783546, 4471.709338892962, 4500.987277862455, 4528.0, 4528.298113792367, 5784.913226301609, 5801.7389217485315, 5803.854903627359, 5805.129230615442, 5852.882942341214, 6175.876482470326, 6258.3741625837965, 6260.624362538262, 6276.372362763687, 6316.147320111818, 5752.3097228332035, 5770.0, 5817.883642327214, 5822.534197264209, 5855.736491857877, 4599.33309670096, 5490.396056433789, 5505.091659876076, 5511.118221084607, 5518.53092487135, 6239.313675495782, 6249.6562689052535, 6303.558750887394, 6363.331850155667, 6372.4583410409705, 6333.081703152925, 6335.524835637253, 6337.556371053982, 6361.427471527585, 6361.93638063632, 6351.872962540631, 6358.841028974312, 6360.204974378203, 6365.713542890674, 6375.362463753588, 6108.961476549086, 6161.229846269216, 6196.752129914933, 6232.968835155795, 6299.716512756845, 6301.306856245717, 6338.746450142127, 6346.746130154742, 6347.0479428086155, 6373.490120790233, 5914.940850591611, 6167.383261673976, 6181.96909015452, 6191.659458729813, 6199.659018754011, 6032.60788048793, 6126.212334160775, 6128.380904898417, 6131.049687298476, 6211.596246243979, 6100.0, 6105.8785319909775, 6119.146555857468, 6120.204373431426, 6127.7393367763425, 6087.208662873975, 6173.351798061319, 6207.906881396873, 6236.719347629459, 6244.625322480666, 5942.1383899335215, 5993.760249589967, 6021.337652858269, 6071.635701857903, 6099.634021958696], "value": 5752.660428662208, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.29740066506236, 529.7669025628724, 531.3411369901322, 539.2935254816191, 563.788579282769, 30494.0, 32263.0, 32462.67537324626, 41726.74819755404, 42962.57037429625, 44234.0, 44597.885052873695, 44616.0, 44820.775896120474, 44822.87942801423, 9835.311528193011, 9990.300678952477, 10000.399976001427, 10006.649767258148, 10010.149137323337, 8865.113488651115, 9050.049744776803, 9772.0, 11957.461914213882, 12221.388930553481, 12179.756404871898, 12233.082518811125, 12249.571265005727, 12250.387480625977, 12289.446974886107, 8908.0, 12141.93929030358, 12222.572209972608, 12226.083043771663, 12229.38853057348, 11757.35334556599, 12072.456739446658, 12088.395580220953, 12320.691982700504, 12325.137240393176, 41643.167136657095, 41919.37120943188, 41921.580784192076, 42038.0, 42098.1580368394, 3636.054625797285, 3684.521012268388, 4273.786310684453, 4276.166147601219, 4465.68740188187, 25620.61099431939, 32096.518552221503, 32973.17567060819, 33006.669933300596, 33164.1708957278, 3320.1035720355367, 3356.8153751543655, 3377.5102610121394, 3425.2635683328203, 3432.2277487565098, 487618.0, 487687.0, 488911.5554422241, 490169.0, 491276.0, 61373.0, 61761.07358389628, 61846.76306473845, 61880.0, 61898.45253868599, 65296.67351663306, 65386.0, 65443.0, 65446.67276663682, 65473.0, 14524.0, 15048.398064077533, 16546.503604891874, 16761.580960476087, 16831.07429091412, 11555.88444115565, 11669.066474682006, 11711.648650540497, 11894.226875253082, 12876.291803950873, 16697.165141742866, 17243.655126897393, 17282.567935801453, 17309.048002359992, 17348.04585747796, 5007.6183339577865, 8002.1997800219515, 8091.838163236703, 8152.714654987047, 8209.425340226127, 937.3813283233034, 1021.9080282774586, 1047.9685609431729, 1062.4315990944963, 1083.875354334246, 26765.598516022274, 29815.65829537676, 30325.694569097945, 31126.066218013497, 31313.373732525222, 1052.5105825791045, 1081.9242653014371, 1097.7036200225893, 1136.982400751323, 1169.3159501691605, 15221.771673424908, 15827.841721582867, 15862.920685396499, 16435.67128657444, 16556.58608534772, 671319.0, 733621.0, 739987.0, 742022.0, 1006923.0, 59837.0, 60100.69949650224, 60488.0, 60498.69750651307, 60563.69718151469, 1182.3674334231146, 1299.2789002103923, 1414.575627311823, 1446.3636000159895, 1461.1817382265965, 30064.699353006188, 30792.53811192789, 31309.0, 32074.198145045626, 32492.83753581217, 57566.136507951574, 58731.0, 58769.11846322394, 58787.412125878196, 58881.41118588931, 613056.0, 635289.0, 636336.0, 637371.0, 638069.0, 24503.0, 27140.371577705402, 27779.0, 28141.43717125605, 28141.859290703414, 46318.30522542097, 47407.76296118635, 48221.517784821706, 49207.29359885098, 49265.76835579139, 38560.42159367556, 39790.60209397985, 39950.0, 40280.79859600683, 40654.79672601618, 38539.036524087125, 39371.80314098411, 39804.800975994935, 40123.398149027205, 40423.39364909587, 38051.42922856215, 38890.0, 39045.63340283076, 39108.0, 39178.80410597929, 31784.841075794473, 33601.79977502812, 34460.48309275413, 34514.65485345215, 34538.82730586331, 22817.77182228223, 25461.3634659135, 25464.490710186063, 25695.357616059002, 25825.483490330465], "value": 20949.029419809092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.62698555415693, 381.3212481782426, 385.21608526648276, 397.40191012526145, 406.4756464161232, 19706.11322490488, 21219.045142968564, 21832.89083554584, 22206.00072996715, 22263.109475620975, 20548.280810171647, 21421.67867481989, 21432.678509822366, 21482.140714371446, 21855.016524256407, 5154.149565321727, 5473.042217611922, 5484.424135465779, 5492.642978206423, 5497.835064948038, 3898.3372826619543, 4112.691548133902, 5364.570834333244, 5845.327787304472, 6125.203723515937, 6170.259568851744, 6171.7531298748, 6175.938240617604, 6185.938140618604, 6201.968990155043, 5900.0, 6158.938410615881, 6170.784022559189, 6177.567570270083, 6180.351063138373, 3767.5478942526797, 3974.3641017437362, 5611.523020543259, 5972.686009077996, 5973.342932277489, 13442.327883605878, 13836.446542138303, 13837.377318020714, 15497.535073947687, 23944.042238310445, 2286.954260914789, 2431.0638129524823, 2470.036685692581, 2852.9301511933027, 3046.299351149226, 8266.793330166794, 9943.900560994442, 10141.949290253575, 10533.631322903662, 10648.520816563274, 1821.8633602479867, 1836.375632285016, 1837.3752924005903, 1860.2744929477515, 1904.304928701028, 127590.08614870692, 128385.0, 129564.0, 130456.0, 132142.678573214, 11445.828312575319, 11550.306981581132, 11634.6509604712, 11691.181617286707, 11865.762684746256, 14752.188629625474, 14831.84938183979, 14864.108153510824, 14873.851261487247, 14881.851181488262, 7320.304571065741, 8458.027326857367, 8536.487810731376, 8626.137386261324, 8733.0, 7816.6091695415, 7918.366530677532, 8556.786080347916, 8584.570771461402, 8739.694110706096, 4534.727916324965, 4619.930701039487, 4621.052684199731, 4655.511171326962, 4675.392199014106, 1480.015789499992, 1769.2407328340873, 1827.7715285589302, 1863.6086421851292, 1903.486058764126, 843.0810416645885, 923.4182465046978, 942.2320808541017, 1007.1942446043137, 1039.9012093851072, 18449.985250811333, 21243.256486023223, 21647.376446766557, 22209.1116355344, 22268.331950041524, 1017.2218253036433, 1017.8574999500075, 1026.0765311219814, 1027.2244455436098, 1041.953112109957, 11108.500117494732, 11693.0, 11721.472533736003, 11801.763964720829, 11940.761184776256, 541663.0, 554128.0, 554503.0, 560773.0, 562251.0, 134361.0, 134488.0, 136992.0, 138133.0, 138754.91867621994, 901.2384535067988, 964.7361955837735, 969.680005598151, 988.4711679251566, 993.4635296939572, 18724.0, 19794.802051979295, 19879.503012424804, 19918.402447926586, 19944.90027549911, 40171.79914100411, 57134.0, 57551.71224143852, 57987.23082999592, 58775.0, 66721.0, 78458.64624061287, 80611.98470038062, 81471.0, 82929.0, 7970.601469926596, 8231.95884020576, 8243.0, 8243.093259741543, 8453.112423195476, 9186.816263674822, 13448.258681680192, 13636.250006249751, 13647.522336718168, 13749.450021999008, 11622.128340374336, 11934.462949167135, 12000.219985700902, 12056.698582535506, 12069.637910862688, 11407.973282404624, 11418.029467495024, 11475.827862582235, 11796.587119450778, 11812.822807658064, 11608.30350178992, 11891.524339026342, 12002.699932501408, 12030.75938481208, 12173.2696038238, 7168.569885806868, 7576.62116894142, 8157.469764465291, 8305.211004954519, 8500.192481714228, 11120.944395277971, 11799.05023744064, 12086.15396922207, 12099.213551119501, 12132.818007729715], "value": 9589.405882391433, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1546.4510098914889, 1582.2168026826719, 1787.0796539782013, 1917.1947781931597, 1917.587718640492, 8462.830743385128, 8650.264727498163, 9090.727278181665, 9096.909030909705, 9099.499527525992, 7820.06159260888, 8062.193780621935, 8069.354451643869, 8157.143499932511, 8894.688685895995, 2149.591577600255, 2323.2449453927456, 2408.349745568695, 2409.662647229389, 2410.8432951858163, 2387.95224095518, 2392.174699728591, 2415.130553000918, 2415.915442959497, 2429.1740808125232, 1995.860289779716, 2128.5955668423026, 2394.7006624171977, 2407.6629271901948, 2412.0351859256352, 2129.1483406637312, 2157.557700671359, 2158.924437644675, 2306.5963456395066, 2399.6400539918977, 2418.8427752196053, 2454.9509009819703, 2455.9386015349583, 2459.7909177719916, 2460.9753902460925, 8938.553072346422, 8983.955080224623, 8987.865182022275, 8995.775105622412, 8999.010108888016, 5332.386775520826, 5516.117421212627, 5539.113741801292, 5540.473655002808, 5541.27963364764, 3515.9648403515894, 3619.2580520993397, 3724.0689827543115, 3728.6457786510236, 3735.701143908481, 1070.9892901070968, 1079.1205167788228, 1081.0810810810826, 1083.9620613278498, 1106.955721771128, 11486.597969071043, 11678.591249306233, 11688.590899318484, 11707.882921170849, 11753.412329383582, 4770.451398089264, 4797.136515427256, 4835.44392394872, 4930.433000204951, 4935.605151587864, 10014.0, 10151.746206344753, 10152.949235253776, 10170.135538479164, 10195.235357348229, 5399.865003374947, 5481.917771233435, 5483.698396588226, 5484.2596249505805, 5487.5884308677005, 4916.262560615936, 4933.28467372229, 4993.350864387679, 4997.775100120503, 5009.223570346602, 3573.857045718142, 3694.1160008395373, 3933.6656384207663, 3950.940735888964, 4038.0106873816103, 1034.332855308322, 1262.9052821038458, 1265.4432049898016, 1283.2685369339388, 1285.0490636928605, 758.6282721466441, 907.5235501361838, 908.3732224765023, 952.2144231009445, 993.7962717642865, 6034.125051867454, 6052.697365131814, 6148.0, 6158.507319414436, 6205.441510264098, 940.1538615246333, 940.4592359393376, 979.6571200079915, 1027.8766548014287, 1044.195969103801, 3897.668698160688, 4084.9387259191135, 4093.324601440729, 4156.937645935313, 4168.937465938014, 10607.257491975488, 10846.78306433867, 10951.0, 10963.013328800445, 10988.120950323955, 10821.783564328669, 10920.12638988879, 10920.672379828458, 10928.945355273172, 10945.83581246282, 6857.485688573377, 8151.5924203790755, 8208.425410221227, 8242.958785206156, 8246.917530824734, 4822.541858523435, 5870.7945221917025, 5990.700464976647, 6007.639541627516, 6043.607165534227, 9797.461139637388, 9993.300468967103, 10000.2999790014, 10043.748906277402, 10072.546735396778, 8141.959290203511, 9846.163076138322, 10129.189664826943, 10349.586016559555, 10365.74085647865, 8222.465539739898, 8409.369297302605, 8426.789330266793, 8512.276456501268, 8512.914870851213, 7489.28851759082, 8327.084020757833, 8453.95773021131, 8508.191721786421, 8523.019852717018, 7525.58609276495, 7586.506877052974, 7616.276453736886, 7743.806404839924, 7746.147923728273, 7436.367908727602, 7482.96258518704, 7483.550986940801, 7523.661435235318, 7619.047619047619, 7010.789676309719, 7112.431005519443, 7155.177154627285, 7161.319674630901, 7164.677589508589, 4883.730230140261, 5076.162433198481, 5142.5885929126325, 5171.224316352577, 5246.632735708617, 4933.457319694902, 5149.459306772735, 5167.638265321541, 5340.546053585489, 5348.545373643284], "value": 4658.008994243826, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.7670998136517, 297.05240283495647, 303.2676087249293, 313.4139159771228, 317.1737623490806, 4729.716217026981, 5102.708752854407, 5218.21726740989, 5273.2617433559335, 5419.376771671259, 5220.738963051851, 5323.467653234675, 5351.68195718654, 5374.946250537493, 5387.757550910209, 3702.4446333049987, 4034.2334956358345, 4061.939070913939, 4078.4086307485395, 4091.713580049398, 3166.8416579171067, 3860.8031510231776, 3900.3759398496245, 4087.4890638670167, 4097.73364731293, 3482.1990942083335, 3484.6863782259593, 3944.1125746706903, 3961.2671655743634, 4061.7969101544954, 3330.81680507572, 4064.410660454247, 4068.1863627274515, 4069.0641152534786, 4075.4701888754566, 2947.852607369623, 3150.70068343507, 3395.947256350539, 3538.7876727396183, 3563.6436356364416, 4719.427911788083, 5159.871003224912, 5187.42938276793, 5196.428392876781, 5216.2958000669805, 3740.775553466801, 4514.367988481616, 4653.697509661895, 4660.300954856764, 4668.323093151508, 5193.013327467769, 5584.101405522101, 5591.944080559183, 5598.488408129823, 5678.205051292823, 3164.8259345735974, 3798.5631652360057, 3804.1821008483034, 3825.3496905526126, 3831.961680383188, 4817.373741413594, 5897.852553686193, 5917.289925209003, 5928.7332070056955, 5968.731407086648, 3601.2257364666198, 3835.9424608630893, 3858.7266202153223, 3871.399933010388, 3873.9612603874166, 3460.8269586520573, 3706.8887933362043, 3925.823337949799, 3938.86213982515, 3954.703397245218, 4442.178706730225, 4493.505714371417, 4539.182947069493, 4539.863804085882, 4546.659000574904, 4614.3309220163555, 5593.049181639131, 5616.9157462638095, 5623.718814059281, 5625.943740562624, 1135.8125909224884, 1314.6844757258189, 1336.7259711759066, 1344.6419116692211, 1344.7310537892265, 845.6490556419141, 903.9774005649912, 907.7186072317603, 909.377076702461, 916.8258030974093, 5141.717205553656, 5236.162214045735, 5240.475952404805, 5246.458640417703, 5259.684418934876, 5794.507466865363, 5816.651000939957, 5823.359430462646, 5831.650100993954, 5859.912101318484, 5057.797688092509, 5150.300400867704, 5164.2770012198325, 5170.276161337416, 5196.506331898464, 2658.3354161459633, 2995.4907665696883, 3008.6239220097486, 3024.2026125833518, 3030.1515575638855, 4877.926831097537, 5612.270404847425, 5941.1385349125, 5960.195373624592, 5961.956657584992, 5894.852628684317, 5956.255468066492, 5957.910631340534, 5973.80523895214, 5993.310769261503, 5226.529612334908, 5997.580169388189, 6033.698315084316, 6039.63762174271, 6075.392460753889, 5521.282233309644, 5779.8555036124435, 5813.09896965971, 5830.76676932918, 5872.618279811799, 4743.573078422821, 5004.374453193351, 5442.727863606883, 5542.367375496607, 5719.799807006735, 4675.555822196886, 4967.925481117858, 6051.485623722033, 6063.454289114001, 6067.848303792441, 2966.821990680566, 3241.951370729488, 3319.120433085186, 3846.9230615388096, 3853.556840963325, 4804.390529172766, 4813.95186048135, 5501.752421140978, 5607.411221821813, 5771.826845194651, 4887.633427493023, 5359.464053594609, 5581.944180558305, 5651.880544795702, 5654.293213348332, 5334.759935802976, 5373.623846330719, 5635.211070450222, 5668.574856885833, 5729.197912292366, 5245.816396426107, 5443.700596467233, 5482.218279059242, 5503.174523821459, 5507.697076660822, 4409.316555933835, 4861.95138048615, 5301.257823904733, 5604.907043126715, 5612.382637909908, 4809.715385192146, 5166.4058633257655, 5238.947610523998, 5475.479829416199, 5528.861778455571], "value": 4066.313166299001, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.6129193465365, 776.2198990015034, 782.1669921533568, 811.5658122904248, 864.3344624639032, 15420.228988550569, 16334.183290835455, 17700.91149544254, 18386.988715620635, 18423.263069477205, 17120.657586848254, 17345.39291124811, 17347.739783903253, 17359.47921562352, 17393.0, 721.0374150509076, 891.4740303221098, 922.6540047482194, 923.0907556057278, 926.7080869526111, 747.0288624787754, 866.2550206822159, 895.2077411490845, 904.4708845325492, 910.1808372464768, 929.832630126577, 935.6912218967724, 936.5785396571536, 936.8594710793367, 938.3337830140608, 754.6377738685462, 931.0921851195133, 931.9813603727921, 932.5383934952174, 933.6452148183714, 724.8803947348681, 724.9746258880914, 725.0320821703033, 726.0416250549224, 728.3808762551841, 15706.371745130182, 15779.05325680463, 15782.921085394615, 15783.368665253376, 15806.13066281354, 13992.580222593338, 14225.359858806378, 14228.786568201382, 14244.857551424457, 14384.856151438456, 13475.66310842227, 13518.324083795771, 13791.448342066305, 13839.446422143103, 14177.858221417757, 4556.270996640555, 4615.100055489181, 4640.621411955008, 4690.507496712864, 4865.148598995197, 121946.0, 122584.0, 123247.0, 124726.0, 127350.72649273482, 11105.0, 11198.0, 11275.0, 11332.660020199408, 11608.187426880037, 22113.894305284994, 22140.33578992633, 22154.88922555377, 22165.0, 22169.889150554467, 7927.9297294864455, 7990.161033091558, 8017.158198389086, 8172.141925097779, 8296.253337199681, 4503.369528266046, 4514.932276015862, 4541.455025396907, 4557.361969324297, 4594.95405045952, 3564.091156755063, 3656.597774244831, 3677.963220367762, 3678.816059197083, 3682.8526858925343, 654.8297442664846, 666.2704338749062, 673.531895332739, 682.2836022176793, 684.4729558240127, 508.1158783716307, 535.2773755430215, 600.2376981233808, 628.6322501336678, 645.3772109913862, 7349.926500735031, 8960.820783584291, 9147.131022552847, 9176.403533770284, 9332.4867132307, 558.5810642018486, 604.8639056212317, 629.1530823025754, 634.1260733647285, 644.8774732800753, 4435.2016637005, 4489.191945449785, 4522.276435770261, 4535.501094879561, 4577.0, 109633.0, 129910.35044824904, 133905.330473347, 135301.0, 138014.30992844971, 141375.0, 143398.0, 143590.28204858908, 144143.0, 144748.0, 126432.0, 127769.3611531955, 128150.0, 128941.0, 132700.6729932727, 9837.0, 9899.554520046759, 9931.205503559553, 9973.900260997298, 9974.700758977242, 60122.79754404975, 61822.0726689091, 62037.1388858335, 62672.37327626848, 64349.67825160844, 66146.0, 69366.0, 87681.0, 87714.0, 88782.0, 13561.321933903462, 13681.247531385869, 13688.589342319747, 13824.377902994189, 14168.92915535457, 15104.093754374773, 15301.693966120838, 15474.922625387251, 15511.5346539604, 16222.756658650365, 14322.641933951318, 14350.569482915529, 14486.0, 14754.409823606935, 14867.0, 14154.0, 14288.35702393374, 14330.283485825876, 14482.710345793235, 14569.0, 13756.931215343859, 14087.366068527146, 14115.647108822362, 14278.0, 14360.066595671247, 10718.624848130277, 11096.11231101526, 11301.491432885705, 11371.033462155809, 11541.422928853692, 10937.617183398543, 11399.943000284946, 11475.770484590095, 11814.409279536161, 11845.52617895309], "value": 8141.900052391964, "unit": "score"}], "name": "dromaeo_css", "value": 7501.816985037951, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 112.0, 110.0, 109.0, 111.0, 110.0, 111.0, 111.0, 111.0, 108.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 107.0, 150.0, 126.0, 140.0, 137.0, 128.0, 142.0, 120.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 182.0, 183.0, 186.0, 179.0, 234.0, 183.0, 183.0], "value": 188.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 59.0, 107.0, 59.0, 58.0, 62.0, 65.0, 95.0, 56.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 91.0, 68.0, 71.0, 68.0, 95.0, 69.0, 67.0, 99.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 87.0, 84.0, 87.0, 85.0, 88.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 92.0, 95.0, 94.0, 94.0, 92.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 85.0, 86.0, 97.0, 132.0, 83.0, 85.0, 101.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 75.0, 76.0, 71.0, 68.0, 72.0, 101.0, 101.0, 69.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 82.0, 83.0, 90.0, 102.0, 76.0, 83.0, 86.0, 79.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 137.0, 135.0, 134.0, 119.0, 132.0, 151.0, 171.0, 135.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 176.0, 172.0, 168.0, 167.0, 226.0, 209.0, 165.0, 164.0, 163.0], "value": 177.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 67.0, 66.0, 68.0, 66.0, 65.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1449.3000000000002, "alertThreshold": 2.0}]} 02:18:09 INFO - Return code: 0 02:18:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:18:09 INFO - # TBPL SUCCESS # 02:18:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:18:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:18:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:18:09 INFO - 2016-07-07 02:18:09.467 screenresolution[1665:9611] starting screenresolution argv=screenresolution get 02:18:09 INFO - 2016-07-07 02:18:09.469 screenresolution[1665:9611] Display 0: 1600x1200x32@60 02:18:09 INFO - 2016-07-07 02:18:09.475 screenresolution[1666:9614] starting screenresolution argv=screenresolution list 02:18:09 INFO - Available Modes on Display 0 02:18:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:18:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:18:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:18:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:18:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:18:09 INFO - Intel Iris: 02:18:09 INFO - Chipset Model: Intel Iris 02:18:09 INFO - Type: GPU 02:18:09 INFO - Bus: Built-In 02:18:09 INFO - VRAM (Dynamic, Max): 1536 MB 02:18:09 INFO - Vendor: Intel (0x8086) 02:18:09 INFO - Device ID: 0x0a2e 02:18:09 INFO - Revision ID: 0x0009 02:18:09 INFO - Displays: 02:18:09 INFO - Display: 02:18:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:18:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:18:09 INFO - Display Serial Number: 1600x1200 60 02:18:09 INFO - Main Display: Yes 02:18:09 INFO - Mirror: Off 02:18:09 INFO - Online: Yes 02:18:09 INFO - Rotation: Supported 02:18:09 INFO - Return code: 0 02:18:09 INFO - Running post-action listener: _resource_record_post_action 02:18:09 INFO - ##### 02:18:09 INFO - ##### Finished run-tests step (success) 02:18:09 INFO - ##### 02:18:09 INFO - Running post-run listener: _resource_record_post_run 02:18:09 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37684736; Write bytes: 704409600; Read time: 387; Write time: 1855 02:18:09 INFO - TinderboxPrint: CPU usage
53.4% 02:18:09 INFO - TinderboxPrint: I/O read bytes / time
37,684,736 / 387 02:18:09 INFO - TinderboxPrint: I/O write bytes / time
704,409,600 / 1,855 02:18:09 INFO - TinderboxPrint: CPU idle
2,259.3 (46.6%) 02:18:09 INFO - TinderboxPrint: CPU system
965.9 (19.9%) 02:18:09 INFO - TinderboxPrint: CPU user
1,620.4 (33.4%) 02:18:09 INFO - TinderboxPrint: Swap in / out
444,248,064 / 0 02:18:09 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387942912; Write bytes: 381449728; Read time: 28954; Write time: 698 02:18:09 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36718080; Write bytes: 295733760; Read time: 372; Write time: 1106 02:18:10 INFO - Running post-run listener: _upload_blobber_files 02:18:10 INFO - Blob upload gear active. 02:18:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:18:10 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 02:18:10 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'] 02:18:10 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 02:18:10 INFO - (blobuploader) - INFO - Open directory for files ... 02:18:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:18:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:18:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:18:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:18:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:18:11 INFO - (blobuploader) - INFO - Done attempting. 02:18:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:18:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:18:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:18:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:18:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:18:12 INFO - (blobuploader) - INFO - Done attempting. 02:18:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:18:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:18:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:18:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:18:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:18:15 INFO - (blobuploader) - INFO - Done attempting. 02:18:15 INFO - (blobuploader) - INFO - Iteration through files over. 02:18:15 INFO - Return code: 0 02:18:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:18:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:18:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e270a7d03cee09d8dfb4f37276d9c8256832baf5a92a88a1ba359a37c1a58aa1e1d0faac50892ec7afc8af3182b696739e7f10849834fd191ba2f4620f0381a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8fee9cfac796ac70046164539eec2db79a7ced43becc8a392f9482ab84700fa646e1434986644b2061e0b24ebe2b45682e85a4fd38ed4c4726a1e3f189edb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9046ad7b4bda7838e74742d0290b2224013dd9fd493dc46b082ae10f1f242a6604b500fd7ee043a5096fb0b2d8acf44d423061ad9773815d232d39045423b680"} 02:18:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:18:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:18:15 INFO - Contents: 02:18:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e270a7d03cee09d8dfb4f37276d9c8256832baf5a92a88a1ba359a37c1a58aa1e1d0faac50892ec7afc8af3182b696739e7f10849834fd191ba2f4620f0381a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8fee9cfac796ac70046164539eec2db79a7ced43becc8a392f9482ab84700fa646e1434986644b2061e0b24ebe2b45682e85a4fd38ed4c4726a1e3f189edb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9046ad7b4bda7838e74742d0290b2224013dd9fd493dc46b082ae10f1f242a6604b500fd7ee043a5096fb0b2d8acf44d423061ad9773815d232d39045423b680"} 02:18:15 INFO - Running post-run listener: copy_logs_to_upload_dir 02:18:15 INFO - Copying logs to upload dir... 02:18:15 INFO - mkdir: /builds/slave/test/build/upload/logs 02:18:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.713050 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-07 02:18:15.296685) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 02:18:15.300188) ========= 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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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/e270a7d03cee09d8dfb4f37276d9c8256832baf5a92a88a1ba359a37c1a58aa1e1d0faac50892ec7afc8af3182b696739e7f10849834fd191ba2f4620f0381a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8fee9cfac796ac70046164539eec2db79a7ced43becc8a392f9482ab84700fa646e1434986644b2061e0b24ebe2b45682e85a4fd38ed4c4726a1e3f189edb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9046ad7b4bda7838e74742d0290b2224013dd9fd493dc46b082ae10f1f242a6604b500fd7ee043a5096fb0b2d8acf44d423061ad9773815d232d39045423b680"} build_url:https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010937 build_url: 'https://queue.taskcluster.net/v1/task/NbzdJVmSS6eWzQQscwGOHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e270a7d03cee09d8dfb4f37276d9c8256832baf5a92a88a1ba359a37c1a58aa1e1d0faac50892ec7afc8af3182b696739e7f10849834fd191ba2f4620f0381a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8fee9cfac796ac70046164539eec2db79a7ced43becc8a392f9482ab84700fa646e1434986644b2061e0b24ebe2b45682e85a4fd38ed4c4726a1e3f189edb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9046ad7b4bda7838e74742d0290b2224013dd9fd493dc46b082ae10f1f242a6604b500fd7ee043a5096fb0b2d8acf44d423061ad9773815d232d39045423b680"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 02:18:15.336274) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 02:18:15.336967) ========= 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.vtj3Qe7l4k/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.LA8fTwsFoS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 02:18:15.386773) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 02:18:15.387130) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 02:18:15.387504) ========= ========= Total master_lag: 0.35 =========