builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0023 starttime: 1468750829.41 results: success (0) buildid: 20160717010853 builduid: 9e863a19fac64e50a016e772a3f82a7f revision: 711963e8daa312ae06409f8ab5c06612cb0b8f7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.413185) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.413671) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.413977) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.438585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.439013) ========= 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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-17 03:20:29-- 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% 16.1M=0.001s 2016-07-17 03:20:29 (16.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123516 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.587586) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.588145) ========= 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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.027287 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:29.639623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 03:20:29.640246) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 711963e8daa312ae06409f8ab5c06612cb0b8f7b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 711963e8daa312ae06409f8ab5c06612cb0b8f7b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rlx3cpzb1n/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.iRYgTiWwbu/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-17 03:20:29,718 truncating revision to first 12 chars 2016-07-17 03:20:29,718 Setting DEBUG logging. 2016-07-17 03:20:29,719 attempt 1/10 2016-07-17 03:20:29,719 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/711963e8daa3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 03:20:30,777 unpacking tar archive at: mozilla-central-711963e8daa3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.313216 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 03:20:30.978912) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:30.979239) ========= script_repo_revision: 711963e8daa312ae06409f8ab5c06612cb0b8f7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:30.979624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:30.979911) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:30.998474) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-07-17 03:20:30.998899) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rlx3cpzb1n/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.iRYgTiWwbu/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:20:31 INFO - MultiFileLogger online at 20160717 03:20:31 in /builds/slave/test 03:20:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:20:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:31 INFO - {'append_to_log': False, 03:20:31 INFO - 'base_work_dir': '/builds/slave/test', 03:20:31 INFO - 'blob_upload_branch': 'Firefox', 03:20:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:31 INFO - 'branch': 'Firefox', 03:20:31 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:31 INFO - 'config_files': ('talos/mac_config.py',), 03:20:31 INFO - 'default_actions': ('clobber', 03:20:31 INFO - 'read-buildbot-config', 03:20:31 INFO - 'download-and-extract', 03:20:31 INFO - 'populate-webroot', 03:20:31 INFO - 'create-virtualenv', 03:20:31 INFO - 'install', 03:20:31 INFO - 'run-tests'), 03:20:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:31 INFO - 'download_minidump_stackwalk': True, 03:20:31 INFO - 'download_symbols': 'ondemand', 03:20:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:20:31 INFO - 'tooltool.py': '/tools/tooltool.py', 03:20:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:20:31 INFO - '/tools/misc-python/virtualenv.py')}, 03:20:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:31 INFO - 'installer_path': 'installer.exe', 03:20:31 INFO - 'log_level': 'info', 03:20:31 INFO - 'log_name': 'talos', 03:20:31 INFO - 'log_to_console': True, 03:20:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:20:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:20:31 INFO - 'opt_config_files': (), 03:20:31 INFO - 'pip_index': False, 03:20:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:31 INFO - 'cmd': ('bash', 03:20:31 INFO - '-c', 03:20:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:31 INFO - 'enabled': True, 03:20:31 INFO - 'halt_on_failure': False, 03:20:31 INFO - 'name': 'check_screen_resolution'},), 03:20:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:31 INFO - 'cmd': ('bash', 03:20:31 INFO - '-c', 03:20:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:31 INFO - 'enabled': True, 03:20:31 INFO - 'halt_on_failure': False, 03:20:31 INFO - 'name': 'check_screen_resolution'},), 03:20:31 INFO - 'run_cmd_checks_enabled': True, 03:20:31 INFO - 'sps_profile': False, 03:20:31 INFO - 'sps_profile_interval': 0, 03:20:31 INFO - 'suite': 'dromaeojs-e10s', 03:20:31 INFO - 'system_bits': '32', 03:20:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:31 INFO - 'title': 't-yosemite-r7-0023', 03:20:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:31 INFO - 'use_talos_json': True, 03:20:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:31 INFO - 'work_dir': 'build'} 03:20:31 INFO - ##### 03:20:31 INFO - ##### Running clobber step. 03:20:31 INFO - ##### 03:20:31 INFO - Running pre-action listener: _resource_record_pre_action 03:20:31 INFO - Running main action method: clobber 03:20:31 INFO - rmtree: /builds/slave/test/build 03:20:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:32 INFO - Running post-action listener: _resource_record_post_action 03:20:32 INFO - ##### 03:20:32 INFO - ##### Finished clobber step (success) 03:20:32 INFO - ##### 03:20:32 INFO - ##### 03:20:32 INFO - ##### Running read-buildbot-config step. 03:20:32 INFO - ##### 03:20:32 INFO - Running pre-action listener: _resource_record_pre_action 03:20:32 INFO - Running main action method: read_buildbot_config 03:20:32 INFO - Using buildbot properties: 03:20:32 INFO - { 03:20:32 INFO - "project": "", 03:20:32 INFO - "product": "firefox", 03:20:32 INFO - "script_repo_revision": "production", 03:20:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:20:32 INFO - "repository": "", 03:20:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:20:32 INFO - "buildid": "20160717010853", 03:20:32 INFO - "pgo_build": "False", 03:20:32 INFO - "basedir": "/builds/slave/test", 03:20:32 INFO - "buildnumber": 7, 03:20:32 INFO - "slavename": "t-yosemite-r7-0023", 03:20:32 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:20:32 INFO - "platform": "yosemite_r7", 03:20:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:20:32 INFO - "branch": "mozilla-central", 03:20:32 INFO - "repo_path": "mozilla-central", 03:20:32 INFO - "revision": "711963e8daa312ae06409f8ab5c06612cb0b8f7b", 03:20:32 INFO - "stage_platform": "macosx64", 03:20:32 INFO - "builduid": "9e863a19fac64e50a016e772a3f82a7f", 03:20:32 INFO - "slavebuilddir": "test" 03:20:32 INFO - } 03:20:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:20:32 INFO - Running post-action listener: _resource_record_post_action 03:20:32 INFO - ##### 03:20:32 INFO - ##### Finished read-buildbot-config step (success) 03:20:32 INFO - ##### 03:20:32 INFO - ##### 03:20:32 INFO - ##### Running download-and-extract step. 03:20:32 INFO - ##### 03:20:32 INFO - Running pre-action listener: _resource_record_pre_action 03:20:32 INFO - Running main action method: download_and_extract 03:20:32 INFO - mkdir: /builds/slave/test/build/tests 03:20:32 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')]} 03:20:32 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:20:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:20:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:20:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:33 INFO - Downloaded 1199 bytes. 03:20:33 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:20:33 INFO - Using the following test package requirements: 03:20:33 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:20:33 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:20:33 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:20:33 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'jsshell-mac.zip'], 03:20:33 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:20:33 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:20:33 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:20:33 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:20:33 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:20:33 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:20:33 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:20:33 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:20: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')]} 03:20:33 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:20:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:20:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:20:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:35 INFO - Downloaded 18835903 bytes. 03:20:35 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'] 03:20:35 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 03:20:38 INFO - Return code: 0 03:20:38 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 03:20:38 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')]} 03:20:38 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:20:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:20:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:20:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:39 INFO - Downloaded 18835903 bytes. 03:20:39 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'] 03:20:39 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 03:20:42 INFO - Return code: 0 03:20: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')]} 03:20:42 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:20:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:20:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:20:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:45 INFO - Downloaded 14403317 bytes. 03:20:45 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'] 03:20:45 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 03:20:45 INFO - Return code: 0 03:20:45 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')]} 03:20:45 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:20:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:20:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:20:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:20:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:17 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg: The read operation timed out 03:21:17 INFO - retry: attempt #1 caught exception: The read operation timed out 03:21:17 INFO - retry: Failed, sleeping 30 seconds before retrying 03:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #2 03:21:50 INFO - Downloaded 112771472 bytes. 03:21:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:21:50 INFO - mkdir: /builds/slave/test/properties 03:21:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:50 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:50 INFO - Contents: 03:21:50 INFO - build_url:https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:21:51 INFO - Running post-action listener: _resource_record_post_action 03:21:51 INFO - Running post-action listener: set_extra_try_arguments 03:21:51 INFO - ##### 03:21:51 INFO - ##### Finished download-and-extract step (success) 03:21:51 INFO - ##### 03:21:51 INFO - ##### 03:21:51 INFO - ##### Running populate-webroot step. 03:21:51 INFO - ##### 03:21:51 INFO - Running pre-action listener: _resource_record_pre_action 03:21:51 INFO - Running main action method: populate_webroot 03:21:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:51 INFO - '--tppagecycles', 03:21:51 INFO - '7'], 03:21:51 INFO - 'tests': ['tsvgm']}}, 03:21:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['damp', 'tps']}, 03:21:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'tests': ['damp', 'tps']}, 03:21:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['dromaeo_dom']}, 03:21:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['basic_compositor_video']}, 03:21:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:21:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['a11yr', 03:21:51 INFO - 'ts_paint', 03:21:51 INFO - 'tpaint', 03:21:51 INFO - 'sessionrestore', 03:21:51 INFO - 'sessionrestore_no_auto_restore', 03:21:51 INFO - 'tabpaint']}, 03:21:51 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:51 INFO - 'ts_paint', 03:21:51 INFO - 'tpaint', 03:21:51 INFO - 'sessionrestore', 03:21:51 INFO - 'sessionrestore_no_auto_restore', 03:21:51 INFO - 'tabpaint']}, 03:21:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:51 INFO - 'ts_paint', 03:21:51 INFO - 'tpaint', 03:21:51 INFO - 'sessionrestore', 03:21:51 INFO - 'sessionrestore_no_auto_restore', 03:21:51 INFO - 'tabpaint']}, 03:21:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:51 INFO - 'ts_paint', 03:21:51 INFO - 'tpaint', 03:21:51 INFO - 'sessionrestore', 03:21:51 INFO - 'sessionrestore_no_auto_restore', 03:21:51 INFO - 'tabpaint']}, 03:21:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['a11yr', 03:21:51 INFO - 'ts_paint', 03:21:51 INFO - 'tpaint', 03:21:51 INFO - 'sessionrestore', 03:21:51 INFO - 'sessionrestore_no_auto_restore', 03:21:51 INFO - 'tabpaint']}, 03:21:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['a11yr', 03:21:51 INFO - 'ts_paint', 03:21:51 INFO - 'tpaint', 03:21:51 INFO - 'sessionrestore', 03:21:51 INFO - 'sessionrestore_no_auto_restore', 03:21:51 INFO - 'tabpaint']}, 03:21:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['tsvgx', 03:21:51 INFO - 'tsvgr_opacity', 03:21:51 INFO - 'tart', 03:21:51 INFO - 'tscrollx', 03:21:51 INFO - 'cart']}, 03:21:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:51 INFO - 'tsvgr_opacity', 03:21:51 INFO - 'tart', 03:21:51 INFO - 'tscrollx', 03:21:51 INFO - 'cart']}, 03:21:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'talos_options': ['--disable-e10s'], 03:21:51 INFO - 'tests': ['tp5o']}, 03:21:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'tests': ['tp5o']}, 03:21:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'talos_options': ['--disable-e10s', 03:21:51 INFO - '--xperf_path', 03:21:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:51 INFO - 'tests': ['tp5n']}, 03:21:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:51 INFO - 'talos_options': ['--xperf_path', 03:21:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:51 INFO - 'tests': ['tp5n']}}, 03:21:51 INFO - 'talos.zip': {'path': '', 03:21:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:51 INFO - Running post-action listener: _resource_record_post_action 03:21:51 INFO - ##### 03:21:51 INFO - ##### Finished populate-webroot step (success) 03:21:51 INFO - ##### 03:21:51 INFO - ##### 03:21:51 INFO - ##### Running create-virtualenv step. 03:21:51 INFO - ##### 03:21:51 INFO - Running pre-action listener: _resource_record_pre_action 03:21:51 INFO - Running main action method: create_virtualenv 03:21:51 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:51 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 03:21:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:51 INFO - Using real prefix '/tools/python27' 03:21:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:52 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:55 INFO - Installing pip.................done. 03:21:55 INFO - Return code: 0 03:21:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:55 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')]} 03:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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')]} 03:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:21:55 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 03:21:55 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 03:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:55 INFO - 'HOME': '/Users/cltbld', 03:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:55 INFO - 'LOGNAME': 'cltbld', 03:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:55 INFO - 'MOZ_NO_REMOTE': '1', 03:21:55 INFO - 'NO_EM_RESTART': '1', 03:21:55 INFO - 'PAGER': '/bin/cat', 03:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:55 INFO - 'PWD': '/builds/slave/test', 03:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:55 INFO - 'SHELL': '/bin/bash', 03:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:21:55 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:55 INFO - 'USER': 'cltbld', 03:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:55 INFO - 'XPC_FLAGS': '0x0', 03:21:55 INFO - 'XPC_SERVICE_NAME': '0', 03:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:55 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 03:21:55 INFO - Cleaning up... 03:21:55 INFO - Return code: 0 03:21:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:55 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')]} 03:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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')]} 03:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:21:55 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 03:21:55 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 03:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:55 INFO - 'HOME': '/Users/cltbld', 03:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:55 INFO - 'LOGNAME': 'cltbld', 03:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:55 INFO - 'MOZ_NO_REMOTE': '1', 03:21:55 INFO - 'NO_EM_RESTART': '1', 03:21:55 INFO - 'PAGER': '/bin/cat', 03:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:55 INFO - 'PWD': '/builds/slave/test', 03:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:55 INFO - 'SHELL': '/bin/bash', 03:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:21:55 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:55 INFO - 'USER': 'cltbld', 03:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:55 INFO - 'XPC_FLAGS': '0x0', 03:21:55 INFO - 'XPC_SERVICE_NAME': '0', 03:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:55 INFO - Downloading/unpacking psutil>=3.1.1 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:59 INFO - Installing collected packages: psutil 03:21:59 INFO - Running setup.py install for psutil 03:21:59 INFO - building 'psutil._psutil_osx' extension 03:21:59 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 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 INFO - building 'psutil._psutil_posix' extension 03:22:00 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 03:22:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:22:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:22:00 INFO - ^ 03:22:00 INFO - 1 warning generated. 03:22:00 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 03:22:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:00 INFO - Successfully installed psutil 03:22:00 INFO - Cleaning up... 03:22:00 INFO - Return code: 0 03:22:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:00 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:22:00 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 03:22:00 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 03:22:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:00 INFO - 'HOME': '/Users/cltbld', 03:22:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:00 INFO - 'LOGNAME': 'cltbld', 03:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:00 INFO - 'MOZ_NO_REMOTE': '1', 03:22:00 INFO - 'NO_EM_RESTART': '1', 03:22:00 INFO - 'PAGER': '/bin/cat', 03:22:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:00 INFO - 'PWD': '/builds/slave/test', 03:22:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:00 INFO - 'SHELL': '/bin/bash', 03:22:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:00 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:00 INFO - 'USER': 'cltbld', 03:22:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:00 INFO - 'XPC_FLAGS': '0x0', 03:22:00 INFO - 'XPC_SERVICE_NAME': '0', 03:22:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 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 03:22:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:22:03 INFO - Installing collected packages: mozsystemmonitor 03:22:03 INFO - Running setup.py install for mozsystemmonitor 03:22:03 INFO - Successfully installed mozsystemmonitor 03:22:03 INFO - Cleaning up... 03:22:03 INFO - Return code: 0 03:22:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:22:03 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')]} 03:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:03 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')]} 03:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:03 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:22:03 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 03:22:03 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 03:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:03 INFO - 'HOME': '/Users/cltbld', 03:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:03 INFO - 'LOGNAME': 'cltbld', 03:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:03 INFO - 'MOZ_NO_REMOTE': '1', 03:22:03 INFO - 'NO_EM_RESTART': '1', 03:22:03 INFO - 'PAGER': '/bin/cat', 03:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:03 INFO - 'PWD': '/builds/slave/test', 03:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:03 INFO - 'SHELL': '/bin/bash', 03:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:03 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:03 INFO - 'USER': 'cltbld', 03:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:03 INFO - 'XPC_FLAGS': '0x0', 03:22:03 INFO - 'XPC_SERVICE_NAME': '0', 03:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:03 INFO - Downloading/unpacking jsonschema==2.5.1 03:22:03 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 03:22:03 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 03:22:03 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 03:22:03 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 03:22:03 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 03:22:03 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 03:22:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:06 INFO - warning: no files found matching '*.txt' 03:22:06 INFO - no previously-included directories found matching 'build' 03:22:06 INFO - no previously-included directories found matching 'dist' 03:22:06 INFO - no previously-included directories found matching '.git*' 03:22:06 INFO - Installing collected packages: jsonschema, functools32 03:22:06 INFO - Running setup.py install for functools32 03:22:07 INFO - warning: no files found matching '*.txt' 03:22:07 INFO - no previously-included directories found matching 'build' 03:22:07 INFO - no previously-included directories found matching 'dist' 03:22:07 INFO - no previously-included directories found matching '.git*' 03:22:07 INFO - Successfully installed jsonschema functools32 03:22:07 INFO - Cleaning up... 03:22:07 INFO - Return code: 0 03:22:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22:07 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')]} 03:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:07 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')]} 03:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:07 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:22:07 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 03:22:07 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 03:22:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:07 INFO - 'HOME': '/Users/cltbld', 03:22:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:07 INFO - 'LOGNAME': 'cltbld', 03:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:07 INFO - 'MOZ_NO_REMOTE': '1', 03:22:07 INFO - 'NO_EM_RESTART': '1', 03:22:07 INFO - 'PAGER': '/bin/cat', 03:22:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:07 INFO - 'PWD': '/builds/slave/test', 03:22:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:07 INFO - 'SHELL': '/bin/bash', 03:22:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:07 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:07 INFO - 'USER': 'cltbld', 03:22:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:07 INFO - 'XPC_FLAGS': '0x0', 03:22:07 INFO - 'XPC_SERVICE_NAME': '0', 03:22:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:07 INFO - Downloading/unpacking blobuploader==1.2.4 03:22:07 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 03:22:07 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 03:22:07 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 03:22:07 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 03:22:07 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 03:22:07 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 03:22:09 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 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 03:22:11 INFO - Downloading docopt-0.6.1.tar.gz 03:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:11 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:11 INFO - Running setup.py install for blobuploader 03:22:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:11 INFO - Running setup.py install for requests 03:22:11 INFO - Running setup.py install for docopt 03:22:12 INFO - Successfully installed blobuploader requests docopt 03:22:12 INFO - Cleaning up... 03:22:12 INFO - Return code: 0 03:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:22:12 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 03:22:12 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 03:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:12 INFO - 'HOME': '/Users/cltbld', 03:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:12 INFO - 'LOGNAME': 'cltbld', 03:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:12 INFO - 'MOZ_NO_REMOTE': '1', 03:22:12 INFO - 'NO_EM_RESTART': '1', 03:22:12 INFO - 'PAGER': '/bin/cat', 03:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:12 INFO - 'PWD': '/builds/slave/test', 03:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:12 INFO - 'SHELL': '/bin/bash', 03:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:12 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:12 INFO - 'USER': 'cltbld', 03:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:12 INFO - 'XPC_FLAGS': '0x0', 03:22:12 INFO - 'XPC_SERVICE_NAME': '0', 03:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-skVJgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-CQzTln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-aJKIBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-si_RCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-k8LVaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-2doo1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-HyO41K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-LX4m76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-sLylNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wxScr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-tAMUNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-YH3IK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wWsaFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Nw8JaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-k4ggdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-zZt1pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:14 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-x_kQUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:14 INFO - Running setup.py install for manifestparser 03:22:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:14 INFO - Running setup.py install for mozcrash 03:22:14 INFO - Running setup.py install for mozdebug 03:22:14 INFO - Running setup.py install for mozdevice 03:22:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:22:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:14 INFO - Running setup.py install for mozfile 03:22:14 INFO - Running setup.py install for mozhttpd 03:22:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for mozinfo 03:22:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for mozInstall 03:22:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for mozleak 03:22:15 INFO - Running setup.py install for mozlog 03:22:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for moznetwork 03:22:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for mozprocess 03:22:15 INFO - Running setup.py install for mozprofile 03:22:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for mozrunner 03:22:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for mozscreenshot 03:22:16 INFO - Running setup.py install for moztest 03:22:16 INFO - Running setup.py install for mozversion 03:22:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:16 INFO - Cleaning up... 03:22:16 INFO - Return code: 0 03:22:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:16 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:22:16 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 03:22:16 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 03:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:16 INFO - 'HOME': '/Users/cltbld', 03:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:16 INFO - 'LOGNAME': 'cltbld', 03:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:16 INFO - 'MOZ_NO_REMOTE': '1', 03:22:16 INFO - 'NO_EM_RESTART': '1', 03:22:16 INFO - 'PAGER': '/bin/cat', 03:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:16 INFO - 'PWD': '/builds/slave/test', 03:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:16 INFO - 'SHELL': '/bin/bash', 03:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:16 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:16 INFO - 'USER': 'cltbld', 03:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:16 INFO - 'XPC_FLAGS': '0x0', 03:22:16 INFO - 'XPC_SERVICE_NAME': '0', 03:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QJ_F1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mUtwYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-0Rz4qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Jqqyut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-d5Fpf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ciCr6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nOqsKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-3em50e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:17 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Af8WPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wY5z75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QjWlz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-tFjWTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-dNq3fQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wkqG4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-E4K0Y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-HRAKCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:18 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-FDCx69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:18 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 03:22:18 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 03:22:18 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 03:22:18 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 03:22:18 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 03:22:18 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 03:22:21 INFO - Downloading blessings-1.6.tar.gz 03:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:21 INFO - Installing collected packages: blessings 03:22:21 INFO - Running setup.py install for blessings 03:22:21 INFO - Successfully installed blessings 03:22:21 INFO - Cleaning up... 03:22:21 INFO - Return code: 0 03:22:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:21 INFO - Reading from file tmpfile_stdout 03:22:21 INFO - Current package versions: 03:22:21 INFO - blessings == 1.6 03:22:21 INFO - blobuploader == 1.2.4 03:22:21 INFO - docopt == 0.6.1 03:22:21 INFO - functools32 == 3.2.3-2 03:22:21 INFO - jsonschema == 2.5.1 03:22:21 INFO - manifestparser == 1.1 03:22:21 INFO - mozInstall == 1.12 03:22:21 INFO - mozcrash == 0.17 03:22:21 INFO - mozdebug == 0.1 03:22:21 INFO - mozdevice == 0.48 03:22:21 INFO - mozfile == 1.2 03:22:21 INFO - mozhttpd == 0.7 03:22:21 INFO - mozinfo == 0.9 03:22:21 INFO - mozleak == 0.1 03:22:21 INFO - mozlog == 3.2 03:22:21 INFO - moznetwork == 0.27 03:22:21 INFO - mozprocess == 0.23 03:22:21 INFO - mozprofile == 0.28 03:22:21 INFO - mozrunner == 6.12 03:22:21 INFO - mozscreenshot == 0.1 03:22:21 INFO - mozsystemmonitor == 0.3 03:22:21 INFO - moztest == 0.7 03:22:21 INFO - mozversion == 1.4 03:22:21 INFO - psutil == 3.1.1 03:22:21 INFO - requests == 1.2.3 03:22:21 INFO - wsgiref == 0.1.2 03:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:21 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:22:21 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 03:22:21 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 03:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:21 INFO - 'HOME': '/Users/cltbld', 03:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:21 INFO - 'LOGNAME': 'cltbld', 03:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:21 INFO - 'MOZ_NO_REMOTE': '1', 03:22:21 INFO - 'NO_EM_RESTART': '1', 03:22:21 INFO - 'PAGER': '/bin/cat', 03:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:21 INFO - 'PWD': '/builds/slave/test', 03:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:21 INFO - 'SHELL': '/bin/bash', 03:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:21 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:21 INFO - 'USER': 'cltbld', 03:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:21 INFO - 'XPC_FLAGS': '0x0', 03:22:21 INFO - 'XPC_SERVICE_NAME': '0', 03:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 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)) 03:22:22 INFO - Cleaning up... 03:22:22 INFO - Return code: 0 03:22:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22:22 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')]} 03:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:22 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')]} 03:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:22 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 0x105048a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050dd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328cd0650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, '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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 03:22:22 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 03:22:22 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 03:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:22 INFO - 'HOME': '/Users/cltbld', 03:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:22 INFO - 'LOGNAME': 'cltbld', 03:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:22 INFO - 'MOZ_NO_REMOTE': '1', 03:22:22 INFO - 'NO_EM_RESTART': '1', 03:22:22 INFO - 'PAGER': '/bin/cat', 03:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:22 INFO - 'PWD': '/builds/slave/test', 03:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:22 INFO - 'SHELL': '/bin/bash', 03:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:22 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:22 INFO - 'USER': 'cltbld', 03:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:22 INFO - 'XPC_FLAGS': '0x0', 03:22:22 INFO - 'XPC_SERVICE_NAME': '0', 03:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:22 INFO - Cleaning up... 03:22:22 INFO - Return code: 0 03:22:22 INFO - Running post-action listener: _resource_record_post_action 03:22:22 INFO - Running post-action listener: _start_resource_monitoring 03:22:22 INFO - Starting resource monitoring. 03:22:22 INFO - ##### 03:22:22 INFO - ##### Finished create-virtualenv step (success) 03:22:22 INFO - ##### 03:22:22 INFO - ##### 03:22:22 INFO - ##### Running install step. 03:22:22 INFO - ##### 03:22:22 INFO - Running pre-action listener: _resource_record_pre_action 03:22:22 INFO - Running main action method: install 03:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:22 INFO - Reading from file tmpfile_stdout 03:22:22 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:22 INFO - Reading from file tmpfile_stdout 03:22:22 INFO - Output received: 03:22:22 INFO - Usage: mozinstall [options] installer 03:22:22 INFO - Options: 03:22:22 INFO - -h, --help show this help message and exit 03:22:22 INFO - -d DEST, --destination=DEST 03:22:22 INFO - Directory to install application into. [default: 03:22:22 INFO - "/builds/slave/test"] 03:22:22 INFO - --app=APP Application being installed. [default: firefox] 03:22:22 INFO - mkdir: /builds/slave/test/build/application 03:22:22 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'] 03:22:22 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 03:22:57 INFO - Reading from file tmpfile_stdout 03:22:57 INFO - Output received: 03:22:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:22:57 INFO - Running post-action listener: _resource_record_post_action 03:22:57 INFO - ##### 03:22:57 INFO - ##### Finished install step (success) 03:22:57 INFO - ##### 03:22:57 INFO - ##### 03:22:57 INFO - ##### Running run-tests step. 03:22:57 INFO - ##### 03:22:57 INFO - Running pre-action listener: _resource_record_pre_action 03:22:57 INFO - Running main action method: run_tests 03:22:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:57 INFO - 2016-07-17 03:22:57.562 screenresolution[1640:7521] starting screenresolution argv=screenresolution get 03:22:57 INFO - 2016-07-17 03:22:57.564 screenresolution[1640:7521] Display 0: 1600x1200x32@60 03:22:57 INFO - 2016-07-17 03:22:57.570 screenresolution[1641:7523] starting screenresolution argv=screenresolution list 03:22:57 INFO - Available Modes on Display 0 03:22:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:57 INFO - Intel Iris: 03:22:57 INFO - Chipset Model: Intel Iris 03:22:57 INFO - Type: GPU 03:22:57 INFO - Bus: Built-In 03:22:57 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:57 INFO - Vendor: Intel (0x8086) 03:22:57 INFO - Device ID: 0x0a2e 03:22:57 INFO - Revision ID: 0x0009 03:22:57 INFO - Displays: 03:22:57 INFO - Display: 03:22:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:57 INFO - Display Serial Number: 1600x1200 60 03:22:57 INFO - Main Display: Yes 03:22:57 INFO - Mirror: Off 03:22:57 INFO - Online: Yes 03:22:57 INFO - Rotation: Supported 03:22:57 INFO - Return code: 0 03:22:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:57 INFO - Python 2.7.3 03:22:57 INFO - Return code: 0 03:22:57 INFO - grabbing minidump binary from tooltool 03:22: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')]} 03:22:57 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 0x104facd30>, '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 0x104d11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fa4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:57 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 03:22:57 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 03:22:57 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 03:22:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:57 INFO - Return code: 0 03:22:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlx3cpzb1n/Render', 03:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:57 INFO - 'HOME': '/Users/cltbld', 03:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:57 INFO - 'LOGNAME': 'cltbld', 03:22:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:57 INFO - 'MOZ_NO_REMOTE': '1', 03:22:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:57 INFO - 'NO_EM_RESTART': '1', 03:22:57 INFO - 'PAGER': '/bin/cat', 03:22:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:57 INFO - 'PWD': '/builds/slave/test', 03:22:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:57 INFO - 'SHELL': '/bin/bash', 03:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRYgTiWwbu/Listeners', 03:22:57 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 03:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:57 INFO - 'USER': 'cltbld', 03:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:57 INFO - 'XPC_FLAGS': '0x0', 03:22:57 INFO - 'XPC_SERVICE_NAME': '0', 03:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:22:58 INFO - mozversion application_buildid: 20160717010853 03:22:58 INFO - mozversion application_changeset: 711963e8daa312ae06409f8ab5c06612cb0b8f7b 03:22:58 INFO - mozversion application_display_name: Nightly 03:22:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:58 INFO - mozversion application_name: Firefox 03:22:58 INFO - mozversion application_remotingname: firefox 03:22:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:22:58 INFO - mozversion application_vendor: Mozilla 03:22:58 INFO - mozversion application_version: 50.0a1 03:22:58 INFO - mozversion platform_buildid: 20160717010853 03:22:58 INFO - mozversion platform_changeset: 711963e8daa312ae06409f8ab5c06612cb0b8f7b 03:22:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:22:58 INFO - mozversion platform_version: 50.0a1 03:22:58 INFO - using testdate: 1468750978 03:22:58 INFO - actual date: 1468750978 03:22:58 INFO - starting webserver on 'localhost:49276' 03:22:58 INFO - SUITE-START | Running 2 tests 03:22:58 INFO - TEST-START | dromaeo_css 03:22:58 INFO - operating with platform_type : mac_ 03:22:58 INFO - Initialising browser for dromaeo_css test... 03:22:58 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmp1R9j13/profile http://localhost:49276/getInfo.html) 03:23:00 INFO - PROCESS | 1651 | JavaScript error: , line 0: uncaught exception: undefined 03:23:01 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 03:23:01 INFO - PROCESS | 1651 | colorDepth:24 03:23:01 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 03:23:01 INFO - PROCESS | 1651 | __metrics 03:23:01 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 03:23:01 INFO - TEST-INFO | 1651: exit 0 03:23:01 INFO - Browser initialized. 03:23:01 INFO - Running cycle 1/1 for dromaeo_css test... 03:23:01 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmp1R9j13/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:13 INFO - PROCESS | 1654 | RSS: Main: 255520768 03:23:13 INFO - PROCESS | 1654 | 03:24:03 INFO - PROCESS | 1654 | 1468751043589 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] 03:26:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 03:26:15 INFO - PROCESS | 1654 | RSS: Main: 268566528 03:26:15 INFO - PROCESS | 1654 | 03:29:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 03:29:18 INFO - PROCESS | 1654 | RSS: Main: 276762624 03:29:18 INFO - PROCESS | 1654 | 03:32:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 03:32:20 INFO - PROCESS | 1654 | RSS: Main: 281202688 03:32:20 INFO - PROCESS | 1654 | 03:35:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 03:35:23 INFO - PROCESS | 1654 | RSS: Main: 280408064 03:35:23 INFO - PROCESS | 1654 | 03:38:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 03:38:26 INFO - PROCESS | 1654 | RSS: Main: 283914240 03:38:26 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 03:41:29 INFO - PROCESS | 1654 | RSS: Main: 284172288 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | __start_tp_report 03:41:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 03:41:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 03:41:29 INFO - PROCESS | 1654 | |i|pagename|runs| 03:41:29 INFO - PROCESS | 1654 | |0;dojo.html;3421.3841508528485;3426.9657303426957;3427.640097789733;3430.3310854383394;3476.7740096893717;4535.682502224838;5836.620619659724;5860.5311575074;5875.236219291492;5905.350411454742;5658.830235092943;5829.358770535248;5836.387179346171;5863.970680146594;5908.88182236355;4985.604030871344;5205.984832957575;5234.502722241381;5341.172118321647;5407.432219616942;2.8475459493065642;5349.197620356958;5359.276497672803;5367.785288588452;5429.592780541456;5299.920501192485;5330.573554115681;5330.76011579476;5334.51989320961;5360.571154307646;5244.606654500928;5339.599530035263;5391.676499410048;5398.433164517748;5410.1073322901675;5241.9737901310245;5255.264263003182;5293.258943747878;5296.894062118736;5358.410574836765;5732.484076433141;5843.474087332161;5873.911891321633;5908.881822363572;5940.198073260098;6110.862540392742;6221.9688901555655;6283.96858015707;6294.968525157391;6300.086487459339;6205.6897155142515;6279.717412716439;6286.151369565096;6295.401936815995;6315.5894866833505;5022.221555658879;6011.428914253139;6058.787942421994;6059.818205453844;6061.696915154269;6218.782342617987;6264.310925798159;6265.498760099181;6384;6432.839179020562;6223.37766223374;6261.311255761862;6294.779682711083;6296.5592408531875;6315.5263355247625;6259.248890133122;6284.591501552384;6314.747410103636;6319.431251187323;6325.399087086719;6114.449440885012;6201.720922558496;6219.098230756515;6270.122182894398;6300.936990630126;6177.536684748662;6197.1324014637985;6280.937190628127;6294.464970477469;6303.558750887486;5020.974895125574;5071.163258062452;5114.462981386975;5121.436641969381;5123.666961647556;3551.058969373118;3898.6491215790716;4250.638695710837;4467.977660111744;4514;5246;5321.255024296602;5903.852403689856;5998.910016349758;6065.636061836304;6257.342978987232;6344.460720838688;6355.872882542323;6377.362263773585;6391.680415979183;4726.1492931272705;4881.243407271879;5362;5937.139114828412;5957.702114894238;4667.509911459316;5522.530584900248;5562.165675148679;5564.415736347706;5579.804706835323;6279.968600157153;6481.189851268591;6483.286838447767;6498;6517.739290428424;5584;6135.814547850547;6238.251409830849;6241.6879156041105;6295.653739044396;5248.921266180934;6487.3836985486305;6513.2835388108215;6517.9348206517325;6525.1517302750335;6138.048602466625;6154.692265386802;6278.937210627835;6292.433680968735;6314.526410519321;6155.815325540241;6203.503719702323;6234.376562343729;6249.312575616769;6254.655993920378;6098.573099882966;6152.815415537541;6153.784617538365;6203.689815509297;6207.255129384503;6070.605410648293;6118.558266176107;6137.7753809893175;6208.81373558794;6222.377762223741;5280.894382112413;5941.554383421347;5996.220491336273;6147.401675564296;6154.3845615438095;6187.412195841388;6220.875582488235;6252.9062064069985;6253.5622506424115;6279.183706118175;4986.775595098302;5981.880362392815;6085.361037091042;6188.381161883775;6196.318404975359 03:41:29 INFO - PROCESS | 1654 | |1;ext.html;467.45541444217486;524.3236225269403;533.2774091106551;540;581.8196359128668;31370.372592548163;40831.59168408315;40866;40867;40944.38583421251;41375.172496550054;41811.16377672445;42040.78979605098;42118.94702632436;42201;8983;9625.374350667184;9644.855327170115;9808.362456440309;9830.115289623933;9736.659216927408;9771.169450596708;9774.706758797247;9787.314887957846;9814.901850981505;9745.853812192823;9770.706878793648;9800.607975680965;9823.754406139833;9838.606455741761;8181.345492360597;8341.666333346659;9802.90197098027;9809.18530071939;9816.361936474143;9642.807143857153;9688.612455501772;9707;9744;9811.754706132404;39694;39985.60014399877;40046.99882502961;40182;40541.78374648765;3183.6338821035647;3409.322054920234;3453.930921381558;3480.460528618068;3589.10272431892;29778.173550964297;30039.699603003908;30702.232444188852;30709;31736.206594835083;3068.5704001439817;3115;3124.98437507811;3161.3045130071346;3253.0403530958424;406502;409961;460812.9999999966;466072;468948;33322.166945826546;33598.160045999044;33659.326813463595;33710.32579348448;33722.15694607605;34679.479807802905;34894.12764680904;34894.65105348965;35829.46255806165;35983.64016359803;16901.661966760596;16915.492535223962;16936.57658558521;16954.745678814827;17074.48776536706;12920;12989.090763646644;12994.480220791062;13000.349982500837;13007.089503734647;15249.695006100037;16504.75242871358;19369.12838922252;19438.514037149183;19438.902805486166;7032.909898965669;8345.833083338299;8409;8507.787305317293;8522.914770852336;842.3682238321259;857.2541888556996;954.7565370830393;1027.7995790820867;1083.7344850511674;30463.847680761453;30720.231994199876;30795.384092318476;30865.8456707715;32191.35617287641;998.6854140945579;1064.3720205079042;1120.462178154474;1157.1668398752884;1264.108803293694;15380.154091525073;15859.04845709238;15908;15973.642240409463;16450.177491125396;581870;590087;748870;754034;761967;43224.567754322685;58407.70796145992;59127.11309330363;59141.704291478265;59244.11133832996;1376.8210132682686;1399.8810101141516;1418.489343836218;1424.9928750356182;1431.270052273348;30274.24314392158;30489.54265685972;30611;30757.38485230238;30890.91881784217;50569.24146137884;50737.492625073275;51096.744516277184;51137.744311278206;51283.23075153801;621467;626061;626465;627811;628346;26904.32739181536;26978.32554186161;27145.321366965192;27275.31811704723;27368.315792105357;47766.283505748135;48482;49186.262206066225;49298.75350623224;49524.75237623789;39473.60526394699;40227.79886100551;40739.79630101831;41307.586924131574;41321;31401.52897706582;35826.64173358233;37536.249275014896;39733.60266397299;39862;38113;38492.6150738489;38732.8380148596;39280;39555.2088958225;34738.131546711535;35092.29815403627;35125.29749405049;35552.64447355493;35669.28661426809;24853.751462485867;25782.355441114123;26118;26157;26784.464310714066 03:41:29 INFO - PROCESS | 1654 | |2;jquery.html;380.69544364508386;382.8353807862619;389.6006593241927;400.4873761585172;411.2104758862983;16148.354065837382;16183.352665893379;19954.90022549887;21351.679724804122;22220.000099995497;21097.894510527465;21284;21805.454863628416;21915.890420547876;21979.450513737163;5816.941830581693;5914.083317085859;5989.131575921489;6017.157597936292;6017.247844019497;5837.328707198684;5880;5888.234529511157;5983.940160598404;6024.638521688691;5553.944460555404;5832.416758324155;5852.092925596539;5881.235439392873;5883.88232235355;5563.471470210324;5905.173275741378;5937.970310148443;6121.90817137741;6473.902891456632;5755.913661295084;5832.795852145155;5891.7054147292465;5909.793157239519;5914.112883067574;23229.53540929172;25079.874600627063;25271.49457010868;26553.867230663916;26793.73206267932;1940.4663717477665;2225.18780645065;2427.6115821468657;2455.5702752018324;2493.252024392675;10273.229507786871;10318.380897146195;10556;12499.062570307264;14867.851321486864;1848.5286252005787;1875.634251320993;1916.492129585661;1930.6814375628005;1985.1091909659133;113719;119972;121174.3941280288;126778;130070;11655.941720291345;11676;11806.232594881305;11864.940675296568;11971.324014637958;14301.92622069794;14742;14796.408143674133;14801.703965920622;14824.777628335583;7229.674664639999;8731.563421828883;8793.516356600448;8804.207621314112;8912.821743565093;7225.494215404871;8729.083446238054;8748.200575815805;8802.86795698065;8868.556572171366;4530.818767249339;4569.474510431276;4591.517890621437;4620.491745908014;4637.582617564368;1757.3585641241111;1939.7090436434366;1968.8523360748002;2013.5670830771455;2028.2191356327626;826.9539033123104;894.3337213775778;930.972070837876;952.7713348796239;1007.3603261928728;17425.825741742672;20227.98860056991;21227.257046003317;21824.563508729738;21934.903254837194;977.8826540815147;977.9706608801747;1007.8639383683109;1009.5255230041869;1028.0901402259055;10751.6236931707;11113.83329250062;11575.88424115765;11602.88397116018;11645.825312620318;554632;563103;567034;584043;587333;141973;144199.27900360432;145615;148380;149244;1006;1010.1464262698008;1012.1700205831148;1030.845373194027;1031.2471895516314;18125;18755.718664219774;19436.02819858973;19453.31913383025;19702.40892773219;53343;53997.73001134969;54542;55378;55436;79387.80918286346;81248.5937570332;82299.17700822915;84020.31227374711;93110;7269.563826170447;7597.506162099446;7632.084149902047;7907.604619768874;8546.829063418822;9546.18857397129;10302.22733295021;11658.94170529142;13613.931930340284;14022.948278878921;11803.80973200717;11987.940060299643;11995.340256285639;12020.398980050788;12039.217450865666;10306.948465257627;11508.82736758966;11962.026265666416;11997.820032699341;12033.4584943676;11919.702007449883;12040.75918481643;12052.638420847388;12069.456874440846;12080.577179798665;8352.248297653241;8397.412181147261;8481.194286542768;8575.614097365758;8617.224449799549;8542.74371768848;11616.244944078946;11818.645440636794;11911.40442977865;12058.758824823279 03:41:29 INFO - PROCESS | 1654 | |3;mootools.html;1560.3212602517901;1628.5765700917761;1787.3029518487792;1852.8517718582514;1947.8441724662027;8020.398470114736;8611.009733880606;8648;9062.410943288682;9087.863682044766;8696.652133914637;8737.344699147558;8738.606762695677;8762.342824288173;8861;2381.8451800632947;2417.093589903786;2417.9758202418016;2419.4919066995953;2425.3572803207076;2302.873341966191;2329.7087864017;2330.1378489958656;2331.5686597979343;2390.1992832401165;2387.928362149138;2391.9043238270533;2392.820538459614;2416.359664688859;2427.5751743444916;2350.753170917046;2384.582698027847;2400.699912510936;2408.2895545814026;2413.4449076712367;2386.5107652931106;2395.700537432821;2400.8919598618104;2407.4823912858647;2411.565918134735;8946.86579701305;8989.910100898973;8999.32505062123;9018.684346047858;9036.954815225947;5291.841244762625;5378.300820893259;5402.243685883979;5452.345718513764;5471.343438787331;3127;3585.3008663310666;3675.724320675933;3687.2072504411412;3725.6833169180654;1086.750047489082;1094.3488624268548;1097.928634638746;1116.3413585984308;1117;11620.07039436843;11786.528538858436;11827.231229970024;11835.526578936919;11859.288442693465;4645.512221216791;4653.395058642423;4872.43966943806;4874.780634871439;4875.878103047381;10087.596496140071;10089.596416143271;10095.646652367132;10099.495025248709;10107.69676909694;5343.572514198932;5393.9730301348245;5426.8914621707345;5438.45615438453;5438.945610543923;4495.483019452739;4815.133276010281;4865.5864251538305;4881.780319885614;4974.726390048582;3709.573399059089;3976.343903255988;4011.478507793968;4092.6112019358116;4117.5882411759185;1302.5245785288491;1313.5533918467768;1314.6065170918666;1318.1827267094463;1320.808482769993;868.1665601022962;886.7650072730731;894.5393122541897;903.5662881816764;906.3202598051462;5590.24531688225;5607.52336051432;6003.729832157476;6050.667213303311;6294.276158241769;943.5235206220815;993.2500961773815;994.1450352696675;996.7009897030862;1024.797507788162;4073.450084238649;4123.8969025774595;4140.751554906716;4174.728642638219;4195.853145139906;10448.843267350996;10782;10802.081823044971;10930.125589952784;10954;10137.847932281022;10668.933106689268;10742.838857417146;10743.301685390425;10900.727481813017;7576.166621671612;7936.2857342839425;8124.350051995826;8257.174282571696;8299.875501867476;5247.947520524746;5540.279763630702;6133.294671112879;6145.446909778141;6282.591631543935;10090.192784577363;10093.14208292274;10105.545250463894;10165.593376264867;10244.436555989492;7716.459847810599;9995.450250236307;10006.399616023062;10060.094591486508;10131.341462804894;6943.826404339932;7314.96342518284;7502.924970750441;7597.6201189939175;7618.276263754934;8372.958135209528;8401.285890699359;8447.113053129333;8470.280026197595;8522.062573116827;7513.511621744569;7682.347000505019;7724.536527808349;7777.844443110993;7788.805279868048;7429.405647548291;7501.962490187514;7507.099148102263;7556.433267505069;7608.923910760822;7080.185778635523;7125.501214914906;7149.571025738473;7151.070360853056;7182.174049984319;4999.600031997359;5073.847784566469;5087;5134.640575159703;5178.61160412963;5156.4327923927585;5200.375954885438;5268.341457317835;5270.262163297065;5312.867178320573 03:41:29 INFO - PROCESS | 1654 | |4;prototype.html;286.44715698701503;287.45240317195726;310.08061098841927;321.2482790270766;322.6902173913043;4632.930506042404;5475.835724928249;5680.403557626451;5777.971110144449;5821.825345239649;5501.394846566875;5635.042042852715;5654.321481422225;5663.377028526858;5671.517920976722;4326.459192600925;4334.978325108378;4349.7390156590545;4361.825526978917;4368.148211098837;3126.4997600383945;4085.5097388313443;4347.478302603676;4356.651467882578;4366.694331396803;4242.38485419614;4254.553271906451;4258.254805409041;4282.421873047146;4293.313069908815;4311.482622085354;4326.5673432656795;4332.566743325674;4339.218940590692;4344.567042456203;3170.904872853795;3600.513930619359;3707.5736290326686;3724.2737666155112;3776.546814382264;4608.377868987711;5303.390110137345;5474.644148130359;5499.092649712793;5527.447255274481;4363.912721745579;4917.311576379309;4945.901081978341;4950.232713929347;5005.874853128665;5675.858103547403;5966.761329546813;5992.790252341147;6001.639901605918;6012;4115.333290017517;4116.279651061052;4125.443065186191;4134.834606615762;4155.2728272552185;6022.6687532185715;6356.523260755462;6400.871982560369;6410.653762709837;6413.781381537493;3864.3237433449035;4136.44158038667;4146.564610715892;4162.729422587522;4203.9910421499235;4172;4181.351890456984;4184.790760461965;4196.559361267084;4210.136921931058;4937.901241975141;4951.678140920829;4957.479464656231;4967.90064198714;4968.527989840959;5756.942430575725;6134.325224225332;6136.539759518054;6144.078388241799;6194.287656919422;1366.371469124214;1413.2721648351107;1413.8656827601362;1423.437742091868;1428.4786053090754;781.5271760584765;798.7244400681756;847.8050048488882;863.3913091270639;877.9736607901773;4827.010462855106;4831;4831.565159135695;4840.515948405202;4875.609951203895;4525.683202175882;5119.539241468286;5128.692278463304;5233.964300705582;5392.649477783932;4171.97914010434;4713.929291060636;4893.681910675795;4939.777710003059;5214.270002199695;2663.5338815707173;2829.0762281648676;3217.3404452087734;3281.770276080699;3329.9667003329655;5215.608829337815;5561.5272701820795;6337.60572674011;6528.314526974695;6573.3098024706715;5194.92207616886;5772.076467765138;5947.762089516457;6418.8716225675225;6534.313897040837;5132.666376685504;6582.703778329987;6606.9008964866525;6614.9669251654395;6705.597664140168;5706.061378390215;5901.7639294427745;6014.729337179925;6353.331600727798;6394.200724909387;6230.221222347207;6258.405451482102;6259.874802503833;6267.341929097379;6274.090256912813;5374.435684253253;6529.640869752209;6545.0182472629485;6608.306127856506;6614.809334319869;3579.480975258625;4074.6129117733767;4110.321796903478;4117.032497363177;4122.2786012448305;5573.888522229614;6013.669248191217;6241.407066328691;6306.337834527309;6336.714847831767;5908.961408314125;6028.367021462858;6100.328963814066;6127.601705889103;6151.723172457339;6123.571350005457;6139.434444216696;6146.692665366803;6162.476189523941;6165.506759459322;5546.916796248141;5794.4205579443405;6002.609830361012;6022.337542870368;6041.758329666764;6109.450149486567;6131.785387511416;6135.938640613536;6144.692765361625;6195.349488303844;5444.074507333842;6009.519238460825;6010.579259451796;6039.943009973325;6045.128563932097 03:41:29 INFO - PROCESS | 1654 | |5;yui.html;760.4334770595907;766.4136935244538;815.5718247919845;875.3259989807844;875.5797217335679;15285.770713439293;15887.84112158878;16111.113888736114;17433.302667893302;18064.096795160254;16751.497455076336;16896.746548801777;16897.577560560992;16904.239309231085;16954.40659576915;664.773976847874;786.5005721366945;895.3061377432476;923.1414784250644;935.5789894547447;916.2395211974045;918.5407296351824;922.3589605224372;922.6447817590209;924.1082355526916;960.7309953213112;966.9516524173798;967.1682353176262;968.1045033344128;972.6644307713863;956.7942892278159;957.6360982826482;957.9137877591015;957.9377340472881;958.5494817435793;716.9856602867914;717.6268340462982;718.0199028326349;719.4999475364624;719.7588807749411;15394.846051539453;15644.921775391163;15698.607534811606;15699.843001570067;15734.44929427476;14533.27333633314;14589.416423343053;14596;14732.042417242845;14763.26183690822;11987.58043468483;12028.819567706492;12057.638270851889;12275;12366.189177465496;3893.98053009736;3932.1742434088874;3952.9011774705573;4066.3900414937702;4066.552679205285;120314;121018;121630.52662630203;122224.38887805594;122554;11285.43572821372;11302.773944521063;11600.955913967784;11610;11661.475233614508;22593.774062259166;22801;23110.960006799734;23183.420414489774;23230.07079716826;8189.385796065319;8270;8350.08149103598;8521.57392130391;8566.400351975302;4513;4558;4572.95427045732;4626.83806066786;4741.596964258077;3554.360215161244;3670.1742108026015;3673.063368840982;3681.4661874028116;3684.8157592120288;682.556338380047;690.92399836018;691.9308069193141;700.0199720391493;708.8192510909685;655.6557807151283;656.2190992718745;681.4786688183543;683.4498228925663;696.2341424433083;7751.698579369878;8413.495190288602;9328.533573321307;9646.228301735844;9858.704238872846;546.9042917489503;619.5786864931799;641.4291280760127;643.234550884446;703.5040296590892;4386.337408264071;4541.591256786839;4606.677532572688;4608.493065762764;4913.852584422473;157435;165949;167021.16489417476;167677.16161419358;168438;109963;125092.1236181458;132670;132739;138614.92077618843;116453;116970.41514792542;117993;118905;124998;9194;9247.213986810928;9490.003549627467;9680.322377433793;9704.6118155273;49986.750066249435;55638;60635;61799.69100154471;64831;69436.80700666858;85372;103618;104000.47999759953;105814;12074.698132546757;13656.863431365957;13794.93102534481;13895.51365702236;14348.210848403438;13811.930940345233;14106.365213565208;14460.783088253895;15837.208139592743;16091.436799711953;14357.21035343066;14424.927875360556;14512.564623061326;14570.708585828012;15108.244587770349;14266.714665706835;14334.784978225127;14425.134491930517;14802.777958330418;14908.740827941623;12524.998000159783;13520;13776.443454748116;13820;14673.779893301822;11432.657020289405;11455.205174151059;11618;11920.821187682364;11945.223560468541;10777.245592808427;11054.668359949215;11487.930036139967;11965.162438629213;11978.341191234565 03:41:29 INFO - PROCESS | 1654 | __end_tp_report 03:41:29 INFO - PROCESS | 1654 | __start_cc_report 03:41:29 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,443 03:41:29 INFO - PROCESS | 1654 | __end_cc_report 03:41:29 INFO - PROCESS | 1654 | __startTimestamp1468752089447__endTimestamp 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | ------- Summary: start ------- 03:41:29 INFO - PROCESS | 1654 | Number of tests: 6 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5745.46 Median:6070.61 stddev:823.75 (13.6%) stddev-sans-first:805.84 03:41:29 INFO - PROCESS | 1654 | Values: 3421.4 3427.0 3427.6 3430.3 3476.8 4535.7 5836.6 5860.5 5875.2 5905.4 5658.8 5829.4 5836.4 5864.0 5908.9 4985.6 5206.0 5234.5 5341.2 5407.4 2.8 5349.2 5359.3 5367.8 5429.6 5299.9 5330.6 5330.8 5334.5 5360.6 5244.6 5339.6 5391.7 5398.4 5410.1 5242.0 5255.3 5293.3 5296.9 5358.4 5732.5 5843.5 5873.9 5908.9 5940.2 6110.9 6222.0 6284.0 6295.0 6300.1 6205.7 6279.7 6286.2 6295.4 6315.6 5022.2 6011.4 6058.8 6059.8 6061.7 6218.8 6264.3 6265.5 6384.0 6432.8 6223.4 6261.3 6294.8 6296.6 6315.5 6259.2 6284.6 6314.7 6319.4 6325.4 6114.4 6201.7 6219.1 6270.1 6300.9 6177.5 6197.1 6280.9 6294.5 6303.6 5021.0 5071.2 5114.5 5121.4 5123.7 3551.1 3898.6 4250.6 4468.0 4514.0 5246.0 5321.3 5903.9 5998.9 6065.6 6257.3 6344.5 6355.9 6377.4 6391.7 4726.1 4881.2 5362.0 5937.1 5957.7 4667.5 5522.5 5562.2 5564.4 5579.8 6280.0 6481.2 6483.3 6498.0 6517.7 5584.0 6135.8 6238.3 6241.7 6295.7 5248.9 6487.4 6513.3 6517.9 6525.2 6138.0 6154.7 6278.9 6292.4 6314.5 6155.8 6203.5 6234.4 6249.3 6254.7 6098.6 6152.8 6153.8 6203.7 6207.3 6070.6 6118.6 6137.8 6208.8 6222.4 5280.9 5941.6 5996.2 6147.4 6154.4 6187.4 6220.9 6252.9 6253.6 6279.2 4986.8 5981.9 6085.4 6188.4 6196.3 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:70245.22 Median:39624.60 stddev:160063.38 (403.9%) stddev-sans-first:160426.66 03:41:29 INFO - PROCESS | 1654 | Values: 467.5 524.3 533.3 540.0 581.8 31370.4 40831.6 40866.0 40867.0 40944.4 41375.2 41811.2 42040.8 42118.9 42201.0 8983.0 9625.4 9644.9 9808.4 9830.1 9736.7 9771.2 9774.7 9787.3 9814.9 9745.9 9770.7 9800.6 9823.8 9838.6 8181.3 8341.7 9802.9 9809.2 9816.4 9642.8 9688.6 9707.0 9744.0 9811.8 39694.0 39985.6 40047.0 40182.0 40541.8 3183.6 3409.3 3453.9 3480.5 3589.1 29778.2 30039.7 30702.2 30709.0 31736.2 3068.6 3115.0 3125.0 3161.3 3253.0 406502.0 409961.0 460813.0 466072.0 468948.0 33322.2 33598.2 33659.3 33710.3 33722.2 34679.5 34894.1 34894.7 35829.5 35983.6 16901.7 16915.5 16936.6 16954.7 17074.5 12920.0 12989.1 12994.5 13000.3 13007.1 15249.7 16504.8 19369.1 19438.5 19438.9 7032.9 8345.8 8409.0 8507.8 8522.9 842.4 857.3 954.8 1027.8 1083.7 30463.8 30720.2 30795.4 30865.8 32191.4 998.7 1064.4 1120.5 1157.2 1264.1 15380.2 15859.0 15908.0 15973.6 16450.2 581870.0 590087.0 748870.0 754034.0 761967.0 43224.6 58407.7 59127.1 59141.7 59244.1 1376.8 1399.9 1418.5 1425.0 1431.3 30274.2 30489.5 30611.0 30757.4 30890.9 50569.2 50737.5 51096.7 51137.7 51283.2 621467.0 626061.0 626465.0 627811.0 628346.0 26904.3 26978.3 27145.3 27275.3 27368.3 47766.3 48482.0 49186.3 49298.8 49524.8 39473.6 40227.8 40739.8 41307.6 41321.0 31401.5 35826.6 37536.2 39733.6 39862.0 38113.0 38492.6 38732.8 39280.0 39555.2 34738.1 35092.3 35125.3 35552.6 35669.3 24853.8 25782.4 26118.0 26157.0 26784.5 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:35243.88 Median:21957.18 stddev:96324.82 (438.7%) stddev-sans-first:96559.47 03:41:29 INFO - PROCESS | 1654 | Values: 380.7 382.8 389.6 400.5 411.2 16148.4 16183.4 19954.9 21351.7 22220.0 21097.9 21284.0 21805.5 21915.9 21979.5 5816.9 5914.1 5989.1 6017.2 6017.2 5837.3 5880.0 5888.2 5983.9 6024.6 5553.9 5832.4 5852.1 5881.2 5883.9 5563.5 5905.2 5938.0 6121.9 6473.9 5755.9 5832.8 5891.7 5909.8 5914.1 23229.5 25079.9 25271.5 26553.9 26793.7 1940.5 2225.2 2427.6 2455.6 2493.3 10273.2 10318.4 10556.0 12499.1 14867.9 1848.5 1875.6 1916.5 1930.7 1985.1 113719.0 119972.0 121174.4 126778.0 130070.0 11655.9 11676.0 11806.2 11864.9 11971.3 14301.9 14742.0 14796.4 14801.7 14824.8 7229.7 8731.6 8793.5 8804.2 8912.8 7225.5 8729.1 8748.2 8802.9 8868.6 4530.8 4569.5 4591.5 4620.5 4637.6 1757.4 1939.7 1968.9 2013.6 2028.2 827.0 894.3 931.0 952.8 1007.4 17425.8 20228.0 21227.3 21824.6 21934.9 977.9 978.0 1007.9 1009.5 1028.1 10751.6 11113.8 11575.9 11602.9 11645.8 554632.0 563103.0 567034.0 584043.0 587333.0 141973.0 144199.3 145615.0 148380.0 149244.0 1006.0 1010.1 1012.2 1030.8 1031.2 18125.0 18755.7 19436.0 19453.3 19702.4 53343.0 53997.7 54542.0 55378.0 55436.0 79387.8 81248.6 82299.2 84020.3 93110.0 7269.6 7597.5 7632.1 7907.6 8546.8 9546.2 10302.2 11658.9 13613.9 14022.9 11803.8 11987.9 11995.3 12020.4 12039.2 10306.9 11508.8 11962.0 11997.8 12033.5 11919.7 12040.8 12052.6 12069.5 12080.6 8352.2 8397.4 8481.2 8575.6 8617.2 8542.7 11616.2 11818.6 11911.4 12058.8 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5774.95 Median:4875.33 stddev:3196.15 (65.6%) stddev-sans-first:3189.42 03:41:29 INFO - PROCESS | 1654 | Values: 1560.3 1628.6 1787.3 1852.9 1947.8 8020.4 8611.0 8648.0 9062.4 9087.9 8696.7 8737.3 8738.6 8762.3 8861.0 2381.8 2417.1 2418.0 2419.5 2425.4 2302.9 2329.7 2330.1 2331.6 2390.2 2387.9 2391.9 2392.8 2416.4 2427.6 2350.8 2384.6 2400.7 2408.3 2413.4 2386.5 2395.7 2400.9 2407.5 2411.6 8946.9 8989.9 8999.3 9018.7 9037.0 5291.8 5378.3 5402.2 5452.3 5471.3 3127.0 3585.3 3675.7 3687.2 3725.7 1086.8 1094.3 1097.9 1116.3 1117.0 11620.1 11786.5 11827.2 11835.5 11859.3 4645.5 4653.4 4872.4 4874.8 4875.9 10087.6 10089.6 10095.6 10099.5 10107.7 5343.6 5394.0 5426.9 5438.5 5438.9 4495.5 4815.1 4865.6 4881.8 4974.7 3709.6 3976.3 4011.5 4092.6 4117.6 1302.5 1313.6 1314.6 1318.2 1320.8 868.2 886.8 894.5 903.6 906.3 5590.2 5607.5 6003.7 6050.7 6294.3 943.5 993.3 994.1 996.7 1024.8 4073.5 4123.9 4140.8 4174.7 4195.9 10448.8 10782.0 10802.1 10930.1 10954.0 10137.8 10668.9 10742.8 10743.3 10900.7 7576.2 7936.3 8124.4 8257.2 8299.9 5247.9 5540.3 6133.3 6145.4 6282.6 10090.2 10093.1 10105.5 10165.6 10244.4 7716.5 9995.5 10006.4 10060.1 10131.3 6943.8 7315.0 7502.9 7597.6 7618.3 8373.0 8401.3 8447.1 8470.3 8522.1 7513.5 7682.3 7724.5 7777.8 7788.8 7429.4 7502.0 7507.1 7556.4 7608.9 7080.2 7125.5 7149.6 7151.1 7182.2 4999.6 5073.8 5087.0 5134.6 5178.6 5156.4 5200.4 5268.3 5270.3 5312.9 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4863.77 Median:5383.54 stddev:1538.44 (28.6%) stddev-sans-first:1503.91 03:41:29 INFO - PROCESS | 1654 | Values: 286.4 287.5 310.1 321.2 322.7 4632.9 5475.8 5680.4 5778.0 5821.8 5501.4 5635.0 5654.3 5663.4 5671.5 4326.5 4335.0 4349.7 4361.8 4368.1 3126.5 4085.5 4347.5 4356.7 4366.7 4242.4 4254.6 4258.3 4282.4 4293.3 4311.5 4326.6 4332.6 4339.2 4344.6 3170.9 3600.5 3707.6 3724.3 3776.5 4608.4 5303.4 5474.6 5499.1 5527.4 4363.9 4917.3 4945.9 4950.2 5005.9 5675.9 5966.8 5992.8 6001.6 6012.0 4115.3 4116.3 4125.4 4134.8 4155.3 6022.7 6356.5 6400.9 6410.7 6413.8 3864.3 4136.4 4146.6 4162.7 4204.0 4172.0 4181.4 4184.8 4196.6 4210.1 4937.9 4951.7 4957.5 4967.9 4968.5 5756.9 6134.3 6136.5 6144.1 6194.3 1366.4 1413.3 1413.9 1423.4 1428.5 781.5 798.7 847.8 863.4 878.0 4827.0 4831.0 4831.6 4840.5 4875.6 4525.7 5119.5 5128.7 5234.0 5392.6 4172.0 4713.9 4893.7 4939.8 5214.3 2663.5 2829.1 3217.3 3281.8 3330.0 5215.6 5561.5 6337.6 6528.3 6573.3 5194.9 5772.1 5947.8 6418.9 6534.3 5132.7 6582.7 6606.9 6615.0 6705.6 5706.1 5901.8 6014.7 6353.3 6394.2 6230.2 6258.4 6259.9 6267.3 6274.1 5374.4 6529.6 6545.0 6608.3 6614.8 3579.5 4074.6 4110.3 4117.0 4122.3 5573.9 6013.7 6241.4 6306.3 6336.7 5909.0 6028.4 6100.3 6127.6 6151.7 6123.6 6139.4 6146.7 6162.5 6165.5 5546.9 5794.4 6002.6 6022.3 6041.8 6109.5 6131.8 6135.9 6144.7 6195.3 5444.1 6009.5 6010.6 6039.9 6045.1 03:41:29 INFO - PROCESS | 1654 | 03:41:29 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:26154.32 Median:23147.19 stddev:42272.51 (182.6%) stddev-sans-first:42348.10 03:41:29 INFO - PROCESS | 1654 | Values: 760.4 766.4 815.6 875.3 875.6 15285.8 15887.8 16111.1 17433.3 18064.1 16751.5 16896.7 16897.6 16904.2 16954.4 664.8 786.5 895.3 923.1 935.6 916.2 918.5 922.4 922.6 924.1 960.7 967.0 967.2 968.1 972.7 956.8 957.6 957.9 957.9 958.5 717.0 717.6 718.0 719.5 719.8 15394.8 15644.9 15698.6 15699.8 15734.4 14533.3 14589.4 14596.0 14732.0 14763.3 11987.6 12028.8 12057.6 12275.0 12366.2 3894.0 3932.2 3952.9 4066.4 4066.6 120314.0 121018.0 121630.5 122224.4 122554.0 11285.4 11302.8 11601.0 11610.0 11661.5 22593.8 22801.0 23111.0 23183.4 23230.1 8189.4 8270.0 8350.1 8521.6 8566.4 4513.0 4558.0 4573.0 4626.8 4741.6 3554.4 3670.2 3673.1 3681.5 3684.8 682.6 690.9 691.9 700.0 708.8 655.7 656.2 681.5 683.4 696.2 7751.7 8413.5 9328.5 9646.2 9858.7 546.9 619.6 641.4 643.2 703.5 4386.3 4541.6 4606.7 4608.5 4913.9 157435.0 165949.0 167021.2 167677.2 168438.0 109963.0 125092.1 132670.0 132739.0 138614.9 116453.0 116970.4 117993.0 118905.0 124998.0 9194.0 9247.2 9490.0 9680.3 9704.6 49986.8 55638.0 60635.0 61799.7 64831.0 69436.8 85372.0 103618.0 104000.5 105814.0 12074.7 13656.9 13794.9 13895.5 14348.2 13811.9 14106.4 14460.8 15837.2 16091.4 14357.2 14424.9 14512.6 14570.7 15108.2 14266.7 14334.8 14425.1 14802.8 14908.7 12525.0 13520.0 13776.4 13820.0 14673.8 11432.7 11455.2 11618.0 11920.8 11945.2 10777.2 11054.7 11487.9 11965.2 11978.3 03:41:29 INFO - PROCESS | 1654 | -------- Summary: end -------- 03:41:29 INFO - PROCESS | 1654 | 03:41:30 INFO - TEST-INFO | 1654: exit 0 03:41:30 INFO - TEST-OK | dromaeo_css | took 1111908ms 03:41:30 INFO - TEST-START | kraken 03:41:30 INFO - operating with platform_type : mac_ 03:41:30 INFO - Initialising browser for kraken test... 03:41:30 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpksRHjo/profile http://localhost:49276/getInfo.html) 03:41:32 INFO - PROCESS | 1686 | JavaScript error: , line 0: uncaught exception: undefined 03:41:32 INFO - PROCESS | 1686 | __metrics Screen width/height:1600/1200 03:41:32 INFO - PROCESS | 1686 | colorDepth:24 03:41:32 INFO - PROCESS | 1686 | Browser inner width/height: 1024/689 03:41:32 INFO - PROCESS | 1686 | __metrics 03:41:32 ERROR - PROCESS | 1686 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 03:41:33 INFO - TEST-INFO | 1686: exit 0 03:41:33 INFO - Browser initialized. 03:41:33 INFO - Running cycle 1/1 for kraken test... 03:41:33 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpksRHjo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:45 INFO - PROCESS | 1688 | RSS: Main: 255791104 03:41:45 INFO - PROCESS | 1688 | 03:41:48 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:49 INFO - PROCESS | 1688 | RSS: Main: 268611584 03:41:49 INFO - PROCESS | 1688 | 03:41:51 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 03:41:52 INFO - PROCESS | 1688 | RSS: Main: 272187392 03:41:52 INFO - PROCESS | 1688 | 03:41:55 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 03:41:56 INFO - PROCESS | 1688 | RSS: Main: 272867328 03:41:56 INFO - PROCESS | 1688 | 03:41:58 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 03:41:58 INFO - PROCESS | 1688 | RSS: Main: 273895424 03:41:58 INFO - PROCESS | 1688 | 03:42:00 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:42:01 INFO - PROCESS | 1688 | RSS: Main: 274739200 03:42:01 INFO - PROCESS | 1688 | 03:42:04 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 03:42:05 INFO - PROCESS | 1688 | RSS: Main: 274784256 03:42:05 INFO - PROCESS | 1688 | 03:42:08 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 03:42:08 INFO - PROCESS | 1688 | RSS: Main: 275742720 03:42:08 INFO - PROCESS | 1688 | 03:42:12 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 03:42:12 INFO - PROCESS | 1688 | RSS: Main: 275984384 03:42:12 INFO - PROCESS | 1688 | 03:42:14 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:42:14 INFO - PROCESS | 1688 | RSS: Main: 275599360 03:42:14 INFO - PROCESS | 1688 | 03:42:16 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:17 INFO - PROCESS | 1688 | RSS: Main: 275001344 03:42:17 INFO - PROCESS | 1688 | 03:42:19 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:20 INFO - PROCESS | 1688 | RSS: Main: 275386368 03:42:20 INFO - PROCESS | 1688 | 03:42:23 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:23 INFO - PROCESS | 1688 | RSS: Main: 275619840 03:42:23 INFO - PROCESS | 1688 | 03:42:26 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:27 INFO - PROCESS | 1688 | RSS: Main: 275886080 03:42:27 INFO - PROCESS | 1688 | 03:42:28 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 03:42:29 INFO - PROCESS | 1688 | RSS: Main: 276594688 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | __start_tp_report 03:42:29 INFO - PROCESS | 1688 | _x_x_mozilla_page_load 03:42:29 INFO - PROCESS | 1688 | _x_x_mozilla_page_load_details 03:42:29 INFO - PROCESS | 1688 | |i|pagename|runs| 03:42:29 INFO - PROCESS | 1688 | |0;ai-astar;104;120;107;119;118;118;116;117;120;118 03:42:29 INFO - PROCESS | 1688 | |1;audio-beat-detection;145;108;137;154;106;127;132;140;161;112 03:42:29 INFO - PROCESS | 1688 | |2;audio-dft;191;200;197;206;198;265;251;198;196;196 03:42:29 INFO - PROCESS | 1688 | |3;audio-fft;71;101;70;113;49;54;58;83;155;61 03:42:29 INFO - PROCESS | 1688 | |4;audio-oscillator;70;73;123;70;86;107;132;60;104;100 03:42:29 INFO - PROCESS | 1688 | |5;imaging-gaussian-blur;116;87;89;99;117;86;91;87;80;132 03:42:29 INFO - PROCESS | 1688 | |6;imaging-darkroom;121;95;119;159;98;99;155;95;146;95 03:42:29 INFO - PROCESS | 1688 | |7;imaging-desaturate;76;152;126;90;99;101;154;141;91;114 03:42:29 INFO - PROCESS | 1688 | |8;json-parse-financial;57;57;55;52;53;55;57;54;55;52 03:42:29 INFO - PROCESS | 1688 | |9;json-stringify-tinderbox;71;73;73;69;81;80;77;73;70;72 03:42:29 INFO - PROCESS | 1688 | |10;stanford-crypto-aes;84;85;85;84;86;107;72;104;108;107 03:42:29 INFO - PROCESS | 1688 | |11;stanford-crypto-ccm;142;134;136;138;142;145;152;148;176;135 03:42:29 INFO - PROCESS | 1688 | |12;stanford-crypto-pbkdf2;154;229;219;172;174;172;170;171;169;170 03:42:29 INFO - PROCESS | 1688 | |13;stanford-crypto-sha256-iterative;67;69;66;68;67;68;67;69;68;69 03:42:29 INFO - PROCESS | 1688 | __end_tp_report 03:42:29 INFO - PROCESS | 1688 | __start_cc_report 03:42:29 INFO - PROCESS | 1688 | _x_x_mozilla_cycle_collect,1118 03:42:29 INFO - PROCESS | 1688 | __end_cc_report 03:42:29 INFO - PROCESS | 1688 | __startTimestamp1468752149218__endTimestamp 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | ------- Summary: start ------- 03:42:29 INFO - PROCESS | 1688 | Number of tests: 14 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#0] ai-astar Cycles:10 Average:115.70 Median:118.00 stddev:5.56 (4.7%) stddev-sans-first:3.97 03:42:29 INFO - PROCESS | 1688 | Values: 104.0 120.0 107.0 119.0 118.0 118.0 116.0 117.0 120.0 118.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:138.50 stddev:19.03 (13.7%) stddev-sans-first:19.61 03:42:29 INFO - PROCESS | 1688 | Values: 145.0 108.0 137.0 154.0 106.0 127.0 132.0 140.0 161.0 112.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#2] audio-dft Cycles:10 Average:209.80 Median:199.00 stddev:25.89 (13.0%) stddev-sans-first:26.55 03:42:29 INFO - PROCESS | 1688 | Values: 191.0 200.0 197.0 206.0 198.0 265.0 251.0 198.0 196.0 196.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#3] audio-fft Cycles:10 Average:81.50 Median:59.50 stddev:33.01 (55.5%) stddev-sans-first:34.79 03:42:29 INFO - PROCESS | 1688 | Values: 71.0 101.0 70.0 113.0 49.0 54.0 58.0 83.0 155.0 61.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:65.00 stddev:24.45 (37.6%) stddev-sans-first:24.54 03:42:29 INFO - PROCESS | 1688 | Values: 70.0 73.0 123.0 70.0 86.0 107.0 132.0 60.0 104.0 100.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:87.00 stddev:17.26 (19.8%) stddev-sans-first:17.09 03:42:29 INFO - PROCESS | 1688 | Values: 116.0 87.0 89.0 99.0 117.0 86.0 91.0 87.0 80.0 132.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#6] imaging-darkroom Cycles:10 Average:118.20 Median:95.00 stddev:26.23 (27.6%) stddev-sans-first:27.80 03:42:29 INFO - PROCESS | 1688 | Values: 121.0 95.0 119.0 159.0 98.0 99.0 155.0 95.0 146.0 95.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#7] imaging-desaturate Cycles:10 Average:114.40 Median:115.00 stddev:27.61 (24.0%) stddev-sans-first:25.54 03:42:29 INFO - PROCESS | 1688 | Values: 76.0 152.0 126.0 90.0 99.0 101.0 154.0 141.0 91.0 114.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.95 (3.5%) stddev-sans-first:1.88 03:42:29 INFO - PROCESS | 1688 | Values: 57.0 57.0 55.0 52.0 53.0 55.0 57.0 54.0 55.0 52.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#9] json-stringify-tinderbox Cycles:10 Average:73.90 Median:73.00 stddev:4.09 (5.6%) stddev-sans-first:4.21 03:42:29 INFO - PROCESS | 1688 | Values: 71.0 73.0 73.0 69.0 81.0 80.0 77.0 73.0 70.0 72.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:84.00 stddev:12.96 (15.4%) stddev-sans-first:13.40 03:42:29 INFO - PROCESS | 1688 | Values: 84.0 85.0 85.0 84.0 86.0 107.0 72.0 104.0 108.0 107.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:143.50 stddev:12.42 (8.7%) stddev-sans-first:13.13 03:42:29 INFO - PROCESS | 1688 | Values: 142.0 134.0 136.0 138.0 142.0 145.0 152.0 148.0 176.0 135.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:172.00 stddev:23.95 (13.9%) stddev-sans-first:23.49 03:42:29 INFO - PROCESS | 1688 | Values: 154.0 229.0 219.0 172.0 174.0 172.0 170.0 171.0 169.0 170.0 03:42:29 INFO - PROCESS | 1688 | 03:42:29 INFO - PROCESS | 1688 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.05 03:42:29 INFO - PROCESS | 1688 | Values: 67.0 69.0 66.0 68.0 67.0 68.0 67.0 69.0 68.0 69.0 03:42:29 INFO - PROCESS | 1688 | -------- Summary: end -------- 03:42:29 INFO - PROCESS | 1688 | 03:42:30 INFO - TEST-INFO | 1688: exit 0 03:42:30 INFO - TEST-OK | kraken | took 60127ms 03:42:30 INFO - SUITE-END | took 1172s 03:42:30 INFO - Completed test suite (00:19:32) 03:42:30 INFO - javascript benchmark 03:42:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7527.031907844392, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3421.3841508528485, 3426.9657303426957, 3427.640097789733, 3430.3310854383394, 3476.7740096893717, 4535.682502224838, 5836.620619659724, 5860.5311575074, 5875.236219291492, 5905.350411454742, 5658.830235092943, 5829.358770535248, 5836.387179346171, 5863.970680146594, 5908.88182236355, 4985.604030871344, 5205.984832957575, 5234.502722241381, 5341.172118321647, 5407.432219616942, 2.8475459493065642, 5349.197620356958, 5359.276497672803, 5367.785288588452, 5429.592780541456, 5299.920501192485, 5330.573554115681, 5330.76011579476, 5334.51989320961, 5360.571154307646, 5244.606654500928, 5339.599530035263, 5391.676499410048, 5398.433164517748, 5410.1073322901675, 5241.9737901310245, 5255.264263003182, 5293.258943747878, 5296.894062118736, 5358.410574836765, 5732.484076433141, 5843.474087332161, 5873.911891321633, 5908.881822363572, 5940.198073260098, 6110.862540392742, 6221.9688901555655, 6283.96858015707, 6294.968525157391, 6300.086487459339, 6205.6897155142515, 6279.717412716439, 6286.151369565096, 6295.401936815995, 6315.5894866833505, 5022.221555658879, 6011.428914253139, 6058.787942421994, 6059.818205453844, 6061.696915154269, 6218.782342617987, 6264.310925798159, 6265.498760099181, 6384.0, 6432.839179020562, 6223.37766223374, 6261.311255761862, 6294.779682711083, 6296.5592408531875, 6315.5263355247625, 6259.248890133122, 6284.591501552384, 6314.747410103636, 6319.431251187323, 6325.399087086719, 6114.449440885012, 6201.720922558496, 6219.098230756515, 6270.122182894398, 6300.936990630126, 6177.536684748662, 6197.1324014637985, 6280.937190628127, 6294.464970477469, 6303.558750887486, 5020.974895125574, 5071.163258062452, 5114.462981386975, 5121.436641969381, 5123.666961647556, 3551.058969373118, 3898.6491215790716, 4250.638695710837, 4467.977660111744, 4514.0, 5246.0, 5321.255024296602, 5903.852403689856, 5998.910016349758, 6065.636061836304, 6257.342978987232, 6344.460720838688, 6355.872882542323, 6377.362263773585, 6391.680415979183, 4726.1492931272705, 4881.243407271879, 5362.0, 5937.139114828412, 5957.702114894238, 4667.509911459316, 5522.530584900248, 5562.165675148679, 5564.415736347706, 5579.804706835323, 6279.968600157153, 6481.189851268591, 6483.286838447767, 6498.0, 6517.739290428424, 5584.0, 6135.814547850547, 6238.251409830849, 6241.6879156041105, 6295.653739044396, 5248.921266180934, 6487.3836985486305, 6513.2835388108215, 6517.9348206517325, 6525.1517302750335, 6138.048602466625, 6154.692265386802, 6278.937210627835, 6292.433680968735, 6314.526410519321, 6155.815325540241, 6203.503719702323, 6234.376562343729, 6249.312575616769, 6254.655993920378, 6098.573099882966, 6152.815415537541, 6153.784617538365, 6203.689815509297, 6207.255129384503, 6070.605410648293, 6118.558266176107, 6137.7753809893175, 6208.81373558794, 6222.377762223741, 5280.894382112413, 5941.554383421347, 5996.220491336273, 6147.401675564296, 6154.3845615438095, 6187.412195841388, 6220.875582488235, 6252.9062064069985, 6253.5622506424115, 6279.183706118175, 4986.775595098302, 5981.880362392815, 6085.361037091042, 6188.381161883775, 6196.318404975359], "value": 5696.168167432678, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.45541444217486, 524.3236225269403, 533.2774091106551, 540.0, 581.8196359128668, 31370.372592548163, 40831.59168408315, 40866.0, 40867.0, 40944.38583421251, 41375.172496550054, 41811.16377672445, 42040.78979605098, 42118.94702632436, 42201.0, 8983.0, 9625.374350667184, 9644.855327170115, 9808.362456440309, 9830.115289623933, 9736.659216927408, 9771.169450596708, 9774.706758797247, 9787.314887957846, 9814.901850981505, 9745.853812192823, 9770.706878793648, 9800.607975680965, 9823.754406139833, 9838.606455741761, 8181.345492360597, 8341.666333346659, 9802.90197098027, 9809.18530071939, 9816.361936474143, 9642.807143857153, 9688.612455501772, 9707.0, 9744.0, 9811.754706132404, 39694.0, 39985.60014399877, 40046.99882502961, 40182.0, 40541.78374648765, 3183.6338821035647, 3409.322054920234, 3453.930921381558, 3480.460528618068, 3589.10272431892, 29778.173550964297, 30039.699603003908, 30702.232444188852, 30709.0, 31736.206594835083, 3068.5704001439817, 3115.0, 3124.98437507811, 3161.3045130071346, 3253.0403530958424, 406502.0, 409961.0, 460812.9999999966, 466072.0, 468948.0, 33322.166945826546, 33598.160045999044, 33659.326813463595, 33710.32579348448, 33722.15694607605, 34679.479807802905, 34894.12764680904, 34894.65105348965, 35829.46255806165, 35983.64016359803, 16901.661966760596, 16915.492535223962, 16936.57658558521, 16954.745678814827, 17074.48776536706, 12920.0, 12989.090763646644, 12994.480220791062, 13000.349982500837, 13007.089503734647, 15249.695006100037, 16504.75242871358, 19369.12838922252, 19438.514037149183, 19438.902805486166, 7032.909898965669, 8345.833083338299, 8409.0, 8507.787305317293, 8522.914770852336, 842.3682238321259, 857.2541888556996, 954.7565370830393, 1027.7995790820867, 1083.7344850511674, 30463.847680761453, 30720.231994199876, 30795.384092318476, 30865.8456707715, 32191.35617287641, 998.6854140945579, 1064.3720205079042, 1120.462178154474, 1157.1668398752884, 1264.108803293694, 15380.154091525073, 15859.04845709238, 15908.0, 15973.642240409463, 16450.177491125396, 581870.0, 590087.0, 748870.0, 754034.0, 761967.0, 43224.567754322685, 58407.70796145992, 59127.11309330363, 59141.704291478265, 59244.11133832996, 1376.8210132682686, 1399.8810101141516, 1418.489343836218, 1424.9928750356182, 1431.270052273348, 30274.24314392158, 30489.54265685972, 30611.0, 30757.38485230238, 30890.91881784217, 50569.24146137884, 50737.492625073275, 51096.744516277184, 51137.744311278206, 51283.23075153801, 621467.0, 626061.0, 626465.0, 627811.0, 628346.0, 26904.32739181536, 26978.32554186161, 27145.321366965192, 27275.31811704723, 27368.315792105357, 47766.283505748135, 48482.0, 49186.262206066225, 49298.75350623224, 49524.75237623789, 39473.60526394699, 40227.79886100551, 40739.79630101831, 41307.586924131574, 41321.0, 31401.52897706582, 35826.64173358233, 37536.249275014896, 39733.60266397299, 39862.0, 38113.0, 38492.6150738489, 38732.8380148596, 39280.0, 39555.2088958225, 34738.131546711535, 35092.29815403627, 35125.29749405049, 35552.64447355493, 35669.28661426809, 24853.751462485867, 25782.355441114123, 26118.0, 26157.0, 26784.464310714066], "value": 19567.576215461315, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.69544364508386, 382.8353807862619, 389.6006593241927, 400.4873761585172, 411.2104758862983, 16148.354065837382, 16183.352665893379, 19954.90022549887, 21351.679724804122, 22220.000099995497, 21097.894510527465, 21284.0, 21805.454863628416, 21915.890420547876, 21979.450513737163, 5816.941830581693, 5914.083317085859, 5989.131575921489, 6017.157597936292, 6017.247844019497, 5837.328707198684, 5880.0, 5888.234529511157, 5983.940160598404, 6024.638521688691, 5553.944460555404, 5832.416758324155, 5852.092925596539, 5881.235439392873, 5883.88232235355, 5563.471470210324, 5905.173275741378, 5937.970310148443, 6121.90817137741, 6473.902891456632, 5755.913661295084, 5832.795852145155, 5891.7054147292465, 5909.793157239519, 5914.112883067574, 23229.53540929172, 25079.874600627063, 25271.49457010868, 26553.867230663916, 26793.73206267932, 1940.4663717477665, 2225.18780645065, 2427.6115821468657, 2455.5702752018324, 2493.252024392675, 10273.229507786871, 10318.380897146195, 10556.0, 12499.062570307264, 14867.851321486864, 1848.5286252005787, 1875.634251320993, 1916.492129585661, 1930.6814375628005, 1985.1091909659133, 113719.0, 119972.0, 121174.3941280288, 126778.0, 130070.0, 11655.941720291345, 11676.0, 11806.232594881305, 11864.940675296568, 11971.324014637958, 14301.92622069794, 14742.0, 14796.408143674133, 14801.703965920622, 14824.777628335583, 7229.674664639999, 8731.563421828883, 8793.516356600448, 8804.207621314112, 8912.821743565093, 7225.494215404871, 8729.083446238054, 8748.200575815805, 8802.86795698065, 8868.556572171366, 4530.818767249339, 4569.474510431276, 4591.517890621437, 4620.491745908014, 4637.582617564368, 1757.3585641241111, 1939.7090436434366, 1968.8523360748002, 2013.5670830771455, 2028.2191356327626, 826.9539033123104, 894.3337213775778, 930.972070837876, 952.7713348796239, 1007.3603261928728, 17425.825741742672, 20227.98860056991, 21227.257046003317, 21824.563508729738, 21934.903254837194, 977.8826540815147, 977.9706608801747, 1007.8639383683109, 1009.5255230041869, 1028.0901402259055, 10751.6236931707, 11113.83329250062, 11575.88424115765, 11602.88397116018, 11645.825312620318, 554632.0, 563103.0, 567034.0, 584043.0, 587333.0, 141973.0, 144199.27900360432, 145615.0, 148380.0, 149244.0, 1006.0, 1010.1464262698008, 1012.1700205831148, 1030.845373194027, 1031.2471895516314, 18125.0, 18755.718664219774, 19436.02819858973, 19453.31913383025, 19702.40892773219, 53343.0, 53997.73001134969, 54542.0, 55378.0, 55436.0, 79387.80918286346, 81248.5937570332, 82299.17700822915, 84020.31227374711, 93110.0, 7269.563826170447, 7597.506162099446, 7632.084149902047, 7907.604619768874, 8546.829063418822, 9546.18857397129, 10302.22733295021, 11658.94170529142, 13613.931930340284, 14022.948278878921, 11803.80973200717, 11987.940060299643, 11995.340256285639, 12020.398980050788, 12039.217450865666, 10306.948465257627, 11508.82736758966, 11962.026265666416, 11997.820032699341, 12033.4584943676, 11919.702007449883, 12040.75918481643, 12052.638420847388, 12069.456874440846, 12080.577179798665, 8352.248297653241, 8397.412181147261, 8481.194286542768, 8575.614097365758, 8617.224449799549, 8542.74371768848, 11616.244944078946, 11818.645440636794, 11911.40442977865, 12058.758824823279], "value": 9802.562864823061, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.3212602517901, 1628.5765700917761, 1787.3029518487792, 1852.8517718582514, 1947.8441724662027, 8020.398470114736, 8611.009733880606, 8648.0, 9062.410943288682, 9087.863682044766, 8696.652133914637, 8737.344699147558, 8738.606762695677, 8762.342824288173, 8861.0, 2381.8451800632947, 2417.093589903786, 2417.9758202418016, 2419.4919066995953, 2425.3572803207076, 2302.873341966191, 2329.7087864017, 2330.1378489958656, 2331.5686597979343, 2390.1992832401165, 2387.928362149138, 2391.9043238270533, 2392.820538459614, 2416.359664688859, 2427.5751743444916, 2350.753170917046, 2384.582698027847, 2400.699912510936, 2408.2895545814026, 2413.4449076712367, 2386.5107652931106, 2395.700537432821, 2400.8919598618104, 2407.4823912858647, 2411.565918134735, 8946.86579701305, 8989.910100898973, 8999.32505062123, 9018.684346047858, 9036.954815225947, 5291.841244762625, 5378.300820893259, 5402.243685883979, 5452.345718513764, 5471.343438787331, 3127.0, 3585.3008663310666, 3675.724320675933, 3687.2072504411412, 3725.6833169180654, 1086.750047489082, 1094.3488624268548, 1097.928634638746, 1116.3413585984308, 1117.0, 11620.07039436843, 11786.528538858436, 11827.231229970024, 11835.526578936919, 11859.288442693465, 4645.512221216791, 4653.395058642423, 4872.43966943806, 4874.780634871439, 4875.878103047381, 10087.596496140071, 10089.596416143271, 10095.646652367132, 10099.495025248709, 10107.69676909694, 5343.572514198932, 5393.9730301348245, 5426.8914621707345, 5438.45615438453, 5438.945610543923, 4495.483019452739, 4815.133276010281, 4865.5864251538305, 4881.780319885614, 4974.726390048582, 3709.573399059089, 3976.343903255988, 4011.478507793968, 4092.6112019358116, 4117.5882411759185, 1302.5245785288491, 1313.5533918467768, 1314.6065170918666, 1318.1827267094463, 1320.808482769993, 868.1665601022962, 886.7650072730731, 894.5393122541897, 903.5662881816764, 906.3202598051462, 5590.24531688225, 5607.52336051432, 6003.729832157476, 6050.667213303311, 6294.276158241769, 943.5235206220815, 993.2500961773815, 994.1450352696675, 996.7009897030862, 1024.797507788162, 4073.450084238649, 4123.8969025774595, 4140.751554906716, 4174.728642638219, 4195.853145139906, 10448.843267350996, 10782.0, 10802.081823044971, 10930.125589952784, 10954.0, 10137.847932281022, 10668.933106689268, 10742.838857417146, 10743.301685390425, 10900.727481813017, 7576.166621671612, 7936.2857342839425, 8124.350051995826, 8257.174282571696, 8299.875501867476, 5247.947520524746, 5540.279763630702, 6133.294671112879, 6145.446909778141, 6282.591631543935, 10090.192784577363, 10093.14208292274, 10105.545250463894, 10165.593376264867, 10244.436555989492, 7716.459847810599, 9995.450250236307, 10006.399616023062, 10060.094591486508, 10131.341462804894, 6943.826404339932, 7314.96342518284, 7502.924970750441, 7597.6201189939175, 7618.276263754934, 8372.958135209528, 8401.285890699359, 8447.113053129333, 8470.280026197595, 8522.062573116827, 7513.511621744569, 7682.347000505019, 7724.536527808349, 7777.844443110993, 7788.805279868048, 7429.405647548291, 7501.962490187514, 7507.099148102263, 7556.433267505069, 7608.923910760822, 7080.185778635523, 7125.501214914906, 7149.571025738473, 7151.070360853056, 7182.174049984319, 4999.600031997359, 5073.847784566469, 5087.0, 5134.640575159703, 5178.61160412963, 5156.4327923927585, 5200.375954885438, 5268.341457317835, 5270.262163297065, 5312.867178320573], "value": 4665.6716891312035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.44715698701503, 287.45240317195726, 310.08061098841927, 321.2482790270766, 322.6902173913043, 4632.930506042404, 5475.835724928249, 5680.403557626451, 5777.971110144449, 5821.825345239649, 5501.394846566875, 5635.042042852715, 5654.321481422225, 5663.377028526858, 5671.517920976722, 4326.459192600925, 4334.978325108378, 4349.7390156590545, 4361.825526978917, 4368.148211098837, 3126.4997600383945, 4085.5097388313443, 4347.478302603676, 4356.651467882578, 4366.694331396803, 4242.38485419614, 4254.553271906451, 4258.254805409041, 4282.421873047146, 4293.313069908815, 4311.482622085354, 4326.5673432656795, 4332.566743325674, 4339.218940590692, 4344.567042456203, 3170.904872853795, 3600.513930619359, 3707.5736290326686, 3724.2737666155112, 3776.546814382264, 4608.377868987711, 5303.390110137345, 5474.644148130359, 5499.092649712793, 5527.447255274481, 4363.912721745579, 4917.311576379309, 4945.901081978341, 4950.232713929347, 5005.874853128665, 5675.858103547403, 5966.761329546813, 5992.790252341147, 6001.639901605918, 6012.0, 4115.333290017517, 4116.279651061052, 4125.443065186191, 4134.834606615762, 4155.2728272552185, 6022.6687532185715, 6356.523260755462, 6400.871982560369, 6410.653762709837, 6413.781381537493, 3864.3237433449035, 4136.44158038667, 4146.564610715892, 4162.729422587522, 4203.9910421499235, 4172.0, 4181.351890456984, 4184.790760461965, 4196.559361267084, 4210.136921931058, 4937.901241975141, 4951.678140920829, 4957.479464656231, 4967.90064198714, 4968.527989840959, 5756.942430575725, 6134.325224225332, 6136.539759518054, 6144.078388241799, 6194.287656919422, 1366.371469124214, 1413.2721648351107, 1413.8656827601362, 1423.437742091868, 1428.4786053090754, 781.5271760584765, 798.7244400681756, 847.8050048488882, 863.3913091270639, 877.9736607901773, 4827.010462855106, 4831.0, 4831.565159135695, 4840.515948405202, 4875.609951203895, 4525.683202175882, 5119.539241468286, 5128.692278463304, 5233.964300705582, 5392.649477783932, 4171.97914010434, 4713.929291060636, 4893.681910675795, 4939.777710003059, 5214.270002199695, 2663.5338815707173, 2829.0762281648676, 3217.3404452087734, 3281.770276080699, 3329.9667003329655, 5215.608829337815, 5561.5272701820795, 6337.60572674011, 6528.314526974695, 6573.3098024706715, 5194.92207616886, 5772.076467765138, 5947.762089516457, 6418.8716225675225, 6534.313897040837, 5132.666376685504, 6582.703778329987, 6606.9008964866525, 6614.9669251654395, 6705.597664140168, 5706.061378390215, 5901.7639294427745, 6014.729337179925, 6353.331600727798, 6394.200724909387, 6230.221222347207, 6258.405451482102, 6259.874802503833, 6267.341929097379, 6274.090256912813, 5374.435684253253, 6529.640869752209, 6545.0182472629485, 6608.306127856506, 6614.809334319869, 3579.480975258625, 4074.6129117733767, 4110.321796903478, 4117.032497363177, 4122.2786012448305, 5573.888522229614, 6013.669248191217, 6241.407066328691, 6306.337834527309, 6336.714847831767, 5908.961408314125, 6028.367021462858, 6100.328963814066, 6127.601705889103, 6151.723172457339, 6123.571350005457, 6139.434444216696, 6146.692665366803, 6162.476189523941, 6165.506759459322, 5546.916796248141, 5794.4205579443405, 6002.609830361012, 6022.337542870368, 6041.758329666764, 6109.450149486567, 6131.785387511416, 6135.938640613536, 6144.692765361625, 6195.349488303844, 5444.074507333842, 6009.519238460825, 6010.579259451796, 6039.943009973325, 6045.128563932097], "value": 4357.019188246217, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.4334770595907, 766.4136935244538, 815.5718247919845, 875.3259989807844, 875.5797217335679, 15285.770713439293, 15887.84112158878, 16111.113888736114, 17433.302667893302, 18064.096795160254, 16751.497455076336, 16896.746548801777, 16897.577560560992, 16904.239309231085, 16954.40659576915, 664.773976847874, 786.5005721366945, 895.3061377432476, 923.1414784250644, 935.5789894547447, 916.2395211974045, 918.5407296351824, 922.3589605224372, 922.6447817590209, 924.1082355526916, 960.7309953213112, 966.9516524173798, 967.1682353176262, 968.1045033344128, 972.6644307713863, 956.7942892278159, 957.6360982826482, 957.9137877591015, 957.9377340472881, 958.5494817435793, 716.9856602867914, 717.6268340462982, 718.0199028326349, 719.4999475364624, 719.7588807749411, 15394.846051539453, 15644.921775391163, 15698.607534811606, 15699.843001570067, 15734.44929427476, 14533.27333633314, 14589.416423343053, 14596.0, 14732.042417242845, 14763.26183690822, 11987.58043468483, 12028.819567706492, 12057.638270851889, 12275.0, 12366.189177465496, 3893.98053009736, 3932.1742434088874, 3952.9011774705573, 4066.3900414937702, 4066.552679205285, 120314.0, 121018.0, 121630.52662630203, 122224.38887805594, 122554.0, 11285.43572821372, 11302.773944521063, 11600.955913967784, 11610.0, 11661.475233614508, 22593.774062259166, 22801.0, 23110.960006799734, 23183.420414489774, 23230.07079716826, 8189.385796065319, 8270.0, 8350.08149103598, 8521.57392130391, 8566.400351975302, 4513.0, 4558.0, 4572.95427045732, 4626.83806066786, 4741.596964258077, 3554.360215161244, 3670.1742108026015, 3673.063368840982, 3681.4661874028116, 3684.8157592120288, 682.556338380047, 690.92399836018, 691.9308069193141, 700.0199720391493, 708.8192510909685, 655.6557807151283, 656.2190992718745, 681.4786688183543, 683.4498228925663, 696.2341424433083, 7751.698579369878, 8413.495190288602, 9328.533573321307, 9646.228301735844, 9858.704238872846, 546.9042917489503, 619.5786864931799, 641.4291280760127, 643.234550884446, 703.5040296590892, 4386.337408264071, 4541.591256786839, 4606.677532572688, 4608.493065762764, 4913.852584422473, 157435.0, 165949.0, 167021.16489417476, 167677.16161419358, 168438.0, 109963.0, 125092.1236181458, 132670.0, 132739.0, 138614.92077618843, 116453.0, 116970.41514792542, 117993.0, 118905.0, 124998.0, 9194.0, 9247.213986810928, 9490.003549627467, 9680.322377433793, 9704.6118155273, 49986.750066249435, 55638.0, 60635.0, 61799.69100154471, 64831.0, 69436.80700666858, 85372.0, 103618.0, 104000.47999759953, 105814.0, 12074.698132546757, 13656.863431365957, 13794.93102534481, 13895.51365702236, 14348.210848403438, 13811.930940345233, 14106.365213565208, 14460.783088253895, 15837.208139592743, 16091.436799711953, 14357.21035343066, 14424.927875360556, 14512.564623061326, 14570.708585828012, 15108.244587770349, 14266.714665706835, 14334.784978225127, 14425.134491930517, 14802.777958330418, 14908.740827941623, 12524.998000159783, 13520.0, 13776.443454748116, 13820.0, 14673.779893301822, 11432.657020289405, 11455.205174151059, 11618.0, 11920.821187682364, 11945.223560468541, 10777.245592808427, 11054.668359949215, 11487.930036139967, 11965.162438629213, 11978.341191234565], "value": 8187.231553763275, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1576.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 120.0, 107.0, 119.0, 118.0, 118.0, 116.0, 117.0, 120.0, 118.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 137.0, 154.0, 106.0, 127.0, 132.0, 140.0, 161.0, 112.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 200.0, 197.0, 206.0, 198.0, 265.0, 251.0, 198.0, 196.0, 196.0], "value": 209.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 101.0, 70.0, 113.0, 49.0, 54.0, 58.0, 83.0, 155.0, 61.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 123.0, 70.0, 86.0, 107.0, 132.0, 60.0, 104.0, 100.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 87.0, 89.0, 99.0, 117.0, 86.0, 91.0, 87.0, 80.0, 132.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 95.0, 119.0, 159.0, 98.0, 99.0, 155.0, 95.0, 146.0, 95.0], "value": 118.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 152.0, 126.0, 90.0, 99.0, 101.0, 154.0, 141.0, 91.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 52.0, 53.0, 55.0, 57.0, 54.0, 55.0, 52.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 73.0, 69.0, 81.0, 80.0, 77.0, 73.0, 70.0, 72.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 84.0, 86.0, 107.0, 72.0, 104.0, 108.0, 107.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 134.0, 136.0, 138.0, 142.0, 145.0, 152.0, 148.0, 176.0, 135.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 229.0, 219.0, 172.0, 174.0, 172.0, 170.0, 171.0, 169.0, 170.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 68.0, 67.0, 68.0, 67.0, 69.0, 68.0, 69.0], "value": 67.8, "unit": "score"}]}]} 03:42:30 INFO - Return code: 0 03:42:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:30 ERROR - # TBPL SUCCESS # 03:42:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:30 INFO - 2016-07-17 03:42:30.548 screenresolution[1693:10332] starting screenresolution argv=screenresolution get 03:42:30 INFO - 2016-07-17 03:42:30.551 screenresolution[1693:10332] Display 0: 1600x1200x32@60 03:42:30 INFO - 2016-07-17 03:42:30.557 screenresolution[1694:10335] starting screenresolution argv=screenresolution list 03:42:30 INFO - Available Modes on Display 0 03:42:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:30 INFO - Intel Iris: 03:42:30 INFO - Chipset Model: Intel Iris 03:42:30 INFO - Type: GPU 03:42:30 INFO - Bus: Built-In 03:42:30 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:30 INFO - Vendor: Intel (0x8086) 03:42:30 INFO - Device ID: 0x0a2e 03:42:30 INFO - Revision ID: 0x0009 03:42:30 INFO - Displays: 03:42:30 INFO - Display: 03:42:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:30 INFO - Display Serial Number: 1600x1200 60 03:42:30 INFO - Main Display: Yes 03:42:30 INFO - Mirror: Off 03:42:30 INFO - Online: Yes 03:42:30 INFO - Rotation: Supported 03:42:30 INFO - Return code: 0 03:42:30 INFO - Running post-action listener: _resource_record_post_action 03:42:30 INFO - ##### 03:42:30 INFO - ##### Finished run-tests step (success) 03:42:30 INFO - ##### 03:42:30 INFO - Running post-run listener: _resource_record_post_run 03:42:30 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 38282752; Write bytes: 705369600; Read time: 379; Write time: 1819 03:42:30 INFO - TinderboxPrint: CPU usage
54.2% 03:42:30 INFO - TinderboxPrint: I/O read bytes / time
38,282,752 / 379 03:42:30 INFO - TinderboxPrint: I/O write bytes / time
705,369,600 / 1,819 03:42:30 INFO - TinderboxPrint: CPU idle
2,209.7 (45.8%) 03:42:30 INFO - TinderboxPrint: CPU system
971.7 (20.1%) 03:42:30 INFO - TinderboxPrint: CPU user
1,648.2 (34.1%) 03:42:30 INFO - TinderboxPrint: Swap in / out
445,726,720 / 0 03:42:30 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 413581824; Write bytes: 409896960; Read time: 30412; Write time: 723 03:42:30 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 29203968; Write bytes: 293920256; Read time: 282; Write time: 1091 03:42:31 INFO - Running post-run listener: _upload_blobber_files 03:42:31 INFO - Blob upload gear active. 03:42:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:31 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 03:42:31 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:42:31 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:42:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:42:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:32 INFO - (blobuploader) - INFO - Done attempting. 03:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:33 INFO - (blobuploader) - INFO - Done attempting. 03:42:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:36 INFO - (blobuploader) - INFO - Done attempting. 03:42:36 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:36 INFO - Return code: 0 03:42:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5716a7fab3c8e741b6eeec3397b06bb4e9f9e97f34e912a0858fc48c90f91f6963fb79025933c16a1f0704f396565e64c7ed145cd1dcb24824bee7dac42548aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8a2a2a5f85ce2fa57068ac1b0c257e3a26eff0767dd00f3c6bc8f2a553192415898e839dbe93a0b2a0bf874f5e0b48cfca34e3ba7361abe5e9e8c27e2cf4a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f5a6562c9573799cd2fc84a7a7fe43fffe3e4336e878f2ad007350c9283ffa2303d64f6ef0696f1e9f8d2ca803e05ddb463763f27d8d4398fab13b92b81713a"} 03:42:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:36 INFO - Contents: 03:42:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5716a7fab3c8e741b6eeec3397b06bb4e9f9e97f34e912a0858fc48c90f91f6963fb79025933c16a1f0704f396565e64c7ed145cd1dcb24824bee7dac42548aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8a2a2a5f85ce2fa57068ac1b0c257e3a26eff0767dd00f3c6bc8f2a553192415898e839dbe93a0b2a0bf874f5e0b48cfca34e3ba7361abe5e9e8c27e2cf4a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f5a6562c9573799cd2fc84a7a7fe43fffe3e4336e878f2ad007350c9283ffa2303d64f6ef0696f1e9f8d2ca803e05ddb463763f27d8d4398fab13b92b81713a"} 03:42:36 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:36 INFO - Copying logs to upload dir... 03:42:36 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1325.392588 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-07-17 03:42:36.437359) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:36.441163) ========= 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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5716a7fab3c8e741b6eeec3397b06bb4e9f9e97f34e912a0858fc48c90f91f6963fb79025933c16a1f0704f396565e64c7ed145cd1dcb24824bee7dac42548aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8a2a2a5f85ce2fa57068ac1b0c257e3a26eff0767dd00f3c6bc8f2a553192415898e839dbe93a0b2a0bf874f5e0b48cfca34e3ba7361abe5e9e8c27e2cf4a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f5a6562c9573799cd2fc84a7a7fe43fffe3e4336e878f2ad007350c9283ffa2303d64f6ef0696f1e9f8d2ca803e05ddb463763f27d8d4398fab13b92b81713a"} build_url:https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010872 build_url: 'https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5716a7fab3c8e741b6eeec3397b06bb4e9f9e97f34e912a0858fc48c90f91f6963fb79025933c16a1f0704f396565e64c7ed145cd1dcb24824bee7dac42548aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8a2a2a5f85ce2fa57068ac1b0c257e3a26eff0767dd00f3c6bc8f2a553192415898e839dbe93a0b2a0bf874f5e0b48cfca34e3ba7361abe5e9e8c27e2cf4a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f5a6562c9573799cd2fc84a7a7fe43fffe3e4336e878f2ad007350c9283ffa2303d64f6ef0696f1e9f8d2ca803e05ddb463763f27d8d4398fab13b92b81713a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:36.478223) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:36.478990) ========= 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.rlx3cpzb1n/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.iRYgTiWwbu/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005099 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:36.532257) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 03:42:36.532869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 03:42:36.533967) ========= ========= Total master_lag: 0.19 =========