builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0116 starttime: 1461959878.52 results: success (0) buildid: 20160429115741 builduid: d46d248ffaa34b5fb29ec7b224fcb5a2 revision: caa76bbe680cded163aa36b046ce65c20b6b727d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.516069) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.516510) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.516796) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.536347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.536756) ========= 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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-29 12:57:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 181M=0s 2016-04-29 12:57:58 (181 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104394 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.658599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.659017) ========= 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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.027109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 12:57:58.709039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-29 12:57:58.709396) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev caa76bbe680cded163aa36b046ce65c20b6b727d --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 caa76bbe680cded163aa36b046ce65c20b6b727d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PrJbcZuS1v/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.dMl1GoQKG4/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-29 12:57:58,790 truncating revision to first 12 chars 2016-04-29 12:57:58,790 Setting DEBUG logging. 2016-04-29 12:57:58,790 attempt 1/10 2016-04-29 12:57:58,790 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/caa76bbe680c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 12:57:59,832 unpacking tar archive at: mozilla-beta-caa76bbe680c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.292079 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-29 12:58:00.020204) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 12:58:00.020534) ========= script_repo_revision: caa76bbe680cded163aa36b046ce65c20b6b727d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 12:58:00.020941) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 12:58:00.021235) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 12:58:00.036611) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-29 12:58:00.037022) ========= /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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:00 INFO - MultiFileLogger online at 20160429 12:58:00 in /builds/slave/test 12:58:00 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:58:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:58:00 INFO - {'append_to_log': False, 12:58:00 INFO - 'base_work_dir': '/builds/slave/test', 12:58:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:58:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:58:00 INFO - 'branch': 'Mozilla-Beta', 12:58:00 INFO - 'buildbot_json_path': 'buildprops.json', 12:58:00 INFO - 'config_files': ('talos/mac_config.py',), 12:58:00 INFO - 'default_actions': ('clobber', 12:58:00 INFO - 'read-buildbot-config', 12:58:00 INFO - 'download-and-extract', 12:58:00 INFO - 'populate-webroot', 12:58:00 INFO - 'create-virtualenv', 12:58:00 INFO - 'install', 12:58:00 INFO - 'run-tests'), 12:58:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:58:00 INFO - 'download_minidump_stackwalk': True, 12:58:00 INFO - 'download_symbols': 'ondemand', 12:58:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:58:00 INFO - 'tooltool.py': '/tools/tooltool.py', 12:58:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:58:00 INFO - '/tools/misc-python/virtualenv.py')}, 12:58:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:58:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:58:00 INFO - 'installer_path': 'installer.exe', 12:58:00 INFO - 'log_level': 'info', 12:58:00 INFO - 'log_name': 'talos', 12:58:00 INFO - 'log_to_console': True, 12:58:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:58:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:58:00 INFO - 'opt_config_files': (), 12:58:00 INFO - 'pip_index': False, 12:58:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:00 INFO - 'cmd': ('bash', 12:58:00 INFO - '-c', 12:58:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:00 INFO - 'enabled': True, 12:58:00 INFO - 'halt_on_failure': False, 12:58:00 INFO - 'name': 'check_screen_resolution'},), 12:58:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:00 INFO - 'cmd': ('bash', 12:58:00 INFO - '-c', 12:58:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:00 INFO - 'enabled': True, 12:58:00 INFO - 'halt_on_failure': False, 12:58:00 INFO - 'name': 'check_screen_resolution'},), 12:58:00 INFO - 'run_cmd_checks_enabled': True, 12:58:00 INFO - 'sps_profile': False, 12:58:00 INFO - 'sps_profile_interval': 0, 12:58:00 INFO - 'suite': 'dromaeojs', 12:58:00 INFO - 'system_bits': '32', 12:58:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:58:00 INFO - 'title': 't-yosemite-r7-0116', 12:58:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:58:00 INFO - 'use_talos_json': True, 12:58:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:58:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:58:00 INFO - 'work_dir': 'build'} 12:58:00 INFO - ##### 12:58:00 INFO - ##### Running clobber step. 12:58:00 INFO - ##### 12:58:00 INFO - Running pre-action listener: _resource_record_pre_action 12:58:00 INFO - Running main action method: clobber 12:58:00 INFO - rmtree: /builds/slave/test/build 12:58:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:58:02 INFO - Running post-action listener: _resource_record_post_action 12:58:02 INFO - ##### 12:58:02 INFO - ##### Running read-buildbot-config step. 12:58:02 INFO - ##### 12:58:02 INFO - Running pre-action listener: _resource_record_pre_action 12:58:02 INFO - Running main action method: read_buildbot_config 12:58:02 INFO - Using buildbot properties: 12:58:02 INFO - { 12:58:02 INFO - "project": "", 12:58:02 INFO - "product": "firefox", 12:58:02 INFO - "script_repo_revision": "production", 12:58:02 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:58:02 INFO - "repository": "", 12:58:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:58:02 INFO - "buildid": "20160429115741", 12:58:02 INFO - "pgo_build": "False", 12:58:02 INFO - "basedir": "/builds/slave/test", 12:58:02 INFO - "buildnumber": 5, 12:58:02 INFO - "slavename": "t-yosemite-r7-0116", 12:58:02 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:58:02 INFO - "platform": "yosemite_r7", 12:58:02 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:58:02 INFO - "branch": "mozilla-beta", 12:58:02 INFO - "repo_path": "releases/mozilla-beta", 12:58:02 INFO - "revision": "caa76bbe680cded163aa36b046ce65c20b6b727d", 12:58:02 INFO - "stage_platform": "macosx64", 12:58:02 INFO - "builduid": "d46d248ffaa34b5fb29ec7b224fcb5a2", 12:58:02 INFO - "slavebuilddir": "test" 12:58:02 INFO - } 12:58:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 12:58:02 INFO - Running post-action listener: _resource_record_post_action 12:58:02 INFO - ##### 12:58:02 INFO - ##### Running download-and-extract step. 12:58:02 INFO - ##### 12:58:02 INFO - Running pre-action listener: _resource_record_pre_action 12:58:02 INFO - Running main action method: download_and_extract 12:58:02 INFO - mkdir: /builds/slave/test/build/tests 12:58:02 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:58:02 INFO - https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:58:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 12:58:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 12:58:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 12:58:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 12:58:03 INFO - Downloaded 1241 bytes. 12:58:03 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 12:58:03 INFO - Using the following test package requirements: 12:58:03 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:58:03 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:58:03 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 12:58:03 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:58:03 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 12:58:03 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:58:03 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:58:03 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 12:58:03 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:58:03 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:58:03 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 12:58:03 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:58:03 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 12:58:03 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:58:03 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 12:58:03 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:58:03 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:58:03 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 12:58:03 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 12:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:03 INFO - https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:58:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:58:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:58:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 12:58:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 12:58:10 INFO - Downloaded 17745609 bytes. 12:58:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:58:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:58:13 INFO - Return code: 0 12:58:13 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:13 INFO - https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:58:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:58:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:58:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 12:58:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 12:58:15 INFO - Downloaded 17745609 bytes. 12:58:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:58:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:58:18 INFO - Return code: 0 12:58: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:58:18 INFO - https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:58:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 12:58:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 12:58:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 12:58:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 12:58:20 INFO - Downloaded 10845194 bytes. 12:58:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:58:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:58:20 INFO - Return code: 0 12:58:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:20 INFO - https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 12:58:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:58:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:58:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 12:58:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 12:58:24 INFO - Downloaded 88320912 bytes. 12:58:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:58:24 INFO - mkdir: /builds/slave/test/properties 12:58:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:58:24 INFO - Writing to file /builds/slave/test/properties/build_url 12:58:24 INFO - Contents: 12:58:24 INFO - build_url:https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:58:25 INFO - Running post-action listener: _resource_record_post_action 12:58:25 INFO - Running post-action listener: set_extra_try_arguments 12:58:25 INFO - ##### 12:58:25 INFO - ##### Running populate-webroot step. 12:58:25 INFO - ##### 12:58:25 INFO - Running pre-action listener: _resource_record_pre_action 12:58:25 INFO - Running main action method: populate_webroot 12:58:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:58:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:58:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:58:25 INFO - '--tppagecycles', 12:58:25 INFO - '7'], 12:58:25 INFO - 'tests': ['tsvgm']}}, 12:58:25 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:58:25 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:58:25 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 12:58:25 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:58:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'tests': ['damp', 'tps']}, 12:58:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['damp', 'tps']}, 12:58:25 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:58:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:58:25 INFO - 'other': {'tests': ['a11yr', 12:58:25 INFO - 'ts_paint', 12:58:25 INFO - 'tpaint', 12:58:25 INFO - 'sessionrestore', 12:58:25 INFO - 'sessionrestore_no_auto_restore', 12:58:25 INFO - 'tabpaint']}, 12:58:25 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['a11yr', 12:58:25 INFO - 'ts_paint', 12:58:25 INFO - 'tpaint', 12:58:25 INFO - 'sessionrestore', 12:58:25 INFO - 'sessionrestore_no_auto_restore', 12:58:25 INFO - 'tabpaint']}, 12:58:25 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['a11yr', 12:58:25 INFO - 'ts_paint', 12:58:25 INFO - 'tpaint', 12:58:25 INFO - 'sessionrestore', 12:58:25 INFO - 'sessionrestore_no_auto_restore', 12:58:25 INFO - 'tabpaint']}, 12:58:25 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['a11yr', 12:58:25 INFO - 'ts_paint', 12:58:25 INFO - 'tpaint', 12:58:25 INFO - 'sessionrestore', 12:58:25 INFO - 'sessionrestore_no_auto_restore', 12:58:25 INFO - 'tabpaint']}, 12:58:25 INFO - 'other_l64': {'tests': ['a11yr', 12:58:25 INFO - 'ts_paint', 12:58:25 INFO - 'tpaint', 12:58:25 INFO - 'sessionrestore', 12:58:25 INFO - 'sessionrestore_no_auto_restore', 12:58:25 INFO - 'tabpaint']}, 12:58:25 INFO - 'other_nol64': {'tests': ['a11yr', 12:58:25 INFO - 'ts_paint', 12:58:25 INFO - 'tpaint', 12:58:25 INFO - 'sessionrestore', 12:58:25 INFO - 'sessionrestore_no_auto_restore', 12:58:25 INFO - 'tabpaint']}, 12:58:25 INFO - 'svgr': {'tests': ['tsvgx', 12:58:25 INFO - 'tsvgr_opacity', 12:58:25 INFO - 'tart', 12:58:25 INFO - 'tscrollx', 12:58:25 INFO - 'cart']}, 12:58:25 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['tsvgx', 12:58:25 INFO - 'tsvgr_opacity', 12:58:25 INFO - 'tart', 12:58:25 INFO - 'tscrollx', 12:58:25 INFO - 'cart']}, 12:58:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'tests': ['tp5o']}, 12:58:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'talos_options': ['--e10s'], 12:58:25 INFO - 'tests': ['tp5o']}, 12:58:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'talos_options': ['--xperf_path', 12:58:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:25 INFO - 'tests': ['tp5n']}, 12:58:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:25 INFO - 'talos_options': ['--e10s', 12:58:25 INFO - '--xperf_path', 12:58:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:25 INFO - 'tests': ['tp5n']}}, 12:58:25 INFO - 'talos.zip': {'path': '', 12:58:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:58:25 INFO - Running post-action listener: _resource_record_post_action 12:58:25 INFO - ##### 12:58:25 INFO - ##### Running create-virtualenv step. 12:58:25 INFO - ##### 12:58:25 INFO - Running pre-action listener: _resource_record_pre_action 12:58:25 INFO - Running main action method: create_virtualenv 12:58:25 INFO - Creating virtualenv /builds/slave/test/build/venv 12:58:25 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:58:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:58:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:58:25 INFO - Using real prefix '/tools/python27' 12:58:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:58:26 INFO - Installing distribute.............................................................................................................................................................................................done. 12:58:28 INFO - Installing pip.................done. 12:58:28 INFO - Return code: 0 12:58:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:58:28 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:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:28 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:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:28 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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:28 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:58:28 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:58:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:28 INFO - 'HOME': '/Users/cltbld', 12:58:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:28 INFO - 'LOGNAME': 'cltbld', 12:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:28 INFO - 'MOZ_NO_REMOTE': '1', 12:58:28 INFO - 'NO_EM_RESTART': '1', 12:58:28 INFO - 'PAGER': '/bin/cat', 12:58:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:28 INFO - 'PWD': '/builds/slave/test', 12:58:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:28 INFO - 'SHELL': '/bin/bash', 12:58:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:28 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:28 INFO - 'USER': 'cltbld', 12:58:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:28 INFO - 'XPC_FLAGS': '0x0', 12:58:28 INFO - 'XPC_SERVICE_NAME': '0', 12:58:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:29 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:58:29 INFO - Cleaning up... 12:58:29 INFO - Return code: 0 12:58:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:58:29 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:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:29 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:58:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:29 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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:29 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:58:29 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:58:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:29 INFO - 'HOME': '/Users/cltbld', 12:58:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:29 INFO - 'LOGNAME': 'cltbld', 12:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:29 INFO - 'MOZ_NO_REMOTE': '1', 12:58:29 INFO - 'NO_EM_RESTART': '1', 12:58:29 INFO - 'PAGER': '/bin/cat', 12:58:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:29 INFO - 'PWD': '/builds/slave/test', 12:58:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:29 INFO - 'SHELL': '/bin/bash', 12:58:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:29 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:29 INFO - 'USER': 'cltbld', 12:58:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:29 INFO - 'XPC_FLAGS': '0x0', 12:58:29 INFO - 'XPC_SERVICE_NAME': '0', 12:58:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:29 INFO - Downloading/unpacking psutil>=0.7.1 12:58:29 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:58:29 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:58:29 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:58:29 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:58:29 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:58:29 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:58:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:58:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:32 INFO - Installing collected packages: psutil 12:58:32 INFO - Running setup.py install for psutil 12:58:32 INFO - building 'psutil._psutil_osx' extension 12:58:32 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:58:33 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:58:33 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:58:33 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:58:33 INFO - building 'psutil._psutil_posix' extension 12:58:33 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:58:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:58:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:58:33 INFO - ^ 12:58:33 INFO - 1 warning generated. 12:58:33 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:58:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:33 INFO - Successfully installed psutil 12:58:33 INFO - Cleaning up... 12:58:33 INFO - Return code: 0 12:58:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58: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', '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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:58: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 mozsystemmonitor==0.0.0 12:58:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:33 INFO - 'HOME': '/Users/cltbld', 12:58:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:33 INFO - 'LOGNAME': 'cltbld', 12:58:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:33 INFO - 'MOZ_NO_REMOTE': '1', 12:58:33 INFO - 'NO_EM_RESTART': '1', 12:58:33 INFO - 'PAGER': '/bin/cat', 12:58:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:33 INFO - 'PWD': '/builds/slave/test', 12:58:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:33 INFO - 'SHELL': '/bin/bash', 12:58:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:33 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:33 INFO - 'USER': 'cltbld', 12:58:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:33 INFO - 'XPC_FLAGS': '0x0', 12:58:33 INFO - 'XPC_SERVICE_NAME': '0', 12:58:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:58:33 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:58:33 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:58:33 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:58:33 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:58:33 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:58:33 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:58:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:58:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:58:36 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:58:36 INFO - Installing collected packages: mozsystemmonitor 12:58:36 INFO - Running setup.py install for mozsystemmonitor 12:58:36 INFO - Successfully installed mozsystemmonitor 12:58:36 INFO - Cleaning up... 12:58:36 INFO - Return code: 0 12:58:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:58:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:58:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:36 INFO - 'HOME': '/Users/cltbld', 12:58:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:36 INFO - 'LOGNAME': 'cltbld', 12:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:36 INFO - 'MOZ_NO_REMOTE': '1', 12:58:36 INFO - 'NO_EM_RESTART': '1', 12:58:36 INFO - 'PAGER': '/bin/cat', 12:58:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:36 INFO - 'PWD': '/builds/slave/test', 12:58:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:36 INFO - 'SHELL': '/bin/bash', 12:58:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:36 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:36 INFO - 'USER': 'cltbld', 12:58:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:36 INFO - 'XPC_FLAGS': '0x0', 12:58:36 INFO - 'XPC_SERVICE_NAME': '0', 12:58:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:36 INFO - Downloading/unpacking blobuploader==1.2.4 12:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:39 INFO - Downloading blobuploader-1.2.4.tar.gz 12:58:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:58:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:58:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:58:40 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:58:40 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:58:40 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:58:40 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:58:40 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:58:40 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:58:40 INFO - Downloading docopt-0.6.1.tar.gz 12:58:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:58:40 INFO - Installing collected packages: blobuploader, requests, docopt 12:58:40 INFO - Running setup.py install for blobuploader 12:58:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:58:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:58:40 INFO - Running setup.py install for requests 12:58:41 INFO - Running setup.py install for docopt 12:58:41 INFO - Successfully installed blobuploader requests docopt 12:58:41 INFO - Cleaning up... 12:58:41 INFO - Return code: 0 12:58:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:58: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:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58: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:58:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:41 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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:41 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:58:41 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:58:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:41 INFO - 'HOME': '/Users/cltbld', 12:58:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:41 INFO - 'LOGNAME': 'cltbld', 12:58:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:41 INFO - 'MOZ_NO_REMOTE': '1', 12:58:41 INFO - 'NO_EM_RESTART': '1', 12:58:41 INFO - 'PAGER': '/bin/cat', 12:58:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:41 INFO - 'PWD': '/builds/slave/test', 12:58:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:41 INFO - 'SHELL': '/bin/bash', 12:58:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:41 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:41 INFO - 'USER': 'cltbld', 12:58:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:41 INFO - 'XPC_FLAGS': '0x0', 12:58:41 INFO - 'XPC_SERVICE_NAME': '0', 12:58:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:58:41 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-JuiYOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:58:41 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Pfemzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:58:41 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-bMKyNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:58:41 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-wOR4RT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-GUcBOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-tpxsyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-g3H30D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-IelNpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-wBwYKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Vk3VsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-G6yVq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-g6yWWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:58:42 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-AzA9Bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:58:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-34QUFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-F8lV23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:58:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-QzU9SJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:58:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-n15hSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:58:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:58:43 INFO - Running setup.py install for manifestparser 12:58:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:58:43 INFO - Running setup.py install for mozcrash 12:58:43 INFO - Running setup.py install for mozdebug 12:58:43 INFO - Running setup.py install for mozdevice 12:58:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Running setup.py install for mozfile 12:58:44 INFO - Running setup.py install for mozhttpd 12:58:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Running setup.py install for mozinfo 12:58:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Running setup.py install for mozInstall 12:58:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Running setup.py install for mozleak 12:58:44 INFO - Running setup.py install for mozlog 12:58:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:58:44 INFO - Running setup.py install for moznetwork 12:58:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:58:45 INFO - Running setup.py install for mozprocess 12:58:45 INFO - Running setup.py install for mozprofile 12:58:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:58:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:58:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:58:45 INFO - Running setup.py install for mozrunner 12:58:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:58:45 INFO - Running setup.py install for mozscreenshot 12:58:45 INFO - Running setup.py install for moztest 12:58:45 INFO - Running setup.py install for mozversion 12:58:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:58:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:58:45 INFO - Cleaning up... 12:58:46 INFO - Return code: 0 12:58:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:58:46 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:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:46 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:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:46 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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:46 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:58:46 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:58:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:46 INFO - 'HOME': '/Users/cltbld', 12:58:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:46 INFO - 'LOGNAME': 'cltbld', 12:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:46 INFO - 'MOZ_NO_REMOTE': '1', 12:58:46 INFO - 'NO_EM_RESTART': '1', 12:58:46 INFO - 'PAGER': '/bin/cat', 12:58:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:46 INFO - 'PWD': '/builds/slave/test', 12:58:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:46 INFO - 'SHELL': '/bin/bash', 12:58:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:46 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:46 INFO - 'USER': 'cltbld', 12:58:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:46 INFO - 'XPC_FLAGS': '0x0', 12:58:46 INFO - 'XPC_SERVICE_NAME': '0', 12:58:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:58:46 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-v3XX5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:58:46 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:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:58:46 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-QDd_Jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:58:46 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:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:58:46 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-6qf51D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:58:46 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:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:58:46 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Vb8nBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:58:46 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:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:58:46 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ahwwIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:58:46 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:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:58:46 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-scqgY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:58:46 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:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:58:46 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-t0rg5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-5XCCWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-NN5b5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-RUg2ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-33RVTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-rYRzpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ncHB2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-wVO_YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-W8WF5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-bfBYI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:58:47 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:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:58:47 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-gumy5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:58:48 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:58:48 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:58:48 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:58:48 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:58:48 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:58:48 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:58:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:58:48 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:58:48 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:58:48 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:58:48 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:58:48 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:58:48 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:58:50 INFO - Downloading blessings-1.6.tar.gz 12:58:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:58:50 INFO - Installing collected packages: blessings 12:58:50 INFO - Running setup.py install for blessings 12:58:50 INFO - Successfully installed blessings 12:58:50 INFO - Cleaning up... 12:58:50 INFO - Return code: 0 12:58:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:58:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:58:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:58:51 INFO - Reading from file tmpfile_stdout 12:58:51 INFO - Current package versions: 12:58:51 INFO - blessings == 1.6 12:58:51 INFO - blobuploader == 1.2.4 12:58:51 INFO - docopt == 0.6.1 12:58:51 INFO - manifestparser == 1.1 12:58:51 INFO - mozInstall == 1.12 12:58:51 INFO - mozcrash == 0.17 12:58:51 INFO - mozdebug == 0.1 12:58:51 INFO - mozdevice == 0.48 12:58:51 INFO - mozfile == 1.2 12:58:51 INFO - mozhttpd == 0.7 12:58:51 INFO - mozinfo == 0.9 12:58:51 INFO - mozleak == 0.1 12:58:51 INFO - mozlog == 3.1 12:58:51 INFO - moznetwork == 0.27 12:58:51 INFO - mozprocess == 0.22 12:58:51 INFO - mozprofile == 0.28 12:58:51 INFO - mozrunner == 6.11 12:58:51 INFO - mozscreenshot == 0.1 12:58:51 INFO - mozsystemmonitor == 0.0 12:58:51 INFO - moztest == 0.7 12:58:51 INFO - mozversion == 1.4 12:58:51 INFO - psutil == 3.1.1 12:58:51 INFO - requests == 1.2.3 12:58:51 INFO - wsgiref == 0.1.2 12:58:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:58:51 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:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 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:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:51 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:58:51 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:58:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:51 INFO - 'HOME': '/Users/cltbld', 12:58:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:51 INFO - 'LOGNAME': 'cltbld', 12:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:51 INFO - 'MOZ_NO_REMOTE': '1', 12:58:51 INFO - 'NO_EM_RESTART': '1', 12:58:51 INFO - 'PAGER': '/bin/cat', 12:58:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:51 INFO - 'PWD': '/builds/slave/test', 12:58:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:51 INFO - 'SHELL': '/bin/bash', 12:58:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:51 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:51 INFO - 'USER': 'cltbld', 12:58:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:51 INFO - 'XPC_FLAGS': '0x0', 12:58:51 INFO - 'XPC_SERVICE_NAME': '0', 12:58:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:51 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:58:51 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:58:51 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:58:51 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:58:51 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:58:51 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:58:51 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:58:51 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:58:51 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:58:51 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:58:51 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:58:51 INFO - Cleaning up... 12:58:51 INFO - Return code: 0 12:58:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:58:51 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:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 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:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 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 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58:51 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:58:51 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:58:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:51 INFO - 'HOME': '/Users/cltbld', 12:58:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:51 INFO - 'LOGNAME': 'cltbld', 12:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:51 INFO - 'MOZ_NO_REMOTE': '1', 12:58:51 INFO - 'NO_EM_RESTART': '1', 12:58:51 INFO - 'PAGER': '/bin/cat', 12:58:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:51 INFO - 'PWD': '/builds/slave/test', 12:58:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:51 INFO - 'SHELL': '/bin/bash', 12:58:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:51 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:51 INFO - 'USER': 'cltbld', 12:58:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:51 INFO - 'XPC_FLAGS': '0x0', 12:58:51 INFO - 'XPC_SERVICE_NAME': '0', 12:58:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:52 INFO - Downloading/unpacking jsonschema 12:58: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:58: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:58: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:58: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:58: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:58: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:58:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:58:54 INFO - Downloading/unpacking functools32 (from jsonschema) 12:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:55 INFO - Downloading functools32-3.2.3-2.tar.gz 12:58:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:58:55 INFO - warning: no files found matching '*.txt' 12:58:55 INFO - no previously-included directories found matching 'build' 12:58:55 INFO - no previously-included directories found matching 'dist' 12:58:55 INFO - no previously-included directories found matching '.git*' 12:58:55 INFO - Installing collected packages: jsonschema, functools32 12:58:55 INFO - Running setup.py install for functools32 12:58:55 INFO - warning: no files found matching '*.txt' 12:58:55 INFO - no previously-included directories found matching 'build' 12:58:55 INFO - no previously-included directories found matching 'dist' 12:58:55 INFO - no previously-included directories found matching '.git*' 12:58:55 INFO - Successfully installed jsonschema functools32 12:58:55 INFO - Cleaning up... 12:58:55 INFO - Return code: 0 12:58:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:58: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:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58: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:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110001a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc181d89430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, '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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:58: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', 'flake8'] in /builds/slave/test/build 12:58: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 flake8 12:58:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:58:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:55 INFO - 'HOME': '/Users/cltbld', 12:58:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:55 INFO - 'LOGNAME': 'cltbld', 12:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:55 INFO - 'MOZ_NO_REMOTE': '1', 12:58:55 INFO - 'NO_EM_RESTART': '1', 12:58:55 INFO - 'PAGER': '/bin/cat', 12:58:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:55 INFO - 'PWD': '/builds/slave/test', 12:58:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:55 INFO - 'SHELL': '/bin/bash', 12:58:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:58:55 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:58:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:55 INFO - 'USER': 'cltbld', 12:58:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:55 INFO - 'XPC_FLAGS': '0x0', 12:58:55 INFO - 'XPC_SERVICE_NAME': '0', 12:58:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:56 INFO - Downloading/unpacking flake8 12:58: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:58: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:58: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:58: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:58: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:58: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:58:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:58:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:58: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:58: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:58: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:58: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:58: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:58: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:58:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:58:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:58: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:58: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:58: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:58: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:58: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:58: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:58:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:58: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:58: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:58: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:58: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:58: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:58: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:58:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:58:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:58:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:58:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:58:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:58:59 INFO - no previously-included directories found matching 'docs/_build' 12:58:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:59:00 INFO - Running setup.py install for pep8 12:59:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:59:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:59:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:59:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:59:00 INFO - no previously-included directories found matching 'docs/_build' 12:59:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:59:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:59:00 INFO - Cleaning up... 12:59:00 INFO - Return code: 0 12:59:00 INFO - Running post-action listener: _resource_record_post_action 12:59:00 INFO - Running post-action listener: _start_resource_monitoring 12:59:00 INFO - Starting resource monitoring. 12:59:00 INFO - ##### 12:59:00 INFO - ##### Running install step. 12:59:00 INFO - ##### 12:59:00 INFO - Running pre-action listener: _resource_record_pre_action 12:59:00 INFO - Running main action method: install 12:59:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:59:00 INFO - Reading from file tmpfile_stdout 12:59:00 INFO - Detecting whether we're running mozinstall >=1.0... 12:59:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:59:00 INFO - Reading from file tmpfile_stdout 12:59:00 INFO - Output received: 12:59:00 INFO - Usage: mozinstall [options] installer 12:59:00 INFO - Options: 12:59:00 INFO - -h, --help show this help message and exit 12:59:00 INFO - -d DEST, --destination=DEST 12:59:00 INFO - Directory to install application into. [default: 12:59:00 INFO - "/builds/slave/test"] 12:59:00 INFO - --app=APP Application being installed. [default: firefox] 12:59:00 INFO - mkdir: /builds/slave/test/build/application 12:59:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:59:23 INFO - Reading from file tmpfile_stdout 12:59:23 INFO - Output received: 12:59:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:59:23 INFO - Running post-action listener: _resource_record_post_action 12:59:23 INFO - ##### 12:59:23 INFO - ##### Running run-tests step. 12:59:23 INFO - ##### 12:59:23 INFO - Running pre-action listener: _resource_record_pre_action 12:59:23 INFO - Running main action method: run_tests 12:59:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:23 INFO - 2016-04-29 12:59:23.523 screenresolution[1638:7418] starting screenresolution argv=screenresolution get 12:59:23 INFO - 2016-04-29 12:59:23.525 screenresolution[1638:7418] Display 0: 1600x1200x32@60 12:59:23 INFO - 2016-04-29 12:59:23.531 screenresolution[1639:7421] starting screenresolution argv=screenresolution list 12:59:23 INFO - Available Modes on Display 0 12:59:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:23 INFO - Intel Iris: 12:59:23 INFO - Chipset Model: Intel Iris 12:59:23 INFO - Type: GPU 12:59:23 INFO - Bus: Built-In 12:59:23 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:23 INFO - Vendor: Intel (0x8086) 12:59:23 INFO - Device ID: 0x0a2e 12:59:23 INFO - Revision ID: 0x0009 12:59:23 INFO - Displays: 12:59:23 INFO - Display: 12:59:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:23 INFO - Display Serial Number: 1600x1200 60 12:59:23 INFO - Main Display: Yes 12:59:23 INFO - Mirror: Off 12:59:23 INFO - Online: Yes 12:59:23 INFO - Rotation: Supported 12:59:23 INFO - Return code: 0 12:59:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:59:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:59:23 INFO - Python 2.7.3 12:59:23 INFO - Return code: 0 12:59:23 INFO - grabbing minidump binary from tooltool 12:59:23 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:59:23 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 0x10fea5d30>, '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 0x10fd09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe9cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:59:23 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:59:23 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:59:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:59:23 INFO - Return code: 0 12:59:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:59:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:59:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:59:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:59:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:59:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:59:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:59:25 INFO - Return code: 0 12:59:25 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/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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:59:25 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/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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:59:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrJbcZuS1v/Render', 12:59:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:25 INFO - 'HOME': '/Users/cltbld', 12:59:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:25 INFO - 'LOGNAME': 'cltbld', 12:59:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:59:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:25 INFO - 'MOZ_NO_REMOTE': '1', 12:59:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:59:25 INFO - 'NO_EM_RESTART': '1', 12:59:25 INFO - 'PAGER': '/bin/cat', 12:59:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:25 INFO - 'PWD': '/builds/slave/test', 12:59:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:59:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:25 INFO - 'SHELL': '/bin/bash', 12:59:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dMl1GoQKG4/Listeners', 12:59:25 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 12:59:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:25 INFO - 'USER': 'cltbld', 12:59:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:25 INFO - 'XPC_FLAGS': '0x0', 12:59:25 INFO - 'XPC_SERVICE_NAME': '0', 12:59:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:25 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/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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:59:25 INFO - mozversion application_buildid: 20160429115741 12:59:25 INFO - mozversion application_changeset: caa76bbe680cded163aa36b046ce65c20b6b727d 12:59:25 INFO - mozversion application_display_name: Firefox 12:59:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:59:25 INFO - mozversion application_name: Firefox 12:59:25 INFO - mozversion application_remotingname: firefox 12:59:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:59:25 INFO - mozversion application_vendor: Mozilla 12:59:25 INFO - mozversion application_version: 47.0 12:59:25 INFO - mozversion platform_buildid: 20160429115741 12:59:25 INFO - mozversion platform_changeset: caa76bbe680cded163aa36b046ce65c20b6b727d 12:59:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:59:25 INFO - mozversion platform_version: 47.0 12:59:25 INFO - using testdate: 1461959965 12:59:25 INFO - actual date: 1461959965 12:59:25 INFO - starting webserver on 'localhost:49274' 12:59:25 INFO - SUITE-START | Running 2 tests 12:59:25 INFO - TEST-START | dromaeo_css 12:59:25 INFO - operating with platform_type : mac_ 12:59:25 INFO - Initialising browser for dromaeo_css test... 12:59:25 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp9k9RDS/profile http://localhost:49274/getInfo.html) 12:59:27 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 12:59:27 INFO - PROCESS | 1657 | colorDepth:24 12:59:27 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 12:59:27 INFO - PROCESS | 1657 | __metrics 12:59:27 INFO - PROCESS | 1657 | 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:59:27 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:59:28 INFO - TEST-INFO | 1657: exit 0 12:59:28 INFO - Browser initialized. 12:59:28 INFO - Running cycle 1/1 for dromaeo_css test... 12:59:28 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp9k9RDS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:59:40 INFO - PROCESS | 1660 | RSS: Main: 157130752 12:59:40 INFO - PROCESS | 1660 | 13:00:31 INFO - PROCESS | 1660 | 1461960031909 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] 13:02:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 13:02:42 INFO - PROCESS | 1660 | RSS: Main: 294932480 13:02:42 INFO - PROCESS | 1660 | 13:05:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 13:05:44 INFO - PROCESS | 1660 | RSS: Main: 293265408 13:05:44 INFO - PROCESS | 1660 | 13:08:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 13:08:47 INFO - PROCESS | 1660 | RSS: Main: 294764544 13:08:47 INFO - PROCESS | 1660 | 13:11:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 13:11:49 INFO - PROCESS | 1660 | RSS: Main: 251555840 13:11:49 INFO - PROCESS | 1660 | 13:14:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 13:14:53 INFO - PROCESS | 1660 | RSS: Main: 254738432 13:14:53 INFO - PROCESS | 1660 | 13:17:55 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 13:17:56 INFO - PROCESS | 1660 | RSS: Main: 298987520 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | __start_tp_report 13:17:56 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 13:17:56 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 13:17:56 INFO - PROCESS | 1660 | |i|pagename|runs| 13:17:56 INFO - PROCESS | 1660 | |0;dojo.html;3579.1767893384526;3617.204215072683;3638.108663377471;3658.213484100919;3693.6675699187067;5952.940470595304;5955.434233747797;5995.550333724972;6012.579119461634;6033.06487494438;5984.371640977689;6032.065029920358;6038.637681739098;6041.335453100155;6041.909371359422;5193.63644544882;5391.32608423947;5397.514223719864;5407.675539467645;5462.6995515246645;5393.703346315951;5398.433164517728;5401.729913504329;5416.241726158341;5416.702081385522;5353.464653534655;5401.918971215416;5406.59450541209;5439;5440.129579267318;4772.880677983044;5423.972880135594;5452.83641490754;5458.6724796512335;5479.835604931858;5261.631685781997;5340.118880384732;5364.195370694388;5387.272718183073;5401.216823560601;5919.112133180058;5953.404659534012;5953.732082056318;5978.342382337939;6006.969965150146;6133.365267548864;6200.968995155041;6208.037754148069;6238.968805155945;6244.906326405107;6265.154204182468;6280.371962803729;6302.369763023707;6305.0857625644485;6327.050942358683;6106.236720409949;6110.052941794029;6110.511159107261;6120.846978825476;6123.050927106306;5423.945760542423;6253.1245625612455;6304.464120549805;6310;6423.357664233539;6097.024476083805;6141.57009009374;6203.534734894901;6204.13142160098;6216.378362163748;6271.3728627136925;6296.811095667045;6299.559030867887;6304.085907543472;6306;6144.78493252734;6162.322144564095;6209.9068513972325;6215.098810672427;6216.813495595139;6123.1606109212;6201.069839524108;6212.9068063978175;6217.285012223652;6217.4715149212825;4228.492580890313;4945.752712364368;4955.083309587738;4981.676191047571;4983.90032199354;4460.531644177315;4500.504944456107;4551.067031258584;4565.429321334833;4576.656750743708;6083.6349819011;6091.42131497507;6098.054801505774;6098.908516372258;6099.725512351954;6252.406021427957;6331.27190373113;6331.493480521547;6342.04869269604;6353.491720662336;6029.276486821609;6101.328853826076;6105.358937311607;6118.05170198611;6141.785037523665;5465.398806131322;5477.972610136924;5502.642328248651;5502.972485137549;5505.587080968863;6340.112384266206;6356.364363563606;6389.2652344980925;6413.583117097373;6417.743290268337;6290.21372328459;6336.841578960379;6339.873202536016;6341.8731625368155;6342.714577843916;6397.616143031433;6408.93591064102;6413.422791948747;6414.711337989708;6438.195225596801;6235.844103897439;6298.527610429144;6356.713947872449;6373.968130159319;6380.1067850501895;6308.779192728232;6320;6320.557560970872;6321.905171422341;6325.746970121144;6246.968765156145;6311.147995020797;6313.779017734358;6314;6325.05124231369;6118.847028824315;6229.563930525001;6239.532035097295;6303.653299068412;6308.747650094128;6051.227958444376;6136.488442913377;6161.260648722182;6166;6174.629522228681;4511.616512596466;6324.7153878076515;6334;6343.873122537616;6349.142865713255;6009.278886533644;6117.877642447037;6165.106059621419;6183.96908015475;6198.752049918133 13:17:56 INFO - PROCESS | 1660 | |1;ext.html;553.7637076365803;560.495554001399;592.6473748119869;595.033071259204;665.5141746525036;38792.806035969814;39284.41073383902;39351.40972885409;39404.80297598515;39538.406923896095;42655.29378824851;43269;43329.566704332945;43346.7832660837;43392.6982190534;9839.75400614985;9956.60173593059;9983.600655973753;10047.899521004805;10070.69787906364;9816.361936474143;9852.90147098527;9902.25733070019;9946.35348702332;9955;9947.850782238236;10024.799504009916;10062;10076.39541627501;10085.949570252174;9992.300538962276;10051.949740251324;10056.597736090547;10070.244731645156;10072.345297555636;9773.853392199122;9946;9951.154151897097;9955;9962.701118966443;41492.37761433581;41899.7905010476;42299.78850105761;42351.78824105891;42352.576474235175;3501.8074005929666;3579.480975258599;3627.546556680415;3926.548446928611;4033.9713373089967;28957;29958.95143669983;30021.549676754865;30065.849670751726;30181.547276790865;2770.1135636596296;2782.6660800703844;2810.2131403207136;2815.81697189682;2837.6311079559523;458971;460441;461356;461793;463422.68288658676;46979;64280.35719642837;64617.67691161608;64652.70694586082;64803.351966479735;46188.38340658128;46207.53792462099;46323.07353852904;46348.3047754284;46434;16870.325186992628;17278.395256165975;17280.876743011664;17299.481015569552;17318.82681173172;13880.791788123186;13979.580612581638;13979.720405591832;13992.250968628921;14018.579442616738;14795.852041479662;14870.479533216501;15044.924775376272;15104.924475377773;15188.316525756367;6057.697115144225;6061.605500919499;6336.968315158487;6505.316941721146;6530.8367290818105;976.511744127936;1017.7353887989176;1099.8790133085354;1115.570505355444;1142.405948906564;30323.241918952204;30328.241793955327;30733.84633076865;30885.845570772002;30897.691023089483;1160.2516376936828;1214.5931113077218;1229.342301868514;1244.6452760963082;1264.3046324521476;15190.696186076217;15394.384224631114;15463;15755.44855930037;15827.68344633101;532543;534152;536244;536892;538138;60841.39158608446;60871.78256434847;60990;61054.77890442166;61415.157545273716;1424.2593851197319;1520.9923950380398;1526.9540364850113;1550.325608360367;1551.317420335049;24743.752562474147;26141.73858261393;28393.574096388158;28946;29477.347038766613;44772.55227447684;45154.54845451635;45486.63540093802;45508.95209715637;45638.77180614076;352498;361531;362097;362326;363038;29708.55437168401;34006.82996585001;34820.47769283412;34825.47761783525;34851.9544413668;41718;42123.57876421197;42275.3658695126;43816.561834381246;43935.56064439315;34717.30565388627;35628.64371356253;35656.465153023244;35981.64018359783;36461.817690911375;26370.868145659915;35497.82251088728;35798.28403431865;36341.45487817738;37541.81229093837;34030;35754.64245357513;36747.816260918524;37019.62980370269;37339.69311074099;31762.364752705278;32430.351392971537;32499.18752031218;32570.34859302848;32586.02241932746;24890.476713204218;25004.999800007798;25078.705138071124;25229.11698090558;25420 13:17:56 INFO - PROCESS | 1660 | |2;jquery.html;379.133679542246;382.5294887288635;383.33108725274394;386.24681870352816;388.854179682619;20387.59224815503;22205.66691499627;22589.887050564765;22953;22956.08175672971;21550.568988620256;21617.891910540464;22402.551948961012;22575.774242257572;22608.43478913024;5776.624519406225;5956.374580669032;6077.9363611367835;6126.356732543086;6152.138700581922;5943.435373639497;5973.372795856438;5997.520198384141;6000.519958403339;6003.849903752397;5813.854653633651;5991.970040149793;6037.366076561963;6594.505412094089;6613.801585952429;6547.279799222106;6585.242697089848;6585.80242592723;6594.274629790719;6607.471402287805;5654.180143879114;5781.913271300934;5796.623219490762;5851.23933888596;5854.356020837705;10747.677569672922;12326.445309961073;14468.710625787426;14579.927100364537;26552.867235663893;1931.777845547766;2101.422108920059;2195.5938151441874;2309.86140831549;2379.821513386503;9530.475823829687;10319.638812641597;10398.68803935883;10669.986351296615;10688.572457101707;1629.527437043256;1695.5252529291818;1742.5120966129505;1787.5709829640793;1851.6481868445087;94875.52562237214;96639.51680241624;98603;99238;99694;11580.305181688957;11584.768304634028;11662.650120496399;11684.70788230301;11768.352740599177;12083.637490875111;14695.632609184855;14699.26503674812;14728.189949552663;14738.63153421173;8872.467651940775;9114;9130.26957843371;9139.725808225763;9202.4478531287;8283.668653253922;8385.909831721838;8593.097724738938;9313.953430232941;9422.434653920649;4295.935560966588;4553.612942899825;4560.6807523473035;4564.863054108382;4654.371659825948;1628.210317995771;1674.7655328254054;1896.838768704648;1923.7306777051224;1970.1134489479791;906.4787747045475;1029.0789743179812;1053.0943388685723;1055.1137044882337;1070.347088276151;15880.602984925239;20814.479638009172;20869.269575564784;20946.847923364057;21137.577248454945;1013.8732658417698;1014.4724743133529;1023.8822535408375;1034.7826956339102;1068.2522234435926;11058.723531911606;11087.556497740161;11115.83326250107;11129.777404451866;11131.332120072822;640995;654798;655328;659584;663932;107583;112200.4389978045;112696;113178.86821131848;115054.4247278775;1062.5006247063868;1108.2685427617735;1127.115214556569;1132.2074547816562;1133.5125895864692;18676.43970680882;18775.812241877407;19302.22791088341;19374.418767437;19701.113449894503;54253;54398.393262838974;55017.72491137519;56300;56894.7155264221;76605;77575.61212193903;77759.61120194363;78145;78316.60841695755;8132.959335203286;8338.541380224146;8367.372447066373;8470.280026197595;8495;9859.704208873745;11288.717782055513;13449.46202151903;13525.526606568723;13629.38667759973;11005.284656497302;11874.643760687193;11914.52341906314;12032.458539365574;12036.819447708116;5446.727663616724;10357.896421035995;11832.112591555495;12035.841257335984;12252.387380630755;6228.408301211378;9782.41305521671;12042.976347010252;12449.626511204679;12492.25046497213;7991;8251.917480825356;8299.626516806878;8344.040435349769;8593.183647553473;8793.076726943578;11512.59705910289;12117.454714537691;12214.083943704436;12234.082443816927 13:17:56 INFO - PROCESS | 1660 | |3;mootools.html;1494.185668810498;1636.0756172762387;1660.2362913059992;2015.6170327637747;2084.551821358408;8297.460665056775;8897.15477029682;9050.728478145666;9064.093590640932;9071.727848164566;8765.517896515688;9029.954850225757;9034.186923176912;9050.45697258165;9060.546972651375;2572.2540463265677;2592.118679648919;2595.2344058502695;2595.6625638666947;2597.5324441600505;2390.4501964548163;2466.580681284186;2467.728549859523;2474.455619763658;2489.3652118709792;2579.877753177374;2582.987085064572;2615.189291319697;2620.3318153870737;2621.0695203202877;2577.420080481895;2597.9740202598014;2609.856457894815;2612.8562929038894;2614.8038897082606;2562.5771747661615;2592.585186370172;2592.6240695099295;2608.8434693918234;2616.816822822403;9227.215686666641;9261.675841345588;9265.44407335562;9280.53597320138;9321;5700.344460387067;5809.912851307234;5811.418858114197;5873.295204575478;5883.676397798119;3624.238909828939;3747.2505498900055;3768.152165762709;3774.0564858785306;3809.5428548574073;1048.077691631367;1065.6536625596634;1069.2835800013931;1095.1895597257972;1098.0721290509587;12471.501139954391;12609.747805043848;12620.936895315557;12625.747485050248;12627.43176557057;5027.773750181251;5053.823116190915;5111.5655169310285;5115.565176959925;5138.691678499302;9089.95455022734;10663.520141593646;10666.14670826332;10667.359958402365;10670.679879603469;5829.912551311733;5839.919614871177;5851.531877449794;5853.326867410302;5873.353931067579;4640.443146822403;4760.571548560646;5017.397912250481;5149.691018538825;5259.76331065103;3588.407912694376;3763.8118094095184;4000.8399664013696;4054.6350828425584;4116.814743336557;1357.565579014706;1362.7274545091022;1363.9318034098453;1371.6570857285678;1388.7361401333715;1387.537836329781;1401.628568429367;1434.9811633740162;1435.8277006759256;1447.9638009049859;8502.404831661725;8581.227689507848;8599.312055035582;8617.526036068077;8647.437916535406;535.6036532965655;541.9160030195326;547.1164070026878;548.4652463847716;550.900837849191;6265.655388953652;6371.267304259976;6435.806925792233;6441.710123044474;6442;10890.96535829095;11311.490982905625;11409.543618255177;11420.600278990196;11445.942770286096;10757.78484430307;11104.500297486633;11136.276142050741;11185.161112916563;11286.153538484647;8720.781980450412;8727.301815854844;8812.779680508038;8814.471131732116;8834.77913052166;8112.148224436585;8186.8771968421715;8216.917830821616;8287.62705678258;8293.170682931659;9606.951965240129;9916.157126644315;9988.350757200758;10015.39907605546;10167.135793457639;9762.707118786448;10065;10136.037076477724;10261.74345641365;10265.897341026493;7493.812654683677;7753.612319383896;7792.337651299703;7798.727044553413;7991.24083212094;8589.613467394107;8590.484570925764;8641.179087986631;8642.56787160652;8664.523451210243;7490.550566966;7663.88504172449;7708.152103268748;7753.147153813189;7768;7463.850722985401;7472.252774722701;7525;7656.157822639394;7664.961675191811;7988.960055199919;8088.757337279891;8096;8103.594820258845;8105.311048560939;7133.322334378226;7323.487355885037;7324.4140468763435;7325.084364454443;7329.1205055391565;7394.408447324308;7424.740134095281;7448.8882666758955;7448.994385757854;7464.253574642492 13:17:56 INFO - PROCESS | 1660 | |4;prototype.html;333.2851034530931;335.79348700549167;341.330991257136;343.1335876910801;343.8830797528841;6602.778485980097;6835.692393842277;6897.344752248541;6901.758438454649;6921.100256966593;6641.203055633331;6653.36793004665;6655.301193374698;6659.866802663943;6673.399394054534;4744.833930812422;4900.803967841282;4920.23736320869;4986.825461108862;5031.773570189332;4986.127427700138;5003.47463516331;5013.899722005558;5017.071841709278;5022.949770502303;4958.281049247857;4963.255511673242;4964;4990.600751939836;4991.675541089818;4721.669483136182;4781.521847815191;4857.368542089541;4857.951420485803;4899.093667671492;4208.600182982612;4211.684123690705;4218.662506999463;4220.36694495825;4253.766042867641;6308.085327627514;6371.936280637181;6372.107904893319;6384.297727249998;6408.711607977605;4670.369500117508;5814.680192589406;5828.795992140297;5829.446202610788;5830.15462757902;6767.627780472072;7016.7544135954995;7018.508704390695;7024.2273349931465;7024.75413360535;4027.496562929634;4778.116048531033;4784.641151913585;4806;4830.130576496229;7345;7394.519356241827;7404.4816862819625;7417.51786133905;7422.814429639302;4834.178189707758;4888.071266459361;4895.143349913751;4897.926531102036;4898.044881248194;4951.653384263139;4958.404991401055;4960.429550601727;4975.129352363322;4979.4771548987555;5610.719464026783;5849.093390524476;5854.004819180749;5858.213244960243;5869.941300586939;5699.344575373885;7043.190033146254;7049.189343225595;7069.222385537587;7070.292970702992;1690.9323627055026;1701.8978861268363;1732.1252767352407;1733.0468242466593;1740.0777587878445;944.367273926471;1052.6578861869923;1103.100972707241;1130.5082289204222;1136.4601814138384;6014.248218972629;6531.477481801445;6683.365080317362;6807.2512023677355;6846.965765171142;7442.367398771057;7442.66508007141;7448.292412220831;7453.105627324756;7460.626968651546;6779.5254332197255;6806.013128096398;6936.34104760047;6948.756793512202;6975.128108986377;3788.886333410002;3884.2231553689376;3909.863154789569;3918.0988372673874;3924.548676902136;7731.420143489261;7758.61206939662;7776.7278145264645;7778.416618753616;7779.533228006338;6270.529710271748;7561;7588.27911348421;7601.619919004028;7606.505577137468;7705.961470192613;7743.99328087345;7752.1472638008645;7760.495567788075;7787.06555213378;6175.44421002112;7009.439244860501;7314.487985841153;7414.221506741715;7444.627768611439;7207.747728829466;7260.4554658399775;7260.709571617287;7275;7291.817704557428;7668.079830420385;7682.8079298018;7683.500572462772;7695.4997925134685;7729.6521656524465;4926.802927882988;4936.308916751729;4936.925946110883;4945.752712364296;4946.332245146859;5748.597598168088;7283.2352602975925;7292.161401438902;7332.5967071811565;7345.6694448751;7122.537035092702;7147.142342918883;7165.319294666998;7174.928250717426;7190.96404517974;7122.5726456412785;7127.928720712934;7129.572225666477;7138.928610713826;7142.392896603847;6959.61722105289;6961.89557156633;6963.651817409211;6964.303569643198;6970.895436568354;6865.794026179223;7115.324044215791;7134.464915131282;7149.0706208192605;7167.390771784457;6717.596944183365;6828.385445309946;6881.3806757390075;6969.337912898267;7038.894416583653 13:17:56 INFO - PROCESS | 1660 | |5;yui.html;721.1166321256461;743.2790193512291;795.3915254830056;802.7070119406418;803.4817542684968;15328.386864525419;15639.452619158332;15648.295826687798;15657.138857362841;15665.373385064599;14474;14617.707645847104;14619.269036548183;14628.34172462239;14639.975201735882;788.1094363369384;801.9599020049003;972.6401231544303;974.1378879691454;975.0736800039955;946.9100435458621;961.2838435365662;962.6486332488661;963.7927845513213;979.8873129590081;830.6220669595319;1011.2365164300943;1013.2805707947366;1017.3692310767335;1034.9586016559367;1010.9595616175344;1032.5147180825;1033.3438266700662;1033.9918579385078;1034;771.5409331447779;772.7488566216002;772.9188435214278;773.0375682275546;775.042822114688;14975.625609359744;15064.246787660573;15194.924025379802;15407;15425.614359640986;12921.67695807603;12938.353082345939;13192.538261160813;13363.599092027254;13502.054856160074;10224.969295136138;10372.308552710425;10429.530671119817;10559.841602375971;11044.061254793352;3580.104973756561;3597.1726502904216;3599.31612993533;3630.6550877666577;3717.5724791649036;85835;86292;88446;88806;88860.6670899937;9691.951540242253;9768.755781105387;9807.901920980841;9820.705378838646;10143.442110683836;22698.43253918665;22892.65661015086;22924.853757312067;22929;22932.312030638775;7552.433567482461;7556.65995030225;7591.58246296459;7685.23147685227;7782.727604533814;4208.053188032729;4227.725197862129;4243.42713733642;4292.656587473057;4359;3693.8707145249787;3757.1358587524473;3760.1539653578275;3760.4359346098304;3762.8118594070734;540.9134538473826;586.3374386942812;610.1671218786324;696.3384784454687;696.5437638346891;589.4812564942823;617.0713076819358;619.6437048696927;621.7077973352517;623.9282482514478;8054.798630034296;8140.674373025131;8395.28640065589;8459.661613535513;8550.657973681107;508.38231548667807;544.5153813106339;589.7198830555452;595.9124597609381;612.5497759147023;4184.205001049791;4280.636145927631;4288.592583704543;4331.566843315643;4379.316486256554;97070;97715.1142442875;99070;99096;99309.50345248372;39450.80274598609;39473.013174670865;39909.401358979056;55613.721931390086;84256.15743842606;72616.63691681508;74137.62931185309;74247.8862817069;74301.62849185828;75093;8551.786205344915;8790.296776257755;8885.555722213734;8916.420432671855;9032.86450703227;48197.27704084512;48983;49415.25877111918;49885;50092.749536252086;67675.98486022615;68922;69916.25209369604;72158.27841721516;72336;11178.720531986766;11281.605143819927;11475.024622906813;11874.109441792072;12107.910288073763;12014.279143251433;12052.841769589477;12564.937175314066;12690.365481726061;12690.682732931382;9638.662646807328;11621.302721836717;12001.699957501132;12030.338331391507;12371.587320622064;11706.824397634213;11795.823062654239;11889.821652675044;12144.90695837379;12205.084618653458;11391.772164556829;11542.538298467967;11688.532458701557;11782.057435405319;11826.408679566159;9842.901570984199;9971.283741603442;9974.750631234278;10153.39079655223;10251.948740256252;9948.900510994798;10182.592696292066;10189.184865210913;10200.795984080129;10202.132818710492 13:17:56 INFO - PROCESS | 1660 | __end_tp_report 13:17:56 INFO - PROCESS | 1660 | __start_cc_report 13:17:56 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1111 13:17:56 INFO - PROCESS | 1660 | __end_cc_report 13:17:56 INFO - PROCESS | 1660 | __startTimestamp1461961076168__endTimestamp 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | ------- Summary: start ------- 13:17:56 INFO - PROCESS | 1660 | Number of tests: 6 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5878.83 Median:6133.37 stddev:631.33 (10.3%) stddev-sans-first:606.95 13:17:56 INFO - PROCESS | 1660 | Values: 3579.2 3617.2 3638.1 3658.2 3693.7 5952.9 5955.4 5995.6 6012.6 6033.1 5984.4 6032.1 6038.6 6041.3 6041.9 5193.6 5391.3 5397.5 5407.7 5462.7 5393.7 5398.4 5401.7 5416.2 5416.7 5353.5 5401.9 5406.6 5439.0 5440.1 4772.9 5424.0 5452.8 5458.7 5479.8 5261.6 5340.1 5364.2 5387.3 5401.2 5919.1 5953.4 5953.7 5978.3 6007.0 6133.4 6201.0 6208.0 6239.0 6244.9 6265.2 6280.4 6302.4 6305.1 6327.1 6106.2 6110.1 6110.5 6120.8 6123.1 5423.9 6253.1 6304.5 6310.0 6423.4 6097.0 6141.6 6203.5 6204.1 6216.4 6271.4 6296.8 6299.6 6304.1 6306.0 6144.8 6162.3 6209.9 6215.1 6216.8 6123.2 6201.1 6212.9 6217.3 6217.5 4228.5 4945.8 4955.1 4981.7 4983.9 4460.5 4500.5 4551.1 4565.4 4576.7 6083.6 6091.4 6098.1 6098.9 6099.7 6252.4 6331.3 6331.5 6342.0 6353.5 6029.3 6101.3 6105.4 6118.1 6141.8 5465.4 5478.0 5502.6 5503.0 5505.6 6340.1 6356.4 6389.3 6413.6 6417.7 6290.2 6336.8 6339.9 6341.9 6342.7 6397.6 6408.9 6413.4 6414.7 6438.2 6235.8 6298.5 6356.7 6374.0 6380.1 6308.8 6320.0 6320.6 6321.9 6325.7 6247.0 6311.1 6313.8 6314.0 6325.1 6118.8 6229.6 6239.5 6303.7 6308.7 6051.2 6136.5 6161.3 6166.0 6174.6 4511.6 6324.7 6334.0 6343.9 6349.1 6009.3 6117.9 6165.1 6184.0 6198.8 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:59785.51 Median:198756.32 stddev:121689.78 (61.2%) stddev-sans-first:121949.89 13:17:56 INFO - PROCESS | 1660 | Values: 553.8 560.5 592.6 595.0 665.5 38792.8 39284.4 39351.4 39404.8 39538.4 42655.3 43269.0 43329.6 43346.8 43392.7 9839.8 9956.6 9983.6 10047.9 10070.7 9816.4 9852.9 9902.3 9946.4 9955.0 9947.9 10024.8 10062.0 10076.4 10085.9 9992.3 10051.9 10056.6 10070.2 10072.3 9773.9 9946.0 9951.2 9955.0 9962.7 41492.4 41899.8 42299.8 42351.8 42352.6 3501.8 3579.5 3627.5 3926.5 4034.0 28957.0 29959.0 30021.5 30065.8 30181.5 2770.1 2782.7 2810.2 2815.8 2837.6 458971.0 460441.0 461356.0 461793.0 463422.7 46979.0 64280.4 64617.7 64652.7 64803.4 46188.4 46207.5 46323.1 46348.3 46434.0 16870.3 17278.4 17280.9 17299.5 17318.8 13880.8 13979.6 13979.7 13992.3 14018.6 14795.9 14870.5 15044.9 15104.9 15188.3 6057.7 6061.6 6337.0 6505.3 6530.8 976.5 1017.7 1099.9 1115.6 1142.4 30323.2 30328.2 30733.8 30885.8 30897.7 1160.3 1214.6 1229.3 1244.6 1264.3 15190.7 15394.4 15463.0 15755.4 15827.7 532543.0 534152.0 536244.0 536892.0 538138.0 60841.4 60871.8 60990.0 61054.8 61415.2 1424.3 1521.0 1527.0 1550.3 1551.3 24743.8 26141.7 28393.6 28946.0 29477.3 44772.6 45154.5 45486.6 45509.0 45638.8 352498.0 361531.0 362097.0 362326.0 363038.0 29708.6 34006.8 34820.5 34825.5 34852.0 41718.0 42123.6 42275.4 43816.6 43935.6 34717.3 35628.6 35656.5 35981.6 36461.8 26370.9 35497.8 35798.3 36341.5 37541.8 34030.0 35754.6 36747.8 37019.6 37339.7 31762.4 32430.4 32499.2 32570.3 32586.0 24890.5 25005.0 25078.7 25229.1 25420.0 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:35551.08 Median:22582.83 stddev:108123.16 (478.8%) stddev-sans-first:108394.22 13:17:56 INFO - PROCESS | 1660 | Values: 379.1 382.5 383.3 386.2 388.9 20387.6 22205.7 22589.9 22953.0 22956.1 21550.6 21617.9 22402.6 22575.8 22608.4 5776.6 5956.4 6077.9 6126.4 6152.1 5943.4 5973.4 5997.5 6000.5 6003.8 5813.9 5992.0 6037.4 6594.5 6613.8 6547.3 6585.2 6585.8 6594.3 6607.5 5654.2 5781.9 5796.6 5851.2 5854.4 10747.7 12326.4 14468.7 14579.9 26552.9 1931.8 2101.4 2195.6 2309.9 2379.8 9530.5 10319.6 10398.7 10670.0 10688.6 1629.5 1695.5 1742.5 1787.6 1851.6 94875.5 96639.5 98603.0 99238.0 99694.0 11580.3 11584.8 11662.7 11684.7 11768.4 12083.6 14695.6 14699.3 14728.2 14738.6 8872.5 9114.0 9130.3 9139.7 9202.4 8283.7 8385.9 8593.1 9314.0 9422.4 4295.9 4553.6 4560.7 4564.9 4654.4 1628.2 1674.8 1896.8 1923.7 1970.1 906.5 1029.1 1053.1 1055.1 1070.3 15880.6 20814.5 20869.3 20946.8 21137.6 1013.9 1014.5 1023.9 1034.8 1068.3 11058.7 11087.6 11115.8 11129.8 11131.3 640995.0 654798.0 655328.0 659584.0 663932.0 107583.0 112200.4 112696.0 113178.9 115054.4 1062.5 1108.3 1127.1 1132.2 1133.5 18676.4 18775.8 19302.2 19374.4 19701.1 54253.0 54398.4 55017.7 56300.0 56894.7 76605.0 77575.6 77759.6 78145.0 78316.6 8133.0 8338.5 8367.4 8470.3 8495.0 9859.7 11288.7 13449.5 13525.5 13629.4 11005.3 11874.6 11914.5 12032.5 12036.8 5446.7 10357.9 11832.1 12035.8 12252.4 6228.4 9782.4 12043.0 12449.6 12492.3 7991.0 8251.9 8299.6 8344.0 8593.2 8793.1 11512.6 12117.5 12214.1 12234.1 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:6314.44 Median:538.76 stddev:3347.72 (621.4%) stddev-sans-first:3337.51 13:17:56 INFO - PROCESS | 1660 | Values: 1494.2 1636.1 1660.2 2015.6 2084.6 8297.5 8897.2 9050.7 9064.1 9071.7 8765.5 9030.0 9034.2 9050.5 9060.5 2572.3 2592.1 2595.2 2595.7 2597.5 2390.5 2466.6 2467.7 2474.5 2489.4 2579.9 2583.0 2615.2 2620.3 2621.1 2577.4 2598.0 2609.9 2612.9 2614.8 2562.6 2592.6 2592.6 2608.8 2616.8 9227.2 9261.7 9265.4 9280.5 9321.0 5700.3 5809.9 5811.4 5873.3 5883.7 3624.2 3747.3 3768.2 3774.1 3809.5 1048.1 1065.7 1069.3 1095.2 1098.1 12471.5 12609.7 12620.9 12625.7 12627.4 5027.8 5053.8 5111.6 5115.6 5138.7 9090.0 10663.5 10666.1 10667.4 10670.7 5829.9 5839.9 5851.5 5853.3 5873.4 4640.4 4760.6 5017.4 5149.7 5259.8 3588.4 3763.8 4000.8 4054.6 4116.8 1357.6 1362.7 1363.9 1371.7 1388.7 1387.5 1401.6 1435.0 1435.8 1448.0 8502.4 8581.2 8599.3 8617.5 8647.4 535.6 541.9 547.1 548.5 550.9 6265.7 6371.3 6435.8 6441.7 6442.0 10891.0 11311.5 11409.5 11420.6 11445.9 10757.8 11104.5 11136.3 11185.2 11286.2 8720.8 8727.3 8812.8 8814.5 8834.8 8112.1 8186.9 8216.9 8287.6 8293.2 9607.0 9916.2 9988.4 10015.4 10167.1 9762.7 10065.0 10136.0 10261.7 10265.9 7493.8 7753.6 7792.3 7798.7 7991.2 8589.6 8590.5 8641.2 8642.6 8664.5 7490.6 7663.9 7708.2 7753.1 7768.0 7463.9 7472.3 7525.0 7656.2 7665.0 7989.0 8088.8 8096.0 8103.6 8105.3 7133.3 7323.5 7324.4 7325.1 7329.1 7394.4 7424.7 7448.9 7449.0 7464.3 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5856.03 Median:6678.38 stddev:1840.96 (27.6%) stddev-sans-first:1798.85 13:17:56 INFO - PROCESS | 1660 | Values: 333.3 335.8 341.3 343.1 343.9 6602.8 6835.7 6897.3 6901.8 6921.1 6641.2 6653.4 6655.3 6659.9 6673.4 4744.8 4900.8 4920.2 4986.8 5031.8 4986.1 5003.5 5013.9 5017.1 5022.9 4958.3 4963.3 4964.0 4990.6 4991.7 4721.7 4781.5 4857.4 4858.0 4899.1 4208.6 4211.7 4218.7 4220.4 4253.8 6308.1 6371.9 6372.1 6384.3 6408.7 4670.4 5814.7 5828.8 5829.4 5830.2 6767.6 7016.8 7018.5 7024.2 7024.8 4027.5 4778.1 4784.6 4806.0 4830.1 7345.0 7394.5 7404.5 7417.5 7422.8 4834.2 4888.1 4895.1 4897.9 4898.0 4951.7 4958.4 4960.4 4975.1 4979.5 5610.7 5849.1 5854.0 5858.2 5869.9 5699.3 7043.2 7049.2 7069.2 7070.3 1690.9 1701.9 1732.1 1733.0 1740.1 944.4 1052.7 1103.1 1130.5 1136.5 6014.2 6531.5 6683.4 6807.3 6847.0 7442.4 7442.7 7448.3 7453.1 7460.6 6779.5 6806.0 6936.3 6948.8 6975.1 3788.9 3884.2 3909.9 3918.1 3924.5 7731.4 7758.6 7776.7 7778.4 7779.5 6270.5 7561.0 7588.3 7601.6 7606.5 7706.0 7744.0 7752.1 7760.5 7787.1 6175.4 7009.4 7314.5 7414.2 7444.6 7207.7 7260.5 7260.7 7275.0 7291.8 7668.1 7682.8 7683.5 7695.5 7729.7 4926.8 4936.3 4936.9 4945.8 4946.3 5748.6 7283.2 7292.2 7332.6 7345.7 7122.5 7147.1 7165.3 7174.9 7191.0 7122.6 7127.9 7129.6 7138.9 7142.4 6959.6 6961.9 6963.7 6964.3 6970.9 6865.8 7115.3 7134.5 7149.1 7167.4 6717.6 6828.4 6881.4 6969.3 7038.9 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18347.65 Median:4345.28 stddev:25818.03 (594.2%) stddev-sans-first:25856.53 13:17:56 INFO - PROCESS | 1660 | Values: 721.1 743.3 795.4 802.7 803.5 15328.4 15639.5 15648.3 15657.1 15665.4 14474.0 14617.7 14619.3 14628.3 14640.0 788.1 802.0 972.6 974.1 975.1 946.9 961.3 962.6 963.8 979.9 830.6 1011.2 1013.3 1017.4 1035.0 1011.0 1032.5 1033.3 1034.0 1034.0 771.5 772.7 772.9 773.0 775.0 14975.6 15064.2 15194.9 15407.0 15425.6 12921.7 12938.4 13192.5 13363.6 13502.1 10225.0 10372.3 10429.5 10559.8 11044.1 3580.1 3597.2 3599.3 3630.7 3717.6 85835.0 86292.0 88446.0 88806.0 88860.7 9692.0 9768.8 9807.9 9820.7 10143.4 22698.4 22892.7 22924.9 22929.0 22932.3 7552.4 7556.7 7591.6 7685.2 7782.7 4208.1 4227.7 4243.4 4292.7 4359.0 3693.9 3757.1 3760.2 3760.4 3762.8 540.9 586.3 610.2 696.3 696.5 589.5 617.1 619.6 621.7 623.9 8054.8 8140.7 8395.3 8459.7 8550.7 508.4 544.5 589.7 595.9 612.5 4184.2 4280.6 4288.6 4331.6 4379.3 97070.0 97715.1 99070.0 99096.0 99309.5 39450.8 39473.0 39909.4 55613.7 84256.2 72616.6 74137.6 74247.9 74301.6 75093.0 8551.8 8790.3 8885.6 8916.4 9032.9 48197.3 48983.0 49415.3 49885.0 50092.7 67676.0 68922.0 69916.3 72158.3 72336.0 11178.7 11281.6 11475.0 11874.1 12107.9 12014.3 12052.8 12564.9 12690.4 12690.7 9638.7 11621.3 12001.7 12030.3 12371.6 11706.8 11795.8 11889.8 12144.9 12205.1 11391.8 11542.5 11688.5 11782.1 11826.4 9842.9 9971.3 9974.8 10153.4 10251.9 9948.9 10182.6 10189.2 10200.8 10202.1 13:17:56 INFO - PROCESS | 1660 | -------- Summary: end -------- 13:17:56 INFO - PROCESS | 1660 | 13:17:56 INFO - TEST-INFO | 1660: exit 0 13:17:56 INFO - TEST-OK | dromaeo_css | took 1111392ms 13:17:56 INFO - TEST-START | kraken 13:17:56 INFO - operating with platform_type : mac_ 13:17:56 INFO - Initialising browser for kraken test... 13:17:56 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp7fluc_/profile http://localhost:49274/getInfo.html) 13:17:58 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 13:17:58 INFO - PROCESS | 1669 | colorDepth:24 13:17:58 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 13:17:58 INFO - PROCESS | 1669 | __metrics 13:17:58 INFO - PROCESS | 1669 | 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" 13:17:58 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:17:59 INFO - TEST-INFO | 1669: exit 0 13:17:59 INFO - Browser initialized. 13:17:59 INFO - Running cycle 1/1 for kraken test... 13:17:59 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp7fluc_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:18:11 INFO - PROCESS | 1672 | RSS: Main: 157167616 13:18:11 INFO - PROCESS | 1672 | 13:18:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 13:18:14 INFO - PROCESS | 1672 | RSS: Main: 213397504 13:18:14 INFO - PROCESS | 1672 | 13:18:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 13:18:17 INFO - PROCESS | 1672 | RSS: Main: 223264768 13:18:17 INFO - PROCESS | 1672 | 13:18:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 13:18:21 INFO - PROCESS | 1672 | RSS: Main: 213790720 13:18:21 INFO - PROCESS | 1672 | 13:18:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 13:18:24 INFO - PROCESS | 1672 | RSS: Main: 215752704 13:18:24 INFO - PROCESS | 1672 | 13:18:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:18:26 INFO - PROCESS | 1672 | RSS: Main: 215773184 13:18:26 INFO - PROCESS | 1672 | 13:18:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 13:18:30 INFO - PROCESS | 1672 | RSS: Main: 215625728 13:18:30 INFO - PROCESS | 1672 | 13:18:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 13:18:33 INFO - PROCESS | 1672 | RSS: Main: 215863296 13:18:33 INFO - PROCESS | 1672 | 13:18:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 13:18:36 INFO - PROCESS | 1672 | RSS: Main: 216121344 13:18:36 INFO - PROCESS | 1672 | 13:18:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:18:39 INFO - PROCESS | 1672 | RSS: Main: 250191872 13:18:39 INFO - PROCESS | 1672 | 13:18:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:18:41 INFO - PROCESS | 1672 | RSS: Main: 251170816 13:18:41 INFO - PROCESS | 1672 | 13:18:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:18:44 INFO - PROCESS | 1672 | RSS: Main: 256528384 13:18:44 INFO - PROCESS | 1672 | 13:18:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:18:47 INFO - PROCESS | 1672 | RSS: Main: 257335296 13:18:47 INFO - PROCESS | 1672 | 13:18:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:18:50 INFO - PROCESS | 1672 | RSS: Main: 257744896 13:18:50 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 13:18:52 INFO - PROCESS | 1672 | RSS: Main: 320086016 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | __start_tp_report 13:18:52 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 13:18:52 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 13:18:52 INFO - PROCESS | 1672 | |i|pagename|runs| 13:18:52 INFO - PROCESS | 1672 | |0;ai-astar;114;157;162;111;111;113;118;112;111;111 13:18:52 INFO - PROCESS | 1672 | |1;audio-beat-detection;171;157;182;158;137;167;173;144;161;118 13:18:52 INFO - PROCESS | 1672 | |2;audio-dft;186;183;184;184;181;182;183;182;189;187 13:18:52 INFO - PROCESS | 1672 | |3;audio-fft;79;76;72;118;55;76;83;84;141;55 13:18:52 INFO - PROCESS | 1672 | |4;audio-oscillator;91;92;126;79;82;84;121;78;77;116 13:18:52 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;88;88;89;86;89;85;90;89;135;129 13:18:52 INFO - PROCESS | 1672 | |6;imaging-darkroom;94;94;94;94;94;105;150;94;94;94 13:18:52 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;86;87;81;85;84;81;85;86;84 13:18:52 INFO - PROCESS | 1672 | |8;json-parse-financial;55;58;56;57;57;57;58;57;53;52 13:18:52 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;63;62;73;72;70;65;65;65;64;64 13:18:52 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;81;81;79;80;80;80;65;85;101;110 13:18:52 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;118;131;116;115;115;115;115;117;114;119 13:18:52 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;132;125;135;135;135;123;134;134;136;134 13:18:52 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;64;66;65;65;66;65;65;65;64 13:18:52 INFO - PROCESS | 1672 | __end_tp_report 13:18:52 INFO - PROCESS | 1672 | __start_cc_report 13:18:52 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1182 13:18:52 INFO - PROCESS | 1672 | __end_cc_report 13:18:52 INFO - PROCESS | 1672 | __startTimestamp1461961132267__endTimestamp 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | ------- Summary: start ------- 13:18:52 INFO - PROCESS | 1672 | Number of tests: 14 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:122.00 Median:113.50 stddev:19.92 (17.5%) stddev-sans-first:20.91 13:18:52 INFO - PROCESS | 1672 | Values: 114.0 157.0 162.0 111.0 111.0 113.0 118.0 112.0 111.0 111.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:156.80 Median:164.00 stddev:19.10 (11.6%) stddev-sans-first:19.56 13:18:52 INFO - PROCESS | 1672 | Values: 171.0 157.0 182.0 158.0 137.0 167.0 173.0 144.0 161.0 118.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:2.51 (1.4%) stddev-sans-first:2.57 13:18:52 INFO - PROCESS | 1672 | Values: 186.0 183.0 184.0 184.0 181.0 182.0 183.0 182.0 189.0 187.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:83.90 Median:76.00 stddev:26.64 (35.0%) stddev-sans-first:28.19 13:18:52 INFO - PROCESS | 1672 | Values: 79.0 76.0 72.0 118.0 55.0 76.0 83.0 84.0 141.0 55.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:80.50 stddev:19.03 (23.6%) stddev-sans-first:20.14 13:18:52 INFO - PROCESS | 1672 | Values: 91.0 92.0 126.0 79.0 82.0 84.0 121.0 78.0 77.0 116.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:88.50 stddev:18.67 (21.1%) stddev-sans-first:19.52 13:18:52 INFO - PROCESS | 1672 | Values: 88.0 88.0 89.0 86.0 89.0 85.0 90.0 89.0 135.0 129.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:94.00 stddev:17.66 (18.8%) stddev-sans-first:18.57 13:18:52 INFO - PROCESS | 1672 | Values: 94.0 94.0 94.0 94.0 94.0 105.0 150.0 94.0 94.0 94.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:2.00 (2.4%) stddev-sans-first:2.12 13:18:52 INFO - PROCESS | 1672 | Values: 84.0 86.0 87.0 81.0 85.0 84.0 81.0 85.0 86.0 84.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:2.05 (3.6%) stddev-sans-first:2.15 13:18:52 INFO - PROCESS | 1672 | Values: 55.0 58.0 56.0 57.0 57.0 57.0 58.0 57.0 53.0 52.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:65.00 stddev:3.89 (6.0%) stddev-sans-first:3.94 13:18:52 INFO - PROCESS | 1672 | Values: 63.0 62.0 73.0 72.0 70.0 65.0 65.0 65.0 64.0 64.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:80.00 stddev:12.55 (15.7%) stddev-sans-first:13.26 13:18:52 INFO - PROCESS | 1672 | Values: 81.0 81.0 79.0 80.0 80.0 80.0 65.0 85.0 101.0 110.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:117.50 Median:116.50 stddev:4.99 (4.3%) stddev-sans-first:5.29 13:18:52 INFO - PROCESS | 1672 | Values: 118.0 131.0 116.0 115.0 115.0 115.0 115.0 117.0 114.0 119.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:132.30 Median:134.50 stddev:4.52 (3.4%) stddev-sans-first:4.80 13:18:52 INFO - PROCESS | 1672 | Values: 132.0 125.0 135.0 135.0 135.0 123.0 134.0 134.0 136.0 134.0 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:0.92 (1.4%) stddev-sans-first:0.71 13:18:52 INFO - PROCESS | 1672 | Values: 67.0 64.0 66.0 65.0 65.0 66.0 65.0 65.0 65.0 64.0 13:18:52 INFO - PROCESS | 1672 | -------- Summary: end -------- 13:18:52 INFO - PROCESS | 1672 | 13:18:52 INFO - TEST-INFO | 1672: exit 0 13:18:53 INFO - TEST-OK | kraken | took 56338ms 13:18:53 INFO - SUITE-END | took 1167s 13:18:53 INFO - Completed test suite (00:19:28) 13:18:53 INFO - javascript benchmark 13:18:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3579.1767893384526, 3617.204215072683, 3638.108663377471, 3658.213484100919, 3693.6675699187067, 5952.940470595304, 5955.434233747797, 5995.550333724972, 6012.579119461634, 6033.06487494438, 5984.371640977689, 6032.065029920358, 6038.637681739098, 6041.335453100155, 6041.909371359422, 5193.63644544882, 5391.32608423947, 5397.514223719864, 5407.675539467645, 5462.6995515246645, 5393.703346315951, 5398.433164517728, 5401.729913504329, 5416.241726158341, 5416.702081385522, 5353.464653534655, 5401.918971215416, 5406.59450541209, 5439.0, 5440.129579267318, 4772.880677983044, 5423.972880135594, 5452.83641490754, 5458.6724796512335, 5479.835604931858, 5261.631685781997, 5340.118880384732, 5364.195370694388, 5387.272718183073, 5401.216823560601, 5919.112133180058, 5953.404659534012, 5953.732082056318, 5978.342382337939, 6006.969965150146, 6133.365267548864, 6200.968995155041, 6208.037754148069, 6238.968805155945, 6244.906326405107, 6265.154204182468, 6280.371962803729, 6302.369763023707, 6305.0857625644485, 6327.050942358683, 6106.236720409949, 6110.052941794029, 6110.511159107261, 6120.846978825476, 6123.050927106306, 5423.945760542423, 6253.1245625612455, 6304.464120549805, 6310.0, 6423.357664233539, 6097.024476083805, 6141.57009009374, 6203.534734894901, 6204.13142160098, 6216.378362163748, 6271.3728627136925, 6296.811095667045, 6299.559030867887, 6304.085907543472, 6306.0, 6144.78493252734, 6162.322144564095, 6209.9068513972325, 6215.098810672427, 6216.813495595139, 6123.1606109212, 6201.069839524108, 6212.9068063978175, 6217.285012223652, 6217.4715149212825, 4228.492580890313, 4945.752712364368, 4955.083309587738, 4981.676191047571, 4983.90032199354, 4460.531644177315, 4500.504944456107, 4551.067031258584, 4565.429321334833, 4576.656750743708, 6083.6349819011, 6091.42131497507, 6098.054801505774, 6098.908516372258, 6099.725512351954, 6252.406021427957, 6331.27190373113, 6331.493480521547, 6342.04869269604, 6353.491720662336, 6029.276486821609, 6101.328853826076, 6105.358937311607, 6118.05170198611, 6141.785037523665, 5465.398806131322, 5477.972610136924, 5502.642328248651, 5502.972485137549, 5505.587080968863, 6340.112384266206, 6356.364363563606, 6389.2652344980925, 6413.583117097373, 6417.743290268337, 6290.21372328459, 6336.841578960379, 6339.873202536016, 6341.8731625368155, 6342.714577843916, 6397.616143031433, 6408.93591064102, 6413.422791948747, 6414.711337989708, 6438.195225596801, 6235.844103897439, 6298.527610429144, 6356.713947872449, 6373.968130159319, 6380.1067850501895, 6308.779192728232, 6320.0, 6320.557560970872, 6321.905171422341, 6325.746970121144, 6246.968765156145, 6311.147995020797, 6313.779017734358, 6314.0, 6325.05124231369, 6118.847028824315, 6229.563930525001, 6239.532035097295, 6303.653299068412, 6308.747650094128, 6051.227958444376, 6136.488442913377, 6161.260648722182, 6166.0, 6174.629522228681, 4511.616512596466, 6324.7153878076515, 6334.0, 6343.873122537616, 6349.142865713255, 6009.278886533644, 6117.877642447037, 6165.106059621419, 6183.96908015475, 6198.752049918133], "unit": "score", "name": "dojo.html", "value": 5841.668073394539}, {"lowerIsBetter": false, "replicates": [553.7637076365803, 560.495554001399, 592.6473748119869, 595.033071259204, 665.5141746525036, 38792.806035969814, 39284.41073383902, 39351.40972885409, 39404.80297598515, 39538.406923896095, 42655.29378824851, 43269.0, 43329.566704332945, 43346.7832660837, 43392.6982190534, 9839.75400614985, 9956.60173593059, 9983.600655973753, 10047.899521004805, 10070.69787906364, 9816.361936474143, 9852.90147098527, 9902.25733070019, 9946.35348702332, 9955.0, 9947.850782238236, 10024.799504009916, 10062.0, 10076.39541627501, 10085.949570252174, 9992.300538962276, 10051.949740251324, 10056.597736090547, 10070.244731645156, 10072.345297555636, 9773.853392199122, 9946.0, 9951.154151897097, 9955.0, 9962.701118966443, 41492.37761433581, 41899.7905010476, 42299.78850105761, 42351.78824105891, 42352.576474235175, 3501.8074005929666, 3579.480975258599, 3627.546556680415, 3926.548446928611, 4033.9713373089967, 28957.0, 29958.95143669983, 30021.549676754865, 30065.849670751726, 30181.547276790865, 2770.1135636596296, 2782.6660800703844, 2810.2131403207136, 2815.81697189682, 2837.6311079559523, 458971.0, 460441.0, 461356.0, 461793.0, 463422.68288658676, 46979.0, 64280.35719642837, 64617.67691161608, 64652.70694586082, 64803.351966479735, 46188.38340658128, 46207.53792462099, 46323.07353852904, 46348.3047754284, 46434.0, 16870.325186992628, 17278.395256165975, 17280.876743011664, 17299.481015569552, 17318.82681173172, 13880.791788123186, 13979.580612581638, 13979.720405591832, 13992.250968628921, 14018.579442616738, 14795.852041479662, 14870.479533216501, 15044.924775376272, 15104.924475377773, 15188.316525756367, 6057.697115144225, 6061.605500919499, 6336.968315158487, 6505.316941721146, 6530.8367290818105, 976.511744127936, 1017.7353887989176, 1099.8790133085354, 1115.570505355444, 1142.405948906564, 30323.241918952204, 30328.241793955327, 30733.84633076865, 30885.845570772002, 30897.691023089483, 1160.2516376936828, 1214.5931113077218, 1229.342301868514, 1244.6452760963082, 1264.3046324521476, 15190.696186076217, 15394.384224631114, 15463.0, 15755.44855930037, 15827.68344633101, 532543.0, 534152.0, 536244.0, 536892.0, 538138.0, 60841.39158608446, 60871.78256434847, 60990.0, 61054.77890442166, 61415.157545273716, 1424.2593851197319, 1520.9923950380398, 1526.9540364850113, 1550.325608360367, 1551.317420335049, 24743.752562474147, 26141.73858261393, 28393.574096388158, 28946.0, 29477.347038766613, 44772.55227447684, 45154.54845451635, 45486.63540093802, 45508.95209715637, 45638.77180614076, 352498.0, 361531.0, 362097.0, 362326.0, 363038.0, 29708.55437168401, 34006.82996585001, 34820.47769283412, 34825.47761783525, 34851.9544413668, 41718.0, 42123.57876421197, 42275.3658695126, 43816.561834381246, 43935.56064439315, 34717.30565388627, 35628.64371356253, 35656.465153023244, 35981.64018359783, 36461.817690911375, 26370.868145659915, 35497.82251088728, 35798.28403431865, 36341.45487817738, 37541.81229093837, 34030.0, 35754.64245357513, 36747.816260918524, 37019.62980370269, 37339.69311074099, 31762.364752705278, 32430.351392971537, 32499.18752031218, 32570.34859302848, 32586.02241932746, 24890.476713204218, 25004.999800007798, 25078.705138071124, 25229.11698090558, 25420.0], "unit": "score", "name": "ext.html", "value": 19506.273268214267}, {"lowerIsBetter": false, "replicates": [379.133679542246, 382.5294887288635, 383.33108725274394, 386.24681870352816, 388.854179682619, 20387.59224815503, 22205.66691499627, 22589.887050564765, 22953.0, 22956.08175672971, 21550.568988620256, 21617.891910540464, 22402.551948961012, 22575.774242257572, 22608.43478913024, 5776.624519406225, 5956.374580669032, 6077.9363611367835, 6126.356732543086, 6152.138700581922, 5943.435373639497, 5973.372795856438, 5997.520198384141, 6000.519958403339, 6003.849903752397, 5813.854653633651, 5991.970040149793, 6037.366076561963, 6594.505412094089, 6613.801585952429, 6547.279799222106, 6585.242697089848, 6585.80242592723, 6594.274629790719, 6607.471402287805, 5654.180143879114, 5781.913271300934, 5796.623219490762, 5851.23933888596, 5854.356020837705, 10747.677569672922, 12326.445309961073, 14468.710625787426, 14579.927100364537, 26552.867235663893, 1931.777845547766, 2101.422108920059, 2195.5938151441874, 2309.86140831549, 2379.821513386503, 9530.475823829687, 10319.638812641597, 10398.68803935883, 10669.986351296615, 10688.572457101707, 1629.527437043256, 1695.5252529291818, 1742.5120966129505, 1787.5709829640793, 1851.6481868445087, 94875.52562237214, 96639.51680241624, 98603.0, 99238.0, 99694.0, 11580.305181688957, 11584.768304634028, 11662.650120496399, 11684.70788230301, 11768.352740599177, 12083.637490875111, 14695.632609184855, 14699.26503674812, 14728.189949552663, 14738.63153421173, 8872.467651940775, 9114.0, 9130.26957843371, 9139.725808225763, 9202.4478531287, 8283.668653253922, 8385.909831721838, 8593.097724738938, 9313.953430232941, 9422.434653920649, 4295.935560966588, 4553.612942899825, 4560.6807523473035, 4564.863054108382, 4654.371659825948, 1628.210317995771, 1674.7655328254054, 1896.838768704648, 1923.7306777051224, 1970.1134489479791, 906.4787747045475, 1029.0789743179812, 1053.0943388685723, 1055.1137044882337, 1070.347088276151, 15880.602984925239, 20814.479638009172, 20869.269575564784, 20946.847923364057, 21137.577248454945, 1013.8732658417698, 1014.4724743133529, 1023.8822535408375, 1034.7826956339102, 1068.2522234435926, 11058.723531911606, 11087.556497740161, 11115.83326250107, 11129.777404451866, 11131.332120072822, 640995.0, 654798.0, 655328.0, 659584.0, 663932.0, 107583.0, 112200.4389978045, 112696.0, 113178.86821131848, 115054.4247278775, 1062.5006247063868, 1108.2685427617735, 1127.115214556569, 1132.2074547816562, 1133.5125895864692, 18676.43970680882, 18775.812241877407, 19302.22791088341, 19374.418767437, 19701.113449894503, 54253.0, 54398.393262838974, 55017.72491137519, 56300.0, 56894.7155264221, 76605.0, 77575.61212193903, 77759.61120194363, 78145.0, 78316.60841695755, 8132.959335203286, 8338.541380224146, 8367.372447066373, 8470.280026197595, 8495.0, 9859.704208873745, 11288.717782055513, 13449.46202151903, 13525.526606568723, 13629.38667759973, 11005.284656497302, 11874.643760687193, 11914.52341906314, 12032.458539365574, 12036.819447708116, 5446.727663616724, 10357.896421035995, 11832.112591555495, 12035.841257335984, 12252.387380630755, 6228.408301211378, 9782.41305521671, 12042.976347010252, 12449.626511204679, 12492.25046497213, 7991.0, 8251.917480825356, 8299.626516806878, 8344.040435349769, 8593.183647553473, 8793.076726943578, 11512.59705910289, 12117.454714537691, 12214.083943704436, 12234.082443816927], "unit": "score", "name": "jquery.html", "value": 9577.800446363415}, {"lowerIsBetter": false, "replicates": [1494.185668810498, 1636.0756172762387, 1660.2362913059992, 2015.6170327637747, 2084.551821358408, 8297.460665056775, 8897.15477029682, 9050.728478145666, 9064.093590640932, 9071.727848164566, 8765.517896515688, 9029.954850225757, 9034.186923176912, 9050.45697258165, 9060.546972651375, 2572.2540463265677, 2592.118679648919, 2595.2344058502695, 2595.6625638666947, 2597.5324441600505, 2390.4501964548163, 2466.580681284186, 2467.728549859523, 2474.455619763658, 2489.3652118709792, 2579.877753177374, 2582.987085064572, 2615.189291319697, 2620.3318153870737, 2621.0695203202877, 2577.420080481895, 2597.9740202598014, 2609.856457894815, 2612.8562929038894, 2614.8038897082606, 2562.5771747661615, 2592.585186370172, 2592.6240695099295, 2608.8434693918234, 2616.816822822403, 9227.215686666641, 9261.675841345588, 9265.44407335562, 9280.53597320138, 9321.0, 5700.344460387067, 5809.912851307234, 5811.418858114197, 5873.295204575478, 5883.676397798119, 3624.238909828939, 3747.2505498900055, 3768.152165762709, 3774.0564858785306, 3809.5428548574073, 1048.077691631367, 1065.6536625596634, 1069.2835800013931, 1095.1895597257972, 1098.0721290509587, 12471.501139954391, 12609.747805043848, 12620.936895315557, 12625.747485050248, 12627.43176557057, 5027.773750181251, 5053.823116190915, 5111.5655169310285, 5115.565176959925, 5138.691678499302, 9089.95455022734, 10663.520141593646, 10666.14670826332, 10667.359958402365, 10670.679879603469, 5829.912551311733, 5839.919614871177, 5851.531877449794, 5853.326867410302, 5873.353931067579, 4640.443146822403, 4760.571548560646, 5017.397912250481, 5149.691018538825, 5259.76331065103, 3588.407912694376, 3763.8118094095184, 4000.8399664013696, 4054.6350828425584, 4116.814743336557, 1357.565579014706, 1362.7274545091022, 1363.9318034098453, 1371.6570857285678, 1388.7361401333715, 1387.537836329781, 1401.628568429367, 1434.9811633740162, 1435.8277006759256, 1447.9638009049859, 8502.404831661725, 8581.227689507848, 8599.312055035582, 8617.526036068077, 8647.437916535406, 535.6036532965655, 541.9160030195326, 547.1164070026878, 548.4652463847716, 550.900837849191, 6265.655388953652, 6371.267304259976, 6435.806925792233, 6441.710123044474, 6442.0, 10890.96535829095, 11311.490982905625, 11409.543618255177, 11420.600278990196, 11445.942770286096, 10757.78484430307, 11104.500297486633, 11136.276142050741, 11185.161112916563, 11286.153538484647, 8720.781980450412, 8727.301815854844, 8812.779680508038, 8814.471131732116, 8834.77913052166, 8112.148224436585, 8186.8771968421715, 8216.917830821616, 8287.62705678258, 8293.170682931659, 9606.951965240129, 9916.157126644315, 9988.350757200758, 10015.39907605546, 10167.135793457639, 9762.707118786448, 10065.0, 10136.037076477724, 10261.74345641365, 10265.897341026493, 7493.812654683677, 7753.612319383896, 7792.337651299703, 7798.727044553413, 7991.24083212094, 8589.613467394107, 8590.484570925764, 8641.179087986631, 8642.56787160652, 8664.523451210243, 7490.550566966, 7663.88504172449, 7708.152103268748, 7753.147153813189, 7768.0, 7463.850722985401, 7472.252774722701, 7525.0, 7656.157822639394, 7664.961675191811, 7988.960055199919, 8088.757337279891, 8096.0, 8103.594820258845, 8105.311048560939, 7133.322334378226, 7323.487355885037, 7324.4140468763435, 7325.084364454443, 7329.1205055391565, 7394.408447324308, 7424.740134095281, 7448.8882666758955, 7448.994385757854, 7464.253574642492], "unit": "score", "name": "mootools.html", "value": 5066.328992985989}, {"lowerIsBetter": false, "replicates": [333.2851034530931, 335.79348700549167, 341.330991257136, 343.1335876910801, 343.8830797528841, 6602.778485980097, 6835.692393842277, 6897.344752248541, 6901.758438454649, 6921.100256966593, 6641.203055633331, 6653.36793004665, 6655.301193374698, 6659.866802663943, 6673.399394054534, 4744.833930812422, 4900.803967841282, 4920.23736320869, 4986.825461108862, 5031.773570189332, 4986.127427700138, 5003.47463516331, 5013.899722005558, 5017.071841709278, 5022.949770502303, 4958.281049247857, 4963.255511673242, 4964.0, 4990.600751939836, 4991.675541089818, 4721.669483136182, 4781.521847815191, 4857.368542089541, 4857.951420485803, 4899.093667671492, 4208.600182982612, 4211.684123690705, 4218.662506999463, 4220.36694495825, 4253.766042867641, 6308.085327627514, 6371.936280637181, 6372.107904893319, 6384.297727249998, 6408.711607977605, 4670.369500117508, 5814.680192589406, 5828.795992140297, 5829.446202610788, 5830.15462757902, 6767.627780472072, 7016.7544135954995, 7018.508704390695, 7024.2273349931465, 7024.75413360535, 4027.496562929634, 4778.116048531033, 4784.641151913585, 4806.0, 4830.130576496229, 7345.0, 7394.519356241827, 7404.4816862819625, 7417.51786133905, 7422.814429639302, 4834.178189707758, 4888.071266459361, 4895.143349913751, 4897.926531102036, 4898.044881248194, 4951.653384263139, 4958.404991401055, 4960.429550601727, 4975.129352363322, 4979.4771548987555, 5610.719464026783, 5849.093390524476, 5854.004819180749, 5858.213244960243, 5869.941300586939, 5699.344575373885, 7043.190033146254, 7049.189343225595, 7069.222385537587, 7070.292970702992, 1690.9323627055026, 1701.8978861268363, 1732.1252767352407, 1733.0468242466593, 1740.0777587878445, 944.367273926471, 1052.6578861869923, 1103.100972707241, 1130.5082289204222, 1136.4601814138384, 6014.248218972629, 6531.477481801445, 6683.365080317362, 6807.2512023677355, 6846.965765171142, 7442.367398771057, 7442.66508007141, 7448.292412220831, 7453.105627324756, 7460.626968651546, 6779.5254332197255, 6806.013128096398, 6936.34104760047, 6948.756793512202, 6975.128108986377, 3788.886333410002, 3884.2231553689376, 3909.863154789569, 3918.0988372673874, 3924.548676902136, 7731.420143489261, 7758.61206939662, 7776.7278145264645, 7778.416618753616, 7779.533228006338, 6270.529710271748, 7561.0, 7588.27911348421, 7601.619919004028, 7606.505577137468, 7705.961470192613, 7743.99328087345, 7752.1472638008645, 7760.495567788075, 7787.06555213378, 6175.44421002112, 7009.439244860501, 7314.487985841153, 7414.221506741715, 7444.627768611439, 7207.747728829466, 7260.4554658399775, 7260.709571617287, 7275.0, 7291.817704557428, 7668.079830420385, 7682.8079298018, 7683.500572462772, 7695.4997925134685, 7729.6521656524465, 4926.802927882988, 4936.308916751729, 4936.925946110883, 4945.752712364296, 4946.332245146859, 5748.597598168088, 7283.2352602975925, 7292.161401438902, 7332.5967071811565, 7345.6694448751, 7122.537035092702, 7147.142342918883, 7165.319294666998, 7174.928250717426, 7190.96404517974, 7122.5726456412785, 7127.928720712934, 7129.572225666477, 7138.928610713826, 7142.392896603847, 6959.61722105289, 6961.89557156633, 6963.651817409211, 6964.303569643198, 6970.895436568354, 6865.794026179223, 7115.324044215791, 7134.464915131282, 7149.0706208192605, 7167.390771784457, 6717.596944183365, 6828.385445309946, 6881.3806757390075, 6969.337912898267, 7038.894416583653], "unit": "score", "name": "prototype.html", "value": 5241.765659778557}, {"lowerIsBetter": false, "replicates": [721.1166321256461, 743.2790193512291, 795.3915254830056, 802.7070119406418, 803.4817542684968, 15328.386864525419, 15639.452619158332, 15648.295826687798, 15657.138857362841, 15665.373385064599, 14474.0, 14617.707645847104, 14619.269036548183, 14628.34172462239, 14639.975201735882, 788.1094363369384, 801.9599020049003, 972.6401231544303, 974.1378879691454, 975.0736800039955, 946.9100435458621, 961.2838435365662, 962.6486332488661, 963.7927845513213, 979.8873129590081, 830.6220669595319, 1011.2365164300943, 1013.2805707947366, 1017.3692310767335, 1034.9586016559367, 1010.9595616175344, 1032.5147180825, 1033.3438266700662, 1033.9918579385078, 1034.0, 771.5409331447779, 772.7488566216002, 772.9188435214278, 773.0375682275546, 775.042822114688, 14975.625609359744, 15064.246787660573, 15194.924025379802, 15407.0, 15425.614359640986, 12921.67695807603, 12938.353082345939, 13192.538261160813, 13363.599092027254, 13502.054856160074, 10224.969295136138, 10372.308552710425, 10429.530671119817, 10559.841602375971, 11044.061254793352, 3580.104973756561, 3597.1726502904216, 3599.31612993533, 3630.6550877666577, 3717.5724791649036, 85835.0, 86292.0, 88446.0, 88806.0, 88860.6670899937, 9691.951540242253, 9768.755781105387, 9807.901920980841, 9820.705378838646, 10143.442110683836, 22698.43253918665, 22892.65661015086, 22924.853757312067, 22929.0, 22932.312030638775, 7552.433567482461, 7556.65995030225, 7591.58246296459, 7685.23147685227, 7782.727604533814, 4208.053188032729, 4227.725197862129, 4243.42713733642, 4292.656587473057, 4359.0, 3693.8707145249787, 3757.1358587524473, 3760.1539653578275, 3760.4359346098304, 3762.8118594070734, 540.9134538473826, 586.3374386942812, 610.1671218786324, 696.3384784454687, 696.5437638346891, 589.4812564942823, 617.0713076819358, 619.6437048696927, 621.7077973352517, 623.9282482514478, 8054.798630034296, 8140.674373025131, 8395.28640065589, 8459.661613535513, 8550.657973681107, 508.38231548667807, 544.5153813106339, 589.7198830555452, 595.9124597609381, 612.5497759147023, 4184.205001049791, 4280.636145927631, 4288.592583704543, 4331.566843315643, 4379.316486256554, 97070.0, 97715.1142442875, 99070.0, 99096.0, 99309.50345248372, 39450.80274598609, 39473.013174670865, 39909.401358979056, 55613.721931390086, 84256.15743842606, 72616.63691681508, 74137.62931185309, 74247.8862817069, 74301.62849185828, 75093.0, 8551.786205344915, 8790.296776257755, 8885.555722213734, 8916.420432671855, 9032.86450703227, 48197.27704084512, 48983.0, 49415.25877111918, 49885.0, 50092.749536252086, 67675.98486022615, 68922.0, 69916.25209369604, 72158.27841721516, 72336.0, 11178.720531986766, 11281.605143819927, 11475.024622906813, 11874.109441792072, 12107.910288073763, 12014.279143251433, 12052.841769589477, 12564.937175314066, 12690.365481726061, 12690.682732931382, 9638.662646807328, 11621.302721836717, 12001.699957501132, 12030.338331391507, 12371.587320622064, 11706.824397634213, 11795.823062654239, 11889.821652675044, 12144.90695837379, 12205.084618653458, 11391.772164556829, 11542.538298467967, 11688.532458701557, 11782.057435405319, 11826.408679566159, 9842.901570984199, 9971.283741603442, 9974.750631234278, 10153.39079655223, 10251.948740256252, 9948.900510994798, 10182.592696292066, 10189.184865210913, 10200.795984080129, 10202.132818710492], "unit": "score", "name": "yui.html", "value": 7112.789349792693}], "name": "dromaeo_css", "value": 7686.053601950688}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 157.0, 162.0, 111.0, 111.0, 113.0, 118.0, 112.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 122.0}, {"lowerIsBetter": true, "replicates": [171.0, 157.0, 182.0, 158.0, 137.0, 167.0, 173.0, 144.0, 161.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 156.8}, {"lowerIsBetter": true, "replicates": [186.0, 183.0, 184.0, 184.0, 181.0, 182.0, 183.0, 182.0, 189.0, 187.0], "unit": "score", "name": "audio-dft", "value": 184.1}, {"lowerIsBetter": true, "replicates": [79.0, 76.0, 72.0, 118.0, 55.0, 76.0, 83.0, 84.0, 141.0, 55.0], "unit": "score", "name": "audio-fft", "value": 83.9}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 126.0, 79.0, 82.0, 84.0, 121.0, 78.0, 77.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 94.6}, {"lowerIsBetter": true, "replicates": [88.0, 88.0, 89.0, 86.0, 89.0, 85.0, 90.0, 89.0, 135.0, 129.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 96.8}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 105.0, 150.0, 94.0, 94.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 100.7}, {"lowerIsBetter": true, "replicates": [84.0, 86.0, 87.0, 81.0, 85.0, 84.0, 81.0, 85.0, 86.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 84.3}, {"lowerIsBetter": true, "replicates": [55.0, 58.0, 56.0, 57.0, 57.0, 57.0, 58.0, 57.0, 53.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 56.0}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 73.0, 72.0, 70.0, 65.0, 65.0, 65.0, 64.0, 64.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.3}, {"lowerIsBetter": true, "replicates": [81.0, 81.0, 79.0, 80.0, 80.0, 80.0, 65.0, 85.0, 101.0, 110.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.2}, {"lowerIsBetter": true, "replicates": [118.0, 131.0, 116.0, 115.0, 115.0, 115.0, 115.0, 117.0, 114.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 117.5}, {"lowerIsBetter": true, "replicates": [132.0, 125.0, 135.0, 135.0, 135.0, 123.0, 134.0, 134.0, 136.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 132.3}, {"lowerIsBetter": true, "replicates": [67.0, 64.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.2}], "name": "kraken", "value": 1444.7}]} 13:18:53 INFO - Return code: 0 13:18:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:18:53 INFO - # TBPL SUCCESS # 13:18:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:18:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:18:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:18:53 INFO - 2016-04-29 13:18:53.591 screenresolution[1676:9721] starting screenresolution argv=screenresolution get 13:18:53 INFO - 2016-04-29 13:18:53.594 screenresolution[1676:9721] Display 0: 1600x1200x32@60 13:18:53 INFO - 2016-04-29 13:18:53.600 screenresolution[1677:9725] starting screenresolution argv=screenresolution list 13:18:53 INFO - Available Modes on Display 0 13:18:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:18:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:18:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:18:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:18:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:18:53 INFO - Intel Iris: 13:18:53 INFO - Chipset Model: Intel Iris 13:18:53 INFO - Type: GPU 13:18:53 INFO - Bus: Built-In 13:18:53 INFO - VRAM (Dynamic, Max): 1536 MB 13:18:53 INFO - Vendor: Intel (0x8086) 13:18:53 INFO - Device ID: 0x0a2e 13:18:53 INFO - Revision ID: 0x0009 13:18:53 INFO - Displays: 13:18:53 INFO - Display: 13:18:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:18:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:18:53 INFO - Display Serial Number: 1600x1200 60 13:18:53 INFO - Main Display: Yes 13:18:53 INFO - Mirror: Off 13:18:53 INFO - Online: Yes 13:18:53 INFO - Rotation: Supported 13:18:53 INFO - Return code: 0 13:18:53 INFO - Running post-action listener: _resource_record_post_action 13:18:53 INFO - Running post-run listener: _resource_record_post_run 13:18:54 INFO - Total resource usage - Wall time: 1193s; CPU: 52.0%; Read bytes: 37701120; Write bytes: 507546624; Read time: 401; Write time: 1668 13:18:54 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 204902912; Write bytes: 199354368; Read time: 18966; Write time: 418 13:18:54 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 35886592; Write bytes: 298447872; Read time: 374; Write time: 1192 13:18:54 INFO - Running post-run listener: _upload_blobber_files 13:18:54 INFO - Blob upload gear active. 13:18:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:18:54 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 13:18:54 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'] 13:18:54 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 13:18:55 INFO - (blobuploader) - INFO - Open directory for files ... 13:18:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:18:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:18:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:18:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:18:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:18:56 INFO - (blobuploader) - INFO - Done attempting. 13:18:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:18:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:18:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:18:57 INFO - (blobuploader) - INFO - Done attempting. 13:18:57 INFO - (blobuploader) - INFO - Iteration through files over. 13:18:57 INFO - Return code: 0 13:18:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:18:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:18:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b731753e53283b683ef447cb056b3406a62525f42b5cfd8dfebaba1c4b46271559f18f4394fcff52b0e96f3e33ecf6ec6ea61a53f07c071cf50c25450d511736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4578402db7b605439b31c2e1055140041794089d01a7a5a76e93a46eec4de10321142b5a6e30a0140c5baab4e3591c3b2534da2096df4590867eb6c6c50fa116"} 13:18:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:18:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:18:57 INFO - Contents: 13:18:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b731753e53283b683ef447cb056b3406a62525f42b5cfd8dfebaba1c4b46271559f18f4394fcff52b0e96f3e33ecf6ec6ea61a53f07c071cf50c25450d511736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4578402db7b605439b31c2e1055140041794089d01a7a5a76e93a46eec4de10321142b5a6e30a0140c5baab4e3591c3b2534da2096df4590867eb6c6c50fa116"} 13:18:57 INFO - Running post-run listener: copy_logs_to_upload_dir 13:18:57 INFO - Copying logs to upload dir... 13:18:57 INFO - mkdir: /builds/slave/test/build/upload/logs 13:18:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.415224 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-29 13:18:57.489607) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 13:18:57.493219) ========= 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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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/b731753e53283b683ef447cb056b3406a62525f42b5cfd8dfebaba1c4b46271559f18f4394fcff52b0e96f3e33ecf6ec6ea61a53f07c071cf50c25450d511736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4578402db7b605439b31c2e1055140041794089d01a7a5a76e93a46eec4de10321142b5a6e30a0140c5baab4e3591c3b2534da2096df4590867eb6c6c50fa116"} build_url:https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011072 build_url: 'https://queue.taskcluster.net/v1/task/KV7ndnKDReqd9ZAPEnc6nw/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b731753e53283b683ef447cb056b3406a62525f42b5cfd8dfebaba1c4b46271559f18f4394fcff52b0e96f3e33ecf6ec6ea61a53f07c071cf50c25450d511736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4578402db7b605439b31c2e1055140041794089d01a7a5a76e93a46eec4de10321142b5a6e30a0140c5baab4e3591c3b2534da2096df4590867eb6c6c50fa116"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 13:18:57.520513) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 13:18:57.520840) ========= 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.PrJbcZuS1v/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.dMl1GoQKG4/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.005117 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 13:18:57.569860) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 13:18:57.570187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 13:18:57.570612) ========= ========= Total master_lag: 0.16 =========