builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0022 starttime: 1468005665.91 results: success (0) buildid: 20160708040727 builduid: 75f4324d657349a998af2caa2550aa8a revision: 24e82cb41040b1f515135ae3ba8b6bddf234d509 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:05.909955) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:05.910390) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:05.910692) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.297553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.297805) ========= 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.xm5v96Dzn1/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.REjYqCmBB9/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-08 12:21:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 218M=0s 2016-07-08 12:21:06 (218 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.157288 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.492672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.492947) ========= 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.xm5v96Dzn1/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.REjYqCmBB9/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.019658 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.553698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 12:21:06.554046) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24e82cb41040b1f515135ae3ba8b6bddf234d509 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24e82cb41040b1f515135ae3ba8b6bddf234d509 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xm5v96Dzn1/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.REjYqCmBB9/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-08 12:21:06,587 truncating revision to first 12 chars 2016-07-08 12:21:06,587 Setting DEBUG logging. 2016-07-08 12:21:06,587 attempt 1/10 2016-07-08 12:21:06,587 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/24e82cb41040?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 12:21:08,051 unpacking tar archive at: mozilla-beta-24e82cb41040/testing/mozharness/ program finished with exit code 0 elapsedTime=1.722922 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 12:21:08.299090) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:08.299393) ========= script_repo_revision: 24e82cb41040b1f515135ae3ba8b6bddf234d509 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:08.299782) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:08.300060) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:08.318463) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-07-08 12:21:08.318848) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xm5v96Dzn1/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.REjYqCmBB9/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:08 INFO - MultiFileLogger online at 20160708 12:21:08 in /builds/slave/test 12:21:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:21:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:21:08 INFO - {'append_to_log': False, 12:21:08 INFO - 'base_work_dir': '/builds/slave/test', 12:21:08 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:21:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:21:08 INFO - 'branch': 'Mozilla-Beta', 12:21:08 INFO - 'buildbot_json_path': 'buildprops.json', 12:21:08 INFO - 'config_files': ('talos/mac_config.py',), 12:21:08 INFO - 'default_actions': ('clobber', 12:21:08 INFO - 'read-buildbot-config', 12:21:08 INFO - 'download-and-extract', 12:21:08 INFO - 'populate-webroot', 12:21:08 INFO - 'create-virtualenv', 12:21:08 INFO - 'install', 12:21:08 INFO - 'run-tests'), 12:21:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:21:08 INFO - 'download_minidump_stackwalk': True, 12:21:08 INFO - 'download_symbols': 'ondemand', 12:21:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:21:08 INFO - 'tooltool.py': '/tools/tooltool.py', 12:21:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:21:08 INFO - '/tools/misc-python/virtualenv.py')}, 12:21:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:21:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:21:08 INFO - 'installer_path': 'installer.exe', 12:21:08 INFO - 'log_level': 'info', 12:21:08 INFO - 'log_name': 'talos', 12:21:08 INFO - 'log_to_console': True, 12:21:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:21:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:21:08 INFO - 'opt_config_files': (), 12:21:08 INFO - 'pip_index': False, 12:21:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:08 INFO - 'cmd': ('bash', 12:21:08 INFO - '-c', 12:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:08 INFO - 'enabled': True, 12:21:08 INFO - 'halt_on_failure': False, 12:21:08 INFO - 'name': 'check_screen_resolution'},), 12:21:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:08 INFO - 'cmd': ('bash', 12:21:08 INFO - '-c', 12:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:08 INFO - 'enabled': True, 12:21:08 INFO - 'halt_on_failure': False, 12:21:08 INFO - 'name': 'check_screen_resolution'},), 12:21:08 INFO - 'run_cmd_checks_enabled': True, 12:21:08 INFO - 'sps_profile': False, 12:21:08 INFO - 'sps_profile_interval': 0, 12:21:08 INFO - 'suite': 'dromaeojs', 12:21:08 INFO - 'system_bits': '32', 12:21:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:21:08 INFO - 'title': 't-yosemite-r7-0022', 12:21:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:21:08 INFO - 'use_talos_json': True, 12:21:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:21:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:21:08 INFO - 'work_dir': 'build'} 12:21:08 INFO - ##### 12:21:08 INFO - ##### Running clobber step. 12:21:08 INFO - ##### 12:21:08 INFO - Running pre-action listener: _resource_record_pre_action 12:21:08 INFO - Running main action method: clobber 12:21:08 INFO - rmtree: /builds/slave/test/build 12:21:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:21:09 INFO - Running post-action listener: _resource_record_post_action 12:21:09 INFO - ##### 12:21:09 INFO - ##### Running read-buildbot-config step. 12:21:09 INFO - ##### 12:21:09 INFO - Running pre-action listener: _resource_record_pre_action 12:21:09 INFO - Running main action method: read_buildbot_config 12:21:09 INFO - Using buildbot properties: 12:21:09 INFO - { 12:21:09 INFO - "project": "", 12:21:09 INFO - "product": "firefox", 12:21:09 INFO - "script_repo_revision": "production", 12:21:09 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:21:09 INFO - "repository": "", 12:21:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:21:09 INFO - "buildid": "20160708040727", 12:21:09 INFO - "pgo_build": "False", 12:21:09 INFO - "basedir": "/builds/slave/test", 12:21:09 INFO - "buildnumber": 26, 12:21:09 INFO - "slavename": "t-yosemite-r7-0022", 12:21:09 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:21:09 INFO - "platform": "yosemite_r7", 12:21:09 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:21:09 INFO - "branch": "mozilla-beta", 12:21:09 INFO - "repo_path": "releases/mozilla-beta", 12:21:09 INFO - "revision": "24e82cb41040b1f515135ae3ba8b6bddf234d509", 12:21:09 INFO - "stage_platform": "macosx64", 12:21:09 INFO - "builduid": "75f4324d657349a998af2caa2550aa8a", 12:21:09 INFO - "slavebuilddir": "test" 12:21:09 INFO - } 12:21:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 12:21:09 INFO - Running post-action listener: _resource_record_post_action 12:21:09 INFO - ##### 12:21:09 INFO - ##### Running download-and-extract step. 12:21:09 INFO - ##### 12:21:09 INFO - Running pre-action listener: _resource_record_pre_action 12:21:09 INFO - Running main action method: download_and_extract 12:21:09 INFO - mkdir: /builds/slave/test/build/tests 12:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:09 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:21:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 12:21:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 12:21:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 12:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 12:21:14 INFO - Downloaded 1165 bytes. 12:21:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 12:21:14 INFO - Using the following test package requirements: 12:21:14 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 12:21:14 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:14 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 12:21:14 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:14 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 12:21:14 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:21:14 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:14 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 12:21:14 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 12:21:14 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:14 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 12:21:14 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:14 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 12:21:14 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:14 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 12:21:14 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:14 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 12:21:14 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 12:21:14 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')]} 12:21:14 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 12:21:15 INFO - Downloaded 18351527 bytes. 12:21:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:21:18 INFO - Return code: 0 12:21:18 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:21:18 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')]} 12:21:18 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 12:21:22 INFO - Downloaded 18351527 bytes. 12:21:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:21:25 INFO - Return code: 0 12:21:25 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')]} 12:21:25 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:21:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 12:21:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 12:21:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 12:21:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 12:21:26 INFO - Downloaded 10840145 bytes. 12:21:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:21:27 INFO - Return code: 0 12:21:27 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')]} 12:21:27 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 12:21:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 12:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 12:21:29 INFO - Downloaded 86183753 bytes. 12:21:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:29 INFO - mkdir: /builds/slave/test/properties 12:21:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:21:29 INFO - Writing to file /builds/slave/test/properties/build_url 12:21:29 INFO - Contents: 12:21:29 INFO - build_url:https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:30 INFO - Running post-action listener: _resource_record_post_action 12:21:30 INFO - Running post-action listener: set_extra_try_arguments 12:21:30 INFO - ##### 12:21:30 INFO - ##### Running populate-webroot step. 12:21:30 INFO - ##### 12:21:30 INFO - Running pre-action listener: _resource_record_pre_action 12:21:30 INFO - Running main action method: populate_webroot 12:21:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:21:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:21:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:21:30 INFO - '--tppagecycles', 12:21:30 INFO - '7'], 12:21:30 INFO - 'tests': ['tsvgm']}}, 12:21:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:21:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:21:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:21:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:21:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['damp', 'tps']}, 12:21:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'tests': ['damp', 'tps']}, 12:21:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['dromaeo_dom']}, 12:21:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:21:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['a11yr', 12:21:30 INFO - 'ts_paint', 12:21:30 INFO - 'tpaint', 12:21:30 INFO - 'sessionrestore', 12:21:30 INFO - 'sessionrestore_no_auto_restore', 12:21:30 INFO - 'tabpaint']}, 12:21:30 INFO - 'other-e10s': {'tests': ['a11yr', 12:21:30 INFO - 'ts_paint', 12:21:30 INFO - 'tpaint', 12:21:30 INFO - 'sessionrestore', 12:21:30 INFO - 'sessionrestore_no_auto_restore', 12:21:30 INFO - 'tabpaint']}, 12:21:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:21:30 INFO - 'ts_paint', 12:21:30 INFO - 'tpaint', 12:21:30 INFO - 'sessionrestore', 12:21:30 INFO - 'sessionrestore_no_auto_restore', 12:21:30 INFO - 'tabpaint']}, 12:21:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:21:30 INFO - 'ts_paint', 12:21:30 INFO - 'tpaint', 12:21:30 INFO - 'sessionrestore', 12:21:30 INFO - 'sessionrestore_no_auto_restore', 12:21:30 INFO - 'tabpaint']}, 12:21:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['a11yr', 12:21:30 INFO - 'ts_paint', 12:21:30 INFO - 'tpaint', 12:21:30 INFO - 'sessionrestore', 12:21:30 INFO - 'sessionrestore_no_auto_restore', 12:21:30 INFO - 'tabpaint']}, 12:21:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['a11yr', 12:21:30 INFO - 'ts_paint', 12:21:30 INFO - 'tpaint', 12:21:30 INFO - 'sessionrestore', 12:21:30 INFO - 'sessionrestore_no_auto_restore', 12:21:30 INFO - 'tabpaint']}, 12:21:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['tsvgx', 12:21:30 INFO - 'tsvgr_opacity', 12:21:30 INFO - 'tart', 12:21:30 INFO - 'tscrollx', 12:21:30 INFO - 'cart']}, 12:21:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:21:30 INFO - 'tsvgr_opacity', 12:21:30 INFO - 'tart', 12:21:30 INFO - 'tscrollx', 12:21:30 INFO - 'cart']}, 12:21:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'talos_options': ['--disable-e10s'], 12:21:30 INFO - 'tests': ['tp5o']}, 12:21:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'tests': ['tp5o']}, 12:21:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'talos_options': ['--disable-e10s', 12:21:30 INFO - '--xperf_path', 12:21:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:30 INFO - 'tests': ['tp5n']}, 12:21:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:30 INFO - 'talos_options': ['--xperf_path', 12:21:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:30 INFO - 'tests': ['tp5n']}}, 12:21:30 INFO - 'talos.zip': {'path': '', 12:21:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:21:30 INFO - Running post-action listener: _resource_record_post_action 12:21:30 INFO - ##### 12:21:30 INFO - ##### Running create-virtualenv step. 12:21:30 INFO - ##### 12:21:30 INFO - Running pre-action listener: _resource_record_pre_action 12:21:30 INFO - Running main action method: create_virtualenv 12:21:30 INFO - Creating virtualenv /builds/slave/test/build/venv 12:21:30 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 12:21:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:21:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:21:30 INFO - Using real prefix '/tools/python27' 12:21:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:21:31 INFO - Installing distribute.............................................................................................................................................................................................done. 12:21:33 INFO - Installing pip.................done. 12:21:33 INFO - Return code: 0 12:21:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:33 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 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:33 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 12:21:33 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 12:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:33 INFO - 'HOME': '/Users/cltbld', 12:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:33 INFO - 'LOGNAME': 'cltbld', 12:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:33 INFO - 'MOZ_NO_REMOTE': '1', 12:21:33 INFO - 'NO_EM_RESTART': '1', 12:21:33 INFO - 'PAGER': '/bin/cat', 12:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:33 INFO - 'PWD': '/builds/slave/test', 12:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:33 INFO - 'SHELL': '/bin/bash', 12:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:33 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:33 INFO - 'USER': 'cltbld', 12:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:33 INFO - 'XPC_FLAGS': '0x0', 12:21:33 INFO - 'XPC_SERVICE_NAME': '0', 12:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:34 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 12:21:34 INFO - Cleaning up... 12:21:34 INFO - Return code: 0 12:21:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 12:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:21:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:34 INFO - 'HOME': '/Users/cltbld', 12:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:34 INFO - 'LOGNAME': 'cltbld', 12:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:34 INFO - 'MOZ_NO_REMOTE': '1', 12:21:34 INFO - 'NO_EM_RESTART': '1', 12:21:34 INFO - 'PAGER': '/bin/cat', 12:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:34 INFO - 'PWD': '/builds/slave/test', 12:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:34 INFO - 'SHELL': '/bin/bash', 12:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:34 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:34 INFO - 'USER': 'cltbld', 12:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:34 INFO - 'XPC_FLAGS': '0x0', 12:21:34 INFO - 'XPC_SERVICE_NAME': '0', 12:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:34 INFO - Downloading/unpacking psutil>=0.7.1 12:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:21:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:37 INFO - Installing collected packages: psutil 12:21:37 INFO - Running setup.py install for psutil 12:21:37 INFO - building 'psutil._psutil_osx' extension 12:21:37 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 12:21:37 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 12:21:37 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 12:21:37 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 12:21:37 INFO - building 'psutil._psutil_posix' extension 12:21:37 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 12:21:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:21:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:21:37 INFO - ^ 12:21:37 INFO - 1 warning generated. 12:21:37 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 12:21:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:38 INFO - Successfully installed psutil 12:21:38 INFO - Cleaning up... 12:21:38 INFO - Return code: 0 12:21:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:21:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:38 INFO - 'HOME': '/Users/cltbld', 12:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:38 INFO - 'LOGNAME': 'cltbld', 12:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:38 INFO - 'MOZ_NO_REMOTE': '1', 12:21:38 INFO - 'NO_EM_RESTART': '1', 12:21:38 INFO - 'PAGER': '/bin/cat', 12:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:38 INFO - 'PWD': '/builds/slave/test', 12:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:38 INFO - 'SHELL': '/bin/bash', 12:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:38 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:38 INFO - 'USER': 'cltbld', 12:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:38 INFO - 'XPC_FLAGS': '0x0', 12:21:38 INFO - 'XPC_SERVICE_NAME': '0', 12:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:21:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:21:40 INFO - Installing collected packages: mozsystemmonitor 12:21:40 INFO - Running setup.py install for mozsystemmonitor 12:21:40 INFO - Successfully installed mozsystemmonitor 12:21:40 INFO - Cleaning up... 12:21:41 INFO - Return code: 0 12:21:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:21:41 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')]} 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:41 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')]} 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:41 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 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:41 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 12:21:41 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 12:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:41 INFO - 'HOME': '/Users/cltbld', 12:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:41 INFO - 'LOGNAME': 'cltbld', 12:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:41 INFO - 'MOZ_NO_REMOTE': '1', 12:21:41 INFO - 'NO_EM_RESTART': '1', 12:21:41 INFO - 'PAGER': '/bin/cat', 12:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:41 INFO - 'PWD': '/builds/slave/test', 12:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:41 INFO - 'SHELL': '/bin/bash', 12:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:41 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:41 INFO - 'USER': 'cltbld', 12:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:41 INFO - 'XPC_FLAGS': '0x0', 12:21:41 INFO - 'XPC_SERVICE_NAME': '0', 12:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:43 INFO - Downloading blobuploader-1.2.4.tar.gz 12:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:21:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:21:43 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 12:21:43 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 12:21:43 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 12:21:43 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 12:21:43 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 12:21:43 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 12:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:21:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:21:44 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 12:21:44 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 12:21:44 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 12:21:44 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 12:21:44 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 12:21:44 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 12:21:44 INFO - Downloading docopt-0.6.1.tar.gz 12:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:21:45 INFO - Installing collected packages: blobuploader, requests, docopt 12:21:45 INFO - Running setup.py install for blobuploader 12:21:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:21:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:21:45 INFO - Running setup.py install for requests 12:21:45 INFO - Running setup.py install for docopt 12:21:45 INFO - Successfully installed blobuploader requests docopt 12:21:45 INFO - Cleaning up... 12:21:45 INFO - Return code: 0 12:21:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:45 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 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:45 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 12:21:45 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 12:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:45 INFO - 'HOME': '/Users/cltbld', 12:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:45 INFO - 'LOGNAME': 'cltbld', 12:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:45 INFO - 'MOZ_NO_REMOTE': '1', 12:21:45 INFO - 'NO_EM_RESTART': '1', 12:21:45 INFO - 'PAGER': '/bin/cat', 12:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:45 INFO - 'PWD': '/builds/slave/test', 12:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:45 INFO - 'SHELL': '/bin/bash', 12:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:45 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:45 INFO - 'USER': 'cltbld', 12:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:45 INFO - 'XPC_FLAGS': '0x0', 12:21:45 INFO - 'XPC_SERVICE_NAME': '0', 12:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-v2buTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-VCxbFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-l3VzMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-R_tOnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-u0SY9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-44ihBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-K68a5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-2AWCME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:46 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-AVVh25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-tskAQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-x6XSYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-ZcVb3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6mHgMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-srwzG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6wO5Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-yVaXSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:47 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-IxV4op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:21:47 INFO - Running setup.py install for manifestparser 12:21:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:21:48 INFO - Running setup.py install for mozcrash 12:21:48 INFO - Running setup.py install for mozdebug 12:21:48 INFO - Running setup.py install for mozdevice 12:21:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:21:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:21:48 INFO - Running setup.py install for mozfile 12:21:48 INFO - Running setup.py install for mozhttpd 12:21:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:21:48 INFO - Running setup.py install for mozinfo 12:21:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:21:48 INFO - Running setup.py install for mozInstall 12:21:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Running setup.py install for mozleak 12:21:49 INFO - Running setup.py install for mozlog 12:21:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Running setup.py install for moznetwork 12:21:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Running setup.py install for mozprocess 12:21:49 INFO - Running setup.py install for mozprofile 12:21:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Running setup.py install for mozrunner 12:21:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:21:49 INFO - Running setup.py install for mozscreenshot 12:21:50 INFO - Running setup.py install for moztest 12:21:50 INFO - Running setup.py install for mozversion 12:21:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:21:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:21:50 INFO - Cleaning up... 12:21:50 INFO - Return code: 0 12:21:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:50 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')]} 12:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:50 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')]} 12:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:50 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 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:50 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 12:21:50 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 12:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:50 INFO - 'HOME': '/Users/cltbld', 12:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:50 INFO - 'LOGNAME': 'cltbld', 12:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:50 INFO - 'MOZ_NO_REMOTE': '1', 12:21:50 INFO - 'NO_EM_RESTART': '1', 12:21:50 INFO - 'PAGER': '/bin/cat', 12:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:50 INFO - 'PWD': '/builds/slave/test', 12:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:50 INFO - 'SHELL': '/bin/bash', 12:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:50 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:50 INFO - 'USER': 'cltbld', 12:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:50 INFO - 'XPC_FLAGS': '0x0', 12:21:50 INFO - 'XPC_SERVICE_NAME': '0', 12:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-GdeQ9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:50 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)) 12:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-our5UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:50 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)) 12:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-EGHevL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-tzuOaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-f6YS9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Dt8q_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-IB6XyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-PDpNr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-pAkhqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-PHtr1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-AdAkBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:51 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)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-aDVFCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-F1YpVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:52 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)) 12:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:52 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-PhLXPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:52 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Ou8ILV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:52 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)) 12:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:52 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-b5L76f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:52 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)) 12:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:52 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-mCnD3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:52 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)) 12:21:52 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)) 12:21:52 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)) 12:21:52 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)) 12:21:52 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)) 12:21:52 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)) 12:21:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:21:52 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 12:21:52 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 12:21:52 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 12:21:52 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 12:21:52 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 12:21:52 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 12:21:54 INFO - Downloading blessings-1.6.tar.gz 12:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:21:54 INFO - Installing collected packages: blessings 12:21:54 INFO - Running setup.py install for blessings 12:21:55 INFO - Successfully installed blessings 12:21:55 INFO - Cleaning up... 12:21:55 INFO - Return code: 0 12:21:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:21:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:21:55 INFO - Reading from file tmpfile_stdout 12:21:55 INFO - Current package versions: 12:21:55 INFO - blessings == 1.6 12:21:55 INFO - blobuploader == 1.2.4 12:21:55 INFO - docopt == 0.6.1 12:21:55 INFO - manifestparser == 1.1 12:21:55 INFO - mozInstall == 1.12 12:21:55 INFO - mozcrash == 0.17 12:21:55 INFO - mozdebug == 0.1 12:21:55 INFO - mozdevice == 0.48 12:21:55 INFO - mozfile == 1.2 12:21:55 INFO - mozhttpd == 0.7 12:21:55 INFO - mozinfo == 0.9 12:21:55 INFO - mozleak == 0.1 12:21:55 INFO - mozlog == 3.1 12:21:55 INFO - moznetwork == 0.27 12:21:55 INFO - mozprocess == 0.22 12:21:55 INFO - mozprofile == 0.28 12:21:55 INFO - mozrunner == 6.11 12:21:55 INFO - mozscreenshot == 0.1 12:21:55 INFO - mozsystemmonitor == 0.0 12:21:55 INFO - moztest == 0.7 12:21:55 INFO - mozversion == 1.4 12:21:55 INFO - psutil == 3.1.1 12:21:55 INFO - requests == 1.2.3 12:21:55 INFO - wsgiref == 0.1.2 12:21:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12: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')]} 12:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12: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')]} 12:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:55 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 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:55 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 12:21:55 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 12:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:55 INFO - 'HOME': '/Users/cltbld', 12:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:55 INFO - 'LOGNAME': 'cltbld', 12:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:55 INFO - 'MOZ_NO_REMOTE': '1', 12:21:55 INFO - 'NO_EM_RESTART': '1', 12:21:55 INFO - 'PAGER': '/bin/cat', 12:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:55 INFO - 'PWD': '/builds/slave/test', 12:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:55 INFO - 'SHELL': '/bin/bash', 12:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:55 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:55 INFO - 'USER': 'cltbld', 12:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:55 INFO - 'XPC_FLAGS': '0x0', 12:21:55 INFO - 'XPC_SERVICE_NAME': '0', 12:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 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)) 12:21:55 INFO - Cleaning up... 12:21:55 INFO - Return code: 0 12:21:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12: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')]} 12:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12: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')]} 12:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12: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', 'jsonschema'] in /builds/slave/test/build 12: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 jsonschema 12:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:55 INFO - 'HOME': '/Users/cltbld', 12:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:55 INFO - 'LOGNAME': 'cltbld', 12:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:55 INFO - 'MOZ_NO_REMOTE': '1', 12:21:55 INFO - 'NO_EM_RESTART': '1', 12:21:55 INFO - 'PAGER': '/bin/cat', 12:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:55 INFO - 'PWD': '/builds/slave/test', 12:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:55 INFO - 'SHELL': '/bin/bash', 12:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:55 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:55 INFO - 'USER': 'cltbld', 12:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:55 INFO - 'XPC_FLAGS': '0x0', 12:21:55 INFO - 'XPC_SERVICE_NAME': '0', 12:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:56 INFO - Downloading/unpacking jsonschema 12:21:56 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 12:21:56 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 12:21:56 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 12:21:56 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 12:21:56 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 12:21:56 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 12:21:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:21:58 INFO - Downloading/unpacking functools32 (from jsonschema) 12:21:58 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 12:21:58 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 12:21:58 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 12:21:58 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 12:21:58 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 12:21:58 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 12:21:58 INFO - Downloading functools32-3.2.3-2.tar.gz 12:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:21:58 INFO - warning: no files found matching '*.txt' 12:21:58 INFO - no previously-included directories found matching 'build' 12:21:58 INFO - no previously-included directories found matching 'dist' 12:21:58 INFO - no previously-included directories found matching '.git*' 12:21:58 INFO - Installing collected packages: jsonschema, functools32 12:21:59 INFO - Running setup.py install for functools32 12:21:59 INFO - warning: no files found matching '*.txt' 12:21:59 INFO - no previously-included directories found matching 'build' 12:21:59 INFO - no previously-included directories found matching 'dist' 12:21:59 INFO - no previously-included directories found matching '.git*' 12:21:59 INFO - Successfully installed jsonschema functools32 12:21:59 INFO - Cleaning up... 12:21:59 INFO - Return code: 0 12:21:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f662a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f672420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f8c6f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, '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.xm5v96Dzn1/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.REjYqCmBB9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:59 INFO - 'HOME': '/Users/cltbld', 12:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:59 INFO - 'LOGNAME': 'cltbld', 12:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:59 INFO - 'MOZ_NO_REMOTE': '1', 12:21:59 INFO - 'NO_EM_RESTART': '1', 12:21:59 INFO - 'PAGER': '/bin/cat', 12:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:59 INFO - 'PWD': '/builds/slave/test', 12:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:59 INFO - 'SHELL': '/bin/bash', 12:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:21:59 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:59 INFO - 'USER': 'cltbld', 12:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:59 INFO - 'XPC_FLAGS': '0x0', 12:21:59 INFO - 'XPC_SERVICE_NAME': '0', 12:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:59 INFO - Downloading/unpacking flake8 12:21:59 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 12:21:59 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 12:21:59 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 12:21:59 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 12:21:59 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 12:21:59 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 12:22:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:22:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:22:01 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 12:22:01 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 12:22:01 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 12:22:01 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 12:22:01 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 12:22:01 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 12:22:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:22:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:22:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:22:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:22:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:22:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:22:03 INFO - no previously-included directories found matching 'docs/_build' 12:22:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:22:03 INFO - Running setup.py install for pep8 12:22:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:22:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:22:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:22:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:22:03 INFO - no previously-included directories found matching 'docs/_build' 12:22:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:22:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:22:03 INFO - Cleaning up... 12:22:03 INFO - Return code: 0 12:22:03 INFO - Running post-action listener: _resource_record_post_action 12:22:03 INFO - Running post-action listener: _start_resource_monitoring 12:22:03 INFO - Starting resource monitoring. 12:22:03 INFO - ##### 12:22:03 INFO - ##### Running install step. 12:22:03 INFO - ##### 12:22:03 INFO - Running pre-action listener: _resource_record_pre_action 12:22:03 INFO - Running main action method: install 12:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:03 INFO - Reading from file tmpfile_stdout 12:22:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:22:03 INFO - Reading from file tmpfile_stdout 12:22:03 INFO - Output received: 12:22:03 INFO - Usage: mozinstall [options] installer 12:22:03 INFO - Options: 12:22:03 INFO - -h, --help show this help message and exit 12:22:03 INFO - -d DEST, --destination=DEST 12:22:03 INFO - Directory to install application into. [default: 12:22:03 INFO - "/builds/slave/test"] 12:22:03 INFO - --app=APP Application being installed. [default: firefox] 12:22:03 INFO - mkdir: /builds/slave/test/build/application 12:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:22:25 INFO - Reading from file tmpfile_stdout 12:22:25 INFO - Output received: 12:22:25 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:22:25 INFO - Running post-action listener: _resource_record_post_action 12:22:25 INFO - ##### 12:22:25 INFO - ##### Running run-tests step. 12:22:25 INFO - ##### 12:22:25 INFO - Running pre-action listener: _resource_record_pre_action 12:22:25 INFO - Running main action method: run_tests 12:22:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:22:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:22:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:22:25 INFO - 2016-07-08 12:22:25.671 screenresolution[1920:11581] starting screenresolution argv=screenresolution get 12:22:25 INFO - 2016-07-08 12:22:25.674 screenresolution[1920:11581] Display 0: 1600x1200x32@60 12:22:25 INFO - 2016-07-08 12:22:25.679 screenresolution[1921:11583] starting screenresolution argv=screenresolution list 12:22:25 INFO - Available Modes on Display 0 12:22:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:22:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:22:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:22:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:22:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:22:25 INFO - Intel Iris: 12:22:25 INFO - Chipset Model: Intel Iris 12:22:25 INFO - Type: GPU 12:22:25 INFO - Bus: Built-In 12:22:25 INFO - VRAM (Dynamic, Max): 1536 MB 12:22:25 INFO - Vendor: Intel (0x8086) 12:22:25 INFO - Device ID: 0x0a2e 12:22:25 INFO - Revision ID: 0x0009 12:22:25 INFO - Displays: 12:22:25 INFO - Display: 12:22:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:22:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:22:25 INFO - Display Serial Number: 1600x1200 60 12:22:25 INFO - Main Display: Yes 12:22:25 INFO - Mirror: Off 12:22:25 INFO - Online: Yes 12:22:25 INFO - Rotation: Supported 12:22:25 INFO - Return code: 0 12:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:22:25 INFO - Python 2.7.3 12:22:25 INFO - Return code: 0 12:22:25 INFO - grabbing minidump binary from tooltool 12:22:25 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')]} 12:22:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f501e00>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:22:25 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 12:22:25 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 12:22:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:22:25 INFO - Return code: 0 12:22:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:22:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:22:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:22:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:22:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:22:27 INFO - Return code: 0 12:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xm5v96Dzn1/Render', 12:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:27 INFO - 'HOME': '/Users/cltbld', 12:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:27 INFO - 'LOGNAME': 'cltbld', 12:22:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:22:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:27 INFO - 'MOZ_NO_REMOTE': '1', 12:22:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:22:27 INFO - 'NO_EM_RESTART': '1', 12:22:27 INFO - 'PAGER': '/bin/cat', 12:22:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:27 INFO - 'PWD': '/builds/slave/test', 12:22:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:27 INFO - 'SHELL': '/bin/bash', 12:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REjYqCmBB9/Listeners', 12:22:27 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:27 INFO - 'USER': 'cltbld', 12:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:27 INFO - 'XPC_FLAGS': '0x0', 12:22:27 INFO - 'XPC_SERVICE_NAME': '0', 12:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:22:27 INFO - mozversion application_buildid: 20160708040727 12:22:27 INFO - mozversion application_changeset: 24e82cb41040b1f515135ae3ba8b6bddf234d509 12:22:27 INFO - mozversion application_display_name: Firefox 12:22:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:22:27 INFO - mozversion application_name: Firefox 12:22:27 INFO - mozversion application_remotingname: firefox 12:22:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:22:27 INFO - mozversion application_vendor: Mozilla 12:22:27 INFO - mozversion application_version: 48.0 12:22:27 INFO - mozversion platform_buildid: 20160708040727 12:22:27 INFO - mozversion platform_changeset: 24e82cb41040b1f515135ae3ba8b6bddf234d509 12:22:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:22:27 INFO - mozversion platform_version: 48.0 12:22:27 INFO - using testdate: 1468005747 12:22:27 INFO - actual date: 1468005747 12:22:27 INFO - starting webserver on 'localhost:50228' 12:22:27 INFO - SUITE-START | Running 2 tests 12:22:27 INFO - TEST-START | dromaeo_css 12:22:27 INFO - operating with platform_type : mac_ 12:22:27 INFO - Initialising browser for dromaeo_css test... 12:22:27 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmp_2eL7l/profile http://localhost:50228/getInfo.html) 12:22:29 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 12:22:29 INFO - PROCESS | 1939 | colorDepth:24 12:22:29 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 12:22:29 INFO - PROCESS | 1939 | __metrics 12:22:29 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:22:29 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:22:30 INFO - TEST-INFO | 1939: exit 0 12:22:30 INFO - Browser initialized. 12:22:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:22:30 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmp_2eL7l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:41 INFO - PROCESS | 1942 | RSS: Main: 161849344 12:22:41 INFO - PROCESS | 1942 | 12:23:33 INFO - PROCESS | 1942 | 1468005813037 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] 12:25:43 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:50228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50228/tests/dromaeo/cssquery-ext.html) 12:25:44 INFO - PROCESS | 1942 | RSS: Main: 261406720 12:25:44 INFO - PROCESS | 1942 | 12:28:46 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:50228/tests/dromaeo/cssquery-ext.html (next: http://localhost:50228/tests/dromaeo/cssquery-jquery.html) 12:28:47 INFO - PROCESS | 1942 | RSS: Main: 298504192 12:28:47 INFO - PROCESS | 1942 | 12:31:49 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:50228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50228/tests/dromaeo/cssquery-mootools.html) 12:31:50 INFO - PROCESS | 1942 | RSS: Main: 298266624 12:31:50 INFO - PROCESS | 1942 | 12:34:52 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:50228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50228/tests/dromaeo/cssquery-prototype.html) 12:34:52 INFO - PROCESS | 1942 | RSS: Main: 294653952 12:34:52 INFO - PROCESS | 1942 | 12:37:56 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:50228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50228/tests/dromaeo/cssquery-yui.html) 12:37:56 INFO - PROCESS | 1942 | RSS: Main: 265768960 12:37:56 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:50228/tests/dromaeo/cssquery-yui.html (next: http://localhost:50228/tests/dromaeo/cssquery-dojo.html) 12:40:59 INFO - PROCESS | 1942 | RSS: Main: 312111104 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | __start_tp_report 12:40:59 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 12:40:59 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 12:40:59 INFO - PROCESS | 1942 | |i|pagename|runs| 12:40:59 INFO - PROCESS | 1942 | |0;dojo.html;3213.807171569706;3536.487209354643;3587.121155316946;3612.349777040132;3646.3436581415335;5750.856228594287;5765.7981970631035;5786.826395208145;5795.681237531934;5834.329052159003;5775.2203452533895;5796.420357964212;5803.129530570416;5804.477597016267;5809.564282678805;5200.8699782505555;5248.370195576536;5253.973730131344;5284.075286824809;5316.893662126755;5324.41431442541;5354.357477102733;5373.865653358678;5378.623496355257;5384.973075134619;5271.815486457975;5309.0709125903;5327.46725327468;5338.946610533903;5356.169793681966;5333.893322133555;5381.704006279653;5384.6769193848495;5407.891842163174;5431.891362172774;4243.299855523835;4492.16894874446;4887.218045112801;5113.821016264413;5267.078261304257;5666.943330566683;5705.515031222351;5731.598788084836;5735.856603584902;5770.394108618577;6228.96885515574;6234.407731265568;6234.625922444668;6235.189425374718;6253.437190652863;6123.740204357149;6166.136740856284;6287.654179020198;6303.810885673392;6309.747610095591;5978.233712760976;5991.64050156992;6015.578909476382;6046.848828779272;6047.244094488189;6413.390727880844;6416.615003099829;6417.1657684500715;6424.453921416639;6427.775027874096;6125.418085281891;6187.876242475215;6273.686315684197;6280.089387038854;6286.025666021774;6274.372562743689;6302.086197501337;6304.243490781044;6312.24253089623;6331;6200.224971878515;6265.4361107499635;6265.937340626535;6268.1851359323;6273.65494897785;6153.5692501525355;6179.28938172116;6238.594491358047;6266.091416744546;6293.905591416133;5008.17365134756;5043.924341134886;5047.646664733434;5047.798088076509;5066.08810414129;4513.81944722214;4531.84138555149;4539.7049191802425;4568.2690769477385;4574.4739354973935;6026.457618814327;6095.478912709122;6104.4505994460715;6113.445900579432;6115.021596544531;6284.685765711696;6307.117003619497;6311.621302721851;6315.147455093595;6317.305096439388;5894.32215295238;5963.4334738199805;6026.758929642853;6083.6045657032155;6085.939140608626;5559.193916882029;5567.192757050286;5581.10702287632;5584.106542953108;5611.7755289788765;6538.346165383424;6540.215174179034;6546.18172728409;6550.0829883816305;6552.246491653425;5221.530062294411;6196.0705894116245;6240.313565507699;6284;6299.622022678654;6468.5472016958365;6477.967610161919;6506.512011599054;6522.510811689048;6533.967330163318;5465.9726701366235;6270.341614130451;6279.748810047729;6280.748770049329;6294.24469063697;6144.924638188389;6217.533684973736;6241.687915604292;6270.717817698123;6285;6235.937640623536;6243.438090571689;6255.0617407387435;6263.498920086291;6268.749250029948;5130.923036154386;6243.344448832989;6247.812565623039;6254.749810007549;6271.216097987752;6016.127661489147;6026.243155966578;6126.908096378469;6176.104464852661;6177.876442471215;6068.939310607014;6122.092878330954;6212.192414986023;6245.750169993149;6258.624482531062;5374.811881584125;5930.140129681258;6206.813795586139;6211;6227.9377206227355 12:40:59 INFO - PROCESS | 1942 | |1;ext.html;526.2711145064087;550.3423409026215;566.9288401736219;596.859737961579;601.1163589523404;24839;32685.019449416526;32775.83612081936;32826.01521954339;33055.66944330559;33239.66760332396;34122.317553648914;34532.79135230267;34534.65465345352;42368.15263694732;9719.757006074835;9872.012798720143;9882;9886.703398898044;9889.06053924876;9670.516474176298;9753.317267791257;9796.314258001943;9798.020197980217;9806.068423499755;9335.3465257432;9790.9510452448;12078.577249796259;12357.567485138023;12374;11813.7637247255;11851.822222666668;11871.406429678567;11876;11892.940535297312;9286.628534858597;11659;11789.58736444229;11798;11814;39540;39550;39582.60417395818;39636.40545391821;40929.38605920914;3353.765236433451;3721.1999420124534;3799.563050249229;3861.1891502784447;3902.0830104925603;28298.15105546837;28382.57426138589;28419.147425577266;29495.70504294951;29887;2750.0787236275874;2789.4839454700946;2807.5928990296293;2823.647044119485;2831.306329949155;505384;511865;514262;514454;515402;73431.26568734244;73960.26039739641;74169.6291518539;74174;74241.62879185677;65075;77867.61066194632;78390.6080469594;78529.60735196287;78579.21420785833;18825.34111306098;19353.612927741648;19360.225590976486;19365.4190374289;19425.251727344606;14725.410983560752;14848.960572759803;14852.851471485363;14853.628659283388;14870.479533216285;18757.624847502975;21051.8947405262;21158.942052897295;21839.563208735737;21866;8436;8519.95740021296;8573.18554737299;8644.654213831376;8773.07882672323;950.2255661635745;958.9089036541517;1114.6990312615549;1201.4053043743404;1221.4991853340266;30685.693143068285;30740.385192296475;30978.380432391226;31363.529547056813;31435.685643143734;1090.52450936374;1096.5668560918552;1146.4668928948079;1169.5263418315596;1297.3967105295894;14532.782008269884;15628.92185539065;15679.372825087097;15722.685546289238;15995.360185592679;603889;605739;612092;612640;614695;64464.355356445834;65048.674756625915;65129;65200.34799651943;65375;1566.7571526413424;1581.351645825206;1582.849629285216;1601.8638415734792;1605.149270886432;29630.81476740906;29771;29833.55249671213;30472.84763576168;30653.386932261677;54039.72980135074;55050;55827.720861395435;55867;55896.44103558912;567143;571967;572575;573108;574216;35079.298414031065;43750;43820.12359752851;45285;45385.54614453813;45026.77486612546;48026;48171;48575.75712121417;48668.756656216494;36434.63565364418;36463;36569.45145822868;36634.816825916765;37380.81309593435;37606.435903462014;37898;38347.2330553393;38497;38854.222915542094;37594;37702.434463483616;38146.618533814304;38878.805605971786;38979.02552436212;31917.14394045216;33025.83487082549;33580;33647.4952875712;33711.4943275856;24832;24972.500549988534;24993.375165621004;25393.365165871;25524.61713074268 12:40:59 INFO - PROCESS | 1942 | |2;jquery.html;419.6370139829045;420.1869382744389;421.1808033375083;421.8312674930028;431.49946062567426;20418;20500;20766.48083797902;20951;21213.893930530347;20624.1750329987;21033.05351259193;21665.043087298614;21868.23461178859;21898.343049708496;5980;6304.464120549759;6307.558470907038;6310.9684451577905;6318.589291696048;4599.862004139881;4844.224924012159;6141;6327.240731112273;6331.018692102732;6994.6152961587095;6997;6997.615131167784;7002.544834585761;7009.649517524129;6684.197896252432;6931.896021559681;6939.063226464464;6940.13248343957;6972.616506092163;3705.3330400527893;5863.648181109147;6064.4541991221;6114.449699527064;6114.510839132859;26909.32726681829;27317.863410683018;27467.86266068677;27488.862555687294;27644.86177569099;2304.573653874039;2357.622780355135;2365.47959448921;2468.938276543083;2815.4650616382824;15720;16017.679646407123;16256.674866502604;16298.511044668561;16481.92867463611;1750.1949103412446;1810.2758896441396;1832.7159290310024;1895.185070419719;1897.127321432143;79533.60233198854;105639;113419.43290283579;114818;116228.41885790601;9478.90521094794;11431.828522572168;11646.592369267035;11686.415679216005;11926.761464770656;15283.38866445352;15286.541403757905;15288.929774915649;15324.846751532565;15350.462733804263;8723.694670686495;9104.544772761335;9182.95408522953;9219.539023048956;9266.860997085048;9172.72188494516;9179.219766319804;9194.448333100036;9305.302102342352;9513.571889265;3902.3951287550476;3964.354792760972;4763.571278584944;4829.275608658659;4882.292067650171;1712.24661149094;1945.727598136262;2005.929792457257;2042.7539201087325;2114.228306668055;887.3655336434481;998.9300748947504;1044.2011860926398;1064.6752740414092;1072.265498133781;20545.48636284075;21318.04068816907;21340.253091142047;21373.25193618247;21506.784932150476;973.9805203895883;982.2485798364257;1056.3661802918189;1060.7825395793843;1061.5806756331206;10505.264631475877;11273;11288.0969522438;11298.604548840916;11315.490802913888;624598;627627;629783;655431;661883;122954.38522807328;126189;128761.35619321844;129231;129287;1021.8518314844306;1033.0083120204538;1035.891231420705;1038.6416686243385;1068.540527573151;18271.54321141982;18676.81323186751;18692;18888;19009.14458849383;51315;51382.743086285824;53090.73454632702;53411;54485.72757136189;81176.59411702903;81338;83843.4078654551;84255.578722106;84705.72941405761;8382.874256886029;8402.579871006548;8440.746777596682;8454.23911847912;8529.488230706178;9395.295352848427;12329;13230.801537977131;13472.730545389233;13721.382537786023;11380.886191137983;11605.477753500943;11824.527018919147;11963.162578619413;12107.031437485157;11355.943220283845;11420.828687569527;11588.942055290006;11692.181547291608;11915.940420297842;11711.472983715583;11717.765644686888;11935.880641193824;12113.454894529592;12280.815787763013;8409.159084091541;8439.95780021096;8469.322454203771;8519.403641745019;8579.957100214458;8692.130786921258;8998.865017024618;10935.070519005974;11642.592509262135;11848.822267665819 12:40:59 INFO - PROCESS | 1942 | |3;mootools.html;1453.6002599285202;1516.4464970285846;1519.2251951504734;1788.5707430216753;1945.8540609454285;8047.597620118992;8668.956655216723;8807.163319484654;8809.867851982226;8832.381733278666;8533.701320453785;8813.338999575042;8814.515201663906;8815.51514666693;8818.691345802898;2521.1554129366637;2544.0459827564664;2547.9872600636972;2548.3374322676054;2551.706553746315;2490.2653717153394;2542.5677634802128;2544.3766277262002;2545.2237067694346;2562.3209849389928;1893;2518.1941778630844;2549.3499157714755;2550.298667866333;2560.116759717894;2514.346269969821;2530.708968468631;2549.6430499730054;2551.413174969758;2551.6045013023013;2467.962980555293;2523.4448421347274;2529.6711427514488;2531.3545046013332;2537.949241015188;8691.08743581921;8876.511791851446;8949.686760963335;8953.238974687094;8957.910420895838;5182;5294.682319060868;5499.312585926759;5701.885962280772;5715.057015592422;3295.1597342677464;3575.249197668493;3709.536307961505;3747.213085252073;3781.1870447853844;1034.1106648282546;1061.0609610494691;1062.1184416933927;1063.346042184059;1086.5870969031796;12163.695907602292;12293;12454.626361209179;12471.688207794787;12474.563390281297;3707.9258414832093;3988.880333589997;4833.32333473314;4859;4861.781219845116;9460.763480913032;9469.289803264783;9487.525623718786;9488.33581649278;9491;5513.7794488220825;5515.393306736256;5519.972400138054;5523.447655234444;5535.806246781424;4592.908141837212;4723.338732577442;4987.775550100254;5089.541941225233;5185.196294574347;3952.407138929126;3986.1629057897585;4008.2384346974563;4045.6763458923215;4107.096438783463;1236.8515778106505;1254.3790823542222;1257.5598540510748;1263.2736176698436;1269.9428525716364;825.014108140778;880.4893161966045;889.755317287751;925.6713866577268;957.2820384711466;5870.503021729432;6100.3899610039525;6121.357257487989;6133.26400831903;6290.465310448663;920.9777147366552;967.1005964452986;968.1673760565908;986.0927946289288;1002.4135880509983;3919.804009799556;3999.960000399959;4031.5162180538523;4032.3951407288555;4115.032967252693;10737.731556710989;10926.79805221425;11102.389368584643;11121.499532521057;11247.887521124847;10964.671059868217;10982.780344393228;11046.834297485544;11107;11114.388708620942;8468.618912148968;8480.703175388831;8601.526916019679;8636;8774.605142768463;5849.970750146222;5852.209951656642;5855.092460668603;5987.191729116514;6039.033754599243;9597.90402095998;9725.173360264456;10114.494275286059;10123.341982771097;10128.24038197153;9983.60065597368;10231.130353919703;10238.692839214835;10304.484775761332;10436.686899393031;6605.372489613479;6739.05653208561;6743.966280168568;7254.818629534093;7393.704251830081;7896;7927.84144317122;8263.504189748634;8474.915250847413;8498.192671696179;7350.77947661571;7414.62926853666;7505.812354690958;7634.656440460081;7650.4644674872225;6541.443977261795;7272.636368181676;7368.521046131984;7372.262773722585;7443.144038435649;7013.614251216232;7013.684384202825;7040.683169257293;7046.964765176142;7057.36483716468;4781.760911954458;4792.832250871203;4800.06398752254;4877.926831097466;4936.506349365035;4233.343831706091;4926.531979461945;4952.975235123801;4977.278294647328;5111.667741596784 12:40:59 INFO - PROCESS | 1942 | |4;prototype.html;302.7033507162981;327.7443593996683;333.0126175888491;343.25856150714463;345.5283537970671;6752.155980502431;6755.62844043578;6763.7294508219675;6774.356436138572;6780.864382712342;6501.252355979063;6567.671616419184;6678.766243181478;6722.260551339349;6774.559653622523;4956.40523137224;4990.8003679852945;4995.35060442142;5004.224345226496;5042.773075211624;5002.099622068026;5006.249062640597;5008.4240312364;5023.472535383787;5025.497450254982;4765.1660959331975;4842.97578512107;4943.703377797326;4943.9258411123865;4962.851114466572;4899.730514821684;4925.694690906913;4928.137575924199;4946.431160416562;4954.851354459354;4243.851465198734;4252.574742525753;4253.425787518708;4262.1688770689725;4272.9145417091795;6471.870562588769;6503.447206987412;6540.803775886683;6544.214694236674;6567.080608714784;5911.349751527328;5934.376890426486;5937.228160339171;5946.791862284843;5963.7912673056235;6861.828454288683;7134.678939447737;7141.678624461912;7191.280871912767;7191.424686025105;4606.286025666027;4638.95361046392;4643.489216186217;4651.976740116312;4655.883602909955;7487.401007919408;7585.696572137108;7592.392608591299;7599.468037237396;7610.353119984808;4725.149473094807;4726.740029298421;4730.195866702668;4746.810127594927;4755.785989630475;4605.286180642006;4801.639877009238;4802.351682522884;4803.471618122004;4818.831340903052;5433.1192953969385;5721.685307308055;5750.971245143747;5755.740991655385;5785.508231800344;7119.145702515628;7166.068411106627;7173.5337203081635;7183.317584829538;7186.7125314986815;1591.053323272651;1649.0847579593442;1674.715298399275;1675.4303536797547;1678.8908720933346;931.9347645664739;999.9050090241416;1053.8629978102797;1122.1135303110614;1151.021631613122;6872.553284036621;6907.861842763116;6921;6928.549644273106;6953.443724502028;7441.069866266716;7452.552846829208;7456.440766942501;7460.776176714708;7473.103227612722;5261.3686357637325;6920.688569014406;6923.6420336136935;6927.376536111673;6957.1651401832105;3308.8345582720767;3799.525059367579;3854.8265328060306;3950.7234493585747;4011.3180759270995;6312.715927783261;7823.843523129505;7880.093789214314;7884.17216192303;7899.249571290718;7059.5764254144915;7062.329078737512;7739.729109481141;7807.882881756778;7819.5699236541395;7929.286364227132;7949.960250198712;7951.966244388308;7959.80100497469;7959.920400795918;7072.504924655224;7294.270572942663;7576.3938884887975;7587.2412758723685;7606.8098297543;7350.70597176107;7396.149442814145;7403.18564957865;7421.332080112816;7431.8142046449275;7933.404994625311;7964.402669799904;7964.522128672298;7965.243301886311;7970;4701.811927522861;4769.785359658755;4771.974025584516;4773.400910694956;4780.569748722632;7179.138503379628;7226;7427.22014188524;7499.287567681062;7504.737334193277;7224.602646854263;7251.963740181265;7262.201157872524;7264.34620884123;7267.818304542428;7257.782266532013;7263.128424589083;7269.563826170447;7271.309225623558;7275.708971641075;6231.158793562992;6696.397324240842;7036.979637952571;7091.60996145217;7097.645117744195;5810.215620891126;6979.476539259677;7220.819479513054;7291.4166866649475;7299.452541059336;7013.473989450709;7022.859542809218;7186.784396468114;7187.389071928945;7203.315685009916 12:40:59 INFO - PROCESS | 1942 | |5;yui.html;709.3155105323362;726.8836986082226;756.6670664907443;771.1941021632393;777.6422845491078;15078;15393.615159621015;15449.15029673369;15458.458953936615;15534.757219422447;14178.71642567148;14364.425422983068;14378.281085945688;14392.280385980685;14407.63980900478;780.7228433905967;807.3178164451059;949.852772820214;963.6145541783272;965.1747755668907;967.4147140979711;973.9366941148803;973.9464329461877;976.3995142987047;978.4373984958642;835.2983493865152;1033.3954636537635;1036.3626369782567;1036.4196050211906;1044.305536818015;846.2426128614886;846.5428668518992;848.0417128644654;1011.640867492037;1038.5586125896464;775.9262870027338;778.8169780101672;778.8364443466879;779.3375630713905;779.9376049915993;15270.847291527165;15323.693526129526;15337.386504539805;15348.309326080353;15356.616084597863;10574.365538067741;12662.36688165588;12712;12884.484620615163;13093.934530327288;9061.864072038925;9767.804643907153;10179.694609161737;10350.378977261389;10406.271560990734;3493.9650603494147;3590.120420496969;3592.119930616989;3604.2250916052913;3677.264547090566;87344.12655873424;88063;88324;88676;89295.55352223197;9730.464824434725;9750.804983900282;9767.658131965347;9810.16613587853;10159.593616255266;18278.360257390927;22484.662730059063;22526.986285617186;22546.436339091324;22654;6584.934150658528;7791.142974272825;7996.480228785111;8031.477953932976;8035.799105022421;3904.7852368120025;4262.616364527207;4317.9136417271475;4342.370356298319;4342.652587792969;3189.6810318968382;3702.204026134394;3770.886873393748;3772.660460558563;3782.167923056923;613.6471528870917;663.9203295604558;672.9327067293232;674.3829396102609;675.0414411535534;522.5741021067818;568.6701713006446;618.116093985605;624.4286477872696;624.865653884417;6765.898511522331;8222.342212622976;8254.092049874509;8260.215279548433;8596.312295016383;517.3119750731518;601.3865856826013;617.4658920034138;617.5183356981562;634.5589815078476;3704.259148170323;3900.414937759302;3918.549366822852;3933.6853051755793;4104.138130992525;92945;95574.13277601684;97141;97146;97196.51401742946;79510;80292;80823.5958820202;81921.3615727682;81984.59007704923;69754;72163;72218.27781722115;73394;73780;7265.891011634935;7646.906083969107;7822;7860.960695196487;8244.381671374551;50151.74924125356;50193.49806502034;50336.49663503318;50772.74613626908;51295.691693874614;52210.47789522056;69796.95304570334;72164;72269.91595126182;73675;10319.845202321821;10352.689419317432;11023.559057637605;11421.657350279505;11622.360770157722;11870.169088163746;12084.697882553006;12186.939065304618;12439.813402799145;12583.182093163916;11812.763744724885;11944.701382465508;11969.880301196876;12032.699182520508;12145.878541214475;11656.30062196271;11730.589429369931;11757.647270581896;11763.000144987773;11803.940980295043;11554.94222528882;11613.825792613286;11618.6514404568;11620;11620.5351785931;9517.238620910171;9705;9799.902000979899;9852.704418867446;10015.998400160217;8133.959330203548;9571.1864491519;9729.318947673595;9764.804703906024;9789.363691360039 12:40:59 INFO - PROCESS | 1942 | __end_tp_report 12:40:59 INFO - PROCESS | 1942 | __start_cc_report 12:40:59 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,975 12:40:59 INFO - PROCESS | 1942 | __end_cc_report 12:40:59 INFO - PROCESS | 1942 | __startTimestamp1468006859328__endTimestamp 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | ------- Summary: start ------- 12:40:59 INFO - PROCESS | 1942 | Number of tests: 6 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5832.42 Median:6115.02 stddev:653.51 (10.7%) stddev-sans-first:622.39 12:40:59 INFO - PROCESS | 1942 | Values: 3213.8 3536.5 3587.1 3612.3 3646.3 5750.9 5765.8 5786.8 5795.7 5834.3 5775.2 5796.4 5803.1 5804.5 5809.6 5200.9 5248.4 5254.0 5284.1 5316.9 5324.4 5354.4 5373.9 5378.6 5385.0 5271.8 5309.1 5327.5 5338.9 5356.2 5333.9 5381.7 5384.7 5407.9 5431.9 4243.3 4492.2 4887.2 5113.8 5267.1 5666.9 5705.5 5731.6 5735.9 5770.4 6229.0 6234.4 6234.6 6235.2 6253.4 6123.7 6166.1 6287.7 6303.8 6309.7 5978.2 5991.6 6015.6 6046.8 6047.2 6413.4 6416.6 6417.2 6424.5 6427.8 6125.4 6187.9 6273.7 6280.1 6286.0 6274.4 6302.1 6304.2 6312.2 6331.0 6200.2 6265.4 6265.9 6268.2 6273.7 6153.6 6179.3 6238.6 6266.1 6293.9 5008.2 5043.9 5047.6 5047.8 5066.1 4513.8 4531.8 4539.7 4568.3 4574.5 6026.5 6095.5 6104.5 6113.4 6115.0 6284.7 6307.1 6311.6 6315.1 6317.3 5894.3 5963.4 6026.8 6083.6 6085.9 5559.2 5567.2 5581.1 5584.1 5611.8 6538.3 6540.2 6546.2 6550.1 6552.2 5221.5 6196.1 6240.3 6284.0 6299.6 6468.5 6478.0 6506.5 6522.5 6534.0 5466.0 6270.3 6279.7 6280.7 6294.2 6144.9 6217.5 6241.7 6270.7 6285.0 6235.9 6243.4 6255.1 6263.5 6268.7 5130.9 6243.3 6247.8 6254.7 6271.2 6016.1 6026.2 6126.9 6176.1 6177.9 6068.9 6122.1 6212.2 6245.8 6258.6 5374.8 5930.1 6206.8 6211.0 6227.9 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:71296.54 Median:20551.01 stddev:150924.98 (734.4%) stddev-sans-first:151254.83 12:40:59 INFO - PROCESS | 1942 | Values: 526.3 550.3 566.9 596.9 601.1 24839.0 32685.0 32775.8 32826.0 33055.7 33239.7 34122.3 34532.8 34534.7 42368.2 9719.8 9872.0 9882.0 9886.7 9889.1 9670.5 9753.3 9796.3 9798.0 9806.1 9335.3 9791.0 12078.6 12357.6 12374.0 11813.8 11851.8 11871.4 11876.0 11892.9 9286.6 11659.0 11789.6 11798.0 11814.0 39540.0 39550.0 39582.6 39636.4 40929.4 3353.8 3721.2 3799.6 3861.2 3902.1 28298.2 28382.6 28419.1 29495.7 29887.0 2750.1 2789.5 2807.6 2823.6 2831.3 505384.0 511865.0 514262.0 514454.0 515402.0 73431.3 73960.3 74169.6 74174.0 74241.6 65075.0 77867.6 78390.6 78529.6 78579.2 18825.3 19353.6 19360.2 19365.4 19425.3 14725.4 14849.0 14852.9 14853.6 14870.5 18757.6 21051.9 21158.9 21839.6 21866.0 8436.0 8520.0 8573.2 8644.7 8773.1 950.2 958.9 1114.7 1201.4 1221.5 30685.7 30740.4 30978.4 31363.5 31435.7 1090.5 1096.6 1146.5 1169.5 1297.4 14532.8 15628.9 15679.4 15722.7 15995.4 603889.0 605739.0 612092.0 612640.0 614695.0 64464.4 65048.7 65129.0 65200.3 65375.0 1566.8 1581.4 1582.8 1601.9 1605.1 29630.8 29771.0 29833.6 30472.8 30653.4 54039.7 55050.0 55827.7 55867.0 55896.4 567143.0 571967.0 572575.0 573108.0 574216.0 35079.3 43750.0 43820.1 45285.0 45385.5 45026.8 48026.0 48171.0 48575.8 48668.8 36434.6 36463.0 36569.5 36634.8 37380.8 37606.4 37898.0 38347.2 38497.0 38854.2 37594.0 37702.4 38146.6 38878.8 38979.0 31917.1 33025.8 33580.0 33647.5 33711.5 24832.0 24972.5 24993.4 25393.4 25524.6 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:36423.77 Median:12101.46 stddev:106181.39 (877.4%) stddev-sans-first:106444.85 12:40:59 INFO - PROCESS | 1942 | Values: 419.6 420.2 421.2 421.8 431.5 20418.0 20500.0 20766.5 20951.0 21213.9 20624.2 21033.1 21665.0 21868.2 21898.3 5980.0 6304.5 6307.6 6311.0 6318.6 4599.9 4844.2 6141.0 6327.2 6331.0 6994.6 6997.0 6997.6 7002.5 7009.6 6684.2 6931.9 6939.1 6940.1 6972.6 3705.3 5863.6 6064.5 6114.4 6114.5 26909.3 27317.9 27467.9 27488.9 27644.9 2304.6 2357.6 2365.5 2468.9 2815.5 15720.0 16017.7 16256.7 16298.5 16481.9 1750.2 1810.3 1832.7 1895.2 1897.1 79533.6 105639.0 113419.4 114818.0 116228.4 9478.9 11431.8 11646.6 11686.4 11926.8 15283.4 15286.5 15288.9 15324.8 15350.5 8723.7 9104.5 9183.0 9219.5 9266.9 9172.7 9179.2 9194.4 9305.3 9513.6 3902.4 3964.4 4763.6 4829.3 4882.3 1712.2 1945.7 2005.9 2042.8 2114.2 887.4 998.9 1044.2 1064.7 1072.3 20545.5 21318.0 21340.3 21373.3 21506.8 974.0 982.2 1056.4 1060.8 1061.6 10505.3 11273.0 11288.1 11298.6 11315.5 624598.0 627627.0 629783.0 655431.0 661883.0 122954.4 126189.0 128761.4 129231.0 129287.0 1021.9 1033.0 1035.9 1038.6 1068.5 18271.5 18676.8 18692.0 18888.0 19009.1 51315.0 51382.7 53090.7 53411.0 54485.7 81176.6 81338.0 83843.4 84255.6 84705.7 8382.9 8402.6 8440.7 8454.2 8529.5 9395.3 12329.0 13230.8 13472.7 13721.4 11380.9 11605.5 11824.5 11963.2 12107.0 11355.9 11420.8 11588.9 11692.2 11915.9 11711.5 11717.8 11935.9 12113.5 12280.8 8409.2 8440.0 8469.3 8519.4 8580.0 8692.1 8998.9 10935.1 11642.6 11848.8 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5777.15 Median:4931.52 stddev:3235.84 (65.6%) stddev-sans-first:3228.60 12:40:59 INFO - PROCESS | 1942 | Values: 1453.6 1516.4 1519.2 1788.6 1945.9 8047.6 8669.0 8807.2 8809.9 8832.4 8533.7 8813.3 8814.5 8815.5 8818.7 2521.2 2544.0 2548.0 2548.3 2551.7 2490.3 2542.6 2544.4 2545.2 2562.3 1893.0 2518.2 2549.3 2550.3 2560.1 2514.3 2530.7 2549.6 2551.4 2551.6 2468.0 2523.4 2529.7 2531.4 2537.9 8691.1 8876.5 8949.7 8953.2 8957.9 5182.0 5294.7 5499.3 5701.9 5715.1 3295.2 3575.2 3709.5 3747.2 3781.2 1034.1 1061.1 1062.1 1063.3 1086.6 12163.7 12293.0 12454.6 12471.7 12474.6 3707.9 3988.9 4833.3 4859.0 4861.8 9460.8 9469.3 9487.5 9488.3 9491.0 5513.8 5515.4 5520.0 5523.4 5535.8 4592.9 4723.3 4987.8 5089.5 5185.2 3952.4 3986.2 4008.2 4045.7 4107.1 1236.9 1254.4 1257.6 1263.3 1269.9 825.0 880.5 889.8 925.7 957.3 5870.5 6100.4 6121.4 6133.3 6290.5 921.0 967.1 968.2 986.1 1002.4 3919.8 4000.0 4031.5 4032.4 4115.0 10737.7 10926.8 11102.4 11121.5 11247.9 10964.7 10982.8 11046.8 11107.0 11114.4 8468.6 8480.7 8601.5 8636.0 8774.6 5850.0 5852.2 5855.1 5987.2 6039.0 9597.9 9725.2 10114.5 10123.3 10128.2 9983.6 10231.1 10238.7 10304.5 10436.7 6605.4 6739.1 6744.0 7254.8 7393.7 7896.0 7927.8 8263.5 8474.9 8498.2 7350.8 7414.6 7505.8 7634.7 7650.5 6541.4 7272.6 7368.5 7372.3 7443.1 7013.6 7013.7 7040.7 7047.0 7057.4 4781.8 4792.8 4800.1 4877.9 4936.5 4233.3 4926.5 4953.0 4977.3 5111.7 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:5909.91 Median:6774.46 stddev:1902.35 (28.1%) stddev-sans-first:1860.55 12:40:59 INFO - PROCESS | 1942 | Values: 302.7 327.7 333.0 343.3 345.5 6752.2 6755.6 6763.7 6774.4 6780.9 6501.3 6567.7 6678.8 6722.3 6774.6 4956.4 4990.8 4995.4 5004.2 5042.8 5002.1 5006.2 5008.4 5023.5 5025.5 4765.2 4843.0 4943.7 4943.9 4962.9 4899.7 4925.7 4928.1 4946.4 4954.9 4243.9 4252.6 4253.4 4262.2 4272.9 6471.9 6503.4 6540.8 6544.2 6567.1 5911.3 5934.4 5937.2 5946.8 5963.8 6861.8 7134.7 7141.7 7191.3 7191.4 4606.3 4639.0 4643.5 4652.0 4655.9 7487.4 7585.7 7592.4 7599.5 7610.4 4725.1 4726.7 4730.2 4746.8 4755.8 4605.3 4801.6 4802.4 4803.5 4818.8 5433.1 5721.7 5751.0 5755.7 5785.5 7119.1 7166.1 7173.5 7183.3 7186.7 1591.1 1649.1 1674.7 1675.4 1678.9 931.9 999.9 1053.9 1122.1 1151.0 6872.6 6907.9 6921.0 6928.5 6953.4 7441.1 7452.6 7456.4 7460.8 7473.1 5261.4 6920.7 6923.6 6927.4 6957.2 3308.8 3799.5 3854.8 3950.7 4011.3 6312.7 7823.8 7880.1 7884.2 7899.2 7059.6 7062.3 7739.7 7807.9 7819.6 7929.3 7950.0 7952.0 7959.8 7959.9 7072.5 7294.3 7576.4 7587.2 7606.8 7350.7 7396.1 7403.2 7421.3 7431.8 7933.4 7964.4 7964.5 7965.2 7970.0 4701.8 4769.8 4772.0 4773.4 4780.6 7179.1 7226.0 7427.2 7499.3 7504.7 7224.6 7252.0 7262.2 7264.3 7267.8 7257.8 7263.1 7269.6 7271.3 7275.7 6231.2 6696.4 7037.0 7091.6 7097.6 5810.2 6979.5 7220.8 7291.4 7299.5 7013.5 7022.9 7186.8 7187.4 7203.3 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:18853.32 Median:607.52 stddev:26879.78 (4424.5%) stddev-sans-first:26920.66 12:40:59 INFO - PROCESS | 1942 | Values: 709.3 726.9 756.7 771.2 777.6 15078.0 15393.6 15449.2 15458.5 15534.8 14178.7 14364.4 14378.3 14392.3 14407.6 780.7 807.3 949.9 963.6 965.2 967.4 973.9 973.9 976.4 978.4 835.3 1033.4 1036.4 1036.4 1044.3 846.2 846.5 848.0 1011.6 1038.6 775.9 778.8 778.8 779.3 779.9 15270.8 15323.7 15337.4 15348.3 15356.6 10574.4 12662.4 12712.0 12884.5 13093.9 9061.9 9767.8 10179.7 10350.4 10406.3 3494.0 3590.1 3592.1 3604.2 3677.3 87344.1 88063.0 88324.0 88676.0 89295.6 9730.5 9750.8 9767.7 9810.2 10159.6 18278.4 22484.7 22527.0 22546.4 22654.0 6584.9 7791.1 7996.5 8031.5 8035.8 3904.8 4262.6 4317.9 4342.4 4342.7 3189.7 3702.2 3770.9 3772.7 3782.2 613.6 663.9 672.9 674.4 675.0 522.6 568.7 618.1 624.4 624.9 6765.9 8222.3 8254.1 8260.2 8596.3 517.3 601.4 617.5 617.5 634.6 3704.3 3900.4 3918.5 3933.7 4104.1 92945.0 95574.1 97141.0 97146.0 97196.5 79510.0 80292.0 80823.6 81921.4 81984.6 69754.0 72163.0 72218.3 73394.0 73780.0 7265.9 7646.9 7822.0 7861.0 8244.4 50151.7 50193.5 50336.5 50772.7 51295.7 52210.5 69797.0 72164.0 72269.9 73675.0 10319.8 10352.7 11023.6 11421.7 11622.4 11870.2 12084.7 12186.9 12439.8 12583.2 11812.8 11944.7 11969.9 12032.7 12145.9 11656.3 11730.6 11757.6 11763.0 11803.9 11554.9 11613.8 11618.7 11620.0 11620.5 9517.2 9705.0 9799.9 9852.7 10016.0 8134.0 9571.2 9729.3 9764.8 9789.4 12:40:59 INFO - PROCESS | 1942 | -------- Summary: end -------- 12:40:59 INFO - PROCESS | 1942 | 12:40:59 INFO - TEST-INFO | 1942: exit 0 12:40:59 INFO - TEST-OK | dromaeo_css | took 1112594ms 12:40:59 INFO - TEST-START | kraken 12:40:59 INFO - operating with platform_type : mac_ 12:40:59 INFO - Initialising browser for kraken test... 12:40:59 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpaQyrF1/profile http://localhost:50228/getInfo.html) 12:41:01 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 12:41:01 INFO - PROCESS | 1948 | colorDepth:24 12:41:01 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 12:41:01 INFO - PROCESS | 1948 | __metrics 12:41:02 INFO - PROCESS | 1948 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:41:02 ERROR - PROCESS | 1948 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:41:02 INFO - TEST-INFO | 1948: exit 0 12:41:02 INFO - Browser initialized. 12:41:02 INFO - Running cycle 1/1 for kraken test... 12:41:02 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpaQyrF1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:14 INFO - PROCESS | 1951 | RSS: Main: 161763328 12:41:14 INFO - PROCESS | 1951 | 12:41:17 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50228/tests/kraken/driver.html?testName=audio-beat-detection) 12:41:18 INFO - PROCESS | 1951 | RSS: Main: 223772672 12:41:18 INFO - PROCESS | 1951 | 12:41:20 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50228/tests/kraken/driver.html?testName=audio-dft) 12:41:21 INFO - PROCESS | 1951 | RSS: Main: 239579136 12:41:21 INFO - PROCESS | 1951 | 12:41:24 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50228/tests/kraken/driver.html?testName=audio-fft) 12:41:25 INFO - PROCESS | 1951 | RSS: Main: 325632000 12:41:25 INFO - PROCESS | 1951 | 12:41:27 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50228/tests/kraken/driver.html?testName=audio-oscillator) 12:41:27 INFO - PROCESS | 1951 | RSS: Main: 231260160 12:41:27 INFO - PROCESS | 1951 | 12:41:29 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:41:30 INFO - PROCESS | 1951 | RSS: Main: 231989248 12:41:30 INFO - PROCESS | 1951 | 12:41:33 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50228/tests/kraken/driver.html?testName=imaging-darkroom) 12:41:33 INFO - PROCESS | 1951 | RSS: Main: 271233024 12:41:33 INFO - PROCESS | 1951 | 12:41:36 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50228/tests/kraken/driver.html?testName=imaging-desaturate) 12:41:37 INFO - PROCESS | 1951 | RSS: Main: 270893056 12:41:37 INFO - PROCESS | 1951 | 12:41:39 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50228/tests/kraken/driver.html?testName=json-parse-financial) 12:41:40 INFO - PROCESS | 1951 | RSS: Main: 268726272 12:41:40 INFO - PROCESS | 1951 | 12:41:42 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:41:42 INFO - PROCESS | 1951 | RSS: Main: 260788224 12:41:42 INFO - PROCESS | 1951 | 12:41:44 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:41:45 INFO - PROCESS | 1951 | RSS: Main: 267333632 12:41:45 INFO - PROCESS | 1951 | 12:41:47 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:41:47 INFO - PROCESS | 1951 | RSS: Main: 271265792 12:41:47 INFO - PROCESS | 1951 | 12:41:50 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:41:51 INFO - PROCESS | 1951 | RSS: Main: 278323200 12:41:51 INFO - PROCESS | 1951 | 12:41:53 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:41:54 INFO - PROCESS | 1951 | RSS: Main: 275656704 12:41:54 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50228/tests/kraken/driver.html?testName=ai-astar) 12:41:56 INFO - PROCESS | 1951 | RSS: Main: 336769024 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | __start_tp_report 12:41:56 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 12:41:56 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 12:41:56 INFO - PROCESS | 1951 | |i|pagename|runs| 12:41:56 INFO - PROCESS | 1951 | |0;ai-astar;114;114;119;113;113;114;113;114;113;136 12:41:56 INFO - PROCESS | 1951 | |1;audio-beat-detection;155;120;142;164;123;141;117;150;157;119 12:41:56 INFO - PROCESS | 1951 | |2;audio-dft;185;187;184;188;184;185;197;261;208;184 12:41:56 INFO - PROCESS | 1951 | |3;audio-fft;70;79;73;180;83;82;56;59;138;67 12:41:56 INFO - PROCESS | 1951 | |4;audio-oscillator;89;92;142;79;78;84;122;80;78;123 12:41:56 INFO - PROCESS | 1951 | |5;imaging-gaussian-blur;88;86;89;87;88;85;90;86;90;86 12:41:56 INFO - PROCESS | 1951 | |6;imaging-darkroom;92;93;94;93;93;94;94;93;101;93 12:41:56 INFO - PROCESS | 1951 | |7;imaging-desaturate;91;92;89;91;89;91;90;91;92;86 12:41:56 INFO - PROCESS | 1951 | |8;json-parse-financial;59;62;64;62;62;63;63;77;58;56 12:41:56 INFO - PROCESS | 1951 | |9;json-stringify-tinderbox;61;64;69;69;79;72;70;67;67;68 12:41:56 INFO - PROCESS | 1951 | |10;stanford-crypto-aes;79;79;79;79;76;78;78;80;81;81 12:41:56 INFO - PROCESS | 1951 | |11;stanford-crypto-ccm;126;126;128;122;122;126;131;125;146;126 12:41:56 INFO - PROCESS | 1951 | |12;stanford-crypto-pbkdf2;160;165;166;163;167;198;164;164;161;158 12:41:56 INFO - PROCESS | 1951 | |13;stanford-crypto-sha256-iterative;67;66;68;66;67;68;65;67;65;69 12:41:56 INFO - PROCESS | 1951 | __end_tp_report 12:41:56 INFO - PROCESS | 1951 | __start_cc_report 12:41:56 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,1931 12:41:56 INFO - PROCESS | 1951 | __end_cc_report 12:41:56 INFO - PROCESS | 1951 | __startTimestamp1468006916528__endTimestamp 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | ------- Summary: start ------- 12:41:56 INFO - PROCESS | 1951 | Number of tests: 14 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#0] ai-astar Cycles:10 Average:116.30 Median:114.00 stddev:7.15 (6.3%) stddev-sans-first:7.54 12:41:56 INFO - PROCESS | 1951 | Values: 114.0 114.0 119.0 113.0 113.0 114.0 113.0 114.0 113.0 136.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:146.00 stddev:17.76 (12.2%) stddev-sans-first:17.85 12:41:56 INFO - PROCESS | 1951 | Values: 155.0 120.0 142.0 164.0 123.0 141.0 117.0 150.0 157.0 119.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#2] audio-dft Cycles:10 Average:196.30 Median:187.50 stddev:24.01 (12.8%) stddev-sans-first:25.12 12:41:56 INFO - PROCESS | 1951 | Values: 185.0 187.0 184.0 188.0 184.0 185.0 197.0 261.0 208.0 184.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#3] audio-fft Cycles:10 Average:88.70 Median:71.50 stddev:39.38 (55.1%) stddev-sans-first:41.18 12:41:56 INFO - PROCESS | 1951 | Values: 70.0 79.0 73.0 180.0 83.0 82.0 56.0 59.0 138.0 67.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:79.50 stddev:23.38 (29.4%) stddev-sans-first:24.63 12:41:56 INFO - PROCESS | 1951 | Values: 89.0 92.0 142.0 79.0 78.0 84.0 122.0 80.0 78.0 123.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.88 12:41:56 INFO - PROCESS | 1951 | Values: 88.0 86.0 89.0 87.0 88.0 85.0 90.0 86.0 90.0 86.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:93.00 stddev:2.54 (2.7%) stddev-sans-first:2.59 12:41:56 INFO - PROCESS | 1951 | Values: 92.0 93.0 94.0 93.0 93.0 94.0 94.0 93.0 101.0 93.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#7] imaging-desaturate Cycles:10 Average:90.20 Median:91.00 stddev:1.81 (2.0%) stddev-sans-first:1.90 12:41:56 INFO - PROCESS | 1951 | Values: 91.0 92.0 89.0 91.0 89.0 91.0 90.0 91.0 92.0 86.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:62.50 stddev:5.66 (9.1%) stddev-sans-first:5.85 12:41:56 INFO - PROCESS | 1951 | Values: 59.0 62.0 64.0 62.0 62.0 63.0 63.0 77.0 58.0 56.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:4.79 (6.9%) stddev-sans-first:4.22 12:41:56 INFO - PROCESS | 1951 | Values: 61.0 64.0 69.0 69.0 79.0 72.0 70.0 67.0 67.0 68.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.00 stddev:1.49 (1.9%) stddev-sans-first:1.58 12:41:56 INFO - PROCESS | 1951 | Values: 79.0 79.0 79.0 79.0 76.0 78.0 78.0 80.0 81.0 81.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:126.00 stddev:6.91 (5.5%) stddev-sans-first:7.30 12:41:56 INFO - PROCESS | 1951 | Values: 126.0 126.0 128.0 122.0 122.0 126.0 131.0 125.0 146.0 126.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:164.50 stddev:11.37 (6.9%) stddev-sans-first:11.81 12:41:56 INFO - PROCESS | 1951 | Values: 160.0 165.0 166.0 163.0 167.0 198.0 164.0 164.0 161.0 158.0 12:41:56 INFO - PROCESS | 1951 | 12:41:56 INFO - PROCESS | 1951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.32 (2.0%) stddev-sans-first:1.39 12:41:56 INFO - PROCESS | 1951 | Values: 67.0 66.0 68.0 66.0 67.0 68.0 65.0 67.0 65.0 69.0 12:41:56 INFO - PROCESS | 1951 | -------- Summary: end -------- 12:41:56 INFO - PROCESS | 1951 | 12:41:57 INFO - TEST-INFO | 1951: exit 0 12:41:57 INFO - TEST-OK | kraken | took 57449ms 12:41:57 INFO - SUITE-END | took 1170s 12:41:57 INFO - Completed test suite (00:19:30) 12:41:57 INFO - javascript benchmark 12:41:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3213.807171569706, 3536.487209354643, 3587.121155316946, 3612.349777040132, 3646.3436581415335, 5750.856228594287, 5765.7981970631035, 5786.826395208145, 5795.681237531934, 5834.329052159003, 5775.2203452533895, 5796.420357964212, 5803.129530570416, 5804.477597016267, 5809.564282678805, 5200.8699782505555, 5248.370195576536, 5253.973730131344, 5284.075286824809, 5316.893662126755, 5324.41431442541, 5354.357477102733, 5373.865653358678, 5378.623496355257, 5384.973075134619, 5271.815486457975, 5309.0709125903, 5327.46725327468, 5338.946610533903, 5356.169793681966, 5333.893322133555, 5381.704006279653, 5384.6769193848495, 5407.891842163174, 5431.891362172774, 4243.299855523835, 4492.16894874446, 4887.218045112801, 5113.821016264413, 5267.078261304257, 5666.943330566683, 5705.515031222351, 5731.598788084836, 5735.856603584902, 5770.394108618577, 6228.96885515574, 6234.407731265568, 6234.625922444668, 6235.189425374718, 6253.437190652863, 6123.740204357149, 6166.136740856284, 6287.654179020198, 6303.810885673392, 6309.747610095591, 5978.233712760976, 5991.64050156992, 6015.578909476382, 6046.848828779272, 6047.244094488189, 6413.390727880844, 6416.615003099829, 6417.1657684500715, 6424.453921416639, 6427.775027874096, 6125.418085281891, 6187.876242475215, 6273.686315684197, 6280.089387038854, 6286.025666021774, 6274.372562743689, 6302.086197501337, 6304.243490781044, 6312.24253089623, 6331.0, 6200.224971878515, 6265.4361107499635, 6265.937340626535, 6268.1851359323, 6273.65494897785, 6153.5692501525355, 6179.28938172116, 6238.594491358047, 6266.091416744546, 6293.905591416133, 5008.17365134756, 5043.924341134886, 5047.646664733434, 5047.798088076509, 5066.08810414129, 4513.81944722214, 4531.84138555149, 4539.7049191802425, 4568.2690769477385, 4574.4739354973935, 6026.457618814327, 6095.478912709122, 6104.4505994460715, 6113.445900579432, 6115.021596544531, 6284.685765711696, 6307.117003619497, 6311.621302721851, 6315.147455093595, 6317.305096439388, 5894.32215295238, 5963.4334738199805, 6026.758929642853, 6083.6045657032155, 6085.939140608626, 5559.193916882029, 5567.192757050286, 5581.10702287632, 5584.106542953108, 5611.7755289788765, 6538.346165383424, 6540.215174179034, 6546.18172728409, 6550.0829883816305, 6552.246491653425, 5221.530062294411, 6196.0705894116245, 6240.313565507699, 6284.0, 6299.622022678654, 6468.5472016958365, 6477.967610161919, 6506.512011599054, 6522.510811689048, 6533.967330163318, 5465.9726701366235, 6270.341614130451, 6279.748810047729, 6280.748770049329, 6294.24469063697, 6144.924638188389, 6217.533684973736, 6241.687915604292, 6270.717817698123, 6285.0, 6235.937640623536, 6243.438090571689, 6255.0617407387435, 6263.498920086291, 6268.749250029948, 5130.923036154386, 6243.344448832989, 6247.812565623039, 6254.749810007549, 6271.216097987752, 6016.127661489147, 6026.243155966578, 6126.908096378469, 6176.104464852661, 6177.876442471215, 6068.939310607014, 6122.092878330954, 6212.192414986023, 6245.750169993149, 6258.624482531062, 5374.811881584125, 5930.140129681258, 6206.813795586139, 6211.0, 6227.9377206227355], "value": 5791.97330644162, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.2711145064087, 550.3423409026215, 566.9288401736219, 596.859737961579, 601.1163589523404, 24839.0, 32685.019449416526, 32775.83612081936, 32826.01521954339, 33055.66944330559, 33239.66760332396, 34122.317553648914, 34532.79135230267, 34534.65465345352, 42368.15263694732, 9719.757006074835, 9872.012798720143, 9882.0, 9886.703398898044, 9889.06053924876, 9670.516474176298, 9753.317267791257, 9796.314258001943, 9798.020197980217, 9806.068423499755, 9335.3465257432, 9790.9510452448, 12078.577249796259, 12357.567485138023, 12374.0, 11813.7637247255, 11851.822222666668, 11871.406429678567, 11876.0, 11892.940535297312, 9286.628534858597, 11659.0, 11789.58736444229, 11798.0, 11814.0, 39540.0, 39550.0, 39582.60417395818, 39636.40545391821, 40929.38605920914, 3353.765236433451, 3721.1999420124534, 3799.563050249229, 3861.1891502784447, 3902.0830104925603, 28298.15105546837, 28382.57426138589, 28419.147425577266, 29495.70504294951, 29887.0, 2750.0787236275874, 2789.4839454700946, 2807.5928990296293, 2823.647044119485, 2831.306329949155, 505384.0, 511865.0, 514262.0, 514454.0, 515402.0, 73431.26568734244, 73960.26039739641, 74169.6291518539, 74174.0, 74241.62879185677, 65075.0, 77867.61066194632, 78390.6080469594, 78529.60735196287, 78579.21420785833, 18825.34111306098, 19353.612927741648, 19360.225590976486, 19365.4190374289, 19425.251727344606, 14725.410983560752, 14848.960572759803, 14852.851471485363, 14853.628659283388, 14870.479533216285, 18757.624847502975, 21051.8947405262, 21158.942052897295, 21839.563208735737, 21866.0, 8436.0, 8519.95740021296, 8573.18554737299, 8644.654213831376, 8773.07882672323, 950.2255661635745, 958.9089036541517, 1114.6990312615549, 1201.4053043743404, 1221.4991853340266, 30685.693143068285, 30740.385192296475, 30978.380432391226, 31363.529547056813, 31435.685643143734, 1090.52450936374, 1096.5668560918552, 1146.4668928948079, 1169.5263418315596, 1297.3967105295894, 14532.782008269884, 15628.92185539065, 15679.372825087097, 15722.685546289238, 15995.360185592679, 603889.0, 605739.0, 612092.0, 612640.0, 614695.0, 64464.355356445834, 65048.674756625915, 65129.0, 65200.34799651943, 65375.0, 1566.7571526413424, 1581.351645825206, 1582.849629285216, 1601.8638415734792, 1605.149270886432, 29630.81476740906, 29771.0, 29833.55249671213, 30472.84763576168, 30653.386932261677, 54039.72980135074, 55050.0, 55827.720861395435, 55867.0, 55896.44103558912, 567143.0, 571967.0, 572575.0, 573108.0, 574216.0, 35079.298414031065, 43750.0, 43820.12359752851, 45285.0, 45385.54614453813, 45026.77486612546, 48026.0, 48171.0, 48575.75712121417, 48668.756656216494, 36434.63565364418, 36463.0, 36569.45145822868, 36634.816825916765, 37380.81309593435, 37606.435903462014, 37898.0, 38347.2330553393, 38497.0, 38854.222915542094, 37594.0, 37702.434463483616, 38146.618533814304, 38878.805605971786, 38979.02552436212, 31917.14394045216, 33025.83487082549, 33580.0, 33647.4952875712, 33711.4943275856, 24832.0, 24972.500549988534, 24993.375165621004, 25393.365165871, 25524.61713074268], "value": 21178.54537457574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.6370139829045, 420.1869382744389, 421.1808033375083, 421.8312674930028, 431.49946062567426, 20418.0, 20500.0, 20766.48083797902, 20951.0, 21213.893930530347, 20624.1750329987, 21033.05351259193, 21665.043087298614, 21868.23461178859, 21898.343049708496, 5980.0, 6304.464120549759, 6307.558470907038, 6310.9684451577905, 6318.589291696048, 4599.862004139881, 4844.224924012159, 6141.0, 6327.240731112273, 6331.018692102732, 6994.6152961587095, 6997.0, 6997.615131167784, 7002.544834585761, 7009.649517524129, 6684.197896252432, 6931.896021559681, 6939.063226464464, 6940.13248343957, 6972.616506092163, 3705.3330400527893, 5863.648181109147, 6064.4541991221, 6114.449699527064, 6114.510839132859, 26909.32726681829, 27317.863410683018, 27467.86266068677, 27488.862555687294, 27644.86177569099, 2304.573653874039, 2357.622780355135, 2365.47959448921, 2468.938276543083, 2815.4650616382824, 15720.0, 16017.679646407123, 16256.674866502604, 16298.511044668561, 16481.92867463611, 1750.1949103412446, 1810.2758896441396, 1832.7159290310024, 1895.185070419719, 1897.127321432143, 79533.60233198854, 105639.0, 113419.43290283579, 114818.0, 116228.41885790601, 9478.90521094794, 11431.828522572168, 11646.592369267035, 11686.415679216005, 11926.761464770656, 15283.38866445352, 15286.541403757905, 15288.929774915649, 15324.846751532565, 15350.462733804263, 8723.694670686495, 9104.544772761335, 9182.95408522953, 9219.539023048956, 9266.860997085048, 9172.72188494516, 9179.219766319804, 9194.448333100036, 9305.302102342352, 9513.571889265, 3902.3951287550476, 3964.354792760972, 4763.571278584944, 4829.275608658659, 4882.292067650171, 1712.24661149094, 1945.727598136262, 2005.929792457257, 2042.7539201087325, 2114.228306668055, 887.3655336434481, 998.9300748947504, 1044.2011860926398, 1064.6752740414092, 1072.265498133781, 20545.48636284075, 21318.04068816907, 21340.253091142047, 21373.25193618247, 21506.784932150476, 973.9805203895883, 982.2485798364257, 1056.3661802918189, 1060.7825395793843, 1061.5806756331206, 10505.264631475877, 11273.0, 11288.0969522438, 11298.604548840916, 11315.490802913888, 624598.0, 627627.0, 629783.0, 655431.0, 661883.0, 122954.38522807328, 126189.0, 128761.35619321844, 129231.0, 129287.0, 1021.8518314844306, 1033.0083120204538, 1035.891231420705, 1038.6416686243385, 1068.540527573151, 18271.54321141982, 18676.81323186751, 18692.0, 18888.0, 19009.14458849383, 51315.0, 51382.743086285824, 53090.73454632702, 53411.0, 54485.72757136189, 81176.59411702903, 81338.0, 83843.4078654551, 84255.578722106, 84705.72941405761, 8382.874256886029, 8402.579871006548, 8440.746777596682, 8454.23911847912, 8529.488230706178, 9395.295352848427, 12329.0, 13230.801537977131, 13472.730545389233, 13721.382537786023, 11380.886191137983, 11605.477753500943, 11824.527018919147, 11963.162578619413, 12107.031437485157, 11355.943220283845, 11420.828687569527, 11588.942055290006, 11692.181547291608, 11915.940420297842, 11711.472983715583, 11717.765644686888, 11935.880641193824, 12113.454894529592, 12280.815787763013, 8409.159084091541, 8439.95780021096, 8469.322454203771, 8519.403641745019, 8579.957100214458, 8692.130786921258, 8998.865017024618, 10935.070519005974, 11642.592509262135, 11848.822267665819], "value": 10062.360985459582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.6002599285202, 1516.4464970285846, 1519.2251951504734, 1788.5707430216753, 1945.8540609454285, 8047.597620118992, 8668.956655216723, 8807.163319484654, 8809.867851982226, 8832.381733278666, 8533.701320453785, 8813.338999575042, 8814.515201663906, 8815.51514666693, 8818.691345802898, 2521.1554129366637, 2544.0459827564664, 2547.9872600636972, 2548.3374322676054, 2551.706553746315, 2490.2653717153394, 2542.5677634802128, 2544.3766277262002, 2545.2237067694346, 2562.3209849389928, 1893.0, 2518.1941778630844, 2549.3499157714755, 2550.298667866333, 2560.116759717894, 2514.346269969821, 2530.708968468631, 2549.6430499730054, 2551.413174969758, 2551.6045013023013, 2467.962980555293, 2523.4448421347274, 2529.6711427514488, 2531.3545046013332, 2537.949241015188, 8691.08743581921, 8876.511791851446, 8949.686760963335, 8953.238974687094, 8957.910420895838, 5182.0, 5294.682319060868, 5499.312585926759, 5701.885962280772, 5715.057015592422, 3295.1597342677464, 3575.249197668493, 3709.536307961505, 3747.213085252073, 3781.1870447853844, 1034.1106648282546, 1061.0609610494691, 1062.1184416933927, 1063.346042184059, 1086.5870969031796, 12163.695907602292, 12293.0, 12454.626361209179, 12471.688207794787, 12474.563390281297, 3707.9258414832093, 3988.880333589997, 4833.32333473314, 4859.0, 4861.781219845116, 9460.763480913032, 9469.289803264783, 9487.525623718786, 9488.33581649278, 9491.0, 5513.7794488220825, 5515.393306736256, 5519.972400138054, 5523.447655234444, 5535.806246781424, 4592.908141837212, 4723.338732577442, 4987.775550100254, 5089.541941225233, 5185.196294574347, 3952.407138929126, 3986.1629057897585, 4008.2384346974563, 4045.6763458923215, 4107.096438783463, 1236.8515778106505, 1254.3790823542222, 1257.5598540510748, 1263.2736176698436, 1269.9428525716364, 825.014108140778, 880.4893161966045, 889.755317287751, 925.6713866577268, 957.2820384711466, 5870.503021729432, 6100.3899610039525, 6121.357257487989, 6133.26400831903, 6290.465310448663, 920.9777147366552, 967.1005964452986, 968.1673760565908, 986.0927946289288, 1002.4135880509983, 3919.804009799556, 3999.960000399959, 4031.5162180538523, 4032.3951407288555, 4115.032967252693, 10737.731556710989, 10926.79805221425, 11102.389368584643, 11121.499532521057, 11247.887521124847, 10964.671059868217, 10982.780344393228, 11046.834297485544, 11107.0, 11114.388708620942, 8468.618912148968, 8480.703175388831, 8601.526916019679, 8636.0, 8774.605142768463, 5849.970750146222, 5852.209951656642, 5855.092460668603, 5987.191729116514, 6039.033754599243, 9597.90402095998, 9725.173360264456, 10114.494275286059, 10123.341982771097, 10128.24038197153, 9983.60065597368, 10231.130353919703, 10238.692839214835, 10304.484775761332, 10436.686899393031, 6605.372489613479, 6739.05653208561, 6743.966280168568, 7254.818629534093, 7393.704251830081, 7896.0, 7927.84144317122, 8263.504189748634, 8474.915250847413, 8498.192671696179, 7350.77947661571, 7414.62926853666, 7505.812354690958, 7634.656440460081, 7650.4644674872225, 6541.443977261795, 7272.636368181676, 7368.521046131984, 7372.262773722585, 7443.144038435649, 7013.614251216232, 7013.684384202825, 7040.683169257293, 7046.964765176142, 7057.36483716468, 4781.760911954458, 4792.832250871203, 4800.06398752254, 4877.926831097466, 4936.506349365035, 4233.343831706091, 4926.531979461945, 4952.975235123801, 4977.278294647328, 5111.667741596784], "value": 4657.774005682317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.7033507162981, 327.7443593996683, 333.0126175888491, 343.25856150714463, 345.5283537970671, 6752.155980502431, 6755.62844043578, 6763.7294508219675, 6774.356436138572, 6780.864382712342, 6501.252355979063, 6567.671616419184, 6678.766243181478, 6722.260551339349, 6774.559653622523, 4956.40523137224, 4990.8003679852945, 4995.35060442142, 5004.224345226496, 5042.773075211624, 5002.099622068026, 5006.249062640597, 5008.4240312364, 5023.472535383787, 5025.497450254982, 4765.1660959331975, 4842.97578512107, 4943.703377797326, 4943.9258411123865, 4962.851114466572, 4899.730514821684, 4925.694690906913, 4928.137575924199, 4946.431160416562, 4954.851354459354, 4243.851465198734, 4252.574742525753, 4253.425787518708, 4262.1688770689725, 4272.9145417091795, 6471.870562588769, 6503.447206987412, 6540.803775886683, 6544.214694236674, 6567.080608714784, 5911.349751527328, 5934.376890426486, 5937.228160339171, 5946.791862284843, 5963.7912673056235, 6861.828454288683, 7134.678939447737, 7141.678624461912, 7191.280871912767, 7191.424686025105, 4606.286025666027, 4638.95361046392, 4643.489216186217, 4651.976740116312, 4655.883602909955, 7487.401007919408, 7585.696572137108, 7592.392608591299, 7599.468037237396, 7610.353119984808, 4725.149473094807, 4726.740029298421, 4730.195866702668, 4746.810127594927, 4755.785989630475, 4605.286180642006, 4801.639877009238, 4802.351682522884, 4803.471618122004, 4818.831340903052, 5433.1192953969385, 5721.685307308055, 5750.971245143747, 5755.740991655385, 5785.508231800344, 7119.145702515628, 7166.068411106627, 7173.5337203081635, 7183.317584829538, 7186.7125314986815, 1591.053323272651, 1649.0847579593442, 1674.715298399275, 1675.4303536797547, 1678.8908720933346, 931.9347645664739, 999.9050090241416, 1053.8629978102797, 1122.1135303110614, 1151.021631613122, 6872.553284036621, 6907.861842763116, 6921.0, 6928.549644273106, 6953.443724502028, 7441.069866266716, 7452.552846829208, 7456.440766942501, 7460.776176714708, 7473.103227612722, 5261.3686357637325, 6920.688569014406, 6923.6420336136935, 6927.376536111673, 6957.1651401832105, 3308.8345582720767, 3799.525059367579, 3854.8265328060306, 3950.7234493585747, 4011.3180759270995, 6312.715927783261, 7823.843523129505, 7880.093789214314, 7884.17216192303, 7899.249571290718, 7059.5764254144915, 7062.329078737512, 7739.729109481141, 7807.882881756778, 7819.5699236541395, 7929.286364227132, 7949.960250198712, 7951.966244388308, 7959.80100497469, 7959.920400795918, 7072.504924655224, 7294.270572942663, 7576.3938884887975, 7587.2412758723685, 7606.8098297543, 7350.70597176107, 7396.149442814145, 7403.18564957865, 7421.332080112816, 7431.8142046449275, 7933.404994625311, 7964.402669799904, 7964.522128672298, 7965.243301886311, 7970.0, 4701.811927522861, 4769.785359658755, 4771.974025584516, 4773.400910694956, 4780.569748722632, 7179.138503379628, 7226.0, 7427.22014188524, 7499.287567681062, 7504.737334193277, 7224.602646854263, 7251.963740181265, 7262.201157872524, 7264.34620884123, 7267.818304542428, 7257.782266532013, 7263.128424589083, 7269.563826170447, 7271.309225623558, 7275.708971641075, 6231.158793562992, 6696.397324240842, 7036.979637952571, 7091.60996145217, 7097.645117744195, 5810.215620891126, 6979.476539259677, 7220.819479513054, 7291.4166866649475, 7299.452541059336, 7013.473989450709, 7022.859542809218, 7186.784396468114, 7187.389071928945, 7203.315685009916], "value": 5265.842421352428, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.3155105323362, 726.8836986082226, 756.6670664907443, 771.1941021632393, 777.6422845491078, 15078.0, 15393.615159621015, 15449.15029673369, 15458.458953936615, 15534.757219422447, 14178.71642567148, 14364.425422983068, 14378.281085945688, 14392.280385980685, 14407.63980900478, 780.7228433905967, 807.3178164451059, 949.852772820214, 963.6145541783272, 965.1747755668907, 967.4147140979711, 973.9366941148803, 973.9464329461877, 976.3995142987047, 978.4373984958642, 835.2983493865152, 1033.3954636537635, 1036.3626369782567, 1036.4196050211906, 1044.305536818015, 846.2426128614886, 846.5428668518992, 848.0417128644654, 1011.640867492037, 1038.5586125896464, 775.9262870027338, 778.8169780101672, 778.8364443466879, 779.3375630713905, 779.9376049915993, 15270.847291527165, 15323.693526129526, 15337.386504539805, 15348.309326080353, 15356.616084597863, 10574.365538067741, 12662.36688165588, 12712.0, 12884.484620615163, 13093.934530327288, 9061.864072038925, 9767.804643907153, 10179.694609161737, 10350.378977261389, 10406.271560990734, 3493.9650603494147, 3590.120420496969, 3592.119930616989, 3604.2250916052913, 3677.264547090566, 87344.12655873424, 88063.0, 88324.0, 88676.0, 89295.55352223197, 9730.464824434725, 9750.804983900282, 9767.658131965347, 9810.16613587853, 10159.593616255266, 18278.360257390927, 22484.662730059063, 22526.986285617186, 22546.436339091324, 22654.0, 6584.934150658528, 7791.142974272825, 7996.480228785111, 8031.477953932976, 8035.799105022421, 3904.7852368120025, 4262.616364527207, 4317.9136417271475, 4342.370356298319, 4342.652587792969, 3189.6810318968382, 3702.204026134394, 3770.886873393748, 3772.660460558563, 3782.167923056923, 613.6471528870917, 663.9203295604558, 672.9327067293232, 674.3829396102609, 675.0414411535534, 522.5741021067818, 568.6701713006446, 618.116093985605, 624.4286477872696, 624.865653884417, 6765.898511522331, 8222.342212622976, 8254.092049874509, 8260.215279548433, 8596.312295016383, 517.3119750731518, 601.3865856826013, 617.4658920034138, 617.5183356981562, 634.5589815078476, 3704.259148170323, 3900.414937759302, 3918.549366822852, 3933.6853051755793, 4104.138130992525, 92945.0, 95574.13277601684, 97141.0, 97146.0, 97196.51401742946, 79510.0, 80292.0, 80823.5958820202, 81921.3615727682, 81984.59007704923, 69754.0, 72163.0, 72218.27781722115, 73394.0, 73780.0, 7265.891011634935, 7646.906083969107, 7822.0, 7860.960695196487, 8244.381671374551, 50151.74924125356, 50193.49806502034, 50336.49663503318, 50772.74613626908, 51295.691693874614, 52210.47789522056, 69796.95304570334, 72164.0, 72269.91595126182, 73675.0, 10319.845202321821, 10352.689419317432, 11023.559057637605, 11421.657350279505, 11622.360770157722, 11870.169088163746, 12084.697882553006, 12186.939065304618, 12439.813402799145, 12583.182093163916, 11812.763744724885, 11944.701382465508, 11969.880301196876, 12032.699182520508, 12145.878541214475, 11656.30062196271, 11730.589429369931, 11757.647270581896, 11763.000144987773, 11803.940980295043, 11554.94222528882, 11613.825792613286, 11618.6514404568, 11620.0, 11620.5351785931, 9517.238620910171, 9705.0, 9799.902000979899, 9852.704418867446, 10015.998400160217, 8133.959330203548, 9571.1864491519, 9729.318947673595, 9764.804703906024, 9789.363691360039], "value": 7052.11786888866, "unit": "score"}], "name": "dromaeo_css", "value": 7731.039318111284, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 119.0, 113.0, 113.0, 114.0, 113.0, 114.0, 113.0, 136.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 120.0, 142.0, 164.0, 123.0, 141.0, 117.0, 150.0, 157.0, 119.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 188.0, 184.0, 185.0, 197.0, 261.0, 208.0, 184.0], "value": 196.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 73.0, 180.0, 83.0, 82.0, 56.0, 59.0, 138.0, 67.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 142.0, 79.0, 78.0, 84.0, 122.0, 80.0, 78.0, 123.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 87.0, 88.0, 85.0, 90.0, 86.0, 90.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0, 101.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 89.0, 91.0, 89.0, 91.0, 90.0, 91.0, 92.0, 86.0], "value": 90.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 64.0, 62.0, 62.0, 63.0, 63.0, 77.0, 58.0, 56.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 69.0, 69.0, 79.0, 72.0, 70.0, 67.0, 67.0, 68.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 76.0, 78.0, 78.0, 80.0, 81.0, 81.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 128.0, 122.0, 122.0, 126.0, 131.0, 125.0, 146.0, 126.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 166.0, 163.0, 167.0, 198.0, 164.0, 164.0, 161.0, 158.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 66.0, 67.0, 68.0, 65.0, 67.0, 65.0, 69.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1479.8999999999999, "alertThreshold": 2.0}]} 12:41:57 INFO - Return code: 0 12:41:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:41:57 ERROR - # TBPL SUCCESS # 12:41:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:41:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:41:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:41:57 INFO - 2016-07-08 12:41:57.830 screenresolution[1955:13609] starting screenresolution argv=screenresolution get 12:41:57 INFO - 2016-07-08 12:41:57.833 screenresolution[1955:13609] Display 0: 1600x1200x32@60 12:41:57 INFO - 2016-07-08 12:41:57.839 screenresolution[1956:13613] starting screenresolution argv=screenresolution list 12:41:57 INFO - Available Modes on Display 0 12:41:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:41:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:41:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:41:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:41:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:41:58 INFO - Intel Iris: 12:41:58 INFO - Chipset Model: Intel Iris 12:41:58 INFO - Type: GPU 12:41:58 INFO - Bus: Built-In 12:41:58 INFO - VRAM (Dynamic, Max): 1536 MB 12:41:58 INFO - Vendor: Intel (0x8086) 12:41:58 INFO - Device ID: 0x0a2e 12:41:58 INFO - Revision ID: 0x0009 12:41:58 INFO - Displays: 12:41:58 INFO - Display: 12:41:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:41:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:41:58 INFO - Display Serial Number: 1600x1200 60 12:41:58 INFO - Main Display: Yes 12:41:58 INFO - Mirror: Off 12:41:58 INFO - Online: Yes 12:41:58 INFO - Rotation: Supported 12:41:58 INFO - Return code: 0 12:41:58 INFO - Running post-action listener: _resource_record_post_action 12:41:58 INFO - Running post-run listener: _resource_record_post_run 12:41:59 INFO - Total resource usage - Wall time: 1194s; CPU: 52.0%; Read bytes: 1097728; Write bytes: 494381056; Read time: 17; Write time: 1680 12:41:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194679296; Write bytes: 197051904; Read time: 17634; Write time: 446 12:41:59 INFO - run-tests - Wall time: 1172s; CPU: 52.0%; Read bytes: 1097728; Write bytes: 296481280; Read time: 17; Write time: 1231 12:41:59 INFO - Running post-run listener: _upload_blobber_files 12:41:59 INFO - Blob upload gear active. 12:41:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:41:59 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 12:41:59 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:41:59 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:41:59 INFO - (blobuploader) - INFO - Open directory for files ... 12:41:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:41:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:42:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:00 INFO - (blobuploader) - INFO - Done attempting. 12:42:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:42:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:42:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:01 INFO - (blobuploader) - INFO - Done attempting. 12:42:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:42:01 INFO - Return code: 0 12:42:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:42:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:42:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/785ded88557237ce3389a2b54dd4c46c6aedec26c79b52350e396227ffe4e8e4e0499f116410053a8cd666da112a23ea2726e0a1490043ca31571b52bed6ab88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94f65b6b776ec22ccb29fb3c41f7aba97580739de65c0e6417f09112b847ff16287a78fd99b3233024bd5e45d6a9c9bd813d9764d0e8a3c12edd4e9de669152"} 12:42:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:42:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:42:01 INFO - Contents: 12:42:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/785ded88557237ce3389a2b54dd4c46c6aedec26c79b52350e396227ffe4e8e4e0499f116410053a8cd666da112a23ea2726e0a1490043ca31571b52bed6ab88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94f65b6b776ec22ccb29fb3c41f7aba97580739de65c0e6417f09112b847ff16287a78fd99b3233024bd5e45d6a9c9bd813d9764d0e8a3c12edd4e9de669152"} 12:42:01 INFO - Running post-run listener: copy_logs_to_upload_dir 12:42:01 INFO - Copying logs to upload dir... 12:42:01 INFO - mkdir: /builds/slave/test/build/upload/logs 12:42:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.496363 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-07-08 12:42:01.861129) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 12:42:01.864476) ========= 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.xm5v96Dzn1/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.REjYqCmBB9/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/785ded88557237ce3389a2b54dd4c46c6aedec26c79b52350e396227ffe4e8e4e0499f116410053a8cd666da112a23ea2726e0a1490043ca31571b52bed6ab88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94f65b6b776ec22ccb29fb3c41f7aba97580739de65c0e6417f09112b847ff16287a78fd99b3233024bd5e45d6a9c9bd813d9764d0e8a3c12edd4e9de669152"} build_url:https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011006 build_url: 'https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/785ded88557237ce3389a2b54dd4c46c6aedec26c79b52350e396227ffe4e8e4e0499f116410053a8cd666da112a23ea2726e0a1490043ca31571b52bed6ab88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94f65b6b776ec22ccb29fb3c41f7aba97580739de65c0e6417f09112b847ff16287a78fd99b3233024bd5e45d6a9c9bd813d9764d0e8a3c12edd4e9de669152"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 12:42:01.899921) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:42:01.900275) ========= 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.xm5v96Dzn1/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.REjYqCmBB9/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.005161 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:42:01.952227) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 12:42:01.952870) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 12:42:01.953511) ========= ========= Total master_lag: 0.22 =========