builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0071 starttime: 1461861008.52 results: success (0) buildid: 20160428073712 builduid: 13f3b14acfca46618e0c13fc413cc1df revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:08.519836) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:08.520211) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-04-28 09:30:08.520480) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-04-28 09:30:19.357522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-28 09:30:19.357986) ========= 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.I6jwSljNRp/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.bYHUrVz6z2/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-28 09:30:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.3M=0.001s 2016-04-28 09:30:19 (21.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.517508 ========= master_lag: 3.99 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-28 09:30:23.864157) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:23.864508) ========= 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.I6jwSljNRp/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.bYHUrVz6z2/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.026792 ========= master_lag: 0.82 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:24.709972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:24.710350) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.I6jwSljNRp/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.bYHUrVz6z2/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-28 09:30:24,786 truncating revision to first 12 chars 2016-04-28 09:30:24,786 Setting DEBUG logging. 2016-04-28 09:30:24,786 attempt 1/10 2016-04-28 09:30:24,786 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/45e67d6b5a61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 09:30:25,385 unpacking tar archive at: fx-team-45e67d6b5a61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.846989 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:25.576270) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:25.576579) ========= script_repo_revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:25.576976) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:25.577262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:30:25.592063) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-28 09:30:25.592564) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.I6jwSljNRp/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.bYHUrVz6z2/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:30:25 INFO - MultiFileLogger online at 20160428 09:30:25 in /builds/slave/test 09:30:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:30:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:25 INFO - {'append_to_log': False, 09:30:25 INFO - 'base_work_dir': '/builds/slave/test', 09:30:25 INFO - 'blob_upload_branch': 'Fx-Team', 09:30:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:25 INFO - 'branch': 'Fx-Team', 09:30:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:25 INFO - 'config_files': ('talos/mac_config.py',), 09:30:25 INFO - 'default_actions': ('clobber', 09:30:25 INFO - 'read-buildbot-config', 09:30:25 INFO - 'download-and-extract', 09:30:25 INFO - 'populate-webroot', 09:30:25 INFO - 'create-virtualenv', 09:30:25 INFO - 'install', 09:30:25 INFO - 'run-tests'), 09:30:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:25 INFO - 'download_minidump_stackwalk': True, 09:30:25 INFO - 'download_symbols': 'ondemand', 09:30:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:25 INFO - 'installer_path': 'installer.exe', 09:30:25 INFO - 'log_level': 'info', 09:30:25 INFO - 'log_name': 'talos', 09:30:25 INFO - 'log_to_console': True, 09:30:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:30:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:30:25 INFO - 'opt_config_files': (), 09:30:25 INFO - 'pip_index': False, 09:30:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:25 INFO - 'cmd': ('bash', 09:30:25 INFO - '-c', 09:30:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:25 INFO - 'enabled': True, 09:30:25 INFO - 'halt_on_failure': False, 09:30:25 INFO - 'name': 'check_screen_resolution'},), 09:30:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:25 INFO - 'cmd': ('bash', 09:30:25 INFO - '-c', 09:30:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:25 INFO - 'enabled': True, 09:30:25 INFO - 'halt_on_failure': False, 09:30:25 INFO - 'name': 'check_screen_resolution'},), 09:30:25 INFO - 'run_cmd_checks_enabled': True, 09:30:25 INFO - 'sps_profile': False, 09:30:25 INFO - 'sps_profile_interval': 0, 09:30:25 INFO - 'suite': 'dromaeojs', 09:30:25 INFO - 'system_bits': '32', 09:30:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:25 INFO - 'title': 't-yosemite-r7-0071', 09:30:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:25 INFO - 'use_talos_json': True, 09:30:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:25 INFO - 'work_dir': 'build'} 09:30:25 INFO - ##### 09:30:25 INFO - ##### Running clobber step. 09:30:25 INFO - ##### 09:30:25 INFO - Running pre-action listener: _resource_record_pre_action 09:30:25 INFO - Running main action method: clobber 09:30:25 INFO - rmtree: /builds/slave/test/build 09:30:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:27 INFO - Running post-action listener: _resource_record_post_action 09:30:27 INFO - ##### 09:30:27 INFO - ##### Running read-buildbot-config step. 09:30:27 INFO - ##### 09:30:27 INFO - Running pre-action listener: _resource_record_pre_action 09:30:27 INFO - Running main action method: read_buildbot_config 09:30:27 INFO - Using buildbot properties: 09:30:27 INFO - { 09:30:27 INFO - "project": "", 09:30:27 INFO - "product": "firefox", 09:30:27 INFO - "script_repo_revision": "production", 09:30:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:30:27 INFO - "repository": "", 09:30:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:30:27 INFO - "buildid": "20160428073712", 09:30:27 INFO - "pgo_build": "False", 09:30:27 INFO - "basedir": "/builds/slave/test", 09:30:27 INFO - "buildnumber": 743, 09:30:27 INFO - "slavename": "t-yosemite-r7-0071", 09:30:27 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:30:27 INFO - "platform": "yosemite_r7", 09:30:27 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:30:27 INFO - "branch": "fx-team", 09:30:27 INFO - "repo_path": "integration/fx-team", 09:30:27 INFO - "revision": "45e67d6b5a6137898898c23ae1fe60ddfcd59ed1", 09:30:27 INFO - "stage_platform": "macosx64", 09:30:27 INFO - "builduid": "13f3b14acfca46618e0c13fc413cc1df", 09:30:27 INFO - "slavebuilddir": "test" 09:30:27 INFO - } 09:30:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:30:27 INFO - Running post-action listener: _resource_record_post_action 09:30:27 INFO - ##### 09:30:27 INFO - ##### Running download-and-extract step. 09:30:27 INFO - ##### 09:30:27 INFO - Running pre-action listener: _resource_record_pre_action 09:30:27 INFO - Running main action method: download_and_extract 09:30:27 INFO - mkdir: /builds/slave/test/build/tests 09:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:27 INFO - https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:30:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:30:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:30:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:30:29 INFO - Downloaded 1199 bytes. 09:30:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:30:29 INFO - Using the following test package requirements: 09:30:29 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:30:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:30:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:30:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'jsshell-mac.zip'], 09:30:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:30:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:30:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:30:29 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:30:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:30:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:30:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:30: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')]} 09:30:29 INFO - https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:30:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:30 INFO - Downloaded 18124097 bytes. 09:30:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:34 INFO - Return code: 0 09:30:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:34 INFO - https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:30:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:35 INFO - Downloaded 18124097 bytes. 09:30:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:38 INFO - Return code: 0 09:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:38 INFO - https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:30:39 INFO - Downloaded 10840145 bytes. 09:30:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:30:39 INFO - Return code: 0 09:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:39 INFO - https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:30:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:30:44 INFO - Downloaded 110274020 bytes. 09:30:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:44 INFO - mkdir: /builds/slave/test/properties 09:30:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:30:44 INFO - Writing to file /builds/slave/test/properties/build_url 09:30:44 INFO - Contents: 09:30:44 INFO - build_url:https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:45 INFO - Running post-action listener: _resource_record_post_action 09:30:45 INFO - Running post-action listener: set_extra_try_arguments 09:30:45 INFO - ##### 09:30:45 INFO - ##### Running populate-webroot step. 09:30:45 INFO - ##### 09:30:45 INFO - Running pre-action listener: _resource_record_pre_action 09:30:45 INFO - Running main action method: populate_webroot 09:30:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:45 INFO - '--tppagecycles', 09:30:45 INFO - '7'], 09:30:45 INFO - 'tests': ['tsvgm']}}, 09:30:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:30:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:30:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:30:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['damp', 'tps']}, 09:30:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'tests': ['damp', 'tps']}, 09:30:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['dromaeo_dom']}, 09:30:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other-e10s': {'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tsvgx', 09:30:45 INFO - 'tsvgr_opacity', 09:30:45 INFO - 'tart', 09:30:45 INFO - 'tscrollx', 09:30:45 INFO - 'cart']}, 09:30:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:30:45 INFO - 'tsvgr_opacity', 09:30:45 INFO - 'tart', 09:30:45 INFO - 'tscrollx', 09:30:45 INFO - 'cart']}, 09:30:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tp5o']}, 09:30:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'tests': ['tp5o']}, 09:30:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s', 09:30:45 INFO - '--xperf_path', 09:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:45 INFO - 'tests': ['tp5n']}, 09:30:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--xperf_path', 09:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:45 INFO - 'tests': ['tp5n']}}, 09:30:45 INFO - 'talos.zip': {'path': '', 09:30:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:45 INFO - Running post-action listener: _resource_record_post_action 09:30:45 INFO - ##### 09:30:45 INFO - ##### Running create-virtualenv step. 09:30:45 INFO - ##### 09:30:45 INFO - Running pre-action listener: _resource_record_pre_action 09:30:45 INFO - Running main action method: create_virtualenv 09:30:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:30:45 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 09:30:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:30:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:45 INFO - Using real prefix '/tools/python27' 09:30:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:30:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:49 INFO - Installing pip.................done. 09:30:49 INFO - Return code: 0 09:30:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:49 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:30:49 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 09:30:49 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 09:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:49 INFO - 'HOME': '/Users/cltbld', 09:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:49 INFO - 'LOGNAME': 'cltbld', 09:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:49 INFO - 'MOZ_NO_REMOTE': '1', 09:30:49 INFO - 'NO_EM_RESTART': '1', 09:30:49 INFO - 'PAGER': '/bin/cat', 09:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:49 INFO - 'PWD': '/builds/slave/test', 09:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:49 INFO - 'SHELL': '/bin/bash', 09:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:30:49 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:49 INFO - 'USER': 'cltbld', 09:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:49 INFO - 'XPC_FLAGS': '0x0', 09:30:49 INFO - 'XPC_SERVICE_NAME': '0', 09:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:49 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 09:30:49 INFO - Cleaning up... 09:30:49 INFO - Return code: 0 09:30:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:49 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:30:49 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 09:30:49 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 09:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:49 INFO - 'HOME': '/Users/cltbld', 09:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:49 INFO - 'LOGNAME': 'cltbld', 09:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:49 INFO - 'MOZ_NO_REMOTE': '1', 09:30:49 INFO - 'NO_EM_RESTART': '1', 09:30:49 INFO - 'PAGER': '/bin/cat', 09:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:49 INFO - 'PWD': '/builds/slave/test', 09:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:49 INFO - 'SHELL': '/bin/bash', 09:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:30:49 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:49 INFO - 'USER': 'cltbld', 09:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:49 INFO - 'XPC_FLAGS': '0x0', 09:30:49 INFO - 'XPC_SERVICE_NAME': '0', 09:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:49 INFO - Downloading/unpacking psutil>=0.7.1 09:30:49 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 09:30:49 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 09:30:49 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 09:30:49 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 09:30:49 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 09:30:49 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 09:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:52 INFO - Installing collected packages: psutil 09:30:52 INFO - Running setup.py install for psutil 09:30:52 INFO - building 'psutil._psutil_osx' extension 09:30:52 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 09:30:53 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 09:30:53 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 09:30:53 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 09:30:53 INFO - building 'psutil._psutil_posix' extension 09:30:53 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 09:30:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:30:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:30:53 INFO - ^ 09:30:53 INFO - 1 warning generated. 09:30:53 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 09:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:53 INFO - Successfully installed psutil 09:30:53 INFO - Cleaning up... 09:30:53 INFO - Return code: 0 09:30:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:30:53 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')]} 09:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:53 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')]} 09:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:53 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:30:53 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 09:30:53 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 09:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:53 INFO - 'HOME': '/Users/cltbld', 09:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:53 INFO - 'LOGNAME': 'cltbld', 09:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:53 INFO - 'MOZ_NO_REMOTE': '1', 09:30:53 INFO - 'NO_EM_RESTART': '1', 09:30:53 INFO - 'PAGER': '/bin/cat', 09:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:53 INFO - 'PWD': '/builds/slave/test', 09:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:53 INFO - 'SHELL': '/bin/bash', 09:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:30:53 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:53 INFO - 'USER': 'cltbld', 09:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:53 INFO - 'XPC_FLAGS': '0x0', 09:30:53 INFO - 'XPC_SERVICE_NAME': '0', 09:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:30:56 INFO - Installing collected packages: mozsystemmonitor 09:30:56 INFO - Running setup.py install for mozsystemmonitor 09:30:56 INFO - Successfully installed mozsystemmonitor 09:30:56 INFO - Cleaning up... 09:30:56 INFO - Return code: 0 09:30:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:56 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')]} 09:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:56 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')]} 09:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:56 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:30:56 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 09:30:56 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 09:30:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:30:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:56 INFO - 'HOME': '/Users/cltbld', 09:30:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:56 INFO - 'LOGNAME': 'cltbld', 09:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:56 INFO - 'MOZ_NO_REMOTE': '1', 09:30:56 INFO - 'NO_EM_RESTART': '1', 09:30:56 INFO - 'PAGER': '/bin/cat', 09:30:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:56 INFO - 'PWD': '/builds/slave/test', 09:30:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:56 INFO - 'SHELL': '/bin/bash', 09:30:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:30:56 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:30:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:56 INFO - 'USER': 'cltbld', 09:30:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:56 INFO - 'XPC_FLAGS': '0x0', 09:30:56 INFO - 'XPC_SERVICE_NAME': '0', 09:30:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:57 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 09:30:57 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 09:30:57 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 09:30:57 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 09:30:57 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 09:30:57 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 09:30:59 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:00 INFO - Downloading docopt-0.6.1.tar.gz 09:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:00 INFO - Running setup.py install for blobuploader 09:31:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:00 INFO - Running setup.py install for requests 09:31:01 INFO - Running setup.py install for docopt 09:31:01 INFO - Successfully installed blobuploader requests docopt 09:31:01 INFO - Cleaning up... 09:31:01 INFO - Return code: 0 09:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:01 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')]} 09:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:01 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')]} 09:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:01 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:31:01 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 09:31:01 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 09:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:01 INFO - 'HOME': '/Users/cltbld', 09:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:01 INFO - 'LOGNAME': 'cltbld', 09:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:01 INFO - 'MOZ_NO_REMOTE': '1', 09:31:01 INFO - 'NO_EM_RESTART': '1', 09:31:01 INFO - 'PAGER': '/bin/cat', 09:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:01 INFO - 'PWD': '/builds/slave/test', 09:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:01 INFO - 'SHELL': '/bin/bash', 09:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:31:01 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:01 INFO - 'USER': 'cltbld', 09:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:01 INFO - 'XPC_FLAGS': '0x0', 09:31:01 INFO - 'XPC_SERVICE_NAME': '0', 09:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:01 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-kp62cP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-arMBHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-tzh5us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-wKH147-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-eWF1Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Tp72O_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-QMC26F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-WG6I1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-3a0jq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-yVbbQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-k66WbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-456EZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-rgciJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-u6Xldw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-0fGtks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-QPpGNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-o0a7rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:03 INFO - Running setup.py install for manifestparser 09:31:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozcrash 09:31:03 INFO - Running setup.py install for mozdebug 09:31:04 INFO - Running setup.py install for mozdevice 09:31:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozfile 09:31:04 INFO - Running setup.py install for mozhttpd 09:31:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozinfo 09:31:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozInstall 09:31:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozleak 09:31:04 INFO - Running setup.py install for mozlog 09:31:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for moznetwork 09:31:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozprocess 09:31:05 INFO - Running setup.py install for mozprofile 09:31:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozrunner 09:31:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozscreenshot 09:31:05 INFO - Running setup.py install for moztest 09:31:06 INFO - Running setup.py install for mozversion 09:31:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:06 INFO - Cleaning up... 09:31:06 INFO - Return code: 0 09:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:31:06 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 09:31:06 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 09:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:06 INFO - 'HOME': '/Users/cltbld', 09:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:06 INFO - 'LOGNAME': 'cltbld', 09:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:06 INFO - 'MOZ_NO_REMOTE': '1', 09:31:06 INFO - 'NO_EM_RESTART': '1', 09:31:06 INFO - 'PAGER': '/bin/cat', 09:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:06 INFO - 'PWD': '/builds/slave/test', 09:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:06 INFO - 'SHELL': '/bin/bash', 09:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:31:06 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:06 INFO - 'USER': 'cltbld', 09:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:06 INFO - 'XPC_FLAGS': '0x0', 09:31:06 INFO - 'XPC_SERVICE_NAME': '0', 09:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:06 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-x4Gmp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:06 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)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:06 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-8M231S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:06 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)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:06 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-wVFD_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:06 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)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:06 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-zlRGdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:06 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)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:06 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-kxUiCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-9nRouJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-fk2CAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-9QocMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ufvsT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-8nGfXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-90hFwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-deh_j2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ksJJB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-OhiDKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:07 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)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:07 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-XRei7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:08 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)) 09:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:08 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-CFV09G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:08 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)) 09:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:08 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-82nLD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:08 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)) 09:31:08 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)) 09:31:08 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)) 09:31:08 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)) 09:31:08 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)) 09:31:08 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)) 09:31:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:08 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 09:31:08 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 09:31:08 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 09:31:08 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 09:31:08 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 09:31:08 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 09:31:10 INFO - Downloading blessings-1.6.tar.gz 09:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:10 INFO - Installing collected packages: blessings 09:31:10 INFO - Running setup.py install for blessings 09:31:10 INFO - Successfully installed blessings 09:31:10 INFO - Cleaning up... 09:31:11 INFO - Return code: 0 09:31:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:11 INFO - Reading from file tmpfile_stdout 09:31:11 INFO - Current package versions: 09:31:11 INFO - blessings == 1.6 09:31:11 INFO - blobuploader == 1.2.4 09:31:11 INFO - docopt == 0.6.1 09:31:11 INFO - manifestparser == 1.1 09:31:11 INFO - mozInstall == 1.12 09:31:11 INFO - mozcrash == 0.17 09:31:11 INFO - mozdebug == 0.1 09:31:11 INFO - mozdevice == 0.48 09:31:11 INFO - mozfile == 1.2 09:31:11 INFO - mozhttpd == 0.7 09:31:11 INFO - mozinfo == 0.9 09:31:11 INFO - mozleak == 0.1 09:31:11 INFO - mozlog == 3.1 09:31:11 INFO - moznetwork == 0.27 09:31:11 INFO - mozprocess == 0.22 09:31:11 INFO - mozprofile == 0.28 09:31:11 INFO - mozrunner == 6.11 09:31:11 INFO - mozscreenshot == 0.1 09:31:11 INFO - mozsystemmonitor == 0.0 09:31:11 INFO - moztest == 0.7 09:31:11 INFO - mozversion == 1.4 09:31:11 INFO - psutil == 3.1.1 09:31:11 INFO - requests == 1.2.3 09:31:11 INFO - wsgiref == 0.1.2 09:31:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:11 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')]} 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 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')]} 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:31:11 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 09:31:11 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 09:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:11 INFO - 'HOME': '/Users/cltbld', 09:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:11 INFO - 'LOGNAME': 'cltbld', 09:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:11 INFO - 'MOZ_NO_REMOTE': '1', 09:31:11 INFO - 'NO_EM_RESTART': '1', 09:31:11 INFO - 'PAGER': '/bin/cat', 09:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:11 INFO - 'PWD': '/builds/slave/test', 09:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:11 INFO - 'SHELL': '/bin/bash', 09:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:31:11 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:11 INFO - 'USER': 'cltbld', 09:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:11 INFO - 'XPC_FLAGS': '0x0', 09:31:11 INFO - 'XPC_SERVICE_NAME': '0', 09:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 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)) 09:31:11 INFO - Cleaning up... 09:31:11 INFO - Return code: 0 09:31:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:31:11 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')]} 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 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')]} 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:31:11 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 09:31:11 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 09:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:11 INFO - 'HOME': '/Users/cltbld', 09:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:11 INFO - 'LOGNAME': 'cltbld', 09:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:11 INFO - 'MOZ_NO_REMOTE': '1', 09:31:11 INFO - 'NO_EM_RESTART': '1', 09:31:11 INFO - 'PAGER': '/bin/cat', 09:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:11 INFO - 'PWD': '/builds/slave/test', 09:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:11 INFO - 'SHELL': '/bin/bash', 09:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:31:11 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:11 INFO - 'USER': 'cltbld', 09:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:11 INFO - 'XPC_FLAGS': '0x0', 09:31:11 INFO - 'XPC_SERVICE_NAME': '0', 09:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:11 INFO - Downloading/unpacking jsonschema 09:31:11 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 09:31:11 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 09:31:11 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 09:31:11 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 09:31:11 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 09:31:11 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 09:31:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:14 INFO - Downloading/unpacking functools32 (from jsonschema) 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:14 INFO - warning: no files found matching '*.txt' 09:31:14 INFO - no previously-included directories found matching 'build' 09:31:14 INFO - no previously-included directories found matching 'dist' 09:31:14 INFO - no previously-included directories found matching '.git*' 09:31:14 INFO - Installing collected packages: jsonschema, functools32 09:31:14 INFO - Running setup.py install for functools32 09:31:14 INFO - warning: no files found matching '*.txt' 09:31:14 INFO - no previously-included directories found matching 'build' 09:31:14 INFO - no previously-included directories found matching 'dist' 09:31:14 INFO - no previously-included directories found matching '.git*' 09:31:14 INFO - Successfully installed jsonschema functools32 09:31:14 INFO - Cleaning up... 09:31:15 INFO - Return code: 0 09:31:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:31:15 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')]} 09:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:15 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')]} 09:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:15 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 0x10c753a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c763420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c85a1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, '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.I6jwSljNRp/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.bYHUrVz6z2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 09:31:15 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 09:31:15 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 09:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:15 INFO - 'HOME': '/Users/cltbld', 09:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:15 INFO - 'LOGNAME': 'cltbld', 09:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:15 INFO - 'MOZ_NO_REMOTE': '1', 09:31:15 INFO - 'NO_EM_RESTART': '1', 09:31:15 INFO - 'PAGER': '/bin/cat', 09:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:15 INFO - 'PWD': '/builds/slave/test', 09:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:15 INFO - 'SHELL': '/bin/bash', 09:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:31:15 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:15 INFO - 'USER': 'cltbld', 09:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:15 INFO - 'XPC_FLAGS': '0x0', 09:31:15 INFO - 'XPC_SERVICE_NAME': '0', 09:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:15 INFO - Downloading/unpacking flake8 09:31:15 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 09:31:15 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 09:31:15 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 09:31:15 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 09:31:15 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 09:31:15 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 09:31:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:31:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:31:17 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 09:31:17 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 09:31:17 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 09:31:17 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 09:31:17 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 09:31:17 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 09:31:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:31:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:18 INFO - no previously-included directories found matching 'docs/_build' 09:31:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:31:19 INFO - Running setup.py install for pep8 09:31:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:19 INFO - no previously-included directories found matching 'docs/_build' 09:31:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:31:19 INFO - Cleaning up... 09:31:19 INFO - Return code: 0 09:31:19 INFO - Running post-action listener: _resource_record_post_action 09:31:19 INFO - Running post-action listener: _start_resource_monitoring 09:31:19 INFO - Starting resource monitoring. 09:31:19 INFO - ##### 09:31:19 INFO - ##### Running install step. 09:31:19 INFO - ##### 09:31:19 INFO - Running pre-action listener: _resource_record_pre_action 09:31:19 INFO - Running main action method: install 09:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:19 INFO - Reading from file tmpfile_stdout 09:31:19 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:31:19 INFO - Reading from file tmpfile_stdout 09:31:19 INFO - Output received: 09:31:19 INFO - Usage: mozinstall [options] installer 09:31:19 INFO - Options: 09:31:19 INFO - -h, --help show this help message and exit 09:31:19 INFO - -d DEST, --destination=DEST 09:31:19 INFO - Directory to install application into. [default: 09:31:19 INFO - "/builds/slave/test"] 09:31:19 INFO - --app=APP Application being installed. [default: firefox] 09:31:19 INFO - mkdir: /builds/slave/test/build/application 09:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:31:53 INFO - Reading from file tmpfile_stdout 09:31:53 INFO - Output received: 09:31:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:31:53 INFO - Running post-action listener: _resource_record_post_action 09:31:53 INFO - ##### 09:31:53 INFO - ##### Running run-tests step. 09:31:53 INFO - ##### 09:31:53 INFO - Running pre-action listener: _resource_record_pre_action 09:31:53 INFO - Running main action method: run_tests 09:31:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:31:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:31:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:31:53 INFO - 2016-04-28 09:31:53.366 screenresolution[1635:7686] starting screenresolution argv=screenresolution get 09:31:53 INFO - 2016-04-28 09:31:53.368 screenresolution[1635:7686] Display 0: 1600x1200x32@60 09:31:53 INFO - 2016-04-28 09:31:53.374 screenresolution[1636:7688] starting screenresolution argv=screenresolution list 09:31:53 INFO - Available Modes on Display 0 09:31:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:31:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:31:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:31:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:31:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:31:53 INFO - Intel Iris: 09:31:53 INFO - Chipset Model: Intel Iris 09:31:53 INFO - Type: GPU 09:31:53 INFO - Bus: Built-In 09:31:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:31:53 INFO - Vendor: Intel (0x8086) 09:31:53 INFO - Device ID: 0x0a2e 09:31:53 INFO - Revision ID: 0x0009 09:31:53 INFO - Displays: 09:31:53 INFO - Display: 09:31:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:31:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:31:53 INFO - Display Serial Number: 1600x1200 60 09:31:53 INFO - Main Display: Yes 09:31:53 INFO - Mirror: Off 09:31:53 INFO - Online: Yes 09:31:53 INFO - Rotation: Supported 09:31:53 INFO - Return code: 0 09:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:31:53 INFO - Python 2.7.3 09:31:53 INFO - Return code: 0 09:31:53 INFO - grabbing minidump binary from tooltool 09:31:53 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')]} 09:31:53 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 0x10c5f2e00>, '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 0x10c41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5edb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:31:53 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 09:31:53 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 09:31:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:53 INFO - Return code: 0 09:31:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:31:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:31:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:31:54 INFO - Return code: 0 09:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 09:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0071 --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 09:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I6jwSljNRp/Render', 09:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:54 INFO - 'HOME': '/Users/cltbld', 09:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:54 INFO - 'LOGNAME': 'cltbld', 09:31:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:54 INFO - 'MOZ_NO_REMOTE': '1', 09:31:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:54 INFO - 'NO_EM_RESTART': '1', 09:31:54 INFO - 'PAGER': '/bin/cat', 09:31:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:54 INFO - 'PWD': '/builds/slave/test', 09:31:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:54 INFO - 'SHELL': '/bin/bash', 09:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYHUrVz6z2/Listeners', 09:31:54 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 09:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:54 INFO - 'USER': 'cltbld', 09:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:54 INFO - 'XPC_FLAGS': '0x0', 09:31:54 INFO - 'XPC_SERVICE_NAME': '0', 09:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 09:31:55 INFO - mozversion application_buildid: 20160428073712 09:31:55 INFO - mozversion application_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 09:31:55 INFO - mozversion application_display_name: Nightly 09:31:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:55 INFO - mozversion application_name: Firefox 09:31:55 INFO - mozversion application_remotingname: firefox 09:31:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:31:55 INFO - mozversion application_vendor: Mozilla 09:31:55 INFO - mozversion application_version: 49.0a1 09:31:55 INFO - mozversion platform_buildid: 20160428073712 09:31:55 INFO - mozversion platform_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 09:31:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:31:55 INFO - mozversion platform_version: 49.0a1 09:31:55 INFO - using testdate: 1461861115 09:31:55 INFO - actual date: 1461861115 09:31:55 INFO - starting webserver on 'localhost:49283' 09:31:55 INFO - SUITE-START | Running 2 tests 09:31:55 INFO - TEST-START | dromaeo_css 09:31:55 INFO - operating with platform_type : mac_ 09:31:55 INFO - Initialising browser for dromaeo_css test... 09:31:55 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpl0V9LJ/profile http://localhost:49283/getInfo.html) 09:31:57 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:31:57 INFO - PROCESS | 1654 | colorDepth:24 09:31:57 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:31:57 INFO - PROCESS | 1654 | __metrics 09:31:57 INFO - PROCESS | 1654 | 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" 09:31:57 ERROR - PROCESS | 1654 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:31:58 INFO - TEST-INFO | 1654: exit 0 09:31:58 INFO - Browser initialized. 09:31:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:58 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpl0V9LJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:09 INFO - PROCESS | 1656 | RSS: Main: 239636480 09:32:09 INFO - PROCESS | 1656 | 09:33:00 INFO - PROCESS | 1656 | 1461861180741 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] 09:35:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 09:35:14 INFO - PROCESS | 1656 | RSS: Main: 489611264 09:35:14 INFO - PROCESS | 1656 | 09:38:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 09:38:17 INFO - PROCESS | 1656 | RSS: Main: 377876480 09:38:17 INFO - PROCESS | 1656 | 09:41:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 09:41:20 INFO - PROCESS | 1656 | RSS: Main: 372019200 09:41:20 INFO - PROCESS | 1656 | 09:44:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 09:44:23 INFO - PROCESS | 1656 | RSS: Main: 369860608 09:44:23 INFO - PROCESS | 1656 | 09:47:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 09:47:27 INFO - PROCESS | 1656 | RSS: Main: 341712896 09:47:27 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 09:50:30 INFO - PROCESS | 1656 | RSS: Main: 381652992 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | __start_tp_report 09:50:30 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:50:30 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:50:30 INFO - PROCESS | 1656 | |i|pagename|runs| 09:50:30 INFO - PROCESS | 1656 | |0;dojo.html;3108.8911888083912;3190.776645634806;3367.175042114684;3408.4205685033544;3435.433153529668;5439.4832490913395;5520.47555482229;5523.889522209553;5545.528630066439;5573.08044172712;5546.223528705985;5570.025245582027;5664.433556644344;5687;5690.9146362804595;5030.522100400474;5051.595872330205;5064.215046667772;5075.010372977271;5267.920981185266;5181.404138524081;5204.193350030751;5212.817551385702;5212.817551385702;5241.449647786984;5023.372078490189;5108.795648174068;5146.305248791421;5149.639525233235;5151.484851514856;5066.46802085783;5085.084684756743;5093.134167191586;5094.592432605383;5114.872128196787;5010.3737032870895;5040.546350828443;5053.2925390445;5062.54437100659;5067.847964561032;5385.192221166817;5442.6462279952075;5446.645968012106;5567.220589117527;5578.163275508666;6052.5157987361345;6085.513158947274;6087.3303936566945;6092.299385570627;6109.877802443926;6072.423119803656;6123.969380153071;6153.692315384213;6162;6170.444659980579;5511.58663100265;5561.4716601922755;5576.470235327637;5619.662820230799;5654.462826031561;6203.7828675996125;6244.562880598405;6262.812115636538;6275.96862015687;6290.433860952537;5912.970435147797;5929.0513517836935;5944.524438044946;5947.613405128653;5964.642121472725;5932.58471906971;6056.3035250946705;6085.269767627913;6154.692265386713;6161.322254551996;6003.879922401527;6043.667598282137;6054.212952316171;6056.485198758155;6074.0281554951;5333.5199832015305;6062.727177277033;6076.3316035236085;6081;6083.39166083397;4093.692973027035;4587.03672228829;4788.11419887322;4789.664723469324;4808.2547205183255;3723.1995121049104;4380.802863871133;4386.8025938832825;4404.823807047682;4409.867703968822;5469.617126801165;5476.288082549323;5489.780408783684;5498.670079795225;5513.035218836688;6056.182415373868;6067.544934129958;6077.1491991121275;6085.9695701521205;6133.723982420713;4684.8360307389075;5505.587080968943;5539.5014448699;5632.04255276604;5638.238837756932;5288.497592728685;5320.015797077553;5320.813771518056;5323.574114070865;5334.59990500714;6233.812985610439;6234.875302493925;6242.843928901723;6245.344238854946;6334.176557047738;5942.940570594412;6085.756569737338;6093.2078829751845;6099.878002440015;6153.907691384722;6159.9528080227365;6192.876142477216;6194.845128871814;6201.162843016135;6215.502759779117;5397.70312632809;5917.526597872074;5919.704014799329;5956.493698035715;6075.422834830684;6137;6137.8979746733885;6154.661493617894;6160.907586386297;6166;5496.900619875961;5919.230500034968;5928.110783382354;5940.524758019435;5960.195373624679;5679.943200567942;5697.4302569742695;5817.243758311392;5833.154192642128;5896.469317761422;5700.3444603871085;5703.971480142573;5704.3725190228215;5707.343655479673;5723.513501352431;5791.8552036199435;5795.072788353843;5830.708464576839;5837.29952405714;5841.678707671119;5704.828855134353;5708.086706127;5715.628484148517;5715.942840571541;5722.0272553665145 09:50:30 INFO - PROCESS | 1656 | |1;ext.html;505.72943475240754;517.7644171901784;538.6175815171229;549.8570371703356;557.3813067495079;36774.632253677424;37055.25889482209;38168.618313816856;39510.40734388983;39873.60126398735;41734.79132604333;42255.366169507404;42280.78859605698;42320.78839605805;42437;8428.199321064489;8603.56982150893;9354.906450935472;9615.56729947154;9618.182454491376;8626.396152269344;8788.077251888555;8791.296696264284;10960.616378426757;11252.887471125267;11423.485943132537;11630.244034137797;11644.359560224226;11652.009579185737;11664.300141991507;11527.077833773319;11606.187566870323;11643.650690479299;11680.94159529197;11691.883081169164;8434.62044208012;11079.446027698583;11281.887181128166;11311.773764524663;11342.659720208325;38756.03109922246;39324;39325;39447.60552394468;39477.40783888244;3231.9515207271675;3308.17295676081;3378.189234583707;3475.4265546184847;3555.466679998021;28379.716202837913;28777.42445151085;28849.71150288491;28887.71112288871;28972.710272897213;2710.205056263608;2711.0917842522776;2735.3298441881666;2744.3276397282593;2812.3953350029637;374440;374606.12696936616;374608;392793;490649;34107.97676069722;55728.72135639296;64402.6779866107;64494.67752661207;64546.031809522894;35712.642873570934;35869;35882.28235435329;36011.09972250715;36037.09907252339;15022.774658380133;15456.768148477782;15464.613384665248;15468.381264749509;15468.845311546966;11865.406729663482;13016.609501714964;13100.541481048118;13110.213387196798;13115.540956066492;7765.145833958373;7994.840103197904;8090.4336696431865;8142.185781421929;8192.426530142833;3331.68349006844;3413.9317213655586;3425.451927691607;3426.8971930842113;3455.7235421166247;933.7338858425317;1007.7631756537207;1030.8866024737272;1057.9100776434088;1086.739182596187;28275;28452;28796.712032879404;29058.564121538195;29952.251193719894;1065.3181963543338;1110.5169251375678;1113.2374323588367;1139.2310190621297;1183.8875306846007;12518.81221781674;12674.493020279086;13153.539626113232;14806.333714982851;15195.772063419057;610249;615213;621682;622486;624597;60174;61486.69256653688;61491.6925415379;61607.691961540804;61635;1332.513632524141;1367.0225788561027;1454.803601513782;1499.5426394949645;1502.0612117426608;28480.715192848635;28649.14052578426;29036.709632904247;29143.85428072846;29220.7077929218;47783;47885.28172077352;48297.5170248293;48347;48433.78915526999;367451;368263;369300.1534992308;370088;371699;22373;25377.111801087613;25664.23007309784;25845.612315814902;26080.217593472225;40494;41177.1764564701;43063.25529453939;43279.567204328814;43882.56117438913;34092.31815363629;34967.475487868214;35559.11102222362;35589.1102722434;36238.63761362458;29302.41395172127;32561.674383256814;34831.12922176966;34992.65007349894;35783.82108089443;30541;34478;36340.87702914762;37194.442083369315;37227.62772372241;25359.746402535737;31677.366452670358;32508.02475925726;32544.83727581347;32572.697092117;18431.72352414688;23657.698826564018;24010.75989240155;24535;24636.50726985486 09:50:30 INFO - PROCESS | 1656 | |2;jquery.html;398.95212574491063;399.6143721308936;400.2255635345605;401.2216300377266;411.6171960077127;11462.426878656055;20276.69584956225;20983.16067357304;21038;21082.789172108292;16512.422065227718;16997.49007529773;19986.60026799467;20219.191232350688;20884.686729699028;5810.331811841639;5959.851003724898;6008.098785182214;6021.397860213988;6021.909671354933;5890.381509941459;5938.46553810157;5944.405559444042;5946.762129514815;6001.849953751147;5760.193572899798;5903.0260007099;5919.437653422918;5937.317208521033;5951.226340575719;5892.852678683046;5973.163757074013;5973.342932277446;5973.462388385023;5974.97012514939;5041.420236672756;5209.869753256161;5225.42520322768;5228.215767634847;5232.843014709565;23051.538969220688;24355;24968;25587.23238302854;26359.472810543873;2136.604728125302;2174.9565008699897;2211.1266049910196;2277.772222777709;2486.8383555069045;9036;10320.79358412835;11226.438678066064;12029.639110826689;14790.408383664748;1721.8708596855286;1726.3094762095136;1765.5850875044355;1874.3439796071402;1982.246746236421;89736;118868;122571.38714306371;124878.9999999991;125839;11490;11515.193936424537;11534.538618455335;11581.42092895352;11718.765624687458;15085.47200847965;15218.619534511727;15231;15232.238388080552;15232.543023709306;7299.854002919912;7514.752080676135;8354.45696029756;8717.738467845975;8823.250023748053;6916.515843890879;7037.472189585802;8490.023647280641;8527.403081784216;8542.359323050796;4522.34426008227;4530.365748795171;4531.235946072893;4544.7500387479;4602.953970460252;1903.6573416784834;1961.6272908147691;1964.430315208602;1968.2187620203738;1969.0745349685624;716.691822516323;734.8934404511423;767;782.8160382310152;872.6029656506274;18894.81105188958;18926.81073189278;19051.428457146307;19563.320519149012;19616.411507654793;814.8248126652679;826.9586520673943;832.0888626953476;835.8286551256978;871.4509858788922;9916.206703463706;10163.898361016443;10246.924072972231;10302.587896484207;10387.740306492398;575225;584916;589309;605971;606926;126239;131606;133324;133394.33302833425;134488.32755836158;997.1922742578535;1053.1785207538285;1069.8341757027674;1070.2080460459204;1072.0137473524376;18022.36921707734;18304.359347422775;18313;18365.90817045906;18694.813051869307;47395.52604473911;47804;48092.759536202095;48375.75812120917;54289;69865.30134698791;73149.63425182841;73994;74720.25279747133;77614.61192694001;7459.216782238004;7827.530348179127;8057.959710201411;8148.674053037812;8551.1448855114;9145;10217.795644087226;11712.355820429617;12634.747305054032;12843.486260549473;10632.946835265775;10804.621838235624;10890.346579204956;10991;11039.006489415991;10608.52261648243;10761.730956726144;10807.189460790629;10902.509387077442;10989.450527473435;11195.72010699739;11472;11525.366104864313;11663.88336116662;11664.94167529157;8176.959115204386;8247.175282471704;8252;8262.049864265686;8573.399862009599;11146.88853111491;11247.550097995989;11248.662540123809;11270.267432616854;11344.546218151512 09:50:30 INFO - PROCESS | 1656 | |3;mootools.html;1422.6158937086993;1440.992795036024;1451.9637009074772;1593.99203003985;1606.879484038697;7625.542467451956;8368.121347258542;8642.654293828255;8663.133686631334;8664.523451210182;8149.103598604163;8481.618327175276;8484.91515084849;8487.448315859465;8518.23335899769;2131.7015617813518;2385.272491889973;2413.963790543143;2462.076721229539;2463.4210960424284;2428.9271321860374;2429.186222615417;2432.3189506937997;2435.366804630791;2435.4398488347692;2416.395901024744;2422.878856057199;2451.154351748644;2453.41118131648;2454.460018795862;2105.884176370299;2350.612148995414;2404.7955923746504;2430.0644251962945;2432.927012189637;2428.91498797543;2452.2152911068465;2457.336519139822;2462.187478132212;2462.8152888533255;7131.536450130777;8225.259726624572;8308.252257296812;8389.538575378352;8411.621477033548;5076.060928728197;5225.581953443715;5233.319668443116;5247.291615631879;5257.7108259045735;3372.6121496028027;3393.7794043387103;3458.412069948115;3606.441001644749;3618.3848745713294;1033.34382667007;1045.0646671229283;1048.5700862646354;1050.6217761605815;1051.7580956380075;11175.32948023113;11368.658940231806;11383.544658213661;11385;11387.54449822006;4785.186518291829;4798.296415706399;4874.122657921537;4909.7790599423115;4921.729304888194;8467.915320846712;9945.950270248748;9955.303128780915;9956.701298961043;9959.253056020827;5421.617487540652;5426.186072089218;5427.810026649127;5429.131338985743;5431.158170483582;3979.880603581897;4343.739375637472;4507.3689683444345;4912.459629440758;4928.704277743347;4012.25773231953;4025.5571887092983;4095.52901416335;4115.588441155861;4141.295979683461;1330.6340756292097;1349.257908150533;1359.0690377091723;1366.760816857066;1370.465518447806;897.3718397122041;919.9172074513192;929.665320484625;944.7826999790087;949.7435692363161;5559.972200138974;5637.802676906289;5732.226149469851;5738.799142030009;5851.21008663825;463.39295522865154;465.75082330953455;470.7222738584252;1005.4671024356957;1013.3413281367023;3857.8071096445065;3972.4041393791163;3982.9246103551877;4004.599540045972;4037.7375470594316;10120.089191972611;10420.114290285259;10591.364518128938;10609.73475663099;10625.574977000988;10510.369377837354;10601.681949541526;10604.734881628021;10613.150947924147;10614.0447359738;7864.174261702439;7947.3642108631175;7979.938424868339;8204.056533498724;8217.260446559838;5649.152627105967;5800.4489573490455;5804.970975145097;5816.767329306949;5837.3870743571315;9420.811583768424;9461;9776.9022309776;9811.411315321104;9928.950355248178;9763.511824408894;9798.902010980084;9998.150157236716;10004.899951000689;10058.14505767018;6794.252632210551;6927.965360173167;7026.929730702627;7029.26192749754;7045.577265364094;8092.5549094798;8100.756977290691;8178.550179740171;8206.097329293892;8232.17678232196;5986.551008674454;7155.356017958408;7217.891731624135;7493.812654683458;7516.285952834472;7176.7488137914925;7209.747658831916;7219;7296.854062918605;7317.817054573678;6899.75850845218;6906.551074180162;6935.098437203132;6936;6952.130983627047;4705.788239529161;4839.1531481991215;4858.878528036686;4948.233023881304;5060.569851562511;4326.437563116775;4492.797824097858;4839.274108883696;4867.075255701406;5076.959223359277 09:50:30 INFO - PROCESS | 1656 | |4;prototype.html;267.33033750455115;313.4514599450961;314.4873855615348;319.8512691598408;325.23894087834464;5239.8952020959605;5334.759401051558;5365.356157261124;5376.666466790591;5396.811111611094;4136.503619565657;4992.7753251103695;5182.896342073156;5196.428392876781;5267.736613169345;3046.923826904334;3208.5508028875915;3363.3441478911623;3840.8847734568008;4003.97998010011;3461.584609846822;4049.6962727795385;4084.775337356444;4087.57080506547;4137.586241375853;4030.2544029354663;4031.798410079499;4039.858604948827;4042.535108462535;4073.9388909166387;3849.5573009104032;3904.746191497558;4083.97958010211;4104.712670113093;4126;3558.839852206657;3587.856485740567;3594.2272411431404;3604.567451905762;3606.801625910574;3810.6951443884423;3818.713596480275;4900.970796132817;4982.427020892607;5153.845384638467;4254.3831144483875;4303.397524346594;4638.512956139591;4648.047150334173;4660.976695116503;5127.871803204913;5236.360729293143;5455.918161227585;5477.890442191134;5496.230527726121;3502.9474557881654;3558.8967420099852;3813.389857622767;3829.15611751135;3832.885013449601;5576.800987787605;5651.8587035324035;5692.0608099663505;5729.598928075036;5784.392638772911;3812.275667623143;3818.3127037133027;3820.7707537547835;3821.3694740367528;3823;3891.774091161275;3894.3963685628773;3894.5521265054313;3904.2581909437117;3926.9803650982135;4343.891402714958;4480.439945006874;4482.5069242383315;4483.618892394118;4488.386848586853;5052.418971818288;5137.486251374908;5482.835514934558;5506.394296627368;5510.8071217507195;1236.511577926732;1428.171660436944;1432.1192466633154;1432.3983926750789;1434.0606902478894;880.1022956584249;898.8966268879032;916.5289710015462;940.5720397219249;956.3449037409397;5105.285260063594;5239.161734122522;5253.632245742838;5441.755121019563;5457.690154362925;5304.340005396264;5399.865003374868;5780.219670344533;5786.971065144731;5788.363280039192;4449.911001779947;5020.799168033311;5201.973990130101;5249.036977082278;5253.765365139189;2771.68125665547;2851.2159156231787;3239.49787782894;3258.6415494295607;3258.989713188919;5739.85650358736;6465.967670161714;6466.771313450429;6475.579087359307;6499.8625240582705;5158.871028224325;5810.273715785527;5917.142014407887;5963.5527335450015;5993.70031498432;5821.243238378984;5983.760649574056;6006.759729610767;6033.42682445167;6061.575689701678;5381.2197231402015;5428.212909128233;5772.480476757112;5772.9711351442975;5801.94198058014;4836.685615434985;5582.5533957282505;5693.629914055573;5719.5138413235345;5720.628159169641;5053.29253904461;5702.885942281214;6015.600811002404;6016.4585187331595;6023.728932197974;3878.5261600591593;3885.9417108743955;3913.119548101768;3917.236138952877;3928.8035598219403;5514.338279406497;5527.53015993645;5815.575184079928;5818.8254352369495;5845.444682755132;4764.2615394613895;4779.354787103696;5549.861253468695;5635.605507614428;5686.857828554319;4898.706077635354;5593.272874526286;5594.5384031026215;5605.747741351567;5609.354924183771;5285.7621407036;5476.959377718221;5521.199426083276;5521.82495065331;5522.392536820867;5520.282363292747;5652.293463317085;5654;5666.744996475086;5675.545956323567;5492.890142197054;5538.667679939216;5550.58370622213;5551.666899985852;5553.167024946129 09:50:30 INFO - PROCESS | 1656 | |5;yui.html;658.6311665467342;659.8383396067964;691.1562737058171;737.5464089584907;748.9739057491238;14685.853141468582;14730.926345368272;14826.258687065658;15114.848851511482;15136.924315378421;13952.2326272055;13969.58091257264;14011.789823152636;14011.929940350285;14027.929860350709;736.3593673504059;762.0474406991261;911.680911680913;922.0088404964656;936.7751739582519;883.7039591736776;934.3693007220099;938.9201917836992;941.2893265584509;941.5245301122924;946.3895787217275;988.2686811759282;989.4459102902362;991.9553620087078;994.5922171909518;759.1232126893447;938.7746940734174;947.6304241345846;974.1816873825985;976.8778902637171;727.287258486686;728.4281838756604;730.3244498838553;730.3280981496982;735.0260904301822;14917.403303867832;14992.625184370369;14997.850021499755;15009.474668386663;15011.174385408907;12171.33057681827;12221.694457638541;12225.449854756469;12505.812412813815;12528.310942898137;9830.410175389428;10015.198784097254;10206.642767503175;10359.844602330972;10755.83866242007;3441.0709108540805;3515.4551044587874;3531.8057506837113;3555.7866528008144;3576.998440436682;71544;73245;73814.63092684503;74035;74041.51916961685;9350.625974961062;9386.90613093874;9554.760157548837;9579.712608621752;9923.354981926295;17838;20183.49541261451;21849.453763655718;21855.125794968022;21880.343589692333;7372.225916278713;7387.519811212254;7475.8504829903095;7600.011998440278;7697.268759467954;3986.3223252047223;4008.959910400917;4024.5170579530645;4033.8386464541677;4045.7977101144825;3548.591911930161;3637.5816781069984;3637.618050104754;3660.7803531788177;3781.716371272166;581.6597290584971;696.3036963036963;701.5895701014862;714.4820005495974;714.9668728686997;512.344199424737;542.2868927360508;571.1917636544243;611.2847967877594;611.8806832667676;7910.2089791020435;8146.877796833052;8324.807549043953;8371.079181290053;8407.411481196257;569.1604882797923;596.8776400837818;601.287474342905;601.8946684330313;630.9684515774193;3680.5767336756617;3925.430812532167;3936.173403585222;4019.718619696652;4055.5538890721996;88535;89353.55323223473;90364.09635903688;96501.55247671291;96856;67751.98372024423;76662.61668691621;78370.6081469589;81155.78266326009;83600;72688.63655681793;73826.63086684533;74151;74594.62702686452;74921.5015699683;7721.227877212458;7760.650770715219;7954;7967.800804979922;8003.559804210824;44349;44446.88849784019;44485.55514444814;44838;44902;45118;58342.54143646273;58765.4123458777;60812;61812.38187618246;11005.669829905115;11198;11231.360031918737;11363.715907102389;11632.651020469399;11938;12019.819702704292;12327.321997290235;12339.876601234231;12753.808692869428;11467.942660286646;11484.483198255932;11839.348835814113;11881.524739010343;12091.032717382765;11249.887501125211;11257.887421126012;11520.078393728314;11656.650300491;11920.225185362924;10803.837942430713;11073.944630276797;11478.253913495597;11505.964463198352;11575.189736718348;9422.340436169403;9525.714228573153;9568.330216884751;9745.31782775199;9876.555554999899;9604.75988100303;9659.855102173333;9695.66065187715;9726.75683107928;9733.270004749531 09:50:30 INFO - PROCESS | 1656 | __end_tp_report 09:50:30 INFO - PROCESS | 1656 | __start_cc_report 09:50:30 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2722 09:50:30 INFO - PROCESS | 1656 | __end_cc_report 09:50:30 INFO - PROCESS | 1656 | __startTimestamp1461862230741__endTimestamp 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:50:30 INFO - PROCESS | 1656 | Number of tests: 6 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5579.55 Median:5704.37 stddev:636.61 (11.2%) stddev-sans-first:608.35 09:50:30 INFO - PROCESS | 1656 | Values: 3108.9 3190.8 3367.2 3408.4 3435.4 5439.5 5520.5 5523.9 5545.5 5573.1 5546.2 5570.0 5664.4 5687.0 5690.9 5030.5 5051.6 5064.2 5075.0 5267.9 5181.4 5204.2 5212.8 5212.8 5241.4 5023.4 5108.8 5146.3 5149.6 5151.5 5066.5 5085.1 5093.1 5094.6 5114.9 5010.4 5040.5 5053.3 5062.5 5067.8 5385.2 5442.6 5446.6 5567.2 5578.2 6052.5 6085.5 6087.3 6092.3 6109.9 6072.4 6124.0 6153.7 6162.0 6170.4 5511.6 5561.5 5576.5 5619.7 5654.5 6203.8 6244.6 6262.8 6276.0 6290.4 5913.0 5929.1 5944.5 5947.6 5964.6 5932.6 6056.3 6085.3 6154.7 6161.3 6003.9 6043.7 6054.2 6056.5 6074.0 5333.5 6062.7 6076.3 6081.0 6083.4 4093.7 4587.0 4788.1 4789.7 4808.3 3723.2 4380.8 4386.8 4404.8 4409.9 5469.6 5476.3 5489.8 5498.7 5513.0 6056.2 6067.5 6077.1 6086.0 6133.7 4684.8 5505.6 5539.5 5632.0 5638.2 5288.5 5320.0 5320.8 5323.6 5334.6 6233.8 6234.9 6242.8 6245.3 6334.2 5942.9 6085.8 6093.2 6099.9 6153.9 6160.0 6192.9 6194.8 6201.2 6215.5 5397.7 5917.5 5919.7 5956.5 6075.4 6137.0 6137.9 6154.7 6160.9 6166.0 5496.9 5919.2 5928.1 5940.5 5960.2 5679.9 5697.4 5817.2 5833.2 5896.5 5700.3 5704.0 5704.4 5707.3 5723.5 5791.9 5795.1 5830.7 5837.3 5841.7 5704.8 5708.1 5715.6 5715.9 5722.0 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:59434.18 Median:3426.17 stddev:127528.89 (3722.2%) stddev-sans-first:127809.89 09:50:30 INFO - PROCESS | 1656 | Values: 505.7 517.8 538.6 549.9 557.4 36774.6 37055.3 38168.6 39510.4 39873.6 41734.8 42255.4 42280.8 42320.8 42437.0 8428.2 8603.6 9354.9 9615.6 9618.2 8626.4 8788.1 8791.3 10960.6 11252.9 11423.5 11630.2 11644.4 11652.0 11664.3 11527.1 11606.2 11643.7 11680.9 11691.9 8434.6 11079.4 11281.9 11311.8 11342.7 38756.0 39324.0 39325.0 39447.6 39477.4 3232.0 3308.2 3378.2 3475.4 3555.5 28379.7 28777.4 28849.7 28887.7 28972.7 2710.2 2711.1 2735.3 2744.3 2812.4 374440.0 374606.1 374608.0 392793.0 490649.0 34108.0 55728.7 64402.7 64494.7 64546.0 35712.6 35869.0 35882.3 36011.1 36037.1 15022.8 15456.8 15464.6 15468.4 15468.8 11865.4 13016.6 13100.5 13110.2 13115.5 7765.1 7994.8 8090.4 8142.2 8192.4 3331.7 3413.9 3425.5 3426.9 3455.7 933.7 1007.8 1030.9 1057.9 1086.7 28275.0 28452.0 28796.7 29058.6 29952.3 1065.3 1110.5 1113.2 1139.2 1183.9 12518.8 12674.5 13153.5 14806.3 15195.8 610249.0 615213.0 621682.0 622486.0 624597.0 60174.0 61486.7 61491.7 61607.7 61635.0 1332.5 1367.0 1454.8 1499.5 1502.1 28480.7 28649.1 29036.7 29143.9 29220.7 47783.0 47885.3 48297.5 48347.0 48433.8 367451.0 368263.0 369300.2 370088.0 371699.0 22373.0 25377.1 25664.2 25845.6 26080.2 40494.0 41177.2 43063.3 43279.6 43882.6 34092.3 34967.5 35559.1 35589.1 36238.6 29302.4 32561.7 34831.1 34992.7 35783.8 30541.0 34478.0 36340.9 37194.4 37227.6 25359.7 31677.4 32508.0 32544.8 32572.7 18431.7 23657.7 24010.8 24535.0 24636.5 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:34532.43 Median:13420.92 stddev:99016.95 (737.8%) stddev-sans-first:99261.55 09:50:30 INFO - PROCESS | 1656 | Values: 399.0 399.6 400.2 401.2 411.6 11462.4 20276.7 20983.2 21038.0 21082.8 16512.4 16997.5 19986.6 20219.2 20884.7 5810.3 5959.9 6008.1 6021.4 6021.9 5890.4 5938.5 5944.4 5946.8 6001.8 5760.2 5903.0 5919.4 5937.3 5951.2 5892.9 5973.2 5973.3 5973.5 5975.0 5041.4 5209.9 5225.4 5228.2 5232.8 23051.5 24355.0 24968.0 25587.2 26359.5 2136.6 2175.0 2211.1 2277.8 2486.8 9036.0 10320.8 11226.4 12029.6 14790.4 1721.9 1726.3 1765.6 1874.3 1982.2 89736.0 118868.0 122571.4 124879.0 125839.0 11490.0 11515.2 11534.5 11581.4 11718.8 15085.5 15218.6 15231.0 15232.2 15232.5 7299.9 7514.8 8354.5 8717.7 8823.3 6916.5 7037.5 8490.0 8527.4 8542.4 4522.3 4530.4 4531.2 4544.8 4603.0 1903.7 1961.6 1964.4 1968.2 1969.1 716.7 734.9 767.0 782.8 872.6 18894.8 18926.8 19051.4 19563.3 19616.4 814.8 827.0 832.1 835.8 871.5 9916.2 10163.9 10246.9 10302.6 10387.7 575225.0 584916.0 589309.0 605971.0 606926.0 126239.0 131606.0 133324.0 133394.3 134488.3 997.2 1053.2 1069.8 1070.2 1072.0 18022.4 18304.4 18313.0 18365.9 18694.8 47395.5 47804.0 48092.8 48375.8 54289.0 69865.3 73149.6 73994.0 74720.3 77614.6 7459.2 7827.5 8058.0 8148.7 8551.1 9145.0 10217.8 11712.4 12634.7 12843.5 10632.9 10804.6 10890.3 10991.0 11039.0 10608.5 10761.7 10807.2 10902.5 10989.5 11195.7 11472.0 11525.4 11663.9 11664.9 8177.0 8247.2 8252.0 8262.0 8573.4 11146.9 11247.6 11248.7 11270.3 11344.5 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5588.88 Median:4911.12 stddev:3095.77 (63.0%) stddev-sans-first:3088.62 09:50:30 INFO - PROCESS | 1656 | Values: 1422.6 1441.0 1452.0 1594.0 1606.9 7625.5 8368.1 8642.7 8663.1 8664.5 8149.1 8481.6 8484.9 8487.4 8518.2 2131.7 2385.3 2414.0 2462.1 2463.4 2428.9 2429.2 2432.3 2435.4 2435.4 2416.4 2422.9 2451.2 2453.4 2454.5 2105.9 2350.6 2404.8 2430.1 2432.9 2428.9 2452.2 2457.3 2462.2 2462.8 7131.5 8225.3 8308.3 8389.5 8411.6 5076.1 5225.6 5233.3 5247.3 5257.7 3372.6 3393.8 3458.4 3606.4 3618.4 1033.3 1045.1 1048.6 1050.6 1051.8 11175.3 11368.7 11383.5 11385.0 11387.5 4785.2 4798.3 4874.1 4909.8 4921.7 8467.9 9946.0 9955.3 9956.7 9959.3 5421.6 5426.2 5427.8 5429.1 5431.2 3979.9 4343.7 4507.4 4912.5 4928.7 4012.3 4025.6 4095.5 4115.6 4141.3 1330.6 1349.3 1359.1 1366.8 1370.5 897.4 919.9 929.7 944.8 949.7 5560.0 5637.8 5732.2 5738.8 5851.2 463.4 465.8 470.7 1005.5 1013.3 3857.8 3972.4 3982.9 4004.6 4037.7 10120.1 10420.1 10591.4 10609.7 10625.6 10510.4 10601.7 10604.7 10613.2 10614.0 7864.2 7947.4 7979.9 8204.1 8217.3 5649.2 5800.4 5805.0 5816.8 5837.4 9420.8 9461.0 9776.9 9811.4 9929.0 9763.5 9798.9 9998.2 10004.9 10058.1 6794.3 6928.0 7026.9 7029.3 7045.6 8092.6 8100.8 8178.6 8206.1 8232.2 5986.6 7155.4 7217.9 7493.8 7516.3 7176.7 7209.7 7219.0 7296.9 7317.8 6899.8 6906.6 6935.1 6936.0 6952.1 4705.8 4839.2 4858.9 4948.2 5060.6 4326.4 4492.8 4839.3 4867.1 5077.0 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4564.47 Median:5199.20 stddev:1412.43 (27.2%) stddev-sans-first:1379.07 09:50:30 INFO - PROCESS | 1656 | Values: 267.3 313.5 314.5 319.9 325.2 5239.9 5334.8 5365.4 5376.7 5396.8 4136.5 4992.8 5182.9 5196.4 5267.7 3046.9 3208.6 3363.3 3840.9 4004.0 3461.6 4049.7 4084.8 4087.6 4137.6 4030.3 4031.8 4039.9 4042.5 4073.9 3849.6 3904.7 4084.0 4104.7 4126.0 3558.8 3587.9 3594.2 3604.6 3606.8 3810.7 3818.7 4901.0 4982.4 5153.8 4254.4 4303.4 4638.5 4648.0 4661.0 5127.9 5236.4 5455.9 5477.9 5496.2 3502.9 3558.9 3813.4 3829.2 3832.9 5576.8 5651.9 5692.1 5729.6 5784.4 3812.3 3818.3 3820.8 3821.4 3823.0 3891.8 3894.4 3894.6 3904.3 3927.0 4343.9 4480.4 4482.5 4483.6 4488.4 5052.4 5137.5 5482.8 5506.4 5510.8 1236.5 1428.2 1432.1 1432.4 1434.1 880.1 898.9 916.5 940.6 956.3 5105.3 5239.2 5253.6 5441.8 5457.7 5304.3 5399.9 5780.2 5787.0 5788.4 4449.9 5020.8 5202.0 5249.0 5253.8 2771.7 2851.2 3239.5 3258.6 3259.0 5739.9 6466.0 6466.8 6475.6 6499.9 5158.9 5810.3 5917.1 5963.6 5993.7 5821.2 5983.8 6006.8 6033.4 6061.6 5381.2 5428.2 5772.5 5773.0 5801.9 4836.7 5582.6 5693.6 5719.5 5720.6 5053.3 5702.9 6015.6 6016.5 6023.7 3878.5 3885.9 3913.1 3917.2 3928.8 5514.3 5527.5 5815.6 5818.8 5845.4 4764.3 4779.4 5549.9 5635.6 5686.9 4898.7 5593.3 5594.5 5605.7 5609.4 5285.8 5477.0 5521.2 5521.8 5522.4 5520.3 5652.3 5654.0 5666.7 5675.5 5492.9 5538.7 5550.6 5551.7 5553.2 09:50:30 INFO - PROCESS | 1656 | 09:50:30 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:17740.03 Median:30711.64 stddev:24739.88 (80.6%) stddev-sans-first:24776.03 09:50:30 INFO - PROCESS | 1656 | Values: 658.6 659.8 691.2 737.5 749.0 14685.9 14730.9 14826.3 15114.8 15136.9 13952.2 13969.6 14011.8 14011.9 14027.9 736.4 762.0 911.7 922.0 936.8 883.7 934.4 938.9 941.3 941.5 946.4 988.3 989.4 992.0 994.6 759.1 938.8 947.6 974.2 976.9 727.3 728.4 730.3 730.3 735.0 14917.4 14992.6 14997.9 15009.5 15011.2 12171.3 12221.7 12225.4 12505.8 12528.3 9830.4 10015.2 10206.6 10359.8 10755.8 3441.1 3515.5 3531.8 3555.8 3577.0 71544.0 73245.0 73814.6 74035.0 74041.5 9350.6 9386.9 9554.8 9579.7 9923.4 17838.0 20183.5 21849.5 21855.1 21880.3 7372.2 7387.5 7475.9 7600.0 7697.3 3986.3 4009.0 4024.5 4033.8 4045.8 3548.6 3637.6 3637.6 3660.8 3781.7 581.7 696.3 701.6 714.5 715.0 512.3 542.3 571.2 611.3 611.9 7910.2 8146.9 8324.8 8371.1 8407.4 569.2 596.9 601.3 601.9 631.0 3680.6 3925.4 3936.2 4019.7 4055.6 88535.0 89353.6 90364.1 96501.6 96856.0 67752.0 76662.6 78370.6 81155.8 83600.0 72688.6 73826.6 74151.0 74594.6 74921.5 7721.2 7760.7 7954.0 7967.8 8003.6 44349.0 44446.9 44485.6 44838.0 44902.0 45118.0 58342.5 58765.4 60812.0 61812.4 11005.7 11198.0 11231.4 11363.7 11632.7 11938.0 12019.8 12327.3 12339.9 12753.8 11467.9 11484.5 11839.3 11881.5 12091.0 11249.9 11257.9 11520.1 11656.7 11920.2 10803.8 11073.9 11478.3 11506.0 11575.2 9422.3 9525.7 9568.3 9745.3 9876.6 9604.8 9659.9 9695.7 9726.8 9733.3 09:50:30 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:50:30 INFO - PROCESS | 1656 | 09:50:31 INFO - TEST-INFO | 1656: exit 0 09:50:31 INFO - TEST-OK | dromaeo_css | took 1116292ms 09:50:31 INFO - TEST-START | kraken 09:50:31 INFO - operating with platform_type : mac_ 09:50:31 INFO - Initialising browser for kraken test... 09:50:31 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpqMgEnF/profile http://localhost:49283/getInfo.html) 09:50:33 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 09:50:33 INFO - PROCESS | 1665 | colorDepth:24 09:50:33 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 09:50:33 INFO - PROCESS | 1665 | __metrics 09:50:33 INFO - PROCESS | 1665 | 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" 09:50:33 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:50:34 INFO - TEST-INFO | 1665: exit 0 09:50:34 INFO - Browser initialized. 09:50:34 INFO - Running cycle 1/1 for kraken test... 09:50:34 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpqMgEnF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:46 INFO - PROCESS | 1667 | RSS: Main: 239587328 09:50:46 INFO - PROCESS | 1667 | 09:50:48 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:49 INFO - PROCESS | 1667 | RSS: Main: 287010816 09:50:49 INFO - PROCESS | 1667 | 09:50:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 09:50:53 INFO - PROCESS | 1667 | RSS: Main: 297218048 09:50:53 INFO - PROCESS | 1667 | 09:50:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 09:50:57 INFO - PROCESS | 1667 | RSS: Main: 383336448 09:50:57 INFO - PROCESS | 1667 | 09:50:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 09:50:59 INFO - PROCESS | 1667 | RSS: Main: 282247168 09:50:59 INFO - PROCESS | 1667 | 09:51:02 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:02 INFO - PROCESS | 1667 | RSS: Main: 286621696 09:51:02 INFO - PROCESS | 1667 | 09:51:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:05 INFO - PROCESS | 1667 | RSS: Main: 324825088 09:51:05 INFO - PROCESS | 1667 | 09:51:08 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:09 INFO - PROCESS | 1667 | RSS: Main: 323166208 09:51:09 INFO - PROCESS | 1667 | 09:51:12 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 09:51:12 INFO - PROCESS | 1667 | RSS: Main: 322760704 09:51:12 INFO - PROCESS | 1667 | 09:51:14 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:51:14 INFO - PROCESS | 1667 | RSS: Main: 314564608 09:51:14 INFO - PROCESS | 1667 | 09:51:16 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:51:17 INFO - PROCESS | 1667 | RSS: Main: 321261568 09:51:17 INFO - PROCESS | 1667 | 09:51:19 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:51:20 INFO - PROCESS | 1667 | RSS: Main: 323104768 09:51:20 INFO - PROCESS | 1667 | 09:51:23 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:51:23 INFO - PROCESS | 1667 | RSS: Main: 320253952 09:51:23 INFO - PROCESS | 1667 | 09:51:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:27 INFO - PROCESS | 1667 | RSS: Main: 321236992 09:51:27 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 09:51:29 INFO - PROCESS | 1667 | RSS: Main: 386154496 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | __start_tp_report 09:51:29 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 09:51:29 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 09:51:29 INFO - PROCESS | 1667 | |i|pagename|runs| 09:51:29 INFO - PROCESS | 1667 | |0;ai-astar;113;113;109;113;113;114;113;113;104;113 09:51:29 INFO - PROCESS | 1667 | |1;audio-beat-detection;157;124;144;178;148;146;140;123;172;119 09:51:29 INFO - PROCESS | 1667 | |2;audio-dft;187;188;187;186;187;183;180;187;184;185 09:51:29 INFO - PROCESS | 1667 | |3;audio-fft;77;71;92;91;57;60;58;82;91;74 09:51:29 INFO - PROCESS | 1667 | |4;audio-oscillator;90;98;137;78;81;81;151;119;112;126 09:51:29 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;87;84;87;85;87;84;87;84;87;84 09:51:29 INFO - PROCESS | 1667 | |6;imaging-darkroom;93;93;93;93;93;96;92;92;92;93 09:51:29 INFO - PROCESS | 1667 | |7;imaging-desaturate;84;81;88;84;85;83;85;86;86;86 09:51:29 INFO - PROCESS | 1667 | |8;json-parse-financial;57;61;60;60;58;58;58;78;60;57 09:51:29 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;65;63;74;75;70;67;66;67;67;70 09:51:29 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;83;83;82;88;92;107;79;82;79;98 09:51:29 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;145;151;152;144;131;135;135;134;194;137 09:51:29 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;172;175;168;174;172;168;166;170;170;170 09:51:29 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;67;68;69;67;69;69;66;69;65;67 09:51:29 INFO - PROCESS | 1667 | __end_tp_report 09:51:29 INFO - PROCESS | 1667 | __start_cc_report 09:51:29 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,2071 09:51:29 INFO - PROCESS | 1667 | __end_cc_report 09:51:29 INFO - PROCESS | 1667 | __startTimestamp1461862289348__endTimestamp 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | ------- Summary: start ------- 09:51:29 INFO - PROCESS | 1667 | Number of tests: 14 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.05 (2.7%) stddev-sans-first:3.20 09:51:29 INFO - PROCESS | 1667 | Values: 113.0 113.0 109.0 113.0 113.0 114.0 113.0 113.0 104.0 113.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:147.00 stddev:20.00 (13.6%) stddev-sans-first:20.74 09:51:29 INFO - PROCESS | 1667 | Values: 157.0 124.0 144.0 178.0 148.0 146.0 140.0 123.0 172.0 119.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:185.40 Median:187.00 stddev:2.46 (1.3%) stddev-sans-first:2.54 09:51:29 INFO - PROCESS | 1667 | Values: 187.0 188.0 187.0 186.0 187.0 183.0 180.0 187.0 184.0 185.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:75.30 Median:79.50 stddev:13.78 (17.3%) stddev-sans-first:14.60 09:51:29 INFO - PROCESS | 1667 | Values: 77.0 71.0 92.0 91.0 57.0 60.0 58.0 82.0 91.0 74.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:107.30 Median:79.50 stddev:25.66 (32.3%) stddev-sans-first:26.45 09:51:29 INFO - PROCESS | 1667 | Values: 90.0 98.0 137.0 78.0 81.0 81.0 151.0 119.0 112.0 126.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:87.00 stddev:1.51 (1.7%) stddev-sans-first:1.51 09:51:29 INFO - PROCESS | 1667 | Values: 87.0 84.0 87.0 85.0 87.0 84.0 87.0 84.0 87.0 84.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:1.15 (1.2%) stddev-sans-first:1.22 09:51:29 INFO - PROCESS | 1667 | Values: 93.0 93.0 93.0 93.0 93.0 96.0 92.0 92.0 92.0 93.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.93 (2.3%) stddev-sans-first:2.03 09:51:29 INFO - PROCESS | 1667 | Values: 84.0 81.0 88.0 84.0 85.0 83.0 85.0 86.0 86.0 86.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:60.00 stddev:6.24 (10.4%) stddev-sans-first:6.47 09:51:29 INFO - PROCESS | 1667 | Values: 57.0 61.0 60.0 60.0 58.0 58.0 58.0 78.0 60.0 57.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:3.84 (5.6%) stddev-sans-first:3.87 09:51:29 INFO - PROCESS | 1667 | Values: 65.0 63.0 74.0 75.0 70.0 67.0 66.0 67.0 67.0 70.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:83.00 stddev:9.17 (11.0%) stddev-sans-first:9.59 09:51:29 INFO - PROCESS | 1667 | Values: 83.0 83.0 82.0 88.0 92.0 107.0 79.0 82.0 79.0 98.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:144.50 stddev:18.44 (12.8%) stddev-sans-first:19.56 09:51:29 INFO - PROCESS | 1667 | Values: 145.0 151.0 152.0 144.0 131.0 135.0 135.0 134.0 194.0 137.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:171.00 stddev:2.80 (1.6%) stddev-sans-first:2.92 09:51:29 INFO - PROCESS | 1667 | Values: 172.0 175.0 168.0 174.0 172.0 168.0 166.0 170.0 170.0 170.0 09:51:29 INFO - PROCESS | 1667 | 09:51:29 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.50 stddev:1.43 (2.1%) stddev-sans-first:1.50 09:51:29 INFO - PROCESS | 1667 | Values: 67.0 68.0 69.0 67.0 69.0 69.0 66.0 69.0 65.0 67.0 09:51:29 INFO - PROCESS | 1667 | -------- Summary: end -------- 09:51:29 INFO - PROCESS | 1667 | 09:51:30 INFO - TEST-INFO | 1667: exit 0 09:51:30 INFO - TEST-OK | kraken | took 58851ms 09:51:30 INFO - SUITE-END | took 1175s 09:51:30 INFO - Completed test suite (00:19:35) 09:51:30 INFO - javascript benchmark 09:51:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.8911888083912, 3190.776645634806, 3367.175042114684, 3408.4205685033544, 3435.433153529668, 5439.4832490913395, 5520.47555482229, 5523.889522209553, 5545.528630066439, 5573.08044172712, 5546.223528705985, 5570.025245582027, 5664.433556644344, 5687.0, 5690.9146362804595, 5030.522100400474, 5051.595872330205, 5064.215046667772, 5075.010372977271, 5267.920981185266, 5181.404138524081, 5204.193350030751, 5212.817551385702, 5212.817551385702, 5241.449647786984, 5023.372078490189, 5108.795648174068, 5146.305248791421, 5149.639525233235, 5151.484851514856, 5066.46802085783, 5085.084684756743, 5093.134167191586, 5094.592432605383, 5114.872128196787, 5010.3737032870895, 5040.546350828443, 5053.2925390445, 5062.54437100659, 5067.847964561032, 5385.192221166817, 5442.6462279952075, 5446.645968012106, 5567.220589117527, 5578.163275508666, 6052.5157987361345, 6085.513158947274, 6087.3303936566945, 6092.299385570627, 6109.877802443926, 6072.423119803656, 6123.969380153071, 6153.692315384213, 6162.0, 6170.444659980579, 5511.58663100265, 5561.4716601922755, 5576.470235327637, 5619.662820230799, 5654.462826031561, 6203.7828675996125, 6244.562880598405, 6262.812115636538, 6275.96862015687, 6290.433860952537, 5912.970435147797, 5929.0513517836935, 5944.524438044946, 5947.613405128653, 5964.642121472725, 5932.58471906971, 6056.3035250946705, 6085.269767627913, 6154.692265386713, 6161.322254551996, 6003.879922401527, 6043.667598282137, 6054.212952316171, 6056.485198758155, 6074.0281554951, 5333.5199832015305, 6062.727177277033, 6076.3316035236085, 6081.0, 6083.39166083397, 4093.692973027035, 4587.03672228829, 4788.11419887322, 4789.664723469324, 4808.2547205183255, 3723.1995121049104, 4380.802863871133, 4386.8025938832825, 4404.823807047682, 4409.867703968822, 5469.617126801165, 5476.288082549323, 5489.780408783684, 5498.670079795225, 5513.035218836688, 6056.182415373868, 6067.544934129958, 6077.1491991121275, 6085.9695701521205, 6133.723982420713, 4684.8360307389075, 5505.587080968943, 5539.5014448699, 5632.04255276604, 5638.238837756932, 5288.497592728685, 5320.015797077553, 5320.813771518056, 5323.574114070865, 5334.59990500714, 6233.812985610439, 6234.875302493925, 6242.843928901723, 6245.344238854946, 6334.176557047738, 5942.940570594412, 6085.756569737338, 6093.2078829751845, 6099.878002440015, 6153.907691384722, 6159.9528080227365, 6192.876142477216, 6194.845128871814, 6201.162843016135, 6215.502759779117, 5397.70312632809, 5917.526597872074, 5919.704014799329, 5956.493698035715, 6075.422834830684, 6137.0, 6137.8979746733885, 6154.661493617894, 6160.907586386297, 6166.0, 5496.900619875961, 5919.230500034968, 5928.110783382354, 5940.524758019435, 5960.195373624679, 5679.943200567942, 5697.4302569742695, 5817.243758311392, 5833.154192642128, 5896.469317761422, 5700.3444603871085, 5703.971480142573, 5704.3725190228215, 5707.343655479673, 5723.513501352431, 5791.8552036199435, 5795.072788353843, 5830.708464576839, 5837.29952405714, 5841.678707671119, 5704.828855134353, 5708.086706127, 5715.628484148517, 5715.942840571541, 5722.0272553665145], "value": 5538.5894138477015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.72943475240754, 517.7644171901784, 538.6175815171229, 549.8570371703356, 557.3813067495079, 36774.632253677424, 37055.25889482209, 38168.618313816856, 39510.40734388983, 39873.60126398735, 41734.79132604333, 42255.366169507404, 42280.78859605698, 42320.78839605805, 42437.0, 8428.199321064489, 8603.56982150893, 9354.906450935472, 9615.56729947154, 9618.182454491376, 8626.396152269344, 8788.077251888555, 8791.296696264284, 10960.616378426757, 11252.887471125267, 11423.485943132537, 11630.244034137797, 11644.359560224226, 11652.009579185737, 11664.300141991507, 11527.077833773319, 11606.187566870323, 11643.650690479299, 11680.94159529197, 11691.883081169164, 8434.62044208012, 11079.446027698583, 11281.887181128166, 11311.773764524663, 11342.659720208325, 38756.03109922246, 39324.0, 39325.0, 39447.60552394468, 39477.40783888244, 3231.9515207271675, 3308.17295676081, 3378.189234583707, 3475.4265546184847, 3555.466679998021, 28379.716202837913, 28777.42445151085, 28849.71150288491, 28887.71112288871, 28972.710272897213, 2710.205056263608, 2711.0917842522776, 2735.3298441881666, 2744.3276397282593, 2812.3953350029637, 374440.0, 374606.12696936616, 374608.0, 392793.0, 490649.0, 34107.97676069722, 55728.72135639296, 64402.6779866107, 64494.67752661207, 64546.031809522894, 35712.642873570934, 35869.0, 35882.28235435329, 36011.09972250715, 36037.09907252339, 15022.774658380133, 15456.768148477782, 15464.613384665248, 15468.381264749509, 15468.845311546966, 11865.406729663482, 13016.609501714964, 13100.541481048118, 13110.213387196798, 13115.540956066492, 7765.145833958373, 7994.840103197904, 8090.4336696431865, 8142.185781421929, 8192.426530142833, 3331.68349006844, 3413.9317213655586, 3425.451927691607, 3426.8971930842113, 3455.7235421166247, 933.7338858425317, 1007.7631756537207, 1030.8866024737272, 1057.9100776434088, 1086.739182596187, 28275.0, 28452.0, 28796.712032879404, 29058.564121538195, 29952.251193719894, 1065.3181963543338, 1110.5169251375678, 1113.2374323588367, 1139.2310190621297, 1183.8875306846007, 12518.81221781674, 12674.493020279086, 13153.539626113232, 14806.333714982851, 15195.772063419057, 610249.0, 615213.0, 621682.0, 622486.0, 624597.0, 60174.0, 61486.69256653688, 61491.6925415379, 61607.691961540804, 61635.0, 1332.513632524141, 1367.0225788561027, 1454.803601513782, 1499.5426394949645, 1502.0612117426608, 28480.715192848635, 28649.14052578426, 29036.709632904247, 29143.85428072846, 29220.7077929218, 47783.0, 47885.28172077352, 48297.5170248293, 48347.0, 48433.78915526999, 367451.0, 368263.0, 369300.1534992308, 370088.0, 371699.0, 22373.0, 25377.111801087613, 25664.23007309784, 25845.612315814902, 26080.217593472225, 40494.0, 41177.1764564701, 43063.25529453939, 43279.567204328814, 43882.56117438913, 34092.31815363629, 34967.475487868214, 35559.11102222362, 35589.1102722434, 36238.63761362458, 29302.41395172127, 32561.674383256814, 34831.12922176966, 34992.65007349894, 35783.82108089443, 30541.0, 34478.0, 36340.87702914762, 37194.442083369315, 37227.62772372241, 25359.746402535737, 31677.366452670358, 32508.02475925726, 32544.83727581347, 32572.697092117, 18431.72352414688, 23657.698826564018, 24010.75989240155, 24535.0, 24636.50726985486], "value": 18139.548893676918, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.95212574491063, 399.6143721308936, 400.2255635345605, 401.2216300377266, 411.6171960077127, 11462.426878656055, 20276.69584956225, 20983.16067357304, 21038.0, 21082.789172108292, 16512.422065227718, 16997.49007529773, 19986.60026799467, 20219.191232350688, 20884.686729699028, 5810.331811841639, 5959.851003724898, 6008.098785182214, 6021.397860213988, 6021.909671354933, 5890.381509941459, 5938.46553810157, 5944.405559444042, 5946.762129514815, 6001.849953751147, 5760.193572899798, 5903.0260007099, 5919.437653422918, 5937.317208521033, 5951.226340575719, 5892.852678683046, 5973.163757074013, 5973.342932277446, 5973.462388385023, 5974.97012514939, 5041.420236672756, 5209.869753256161, 5225.42520322768, 5228.215767634847, 5232.843014709565, 23051.538969220688, 24355.0, 24968.0, 25587.23238302854, 26359.472810543873, 2136.604728125302, 2174.9565008699897, 2211.1266049910196, 2277.772222777709, 2486.8383555069045, 9036.0, 10320.79358412835, 11226.438678066064, 12029.639110826689, 14790.408383664748, 1721.8708596855286, 1726.3094762095136, 1765.5850875044355, 1874.3439796071402, 1982.246746236421, 89736.0, 118868.0, 122571.38714306371, 124878.9999999991, 125839.0, 11490.0, 11515.193936424537, 11534.538618455335, 11581.42092895352, 11718.765624687458, 15085.47200847965, 15218.619534511727, 15231.0, 15232.238388080552, 15232.543023709306, 7299.854002919912, 7514.752080676135, 8354.45696029756, 8717.738467845975, 8823.250023748053, 6916.515843890879, 7037.472189585802, 8490.023647280641, 8527.403081784216, 8542.359323050796, 4522.34426008227, 4530.365748795171, 4531.235946072893, 4544.7500387479, 4602.953970460252, 1903.6573416784834, 1961.6272908147691, 1964.430315208602, 1968.2187620203738, 1969.0745349685624, 716.691822516323, 734.8934404511423, 767.0, 782.8160382310152, 872.6029656506274, 18894.81105188958, 18926.81073189278, 19051.428457146307, 19563.320519149012, 19616.411507654793, 814.8248126652679, 826.9586520673943, 832.0888626953476, 835.8286551256978, 871.4509858788922, 9916.206703463706, 10163.898361016443, 10246.924072972231, 10302.587896484207, 10387.740306492398, 575225.0, 584916.0, 589309.0, 605971.0, 606926.0, 126239.0, 131606.0, 133324.0, 133394.33302833425, 134488.32755836158, 997.1922742578535, 1053.1785207538285, 1069.8341757027674, 1070.2080460459204, 1072.0137473524376, 18022.36921707734, 18304.359347422775, 18313.0, 18365.90817045906, 18694.813051869307, 47395.52604473911, 47804.0, 48092.759536202095, 48375.75812120917, 54289.0, 69865.30134698791, 73149.63425182841, 73994.0, 74720.25279747133, 77614.61192694001, 7459.216782238004, 7827.530348179127, 8057.959710201411, 8148.674053037812, 8551.1448855114, 9145.0, 10217.795644087226, 11712.355820429617, 12634.747305054032, 12843.486260549473, 10632.946835265775, 10804.621838235624, 10890.346579204956, 10991.0, 11039.006489415991, 10608.52261648243, 10761.730956726144, 10807.189460790629, 10902.509387077442, 10989.450527473435, 11195.72010699739, 11472.0, 11525.366104864313, 11663.88336116662, 11664.94167529157, 8176.959115204386, 8247.175282471704, 8252.0, 8262.049864265686, 8573.399862009599, 11146.88853111491, 11247.550097995989, 11248.662540123809, 11270.267432616854, 11344.546218151512], "value": 9385.899694615391, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.6158937086993, 1440.992795036024, 1451.9637009074772, 1593.99203003985, 1606.879484038697, 7625.542467451956, 8368.121347258542, 8642.654293828255, 8663.133686631334, 8664.523451210182, 8149.103598604163, 8481.618327175276, 8484.91515084849, 8487.448315859465, 8518.23335899769, 2131.7015617813518, 2385.272491889973, 2413.963790543143, 2462.076721229539, 2463.4210960424284, 2428.9271321860374, 2429.186222615417, 2432.3189506937997, 2435.366804630791, 2435.4398488347692, 2416.395901024744, 2422.878856057199, 2451.154351748644, 2453.41118131648, 2454.460018795862, 2105.884176370299, 2350.612148995414, 2404.7955923746504, 2430.0644251962945, 2432.927012189637, 2428.91498797543, 2452.2152911068465, 2457.336519139822, 2462.187478132212, 2462.8152888533255, 7131.536450130777, 8225.259726624572, 8308.252257296812, 8389.538575378352, 8411.621477033548, 5076.060928728197, 5225.581953443715, 5233.319668443116, 5247.291615631879, 5257.7108259045735, 3372.6121496028027, 3393.7794043387103, 3458.412069948115, 3606.441001644749, 3618.3848745713294, 1033.34382667007, 1045.0646671229283, 1048.5700862646354, 1050.6217761605815, 1051.7580956380075, 11175.32948023113, 11368.658940231806, 11383.544658213661, 11385.0, 11387.54449822006, 4785.186518291829, 4798.296415706399, 4874.122657921537, 4909.7790599423115, 4921.729304888194, 8467.915320846712, 9945.950270248748, 9955.303128780915, 9956.701298961043, 9959.253056020827, 5421.617487540652, 5426.186072089218, 5427.810026649127, 5429.131338985743, 5431.158170483582, 3979.880603581897, 4343.739375637472, 4507.3689683444345, 4912.459629440758, 4928.704277743347, 4012.25773231953, 4025.5571887092983, 4095.52901416335, 4115.588441155861, 4141.295979683461, 1330.6340756292097, 1349.257908150533, 1359.0690377091723, 1366.760816857066, 1370.465518447806, 897.3718397122041, 919.9172074513192, 929.665320484625, 944.7826999790087, 949.7435692363161, 5559.972200138974, 5637.802676906289, 5732.226149469851, 5738.799142030009, 5851.21008663825, 463.39295522865154, 465.75082330953455, 470.7222738584252, 1005.4671024356957, 1013.3413281367023, 3857.8071096445065, 3972.4041393791163, 3982.9246103551877, 4004.599540045972, 4037.7375470594316, 10120.089191972611, 10420.114290285259, 10591.364518128938, 10609.73475663099, 10625.574977000988, 10510.369377837354, 10601.681949541526, 10604.734881628021, 10613.150947924147, 10614.0447359738, 7864.174261702439, 7947.3642108631175, 7979.938424868339, 8204.056533498724, 8217.260446559838, 5649.152627105967, 5800.4489573490455, 5804.970975145097, 5816.767329306949, 5837.3870743571315, 9420.811583768424, 9461.0, 9776.9022309776, 9811.411315321104, 9928.950355248178, 9763.511824408894, 9798.902010980084, 9998.150157236716, 10004.899951000689, 10058.14505767018, 6794.252632210551, 6927.965360173167, 7026.929730702627, 7029.26192749754, 7045.577265364094, 8092.5549094798, 8100.756977290691, 8178.550179740171, 8206.097329293892, 8232.17678232196, 5986.551008674454, 7155.356017958408, 7217.891731624135, 7493.812654683458, 7516.285952834472, 7176.7488137914925, 7209.747658831916, 7219.0, 7296.854062918605, 7317.817054573678, 6899.75850845218, 6906.551074180162, 6935.098437203132, 6936.0, 6952.130983627047, 4705.788239529161, 4839.1531481991215, 4858.878528036686, 4948.233023881304, 5060.569851562511, 4326.437563116775, 4492.797824097858, 4839.274108883696, 4867.075255701406, 5076.959223359277], "value": 4496.230010164101, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.33033750455115, 313.4514599450961, 314.4873855615348, 319.8512691598408, 325.23894087834464, 5239.8952020959605, 5334.759401051558, 5365.356157261124, 5376.666466790591, 5396.811111611094, 4136.503619565657, 4992.7753251103695, 5182.896342073156, 5196.428392876781, 5267.736613169345, 3046.923826904334, 3208.5508028875915, 3363.3441478911623, 3840.8847734568008, 4003.97998010011, 3461.584609846822, 4049.6962727795385, 4084.775337356444, 4087.57080506547, 4137.586241375853, 4030.2544029354663, 4031.798410079499, 4039.858604948827, 4042.535108462535, 4073.9388909166387, 3849.5573009104032, 3904.746191497558, 4083.97958010211, 4104.712670113093, 4126.0, 3558.839852206657, 3587.856485740567, 3594.2272411431404, 3604.567451905762, 3606.801625910574, 3810.6951443884423, 3818.713596480275, 4900.970796132817, 4982.427020892607, 5153.845384638467, 4254.3831144483875, 4303.397524346594, 4638.512956139591, 4648.047150334173, 4660.976695116503, 5127.871803204913, 5236.360729293143, 5455.918161227585, 5477.890442191134, 5496.230527726121, 3502.9474557881654, 3558.8967420099852, 3813.389857622767, 3829.15611751135, 3832.885013449601, 5576.800987787605, 5651.8587035324035, 5692.0608099663505, 5729.598928075036, 5784.392638772911, 3812.275667623143, 3818.3127037133027, 3820.7707537547835, 3821.3694740367528, 3823.0, 3891.774091161275, 3894.3963685628773, 3894.5521265054313, 3904.2581909437117, 3926.9803650982135, 4343.891402714958, 4480.439945006874, 4482.5069242383315, 4483.618892394118, 4488.386848586853, 5052.418971818288, 5137.486251374908, 5482.835514934558, 5506.394296627368, 5510.8071217507195, 1236.511577926732, 1428.171660436944, 1432.1192466633154, 1432.3983926750789, 1434.0606902478894, 880.1022956584249, 898.8966268879032, 916.5289710015462, 940.5720397219249, 956.3449037409397, 5105.285260063594, 5239.161734122522, 5253.632245742838, 5441.755121019563, 5457.690154362925, 5304.340005396264, 5399.865003374868, 5780.219670344533, 5786.971065144731, 5788.363280039192, 4449.911001779947, 5020.799168033311, 5201.973990130101, 5249.036977082278, 5253.765365139189, 2771.68125665547, 2851.2159156231787, 3239.49787782894, 3258.6415494295607, 3258.989713188919, 5739.85650358736, 6465.967670161714, 6466.771313450429, 6475.579087359307, 6499.8625240582705, 5158.871028224325, 5810.273715785527, 5917.142014407887, 5963.5527335450015, 5993.70031498432, 5821.243238378984, 5983.760649574056, 6006.759729610767, 6033.42682445167, 6061.575689701678, 5381.2197231402015, 5428.212909128233, 5772.480476757112, 5772.9711351442975, 5801.94198058014, 4836.685615434985, 5582.5533957282505, 5693.629914055573, 5719.5138413235345, 5720.628159169641, 5053.29253904461, 5702.885942281214, 6015.600811002404, 6016.4585187331595, 6023.728932197974, 3878.5261600591593, 3885.9417108743955, 3913.119548101768, 3917.236138952877, 3928.8035598219403, 5514.338279406497, 5527.53015993645, 5815.575184079928, 5818.8254352369495, 5845.444682755132, 4764.2615394613895, 4779.354787103696, 5549.861253468695, 5635.605507614428, 5686.857828554319, 4898.706077635354, 5593.272874526286, 5594.5384031026215, 5605.747741351567, 5609.354924183771, 5285.7621407036, 5476.959377718221, 5521.199426083276, 5521.82495065331, 5522.392536820867, 5520.282363292747, 5652.293463317085, 5654.0, 5666.744996475086, 5675.545956323567, 5492.890142197054, 5538.667679939216, 5550.58370622213, 5551.666899985852, 5553.167024946129], "value": 4119.303492866655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.6311665467342, 659.8383396067964, 691.1562737058171, 737.5464089584907, 748.9739057491238, 14685.853141468582, 14730.926345368272, 14826.258687065658, 15114.848851511482, 15136.924315378421, 13952.2326272055, 13969.58091257264, 14011.789823152636, 14011.929940350285, 14027.929860350709, 736.3593673504059, 762.0474406991261, 911.680911680913, 922.0088404964656, 936.7751739582519, 883.7039591736776, 934.3693007220099, 938.9201917836992, 941.2893265584509, 941.5245301122924, 946.3895787217275, 988.2686811759282, 989.4459102902362, 991.9553620087078, 994.5922171909518, 759.1232126893447, 938.7746940734174, 947.6304241345846, 974.1816873825985, 976.8778902637171, 727.287258486686, 728.4281838756604, 730.3244498838553, 730.3280981496982, 735.0260904301822, 14917.403303867832, 14992.625184370369, 14997.850021499755, 15009.474668386663, 15011.174385408907, 12171.33057681827, 12221.694457638541, 12225.449854756469, 12505.812412813815, 12528.310942898137, 9830.410175389428, 10015.198784097254, 10206.642767503175, 10359.844602330972, 10755.83866242007, 3441.0709108540805, 3515.4551044587874, 3531.8057506837113, 3555.7866528008144, 3576.998440436682, 71544.0, 73245.0, 73814.63092684503, 74035.0, 74041.51916961685, 9350.625974961062, 9386.90613093874, 9554.760157548837, 9579.712608621752, 9923.354981926295, 17838.0, 20183.49541261451, 21849.453763655718, 21855.125794968022, 21880.343589692333, 7372.225916278713, 7387.519811212254, 7475.8504829903095, 7600.011998440278, 7697.268759467954, 3986.3223252047223, 4008.959910400917, 4024.5170579530645, 4033.8386464541677, 4045.7977101144825, 3548.591911930161, 3637.5816781069984, 3637.618050104754, 3660.7803531788177, 3781.716371272166, 581.6597290584971, 696.3036963036963, 701.5895701014862, 714.4820005495974, 714.9668728686997, 512.344199424737, 542.2868927360508, 571.1917636544243, 611.2847967877594, 611.8806832667676, 7910.2089791020435, 8146.877796833052, 8324.807549043953, 8371.079181290053, 8407.411481196257, 569.1604882797923, 596.8776400837818, 601.287474342905, 601.8946684330313, 630.9684515774193, 3680.5767336756617, 3925.430812532167, 3936.173403585222, 4019.718619696652, 4055.5538890721996, 88535.0, 89353.55323223473, 90364.09635903688, 96501.55247671291, 96856.0, 67751.98372024423, 76662.61668691621, 78370.6081469589, 81155.78266326009, 83600.0, 72688.63655681793, 73826.63086684533, 74151.0, 74594.62702686452, 74921.5015699683, 7721.227877212458, 7760.650770715219, 7954.0, 7967.800804979922, 8003.559804210824, 44349.0, 44446.88849784019, 44485.55514444814, 44838.0, 44902.0, 45118.0, 58342.54143646273, 58765.4123458777, 60812.0, 61812.38187618246, 11005.669829905115, 11198.0, 11231.360031918737, 11363.715907102389, 11632.651020469399, 11938.0, 12019.819702704292, 12327.321997290235, 12339.876601234231, 12753.808692869428, 11467.942660286646, 11484.483198255932, 11839.348835814113, 11881.524739010343, 12091.032717382765, 11249.887501125211, 11257.887421126012, 11520.078393728314, 11656.650300491, 11920.225185362924, 10803.837942430713, 11073.944630276797, 11478.253913495597, 11505.964463198352, 11575.189736718348, 9422.340436169403, 9525.714228573153, 9568.330216884751, 9745.31782775199, 9876.555554999899, 9604.75988100303, 9659.855102173333, 9695.66065187715, 9726.75683107928, 9733.270004749531], "value": 6861.88623742299, "unit": "score"}], "name": "dromaeo_css", "value": 7021.717749106918, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 109.0, 113.0, 113.0, 114.0, 113.0, 113.0, 104.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 144.0, 178.0, 148.0, 146.0, 140.0, 123.0, 172.0, 119.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 187.0, 186.0, 187.0, 183.0, 180.0, 187.0, 184.0, 185.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 92.0, 91.0, 57.0, 60.0, 58.0, 82.0, 91.0, 74.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 137.0, 78.0, 81.0, 81.0, 151.0, 119.0, 112.0, 126.0], "value": 107.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 96.0, 92.0, 92.0, 92.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 88.0, 84.0, 85.0, 83.0, 85.0, 86.0, 86.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 60.0, 60.0, 58.0, 58.0, 58.0, 78.0, 60.0, 57.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 74.0, 75.0, 70.0, 67.0, 66.0, 67.0, 67.0, 70.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 88.0, 92.0, 107.0, 79.0, 82.0, 79.0, 98.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 151.0, 152.0, 144.0, 131.0, 135.0, 135.0, 134.0, 194.0, 137.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 168.0, 174.0, 172.0, 168.0, 166.0, 170.0, 170.0, 170.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 67.0, 69.0, 69.0, 66.0, 69.0, 65.0, 67.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1488.5999999999997, "alertThreshold": 2.0}]} 09:51:30 INFO - Return code: 0 09:51:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:51:30 ERROR - # TBPL SUCCESS # 09:51:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:51:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:51:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:51:30 INFO - 2016-04-28 09:51:30.678 screenresolution[1671:10059] starting screenresolution argv=screenresolution get 09:51:30 INFO - 2016-04-28 09:51:30.681 screenresolution[1671:10059] Display 0: 1600x1200x32@60 09:51:30 INFO - 2016-04-28 09:51:30.687 screenresolution[1672:10062] starting screenresolution argv=screenresolution list 09:51:30 INFO - Available Modes on Display 0 09:51:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:51:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:51:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:51:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:51:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:51:30 INFO - Intel Iris: 09:51:30 INFO - Chipset Model: Intel Iris 09:51:30 INFO - Type: GPU 09:51:30 INFO - Bus: Built-In 09:51:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:51:30 INFO - Vendor: Intel (0x8086) 09:51:30 INFO - Device ID: 0x0a2e 09:51:30 INFO - Revision ID: 0x0009 09:51:30 INFO - Displays: 09:51:30 INFO - Display: 09:51:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:51:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:51:30 INFO - Display Serial Number: 1600x1200 60 09:51:30 INFO - Main Display: Yes 09:51:30 INFO - Mirror: Off 09:51:30 INFO - Online: Yes 09:51:30 INFO - Rotation: Supported 09:51:30 INFO - Return code: 0 09:51:30 INFO - Running post-action listener: _resource_record_post_action 09:51:30 INFO - Running post-run listener: _resource_record_post_run 09:51:31 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37778944; Write bytes: 695477248; Read time: 394; Write time: 2122 09:51:31 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396898816; Write bytes: 398108160; Read time: 30256; Write time: 785 09:51:31 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 35997184; Write bytes: 296521216; Read time: 376; Write time: 1336 09:51:31 INFO - Running post-run listener: _upload_blobber_files 09:51:31 INFO - Blob upload gear active. 09:51:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:51:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:51:32 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:51:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:51:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:33 INFO - (blobuploader) - INFO - Done attempting. 09:51:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:51:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:51:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:34 INFO - (blobuploader) - INFO - Done attempting. 09:51:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:34 INFO - Return code: 0 09:51:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b297ea271570051d0e35ae1b5a507e4c9ba6b537bc82240e77112e49d7586654f629eb7e147073928668002dc24341f815d350ba4d00c9bd700746f6739fb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57db80d87ff9fabec2100ac05d950d8ff103ecfdbb22b9aa7dd2bdccdc3cbb2002c78c72df6259cbff1f9bb4f0628622261811be73ba5129c43458e103ba593"} 09:51:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:34 INFO - Contents: 09:51:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b297ea271570051d0e35ae1b5a507e4c9ba6b537bc82240e77112e49d7586654f629eb7e147073928668002dc24341f815d350ba4d00c9bd700746f6739fb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57db80d87ff9fabec2100ac05d950d8ff103ecfdbb22b9aa7dd2bdccdc3cbb2002c78c72df6259cbff1f9bb4f0628622261811be73ba5129c43458e103ba593"} 09:51:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:34 INFO - Copying logs to upload dir... 09:51:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.571919 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-28 09:51:34.198855) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:51:34.202611) ========= 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.I6jwSljNRp/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.bYHUrVz6z2/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b297ea271570051d0e35ae1b5a507e4c9ba6b537bc82240e77112e49d7586654f629eb7e147073928668002dc24341f815d350ba4d00c9bd700746f6739fb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57db80d87ff9fabec2100ac05d950d8ff103ecfdbb22b9aa7dd2bdccdc3cbb2002c78c72df6259cbff1f9bb4f0628622261811be73ba5129c43458e103ba593"} build_url:https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011535 build_url: 'https://queue.taskcluster.net/v1/task/a7ckXhheTNOSMDXpvJvPMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b297ea271570051d0e35ae1b5a507e4c9ba6b537bc82240e77112e49d7586654f629eb7e147073928668002dc24341f815d350ba4d00c9bd700746f6739fb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57db80d87ff9fabec2100ac05d950d8ff103ecfdbb22b9aa7dd2bdccdc3cbb2002c78c72df6259cbff1f9bb4f0628622261811be73ba5129c43458e103ba593"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:51:34.230732) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:51:34.231053) ========= 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.I6jwSljNRp/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.bYHUrVz6z2/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.005033 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:51:34.280058) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:51:34.280382) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:51:34.280773) ========= ========= Total master_lag: 4.92 =========