builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0059 starttime: 1465034286.32 results: success (0) buildid: 20160604004005 builduid: 3bb2275371504e288b069880101f44c1 revision: ab782095ef4ab44dae4cc5f919858c5684837378 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:06.319702) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:06.320102) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:06.320394) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:06.358287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:06.358752) ========= 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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-06-04 02:58:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.84M=0.002s 2016-06-04 02:58:06 (6.84 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.610353 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:06.990602) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:06.990939) ========= 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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.019848 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:07.040291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-04 02:58:07.040682) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ab782095ef4ab44dae4cc5f919858c5684837378 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ab782095ef4ab44dae4cc5f919858c5684837378 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-06-04 02:58:07,114 truncating revision to first 12 chars 2016-06-04 02:58:07,114 Setting DEBUG logging. 2016-06-04 02:58:07,115 attempt 1/10 2016-06-04 02:58:07,115 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ab782095ef4a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 02:58:08,229 unpacking tar archive at: mozilla-aurora-ab782095ef4a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.372745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-04 02:58:08.434397) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:08.434708) ========= script_repo_revision: ab782095ef4ab44dae4cc5f919858c5684837378 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:08.438143) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:08.438437) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 02:58:08.455777) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-06-04 02:58:08.456113) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:58:08 INFO - MultiFileLogger online at 20160604 02:58:08 in /builds/slave/test 02:58:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:58:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:58:08 INFO - {'append_to_log': False, 02:58:08 INFO - 'base_work_dir': '/builds/slave/test', 02:58:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:58:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:58:08 INFO - 'branch': 'Mozilla-Aurora', 02:58:08 INFO - 'buildbot_json_path': 'buildprops.json', 02:58:08 INFO - 'config_files': ('talos/mac_config.py',), 02:58:08 INFO - 'default_actions': ('clobber', 02:58:08 INFO - 'read-buildbot-config', 02:58:08 INFO - 'download-and-extract', 02:58:08 INFO - 'populate-webroot', 02:58:08 INFO - 'create-virtualenv', 02:58:08 INFO - 'install', 02:58:08 INFO - 'run-tests'), 02:58:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:58:08 INFO - 'download_minidump_stackwalk': True, 02:58:08 INFO - 'download_symbols': 'ondemand', 02:58:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:58:08 INFO - 'tooltool.py': '/tools/tooltool.py', 02:58:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:58:08 INFO - '/tools/misc-python/virtualenv.py')}, 02:58:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:58:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:58:08 INFO - 'installer_path': 'installer.exe', 02:58:08 INFO - 'log_level': 'info', 02:58:08 INFO - 'log_name': 'talos', 02:58:08 INFO - 'log_to_console': True, 02:58:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:58:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:58:08 INFO - 'opt_config_files': (), 02:58:08 INFO - 'pip_index': False, 02:58:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:58:08 INFO - 'cmd': ('bash', 02:58:08 INFO - '-c', 02:58:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:58:08 INFO - 'enabled': True, 02:58:08 INFO - 'halt_on_failure': False, 02:58:08 INFO - 'name': 'check_screen_resolution'},), 02:58:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:58:08 INFO - 'cmd': ('bash', 02:58:08 INFO - '-c', 02:58:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:58:08 INFO - 'enabled': True, 02:58:08 INFO - 'halt_on_failure': False, 02:58:08 INFO - 'name': 'check_screen_resolution'},), 02:58:08 INFO - 'run_cmd_checks_enabled': True, 02:58:08 INFO - 'sps_profile': False, 02:58:08 INFO - 'sps_profile_interval': 0, 02:58:08 INFO - 'suite': 'dromaeojs', 02:58:08 INFO - 'system_bits': '32', 02:58:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:58:08 INFO - 'title': 't-yosemite-r7-0059', 02:58:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:58:08 INFO - 'use_talos_json': True, 02:58:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:58:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:58:08 INFO - 'work_dir': 'build'} 02:58:08 INFO - ##### 02:58:08 INFO - ##### Running clobber step. 02:58:08 INFO - ##### 02:58:08 INFO - Running pre-action listener: _resource_record_pre_action 02:58:08 INFO - Running main action method: clobber 02:58:08 INFO - rmtree: /builds/slave/test/build 02:58:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:58:10 INFO - Running post-action listener: _resource_record_post_action 02:58:10 INFO - ##### 02:58:10 INFO - ##### Running read-buildbot-config step. 02:58:10 INFO - ##### 02:58:10 INFO - Running pre-action listener: _resource_record_pre_action 02:58:10 INFO - Running main action method: read_buildbot_config 02:58:10 INFO - Using buildbot properties: 02:58:10 INFO - { 02:58:10 INFO - "project": "", 02:58:10 INFO - "product": "firefox", 02:58:10 INFO - "script_repo_revision": "production", 02:58:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:58:10 INFO - "repository": "", 02:58:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:58:10 INFO - "buildid": "20160604004005", 02:58:10 INFO - "stage_platform": "macosx64", 02:58:10 INFO - "basedir": "/builds/slave/test", 02:58:10 INFO - "nightly_build": "True", 02:58:10 INFO - "buildnumber": 20, 02:58:10 INFO - "slavename": "t-yosemite-r7-0059", 02:58:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:58:10 INFO - "platform": "yosemite_r7", 02:58:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:58:10 INFO - "branch": "mozilla-aurora", 02:58:10 INFO - "repo_path": "releases/mozilla-aurora", 02:58:10 INFO - "revision": "ab782095ef4ab44dae4cc5f919858c5684837378", 02:58:10 INFO - "pgo_build": "False", 02:58:10 INFO - "builduid": "3bb2275371504e288b069880101f44c1", 02:58:10 INFO - "slavebuilddir": "test" 02:58:10 INFO - } 02:58:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:58:10 INFO - Running post-action listener: _resource_record_post_action 02:58:10 INFO - ##### 02:58:10 INFO - ##### Running download-and-extract step. 02:58:10 INFO - ##### 02:58:10 INFO - Running pre-action listener: _resource_record_pre_action 02:58:10 INFO - Running main action method: download_and_extract 02:58:10 INFO - mkdir: /builds/slave/test/build/tests 02:58:10 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')]} 02:58:10 INFO - https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:58:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:58:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:58:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:58:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:58:13 INFO - Downloaded 1199 bytes. 02:58:13 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:58:13 INFO - Using the following test package requirements: 02:58:13 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:58:13 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:58:13 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:58:13 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'jsshell-mac.zip'], 02:58:13 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:58:13 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:58:13 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:58:13 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:58:13 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:58:13 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:58:13 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:58:13 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:58:13 INFO - https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:58:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:58:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:58:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:58:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:58:15 INFO - Downloaded 18466854 bytes. 02:58:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:58:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:58:18 INFO - Return code: 0 02:58:18 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:58:18 INFO - https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:58:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:58:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:58:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:58:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:58:41 INFO - Downloaded 18466854 bytes. 02:58:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:58:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:58:44 INFO - Return code: 0 02:58:44 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')]} 02:58:44 INFO - https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:58:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:58:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:58:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:58:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:58:45 INFO - Downloaded 10840145 bytes. 02:58:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:58:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:58:46 INFO - Return code: 0 02:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:58:46 INFO - https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:58:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:58:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:58:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:58:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:58:57 INFO - Downloaded 114223823 bytes. 02:58:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:58:57 INFO - mkdir: /builds/slave/test/properties 02:58:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:58:57 INFO - Writing to file /builds/slave/test/properties/build_url 02:58:57 INFO - Contents: 02:58:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:58:58 INFO - Running post-action listener: _resource_record_post_action 02:58:58 INFO - Running post-action listener: set_extra_try_arguments 02:58:58 INFO - ##### 02:58:58 INFO - ##### Running populate-webroot step. 02:58:58 INFO - ##### 02:58:58 INFO - Running pre-action listener: _resource_record_pre_action 02:58:58 INFO - Running main action method: populate_webroot 02:58:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:58:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:58:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:58:58 INFO - '--tppagecycles', 02:58:58 INFO - '7'], 02:58:58 INFO - 'tests': ['tsvgm']}}, 02:58:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:58:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:58:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:58:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:58:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:58:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:58:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['damp', 'tps']}, 02:58:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'tests': ['damp', 'tps']}, 02:58:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['dromaeo_dom']}, 02:58:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:58:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['a11yr', 02:58:58 INFO - 'ts_paint', 02:58:58 INFO - 'tpaint', 02:58:58 INFO - 'sessionrestore', 02:58:58 INFO - 'sessionrestore_no_auto_restore', 02:58:58 INFO - 'tabpaint']}, 02:58:58 INFO - 'other-e10s': {'tests': ['a11yr', 02:58:58 INFO - 'ts_paint', 02:58:58 INFO - 'tpaint', 02:58:58 INFO - 'sessionrestore', 02:58:58 INFO - 'sessionrestore_no_auto_restore', 02:58:58 INFO - 'tabpaint']}, 02:58:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:58:58 INFO - 'ts_paint', 02:58:58 INFO - 'tpaint', 02:58:58 INFO - 'sessionrestore', 02:58:58 INFO - 'sessionrestore_no_auto_restore', 02:58:58 INFO - 'tabpaint']}, 02:58:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:58:58 INFO - 'ts_paint', 02:58:58 INFO - 'tpaint', 02:58:58 INFO - 'sessionrestore', 02:58:58 INFO - 'sessionrestore_no_auto_restore', 02:58:58 INFO - 'tabpaint']}, 02:58:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['a11yr', 02:58:58 INFO - 'ts_paint', 02:58:58 INFO - 'tpaint', 02:58:58 INFO - 'sessionrestore', 02:58:58 INFO - 'sessionrestore_no_auto_restore', 02:58:58 INFO - 'tabpaint']}, 02:58:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['a11yr', 02:58:58 INFO - 'ts_paint', 02:58:58 INFO - 'tpaint', 02:58:58 INFO - 'sessionrestore', 02:58:58 INFO - 'sessionrestore_no_auto_restore', 02:58:58 INFO - 'tabpaint']}, 02:58:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['tsvgx', 02:58:58 INFO - 'tsvgr_opacity', 02:58:58 INFO - 'tart', 02:58:58 INFO - 'tscrollx', 02:58:58 INFO - 'cart']}, 02:58:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:58:58 INFO - 'tsvgr_opacity', 02:58:58 INFO - 'tart', 02:58:58 INFO - 'tscrollx', 02:58:58 INFO - 'cart']}, 02:58:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'talos_options': ['--disable-e10s'], 02:58:58 INFO - 'tests': ['tp5o']}, 02:58:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'tests': ['tp5o']}, 02:58:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'talos_options': ['--disable-e10s', 02:58:58 INFO - '--xperf_path', 02:58:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:58:58 INFO - 'tests': ['tp5n']}, 02:58:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:58:58 INFO - 'talos_options': ['--xperf_path', 02:58:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:58:58 INFO - 'tests': ['tp5n']}}, 02:58:58 INFO - 'talos.zip': {'path': '', 02:58:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:58:58 INFO - Running post-action listener: _resource_record_post_action 02:58:58 INFO - ##### 02:58:58 INFO - ##### Running create-virtualenv step. 02:58:58 INFO - ##### 02:58:58 INFO - Running pre-action listener: _resource_record_pre_action 02:58:58 INFO - Running main action method: create_virtualenv 02:58:58 INFO - Creating virtualenv /builds/slave/test/build/venv 02:58:58 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 02:58:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:58:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:58:58 INFO - Using real prefix '/tools/python27' 02:58:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:58:59 INFO - Installing distribute.............................................................................................................................................................................................done. 02:59:02 INFO - Installing pip.................done. 02:59:02 INFO - Return code: 0 02:59:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:02 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:02 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 02:59:02 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 02:59:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:02 INFO - 'HOME': '/Users/cltbld', 02:59:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:02 INFO - 'LOGNAME': 'cltbld', 02:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:02 INFO - 'MOZ_NO_REMOTE': '1', 02:59:02 INFO - 'NO_EM_RESTART': '1', 02:59:02 INFO - 'PAGER': '/bin/cat', 02:59:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:02 INFO - 'PWD': '/builds/slave/test', 02:59:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:02 INFO - 'SHELL': '/bin/bash', 02:59:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:02 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:02 INFO - 'USER': 'cltbld', 02:59:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:02 INFO - 'XPC_FLAGS': '0x0', 02:59:02 INFO - 'XPC_SERVICE_NAME': '0', 02:59:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:02 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 02:59:02 INFO - Cleaning up... 02:59:02 INFO - Return code: 0 02:59:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:02 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:02 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 02:59:02 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 02:59:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:02 INFO - 'HOME': '/Users/cltbld', 02:59:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:02 INFO - 'LOGNAME': 'cltbld', 02:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:02 INFO - 'MOZ_NO_REMOTE': '1', 02:59:02 INFO - 'NO_EM_RESTART': '1', 02:59:02 INFO - 'PAGER': '/bin/cat', 02:59:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:02 INFO - 'PWD': '/builds/slave/test', 02:59:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:02 INFO - 'SHELL': '/bin/bash', 02:59:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:02 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:02 INFO - 'USER': 'cltbld', 02:59:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:02 INFO - 'XPC_FLAGS': '0x0', 02:59:02 INFO - 'XPC_SERVICE_NAME': '0', 02:59:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:02 INFO - Downloading/unpacking psutil>=0.7.1 02:59: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 02:59: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 02:59: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 02:59: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 02:59: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 02:59: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 02:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:59:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:59:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:59:05 INFO - Installing collected packages: psutil 02:59:05 INFO - Running setup.py install for psutil 02:59:05 INFO - building 'psutil._psutil_osx' extension 02:59:05 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 02:59:05 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 02:59:05 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 02:59:06 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 02:59:06 INFO - building 'psutil._psutil_posix' extension 02:59:06 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 02:59:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:59:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:59:06 INFO - ^ 02:59:06 INFO - 1 warning generated. 02:59:06 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 02:59:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:59:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:59:06 INFO - Successfully installed psutil 02:59:06 INFO - Cleaning up... 02:59:06 INFO - Return code: 0 02:59:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:06 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:06 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 02:59:06 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 02:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:06 INFO - 'HOME': '/Users/cltbld', 02:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:06 INFO - 'LOGNAME': 'cltbld', 02:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:06 INFO - 'MOZ_NO_REMOTE': '1', 02:59:06 INFO - 'NO_EM_RESTART': '1', 02:59:06 INFO - 'PAGER': '/bin/cat', 02:59:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:06 INFO - 'PWD': '/builds/slave/test', 02:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:06 INFO - 'SHELL': '/bin/bash', 02:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:06 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:06 INFO - 'USER': 'cltbld', 02:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:06 INFO - 'XPC_FLAGS': '0x0', 02:59:06 INFO - 'XPC_SERVICE_NAME': '0', 02:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:59:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:59:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:59:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:59:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:59:09 INFO - Installing collected packages: mozsystemmonitor 02:59:09 INFO - Running setup.py install for mozsystemmonitor 02:59:09 INFO - Successfully installed mozsystemmonitor 02:59:09 INFO - Cleaning up... 02:59:09 INFO - Return code: 0 02:59:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:09 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:09 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 02:59:09 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 02:59:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:09 INFO - 'HOME': '/Users/cltbld', 02:59:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:09 INFO - 'LOGNAME': 'cltbld', 02:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:09 INFO - 'MOZ_NO_REMOTE': '1', 02:59:09 INFO - 'NO_EM_RESTART': '1', 02:59:09 INFO - 'PAGER': '/bin/cat', 02:59:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:09 INFO - 'PWD': '/builds/slave/test', 02:59:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:09 INFO - 'SHELL': '/bin/bash', 02:59:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:09 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:09 INFO - 'USER': 'cltbld', 02:59:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:09 INFO - 'XPC_FLAGS': '0x0', 02:59:09 INFO - 'XPC_SERVICE_NAME': '0', 02:59:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:09 INFO - Downloading/unpacking blobuploader==1.2.4 02:59:09 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 02:59:09 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 02:59:09 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 02:59:09 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 02:59:09 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 02:59:09 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 02:59:11 INFO - Downloading blobuploader-1.2.4.tar.gz 02:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:59:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:59:12 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 02:59:12 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 02:59:12 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 02:59:12 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 02:59:12 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 02:59:12 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 02:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:59:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:59:12 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 02:59:12 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 02:59:12 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 02:59:12 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 02:59:12 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 02:59:12 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 02:59:13 INFO - Downloading docopt-0.6.1.tar.gz 02:59:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:59:13 INFO - Installing collected packages: blobuploader, requests, docopt 02:59:13 INFO - Running setup.py install for blobuploader 02:59:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:59:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:59:13 INFO - Running setup.py install for requests 02:59:13 INFO - Running setup.py install for docopt 02:59:13 INFO - Successfully installed blobuploader requests docopt 02:59:13 INFO - Cleaning up... 02:59:14 INFO - Return code: 0 02:59:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:14 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:14 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 02:59:14 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 02:59:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:14 INFO - 'HOME': '/Users/cltbld', 02:59:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:14 INFO - 'LOGNAME': 'cltbld', 02:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:14 INFO - 'MOZ_NO_REMOTE': '1', 02:59:14 INFO - 'NO_EM_RESTART': '1', 02:59:14 INFO - 'PAGER': '/bin/cat', 02:59:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:14 INFO - 'PWD': '/builds/slave/test', 02:59:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:14 INFO - 'SHELL': '/bin/bash', 02:59:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:14 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:14 INFO - 'USER': 'cltbld', 02:59:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:14 INFO - 'XPC_FLAGS': '0x0', 02:59:14 INFO - 'XPC_SERVICE_NAME': '0', 02:59:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:59:14 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-jwn7RW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:59:14 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-PBdVAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:59:14 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-jA6VIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-v5V7Bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-52eeVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-TMH2qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-V8dNdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Mi2nY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-BI4i2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-99pXzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-w2e9Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:59:15 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-j_SkZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:59:16 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-i3rdUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:59:16 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-JDtt3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:16 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-GvbxsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:59:16 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-J0sKfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:59:16 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-FAO4wT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:59:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:59:16 INFO - Running setup.py install for manifestparser 02:59:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:59:16 INFO - Running setup.py install for mozcrash 02:59:16 INFO - Running setup.py install for mozdebug 02:59:16 INFO - Running setup.py install for mozdevice 02:59:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Running setup.py install for mozfile 02:59:17 INFO - Running setup.py install for mozhttpd 02:59:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Running setup.py install for mozinfo 02:59:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Running setup.py install for mozInstall 02:59:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:59:17 INFO - Running setup.py install for mozleak 02:59:17 INFO - Running setup.py install for mozlog 02:59:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:59:18 INFO - Running setup.py install for moznetwork 02:59:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:59:18 INFO - Running setup.py install for mozprocess 02:59:18 INFO - Running setup.py install for mozprofile 02:59:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:59:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:59:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:59:18 INFO - Running setup.py install for mozrunner 02:59:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:59:18 INFO - Running setup.py install for mozscreenshot 02:59:18 INFO - Running setup.py install for moztest 02:59:18 INFO - Running setup.py install for mozversion 02:59:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:59:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:59:19 INFO - Cleaning up... 02:59:19 INFO - Return code: 0 02:59:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:59:19 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')]} 02:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:19 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')]} 02:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:19 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:19 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 02:59:19 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 02:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:19 INFO - 'HOME': '/Users/cltbld', 02:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:19 INFO - 'LOGNAME': 'cltbld', 02:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:19 INFO - 'MOZ_NO_REMOTE': '1', 02:59:19 INFO - 'NO_EM_RESTART': '1', 02:59:19 INFO - 'PAGER': '/bin/cat', 02:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:19 INFO - 'PWD': '/builds/slave/test', 02:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:19 INFO - 'SHELL': '/bin/bash', 02:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:19 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:19 INFO - 'USER': 'cltbld', 02:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:19 INFO - 'XPC_FLAGS': '0x0', 02:59:19 INFO - 'XPC_SERVICE_NAME': '0', 02:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:59:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-oe_kN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:59:19 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)) 02:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:59:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Ixn7pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:59:19 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)) 02:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:59:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-hFMlYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:59:19 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)) 02:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:59:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-DT4PPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:59:19 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)) 02:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:59:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-w2XSuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:59:19 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)) 02:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:59:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-j29vmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-B2PMW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-9Ck5at-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-iYzkgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-6ZHXB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-_uYKxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-UWsQTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-dTjwLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-SBjZ_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-eQRgWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:20 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)) 02:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:59:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Ymldtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:59:21 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)) 02:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:59:21 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-QNiweW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:59:21 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)) 02:59:21 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)) 02:59:21 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)) 02:59:21 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)) 02:59:21 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)) 02:59:21 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)) 02:59:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:59:21 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 02:59:21 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 02:59:21 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 02:59:21 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 02:59:21 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 02:59:21 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 02:59:23 INFO - Downloading blessings-1.6.tar.gz 02:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:59:23 INFO - Installing collected packages: blessings 02:59:23 INFO - Running setup.py install for blessings 02:59:23 INFO - Successfully installed blessings 02:59:23 INFO - Cleaning up... 02:59:23 INFO - Return code: 0 02:59:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:59:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:59:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:59:24 INFO - Reading from file tmpfile_stdout 02:59:24 INFO - Current package versions: 02:59:24 INFO - blessings == 1.6 02:59:24 INFO - blobuploader == 1.2.4 02:59:24 INFO - docopt == 0.6.1 02:59:24 INFO - manifestparser == 1.1 02:59:24 INFO - mozInstall == 1.12 02:59:24 INFO - mozcrash == 0.17 02:59:24 INFO - mozdebug == 0.1 02:59:24 INFO - mozdevice == 0.48 02:59:24 INFO - mozfile == 1.2 02:59:24 INFO - mozhttpd == 0.7 02:59:24 INFO - mozinfo == 0.9 02:59:24 INFO - mozleak == 0.1 02:59:24 INFO - mozlog == 3.1 02:59:24 INFO - moznetwork == 0.27 02:59:24 INFO - mozprocess == 0.22 02:59:24 INFO - mozprofile == 0.28 02:59:24 INFO - mozrunner == 6.11 02:59:24 INFO - mozscreenshot == 0.1 02:59:24 INFO - mozsystemmonitor == 0.0 02:59:24 INFO - moztest == 0.7 02:59:24 INFO - mozversion == 1.4 02:59:24 INFO - psutil == 3.1.1 02:59:24 INFO - requests == 1.2.3 02:59:24 INFO - wsgiref == 0.1.2 02:59:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:59: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')]} 02:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59: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')]} 02:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:24 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:24 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 02:59:24 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 02:59:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:24 INFO - 'HOME': '/Users/cltbld', 02:59:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:24 INFO - 'LOGNAME': 'cltbld', 02:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:24 INFO - 'MOZ_NO_REMOTE': '1', 02:59:24 INFO - 'NO_EM_RESTART': '1', 02:59:24 INFO - 'PAGER': '/bin/cat', 02:59:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:24 INFO - 'PWD': '/builds/slave/test', 02:59:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:24 INFO - 'SHELL': '/bin/bash', 02:59:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:24 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:24 INFO - 'USER': 'cltbld', 02:59:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:24 INFO - 'XPC_FLAGS': '0x0', 02:59:24 INFO - 'XPC_SERVICE_NAME': '0', 02:59:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 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)) 02:59:24 INFO - Cleaning up... 02:59:24 INFO - Return code: 0 02:59:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:59: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')]} 02:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59: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')]} 02:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:24 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:24 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 02:59:24 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 02:59:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:24 INFO - 'HOME': '/Users/cltbld', 02:59:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:24 INFO - 'LOGNAME': 'cltbld', 02:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:24 INFO - 'MOZ_NO_REMOTE': '1', 02:59:24 INFO - 'NO_EM_RESTART': '1', 02:59:24 INFO - 'PAGER': '/bin/cat', 02:59:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:24 INFO - 'PWD': '/builds/slave/test', 02:59:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:24 INFO - 'SHELL': '/bin/bash', 02:59:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:24 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:24 INFO - 'USER': 'cltbld', 02:59:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:24 INFO - 'XPC_FLAGS': '0x0', 02:59:24 INFO - 'XPC_SERVICE_NAME': '0', 02:59:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:24 INFO - Downloading/unpacking jsonschema 02:59:24 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 02:59:24 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 02:59:24 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 02:59:24 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 02:59:24 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 02:59:24 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 02:59:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:59:27 INFO - Downloading/unpacking functools32 (from jsonschema) 02:59:27 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 02:59:27 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 02:59:27 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 02:59:27 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 02:59:27 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 02:59:27 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 02:59:27 INFO - Downloading functools32-3.2.3-2.tar.gz 02:59:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:59:27 INFO - warning: no files found matching '*.txt' 02:59:27 INFO - no previously-included directories found matching 'build' 02:59:27 INFO - no previously-included directories found matching 'dist' 02:59:27 INFO - no previously-included directories found matching '.git*' 02:59:27 INFO - Installing collected packages: jsonschema, functools32 02:59:27 INFO - Running setup.py install for functools32 02:59:27 INFO - warning: no files found matching '*.txt' 02:59:27 INFO - no previously-included directories found matching 'build' 02:59:27 INFO - no previously-included directories found matching 'dist' 02:59:27 INFO - no previously-included directories found matching '.git*' 02:59:27 INFO - Successfully installed jsonschema functools32 02:59:27 INFO - Cleaning up... 02:59:27 INFO - Return code: 0 02:59:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:59:27 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 0x10276ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10277c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe76bdb2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, '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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 02:59:27 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 02:59:27 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 02:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 02:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:59:27 INFO - 'HOME': '/Users/cltbld', 02:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:59:27 INFO - 'LOGNAME': 'cltbld', 02:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:27 INFO - 'MOZ_NO_REMOTE': '1', 02:59:27 INFO - 'NO_EM_RESTART': '1', 02:59:27 INFO - 'PAGER': '/bin/cat', 02:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:27 INFO - 'PWD': '/builds/slave/test', 02:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:59:27 INFO - 'SHELL': '/bin/bash', 02:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 02:59:27 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 02:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:59:27 INFO - 'USER': 'cltbld', 02:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:27 INFO - 'XPC_FLAGS': '0x0', 02:59:27 INFO - 'XPC_SERVICE_NAME': '0', 02:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:28 INFO - Downloading/unpacking flake8 02:59:28 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 02:59:28 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 02:59:28 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 02:59:28 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 02:59:28 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 02:59:28 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 02:59:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:59:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:59:30 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 02:59:30 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 02:59:30 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 02:59:30 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 02:59:30 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 02:59:30 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 02:59:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:59:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 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 02:59:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:59:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:59:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:59:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:59:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:59:31 INFO - no previously-included directories found matching 'docs/_build' 02:59:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:59:32 INFO - Running setup.py install for pep8 02:59:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:59:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:59:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:59:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:59:32 INFO - no previously-included directories found matching 'docs/_build' 02:59:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:59:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:59:32 INFO - Cleaning up... 02:59:32 INFO - Return code: 0 02:59:32 INFO - Running post-action listener: _resource_record_post_action 02:59:32 INFO - Running post-action listener: _start_resource_monitoring 02:59:32 INFO - Starting resource monitoring. 02:59:32 INFO - ##### 02:59:32 INFO - ##### Running install step. 02:59:32 INFO - ##### 02:59:32 INFO - Running pre-action listener: _resource_record_pre_action 02:59:32 INFO - Running main action method: install 02:59:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:59:32 INFO - Reading from file tmpfile_stdout 02:59:32 INFO - Detecting whether we're running mozinstall >=1.0... 02:59:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:59:32 INFO - Reading from file tmpfile_stdout 02:59:32 INFO - Output received: 02:59:32 INFO - Usage: mozinstall [options] installer 02:59:32 INFO - Options: 02:59:32 INFO - -h, --help show this help message and exit 02:59:32 INFO - -d DEST, --destination=DEST 02:59:32 INFO - Directory to install application into. [default: 02:59:32 INFO - "/builds/slave/test"] 02:59:32 INFO - --app=APP Application being installed. [default: firefox] 02:59:32 INFO - mkdir: /builds/slave/test/build/application 02:59:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:00:04 INFO - Reading from file tmpfile_stdout 03:00:04 INFO - Output received: 03:00:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:00:04 INFO - Running post-action listener: _resource_record_post_action 03:00:04 INFO - ##### 03:00:04 INFO - ##### Running run-tests step. 03:00:04 INFO - ##### 03:00:04 INFO - Running pre-action listener: _resource_record_pre_action 03:00:04 INFO - Running main action method: run_tests 03:00:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:00:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:00:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:00:04 INFO - 2016-06-04 03:00:04.592 screenresolution[1932:19520] starting screenresolution argv=screenresolution get 03:00:04 INFO - 2016-06-04 03:00:04.595 screenresolution[1932:19520] Display 0: 1600x1200x32@60 03:00:04 INFO - 2016-06-04 03:00:04.601 screenresolution[1933:19522] starting screenresolution argv=screenresolution list 03:00:04 INFO - Available Modes on Display 0 03:00:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:00:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:00:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:00:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:00:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:00:04 INFO - Intel Iris: 03:00:04 INFO - Chipset Model: Intel Iris 03:00:04 INFO - Type: GPU 03:00:04 INFO - Bus: Built-In 03:00:04 INFO - VRAM (Dynamic, Max): 1536 MB 03:00:04 INFO - Vendor: Intel (0x8086) 03:00:04 INFO - Device ID: 0x0a2e 03:00:04 INFO - Revision ID: 0x0009 03:00:04 INFO - Displays: 03:00:04 INFO - Display: 03:00:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:00:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:00:04 INFO - Display Serial Number: 1600x1200 60 03:00:04 INFO - Main Display: Yes 03:00:04 INFO - Mirror: Off 03:00:04 INFO - Online: Yes 03:00:04 INFO - Rotation: Supported 03:00:04 INFO - Return code: 0 03:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:00:04 INFO - Python 2.7.3 03:00:04 INFO - Return code: 0 03:00:04 INFO - grabbing minidump binary from tooltool 03:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:04 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 0x10260be00>, '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 0x102474db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102606b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:00:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:00:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:00:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:00:04 INFO - Return code: 0 03:00:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:00:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:00:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:00:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:00:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:00:06 INFO - Return code: 0 03:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--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 03:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0059 --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 03:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KTAVLtT4Lo/Render', 03:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:06 INFO - 'HOME': '/Users/cltbld', 03:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:06 INFO - 'LOGNAME': 'cltbld', 03:00:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:00:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:06 INFO - 'MOZ_NO_REMOTE': '1', 03:00:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:00:06 INFO - 'NO_EM_RESTART': '1', 03:00:06 INFO - 'PAGER': '/bin/cat', 03:00:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:06 INFO - 'PWD': '/builds/slave/test', 03:00:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:06 INFO - 'SHELL': '/bin/bash', 03:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gYyJcoLT5Y/Listeners', 03:00:06 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 03:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:06 INFO - 'USER': 'cltbld', 03:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:06 INFO - 'XPC_FLAGS': '0x0', 03:00:06 INFO - 'XPC_SERVICE_NAME': '0', 03:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--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 03:00:06 INFO - mozversion application_buildid: 20160604004005 03:00:06 INFO - mozversion application_changeset: ab782095ef4ab44dae4cc5f919858c5684837378 03:00:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:00:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:00:06 INFO - mozversion application_name: Firefox 03:00:06 INFO - mozversion application_remotingname: firefox-dev 03:00:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:00:06 INFO - mozversion application_vendor: Mozilla 03:00:06 INFO - mozversion application_version: 48.0a2 03:00:06 INFO - mozversion platform_buildid: 20160604004005 03:00:06 INFO - mozversion platform_changeset: ab782095ef4ab44dae4cc5f919858c5684837378 03:00:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:00:06 INFO - mozversion platform_version: 48.0a2 03:00:06 INFO - using testdate: 1465034406 03:00:06 INFO - actual date: 1465034406 03:00:06 INFO - starting webserver on 'localhost:55536' 03:00:06 INFO - SUITE-START | Running 2 tests 03:00:06 INFO - TEST-START | dromaeo_css 03:00:06 INFO - operating with platform_type : mac_ 03:00:06 INFO - Initialising browser for dromaeo_css test... 03:00:06 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpuxPWBQ/profile http://localhost:55536/getInfo.html) 03:00:08 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 03:00:08 INFO - PROCESS | 1951 | colorDepth:24 03:00:08 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 03:00:08 INFO - PROCESS | 1951 | __metrics 03:00:08 INFO - PROCESS | 1951 | 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" 03:00:08 ERROR - PROCESS | 1951 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:00:09 INFO - TEST-INFO | 1951: exit 0 03:00:09 INFO - Browser initialized. 03:00:09 INFO - Running cycle 1/1 for dromaeo_css test... 03:00:09 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpuxPWBQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:00:21 INFO - PROCESS | 1954 | RSS: Main: 211128320 03:00:21 INFO - PROCESS | 1954 | 03:01:12 INFO - PROCESS | 1954 | 1465034472757 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:03:23 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:55536/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55536/tests/dromaeo/cssquery-ext.html) 03:03:25 INFO - PROCESS | 1954 | RSS: Main: 339996672 03:03:25 INFO - PROCESS | 1954 | 03:06:27 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:55536/tests/dromaeo/cssquery-ext.html (next: http://localhost:55536/tests/dromaeo/cssquery-jquery.html) 03:06:28 INFO - PROCESS | 1954 | RSS: Main: 359411712 03:06:28 INFO - PROCESS | 1954 | 03:09:30 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:55536/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55536/tests/dromaeo/cssquery-mootools.html) 03:09:31 INFO - PROCESS | 1954 | RSS: Main: 364924928 03:09:31 INFO - PROCESS | 1954 | 03:12:33 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:55536/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55536/tests/dromaeo/cssquery-prototype.html) 03:12:33 INFO - PROCESS | 1954 | RSS: Main: 351977472 03:12:33 INFO - PROCESS | 1954 | 03:15:37 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:55536/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55536/tests/dromaeo/cssquery-yui.html) 03:15:37 INFO - PROCESS | 1954 | RSS: Main: 320778240 03:15:37 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:55536/tests/dromaeo/cssquery-yui.html (next: http://localhost:55536/tests/dromaeo/cssquery-dojo.html) 03:18:40 INFO - PROCESS | 1954 | RSS: Main: 367599616 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | __start_tp_report 03:18:40 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 03:18:40 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 03:18:40 INFO - PROCESS | 1954 | |i|pagename|runs| 03:18:40 INFO - PROCESS | 1954 | |0;dojo.html;3350.8827191048313;3370.191154123011;3387.424137896557;3432.8843125984063;3442.0706409269496;5599.804006859761;5610.635308704931;5612.186232996218;5627.409122042183;5636.549076073909;5610.66336019838;5644.435556444353;5650.6892120933435;5660.660360378381;5669.177969194464;5069.188929771238;5105.43840177582;5162.948370516303;5202.895942081156;5242.370915490146;5176.818811341604;5192.1432963560965;5200.895982080356;5203.2975548301065;5205.635605507616;5114.951434955825;5147.150720131174;5167.612429067816;5179;5179.093658609747;5149.768260428271;5182.948170518284;5187.922181167285;5188.299579556749;5235.607329450307;5036.345275114249;5040.621953353513;5069.417017043013;5125.923111153335;5130.333056702642;5368.865778355534;5408.513233808976;5424.701641409721;5449.64577302474;5509.917351239735;6165.784197553064;6173.41352571505;6175.536834737412;6191.102290167901;6193.0091185410565;6085.208922840003;6143.573288550997;6160;6228.377162283736;6262.060690896356;5697.829065127969;5724.542036637059;5724.998125328051;5744.770209191627;5756.395578464285;6290.4653104485715;6291.779787707408;6298.464630506458;6300.1494798202575;6305.180326557518;5940.821775346746;6018.277806663228;6022.518198544106;6065.5450841187085;6092.086187071975;6203.410675985864;6225.221847269091;6231.75072997075;6239.344868788802;6244.500439964792;6105.175801266861;6146.047362658705;6159.3224745277985;6162.26052873658;6171;6025.397460253939;6107.401224262706;6136.325004249529;6184.134221209034;6196.442320191204;4827.444843843003;4831.275308703653;4868.269759536098;4869.732164730974;4870.269459581091;4299.462567179103;4403.471583409947;4408.493023302297;4424.7123936944;4463.24124898768;5066.746662666926;5206.76569554371;5604.747786349542;5611.158326251095;5619.971900140555;6081.2094427725;6138.265785110792;6146.7706458708435;6226.128342032119;6256.655883926337;5573.916391254134;5577.0797818359515;5581.776728930798;5589.329280486368;5612.186232996274;5278.076336641149;5332.653377530526;5332.893342133213;5351.438098999549;5397.163439666858;6190.473809726223;6204.689765511796;6236.563440559117;6253.499720022387;6286.622802631857;5074.568661663652;6214.875702486015;6231.813045608639;6237.407446292595;6246.46905013079;6218.5647004709235;6259.561830671992;6263.561550691408;6274.560780745304;6283.3716628338625;4968.403791545038;6000.8499787503915;6116.755329786936;6118.357572455006;6126.761967422209;5601.271834661468;5933.406659334032;6190.50475961913;6207.844803879939;6210.875782484415;5937.9406205937385;5957.314908785372;6004.309504406874;6014.097885317237;6023.548233882564;5810.477057064884;5904.143899134688;5926.940730592639;5992.250968628921;6012.549058820518;5795;5835.241418615723;5840.094785308114;5854.385289544536;5859.179714840011;4952.628552858478;6006.3693312201585;6010.158577799024;6027.789027384196;6028.125921741235;4538.205813982474;5716.085426331767;5793.217915581343;5798.362180160095;5823 03:18:40 INFO - PROCESS | 1954 | |1;ext.html;517.8581228391397;528.2657106621797;530.0750190916848;531.8776681363289;592.1739173852474;30896.69103308966;31557.684423155762;38268;38992.22015559687;39061.804690976576;43706.562934370646;44375.55624443755;44414.77792611033;44507.33239001418;44543.554564454345;8389.664413423456;8556.871646925301;9475.289353298513;9571.090746379083;9723.708288751348;8464;11318.377489238088;11517.193796434252;11528.711782205428;11540.24988375753;11280.774384512304;11357.204995650307;11360.943195284011;11368.658940231806;11441.256318339323;8483.490990540588;8703.737957996118;11239.157063220251;11291.717707057307;11310.37792921389;8479.533625650587;8607.139286071342;11301;11497.482613282338;11518.078553715683;38700;39147;39179.804100979316;39195.216095677926;39228.80385598083;3107.3319236364064;3176.6188057433023;3210.2455922858107;3295.6045274567205;3323.1525960880062;22959.77040229593;27381.726182738315;27519.44961100787;27635.723642763514;27979;2637.208837348807;2752.4632696624167;2758.158761577718;2786.2477131174674;2881.7838662100216;486787;490020;490539.99999999645;490991;491024;61550.38449615536;62689.68655156695;62771.74456510936;62848.05727914085;62885;65564.34435655583;65842;65897.67051164809;65990.01014984779;65997;17380.65238695219;17903.37338193157;17938.91030544839;17945.282188712565;17960.371387001393;12651.93674031624;12880.935595322151;13634.318284085755;13884.722305554034;14015.229162396166;15867.444639437565;15957.601059973362;15999.28003239857;16041.679166416607;16637.33450661984;6525.11910892033;6600.1089852869245;6662.86674266512;6676.26561078281;6681.198256209286;925.648253663617;975.0931633580838;1025.6153942271649;1033.3593172233263;1075.4622688655672;29766.10701678953;29889.40221195564;30165.09504714862;30231.848840755654;31288.68711312903;1065.2223876569992;1081.7836432713489;1106.0101209417526;1110.35044498969;1148.1733152130619;14355.559554875696;14399.280035998157;14447.638809029648;14713.92643036778;14780.556583302518;619025;621216;624322.5135497265;628016;628380;58778;58819.82360352769;58889.82220355655;58912;58925.41074589199;1360.4490181476517;1479.8224213094497;1513.6972605478948;1518.1270769307914;1528.533797191845;28199.295017624725;28322.575161372184;28852;29128.27179320534;29163.270918226364;45814.31278530758;46368.6089417318;46423.839404013816;46470.070598588514;46477;365373;367066;367486;367488;367602;25357;26035.479290414463;26434.471310574067;26455.735442645328;26514.337141571614;43919;44775.20899163997;45923.77038114788;46323.768381157875;46453;36019.45970810492;36713;37041;37371;38165.4275185878;30431.54352684756;36289.637103629684;36315.91052268436;36813;36964.44553331648;36488.27023459569;36596.451053234756;37047.073823154635;37426.6257337434;37917.81041094777;32008;32509;32900.01299961005;33128.33743325168;33150.5027424584;24655.630165547173;24756.009759610133;24921.127760528296;25040.62439063379;25256.621150682386 03:18:40 INFO - PROCESS | 1954 | |2;jquery.html;388.6696308138083;394.73355485047585;401.53622565936354;404.15329883893236;410.85825390240376;19187.32844350448;20068.498287542818;20710.068046937886;20714.89642551787;20720;20453.48866278344;20722.689159662616;21090.734555926654;21435.464113397175;21487.462813429673;5952.910706339398;5975.880482390371;6065.302490213638;6143.10924915887;6201.658908760016;5664.20701101846;6090.939090609104;6130.080487926824;6140.570160088796;6143.293521245069;5830.358660547357;6103.1455596216565;6103.6032657877095;6141.477974372161;6152.107944348067;6076.848078798022;6106.847328816771;6127.050307202391;6130.724117414727;6139.386061393847;5838.38696936824;5918.082697181943;5925.851853703649;5929.407059294079;5929.614575052651;13711.314434278345;13888.513902013381;13943.860561394458;19707.40877773669;26340.814663340196;2130.3608917324896;2207.7129973103556;2210.9115635374565;2285.737140228878;2315.872627005514;10151.593936242616;10270.281080324381;10272.948635256776;13802.091105341184;13872.514461993882;1712.1268153241813;1737.1314342828587;1746.1792957310045;1764.2678288510253;1798.3346161920174;110729.44635276853;110990;116170.41914790457;117170.24244636338;117228;11762.823557646641;11817.822732659017;11840.585579504675;11844.052475801915;11852.288862668094;12439;15057.849421505864;15120.773188402183;15126.319315630823;15153.939224254196;8799.648014079494;8814.206721395105;8854.513001784964;8903.599338029804;8971.237444817263;7077.752278670222;8737.956310218535;8847.73456796297;8859.202671759573;8875.378723489423;4112.992316882383;4539.750313732777;4617.561331673485;4641.466231383367;4672.649551283667;1849.0921505788876;1955.9706604400944;1961.5586493039236;1975.7530308711412;1987.7316562264198;889.9377043606885;933.1228645073609;940.7977284883783;995.7908839143861;1083.1226706367709;16689.916550417413;19187.32844350441;20385.694214586794;20389.694154587694;20414.38756837297;973.9707808765748;1001.6994901529512;1005.1807776661997;1036.9481525923825;1051.553089936789;10641.308314959502;10985.780284394268;11001.724956875982;11054.613088541862;11113.498173896975;595569;604069;609841;630853;634258;118528.40735796439;119795.20307195399;122000;123313;125209.37395313148;1014.2899970021015;1015.3248090020202;1033.0341131042499;1033.4161198922584;1037.5538518437145;17856.821431785516;18017;18070.909645451688;18378;18720.034396388728;49231;49480.76298092576;50973;51902.221466678784;52031.73984130055;70049.29950700632;71363;71872.64063679648;74252;74552;7811.218878112143;8109.310708589836;8231.46495477792;8289.253967142986;8315.210055044761;9114.908850911406;11981;12202.816957745463;12330.260184388966;12437.937810311252;9255.907440925504;10272.691819245434;10428.009339112772;10796.92030796914;10821.971912668283;10294.176465882862;10347.63783267582;10698.197635177548;10758.13934885223;10988.945055274673;10724.89275107239;10740;10947.452627368759;11024.5038973248;11110.722231944266;7937.960310198412;8181.590920454072;8183.5499047550575;8198.631061602362;8449.95775021121;8891.244244239311;9534;10669.733256668645;10726.356418614909;10774.299670521392 03:18:40 INFO - PROCESS | 1954 | |3;mootools.html;1385.8544852790462;1530.5867415797734;1554.6579752454459;1782.8573714102872;1940.1463356123313;7835.412344074196;8510.744677659672;8699.782505437368;8699.956500217506;8700.869486957701;8351.791205219872;8593.968723753158;8596.054434012254;8599.785005374853;8604.698835540757;2428.150147448392;2453.619688948207;2456.312232574882;2458.4345600511892;2465.0016743219026;2403.0682567267904;2447.608382658766;2448;2450.0567281596623;2468.1854987854053;2430.2889497644483;2440.9389765255924;2445.8532488050773;2454.840435371704;2464.507098580282;2410.1805386168785;2432.501337225873;2442.3039433761382;2446.755324467548;2455.963160552575;2397.520495900827;2430.927072187837;2434.8417352872007;2437.293184976355;2437.390652336916;8464.195901389357;8692.956535217347;8771.561421928878;8781;8789.956050219773;5293.523582877521;5457.426970168154;5499.065158922953;5501.559875209973;5503.697296648683;3472.9826350868084;3567.090391950036;3634.254977729559;3644.5808731995894;3659.1949771050326;1015.6648306058983;1057.7884423115408;1060.342587595688;1061.198794909846;1070.507566519402;11382.886171138265;11814.409279535988;11825.467853946593;11844.34856082915;11851.644450666494;4789.281607758794;4835.734034628059;4862.756862156878;4922.360093187863;4941.2835138905375;10214.642487512901;10235.283530152967;10237.641682541076;10240.283180177465;10253.487325633838;5457.945420545823;5491.670499770026;5493.544210784145;5500.972495137498;5507.476789704971;4375.256206444912;4483.843065492692;4731.52684731531;4862.78117484714;5000.199968005102;4022.933922510537;4045.939310910339;4172.895677608084;4186.372044193335;4191.428214419592;1141.4692168141853;1150.355800751565;1334.4728832111261;1350.7163495665823;1367.5418734723978;846.4582667093065;895.3106108296575;897.0849733272026;917.2249449215402;922.5617831530104;4690.437147542317;5641.661500309912;5838.591298609142;5867.2959244890035;5868.765249390062;876.2989608313324;925.9444433334022;1003.6336737090909;1028.2236911132047;1040.380973320888;3161.604799400075;3727.5154229949935;3766.8681596144;3849.1531862990096;3849.191669749328;10291.794164116825;10605.045545900906;10620.309679870783;10634.946825265824;10736.409497477714;10430;10489.213309001856;10580.947095264628;10589.470526473646;10605.522751476203;7867.567283799332;8358.791030224293;8373.665053397795;8436.072032076467;8485.236328730567;4773.952260477489;4845.8061677532505;5421.349438067457;5630.183623374506;5650.85872853182;9171.587278572346;9382.859257111286;9489.905100949178;9492.57283422234;9668.419894806335;9400.435973841591;9770.706878793648;9824.4105353679;9989.750256243362;10014.19886409071;6361.650109244036;6554.344565543408;6721.731130754715;6985.545939513915;7081.46888983318;8084.231997960184;8095.676172953016;8132.064812546396;8146.592670366576;8152.307053900247;7134.536255143399;7218.494705370784;7259.709611615476;7307.525010874276;7355.264473552816;7091.858162836611;7100.254473280438;7163.606001669749;7206.2793720627515;7243.927560724325;6696.062551242732;6803.217629972616;6827.010083537959;6938.757143499954;6938.826529336807;4699.929501057419;4763.118823017753;4779.5220477951925;4859.979404325131;4890.388701412324;4743.217369134054;4820.71075735457;4832.299316599003;4838.854834354975;4923.433805112458 03:18:40 INFO - PROCESS | 1954 | |4;prototype.html;317.30193574136905;322.32472969129674;332.47468998981606;334.40475952803985;335.2758042627924;5392;6531.9673401633045;6575.9342406575915;6673.065770792093;6703.128593282879;6368.235811702602;6582.111414958979;6597.670116494168;6606.834829129274;6611.239707433647;4929.630277729185;4935.950640493603;4936.284238785374;4939.43196532398;4947.727874966884;3941.8620348287814;4661.834541364658;4944.777485013183;4957;4965.652404331699;3978.8461346209565;4640.582347588716;4717.834875779348;4769.117713223048;4816.518348165173;4876.341693871317;4878.097551952882;4890.290907818364;4903.436104847917;4906.092372910987;4180.101278225196;4183.686223533247;4188.727732697365;4193.308104162809;4203.516595591485;5083.313752643383;5706.543476521868;5999.490043346321;6296.401841825019;6341.524385671093;5674.773009079632;5686.800961966353;5687.7156142192725;5691.943080569183;5692.743826527775;6702.329767023308;6953.443724502028;7023.051887995158;7030;7061.187963384174;4550.977245113786;4571.817127314904;4585.564371384713;4585.885352866172;4594.816207351735;7344.853102937965;7433.962830185815;7440.85118297631;7465.178830328659;7471.25287471254;4617.722936623813;4625.398698169217;4628.328892310664;4636.258198688193;4636.44362676481;3935.58676338986;4679.064187162581;4681.321208424827;4685.531446855355;4689.99165179488;5347.1979203119845;5427.5074354552025;5555.444455554412;5559.77760889568;5570.387257401682;6070.332263451017;6683.331666833375;6991.685374158175;7021.1574611047;7033.753818616324;1297.941592628315;1640.5734509027739;1640.753886916972;1644.925978330978;1645.3418632547077;982.7985263021063;1001.1590264178126;1027.8201314770035;1030.8093002794358;1057.4606950455322;6550.0829883816305;6568.343165683489;6623.768168114093;6640.20317561886;6641.236257830315;7291.8906216406795;7320.377767889682;7321.377682897013;7324.890126648104;7329.19378868324;6605.30644282353;6606.636634985122;6615.239247486505;6619.768308109193;6620.602763834186;3688.483612294281;3806.200697853482;3865.188310454836;3868.7291889568023;3870.496835411435;7552.546847189186;7573.848523029508;7588.886166707503;7599.65801538921;7623.54258744466;7392.445566582528;7431.479796414199;7464.365528929993;7465.029546159073;7465.925340746523;7568.5458872467825;7578.621068946531;7581.583012934341;7598.240175982468;7606.581638009852;6943.722251109899;7226.963865180641;7234.96382518084;7246.746363877239;7275.672594733355;7057.682404291717;7103.467239957127;7105.573665579875;7116.110486189226;7123.252058533986;6297.905531416941;7416.184219735933;7520.398368130427;7550.509216900884;7555.206703296075;4577.679562430598;4633.791479383503;4648.2562789953445;4649.349091127177;4654.534546545318;6799.388055074869;7159.248278930637;7171.820704482429;7179.1026121734785;7186.24544422849;6886.311368863074;6904.4131248842405;6917.169939607078;6927.064846245692;6932.514723969475;6919.515633905578;6921.7231310747;6950.860982780215;6955.513114081966;6961.965190174017;5843.714382835769;6762.4928130389435;6764.864702706017;6776.15298087739;6778.694958726771;6607.867842643216;6773.187217533928;6914.792556223321;6934.514583979073;7057.929420705933;6708.597484150967;6777.525573209828;6881.793546193622;6917.619530925847;6919.654017299215 03:18:40 INFO - PROCESS | 1954 | |5;yui.html;667.8530723240889;693.1820451866797;721.9891701624476;732.9083864516936;749.9062617172854;14908.850911490881;15062.623434414145;15230.771538426932;15241.695166096672;15254.999999999985;13989.720205595882;14114.364853581588;14118.294085295745;14136.363863626137;14162.78755818661;762.2872614105801;768.0706345322167;910.8588168833825;914.4376208631677;946.8911075226334;920.8020275640736;921.5438358012793;922.5525620074259;922.9861552076724;934.5327336331834;965.6764983730459;970.2383628851353;970.6020531582053;972.4457059476101;984.3257368702461;778.1168373895641;954.742219600704;981.4896253947943;982.0866594067514;987.1017374189453;696.314130581377;709.6097146569366;718.8023293594251;720.5748608321063;720.8306048078697;14599.635009124751;14604.977651564395;14626.414943402251;14627.488037918622;14639.341229644586;12522.874771252262;12688.36558172097;12703.41311682311;12992.805107923388;13203.405846736823;10804.837927431096;10956.068734157607;10972.377613684377;11310.321380717183;11397.544098236061;3567.500549923013;3567.9108022299392;3592.8742494012586;3678.0437086357474;3811.447340135665;75592;76004;76482.61758691171;78051.8292225617;78774.81837772438;9787.951060244653;9803.264755143393;9921.255905807093;10035.397876127456;10452.216083793593;22642.886785565966;22782.544349113257;23105.768942310697;23114.768852311598;23128.4217894554;7619.123800762873;7770.572618505923;7820.7262745804765;7920.366370690331;7959;4016.6184212499766;4043.3732771420478;4063.756174629532;4112.09533902541;4129.215449064668;3615.168511242429;3685.1708365618056;3689.465027571041;3690.538682664667;3691.7046636269033;577.3158806813939;649.250116115887;662.8641128568631;669.9832504187336;688.6763221285987;505.83054676683724;545.021686073499;555.5914464758654;558.3634656491603;584.6141546579237;7942.523448593103;7994;8005.519668819889;8120.918790812017;8165.346772258205;582.4990508162977;586.7213073789916;593.8752861898963;597.7340083662729;607.4714997951722;3723.8696645617274;3735.9626403736156;3796.2976849282973;3916.74541154824;4025.6578190854107;83812.16187838165;84726.576367119;85442;89904;90082.54958725165;74003.2599674007;74092;74286;75030;77074;67622.66188669026;68726.65636671784;70277.64861175661;70850.2914970854;73518;6720.46236301085;7926.881096783438;8096.7166149184495;8166.83666326682;8214.753557393287;47345.7632711848;49351.753241233564;49898;50300.24549631832;51003.72490687762;52733.15433959793;66049;70785;71364.57270854658;72299.63850180716;10760.40817755031;10883.455827208449;11132.165087618623;11281.71795705114;11307.547698091985;11575.652730418102;11960.923516883522;12035.277883327028;12056.939715301367;12316.261024338568;11130.666080017612;11404;11406;11569.768604628029;11830.763384732085;11376.54493820238;11480.598179064027;11529.711757206136;11777.646670599896;11866.406679666155;10893.727656808644;11047.281926674741;11259.943700281447;11297.7740445189;11763.058955283386;8562.629979203546;9383.812323753624;9440.611505434998;9486.430814151174;9514.38156519824;9371.718848434557;9515.904840951502;9527.904720952702;9596.088371604686;9750.122488976027 03:18:40 INFO - PROCESS | 1954 | __end_tp_report 03:18:40 INFO - PROCESS | 1954 | __start_cc_report 03:18:40 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,1223 03:18:40 INFO - PROCESS | 1954 | __end_cc_report 03:18:40 INFO - PROCESS | 1954 | __startTimestamp1465035520739__endTimestamp 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | ------- Summary: start ------- 03:18:40 INFO - PROCESS | 1954 | Number of tests: 6 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | [#0] dojo.html Cycles:165 Average:5637.68 Median:5795.00 stddev:634.46 (10.9%) stddev-sans-first:610.52 03:18:40 INFO - PROCESS | 1954 | Values: 3350.9 3370.2 3387.4 3432.9 3442.1 5599.8 5610.6 5612.2 5627.4 5636.5 5610.7 5644.4 5650.7 5660.7 5669.2 5069.2 5105.4 5162.9 5202.9 5242.4 5176.8 5192.1 5200.9 5203.3 5205.6 5115.0 5147.2 5167.6 5179.0 5179.1 5149.8 5182.9 5187.9 5188.3 5235.6 5036.3 5040.6 5069.4 5125.9 5130.3 5368.9 5408.5 5424.7 5449.6 5509.9 6165.8 6173.4 6175.5 6191.1 6193.0 6085.2 6143.6 6160.0 6228.4 6262.1 5697.8 5724.5 5725.0 5744.8 5756.4 6290.5 6291.8 6298.5 6300.1 6305.2 5940.8 6018.3 6022.5 6065.5 6092.1 6203.4 6225.2 6231.8 6239.3 6244.5 6105.2 6146.0 6159.3 6162.3 6171.0 6025.4 6107.4 6136.3 6184.1 6196.4 4827.4 4831.3 4868.3 4869.7 4870.3 4299.5 4403.5 4408.5 4424.7 4463.2 5066.7 5206.8 5604.7 5611.2 5620.0 6081.2 6138.3 6146.8 6226.1 6256.7 5573.9 5577.1 5581.8 5589.3 5612.2 5278.1 5332.7 5332.9 5351.4 5397.2 6190.5 6204.7 6236.6 6253.5 6286.6 5074.6 6214.9 6231.8 6237.4 6246.5 6218.6 6259.6 6263.6 6274.6 6283.4 4968.4 6000.8 6116.8 6118.4 6126.8 5601.3 5933.4 6190.5 6207.8 6210.9 5937.9 5957.3 6004.3 6014.1 6023.5 5810.5 5904.1 5926.9 5992.3 6012.5 5795.0 5835.2 5840.1 5854.4 5859.2 4952.6 6006.4 6010.2 6027.8 6028.1 4538.2 5716.1 5793.2 5798.4 5823.0 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | [#1] ext.html Cycles:180 Average:63578.17 Median:19671.31 stddev:134737.26 (684.9%) stddev-sans-first:135032.05 03:18:40 INFO - PROCESS | 1954 | Values: 517.9 528.3 530.1 531.9 592.2 30896.7 31557.7 38268.0 38992.2 39061.8 43706.6 44375.6 44414.8 44507.3 44543.6 8389.7 8556.9 9475.3 9571.1 9723.7 8464.0 11318.4 11517.2 11528.7 11540.2 11280.8 11357.2 11360.9 11368.7 11441.3 8483.5 8703.7 11239.2 11291.7 11310.4 8479.5 8607.1 11301.0 11497.5 11518.1 38700.0 39147.0 39179.8 39195.2 39228.8 3107.3 3176.6 3210.2 3295.6 3323.2 22959.8 27381.7 27519.4 27635.7 27979.0 2637.2 2752.5 2758.2 2786.2 2881.8 486787.0 490020.0 490540.0 490991.0 491024.0 61550.4 62689.7 62771.7 62848.1 62885.0 65564.3 65842.0 65897.7 65990.0 65997.0 17380.7 17903.4 17938.9 17945.3 17960.4 12651.9 12880.9 13634.3 13884.7 14015.2 15867.4 15957.6 15999.3 16041.7 16637.3 6525.1 6600.1 6662.9 6676.3 6681.2 925.6 975.1 1025.6 1033.4 1075.5 29766.1 29889.4 30165.1 30231.8 31288.7 1065.2 1081.8 1106.0 1110.4 1148.2 14355.6 14399.3 14447.6 14713.9 14780.6 619025.0 621216.0 624322.5 628016.0 628380.0 58778.0 58819.8 58889.8 58912.0 58925.4 1360.4 1479.8 1513.7 1518.1 1528.5 28199.3 28322.6 28852.0 29128.3 29163.3 45814.3 46368.6 46423.8 46470.1 46477.0 365373.0 367066.0 367486.0 367488.0 367602.0 25357.0 26035.5 26434.5 26455.7 26514.3 43919.0 44775.2 45923.8 46323.8 46453.0 36019.5 36713.0 37041.0 37371.0 38165.4 30431.5 36289.6 36315.9 36813.0 36964.4 36488.3 36596.5 37047.1 37426.6 37917.8 32008.0 32509.0 32900.0 33128.3 33150.5 24655.6 24756.0 24921.1 25040.6 25256.6 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | [#2] jquery.html Cycles:180 Average:34752.54 Median:20721.34 stddev:102241.52 (493.4%) stddev-sans-first:102495.78 03:18:40 INFO - PROCESS | 1954 | Values: 388.7 394.7 401.5 404.2 410.9 19187.3 20068.5 20710.1 20714.9 20720.0 20453.5 20722.7 21090.7 21435.5 21487.5 5952.9 5975.9 6065.3 6143.1 6201.7 5664.2 6090.9 6130.1 6140.6 6143.3 5830.4 6103.1 6103.6 6141.5 6152.1 6076.8 6106.8 6127.1 6130.7 6139.4 5838.4 5918.1 5925.9 5929.4 5929.6 13711.3 13888.5 13943.9 19707.4 26340.8 2130.4 2207.7 2210.9 2285.7 2315.9 10151.6 10270.3 10272.9 13802.1 13872.5 1712.1 1737.1 1746.2 1764.3 1798.3 110729.4 110990.0 116170.4 117170.2 117228.0 11762.8 11817.8 11840.6 11844.1 11852.3 12439.0 15057.8 15120.8 15126.3 15153.9 8799.6 8814.2 8854.5 8903.6 8971.2 7077.8 8738.0 8847.7 8859.2 8875.4 4113.0 4539.8 4617.6 4641.5 4672.6 1849.1 1956.0 1961.6 1975.8 1987.7 889.9 933.1 940.8 995.8 1083.1 16689.9 19187.3 20385.7 20389.7 20414.4 974.0 1001.7 1005.2 1036.9 1051.6 10641.3 10985.8 11001.7 11054.6 11113.5 595569.0 604069.0 609841.0 630853.0 634258.0 118528.4 119795.2 122000.0 123313.0 125209.4 1014.3 1015.3 1033.0 1033.4 1037.6 17856.8 18017.0 18070.9 18378.0 18720.0 49231.0 49480.8 50973.0 51902.2 52031.7 70049.3 71363.0 71872.6 74252.0 74552.0 7811.2 8109.3 8231.5 8289.3 8315.2 9114.9 11981.0 12202.8 12330.3 12437.9 9255.9 10272.7 10428.0 10796.9 10822.0 10294.2 10347.6 10698.2 10758.1 10988.9 10724.9 10740.0 10947.5 11024.5 11110.7 7938.0 8181.6 8183.5 8198.6 8450.0 8891.2 9534.0 10669.7 10726.4 10774.3 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | [#3] mootools.html Cycles:180 Average:5636.37 Median:4834.02 stddev:3132.33 (64.8%) stddev-sans-first:3124.83 03:18:40 INFO - PROCESS | 1954 | Values: 1385.9 1530.6 1554.7 1782.9 1940.1 7835.4 8510.7 8699.8 8700.0 8700.9 8351.8 8594.0 8596.1 8599.8 8604.7 2428.2 2453.6 2456.3 2458.4 2465.0 2403.1 2447.6 2448.0 2450.1 2468.2 2430.3 2440.9 2445.9 2454.8 2464.5 2410.2 2432.5 2442.3 2446.8 2456.0 2397.5 2430.9 2434.8 2437.3 2437.4 8464.2 8693.0 8771.6 8781.0 8790.0 5293.5 5457.4 5499.1 5501.6 5503.7 3473.0 3567.1 3634.3 3644.6 3659.2 1015.7 1057.8 1060.3 1061.2 1070.5 11382.9 11814.4 11825.5 11844.3 11851.6 4789.3 4835.7 4862.8 4922.4 4941.3 10214.6 10235.3 10237.6 10240.3 10253.5 5457.9 5491.7 5493.5 5501.0 5507.5 4375.3 4483.8 4731.5 4862.8 5000.2 4022.9 4045.9 4172.9 4186.4 4191.4 1141.5 1150.4 1334.5 1350.7 1367.5 846.5 895.3 897.1 917.2 922.6 4690.4 5641.7 5838.6 5867.3 5868.8 876.3 925.9 1003.6 1028.2 1040.4 3161.6 3727.5 3766.9 3849.2 3849.2 10291.8 10605.0 10620.3 10634.9 10736.4 10430.0 10489.2 10580.9 10589.5 10605.5 7867.6 8358.8 8373.7 8436.1 8485.2 4774.0 4845.8 5421.3 5630.2 5650.9 9171.6 9382.9 9489.9 9492.6 9668.4 9400.4 9770.7 9824.4 9989.8 10014.2 6361.7 6554.3 6721.7 6985.5 7081.5 8084.2 8095.7 8132.1 8146.6 8152.3 7134.5 7218.5 7259.7 7307.5 7355.3 7091.9 7100.3 7163.6 7206.3 7243.9 6696.1 6803.2 6827.0 6938.8 6938.8 4699.9 4763.1 4779.5 4860.0 4890.4 4743.2 4820.7 4832.3 4838.9 4923.4 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | [#4] prototype.html Cycles:180 Average:5708.29 Median:6605.97 stddev:1829.69 (27.7%) stddev-sans-first:1789.52 03:18:40 INFO - PROCESS | 1954 | Values: 317.3 322.3 332.5 334.4 335.3 5392.0 6532.0 6575.9 6673.1 6703.1 6368.2 6582.1 6597.7 6606.8 6611.2 4929.6 4936.0 4936.3 4939.4 4947.7 3941.9 4661.8 4944.8 4957.0 4965.7 3978.8 4640.6 4717.8 4769.1 4816.5 4876.3 4878.1 4890.3 4903.4 4906.1 4180.1 4183.7 4188.7 4193.3 4203.5 5083.3 5706.5 5999.5 6296.4 6341.5 5674.8 5686.8 5687.7 5691.9 5692.7 6702.3 6953.4 7023.1 7030.0 7061.2 4551.0 4571.8 4585.6 4585.9 4594.8 7344.9 7434.0 7440.9 7465.2 7471.3 4617.7 4625.4 4628.3 4636.3 4636.4 3935.6 4679.1 4681.3 4685.5 4690.0 5347.2 5427.5 5555.4 5559.8 5570.4 6070.3 6683.3 6991.7 7021.2 7033.8 1297.9 1640.6 1640.8 1644.9 1645.3 982.8 1001.2 1027.8 1030.8 1057.5 6550.1 6568.3 6623.8 6640.2 6641.2 7291.9 7320.4 7321.4 7324.9 7329.2 6605.3 6606.6 6615.2 6619.8 6620.6 3688.5 3806.2 3865.2 3868.7 3870.5 7552.5 7573.8 7588.9 7599.7 7623.5 7392.4 7431.5 7464.4 7465.0 7465.9 7568.5 7578.6 7581.6 7598.2 7606.6 6943.7 7227.0 7235.0 7246.7 7275.7 7057.7 7103.5 7105.6 7116.1 7123.3 6297.9 7416.2 7520.4 7550.5 7555.2 4577.7 4633.8 4648.3 4649.3 4654.5 6799.4 7159.2 7171.8 7179.1 7186.2 6886.3 6904.4 6917.2 6927.1 6932.5 6919.5 6921.7 6950.9 6955.5 6962.0 5843.7 6762.5 6764.9 6776.2 6778.7 6607.9 6773.2 6914.8 6934.5 7057.9 6708.6 6777.5 6881.8 6917.6 6919.7 03:18:40 INFO - PROCESS | 1954 | 03:18:40 INFO - PROCESS | 1954 | [#5] yui.html Cycles:180 Average:17996.61 Median:590.30 stddev:24761.38 (4194.7%) stddev-sans-first:24796.66 03:18:40 INFO - PROCESS | 1954 | Values: 667.9 693.2 722.0 732.9 749.9 14908.9 15062.6 15230.8 15241.7 15255.0 13989.7 14114.4 14118.3 14136.4 14162.8 762.3 768.1 910.9 914.4 946.9 920.8 921.5 922.6 923.0 934.5 965.7 970.2 970.6 972.4 984.3 778.1 954.7 981.5 982.1 987.1 696.3 709.6 718.8 720.6 720.8 14599.6 14605.0 14626.4 14627.5 14639.3 12522.9 12688.4 12703.4 12992.8 13203.4 10804.8 10956.1 10972.4 11310.3 11397.5 3567.5 3567.9 3592.9 3678.0 3811.4 75592.0 76004.0 76482.6 78051.8 78774.8 9788.0 9803.3 9921.3 10035.4 10452.2 22642.9 22782.5 23105.8 23114.8 23128.4 7619.1 7770.6 7820.7 7920.4 7959.0 4016.6 4043.4 4063.8 4112.1 4129.2 3615.2 3685.2 3689.5 3690.5 3691.7 577.3 649.3 662.9 670.0 688.7 505.8 545.0 555.6 558.4 584.6 7942.5 7994.0 8005.5 8120.9 8165.3 582.5 586.7 593.9 597.7 607.5 3723.9 3736.0 3796.3 3916.7 4025.7 83812.2 84726.6 85442.0 89904.0 90082.5 74003.3 74092.0 74286.0 75030.0 77074.0 67622.7 68726.7 70277.6 70850.3 73518.0 6720.5 7926.9 8096.7 8166.8 8214.8 47345.8 49351.8 49898.0 50300.2 51003.7 52733.2 66049.0 70785.0 71364.6 72299.6 10760.4 10883.5 11132.2 11281.7 11307.5 11575.7 11960.9 12035.3 12056.9 12316.3 11130.7 11404.0 11406.0 11569.8 11830.8 11376.5 11480.6 11529.7 11777.6 11866.4 10893.7 11047.3 11259.9 11297.8 11763.1 8562.6 9383.8 9440.6 9486.4 9514.4 9371.7 9515.9 9527.9 9596.1 9750.1 03:18:40 INFO - PROCESS | 1954 | -------- Summary: end -------- 03:18:40 INFO - PROCESS | 1954 | 03:18:41 INFO - TEST-INFO | 1954: exit 0 03:18:41 INFO - TEST-OK | dromaeo_css | took 1115071ms 03:18:41 INFO - TEST-START | kraken 03:18:41 INFO - operating with platform_type : mac_ 03:18:41 INFO - Initialising browser for kraken test... 03:18:41 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpjY7WZa/profile http://localhost:55536/getInfo.html) 03:18:43 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 03:18:43 INFO - PROCESS | 1957 | colorDepth:24 03:18:43 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 03:18:43 INFO - PROCESS | 1957 | __metrics 03:18:43 INFO - PROCESS | 1957 | 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" 03:18:43 ERROR - PROCESS | 1957 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:18:44 INFO - TEST-INFO | 1957: exit 0 03:18:44 INFO - Browser initialized. 03:18:44 INFO - Running cycle 1/1 for kraken test... 03:18:44 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpjY7WZa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:18:56 INFO - PROCESS | 1960 | RSS: Main: 211243008 03:18:56 INFO - PROCESS | 1960 | 03:18:59 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55536/tests/kraken/driver.html?testName=audio-beat-detection) 03:18:59 INFO - PROCESS | 1960 | RSS: Main: 272551936 03:18:59 INFO - PROCESS | 1960 | 03:19:02 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55536/tests/kraken/driver.html?testName=audio-dft) 03:19:03 INFO - PROCESS | 1960 | RSS: Main: 286867456 03:19:03 INFO - PROCESS | 1960 | 03:19:06 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55536/tests/kraken/driver.html?testName=audio-fft) 03:19:06 INFO - PROCESS | 1960 | RSS: Main: 373297152 03:19:06 INFO - PROCESS | 1960 | 03:19:08 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55536/tests/kraken/driver.html?testName=audio-oscillator) 03:19:09 INFO - PROCESS | 1960 | RSS: Main: 280068096 03:19:09 INFO - PROCESS | 1960 | 03:19:11 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55536/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:19:12 INFO - PROCESS | 1960 | RSS: Main: 280985600 03:19:12 INFO - PROCESS | 1960 | 03:19:15 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55536/tests/kraken/driver.html?testName=imaging-darkroom) 03:19:15 INFO - PROCESS | 1960 | RSS: Main: 318455808 03:19:15 INFO - PROCESS | 1960 | 03:19:18 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55536/tests/kraken/driver.html?testName=imaging-desaturate) 03:19:19 INFO - PROCESS | 1960 | RSS: Main: 319983616 03:19:19 INFO - PROCESS | 1960 | 03:19:22 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55536/tests/kraken/driver.html?testName=json-parse-financial) 03:19:22 INFO - PROCESS | 1960 | RSS: Main: 318046208 03:19:22 INFO - PROCESS | 1960 | 03:19:24 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55536/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:19:25 INFO - PROCESS | 1960 | RSS: Main: 310095872 03:19:25 INFO - PROCESS | 1960 | 03:19:26 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:19:27 INFO - PROCESS | 1960 | RSS: Main: 317403136 03:19:27 INFO - PROCESS | 1960 | 03:19:29 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:19:30 INFO - PROCESS | 1960 | RSS: Main: 319520768 03:19:30 INFO - PROCESS | 1960 | 03:19:33 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:19:33 INFO - PROCESS | 1960 | RSS: Main: 321503232 03:19:33 INFO - PROCESS | 1960 | 03:19:36 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:19:37 INFO - PROCESS | 1960 | RSS: Main: 318197760 03:19:37 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:55536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55536/tests/kraken/driver.html?testName=ai-astar) 03:19:39 INFO - PROCESS | 1960 | RSS: Main: 382447616 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | __start_tp_report 03:19:39 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 03:19:39 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 03:19:39 INFO - PROCESS | 1960 | |i|pagename|runs| 03:19:39 INFO - PROCESS | 1960 | |0;ai-astar;114;114;121;112;113;112;114;114;112;133 03:19:39 INFO - PROCESS | 1960 | |1;audio-beat-detection;157;122;143;165;119;140;141;117;169;106 03:19:39 INFO - PROCESS | 1960 | |2;audio-dft;182;186;187;184;183;185;185;183;184;185 03:19:39 INFO - PROCESS | 1960 | |3;audio-fft;72;81;65;142;46;58;88;94;162;57 03:19:39 INFO - PROCESS | 1960 | |4;audio-oscillator;92;90;143;76;77;86;122;79;76;124 03:19:39 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;87;84;88;85;85;83;87;83;108;130 03:19:39 INFO - PROCESS | 1960 | |6;imaging-darkroom;92;93;93;94;92;93;92;93;92;107 03:19:39 INFO - PROCESS | 1960 | |7;imaging-desaturate;83;84;85;86;95;87;86;84;83;84 03:19:39 INFO - PROCESS | 1960 | |8;json-parse-financial;58;63;65;61;69;92;97;113;64;59 03:19:39 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;64;65;74;71;72;68;69;68;67;69 03:19:39 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;84;105;105;103;83;81;81;97;103;85 03:19:39 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;146;146;143;134;131;128;128;129;173;167 03:19:39 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;177;166;167;167;160;219;155;204;191;169 03:19:39 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;67;66;70;86;97;97;76;69;70;66 03:19:39 INFO - PROCESS | 1960 | __end_tp_report 03:19:39 INFO - PROCESS | 1960 | __start_cc_report 03:19:39 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,2030 03:19:39 INFO - PROCESS | 1960 | __end_cc_report 03:19:39 INFO - PROCESS | 1960 | __startTimestamp1465035579644__endTimestamp 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | ------- Summary: start ------- 03:19:39 INFO - PROCESS | 1960 | Number of tests: 14 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:115.90 Median:114.00 stddev:6.56 (5.8%) stddev-sans-first:6.92 03:19:39 INFO - PROCESS | 1960 | Values: 114.0 114.0 121.0 112.0 113.0 112.0 114.0 114.0 112.0 133.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:142.00 stddev:21.53 (15.2%) stddev-sans-first:21.70 03:19:39 INFO - PROCESS | 1960 | Values: 157.0 122.0 143.0 165.0 119.0 140.0 141.0 117.0 169.0 106.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.51 (0.8%) stddev-sans-first:1.32 03:19:39 INFO - PROCESS | 1960 | Values: 182.0 186.0 187.0 184.0 183.0 185.0 185.0 183.0 184.0 185.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:86.50 Median:68.50 stddev:37.81 (55.2%) stddev-sans-first:39.73 03:19:39 INFO - PROCESS | 1960 | Values: 72.0 81.0 65.0 142.0 46.0 58.0 88.0 94.0 162.0 57.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:78.00 stddev:24.19 (31.0%) stddev-sans-first:25.61 03:19:39 INFO - PROCESS | 1960 | Values: 92.0 90.0 143.0 76.0 77.0 86.0 122.0 79.0 76.0 124.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:92.00 Median:85.00 stddev:15.24 (17.9%) stddev-sans-first:16.06 03:19:39 INFO - PROCESS | 1960 | Values: 87.0 84.0 88.0 85.0 85.0 83.0 87.0 83.0 108.0 130.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:93.00 stddev:4.58 (4.9%) stddev-sans-first:4.80 03:19:39 INFO - PROCESS | 1960 | Values: 92.0 93.0 93.0 94.0 92.0 93.0 92.0 93.0 92.0 107.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:85.50 stddev:3.53 (4.1%) stddev-sans-first:3.61 03:19:39 INFO - PROCESS | 1960 | Values: 83.0 84.0 85.0 86.0 95.0 87.0 86.0 84.0 83.0 84.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:74.10 Median:64.50 stddev:19.30 (29.9%) stddev-sans-first:19.57 03:19:39 INFO - PROCESS | 1960 | Values: 58.0 63.0 65.0 61.0 69.0 92.0 97.0 113.0 64.0 59.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:3.06 (4.4%) stddev-sans-first:2.73 03:19:39 INFO - PROCESS | 1960 | Values: 64.0 65.0 74.0 71.0 72.0 68.0 69.0 68.0 67.0 69.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:82.00 stddev:10.73 (13.1%) stddev-sans-first:10.91 03:19:39 INFO - PROCESS | 1960 | Values: 84.0 105.0 105.0 103.0 83.0 81.0 81.0 97.0 103.0 85.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:144.50 stddev:16.20 (11.2%) stddev-sans-first:17.14 03:19:39 INFO - PROCESS | 1960 | Values: 146.0 146.0 143.0 134.0 131.0 128.0 128.0 129.0 173.0 167.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:173.00 stddev:20.67 (11.9%) stddev-sans-first:21.92 03:19:39 INFO - PROCESS | 1960 | Values: 177.0 166.0 167.0 167.0 160.0 219.0 155.0 204.0 191.0 169.0 03:19:39 INFO - PROCESS | 1960 | 03:19:39 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:73.00 stddev:12.39 (17.0%) stddev-sans-first:12.67 03:19:39 INFO - PROCESS | 1960 | Values: 67.0 66.0 70.0 86.0 97.0 97.0 76.0 69.0 70.0 66.0 03:19:39 INFO - PROCESS | 1960 | -------- Summary: end -------- 03:19:39 INFO - PROCESS | 1960 | 03:19:40 INFO - TEST-INFO | 1960: exit 0 03:19:40 INFO - TEST-OK | kraken | took 59122ms 03:19:40 INFO - SUITE-END | took 1174s 03:19:40 INFO - Completed test suite (00:19:34) 03:19:40 INFO - javascript benchmark 03:19:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.8827191048313, 3370.191154123011, 3387.424137896557, 3432.8843125984063, 3442.0706409269496, 5599.804006859761, 5610.635308704931, 5612.186232996218, 5627.409122042183, 5636.549076073909, 5610.66336019838, 5644.435556444353, 5650.6892120933435, 5660.660360378381, 5669.177969194464, 5069.188929771238, 5105.43840177582, 5162.948370516303, 5202.895942081156, 5242.370915490146, 5176.818811341604, 5192.1432963560965, 5200.895982080356, 5203.2975548301065, 5205.635605507616, 5114.951434955825, 5147.150720131174, 5167.612429067816, 5179.0, 5179.093658609747, 5149.768260428271, 5182.948170518284, 5187.922181167285, 5188.299579556749, 5235.607329450307, 5036.345275114249, 5040.621953353513, 5069.417017043013, 5125.923111153335, 5130.333056702642, 5368.865778355534, 5408.513233808976, 5424.701641409721, 5449.64577302474, 5509.917351239735, 6165.784197553064, 6173.41352571505, 6175.536834737412, 6191.102290167901, 6193.0091185410565, 6085.208922840003, 6143.573288550997, 6160.0, 6228.377162283736, 6262.060690896356, 5697.829065127969, 5724.542036637059, 5724.998125328051, 5744.770209191627, 5756.395578464285, 6290.4653104485715, 6291.779787707408, 6298.464630506458, 6300.1494798202575, 6305.180326557518, 5940.821775346746, 6018.277806663228, 6022.518198544106, 6065.5450841187085, 6092.086187071975, 6203.410675985864, 6225.221847269091, 6231.75072997075, 6239.344868788802, 6244.500439964792, 6105.175801266861, 6146.047362658705, 6159.3224745277985, 6162.26052873658, 6171.0, 6025.397460253939, 6107.401224262706, 6136.325004249529, 6184.134221209034, 6196.442320191204, 4827.444843843003, 4831.275308703653, 4868.269759536098, 4869.732164730974, 4870.269459581091, 4299.462567179103, 4403.471583409947, 4408.493023302297, 4424.7123936944, 4463.24124898768, 5066.746662666926, 5206.76569554371, 5604.747786349542, 5611.158326251095, 5619.971900140555, 6081.2094427725, 6138.265785110792, 6146.7706458708435, 6226.128342032119, 6256.655883926337, 5573.916391254134, 5577.0797818359515, 5581.776728930798, 5589.329280486368, 5612.186232996274, 5278.076336641149, 5332.653377530526, 5332.893342133213, 5351.438098999549, 5397.163439666858, 6190.473809726223, 6204.689765511796, 6236.563440559117, 6253.499720022387, 6286.622802631857, 5074.568661663652, 6214.875702486015, 6231.813045608639, 6237.407446292595, 6246.46905013079, 6218.5647004709235, 6259.561830671992, 6263.561550691408, 6274.560780745304, 6283.3716628338625, 4968.403791545038, 6000.8499787503915, 6116.755329786936, 6118.357572455006, 6126.761967422209, 5601.271834661468, 5933.406659334032, 6190.50475961913, 6207.844803879939, 6210.875782484415, 5937.9406205937385, 5957.314908785372, 6004.309504406874, 6014.097885317237, 6023.548233882564, 5810.477057064884, 5904.143899134688, 5926.940730592639, 5992.250968628921, 6012.549058820518, 5795.0, 5835.241418615723, 5840.094785308114, 5854.385289544536, 5859.179714840011, 4952.628552858478, 6006.3693312201585, 6010.158577799024, 6027.789027384196, 6028.125921741235, 4538.205813982474, 5716.085426331767, 5793.217915581343, 5798.362180160095, 5823.0], "value": 5599.344657485739, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.8581228391397, 528.2657106621797, 530.0750190916848, 531.8776681363289, 592.1739173852474, 30896.69103308966, 31557.684423155762, 38268.0, 38992.22015559687, 39061.804690976576, 43706.562934370646, 44375.55624443755, 44414.77792611033, 44507.33239001418, 44543.554564454345, 8389.664413423456, 8556.871646925301, 9475.289353298513, 9571.090746379083, 9723.708288751348, 8464.0, 11318.377489238088, 11517.193796434252, 11528.711782205428, 11540.24988375753, 11280.774384512304, 11357.204995650307, 11360.943195284011, 11368.658940231806, 11441.256318339323, 8483.490990540588, 8703.737957996118, 11239.157063220251, 11291.717707057307, 11310.37792921389, 8479.533625650587, 8607.139286071342, 11301.0, 11497.482613282338, 11518.078553715683, 38700.0, 39147.0, 39179.804100979316, 39195.216095677926, 39228.80385598083, 3107.3319236364064, 3176.6188057433023, 3210.2455922858107, 3295.6045274567205, 3323.1525960880062, 22959.77040229593, 27381.726182738315, 27519.44961100787, 27635.723642763514, 27979.0, 2637.208837348807, 2752.4632696624167, 2758.158761577718, 2786.2477131174674, 2881.7838662100216, 486787.0, 490020.0, 490539.99999999645, 490991.0, 491024.0, 61550.38449615536, 62689.68655156695, 62771.74456510936, 62848.05727914085, 62885.0, 65564.34435655583, 65842.0, 65897.67051164809, 65990.01014984779, 65997.0, 17380.65238695219, 17903.37338193157, 17938.91030544839, 17945.282188712565, 17960.371387001393, 12651.93674031624, 12880.935595322151, 13634.318284085755, 13884.722305554034, 14015.229162396166, 15867.444639437565, 15957.601059973362, 15999.28003239857, 16041.679166416607, 16637.33450661984, 6525.11910892033, 6600.1089852869245, 6662.86674266512, 6676.26561078281, 6681.198256209286, 925.648253663617, 975.0931633580838, 1025.6153942271649, 1033.3593172233263, 1075.4622688655672, 29766.10701678953, 29889.40221195564, 30165.09504714862, 30231.848840755654, 31288.68711312903, 1065.2223876569992, 1081.7836432713489, 1106.0101209417526, 1110.35044498969, 1148.1733152130619, 14355.559554875696, 14399.280035998157, 14447.638809029648, 14713.92643036778, 14780.556583302518, 619025.0, 621216.0, 624322.5135497265, 628016.0, 628380.0, 58778.0, 58819.82360352769, 58889.82220355655, 58912.0, 58925.41074589199, 1360.4490181476517, 1479.8224213094497, 1513.6972605478948, 1518.1270769307914, 1528.533797191845, 28199.295017624725, 28322.575161372184, 28852.0, 29128.27179320534, 29163.270918226364, 45814.31278530758, 46368.6089417318, 46423.839404013816, 46470.070598588514, 46477.0, 365373.0, 367066.0, 367486.0, 367488.0, 367602.0, 25357.0, 26035.479290414463, 26434.471310574067, 26455.735442645328, 26514.337141571614, 43919.0, 44775.20899163997, 45923.77038114788, 46323.768381157875, 46453.0, 36019.45970810492, 36713.0, 37041.0, 37371.0, 38165.4275185878, 30431.54352684756, 36289.637103629684, 36315.91052268436, 36813.0, 36964.44553331648, 36488.27023459569, 36596.451053234756, 37047.073823154635, 37426.6257337434, 37917.81041094777, 32008.0, 32509.0, 32900.01299961005, 33128.33743325168, 33150.5027424584, 24655.630165547173, 24756.009759610133, 24921.127760528296, 25040.62439063379, 25256.621150682386], "value": 19542.174496373646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.6696308138083, 394.73355485047585, 401.53622565936354, 404.15329883893236, 410.85825390240376, 19187.32844350448, 20068.498287542818, 20710.068046937886, 20714.89642551787, 20720.0, 20453.48866278344, 20722.689159662616, 21090.734555926654, 21435.464113397175, 21487.462813429673, 5952.910706339398, 5975.880482390371, 6065.302490213638, 6143.10924915887, 6201.658908760016, 5664.20701101846, 6090.939090609104, 6130.080487926824, 6140.570160088796, 6143.293521245069, 5830.358660547357, 6103.1455596216565, 6103.6032657877095, 6141.477974372161, 6152.107944348067, 6076.848078798022, 6106.847328816771, 6127.050307202391, 6130.724117414727, 6139.386061393847, 5838.38696936824, 5918.082697181943, 5925.851853703649, 5929.407059294079, 5929.614575052651, 13711.314434278345, 13888.513902013381, 13943.860561394458, 19707.40877773669, 26340.814663340196, 2130.3608917324896, 2207.7129973103556, 2210.9115635374565, 2285.737140228878, 2315.872627005514, 10151.593936242616, 10270.281080324381, 10272.948635256776, 13802.091105341184, 13872.514461993882, 1712.1268153241813, 1737.1314342828587, 1746.1792957310045, 1764.2678288510253, 1798.3346161920174, 110729.44635276853, 110990.0, 116170.41914790457, 117170.24244636338, 117228.0, 11762.823557646641, 11817.822732659017, 11840.585579504675, 11844.052475801915, 11852.288862668094, 12439.0, 15057.849421505864, 15120.773188402183, 15126.319315630823, 15153.939224254196, 8799.648014079494, 8814.206721395105, 8854.513001784964, 8903.599338029804, 8971.237444817263, 7077.752278670222, 8737.956310218535, 8847.73456796297, 8859.202671759573, 8875.378723489423, 4112.992316882383, 4539.750313732777, 4617.561331673485, 4641.466231383367, 4672.649551283667, 1849.0921505788876, 1955.9706604400944, 1961.5586493039236, 1975.7530308711412, 1987.7316562264198, 889.9377043606885, 933.1228645073609, 940.7977284883783, 995.7908839143861, 1083.1226706367709, 16689.916550417413, 19187.32844350441, 20385.694214586794, 20389.694154587694, 20414.38756837297, 973.9707808765748, 1001.6994901529512, 1005.1807776661997, 1036.9481525923825, 1051.553089936789, 10641.308314959502, 10985.780284394268, 11001.724956875982, 11054.613088541862, 11113.498173896975, 595569.0, 604069.0, 609841.0, 630853.0, 634258.0, 118528.40735796439, 119795.20307195399, 122000.0, 123313.0, 125209.37395313148, 1014.2899970021015, 1015.3248090020202, 1033.0341131042499, 1033.4161198922584, 1037.5538518437145, 17856.821431785516, 18017.0, 18070.909645451688, 18378.0, 18720.034396388728, 49231.0, 49480.76298092576, 50973.0, 51902.221466678784, 52031.73984130055, 70049.29950700632, 71363.0, 71872.64063679648, 74252.0, 74552.0, 7811.218878112143, 8109.310708589836, 8231.46495477792, 8289.253967142986, 8315.210055044761, 9114.908850911406, 11981.0, 12202.816957745463, 12330.260184388966, 12437.937810311252, 9255.907440925504, 10272.691819245434, 10428.009339112772, 10796.92030796914, 10821.971912668283, 10294.176465882862, 10347.63783267582, 10698.197635177548, 10758.13934885223, 10988.945055274673, 10724.89275107239, 10740.0, 10947.452627368759, 11024.5038973248, 11110.722231944266, 7937.960310198412, 8181.590920454072, 8183.5499047550575, 8198.631061602362, 8449.95775021121, 8891.244244239311, 9534.0, 10669.733256668645, 10726.356418614909, 10774.299670521392], "value": 9476.928929464493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.8544852790462, 1530.5867415797734, 1554.6579752454459, 1782.8573714102872, 1940.1463356123313, 7835.412344074196, 8510.744677659672, 8699.782505437368, 8699.956500217506, 8700.869486957701, 8351.791205219872, 8593.968723753158, 8596.054434012254, 8599.785005374853, 8604.698835540757, 2428.150147448392, 2453.619688948207, 2456.312232574882, 2458.4345600511892, 2465.0016743219026, 2403.0682567267904, 2447.608382658766, 2448.0, 2450.0567281596623, 2468.1854987854053, 2430.2889497644483, 2440.9389765255924, 2445.8532488050773, 2454.840435371704, 2464.507098580282, 2410.1805386168785, 2432.501337225873, 2442.3039433761382, 2446.755324467548, 2455.963160552575, 2397.520495900827, 2430.927072187837, 2434.8417352872007, 2437.293184976355, 2437.390652336916, 8464.195901389357, 8692.956535217347, 8771.561421928878, 8781.0, 8789.956050219773, 5293.523582877521, 5457.426970168154, 5499.065158922953, 5501.559875209973, 5503.697296648683, 3472.9826350868084, 3567.090391950036, 3634.254977729559, 3644.5808731995894, 3659.1949771050326, 1015.6648306058983, 1057.7884423115408, 1060.342587595688, 1061.198794909846, 1070.507566519402, 11382.886171138265, 11814.409279535988, 11825.467853946593, 11844.34856082915, 11851.644450666494, 4789.281607758794, 4835.734034628059, 4862.756862156878, 4922.360093187863, 4941.2835138905375, 10214.642487512901, 10235.283530152967, 10237.641682541076, 10240.283180177465, 10253.487325633838, 5457.945420545823, 5491.670499770026, 5493.544210784145, 5500.972495137498, 5507.476789704971, 4375.256206444912, 4483.843065492692, 4731.52684731531, 4862.78117484714, 5000.199968005102, 4022.933922510537, 4045.939310910339, 4172.895677608084, 4186.372044193335, 4191.428214419592, 1141.4692168141853, 1150.355800751565, 1334.4728832111261, 1350.7163495665823, 1367.5418734723978, 846.4582667093065, 895.3106108296575, 897.0849733272026, 917.2249449215402, 922.5617831530104, 4690.437147542317, 5641.661500309912, 5838.591298609142, 5867.2959244890035, 5868.765249390062, 876.2989608313324, 925.9444433334022, 1003.6336737090909, 1028.2236911132047, 1040.380973320888, 3161.604799400075, 3727.5154229949935, 3766.8681596144, 3849.1531862990096, 3849.191669749328, 10291.794164116825, 10605.045545900906, 10620.309679870783, 10634.946825265824, 10736.409497477714, 10430.0, 10489.213309001856, 10580.947095264628, 10589.470526473646, 10605.522751476203, 7867.567283799332, 8358.791030224293, 8373.665053397795, 8436.072032076467, 8485.236328730567, 4773.952260477489, 4845.8061677532505, 5421.349438067457, 5630.183623374506, 5650.85872853182, 9171.587278572346, 9382.859257111286, 9489.905100949178, 9492.57283422234, 9668.419894806335, 9400.435973841591, 9770.706878793648, 9824.4105353679, 9989.750256243362, 10014.19886409071, 6361.650109244036, 6554.344565543408, 6721.731130754715, 6985.545939513915, 7081.46888983318, 8084.231997960184, 8095.676172953016, 8132.064812546396, 8146.592670366576, 8152.307053900247, 7134.536255143399, 7218.494705370784, 7259.709611615476, 7307.525010874276, 7355.264473552816, 7091.858162836611, 7100.254473280438, 7163.606001669749, 7206.2793720627515, 7243.927560724325, 6696.062551242732, 6803.217629972616, 6827.010083537959, 6938.757143499954, 6938.826529336807, 4699.929501057419, 4763.118823017753, 4779.5220477951925, 4859.979404325131, 4890.388701412324, 4743.217369134054, 4820.71075735457, 4832.299316599003, 4838.854834354975, 4923.433805112458], "value": 4561.331748711585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.30193574136905, 322.32472969129674, 332.47468998981606, 334.40475952803985, 335.2758042627924, 5392.0, 6531.9673401633045, 6575.9342406575915, 6673.065770792093, 6703.128593282879, 6368.235811702602, 6582.111414958979, 6597.670116494168, 6606.834829129274, 6611.239707433647, 4929.630277729185, 4935.950640493603, 4936.284238785374, 4939.43196532398, 4947.727874966884, 3941.8620348287814, 4661.834541364658, 4944.777485013183, 4957.0, 4965.652404331699, 3978.8461346209565, 4640.582347588716, 4717.834875779348, 4769.117713223048, 4816.518348165173, 4876.341693871317, 4878.097551952882, 4890.290907818364, 4903.436104847917, 4906.092372910987, 4180.101278225196, 4183.686223533247, 4188.727732697365, 4193.308104162809, 4203.516595591485, 5083.313752643383, 5706.543476521868, 5999.490043346321, 6296.401841825019, 6341.524385671093, 5674.773009079632, 5686.800961966353, 5687.7156142192725, 5691.943080569183, 5692.743826527775, 6702.329767023308, 6953.443724502028, 7023.051887995158, 7030.0, 7061.187963384174, 4550.977245113786, 4571.817127314904, 4585.564371384713, 4585.885352866172, 4594.816207351735, 7344.853102937965, 7433.962830185815, 7440.85118297631, 7465.178830328659, 7471.25287471254, 4617.722936623813, 4625.398698169217, 4628.328892310664, 4636.258198688193, 4636.44362676481, 3935.58676338986, 4679.064187162581, 4681.321208424827, 4685.531446855355, 4689.99165179488, 5347.1979203119845, 5427.5074354552025, 5555.444455554412, 5559.77760889568, 5570.387257401682, 6070.332263451017, 6683.331666833375, 6991.685374158175, 7021.1574611047, 7033.753818616324, 1297.941592628315, 1640.5734509027739, 1640.753886916972, 1644.925978330978, 1645.3418632547077, 982.7985263021063, 1001.1590264178126, 1027.8201314770035, 1030.8093002794358, 1057.4606950455322, 6550.0829883816305, 6568.343165683489, 6623.768168114093, 6640.20317561886, 6641.236257830315, 7291.8906216406795, 7320.377767889682, 7321.377682897013, 7324.890126648104, 7329.19378868324, 6605.30644282353, 6606.636634985122, 6615.239247486505, 6619.768308109193, 6620.602763834186, 3688.483612294281, 3806.200697853482, 3865.188310454836, 3868.7291889568023, 3870.496835411435, 7552.546847189186, 7573.848523029508, 7588.886166707503, 7599.65801538921, 7623.54258744466, 7392.445566582528, 7431.479796414199, 7464.365528929993, 7465.029546159073, 7465.925340746523, 7568.5458872467825, 7578.621068946531, 7581.583012934341, 7598.240175982468, 7606.581638009852, 6943.722251109899, 7226.963865180641, 7234.96382518084, 7246.746363877239, 7275.672594733355, 7057.682404291717, 7103.467239957127, 7105.573665579875, 7116.110486189226, 7123.252058533986, 6297.905531416941, 7416.184219735933, 7520.398368130427, 7550.509216900884, 7555.206703296075, 4577.679562430598, 4633.791479383503, 4648.2562789953445, 4649.349091127177, 4654.534546545318, 6799.388055074869, 7159.248278930637, 7171.820704482429, 7179.1026121734785, 7186.24544422849, 6886.311368863074, 6904.4131248842405, 6917.169939607078, 6927.064846245692, 6932.514723969475, 6919.515633905578, 6921.7231310747, 6950.860982780215, 6955.513114081966, 6961.965190174017, 5843.714382835769, 6762.4928130389435, 6764.864702706017, 6776.15298087739, 6778.694958726771, 6607.867842643216, 6773.187217533928, 6914.792556223321, 6934.514583979073, 7057.929420705933, 6708.597484150967, 6777.525573209828, 6881.793546193622, 6917.619530925847, 6919.654017299215], "value": 5092.158638355918, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.8530723240889, 693.1820451866797, 721.9891701624476, 732.9083864516936, 749.9062617172854, 14908.850911490881, 15062.623434414145, 15230.771538426932, 15241.695166096672, 15254.999999999985, 13989.720205595882, 14114.364853581588, 14118.294085295745, 14136.363863626137, 14162.78755818661, 762.2872614105801, 768.0706345322167, 910.8588168833825, 914.4376208631677, 946.8911075226334, 920.8020275640736, 921.5438358012793, 922.5525620074259, 922.9861552076724, 934.5327336331834, 965.6764983730459, 970.2383628851353, 970.6020531582053, 972.4457059476101, 984.3257368702461, 778.1168373895641, 954.742219600704, 981.4896253947943, 982.0866594067514, 987.1017374189453, 696.314130581377, 709.6097146569366, 718.8023293594251, 720.5748608321063, 720.8306048078697, 14599.635009124751, 14604.977651564395, 14626.414943402251, 14627.488037918622, 14639.341229644586, 12522.874771252262, 12688.36558172097, 12703.41311682311, 12992.805107923388, 13203.405846736823, 10804.837927431096, 10956.068734157607, 10972.377613684377, 11310.321380717183, 11397.544098236061, 3567.500549923013, 3567.9108022299392, 3592.8742494012586, 3678.0437086357474, 3811.447340135665, 75592.0, 76004.0, 76482.61758691171, 78051.8292225617, 78774.81837772438, 9787.951060244653, 9803.264755143393, 9921.255905807093, 10035.397876127456, 10452.216083793593, 22642.886785565966, 22782.544349113257, 23105.768942310697, 23114.768852311598, 23128.4217894554, 7619.123800762873, 7770.572618505923, 7820.7262745804765, 7920.366370690331, 7959.0, 4016.6184212499766, 4043.3732771420478, 4063.756174629532, 4112.09533902541, 4129.215449064668, 3615.168511242429, 3685.1708365618056, 3689.465027571041, 3690.538682664667, 3691.7046636269033, 577.3158806813939, 649.250116115887, 662.8641128568631, 669.9832504187336, 688.6763221285987, 505.83054676683724, 545.021686073499, 555.5914464758654, 558.3634656491603, 584.6141546579237, 7942.523448593103, 7994.0, 8005.519668819889, 8120.918790812017, 8165.346772258205, 582.4990508162977, 586.7213073789916, 593.8752861898963, 597.7340083662729, 607.4714997951722, 3723.8696645617274, 3735.9626403736156, 3796.2976849282973, 3916.74541154824, 4025.6578190854107, 83812.16187838165, 84726.576367119, 85442.0, 89904.0, 90082.54958725165, 74003.2599674007, 74092.0, 74286.0, 75030.0, 77074.0, 67622.66188669026, 68726.65636671784, 70277.64861175661, 70850.2914970854, 73518.0, 6720.46236301085, 7926.881096783438, 8096.7166149184495, 8166.83666326682, 8214.753557393287, 47345.7632711848, 49351.753241233564, 49898.0, 50300.24549631832, 51003.72490687762, 52733.15433959793, 66049.0, 70785.0, 71364.57270854658, 72299.63850180716, 10760.40817755031, 10883.455827208449, 11132.165087618623, 11281.71795705114, 11307.547698091985, 11575.652730418102, 11960.923516883522, 12035.277883327028, 12056.939715301367, 12316.261024338568, 11130.666080017612, 11404.0, 11406.0, 11569.768604628029, 11830.763384732085, 11376.54493820238, 11480.598179064027, 11529.711757206136, 11777.646670599896, 11866.406679666155, 10893.727656808644, 11047.281926674741, 11259.943700281447, 11297.7740445189, 11763.058955283386, 8562.629979203546, 9383.812323753624, 9440.611505434998, 9486.430814151174, 9514.38156519824, 9371.718848434557, 9515.904840951502, 9527.904720952702, 9596.088371604686, 9750.122488976027], "value": 6913.554868835923, "unit": "score"}], "name": "dromaeo_css", "value": 7417.400354701185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 121.0, 112.0, 113.0, 112.0, 114.0, 114.0, 112.0, 133.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 143.0, 165.0, 119.0, 140.0, 141.0, 117.0, 169.0, 106.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 187.0, 184.0, 183.0, 185.0, 185.0, 183.0, 184.0, 185.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 65.0, 142.0, 46.0, 58.0, 88.0, 94.0, 162.0, 57.0], "value": 86.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 143.0, 76.0, 77.0, 86.0, 122.0, 79.0, 76.0, 124.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 85.0, 85.0, 83.0, 87.0, 83.0, 108.0, 130.0], "value": 92.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 92.0, 93.0, 92.0, 93.0, 92.0, 107.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 86.0, 95.0, 87.0, 86.0, 84.0, 83.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 65.0, 61.0, 69.0, 92.0, 97.0, 113.0, 64.0, 59.0], "value": 74.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 74.0, 71.0, 72.0, 68.0, 69.0, 68.0, 67.0, 69.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 105.0, 105.0, 103.0, 83.0, 81.0, 81.0, 97.0, 103.0, 85.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 143.0, 134.0, 131.0, 128.0, 128.0, 129.0, 173.0, 167.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 166.0, 167.0, 167.0, 160.0, 219.0, 155.0, 204.0, 191.0, 169.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 86.0, 97.0, 97.0, 76.0, 69.0, 70.0, 66.0], "value": 76.4, "unit": "score"}], "name": "kraken", "value": 1524.9000000000003, "alertThreshold": 2.0}]} 03:19:40 INFO - Return code: 0 03:19:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:19:40 ERROR - # TBPL SUCCESS # 03:19:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:19:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:19:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:19:40 INFO - 2016-06-04 03:19:40.955 screenresolution[1964:21450] starting screenresolution argv=screenresolution get 03:19:40 INFO - 2016-06-04 03:19:40.958 screenresolution[1964:21450] Display 0: 1600x1200x32@60 03:19:40 INFO - 2016-06-04 03:19:40.964 screenresolution[1965:21453] starting screenresolution argv=screenresolution list 03:19:40 INFO - Available Modes on Display 0 03:19:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:19:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:19:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:19:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:19:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:19:41 INFO - Intel Iris: 03:19:41 INFO - Chipset Model: Intel Iris 03:19:41 INFO - Type: GPU 03:19:41 INFO - Bus: Built-In 03:19:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:19:41 INFO - Vendor: Intel (0x8086) 03:19:41 INFO - Device ID: 0x0a2e 03:19:41 INFO - Revision ID: 0x0009 03:19:41 INFO - Displays: 03:19:41 INFO - Display: 03:19:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:19:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:19:41 INFO - Display Serial Number: 1600x1200 60 03:19:41 INFO - Main Display: Yes 03:19:41 INFO - Mirror: Off 03:19:41 INFO - Online: Yes 03:19:41 INFO - Rotation: Supported 03:19:41 INFO - Return code: 0 03:19:41 INFO - Running post-action listener: _resource_record_post_action 03:19:41 INFO - Running post-run listener: _resource_record_post_run 03:19:42 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 405504; Write bytes: 652689408; Read time: 7; Write time: 1937 03:19:42 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353059328; Write bytes: 347612672; Read time: 27799; Write time: 632 03:19:42 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 385024; Write bytes: 295332352; Read time: 6; Write time: 1276 03:19:42 INFO - Running post-run listener: _upload_blobber_files 03:19:42 INFO - Blob upload gear active. 03:19:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:19:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:19:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:19:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:19:42 INFO - (blobuploader) - INFO - Open directory for files ... 03:19:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:19:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:19:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:19:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:19:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:19:43 INFO - (blobuploader) - INFO - Done attempting. 03:19:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:19:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:19:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:19:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:19:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:19:44 INFO - (blobuploader) - INFO - Done attempting. 03:19:44 INFO - (blobuploader) - INFO - Iteration through files over. 03:19:44 INFO - Return code: 0 03:19:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:19:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:19:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be54f04f5fd36b20be473383fbb82ace630321eda645c3b0e223ea0da11d8286c535c6afdb7c95b65aab72a87980a493b9085948177bf203b9f75c2f07f0fc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48515f399cb4bb7011034de4264beb54a2c9df8633bd828ac0be944bbde0e1f7c774a5d0862e9e2b37af45198e9be61cab90fe202120aa55a3a4e92fd33aa38f"} 03:19:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:19:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:19:44 INFO - Contents: 03:19:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be54f04f5fd36b20be473383fbb82ace630321eda645c3b0e223ea0da11d8286c535c6afdb7c95b65aab72a87980a493b9085948177bf203b9f75c2f07f0fc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48515f399cb4bb7011034de4264beb54a2c9df8633bd828ac0be944bbde0e1f7c774a5d0862e9e2b37af45198e9be61cab90fe202120aa55a3a4e92fd33aa38f"} 03:19:44 INFO - Running post-run listener: copy_logs_to_upload_dir 03:19:44 INFO - Copying logs to upload dir... 03:19:44 INFO - mkdir: /builds/slave/test/build/upload/logs 03:19:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.988995 ========= master_lag: 0.47 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-06-04 03:19:44.910542) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 03:19:44.911618) ========= 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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be54f04f5fd36b20be473383fbb82ace630321eda645c3b0e223ea0da11d8286c535c6afdb7c95b65aab72a87980a493b9085948177bf203b9f75c2f07f0fc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48515f399cb4bb7011034de4264beb54a2c9df8633bd828ac0be944bbde0e1f7c774a5d0862e9e2b37af45198e9be61cab90fe202120aa55a3a4e92fd33aa38f"} build_url:https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011288 build_url: 'https://queue.taskcluster.net/v1/task/Gfv-JoD3RBOK9ldwS7cP_A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be54f04f5fd36b20be473383fbb82ace630321eda645c3b0e223ea0da11d8286c535c6afdb7c95b65aab72a87980a493b9085948177bf203b9f75c2f07f0fc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48515f399cb4bb7011034de4264beb54a2c9df8633bd828ac0be944bbde0e1f7c774a5d0862e9e2b37af45198e9be61cab90fe202120aa55a3a4e92fd33aa38f"}' ========= master_lag: 0.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 03:19:45.213049) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-06-04 03:19:45.213367) ========= 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.KTAVLtT4Lo/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.gYyJcoLT5Y/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.005237 ========= master_lag: 5.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-06-04 03:19:50.280499) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 03:19:50.280842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 03:19:50.390178) ========= ========= Total master_lag: 5.89 =========