builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0173 starttime: 1461833047.13 results: success (0) buildid: 20160428004649 builduid: 97b87ca006c84df7988e97f5dc309a7a revision: 84bc3fe034f2e40438beb879b5ce59abc62f8695 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.133628) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.134135) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.134553) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.156471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.156789) ========= 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.sgQTdRI1zS/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.lD8TufznAg/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:44:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.22M=0.001s 2016-04-28 01:44:07 (9.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080651 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.255307) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.255616) ========= 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.sgQTdRI1zS/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.lD8TufznAg/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.027397 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:07.306026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 01:44:07.306379) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 84bc3fe034f2e40438beb879b5ce59abc62f8695 --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 84bc3fe034f2e40438beb879b5ce59abc62f8695 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sgQTdRI1zS/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.lD8TufznAg/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:44:07,384 truncating revision to first 12 chars 2016-04-28 01:44:07,384 Setting DEBUG logging. 2016-04-28 01:44:07,384 attempt 1/10 2016-04-28 01:44:07,385 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/84bc3fe034f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 01:44:08,497 unpacking tar archive at: fx-team-84bc3fe034f2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.353048 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 01:44:08.679004) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:08.679372) ========= script_repo_revision: 84bc3fe034f2e40438beb879b5ce59abc62f8695 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:08.679846) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:08.681012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 01:44:08.708655) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-04-28 01:44:08.709022) ========= /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.sgQTdRI1zS/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.lD8TufznAg/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:44:08 INFO - MultiFileLogger online at 20160428 01:44:08 in /builds/slave/test 01:44:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:44:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:44:08 INFO - {'append_to_log': False, 01:44:08 INFO - 'base_work_dir': '/builds/slave/test', 01:44:08 INFO - 'blob_upload_branch': 'Fx-Team', 01:44:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:44:08 INFO - 'branch': 'Fx-Team', 01:44:08 INFO - 'buildbot_json_path': 'buildprops.json', 01:44:08 INFO - 'config_files': ('talos/mac_config.py',), 01:44:08 INFO - 'default_actions': ('clobber', 01:44:08 INFO - 'read-buildbot-config', 01:44:08 INFO - 'download-and-extract', 01:44:08 INFO - 'populate-webroot', 01:44:08 INFO - 'create-virtualenv', 01:44:08 INFO - 'install', 01:44:08 INFO - 'run-tests'), 01:44:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:44:08 INFO - 'download_minidump_stackwalk': True, 01:44:08 INFO - 'download_symbols': 'ondemand', 01:44:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:44:08 INFO - 'tooltool.py': '/tools/tooltool.py', 01:44:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:44:08 INFO - '/tools/misc-python/virtualenv.py')}, 01:44:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:44:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:44:08 INFO - 'installer_path': 'installer.exe', 01:44:08 INFO - 'log_level': 'info', 01:44:08 INFO - 'log_name': 'talos', 01:44:08 INFO - 'log_to_console': True, 01:44:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:44:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:44:08 INFO - 'opt_config_files': (), 01:44:08 INFO - 'pip_index': False, 01:44:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:44:08 INFO - 'cmd': ('bash', 01:44:08 INFO - '-c', 01:44:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:44:08 INFO - 'enabled': True, 01:44:08 INFO - 'halt_on_failure': False, 01:44:08 INFO - 'name': 'check_screen_resolution'},), 01:44:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:44:08 INFO - 'cmd': ('bash', 01:44:08 INFO - '-c', 01:44:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:44:08 INFO - 'enabled': True, 01:44:08 INFO - 'halt_on_failure': False, 01:44:08 INFO - 'name': 'check_screen_resolution'},), 01:44:08 INFO - 'run_cmd_checks_enabled': True, 01:44:08 INFO - 'sps_profile': False, 01:44:08 INFO - 'sps_profile_interval': 0, 01:44:08 INFO - 'suite': 'dromaeojs', 01:44:08 INFO - 'system_bits': '32', 01:44:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:44:08 INFO - 'title': 't-yosemite-r7-0173', 01:44:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:44:08 INFO - 'use_talos_json': True, 01:44:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:44:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:44:08 INFO - 'work_dir': 'build'} 01:44:08 INFO - ##### 01:44:08 INFO - ##### Running clobber step. 01:44:08 INFO - ##### 01:44:08 INFO - Running pre-action listener: _resource_record_pre_action 01:44:08 INFO - Running main action method: clobber 01:44:08 INFO - rmtree: /builds/slave/test/build 01:44:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:44:11 INFO - Running post-action listener: _resource_record_post_action 01:44:11 INFO - ##### 01:44:11 INFO - ##### Running read-buildbot-config step. 01:44:11 INFO - ##### 01:44:11 INFO - Running pre-action listener: _resource_record_pre_action 01:44:11 INFO - Running main action method: read_buildbot_config 01:44:11 INFO - Using buildbot properties: 01:44:11 INFO - { 01:44:11 INFO - "project": "", 01:44:11 INFO - "product": "firefox", 01:44:11 INFO - "script_repo_revision": "production", 01:44:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:44:11 INFO - "repository": "", 01:44:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:44:11 INFO - "buildid": "20160428004649", 01:44:11 INFO - "pgo_build": "False", 01:44:11 INFO - "basedir": "/builds/slave/test", 01:44:11 INFO - "buildnumber": 736, 01:44:11 INFO - "slavename": "t-yosemite-r7-0173", 01:44:11 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 01:44:11 INFO - "platform": "yosemite_r7", 01:44:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:44:11 INFO - "branch": "fx-team", 01:44:11 INFO - "repo_path": "integration/fx-team", 01:44:11 INFO - "revision": "84bc3fe034f2e40438beb879b5ce59abc62f8695", 01:44:11 INFO - "stage_platform": "macosx64", 01:44:11 INFO - "builduid": "97b87ca006c84df7988e97f5dc309a7a", 01:44:11 INFO - "slavebuilddir": "test" 01:44:11 INFO - } 01:44:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:44:11 INFO - Running post-action listener: _resource_record_post_action 01:44:11 INFO - ##### 01:44:11 INFO - ##### Running download-and-extract step. 01:44:11 INFO - ##### 01:44:11 INFO - Running pre-action listener: _resource_record_pre_action 01:44:11 INFO - Running main action method: download_and_extract 01:44:11 INFO - mkdir: /builds/slave/test/build/tests 01:44: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')]} 01:44:11 INFO - https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:44:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:44:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:44:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:12 INFO - Downloaded 1199 bytes. 01:44:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:44:12 INFO - Using the following test package requirements: 01:44:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:44:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:44:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:44:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'jsshell-mac.zip'], 01:44:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:44:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:44:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:44:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:44:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:44:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:44:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:44:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:44:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:12 INFO - https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:44:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:44:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:44:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:13 INFO - Downloaded 18116496 bytes. 01:44:13 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'] 01:44:13 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 01:44:16 INFO - Return code: 0 01:44:16 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 01:44:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:16 INFO - https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:44:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:44:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:44:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:18 INFO - Downloaded 18116496 bytes. 01:44:18 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'] 01:44:18 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 01:44:22 INFO - Return code: 0 01:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:22 INFO - https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:44:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:44:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:44:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:24 INFO - Downloaded 10840145 bytes. 01:44:24 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'] 01:44:24 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 01:44:24 INFO - Return code: 0 01:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:24 INFO - https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:44:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:44:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:44:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:44:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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 01:44:27 INFO - Downloaded 110188324 bytes. 01:44:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:44:27 INFO - mkdir: /builds/slave/test/properties 01:44:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:44:27 INFO - Writing to file /builds/slave/test/properties/build_url 01:44:27 INFO - Contents: 01:44:27 INFO - build_url:https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:44:28 INFO - Running post-action listener: _resource_record_post_action 01:44:28 INFO - Running post-action listener: set_extra_try_arguments 01:44:28 INFO - ##### 01:44:28 INFO - ##### Running populate-webroot step. 01:44:28 INFO - ##### 01:44:28 INFO - Running pre-action listener: _resource_record_pre_action 01:44:28 INFO - Running main action method: populate_webroot 01:44:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:44:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:44:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:44:28 INFO - '--tppagecycles', 01:44:28 INFO - '7'], 01:44:28 INFO - 'tests': ['tsvgm']}}, 01:44:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:44:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:44:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:44:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:44:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:44:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:44:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['damp', 'tps']}, 01:44:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'tests': ['damp', 'tps']}, 01:44:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['dromaeo_dom']}, 01:44:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:44:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['a11yr', 01:44:28 INFO - 'ts_paint', 01:44:28 INFO - 'tpaint', 01:44:28 INFO - 'sessionrestore', 01:44:28 INFO - 'sessionrestore_no_auto_restore', 01:44:28 INFO - 'tabpaint']}, 01:44:28 INFO - 'other-e10s': {'tests': ['a11yr', 01:44:28 INFO - 'ts_paint', 01:44:28 INFO - 'tpaint', 01:44:28 INFO - 'sessionrestore', 01:44:28 INFO - 'sessionrestore_no_auto_restore', 01:44:28 INFO - 'tabpaint']}, 01:44:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:44:28 INFO - 'ts_paint', 01:44:28 INFO - 'tpaint', 01:44:28 INFO - 'sessionrestore', 01:44:28 INFO - 'sessionrestore_no_auto_restore', 01:44:28 INFO - 'tabpaint']}, 01:44:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:44:28 INFO - 'ts_paint', 01:44:28 INFO - 'tpaint', 01:44:28 INFO - 'sessionrestore', 01:44:28 INFO - 'sessionrestore_no_auto_restore', 01:44:28 INFO - 'tabpaint']}, 01:44:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['a11yr', 01:44:28 INFO - 'ts_paint', 01:44:28 INFO - 'tpaint', 01:44:28 INFO - 'sessionrestore', 01:44:28 INFO - 'sessionrestore_no_auto_restore', 01:44:28 INFO - 'tabpaint']}, 01:44:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['a11yr', 01:44:28 INFO - 'ts_paint', 01:44:28 INFO - 'tpaint', 01:44:28 INFO - 'sessionrestore', 01:44:28 INFO - 'sessionrestore_no_auto_restore', 01:44:28 INFO - 'tabpaint']}, 01:44:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['tsvgx', 01:44:28 INFO - 'tsvgr_opacity', 01:44:28 INFO - 'tart', 01:44:28 INFO - 'tscrollx', 01:44:28 INFO - 'cart']}, 01:44:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:44:28 INFO - 'tsvgr_opacity', 01:44:28 INFO - 'tart', 01:44:28 INFO - 'tscrollx', 01:44:28 INFO - 'cart']}, 01:44:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'talos_options': ['--disable-e10s'], 01:44:28 INFO - 'tests': ['tp5o']}, 01:44:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'tests': ['tp5o']}, 01:44:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'talos_options': ['--disable-e10s', 01:44:28 INFO - '--xperf_path', 01:44:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:44:28 INFO - 'tests': ['tp5n']}, 01:44:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:44:28 INFO - 'talos_options': ['--xperf_path', 01:44:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:44:28 INFO - 'tests': ['tp5n']}}, 01:44:28 INFO - 'talos.zip': {'path': '', 01:44:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:44:28 INFO - Running post-action listener: _resource_record_post_action 01:44:28 INFO - ##### 01:44:28 INFO - ##### Running create-virtualenv step. 01:44:28 INFO - ##### 01:44:28 INFO - Running pre-action listener: _resource_record_pre_action 01:44:28 INFO - Running main action method: create_virtualenv 01:44:28 INFO - Creating virtualenv /builds/slave/test/build/venv 01:44:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:44:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:44:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:44:28 INFO - Using real prefix '/tools/python27' 01:44:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:44:29 INFO - Installing distribute.............................................................................................................................................................................................done. 01:44:32 INFO - Installing pip.................done. 01:44:32 INFO - Return code: 0 01:44:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:32 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 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:44:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:32 INFO - 'HOME': '/Users/cltbld', 01:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:32 INFO - 'LOGNAME': 'cltbld', 01:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:32 INFO - 'MOZ_NO_REMOTE': '1', 01:44:32 INFO - 'NO_EM_RESTART': '1', 01:44:32 INFO - 'PAGER': '/bin/cat', 01:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:32 INFO - 'PWD': '/builds/slave/test', 01:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:32 INFO - 'SHELL': '/bin/bash', 01:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:32 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:32 INFO - 'USER': 'cltbld', 01:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:32 INFO - 'XPC_FLAGS': '0x0', 01:44:32 INFO - 'XPC_SERVICE_NAME': '0', 01:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:44:32 INFO - Cleaning up... 01:44:32 INFO - Return code: 0 01:44:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:32 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 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:32 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 01:44:32 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 01:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:32 INFO - 'HOME': '/Users/cltbld', 01:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:32 INFO - 'LOGNAME': 'cltbld', 01:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:32 INFO - 'MOZ_NO_REMOTE': '1', 01:44:32 INFO - 'NO_EM_RESTART': '1', 01:44:32 INFO - 'PAGER': '/bin/cat', 01:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:32 INFO - 'PWD': '/builds/slave/test', 01:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:32 INFO - 'SHELL': '/bin/bash', 01:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:32 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:32 INFO - 'USER': 'cltbld', 01:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:32 INFO - 'XPC_FLAGS': '0x0', 01:44:32 INFO - 'XPC_SERVICE_NAME': '0', 01:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:33 INFO - Downloading/unpacking psutil>=0.7.1 01:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:44:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:44:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:44:36 INFO - Installing collected packages: psutil 01:44:36 INFO - Running setup.py install for psutil 01:44:36 INFO - building 'psutil._psutil_osx' extension 01:44:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:44:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:44:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:44:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:44:37 INFO - building 'psutil._psutil_posix' extension 01:44:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:44:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:44:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:44:37 INFO - ^ 01:44:37 INFO - 1 warning generated. 01:44:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:44:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:44:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:44:37 INFO - Successfully installed psutil 01:44:37 INFO - Cleaning up... 01:44:37 INFO - Return code: 0 01:44:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:37 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 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:37 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 01:44:37 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 01:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:37 INFO - 'HOME': '/Users/cltbld', 01:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:37 INFO - 'LOGNAME': 'cltbld', 01:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:37 INFO - 'MOZ_NO_REMOTE': '1', 01:44:37 INFO - 'NO_EM_RESTART': '1', 01:44:37 INFO - 'PAGER': '/bin/cat', 01:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:37 INFO - 'PWD': '/builds/slave/test', 01:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:37 INFO - 'SHELL': '/bin/bash', 01:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:37 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:37 INFO - 'USER': 'cltbld', 01:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:37 INFO - 'XPC_FLAGS': '0x0', 01:44:37 INFO - 'XPC_SERVICE_NAME': '0', 01:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:44:40 INFO - Installing collected packages: mozsystemmonitor 01:44:40 INFO - Running setup.py install for mozsystemmonitor 01:44:40 INFO - Successfully installed mozsystemmonitor 01:44:40 INFO - Cleaning up... 01:44:40 INFO - Return code: 0 01:44:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:40 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 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:40 INFO - 'HOME': '/Users/cltbld', 01:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:40 INFO - 'LOGNAME': 'cltbld', 01:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:40 INFO - 'MOZ_NO_REMOTE': '1', 01:44:40 INFO - 'NO_EM_RESTART': '1', 01:44:40 INFO - 'PAGER': '/bin/cat', 01:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:40 INFO - 'PWD': '/builds/slave/test', 01:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:40 INFO - 'SHELL': '/bin/bash', 01:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:40 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:40 INFO - 'USER': 'cltbld', 01:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:40 INFO - 'XPC_FLAGS': '0x0', 01:44:40 INFO - 'XPC_SERVICE_NAME': '0', 01:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:40 INFO - Downloading/unpacking blobuploader==1.2.4 01:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:42 INFO - Downloading blobuploader-1.2.4.tar.gz 01:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:44:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:44:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:44 INFO - Downloading docopt-0.6.1.tar.gz 01:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:44:44 INFO - Installing collected packages: blobuploader, requests, docopt 01:44:44 INFO - Running setup.py install for blobuploader 01:44:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:44:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:44:44 INFO - Running setup.py install for requests 01:44:44 INFO - Running setup.py install for docopt 01:44:45 INFO - Successfully installed blobuploader requests docopt 01:44:45 INFO - Cleaning up... 01:44:45 INFO - Return code: 0 01:44:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:44:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:45 INFO - 'HOME': '/Users/cltbld', 01:44:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:45 INFO - 'LOGNAME': 'cltbld', 01:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:45 INFO - 'MOZ_NO_REMOTE': '1', 01:44:45 INFO - 'NO_EM_RESTART': '1', 01:44:45 INFO - 'PAGER': '/bin/cat', 01:44:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:45 INFO - 'PWD': '/builds/slave/test', 01:44:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:45 INFO - 'SHELL': '/bin/bash', 01:44:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:45 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:45 INFO - 'USER': 'cltbld', 01:44:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:45 INFO - 'XPC_FLAGS': '0x0', 01:44:45 INFO - 'XPC_SERVICE_NAME': '0', 01:44:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:44:45 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Vjbb1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:44:45 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-pJzutO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:44:45 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-8NxSbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:44:45 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-9fLjZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:44:45 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-gVCpyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:44:45 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-cPFk_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:44:45 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-mh8xXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-nQNsSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5mnY3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-x3ff0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-JAqLi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-sAYNB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5gQJy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BzsdL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-QRNr9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:44:46 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Xqf0bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:44:47 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-63PHx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:44:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:44:47 INFO - Running setup.py install for manifestparser 01:44:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:44:47 INFO - Running setup.py install for mozcrash 01:44:47 INFO - Running setup.py install for mozdebug 01:44:47 INFO - Running setup.py install for mozdevice 01:44:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:44:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:44:47 INFO - Running setup.py install for mozfile 01:44:47 INFO - Running setup.py install for mozhttpd 01:44:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Running setup.py install for mozinfo 01:44:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Running setup.py install for mozInstall 01:44:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Running setup.py install for mozleak 01:44:48 INFO - Running setup.py install for mozlog 01:44:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Running setup.py install for moznetwork 01:44:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:44:48 INFO - Running setup.py install for mozprocess 01:44:48 INFO - Running setup.py install for mozprofile 01:44:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:44:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:44:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:44:49 INFO - Running setup.py install for mozrunner 01:44:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:44:49 INFO - Running setup.py install for mozscreenshot 01:44:49 INFO - Running setup.py install for moztest 01:44:49 INFO - Running setup.py install for mozversion 01:44:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:44:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:44:49 INFO - Cleaning up... 01:44:49 INFO - Return code: 0 01:44:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:44: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')]} 01:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44: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')]} 01:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:49 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 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:49 INFO - 'HOME': '/Users/cltbld', 01:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:49 INFO - 'LOGNAME': 'cltbld', 01:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:49 INFO - 'MOZ_NO_REMOTE': '1', 01:44:49 INFO - 'NO_EM_RESTART': '1', 01:44:49 INFO - 'PAGER': '/bin/cat', 01:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:49 INFO - 'PWD': '/builds/slave/test', 01:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:49 INFO - 'SHELL': '/bin/bash', 01:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:49 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:49 INFO - 'USER': 'cltbld', 01:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:49 INFO - 'XPC_FLAGS': '0x0', 01:44:49 INFO - 'XPC_SERVICE_NAME': '0', 01:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:44:49 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-XQpQlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-KtV8Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-964n87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-NX0fo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-hhyXLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-YNUKf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-22g76C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-CcQQsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-XpTnhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:44:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-iOAMLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:44:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-XxEzWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:44:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-cnQwtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:44:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-L94ljz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:44:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-kgkmLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:44:51 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)) 01:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:44:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-otEgok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:44:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5Afyw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:44:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ckOKdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:44:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:54 INFO - Downloading blessings-1.6.tar.gz 01:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:44:54 INFO - Installing collected packages: blessings 01:44:54 INFO - Running setup.py install for blessings 01:44:54 INFO - Successfully installed blessings 01:44:54 INFO - Cleaning up... 01:44:54 INFO - Return code: 0 01:44:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:44:54 INFO - Reading from file tmpfile_stdout 01:44:54 INFO - Current package versions: 01:44:54 INFO - blessings == 1.6 01:44:54 INFO - blobuploader == 1.2.4 01:44:54 INFO - docopt == 0.6.1 01:44:54 INFO - manifestparser == 1.1 01:44:54 INFO - mozInstall == 1.12 01:44:54 INFO - mozcrash == 0.17 01:44:54 INFO - mozdebug == 0.1 01:44:54 INFO - mozdevice == 0.48 01:44:54 INFO - mozfile == 1.2 01:44:54 INFO - mozhttpd == 0.7 01:44:54 INFO - mozinfo == 0.9 01:44:54 INFO - mozleak == 0.1 01:44:54 INFO - mozlog == 3.1 01:44:54 INFO - moznetwork == 0.27 01:44:54 INFO - mozprocess == 0.22 01:44:54 INFO - mozprofile == 0.28 01:44:54 INFO - mozrunner == 6.11 01:44:54 INFO - mozscreenshot == 0.1 01:44:54 INFO - mozsystemmonitor == 0.0 01:44:54 INFO - moztest == 0.7 01:44:54 INFO - mozversion == 1.4 01:44:54 INFO - psutil == 3.1.1 01:44:54 INFO - requests == 1.2.3 01:44:54 INFO - wsgiref == 0.1.2 01:44:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:54 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 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:54 INFO - 'HOME': '/Users/cltbld', 01:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:54 INFO - 'LOGNAME': 'cltbld', 01:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:54 INFO - 'MOZ_NO_REMOTE': '1', 01:44:54 INFO - 'NO_EM_RESTART': '1', 01:44:54 INFO - 'PAGER': '/bin/cat', 01:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:54 INFO - 'PWD': '/builds/slave/test', 01:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:54 INFO - 'SHELL': '/bin/bash', 01:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:54 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:54 INFO - 'USER': 'cltbld', 01:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:54 INFO - 'XPC_FLAGS': '0x0', 01:44:54 INFO - 'XPC_SERVICE_NAME': '0', 01:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:44:55 INFO - Cleaning up... 01:44:55 INFO - Return code: 0 01:44:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:55 INFO - 'HOME': '/Users/cltbld', 01:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:55 INFO - 'LOGNAME': 'cltbld', 01:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:55 INFO - 'MOZ_NO_REMOTE': '1', 01:44:55 INFO - 'NO_EM_RESTART': '1', 01:44:55 INFO - 'PAGER': '/bin/cat', 01:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:55 INFO - 'PWD': '/builds/slave/test', 01:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:55 INFO - 'SHELL': '/bin/bash', 01:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:55 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:55 INFO - 'USER': 'cltbld', 01:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:55 INFO - 'XPC_FLAGS': '0x0', 01:44:55 INFO - 'XPC_SERVICE_NAME': '0', 01:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:55 INFO - Downloading/unpacking jsonschema 01:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:44:57 INFO - Downloading/unpacking functools32 (from jsonschema) 01:44: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 01:44: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 01:44: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 01:44: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 01:44: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 01:44: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 01:44:58 INFO - Downloading functools32-3.2.3-2.tar.gz 01:44:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:44:58 INFO - warning: no files found matching '*.txt' 01:44:58 INFO - no previously-included directories found matching 'build' 01:44:58 INFO - no previously-included directories found matching 'dist' 01:44:58 INFO - no previously-included directories found matching '.git*' 01:44:58 INFO - Installing collected packages: jsonschema, functools32 01:44:58 INFO - Running setup.py install for functools32 01:44:58 INFO - warning: no files found matching '*.txt' 01:44:58 INFO - no previously-included directories found matching 'build' 01:44:58 INFO - no previously-included directories found matching 'dist' 01:44:58 INFO - no previously-included directories found matching '.git*' 01:44:58 INFO - Successfully installed jsonschema functools32 01:44:58 INFO - Cleaning up... 01:44:58 INFO - Return code: 0 01:44:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:44:58 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 0x1014b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8680c92ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, '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.sgQTdRI1zS/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.lD8TufznAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:44:58 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 01:44:58 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 01:44:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:44:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:44:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:44:58 INFO - 'HOME': '/Users/cltbld', 01:44:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:44:58 INFO - 'LOGNAME': 'cltbld', 01:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:44:58 INFO - 'MOZ_NO_REMOTE': '1', 01:44:58 INFO - 'NO_EM_RESTART': '1', 01:44:58 INFO - 'PAGER': '/bin/cat', 01:44:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:44:58 INFO - 'PWD': '/builds/slave/test', 01:44:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:44:58 INFO - 'SHELL': '/bin/bash', 01:44:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:44:58 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:44:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:44:58 INFO - 'USER': 'cltbld', 01:44:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:44:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:44:58 INFO - 'XPC_FLAGS': '0x0', 01:44:58 INFO - 'XPC_SERVICE_NAME': '0', 01:44:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:44:58 INFO - Downloading/unpacking flake8 01:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:44:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:45:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:45:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:45:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:45:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:45:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:45:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:45:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:45:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:45:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:45:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:45:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:45:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:45:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:45:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:45:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:45:02 INFO - no previously-included directories found matching 'docs/_build' 01:45:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:45:02 INFO - Running setup.py install for pep8 01:45:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:45:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:45:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:45:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:45:02 INFO - no previously-included directories found matching 'docs/_build' 01:45:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:45:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:45:02 INFO - Cleaning up... 01:45:02 INFO - Return code: 0 01:45:02 INFO - Running post-action listener: _resource_record_post_action 01:45:02 INFO - Running post-action listener: _start_resource_monitoring 01:45:03 INFO - Starting resource monitoring. 01:45:03 INFO - ##### 01:45:03 INFO - ##### Running install step. 01:45:03 INFO - ##### 01:45:03 INFO - Running pre-action listener: _resource_record_pre_action 01:45:03 INFO - Running main action method: install 01:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:45:03 INFO - Reading from file tmpfile_stdout 01:45:03 INFO - Detecting whether we're running mozinstall >=1.0... 01:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:45:03 INFO - Reading from file tmpfile_stdout 01:45:03 INFO - Output received: 01:45:03 INFO - Usage: mozinstall [options] installer 01:45:03 INFO - Options: 01:45:03 INFO - -h, --help show this help message and exit 01:45:03 INFO - -d DEST, --destination=DEST 01:45:03 INFO - Directory to install application into. [default: 01:45:03 INFO - "/builds/slave/test"] 01:45:03 INFO - --app=APP Application being installed. [default: firefox] 01:45:03 INFO - mkdir: /builds/slave/test/build/application 01:45:03 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'] 01:45:03 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 01:45:37 INFO - Reading from file tmpfile_stdout 01:45:37 INFO - Output received: 01:45:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:45:37 INFO - Running post-action listener: _resource_record_post_action 01:45:37 INFO - ##### 01:45:37 INFO - ##### Running run-tests step. 01:45:37 INFO - ##### 01:45:37 INFO - Running pre-action listener: _resource_record_pre_action 01:45:37 INFO - Running main action method: run_tests 01:45:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:45:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:45:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:45:37 INFO - 2016-04-28 01:45:37.204 screenresolution[1643:7455] starting screenresolution argv=screenresolution get 01:45:37 INFO - 2016-04-28 01:45:37.207 screenresolution[1643:7455] Display 0: 1600x1200x32@60 01:45:37 INFO - 2016-04-28 01:45:37.212 screenresolution[1644:7457] starting screenresolution argv=screenresolution list 01:45:37 INFO - Available Modes on Display 0 01:45:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:45:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:45:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:45:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:45:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:45:37 INFO - Intel Iris: 01:45:37 INFO - Chipset Model: Intel Iris 01:45:37 INFO - Type: GPU 01:45:37 INFO - Bus: Built-In 01:45:37 INFO - VRAM (Dynamic, Max): 1536 MB 01:45:37 INFO - Vendor: Intel (0x8086) 01:45:37 INFO - Device ID: 0x0a2e 01:45:37 INFO - Revision ID: 0x0009 01:45:37 INFO - Displays: 01:45:37 INFO - Display: 01:45:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:45:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:45:37 INFO - Display Serial Number: 1600x1200 60 01:45:37 INFO - Main Display: Yes 01:45:37 INFO - Mirror: Off 01:45:37 INFO - Online: Yes 01:45:37 INFO - Rotation: Supported 01:45:37 INFO - Return code: 0 01:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:45:37 INFO - Python 2.7.3 01:45:37 INFO - Return code: 0 01:45:37 INFO - grabbing minidump binary from tooltool 01:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:45:37 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 0x101358e00>, '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 0x101181db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101353b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:45:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:45:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:45:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:45:37 INFO - Return code: 0 01:45:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:45:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:45:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:45:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:45:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:45:38 INFO - Return code: 0 01:45:38 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/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:45:38 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/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sgQTdRI1zS/Render', 01:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:45:38 INFO - 'HOME': '/Users/cltbld', 01:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:45:38 INFO - 'LOGNAME': 'cltbld', 01:45:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:45:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:45:38 INFO - 'MOZ_NO_REMOTE': '1', 01:45:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:45:38 INFO - 'NO_EM_RESTART': '1', 01:45:38 INFO - 'PAGER': '/bin/cat', 01:45:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:45:38 INFO - 'PWD': '/builds/slave/test', 01:45:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:45:38 INFO - 'SHELL': '/bin/bash', 01:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lD8TufznAg/Listeners', 01:45:38 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:45:38 INFO - 'USER': 'cltbld', 01:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:45:38 INFO - 'XPC_FLAGS': '0x0', 01:45:38 INFO - 'XPC_SERVICE_NAME': '0', 01:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:45:38 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/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:45:38 INFO - mozversion application_buildid: 20160428004649 01:45:38 INFO - mozversion application_changeset: 84bc3fe034f2e40438beb879b5ce59abc62f8695 01:45:38 INFO - mozversion application_display_name: Nightly 01:45:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:45:38 INFO - mozversion application_name: Firefox 01:45:38 INFO - mozversion application_remotingname: firefox 01:45:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:45:38 INFO - mozversion application_vendor: Mozilla 01:45:38 INFO - mozversion application_version: 49.0a1 01:45:38 INFO - mozversion platform_buildid: 20160428004649 01:45:38 INFO - mozversion platform_changeset: 84bc3fe034f2e40438beb879b5ce59abc62f8695 01:45:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:45:38 INFO - mozversion platform_version: 49.0a1 01:45:38 INFO - using testdate: 1461833138 01:45:38 INFO - actual date: 1461833138 01:45:38 INFO - starting webserver on 'localhost:49275' 01:45:38 INFO - SUITE-START | Running 2 tests 01:45:38 INFO - TEST-START | dromaeo_css 01:45:38 INFO - operating with platform_type : mac_ 01:45:38 INFO - Initialising browser for dromaeo_css test... 01:45:38 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpx2Zwr3/profile http://localhost:49275/getInfo.html) 01:45:41 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 01:45:41 INFO - PROCESS | 1662 | colorDepth:24 01:45:41 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 01:45:41 INFO - PROCESS | 1662 | __metrics 01:45:41 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:45:41 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:45:41 INFO - TEST-INFO | 1662: exit 0 01:45:41 INFO - Browser initialized. 01:45:41 INFO - Running cycle 1/1 for dromaeo_css test... 01:45:41 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpx2Zwr3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:45:54 INFO - PROCESS | 1664 | RSS: Main: 239165440 01:45:54 INFO - PROCESS | 1664 | 01:46:45 INFO - PROCESS | 1664 | 1461833205201 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] 01:48:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 01:48:56 INFO - PROCESS | 1664 | RSS: Main: 325955584 01:48:56 INFO - PROCESS | 1664 | 01:51:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 01:52:00 INFO - PROCESS | 1664 | RSS: Main: 380121088 01:52:00 INFO - PROCESS | 1664 | 01:55:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 01:55:02 INFO - PROCESS | 1664 | RSS: Main: 375820288 01:55:02 INFO - PROCESS | 1664 | 01:58:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 01:58:05 INFO - PROCESS | 1664 | RSS: Main: 376438784 01:58:05 INFO - PROCESS | 1664 | 02:01:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 02:01:09 INFO - PROCESS | 1664 | RSS: Main: 354840576 02:01:09 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 02:04:13 INFO - PROCESS | 1664 | RSS: Main: 384843776 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | __start_tp_report 02:04:13 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 02:04:13 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 02:04:13 INFO - PROCESS | 1664 | |i|pagename|runs| 02:04:13 INFO - PROCESS | 1664 | |0;dojo.html;2816.3663175785446;3362.0922350965257;3379.729621630269;3384.830758462076;3443.3285509325674;5445.673259604417;5514.420985796493;5523.475269849363;5537.889242215158;5571.164325351199;5511.9724401378035;5548.833534993957;5614.9438505614935;5664.320281566207;5685.658860468375;4109.938350924738;4993.525615066563;5017.247412888059;5058.064258112261;5162.896742065156;4297.73216901015;5083.84748457545;5089.821856235032;5109.744512774365;5178.171492561191;4934.333864928225;5026.321446604698;5032.874178145557;5036.697798132123;5120.692758434488;4068.5524592294823;5036.043151801183;5042.067217564762;5052;5111.923321150186;5004.299398084271;5004.499550044967;5019.096562618727;5019.222020586815;5033.899322013539;5449.509544141047;5457.2359869618285;5478.506934375925;5482.5613950883835;5482.835514934558;5853.09277062056;5937.732802023919;5958.285005799288;5962.1951036609935;5984.581079324449;6104.481119104881;6128.295246046717;6147.415995480422;6161.7227224775;6183.598066125687;5625.943740562583;5632.324121105412;5634.126710359901;5679.403662615448;5696.544276457915;6119.112728654409;6160.476359509402;6189.535784816067;6264.2796078451565;6264.686765661699;5881.529477641779;5928.140419639129;5941.465268125889;5993.340732519419;6004.939950600526;6024.367441418588;6025;6040.697965101727;6057.242844644419;6060.151578778975;5918.319393269743;5989.101634754822;5998.1002849572915;6033.215681961317;6034.487068599218;5958.374370691102;6009.849753756103;6014.518838492998;6026.939730602726;6074.75700971965;4801.327814106028;4813.989062296957;4822.758862056883;4829.5653391194965;4865.683730557503;4266.893327666771;4349.630281426051;4375.190589740908;4395.53846846083;4396.900774806299;5454.10007348791;5521.475459831309;5530.5022547970875;5553.111502159635;5569.832905012856;6117.143599896018;6155.045967874987;6169.722362493699;6176.722047507783;6185.288691800411;5552.139418390156;5558.86102847424;5571.359293681197;5591.4688104629995;5594.272744543265;5094.61790365724;5190.117679994334;5241.135212689864;5282.709450980233;5311.442298558596;6270.278917924497;6290.245170579468;6296.779612713533;6304.306526282197;6308.842278943063;6154.522914500398;6168.290646575521;6200.844978875564;6203.379662033941;6206.131141640084;6157.138000679819;6172.753089876354;6266.968665156645;6286.52851036165;6292.4651404629285;5946.108083787293;5977.043673012297;5982.0727787193055;5993.430624090704;6046.909296360646;5803.245578074823;5985.670788106696;6078.787242446493;6135.84660383494;6139.416755408229;5745.6552606843725;5849.356570777126;5935.614185077956;5940.3168635605725;5990.880182396415;4480.372747815373;5906.468417842243;5910.881782364243;5912.46787789101;5940;4800.183968725395;5589.357223919302;5751.884962300647;5777.075667893117;5807.041838096836;5824.95150872847;5838.1826544282985;5859.912101318569;5940.227770389822;5941.970290148522;5730.742116604846;5755.740991655469;5764.135379692912;5767.711614419346;5792.275965504312 02:04:13 INFO - PROCESS | 1664 | |1;ext.html;495.0371527379247;508.2883962452567;522.8117877564076;526.1686535274267;563.3408911573458;24264.39339016523;29848.850755746193;29861.55207671887;29898.402031959347;29914;37628.623713762856;38229.42655860164;38308.616913830854;38317.42523862144;38365.616343836555;9292.488913109777;9509.857352139723;9510.762230944212;9519.714408567754;9528.618855245782;9281.164695177433;9447.338686291961;9452.8582071269;9458.668946586871;9466.574004169828;9437.197838183763;9478.71563853082;9497.192738617226;9603.279754018477;9612.903870961305;9512.239020878347;9582.37714548559;9593.808123837554;9595.944446110958;9595.952020239854;8196.590170491452;9472.715818525456;9580.0898914603;9589.616415343378;9594.13652771247;38627;38955.805220974;39003.41494877579;39033;39169.216615667814;3391.8812841550675;3449.999500144955;3759.8684046058256;3764.4353346997896;3902.590228026073;27755.167344979684;28141.718582814116;28933.56599650986;29077.273068173254;29117.12648620545;2641.841489510616;2687.516247075526;2723.795715321359;2748.560230363152;2793.0922450203766;362514;366058.3394166051;366135;367346;368047.31952680397;61170.38829611736;61382.69308653428;61799.38200617936;61995.76008479805;62062.689686551275;62933.74132517324;63091;63295;63342;63351.049734254026;16569.42007029748;16783.496495105166;16791;16793.83206167947;16798.160091995596;12913.418896149697;12979.351032448529;12998.220106793622;12998.740025199631;13056.151350162208;14142.858571414154;17236.310547577956;17250.051247181527;17295.048772317645;17310.047947363015;6941.965290173517;6947.3747362736585;6958.65206739661;6962.825929351807;7024.121984751906;875.5490922175085;963.3352986439335;988.4267125067587;1015.2639336480992;1047.3820445936933;24973.750262497506;27790.589030475385;27861;27973.860130699217;28250.011249606167;697.8778713725078;1029.8146333660013;1064.7125276175389;1079.0073132717919;1134.7390100276818;13803.861961380459;13813.79279310791;14198.858011419754;14242.359093840802;14387.856121438861;468443;471752;473431;474608;474644;56762.7161864188;56888.71555642278;56979;57067.42932570621;57129.714351427974;1421.5522110535148;1449.7607894697258;1482.7553453680023;1498.9956728991601;1499.475183685723;28317.336532693957;28738.568921465776;28825.711742882304;29117.417651647273;29306.56040159442;44974;45577.77211113923;45661;45663.31505027361;45707.08585828331;300956;301566;302584.4870775632;302985;303634;31042.68957310398;38269.617303826606;38592;38882.22235555216;39649.80175099106;43203;44262.11475770531;44587;44943.77528112339;45701.08597828091;35282.647173527934;36562.45156322604;36906.26187476182;37223.44164837584;37396.6260337404;30053.549196762502;35529.467057993636;35975.640243598275;35981.460278096376;36859.447108293934;29623.851880740458;35512.28975420529;36342.63657363393;36548.63451365559;36551.634483655886;30470.542941855445;31933.68066319307;32150;32312.030639080866;32671.509927350635;21873;21924.963436837428;22244.11023559104;22372.552548948603;22459.77540224642 02:04:13 INFO - PROCESS | 1664 | |2;jquery.html;365.5156917084863;386.3142921314667;386.74281602734186;400.21757464157577;407.1328071208326;13951.930240348796;19387.70918436221;19494.610107797835;19734.70397944032;19742.802571974276;19083.809161908375;19447.805521944778;19868.304609338677;19944.50138746532;20546.972651367447;5290.39160496543;5395.622306438551;5506.146547285177;5512.586556008296;5513.724313784315;5297.099493086165;5524.861878453051;5537.418571050042;5546.861328466801;5548.140038294071;5489;5530.668159910399;5533.833984980457;5675.659460432387;6042.9395706042815;5830.688095178572;5991.610545314584;6019.097135429699;6026.156338112624;6036.245469316335;4534.478534968488;5275.285532202011;5330.493603107699;5340.759665815048;5346.518813306821;25117.748822511723;25575.74424255752;26803.46393072147;27276.45447091067;28300.433991320057;2013.2752209204675;2101.107029512451;2121.692354608573;2366.7041619797524;2434.977309529984;15357.385704571803;15786;15789.526314210478;16420.507384778357;16626.418075367426;1775.4052392448546;1876.7091100879386;1889.310401703382;1925.749652545174;1997.0913234478278;117529.41235293768;122050;122734;123326;123581.9999999991;15668.138252396227;16985.23566439513;20268.96782473644;20494.89752551228;20544.897275513827;34494.13764655904;34518;34565.30869382598;34603.307933841184;34633.65366346355;10558.208134389797;11092.223544351978;11135.387553684624;11146.721331966604;11254.987051165434;9017;9102.499362535124;9107.08929107084;9174.577940419245;9188.40275382098;9531;9595.47224902637;9696.224302055818;9697.806043879084;9720.805583888425;3105.3013072059057;3337.8497967591516;3357.7985320880825;3531.29374125176;3630.745847790682;884.787650963764;918.2241006349624;919.5494207838245;950.3490109275168;998.7203582996773;16048.839511604969;19358.516037098903;19737.013149342474;19854.70217946732;19912.303069392503;876.421561769242;961.9086186812242;976.8070681320481;987.4914419074182;997.2470784557613;10405.375677459226;10471.47642617866;10607.469626518643;10713.985033899005;10776.892231077745;534517;603531;608586;611244.8313275304;627750;110711.44644276888;112933;115134;115775;117063;968.9660861869801;988.7923536057508;999.3204620857741;1003.603576587244;1008.9949550252202;13421.798673019717;17336.826631733522;17507.56231094233;17562.912185438992;17644.647107058045;47249.05501889874;47480.287795683784;47973.695519642184;48322.75838620925;49111.508884912124;66495.66752166208;68677.65661171662;70120.94818577623;71080;72725;6543.1821022372205;7666.080070391588;7945.801354966172;8105.311048560939;8155.266026057683;11159.49782259784;12128.151029428209;12451.564195253122;12559.748805024032;12622.053345998904;10478.738031548968;10569.154467642416;10743.301685390425;11068.446577670924;11212.943935280598;7274.96362518184;10645.148388129086;10839.764774009363;11073.557057717924;11121.777564448828;9795.265355098538;10871.510782014986;11627.825582616098;11715.121365897421;11874;7990.400719946144;8170;8271.83456330858;8305.46014509055;8644.956775216084;8387.95806020966;10498;10662.200334974752;10969;11078.723031924266 02:04:13 INFO - PROCESS | 1664 | |3;mootools.html;1438.4749566408252;1611.8549330560252;1619.0043123479059;1859.39569639867;1978.5943881504284;7841.411894107943;8622.611982460789;8842.336824738139;8845.425047371933;8859.981102173251;8398.748037558868;8654.134586541342;8671.826563468727;8672.826543469142;8682.782930426742;2145.2491627930176;2367.9408014799683;2431.4407686232175;2468.8643224116927;2471.1845091119885;2477.4054226985486;2505.2233807519638;2505.473850491399;2510.548101341767;2511.9246422607353;2406.2179791567723;2472.7032756069298;2475.7276699563035;2476.2942561370014;2480.082369523279;2128.4146859613575;2213.867167969927;2214.169686367612;2214.579229946305;2408.6025805742033;2368.3723813189517;2460.950780984388;2466.839655422392;2468.0868078810777;2475.009996001596;7410.37011853993;8628.180322869317;8728.214460698568;8742.994555626057;8745.693900713444;5168.638195326329;5366.034113859504;5369.731513424314;5406.270153529263;5409.107497262986;3302.339532093591;3409.7783644063306;3485.7559970802054;3496.8984769797435;3513.54323937889;785.4148659248896;988.2242439684813;1005.9798804023952;1008.4907121903392;1011.7066050845247;11551.884481155164;11620.883791162065;11620.941895290469;11697.473613687489;11720.824187637192;4785.507759485422;4792.520747925179;4810.95189048105;4829.903401932012;4833.806647734122;9968.252381071448;10000.899991000142;10010.399376037461;10011.399316041061;10013.449260290608;5362.410134885159;5435.429279925551;5445.591580631469;5446.645968012067;5451.264079349237;4359.651227901761;4410.470743510799;4572.3827283317;4594.241950078266;4952.331435256267;3246.902592922216;3374.4600863861665;3917.6082391761165;4007.4189242560246;4034.8386064557676;1331.9866801332057;1340.4303171152417;1349.1837438349853;1352.9120607160503;1373.3339330424735;783.2559068884468;876.9386142969931;913.7852604637905;933.1275257633998;938.9671361502315;5738.254026976467;5861.736221870026;5987.43119403656;6021.314475790503;6111.602745821508;419.20978954670056;467.49277034417355;479.8608403562997;486.93913260842396;488.0483058036771;3889.8055097245024;3983.24318379507;3990.521137463465;4079.9592004080173;4106.260873042821;8997.865032024525;10623.415712135753;10827.945860270649;10828.675139745821;10858.402787846744;10801.297915635458;10813.91860813928;10819.026287634151;10819.891801082045;10839.783204335869;6331.5251356147555;8045.879311810328;8132.268095871282;8225.930629018309;8242.958785206156;5910.822675319747;5919.85200369977;6046.334903160737;6057.939420605738;6088.6042407243385;9022.774430639287;9655.986121457147;9707.514624268899;10008.899911000797;10133.391996480235;9504.619815207314;9819.165370943265;10088.646897358558;10221.28451008452;10258.43578603184;6869.484788640772;7123.145222573325;7426.851462970819;7508.812279693051;7685.846283074419;8403.789905252417;8404.831903362021;8503.617337219715;8507.8723819144;8512.829743405222;7176;7262.3826974707745;7445.739399121005;7549.50928189666;7631.122420921443;7209.243029481829;7235;7239.384652304613;7312.890306645511;7390.076240469942;6981.3367730065565;7023.438124950094;7059.505834591735;7068.964655176691;7076.080109585927;4475.507694153705;4815.2054910939305;4826.613870890391;4870.805167793249;5096.362954630671;4980.501949805135;5014.473480284518;5027.120253955616;5087.465816089405;5257.00116977773 02:04:13 INFO - PROCESS | 1664 | |4;prototype.html;315.59918902993195;318.2648082928434;323.46628063694885;323.7604172912046;327.5136422412715;4801.543853333937;4831.782569784359;5216.8695782605455;5237.345331833526;5289.338832645919;4805.399325084364;4827.396575428072;5237.45006774289;5238.895222095565;5271.525562699357;3166.002709146623;3832.6550610445056;3890.319194141028;3995.1422588496343;4026.8389264429534;3947.0921688011776;3997.6002399759936;4014.2774300625874;4015.8795236142964;4042.70633397313;3494.6680065393875;3831.7430184343366;3904.1020565270005;3958.5645578986287;3993.381025940984;3016.894408695707;3211.759118066131;3288.6875746804017;3628.909277268063;3882.1847412043503;2974.3902499987666;2984.85075746214;3526.982365088184;3561.554805649294;3580.838862251205;4178.415021896937;5034.194528875362;5052;5052.242163675442;5092.337996060525;3891.7935440013403;3975.52293724752;4609.83865564707;4621.584057434847;4629.421322334708;4639.512851150648;4729.314249433813;5392.973035134838;5429.724014856505;5479.205515200274;3403.5745531808525;3466.4973578830927;3920.09837737322;3925.8222533239914;3945.8816235512695;4774.880627984294;5763.4812866842185;5776.422357764232;5781.566382521328;5794.246747922743;3596.8669868991183;3739.6447337502896;3967.7222594418113;3967.781772002568;4018.8995275118355;3376.071580315433;3534.770239934396;3720.5907350191455;4021.3565829467143;4031.1937612477623;4076.286649836267;4599.379083823641;4618.35343051973;4618.56123668251;4642.817875598464;4281.636060934856;5214.530692237717;5539.972300138474;5547.77808887648;5559.694216818114;1217.713837248246;1416.129080615414;1417.2630232279364;1427.414759948416;1430.542226487514;830.8172202115525;845.9450135741157;887.8236336853695;940.4874343482818;947.5215016416665;4743.407074115736;5079.6952182869145;5125.333706618116;5552.7223638817895;5570.192322113271;4895.678166894919;5561.277033985556;5582.944170558242;5616.494515493625;5796.826190178683;4307.978460107679;4558.316252562142;4611.400517932647;5016.610465842388;5124.333836601218;2767.349672826884;3092.6907309268895;3262.869485220612;3286.7041966223155;3294.522294267366;5962.880742385128;6467.320931302145;6485.740570377132;6490.286068532458;6494.805155845238;6106.969465152646;6416.615003099736;6417.133686952295;6421.903671444838;6543.149390579194;5654.802081927113;6399.2082216979325;6428.55000149985;6440.935590644033;6464.644444555594;5477.7541208104485;5713.485786279255;5882.588218824812;6189.462320448594;6221.751129954751;5197.7141257231215;6033.01820798271;6156;6180.258368995569;6203.348648391854;5459.890802183854;5993.850153746017;6452.225732911893;6457.644829534421;6502.837429064311;3805.6765174960437;3987.720859539804;4006.5592784793066;4213.957860421357;4287.528371879029;5379.784808607612;5615.803446879339;5649.491545760901;5662.876421341996;5669.824898057102;5139.535232458881;5208.270842082187;5497.780088796403;5500.1749737539685;5504.128596277285;4707.199216466197;5257.447967963309;5454.236406903116;5583.497485226349;5606.439356064361;5428.9370039384785;5459.945400545944;5473.28847249855;5474.124140137559;5482.42434544383;4814.735189564468;5168.173092305213;5339.385970613429;5370.838874833761;5414.35027796667;4591.931121033254;5396.109641909041;5417.620766546462;5433.744995826845;5442.782288708566 02:04:13 INFO - PROCESS | 1664 | |5;yui.html;681.3697329969776;729.6096588325247;733.537871141181;742.2762806263892;756.5309508104975;14517.20155391453;14971.700565988674;14996.625084372869;15005.84994150058;15020.96378437536;13677.24775137367;13772.24252666103;13792.517261895835;14880.255987200624;15477.613059673513;923.2291036984126;936.69089200564;936.7751739582485;937.5546615357719;939.4598106088991;904.3488688144525;925.1072714830187;925.421611492817;925.676013395313;928.4986107501941;982.2044144243185;984.5421878826344;984.9803003939917;987.0573602209909;987.9654212102578;986.7138529826342;986.9703908882746;987.980240395188;989.7030890732751;991.6033586565432;731.5976213082783;731.8572878288736;732.5348403763649;733.2594079979206;737.959412232327;15045.548633540902;15089.39642414302;15112.697746045127;15117.319720612599;15127;12255;12259.509619615204;12306.753864922652;12350.938245308806;12856.742865142738;8394.958025209897;9944.453055081967;10106.949465252701;10140.239482038876;10457.68626941193;3434.4676575130893;3509.2455122148863;3621.257642183346;3695.926081478355;3703.8518459261595;47139.33011426954;65876.67061664708;73010.26989730088;73658.26341736621;77863;9310.813783724288;9314.72055838326;9336.859947100798;9560.091791279818;9737.951310243403;16979.660406791794;20266.18935242574;20463.28378506745;20527.4868128298;20835.479113022295;7259.9274007259255;7262.237465066198;7291.453141014339;7482.289182527651;7633.618319084024;3915.3735402335487;3919.8824035278985;4035.616616421435;4056.14820887611;4231.746095234295;3292.785968912061;3370.8820191293676;3573.6777392364993;3833.5783063862955;3834.328992426314;675.4056430341268;683.282553319013;691.1464341538197;694.2120693013466;696.5750891955903;498.62852175129035;583.9065749480063;591.4558606082414;595.4611076975344;607.0348146447081;7744.612769361509;7917.722879699183;8164.550949697705;8214.917850821535;8336.333093352518;580.0255570641278;596.7970889897368;609.4514936557046;625.4996003197424;628.2586547873461;3795.35478968576;3903.590123037097;3930.3711406174875;3958.1687845552183;3981.144054028398;94351;101240;101671;101702.98297016935;103292.48353758184;79672.60163699261;80871.5956420214;82324.58837705893;82735;83351.5832420834;63151;68793.65603171953;69344.6532767333;71536.28463715296;74600;7657.923420765721;7786.104597971306;7845.215478452109;7855.3715702744785;8060.987079847393;47129.293060603435;47384;47431.52568474271;47441;47529;63222.15560257391;64124.358756411835;67674.66162669155;69544.30455695378;70324.89025329248;10235.84646230322;10235.897641023494;10302.484875756032;10398.324108932895;10693.930606939244;11270.098392128773;11356.659300221007;11607.30356178632;11818.054555635701;12331.630051098482;10612.151027917902;11031.22781405294;11078.390688512209;11433.656990290305;11641.825372619249;10833.349999000086;11029.724256893642;11039.889601103887;11084.944575277072;11085.722856928642;10453.529591168264;10561.577536898438;10802.243842931233;10930.672079837617;11423.257488263236;9095.772605684646;9213.723588292361;9305.255579553756;9402.905970940203;9574.716469366726;8602.653554827837;8915.37592368528;9107.499087550248;9143.405678630868;9325.626974920928 02:04:13 INFO - PROCESS | 1664 | __end_tp_report 02:04:13 INFO - PROCESS | 1664 | __start_cc_report 02:04:13 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1355 02:04:13 INFO - PROCESS | 1664 | __end_cc_report 02:04:13 INFO - PROCESS | 1664 | __startTimestamp1461834253348__endTimestamp 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | ------- Summary: start ------- 02:04:13 INFO - PROCESS | 1664 | Number of tests: 6 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5565.22 Median:5764.14 stddev:661.14 (11.5%) stddev-sans-first:627.02 02:04:13 INFO - PROCESS | 1664 | Values: 2816.4 3362.1 3379.7 3384.8 3443.3 5445.7 5514.4 5523.5 5537.9 5571.2 5512.0 5548.8 5614.9 5664.3 5685.7 4109.9 4993.5 5017.2 5058.1 5162.9 4297.7 5083.8 5089.8 5109.7 5178.2 4934.3 5026.3 5032.9 5036.7 5120.7 4068.6 5036.0 5042.1 5052.0 5111.9 5004.3 5004.5 5019.1 5019.2 5033.9 5449.5 5457.2 5478.5 5482.6 5482.8 5853.1 5937.7 5958.3 5962.2 5984.6 6104.5 6128.3 6147.4 6161.7 6183.6 5625.9 5632.3 5634.1 5679.4 5696.5 6119.1 6160.5 6189.5 6264.3 6264.7 5881.5 5928.1 5941.5 5993.3 6004.9 6024.4 6025.0 6040.7 6057.2 6060.2 5918.3 5989.1 5998.1 6033.2 6034.5 5958.4 6009.8 6014.5 6026.9 6074.8 4801.3 4814.0 4822.8 4829.6 4865.7 4266.9 4349.6 4375.2 4395.5 4396.9 5454.1 5521.5 5530.5 5553.1 5569.8 6117.1 6155.0 6169.7 6176.7 6185.3 5552.1 5558.9 5571.4 5591.5 5594.3 5094.6 5190.1 5241.1 5282.7 5311.4 6270.3 6290.2 6296.8 6304.3 6308.8 6154.5 6168.3 6200.8 6203.4 6206.1 6157.1 6172.8 6267.0 6286.5 6292.5 5946.1 5977.0 5982.1 5993.4 6046.9 5803.2 5985.7 6078.8 6135.8 6139.4 5745.7 5849.4 5935.6 5940.3 5990.9 4480.4 5906.5 5910.9 5912.5 5940.0 4800.2 5589.4 5751.9 5777.1 5807.0 5825.0 5838.2 5859.9 5940.2 5942.0 5730.7 5755.7 5764.1 5767.7 5792.3 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:53513.20 Median:36882.85 stddev:102359.28 (277.5%) stddev-sans-first:102569.02 02:04:13 INFO - PROCESS | 1664 | Values: 495.0 508.3 522.8 526.2 563.3 24264.4 29848.9 29861.6 29898.4 29914.0 37628.6 38229.4 38308.6 38317.4 38365.6 9292.5 9509.9 9510.8 9519.7 9528.6 9281.2 9447.3 9452.9 9458.7 9466.6 9437.2 9478.7 9497.2 9603.3 9612.9 9512.2 9582.4 9593.8 9595.9 9596.0 8196.6 9472.7 9580.1 9589.6 9594.1 38627.0 38955.8 39003.4 39033.0 39169.2 3391.9 3450.0 3759.9 3764.4 3902.6 27755.2 28141.7 28933.6 29077.3 29117.1 2641.8 2687.5 2723.8 2748.6 2793.1 362514.0 366058.3 366135.0 367346.0 368047.3 61170.4 61382.7 61799.4 61995.8 62062.7 62933.7 63091.0 63295.0 63342.0 63351.0 16569.4 16783.5 16791.0 16793.8 16798.2 12913.4 12979.4 12998.2 12998.7 13056.2 14142.9 17236.3 17250.1 17295.0 17310.0 6942.0 6947.4 6958.7 6962.8 7024.1 875.5 963.3 988.4 1015.3 1047.4 24973.8 27790.6 27861.0 27973.9 28250.0 697.9 1029.8 1064.7 1079.0 1134.7 13803.9 13813.8 14198.9 14242.4 14387.9 468443.0 471752.0 473431.0 474608.0 474644.0 56762.7 56888.7 56979.0 57067.4 57129.7 1421.6 1449.8 1482.8 1499.0 1499.5 28317.3 28738.6 28825.7 29117.4 29306.6 44974.0 45577.8 45661.0 45663.3 45707.1 300956.0 301566.0 302584.5 302985.0 303634.0 31042.7 38269.6 38592.0 38882.2 39649.8 43203.0 44262.1 44587.0 44943.8 45701.1 35282.6 36562.5 36906.3 37223.4 37396.6 30053.5 35529.5 35975.6 35981.5 36859.4 29623.9 35512.3 36342.6 36548.6 36551.6 30470.5 31933.7 32150.0 32312.0 32671.5 21873.0 21925.0 22244.1 22372.6 22459.8 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:35293.72 Median:3347.82 stddev:99312.37 (2966.5%) stddev-sans-first:99556.33 02:04:13 INFO - PROCESS | 1664 | Values: 365.5 386.3 386.7 400.2 407.1 13951.9 19387.7 19494.6 19734.7 19742.8 19083.8 19447.8 19868.3 19944.5 20547.0 5290.4 5395.6 5506.1 5512.6 5513.7 5297.1 5524.9 5537.4 5546.9 5548.1 5489.0 5530.7 5533.8 5675.7 6042.9 5830.7 5991.6 6019.1 6026.2 6036.2 4534.5 5275.3 5330.5 5340.8 5346.5 25117.7 25575.7 26803.5 27276.5 28300.4 2013.3 2101.1 2121.7 2366.7 2435.0 15357.4 15786.0 15789.5 16420.5 16626.4 1775.4 1876.7 1889.3 1925.7 1997.1 117529.4 122050.0 122734.0 123326.0 123582.0 15668.1 16985.2 20269.0 20494.9 20544.9 34494.1 34518.0 34565.3 34603.3 34633.7 10558.2 11092.2 11135.4 11146.7 11255.0 9017.0 9102.5 9107.1 9174.6 9188.4 9531.0 9595.5 9696.2 9697.8 9720.8 3105.3 3337.8 3357.8 3531.3 3630.7 884.8 918.2 919.5 950.3 998.7 16048.8 19358.5 19737.0 19854.7 19912.3 876.4 961.9 976.8 987.5 997.2 10405.4 10471.5 10607.5 10714.0 10776.9 534517.0 603531.0 608586.0 611244.8 627750.0 110711.4 112933.0 115134.0 115775.0 117063.0 969.0 988.8 999.3 1003.6 1009.0 13421.8 17336.8 17507.6 17562.9 17644.6 47249.1 47480.3 47973.7 48322.8 49111.5 66495.7 68677.7 70120.9 71080.0 72725.0 6543.2 7666.1 7945.8 8105.3 8155.3 11159.5 12128.2 12451.6 12559.7 12622.1 10478.7 10569.2 10743.3 11068.4 11212.9 7275.0 10645.1 10839.8 11073.6 11121.8 9795.3 10871.5 11627.8 11715.1 11874.0 7990.4 8170.0 8271.8 8305.5 8645.0 8388.0 10498.0 10662.2 10969.0 11078.7 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5670.03 Median:2660.37 stddev:3184.03 (119.7%) stddev-sans-first:3177.08 02:04:13 INFO - PROCESS | 1664 | Values: 1438.5 1611.9 1619.0 1859.4 1978.6 7841.4 8622.6 8842.3 8845.4 8860.0 8398.7 8654.1 8671.8 8672.8 8682.8 2145.2 2367.9 2431.4 2468.9 2471.2 2477.4 2505.2 2505.5 2510.5 2511.9 2406.2 2472.7 2475.7 2476.3 2480.1 2128.4 2213.9 2214.2 2214.6 2408.6 2368.4 2461.0 2466.8 2468.1 2475.0 7410.4 8628.2 8728.2 8743.0 8745.7 5168.6 5366.0 5369.7 5406.3 5409.1 3302.3 3409.8 3485.8 3496.9 3513.5 785.4 988.2 1006.0 1008.5 1011.7 11551.9 11620.9 11620.9 11697.5 11720.8 4785.5 4792.5 4811.0 4829.9 4833.8 9968.3 10000.9 10010.4 10011.4 10013.4 5362.4 5435.4 5445.6 5446.6 5451.3 4359.7 4410.5 4572.4 4594.2 4952.3 3246.9 3374.5 3917.6 4007.4 4034.8 1332.0 1340.4 1349.2 1352.9 1373.3 783.3 876.9 913.8 933.1 939.0 5738.3 5861.7 5987.4 6021.3 6111.6 419.2 467.5 479.9 486.9 488.0 3889.8 3983.2 3990.5 4080.0 4106.3 8997.9 10623.4 10827.9 10828.7 10858.4 10801.3 10813.9 10819.0 10819.9 10839.8 6331.5 8045.9 8132.3 8225.9 8243.0 5910.8 5919.9 6046.3 6057.9 6088.6 9022.8 9656.0 9707.5 10008.9 10133.4 9504.6 9819.2 10088.6 10221.3 10258.4 6869.5 7123.1 7426.9 7508.8 7685.8 8403.8 8404.8 8503.6 8507.9 8512.8 7176.0 7262.4 7445.7 7549.5 7631.1 7209.2 7235.0 7239.4 7312.9 7390.1 6981.3 7023.4 7059.5 7069.0 7076.1 4475.5 4815.2 4826.6 4870.8 5096.4 4980.5 5014.5 5027.1 5087.5 5257.0 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4556.34 Median:5065.97 stddev:1460.09 (28.8%) stddev-sans-first:1429.07 02:04:13 INFO - PROCESS | 1664 | Values: 315.6 318.3 323.5 323.8 327.5 4801.5 4831.8 5216.9 5237.3 5289.3 4805.4 4827.4 5237.5 5238.9 5271.5 3166.0 3832.7 3890.3 3995.1 4026.8 3947.1 3997.6 4014.3 4015.9 4042.7 3494.7 3831.7 3904.1 3958.6 3993.4 3016.9 3211.8 3288.7 3628.9 3882.2 2974.4 2984.9 3527.0 3561.6 3580.8 4178.4 5034.2 5052.0 5052.2 5092.3 3891.8 3975.5 4609.8 4621.6 4629.4 4639.5 4729.3 5393.0 5429.7 5479.2 3403.6 3466.5 3920.1 3925.8 3945.9 4774.9 5763.5 5776.4 5781.6 5794.2 3596.9 3739.6 3967.7 3967.8 4018.9 3376.1 3534.8 3720.6 4021.4 4031.2 4076.3 4599.4 4618.4 4618.6 4642.8 4281.6 5214.5 5540.0 5547.8 5559.7 1217.7 1416.1 1417.3 1427.4 1430.5 830.8 845.9 887.8 940.5 947.5 4743.4 5079.7 5125.3 5552.7 5570.2 4895.7 5561.3 5582.9 5616.5 5796.8 4308.0 4558.3 4611.4 5016.6 5124.3 2767.3 3092.7 3262.9 3286.7 3294.5 5962.9 6467.3 6485.7 6490.3 6494.8 6107.0 6416.6 6417.1 6421.9 6543.1 5654.8 6399.2 6428.6 6440.9 6464.6 5477.8 5713.5 5882.6 6189.5 6221.8 5197.7 6033.0 6156.0 6180.3 6203.3 5459.9 5993.9 6452.2 6457.6 6502.8 3805.7 3987.7 4006.6 4214.0 4287.5 5379.8 5615.8 5649.5 5662.9 5669.8 5139.5 5208.3 5497.8 5500.2 5504.1 4707.2 5257.4 5454.2 5583.5 5606.4 5428.9 5459.9 5473.3 5474.1 5482.4 4814.7 5168.2 5339.4 5370.8 5414.4 4591.9 5396.1 5417.6 5433.7 5442.8 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18010.62 Median:617.48 stddev:25774.50 (4174.2%) stddev-sans-first:25813.96 02:04:13 INFO - PROCESS | 1664 | Values: 681.4 729.6 733.5 742.3 756.5 14517.2 14971.7 14996.6 15005.8 15021.0 13677.2 13772.2 13792.5 14880.3 15477.6 923.2 936.7 936.8 937.6 939.5 904.3 925.1 925.4 925.7 928.5 982.2 984.5 985.0 987.1 988.0 986.7 987.0 988.0 989.7 991.6 731.6 731.9 732.5 733.3 738.0 15045.5 15089.4 15112.7 15117.3 15127.0 12255.0 12259.5 12306.8 12350.9 12856.7 8395.0 9944.5 10106.9 10140.2 10457.7 3434.5 3509.2 3621.3 3695.9 3703.9 47139.3 65876.7 73010.3 73658.3 77863.0 9310.8 9314.7 9336.9 9560.1 9738.0 16979.7 20266.2 20463.3 20527.5 20835.5 7259.9 7262.2 7291.5 7482.3 7633.6 3915.4 3919.9 4035.6 4056.1 4231.7 3292.8 3370.9 3573.7 3833.6 3834.3 675.4 683.3 691.1 694.2 696.6 498.6 583.9 591.5 595.5 607.0 7744.6 7917.7 8164.6 8214.9 8336.3 580.0 596.8 609.5 625.5 628.3 3795.4 3903.6 3930.4 3958.2 3981.1 94351.0 101240.0 101671.0 101703.0 103292.5 79672.6 80871.6 82324.6 82735.0 83351.6 63151.0 68793.7 69344.7 71536.3 74600.0 7657.9 7786.1 7845.2 7855.4 8061.0 47129.3 47384.0 47431.5 47441.0 47529.0 63222.2 64124.4 67674.7 69544.3 70324.9 10235.8 10235.9 10302.5 10398.3 10693.9 11270.1 11356.7 11607.3 11818.1 12331.6 10612.2 11031.2 11078.4 11433.7 11641.8 10833.3 11029.7 11039.9 11084.9 11085.7 10453.5 10561.6 10802.2 10930.7 11423.3 9095.8 9213.7 9305.3 9402.9 9574.7 8602.7 8915.4 9107.5 9143.4 9325.6 02:04:13 INFO - PROCESS | 1664 | -------- Summary: end -------- 02:04:13 INFO - PROCESS | 1664 | 02:04:13 INFO - TEST-INFO | 1664: exit 0 02:04:13 INFO - TEST-OK | dromaeo_css | took 1115028ms 02:04:13 INFO - TEST-START | kraken 02:04:13 INFO - operating with platform_type : mac_ 02:04:13 INFO - Initialising browser for kraken test... 02:04:13 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmplpcIVm/profile http://localhost:49275/getInfo.html) 02:04:16 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 02:04:16 INFO - PROCESS | 1674 | colorDepth:24 02:04:16 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 02:04:16 INFO - PROCESS | 1674 | __metrics 02:04:16 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:04:16 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:04:16 INFO - TEST-INFO | 1674: exit 0 02:04:16 INFO - Browser initialized. 02:04:16 INFO - Running cycle 1/1 for kraken test... 02:04:16 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmplpcIVm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:04:28 INFO - PROCESS | 1676 | RSS: Main: 239550464 02:04:28 INFO - PROCESS | 1676 | 02:04:31 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 02:04:32 INFO - PROCESS | 1676 | RSS: Main: 287629312 02:04:32 INFO - PROCESS | 1676 | 02:04:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 02:04:35 INFO - PROCESS | 1676 | RSS: Main: 299925504 02:04:35 INFO - PROCESS | 1676 | 02:04:39 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 02:04:40 INFO - PROCESS | 1676 | RSS: Main: 384704512 02:04:40 INFO - PROCESS | 1676 | 02:04:42 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 02:04:42 INFO - PROCESS | 1676 | RSS: Main: 288940032 02:04:42 INFO - PROCESS | 1676 | 02:04:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:04:45 INFO - PROCESS | 1676 | RSS: Main: 292384768 02:04:45 INFO - PROCESS | 1676 | 02:04:48 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 02:04:48 INFO - PROCESS | 1676 | RSS: Main: 330457088 02:04:48 INFO - PROCESS | 1676 | 02:04:51 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 02:04:52 INFO - PROCESS | 1676 | RSS: Main: 330756096 02:04:52 INFO - PROCESS | 1676 | 02:04:55 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 02:04:55 INFO - PROCESS | 1676 | RSS: Main: 329793536 02:04:55 INFO - PROCESS | 1676 | 02:04:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:04:58 INFO - PROCESS | 1676 | RSS: Main: 322105344 02:04:58 INFO - PROCESS | 1676 | 02:05:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:05:00 INFO - PROCESS | 1676 | RSS: Main: 328417280 02:05:00 INFO - PROCESS | 1676 | 02:05:02 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:05:03 INFO - PROCESS | 1676 | RSS: Main: 330764288 02:05:03 INFO - PROCESS | 1676 | 02:05:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:05:07 INFO - PROCESS | 1676 | RSS: Main: 328077312 02:05:07 INFO - PROCESS | 1676 | 02:05:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:05:11 INFO - PROCESS | 1676 | RSS: Main: 328691712 02:05:11 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 02:05:13 INFO - PROCESS | 1676 | RSS: Main: 392921088 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | __start_tp_report 02:05:13 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 02:05:13 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 02:05:13 INFO - PROCESS | 1676 | |i|pagename|runs| 02:05:13 INFO - PROCESS | 1676 | |0;ai-astar;112;111;111;162;161;112;112;113;105;111 02:05:13 INFO - PROCESS | 1676 | |1;audio-beat-detection;184;148;172;169;119;145;140;122;170;118 02:05:13 INFO - PROCESS | 1676 | |2;audio-dft;187;186;185;184;181;183;184;182;184;183 02:05:13 INFO - PROCESS | 1676 | |3;audio-fft;76;70;76;148;56;58;64;64;137;54 02:05:13 INFO - PROCESS | 1676 | |4;audio-oscillator;92;92;140;76;80;88;122;83;91;161 02:05:13 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;91;87;92;88;90;87;91;88;90;86 02:05:13 INFO - PROCESS | 1676 | |6;imaging-darkroom;141;107;95;95;95;95;96;94;95;95 02:05:13 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;84;83;81;84;84;85;84;84;84 02:05:13 INFO - PROCESS | 1676 | |8;json-parse-financial;58;58;58;59;81;87;58;77;59;57 02:05:13 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;64;69;63;69;72;72;67;69;67 02:05:13 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;84;82;82;82;81;81;80;82;82;83 02:05:13 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;144;140;137;137;138;139;149;162;180;139 02:05:13 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;196;199;209;198;238;196;202;219;263;267 02:05:13 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;59;88;100;69;74;83;101;101;100;70 02:05:13 INFO - PROCESS | 1676 | __end_tp_report 02:05:13 INFO - PROCESS | 1676 | __start_cc_report 02:05:13 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2046 02:05:13 INFO - PROCESS | 1676 | __end_cc_report 02:05:13 INFO - PROCESS | 1676 | __startTimestamp1461834313308__endTimestamp 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | ------- Summary: start ------- 02:05:13 INFO - PROCESS | 1676 | Number of tests: 14 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:121.00 Median:112.00 stddev:21.46 (19.2%) stddev-sans-first:22.51 02:05:13 INFO - PROCESS | 1676 | Values: 112.0 111.0 111.0 162.0 161.0 112.0 112.0 113.0 105.0 111.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:148.70 Median:158.50 stddev:24.23 (15.3%) stddev-sans-first:22.07 02:05:13 INFO - PROCESS | 1676 | Values: 184.0 148.0 172.0 169.0 119.0 145.0 140.0 122.0 170.0 118.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.79 (1.0%) stddev-sans-first:1.51 02:05:13 INFO - PROCESS | 1676 | Values: 187.0 186.0 185.0 184.0 181.0 183.0 184.0 182.0 184.0 183.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:80.30 Median:64.00 stddev:33.75 (52.7%) stddev-sans-first:35.76 02:05:13 INFO - PROCESS | 1676 | Values: 76.0 70.0 76.0 148.0 56.0 58.0 64.0 64.0 137.0 54.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:85.50 stddev:28.60 (33.4%) stddev-sans-first:30.08 02:05:13 INFO - PROCESS | 1676 | Values: 92.0 92.0 140.0 76.0 80.0 88.0 122.0 83.0 91.0 161.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:90.00 stddev:2.05 (2.3%) stddev-sans-first:2.05 02:05:13 INFO - PROCESS | 1676 | Values: 91.0 87.0 92.0 88.0 90.0 87.0 91.0 88.0 90.0 86.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:95.00 stddev:14.63 (15.4%) stddev-sans-first:4.03 02:05:13 INFO - PROCESS | 1676 | Values: 141.0 107.0 95.0 95.0 95.0 95.0 96.0 94.0 95.0 95.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.14 (1.4%) stddev-sans-first:1.12 02:05:13 INFO - PROCESS | 1676 | Values: 85.0 84.0 83.0 81.0 84.0 84.0 85.0 84.0 84.0 84.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:59.00 stddev:11.62 (19.7%) stddev-sans-first:12.03 02:05:13 INFO - PROCESS | 1676 | Values: 58.0 58.0 58.0 59.0 81.0 87.0 58.0 77.0 59.0 57.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:69.00 stddev:3.34 (4.8%) stddev-sans-first:3.12 02:05:13 INFO - PROCESS | 1676 | Values: 63.0 64.0 69.0 63.0 69.0 72.0 72.0 67.0 69.0 67.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.00 stddev:1.10 (1.3%) stddev-sans-first:0.87 02:05:13 INFO - PROCESS | 1676 | Values: 84.0 82.0 82.0 82.0 81.0 81.0 80.0 82.0 82.0 83.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:142.00 stddev:14.07 (9.9%) stddev-sans-first:14.90 02:05:13 INFO - PROCESS | 1676 | Values: 144.0 140.0 137.0 137.0 138.0 139.0 149.0 162.0 180.0 139.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:218.70 Median:214.00 stddev:27.66 (12.9%) stddev-sans-first:28.10 02:05:13 INFO - PROCESS | 1676 | Values: 196.0 199.0 209.0 198.0 238.0 196.0 202.0 219.0 263.0 267.0 02:05:13 INFO - PROCESS | 1676 | 02:05:13 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.50 Median:69.50 stddev:15.81 (22.8%) stddev-sans-first:13.82 02:05:13 INFO - PROCESS | 1676 | Values: 59.0 88.0 100.0 69.0 74.0 83.0 101.0 101.0 100.0 70.0 02:05:13 INFO - PROCESS | 1676 | -------- Summary: end -------- 02:05:13 INFO - PROCESS | 1676 | 02:05:14 INFO - TEST-INFO | 1676: exit 0 02:05:14 INFO - TEST-OK | kraken | took 60203ms 02:05:14 INFO - SUITE-END | took 1175s 02:05:14 INFO - Completed test suite (00:19:35) 02:05:14 INFO - javascript benchmark 02:05:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2816.3663175785446, 3362.0922350965257, 3379.729621630269, 3384.830758462076, 3443.3285509325674, 5445.673259604417, 5514.420985796493, 5523.475269849363, 5537.889242215158, 5571.164325351199, 5511.9724401378035, 5548.833534993957, 5614.9438505614935, 5664.320281566207, 5685.658860468375, 4109.938350924738, 4993.525615066563, 5017.247412888059, 5058.064258112261, 5162.896742065156, 4297.73216901015, 5083.84748457545, 5089.821856235032, 5109.744512774365, 5178.171492561191, 4934.333864928225, 5026.321446604698, 5032.874178145557, 5036.697798132123, 5120.692758434488, 4068.5524592294823, 5036.043151801183, 5042.067217564762, 5052.0, 5111.923321150186, 5004.299398084271, 5004.499550044967, 5019.096562618727, 5019.222020586815, 5033.899322013539, 5449.509544141047, 5457.2359869618285, 5478.506934375925, 5482.5613950883835, 5482.835514934558, 5853.09277062056, 5937.732802023919, 5958.285005799288, 5962.1951036609935, 5984.581079324449, 6104.481119104881, 6128.295246046717, 6147.415995480422, 6161.7227224775, 6183.598066125687, 5625.943740562583, 5632.324121105412, 5634.126710359901, 5679.403662615448, 5696.544276457915, 6119.112728654409, 6160.476359509402, 6189.535784816067, 6264.2796078451565, 6264.686765661699, 5881.529477641779, 5928.140419639129, 5941.465268125889, 5993.340732519419, 6004.939950600526, 6024.367441418588, 6025.0, 6040.697965101727, 6057.242844644419, 6060.151578778975, 5918.319393269743, 5989.101634754822, 5998.1002849572915, 6033.215681961317, 6034.487068599218, 5958.374370691102, 6009.849753756103, 6014.518838492998, 6026.939730602726, 6074.75700971965, 4801.327814106028, 4813.989062296957, 4822.758862056883, 4829.5653391194965, 4865.683730557503, 4266.893327666771, 4349.630281426051, 4375.190589740908, 4395.53846846083, 4396.900774806299, 5454.10007348791, 5521.475459831309, 5530.5022547970875, 5553.111502159635, 5569.832905012856, 6117.143599896018, 6155.045967874987, 6169.722362493699, 6176.722047507783, 6185.288691800411, 5552.139418390156, 5558.86102847424, 5571.359293681197, 5591.4688104629995, 5594.272744543265, 5094.61790365724, 5190.117679994334, 5241.135212689864, 5282.709450980233, 5311.442298558596, 6270.278917924497, 6290.245170579468, 6296.779612713533, 6304.306526282197, 6308.842278943063, 6154.522914500398, 6168.290646575521, 6200.844978875564, 6203.379662033941, 6206.131141640084, 6157.138000679819, 6172.753089876354, 6266.968665156645, 6286.52851036165, 6292.4651404629285, 5946.108083787293, 5977.043673012297, 5982.0727787193055, 5993.430624090704, 6046.909296360646, 5803.245578074823, 5985.670788106696, 6078.787242446493, 6135.84660383494, 6139.416755408229, 5745.6552606843725, 5849.356570777126, 5935.614185077956, 5940.3168635605725, 5990.880182396415, 4480.372747815373, 5906.468417842243, 5910.881782364243, 5912.46787789101, 5940.0, 4800.183968725395, 5589.357223919302, 5751.884962300647, 5777.075667893117, 5807.041838096836, 5824.95150872847, 5838.1826544282985, 5859.912101318569, 5940.227770389822, 5941.970290148522, 5730.742116604846, 5755.740991655469, 5764.135379692912, 5767.711614419346, 5792.275965504312], "value": 5522.765775248306, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.0371527379247, 508.2883962452567, 522.8117877564076, 526.1686535274267, 563.3408911573458, 24264.39339016523, 29848.850755746193, 29861.55207671887, 29898.402031959347, 29914.0, 37628.623713762856, 38229.42655860164, 38308.616913830854, 38317.42523862144, 38365.616343836555, 9292.488913109777, 9509.857352139723, 9510.762230944212, 9519.714408567754, 9528.618855245782, 9281.164695177433, 9447.338686291961, 9452.8582071269, 9458.668946586871, 9466.574004169828, 9437.197838183763, 9478.71563853082, 9497.192738617226, 9603.279754018477, 9612.903870961305, 9512.239020878347, 9582.37714548559, 9593.808123837554, 9595.944446110958, 9595.952020239854, 8196.590170491452, 9472.715818525456, 9580.0898914603, 9589.616415343378, 9594.13652771247, 38627.0, 38955.805220974, 39003.41494877579, 39033.0, 39169.216615667814, 3391.8812841550675, 3449.999500144955, 3759.8684046058256, 3764.4353346997896, 3902.590228026073, 27755.167344979684, 28141.718582814116, 28933.56599650986, 29077.273068173254, 29117.12648620545, 2641.841489510616, 2687.516247075526, 2723.795715321359, 2748.560230363152, 2793.0922450203766, 362514.0, 366058.3394166051, 366135.0, 367346.0, 368047.31952680397, 61170.38829611736, 61382.69308653428, 61799.38200617936, 61995.76008479805, 62062.689686551275, 62933.74132517324, 63091.0, 63295.0, 63342.0, 63351.049734254026, 16569.42007029748, 16783.496495105166, 16791.0, 16793.83206167947, 16798.160091995596, 12913.418896149697, 12979.351032448529, 12998.220106793622, 12998.740025199631, 13056.151350162208, 14142.858571414154, 17236.310547577956, 17250.051247181527, 17295.048772317645, 17310.047947363015, 6941.965290173517, 6947.3747362736585, 6958.65206739661, 6962.825929351807, 7024.121984751906, 875.5490922175085, 963.3352986439335, 988.4267125067587, 1015.2639336480992, 1047.3820445936933, 24973.750262497506, 27790.589030475385, 27861.0, 27973.860130699217, 28250.011249606167, 697.8778713725078, 1029.8146333660013, 1064.7125276175389, 1079.0073132717919, 1134.7390100276818, 13803.861961380459, 13813.79279310791, 14198.858011419754, 14242.359093840802, 14387.856121438861, 468443.0, 471752.0, 473431.0, 474608.0, 474644.0, 56762.7161864188, 56888.71555642278, 56979.0, 57067.42932570621, 57129.714351427974, 1421.5522110535148, 1449.7607894697258, 1482.7553453680023, 1498.9956728991601, 1499.475183685723, 28317.336532693957, 28738.568921465776, 28825.711742882304, 29117.417651647273, 29306.56040159442, 44974.0, 45577.77211113923, 45661.0, 45663.31505027361, 45707.08585828331, 300956.0, 301566.0, 302584.4870775632, 302985.0, 303634.0, 31042.68957310398, 38269.617303826606, 38592.0, 38882.22235555216, 39649.80175099106, 43203.0, 44262.11475770531, 44587.0, 44943.77528112339, 45701.08597828091, 35282.647173527934, 36562.45156322604, 36906.26187476182, 37223.44164837584, 37396.6260337404, 30053.549196762502, 35529.467057993636, 35975.640243598275, 35981.460278096376, 36859.447108293934, 29623.851880740458, 35512.28975420529, 36342.63657363393, 36548.63451365559, 36551.634483655886, 30470.542941855445, 31933.68066319307, 32150.0, 32312.030639080866, 32671.509927350635, 21873.0, 21924.963436837428, 22244.11023559104, 22372.552548948603, 22459.77540224642], "value": 18637.220954251174, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.5156917084863, 386.3142921314667, 386.74281602734186, 400.21757464157577, 407.1328071208326, 13951.930240348796, 19387.70918436221, 19494.610107797835, 19734.70397944032, 19742.802571974276, 19083.809161908375, 19447.805521944778, 19868.304609338677, 19944.50138746532, 20546.972651367447, 5290.39160496543, 5395.622306438551, 5506.146547285177, 5512.586556008296, 5513.724313784315, 5297.099493086165, 5524.861878453051, 5537.418571050042, 5546.861328466801, 5548.140038294071, 5489.0, 5530.668159910399, 5533.833984980457, 5675.659460432387, 6042.9395706042815, 5830.688095178572, 5991.610545314584, 6019.097135429699, 6026.156338112624, 6036.245469316335, 4534.478534968488, 5275.285532202011, 5330.493603107699, 5340.759665815048, 5346.518813306821, 25117.748822511723, 25575.74424255752, 26803.46393072147, 27276.45447091067, 28300.433991320057, 2013.2752209204675, 2101.107029512451, 2121.692354608573, 2366.7041619797524, 2434.977309529984, 15357.385704571803, 15786.0, 15789.526314210478, 16420.507384778357, 16626.418075367426, 1775.4052392448546, 1876.7091100879386, 1889.310401703382, 1925.749652545174, 1997.0913234478278, 117529.41235293768, 122050.0, 122734.0, 123326.0, 123581.9999999991, 15668.138252396227, 16985.23566439513, 20268.96782473644, 20494.89752551228, 20544.897275513827, 34494.13764655904, 34518.0, 34565.30869382598, 34603.307933841184, 34633.65366346355, 10558.208134389797, 11092.223544351978, 11135.387553684624, 11146.721331966604, 11254.987051165434, 9017.0, 9102.499362535124, 9107.08929107084, 9174.577940419245, 9188.40275382098, 9531.0, 9595.47224902637, 9696.224302055818, 9697.806043879084, 9720.805583888425, 3105.3013072059057, 3337.8497967591516, 3357.7985320880825, 3531.29374125176, 3630.745847790682, 884.787650963764, 918.2241006349624, 919.5494207838245, 950.3490109275168, 998.7203582996773, 16048.839511604969, 19358.516037098903, 19737.013149342474, 19854.70217946732, 19912.303069392503, 876.421561769242, 961.9086186812242, 976.8070681320481, 987.4914419074182, 997.2470784557613, 10405.375677459226, 10471.47642617866, 10607.469626518643, 10713.985033899005, 10776.892231077745, 534517.0, 603531.0, 608586.0, 611244.8313275304, 627750.0, 110711.44644276888, 112933.0, 115134.0, 115775.0, 117063.0, 968.9660861869801, 988.7923536057508, 999.3204620857741, 1003.603576587244, 1008.9949550252202, 13421.798673019717, 17336.826631733522, 17507.56231094233, 17562.912185438992, 17644.647107058045, 47249.05501889874, 47480.287795683784, 47973.695519642184, 48322.75838620925, 49111.508884912124, 66495.66752166208, 68677.65661171662, 70120.94818577623, 71080.0, 72725.0, 6543.1821022372205, 7666.080070391588, 7945.801354966172, 8105.311048560939, 8155.266026057683, 11159.49782259784, 12128.151029428209, 12451.564195253122, 12559.748805024032, 12622.053345998904, 10478.738031548968, 10569.154467642416, 10743.301685390425, 11068.446577670924, 11212.943935280598, 7274.96362518184, 10645.148388129086, 10839.764774009363, 11073.557057717924, 11121.777564448828, 9795.265355098538, 10871.510782014986, 11627.825582616098, 11715.121365897421, 11874.0, 7990.400719946144, 8170.0, 8271.83456330858, 8305.46014509055, 8644.956775216084, 8387.95806020966, 10498.0, 10662.200334974752, 10969.0, 11078.723031924266], "value": 10238.483215247135, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.4749566408252, 1611.8549330560252, 1619.0043123479059, 1859.39569639867, 1978.5943881504284, 7841.411894107943, 8622.611982460789, 8842.336824738139, 8845.425047371933, 8859.981102173251, 8398.748037558868, 8654.134586541342, 8671.826563468727, 8672.826543469142, 8682.782930426742, 2145.2491627930176, 2367.9408014799683, 2431.4407686232175, 2468.8643224116927, 2471.1845091119885, 2477.4054226985486, 2505.2233807519638, 2505.473850491399, 2510.548101341767, 2511.9246422607353, 2406.2179791567723, 2472.7032756069298, 2475.7276699563035, 2476.2942561370014, 2480.082369523279, 2128.4146859613575, 2213.867167969927, 2214.169686367612, 2214.579229946305, 2408.6025805742033, 2368.3723813189517, 2460.950780984388, 2466.839655422392, 2468.0868078810777, 2475.009996001596, 7410.37011853993, 8628.180322869317, 8728.214460698568, 8742.994555626057, 8745.693900713444, 5168.638195326329, 5366.034113859504, 5369.731513424314, 5406.270153529263, 5409.107497262986, 3302.339532093591, 3409.7783644063306, 3485.7559970802054, 3496.8984769797435, 3513.54323937889, 785.4148659248896, 988.2242439684813, 1005.9798804023952, 1008.4907121903392, 1011.7066050845247, 11551.884481155164, 11620.883791162065, 11620.941895290469, 11697.473613687489, 11720.824187637192, 4785.507759485422, 4792.520747925179, 4810.95189048105, 4829.903401932012, 4833.806647734122, 9968.252381071448, 10000.899991000142, 10010.399376037461, 10011.399316041061, 10013.449260290608, 5362.410134885159, 5435.429279925551, 5445.591580631469, 5446.645968012067, 5451.264079349237, 4359.651227901761, 4410.470743510799, 4572.3827283317, 4594.241950078266, 4952.331435256267, 3246.902592922216, 3374.4600863861665, 3917.6082391761165, 4007.4189242560246, 4034.8386064557676, 1331.9866801332057, 1340.4303171152417, 1349.1837438349853, 1352.9120607160503, 1373.3339330424735, 783.2559068884468, 876.9386142969931, 913.7852604637905, 933.1275257633998, 938.9671361502315, 5738.254026976467, 5861.736221870026, 5987.43119403656, 6021.314475790503, 6111.602745821508, 419.20978954670056, 467.49277034417355, 479.8608403562997, 486.93913260842396, 488.0483058036771, 3889.8055097245024, 3983.24318379507, 3990.521137463465, 4079.9592004080173, 4106.260873042821, 8997.865032024525, 10623.415712135753, 10827.945860270649, 10828.675139745821, 10858.402787846744, 10801.297915635458, 10813.91860813928, 10819.026287634151, 10819.891801082045, 10839.783204335869, 6331.5251356147555, 8045.879311810328, 8132.268095871282, 8225.930629018309, 8242.958785206156, 5910.822675319747, 5919.85200369977, 6046.334903160737, 6057.939420605738, 6088.6042407243385, 9022.774430639287, 9655.986121457147, 9707.514624268899, 10008.899911000797, 10133.391996480235, 9504.619815207314, 9819.165370943265, 10088.646897358558, 10221.28451008452, 10258.43578603184, 6869.484788640772, 7123.145222573325, 7426.851462970819, 7508.812279693051, 7685.846283074419, 8403.789905252417, 8404.831903362021, 8503.617337219715, 8507.8723819144, 8512.829743405222, 7176.0, 7262.3826974707745, 7445.739399121005, 7549.50928189666, 7631.122420921443, 7209.243029481829, 7235.0, 7239.384652304613, 7312.890306645511, 7390.076240469942, 6981.3367730065565, 7023.438124950094, 7059.505834591735, 7068.964655176691, 7076.080109585927, 4475.507694153705, 4815.2054910939305, 4826.613870890391, 4870.805167793249, 5096.362954630671, 4980.501949805135, 5014.473480284518, 5027.120253955616, 5087.465816089405, 5257.00116977773], "value": 4492.512890382479, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.59918902993195, 318.2648082928434, 323.46628063694885, 323.7604172912046, 327.5136422412715, 4801.543853333937, 4831.782569784359, 5216.8695782605455, 5237.345331833526, 5289.338832645919, 4805.399325084364, 4827.396575428072, 5237.45006774289, 5238.895222095565, 5271.525562699357, 3166.002709146623, 3832.6550610445056, 3890.319194141028, 3995.1422588496343, 4026.8389264429534, 3947.0921688011776, 3997.6002399759936, 4014.2774300625874, 4015.8795236142964, 4042.70633397313, 3494.6680065393875, 3831.7430184343366, 3904.1020565270005, 3958.5645578986287, 3993.381025940984, 3016.894408695707, 3211.759118066131, 3288.6875746804017, 3628.909277268063, 3882.1847412043503, 2974.3902499987666, 2984.85075746214, 3526.982365088184, 3561.554805649294, 3580.838862251205, 4178.415021896937, 5034.194528875362, 5052.0, 5052.242163675442, 5092.337996060525, 3891.7935440013403, 3975.52293724752, 4609.83865564707, 4621.584057434847, 4629.421322334708, 4639.512851150648, 4729.314249433813, 5392.973035134838, 5429.724014856505, 5479.205515200274, 3403.5745531808525, 3466.4973578830927, 3920.09837737322, 3925.8222533239914, 3945.8816235512695, 4774.880627984294, 5763.4812866842185, 5776.422357764232, 5781.566382521328, 5794.246747922743, 3596.8669868991183, 3739.6447337502896, 3967.7222594418113, 3967.781772002568, 4018.8995275118355, 3376.071580315433, 3534.770239934396, 3720.5907350191455, 4021.3565829467143, 4031.1937612477623, 4076.286649836267, 4599.379083823641, 4618.35343051973, 4618.56123668251, 4642.817875598464, 4281.636060934856, 5214.530692237717, 5539.972300138474, 5547.77808887648, 5559.694216818114, 1217.713837248246, 1416.129080615414, 1417.2630232279364, 1427.414759948416, 1430.542226487514, 830.8172202115525, 845.9450135741157, 887.8236336853695, 940.4874343482818, 947.5215016416665, 4743.407074115736, 5079.6952182869145, 5125.333706618116, 5552.7223638817895, 5570.192322113271, 4895.678166894919, 5561.277033985556, 5582.944170558242, 5616.494515493625, 5796.826190178683, 4307.978460107679, 4558.316252562142, 4611.400517932647, 5016.610465842388, 5124.333836601218, 2767.349672826884, 3092.6907309268895, 3262.869485220612, 3286.7041966223155, 3294.522294267366, 5962.880742385128, 6467.320931302145, 6485.740570377132, 6490.286068532458, 6494.805155845238, 6106.969465152646, 6416.615003099736, 6417.133686952295, 6421.903671444838, 6543.149390579194, 5654.802081927113, 6399.2082216979325, 6428.55000149985, 6440.935590644033, 6464.644444555594, 5477.7541208104485, 5713.485786279255, 5882.588218824812, 6189.462320448594, 6221.751129954751, 5197.7141257231215, 6033.01820798271, 6156.0, 6180.258368995569, 6203.348648391854, 5459.890802183854, 5993.850153746017, 6452.225732911893, 6457.644829534421, 6502.837429064311, 3805.6765174960437, 3987.720859539804, 4006.5592784793066, 4213.957860421357, 4287.528371879029, 5379.784808607612, 5615.803446879339, 5649.491545760901, 5662.876421341996, 5669.824898057102, 5139.535232458881, 5208.270842082187, 5497.780088796403, 5500.1749737539685, 5504.128596277285, 4707.199216466197, 5257.447967963309, 5454.236406903116, 5583.497485226349, 5606.439356064361, 5428.9370039384785, 5459.945400545944, 5473.28847249855, 5474.124140137559, 5482.42434544383, 4814.735189564468, 5168.173092305213, 5339.385970613429, 5370.838874833761, 5414.35027796667, 4591.931121033254, 5396.109641909041, 5417.620766546462, 5433.744995826845, 5442.782288708566], "value": 4103.969830347002, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.3697329969776, 729.6096588325247, 733.537871141181, 742.2762806263892, 756.5309508104975, 14517.20155391453, 14971.700565988674, 14996.625084372869, 15005.84994150058, 15020.96378437536, 13677.24775137367, 13772.24252666103, 13792.517261895835, 14880.255987200624, 15477.613059673513, 923.2291036984126, 936.69089200564, 936.7751739582485, 937.5546615357719, 939.4598106088991, 904.3488688144525, 925.1072714830187, 925.421611492817, 925.676013395313, 928.4986107501941, 982.2044144243185, 984.5421878826344, 984.9803003939917, 987.0573602209909, 987.9654212102578, 986.7138529826342, 986.9703908882746, 987.980240395188, 989.7030890732751, 991.6033586565432, 731.5976213082783, 731.8572878288736, 732.5348403763649, 733.2594079979206, 737.959412232327, 15045.548633540902, 15089.39642414302, 15112.697746045127, 15117.319720612599, 15127.0, 12255.0, 12259.509619615204, 12306.753864922652, 12350.938245308806, 12856.742865142738, 8394.958025209897, 9944.453055081967, 10106.949465252701, 10140.239482038876, 10457.68626941193, 3434.4676575130893, 3509.2455122148863, 3621.257642183346, 3695.926081478355, 3703.8518459261595, 47139.33011426954, 65876.67061664708, 73010.26989730088, 73658.26341736621, 77863.0, 9310.813783724288, 9314.72055838326, 9336.859947100798, 9560.091791279818, 9737.951310243403, 16979.660406791794, 20266.18935242574, 20463.28378506745, 20527.4868128298, 20835.479113022295, 7259.9274007259255, 7262.237465066198, 7291.453141014339, 7482.289182527651, 7633.618319084024, 3915.3735402335487, 3919.8824035278985, 4035.616616421435, 4056.14820887611, 4231.746095234295, 3292.785968912061, 3370.8820191293676, 3573.6777392364993, 3833.5783063862955, 3834.328992426314, 675.4056430341268, 683.282553319013, 691.1464341538197, 694.2120693013466, 696.5750891955903, 498.62852175129035, 583.9065749480063, 591.4558606082414, 595.4611076975344, 607.0348146447081, 7744.612769361509, 7917.722879699183, 8164.550949697705, 8214.917850821535, 8336.333093352518, 580.0255570641278, 596.7970889897368, 609.4514936557046, 625.4996003197424, 628.2586547873461, 3795.35478968576, 3903.590123037097, 3930.3711406174875, 3958.1687845552183, 3981.144054028398, 94351.0, 101240.0, 101671.0, 101702.98297016935, 103292.48353758184, 79672.60163699261, 80871.5956420214, 82324.58837705893, 82735.0, 83351.5832420834, 63151.0, 68793.65603171953, 69344.6532767333, 71536.28463715296, 74600.0, 7657.923420765721, 7786.104597971306, 7845.215478452109, 7855.3715702744785, 8060.987079847393, 47129.293060603435, 47384.0, 47431.52568474271, 47441.0, 47529.0, 63222.15560257391, 64124.358756411835, 67674.66162669155, 69544.30455695378, 70324.89025329248, 10235.84646230322, 10235.897641023494, 10302.484875756032, 10398.324108932895, 10693.930606939244, 11270.098392128773, 11356.659300221007, 11607.30356178632, 11818.054555635701, 12331.630051098482, 10612.151027917902, 11031.22781405294, 11078.390688512209, 11433.656990290305, 11641.825372619249, 10833.349999000086, 11029.724256893642, 11039.889601103887, 11084.944575277072, 11085.722856928642, 10453.529591168264, 10561.577536898438, 10802.243842931233, 10930.672079837617, 11423.257488263236, 9095.772605684646, 9213.723588292361, 9305.255579553756, 9402.905970940203, 9574.716469366726, 8602.653554827837, 8915.37592368528, 9107.499087550248, 9143.405678630868, 9325.626974920928], "value": 6856.782063598434, "unit": "score"}], "name": "dromaeo_css", "value": 7146.698608874196, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 111.0, 162.0, 161.0, 112.0, 112.0, 113.0, 105.0, 111.0], "value": 121.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 148.0, 172.0, 169.0, 119.0, 145.0, 140.0, 122.0, 170.0, 118.0], "value": 148.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 185.0, 184.0, 181.0, 183.0, 184.0, 182.0, 184.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 70.0, 76.0, 148.0, 56.0, 58.0, 64.0, 64.0, 137.0, 54.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 140.0, 76.0, 80.0, 88.0, 122.0, 83.0, 91.0, 161.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 92.0, 88.0, 90.0, 87.0, 91.0, 88.0, 90.0, 86.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 107.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 81.0, 84.0, 84.0, 85.0, 84.0, 84.0, 84.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 81.0, 87.0, 58.0, 77.0, 59.0, 57.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 69.0, 63.0, 69.0, 72.0, 72.0, 67.0, 69.0, 67.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 82.0, 82.0, 81.0, 81.0, 80.0, 82.0, 82.0, 83.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 140.0, 137.0, 137.0, 138.0, 139.0, 149.0, 162.0, 180.0, 139.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 209.0, 198.0, 238.0, 196.0, 202.0, 219.0, 263.0, 267.0], "value": 218.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 88.0, 100.0, 69.0, 74.0, 83.0, 101.0, 101.0, 100.0, 70.0], "value": 84.5, "unit": "score"}], "name": "kraken", "value": 1574.3, "alertThreshold": 2.0}]} 02:05:14 INFO - Return code: 0 02:05:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:05:14 ERROR - # TBPL SUCCESS # 02:05:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:05:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:05:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:05:14 INFO - 2016-04-28 02:05:14.605 screenresolution[1680:9679] starting screenresolution argv=screenresolution get 02:05:14 INFO - 2016-04-28 02:05:14.608 screenresolution[1680:9679] Display 0: 1600x1200x32@60 02:05:14 INFO - 2016-04-28 02:05:14.613 screenresolution[1681:9682] starting screenresolution argv=screenresolution list 02:05:14 INFO - Available Modes on Display 0 02:05:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:05:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:05:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:05:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:05:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:05:14 INFO - Intel Iris: 02:05:14 INFO - Chipset Model: Intel Iris 02:05:14 INFO - Type: GPU 02:05:14 INFO - Bus: Built-In 02:05:14 INFO - VRAM (Dynamic, Max): 1536 MB 02:05:14 INFO - Vendor: Intel (0x8086) 02:05:14 INFO - Device ID: 0x0a2e 02:05:14 INFO - Revision ID: 0x0009 02:05:14 INFO - Displays: 02:05:14 INFO - Display: 02:05:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:05:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:05:14 INFO - Display Serial Number: 1600x1200 60 02:05:14 INFO - Main Display: Yes 02:05:14 INFO - Mirror: Off 02:05:14 INFO - Online: Yes 02:05:14 INFO - Rotation: Supported 02:05:14 INFO - Return code: 0 02:05:14 INFO - Running post-action listener: _resource_record_post_action 02:05:14 INFO - Running post-run listener: _resource_record_post_run 02:05:15 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37873152; Write bytes: 694321152; Read time: 399; Write time: 1936 02:05:15 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 396483072; Write bytes: 394483200; Read time: 29887; Write time: 754 02:05:15 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36054528; Write bytes: 295725568; Read time: 379; Write time: 1166 02:05:15 INFO - Running post-run listener: _upload_blobber_files 02:05:15 INFO - Blob upload gear active. 02:05:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:05:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:05:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:05:16 INFO - (blobuploader) - INFO - Open directory for files ... 02:05:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:05:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:05:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:05:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:05:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:05:17 INFO - (blobuploader) - INFO - Done attempting. 02:05:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:05:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:05:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:05:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:05:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:05:18 INFO - (blobuploader) - INFO - Done attempting. 02:05:18 INFO - (blobuploader) - INFO - Iteration through files over. 02:05:18 INFO - Return code: 0 02:05:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:05:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:05:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93459cc39359115149011e472de76a444fe1e9505e7d2bea67c9b31b3940a108f4134baee5d6718e9f0c8e527bab94b519dbe0fbd0452297746d713172132641", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6355e2aa7685ae26a9da280bc0db9e72679614974a05a6749cc5ce39ec4036cdf01317e612f8caa3ce479b677aa394861b48398af0a5b2774b04774fa01e009d"} 02:05:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:05:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:05:18 INFO - Contents: 02:05:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93459cc39359115149011e472de76a444fe1e9505e7d2bea67c9b31b3940a108f4134baee5d6718e9f0c8e527bab94b519dbe0fbd0452297746d713172132641", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6355e2aa7685ae26a9da280bc0db9e72679614974a05a6749cc5ce39ec4036cdf01317e612f8caa3ce479b677aa394861b48398af0a5b2774b04774fa01e009d"} 02:05:18 INFO - Running post-run listener: copy_logs_to_upload_dir 02:05:18 INFO - Copying logs to upload dir... 02:05:18 INFO - mkdir: /builds/slave/test/build/upload/logs 02:05:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.761031 ========= master_lag: 61.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-04-28 02:06:19.536599) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-28 02:06:19.537573) ========= 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.sgQTdRI1zS/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.lD8TufznAg/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/93459cc39359115149011e472de76a444fe1e9505e7d2bea67c9b31b3940a108f4134baee5d6718e9f0c8e527bab94b519dbe0fbd0452297746d713172132641", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6355e2aa7685ae26a9da280bc0db9e72679614974a05a6749cc5ce39ec4036cdf01317e612f8caa3ce479b677aa394861b48398af0a5b2774b04774fa01e009d"} build_url:https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011381 build_url: 'https://queue.taskcluster.net/v1/task/aSbE8NWpRm-5ygFv0w6P9g/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/93459cc39359115149011e472de76a444fe1e9505e7d2bea67c9b31b3940a108f4134baee5d6718e9f0c8e527bab94b519dbe0fbd0452297746d713172132641", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6355e2aa7685ae26a9da280bc0db9e72679614974a05a6749cc5ce39ec4036cdf01317e612f8caa3ce479b677aa394861b48398af0a5b2774b04774fa01e009d"}' ========= master_lag: 1.67 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-28 02:06:21.222773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 02:06:21.223093) ========= 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.sgQTdRI1zS/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.lD8TufznAg/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005155 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 02:06:21.278776) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 02:06:21.279115) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 02:06:21.288175) ========= ========= Total master_lag: 62.85 =========