builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0037 starttime: 1462576005.2 results: success (0) buildid: 20160506135800 builduid: 25f8d0b308a64bd4bfe3f1d116779698 revision: 0a55f3f89efd80dadf3d951e1a0d073a66fec9a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.196603) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.197086) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.197363) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.215432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.215809) ========= 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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-05-06 16:06:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-05-06 16:06:45 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086726 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.406008) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.406327) ========= 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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.020110 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:45.441927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 16:06:45.442297) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0a55f3f89efd80dadf3d951e1a0d073a66fec9a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0a55f3f89efd80dadf3d951e1a0d073a66fec9a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-05-06 16:06:45,513 truncating revision to first 12 chars 2016-05-06 16:06:45,513 Setting DEBUG logging. 2016-05-06 16:06:45,513 attempt 1/10 2016-05-06 16:06:45,513 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0a55f3f89efd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 16:06:47,111 unpacking tar archive at: fx-team-0a55f3f89efd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.846670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 16:06:47.305774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:47.306101) ========= script_repo_revision: 0a55f3f89efd80dadf3d951e1a0d073a66fec9a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:47.306484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:47.306768) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 16:06:47.320528) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-06 16:06:47.320946) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:06:47 INFO - MultiFileLogger online at 20160506 16:06:47 in /builds/slave/test 16:06:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:06:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:06:47 INFO - {'append_to_log': False, 16:06:47 INFO - 'base_work_dir': '/builds/slave/test', 16:06:47 INFO - 'blob_upload_branch': 'Fx-Team', 16:06:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:06:47 INFO - 'branch': 'Fx-Team', 16:06:47 INFO - 'buildbot_json_path': 'buildprops.json', 16:06:47 INFO - 'config_files': ('talos/mac_config.py',), 16:06:47 INFO - 'default_actions': ('clobber', 16:06:47 INFO - 'read-buildbot-config', 16:06:47 INFO - 'download-and-extract', 16:06:47 INFO - 'populate-webroot', 16:06:47 INFO - 'create-virtualenv', 16:06:47 INFO - 'install', 16:06:47 INFO - 'run-tests'), 16:06:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:06:47 INFO - 'download_minidump_stackwalk': True, 16:06:47 INFO - 'download_symbols': 'ondemand', 16:06:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:06:47 INFO - 'tooltool.py': '/tools/tooltool.py', 16:06:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:06:47 INFO - '/tools/misc-python/virtualenv.py')}, 16:06:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:06:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:06:47 INFO - 'installer_path': 'installer.exe', 16:06:47 INFO - 'log_level': 'info', 16:06:47 INFO - 'log_name': 'talos', 16:06:47 INFO - 'log_to_console': True, 16:06:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:06:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:06:47 INFO - 'opt_config_files': (), 16:06:47 INFO - 'pip_index': False, 16:06:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:06:47 INFO - 'cmd': ('bash', 16:06:47 INFO - '-c', 16:06:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:06:47 INFO - 'enabled': True, 16:06:47 INFO - 'halt_on_failure': False, 16:06:47 INFO - 'name': 'check_screen_resolution'},), 16:06:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:06:47 INFO - 'cmd': ('bash', 16:06:47 INFO - '-c', 16:06:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:06:47 INFO - 'enabled': True, 16:06:47 INFO - 'halt_on_failure': False, 16:06:47 INFO - 'name': 'check_screen_resolution'},), 16:06:47 INFO - 'run_cmd_checks_enabled': True, 16:06:47 INFO - 'sps_profile': False, 16:06:47 INFO - 'sps_profile_interval': 0, 16:06:47 INFO - 'suite': 'dromaeojs', 16:06:47 INFO - 'system_bits': '32', 16:06:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:06:47 INFO - 'title': 't-yosemite-r7-0037', 16:06:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:06:47 INFO - 'use_talos_json': True, 16:06:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:06:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:06:47 INFO - 'work_dir': 'build'} 16:06:47 INFO - ##### 16:06:47 INFO - ##### Running clobber step. 16:06:47 INFO - ##### 16:06:47 INFO - Running pre-action listener: _resource_record_pre_action 16:06:47 INFO - Running main action method: clobber 16:06:47 INFO - rmtree: /builds/slave/test/build 16:06:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:06:48 INFO - Running post-action listener: _resource_record_post_action 16:06:48 INFO - ##### 16:06:48 INFO - ##### Finished clobber step (success) 16:06:48 INFO - ##### 16:06:48 INFO - ##### 16:06:48 INFO - ##### Running read-buildbot-config step. 16:06:48 INFO - ##### 16:06:48 INFO - Running pre-action listener: _resource_record_pre_action 16:06:48 INFO - Running main action method: read_buildbot_config 16:06:48 INFO - Using buildbot properties: 16:06:48 INFO - { 16:06:48 INFO - "project": "", 16:06:48 INFO - "product": "firefox", 16:06:48 INFO - "script_repo_revision": "production", 16:06:48 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:06:48 INFO - "repository": "", 16:06:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:06:48 INFO - "buildid": "20160506135800", 16:06:48 INFO - "pgo_build": "False", 16:06:48 INFO - "basedir": "/builds/slave/test", 16:06:48 INFO - "buildnumber": 699, 16:06:48 INFO - "slavename": "t-yosemite-r7-0037", 16:06:48 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:06:48 INFO - "platform": "yosemite_r7", 16:06:48 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:06:48 INFO - "branch": "fx-team", 16:06:48 INFO - "repo_path": "integration/fx-team", 16:06:48 INFO - "revision": "0a55f3f89efd80dadf3d951e1a0d073a66fec9a0", 16:06:48 INFO - "stage_platform": "macosx64", 16:06:48 INFO - "builduid": "25f8d0b308a64bd4bfe3f1d116779698", 16:06:48 INFO - "slavebuilddir": "test" 16:06:48 INFO - } 16:06:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:06:48 INFO - Running post-action listener: _resource_record_post_action 16:06:48 INFO - ##### 16:06:48 INFO - ##### Finished read-buildbot-config step (success) 16:06:48 INFO - ##### 16:06:48 INFO - ##### 16:06:48 INFO - ##### Running download-and-extract step. 16:06:48 INFO - ##### 16:06:48 INFO - Running pre-action listener: _resource_record_pre_action 16:06:48 INFO - Running main action method: download_and_extract 16:06:48 INFO - mkdir: /builds/slave/test/build/tests 16:06:48 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')]} 16:06:48 INFO - https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:06:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:06:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:06:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:06:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 16:06:49 INFO - Downloaded 1199 bytes. 16:06:49 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:06:49 INFO - Using the following test package requirements: 16:06:49 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:06:49 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:06:49 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:06:49 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'jsshell-mac.zip'], 16:06:49 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:06:49 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:06:49 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:06:49 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:06:49 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:06:49 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:06:49 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:06:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:49 INFO - https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:06:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:06:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:06:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:06:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:06:51 INFO - Downloaded 18145130 bytes. 16:06:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:06:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:06:54 INFO - Return code: 0 16:06:54 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:54 INFO - https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:06:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:06:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:06:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:06:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:06:55 INFO - Downloaded 18145130 bytes. 16:06:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:06:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:06:58 INFO - Return code: 0 16:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:58 INFO - https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:06:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:06:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:06:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:06:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:06:59 INFO - Downloaded 10840152 bytes. 16:06:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:06:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:07:00 INFO - Return code: 0 16:07:00 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')]} 16:07:00 INFO - https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:07:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:07:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:07:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:07:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 16:07:04 INFO - Downloaded 110443422 bytes. 16:07:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:07:04 INFO - mkdir: /builds/slave/test/properties 16:07:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:07:04 INFO - Writing to file /builds/slave/test/properties/build_url 16:07:04 INFO - Contents: 16:07:04 INFO - build_url:https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:07:05 INFO - Running post-action listener: _resource_record_post_action 16:07:05 INFO - Running post-action listener: set_extra_try_arguments 16:07:05 INFO - ##### 16:07:05 INFO - ##### Finished download-and-extract step (success) 16:07:05 INFO - ##### 16:07:05 INFO - ##### 16:07:05 INFO - ##### Running populate-webroot step. 16:07:05 INFO - ##### 16:07:05 INFO - Running pre-action listener: _resource_record_pre_action 16:07:05 INFO - Running main action method: populate_webroot 16:07:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:07:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:07:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:07:05 INFO - '--tppagecycles', 16:07:05 INFO - '7'], 16:07:05 INFO - 'tests': ['tsvgm']}}, 16:07:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:07:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:07:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:07:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:07:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:07:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:07:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['damp', 'tps']}, 16:07:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'tests': ['damp', 'tps']}, 16:07:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['dromaeo_dom']}, 16:07:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:07:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['a11yr', 16:07:05 INFO - 'ts_paint', 16:07:05 INFO - 'tpaint', 16:07:05 INFO - 'sessionrestore', 16:07:05 INFO - 'sessionrestore_no_auto_restore', 16:07:05 INFO - 'tabpaint']}, 16:07:05 INFO - 'other-e10s': {'tests': ['a11yr', 16:07:05 INFO - 'ts_paint', 16:07:05 INFO - 'tpaint', 16:07:05 INFO - 'sessionrestore', 16:07:05 INFO - 'sessionrestore_no_auto_restore', 16:07:05 INFO - 'tabpaint']}, 16:07:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:07:05 INFO - 'ts_paint', 16:07:05 INFO - 'tpaint', 16:07:05 INFO - 'sessionrestore', 16:07:05 INFO - 'sessionrestore_no_auto_restore', 16:07:05 INFO - 'tabpaint']}, 16:07:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:07:05 INFO - 'ts_paint', 16:07:05 INFO - 'tpaint', 16:07:05 INFO - 'sessionrestore', 16:07:05 INFO - 'sessionrestore_no_auto_restore', 16:07:05 INFO - 'tabpaint']}, 16:07:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['a11yr', 16:07:05 INFO - 'ts_paint', 16:07:05 INFO - 'tpaint', 16:07:05 INFO - 'sessionrestore', 16:07:05 INFO - 'sessionrestore_no_auto_restore', 16:07:05 INFO - 'tabpaint']}, 16:07:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['a11yr', 16:07:05 INFO - 'ts_paint', 16:07:05 INFO - 'tpaint', 16:07:05 INFO - 'sessionrestore', 16:07:05 INFO - 'sessionrestore_no_auto_restore', 16:07:05 INFO - 'tabpaint']}, 16:07:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['tsvgx', 16:07:05 INFO - 'tsvgr_opacity', 16:07:05 INFO - 'tart', 16:07:05 INFO - 'tscrollx', 16:07:05 INFO - 'cart']}, 16:07:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:07:05 INFO - 'tsvgr_opacity', 16:07:05 INFO - 'tart', 16:07:05 INFO - 'tscrollx', 16:07:05 INFO - 'cart']}, 16:07:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'talos_options': ['--disable-e10s'], 16:07:05 INFO - 'tests': ['tp5o']}, 16:07:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'tests': ['tp5o']}, 16:07:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'talos_options': ['--disable-e10s', 16:07:05 INFO - '--xperf_path', 16:07:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:07:05 INFO - 'tests': ['tp5n']}, 16:07:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:05 INFO - 'talos_options': ['--xperf_path', 16:07:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:07:05 INFO - 'tests': ['tp5n']}}, 16:07:05 INFO - 'talos.zip': {'path': '', 16:07:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:07:05 INFO - Running post-action listener: _resource_record_post_action 16:07:05 INFO - ##### 16:07:05 INFO - ##### Finished populate-webroot step (success) 16:07:05 INFO - ##### 16:07:05 INFO - ##### 16:07:05 INFO - ##### Running create-virtualenv step. 16:07:05 INFO - ##### 16:07:05 INFO - Running pre-action listener: _resource_record_pre_action 16:07:05 INFO - Running main action method: create_virtualenv 16:07:05 INFO - Creating virtualenv /builds/slave/test/build/venv 16:07:05 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 16:07:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:07:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:07:05 INFO - Using real prefix '/tools/python27' 16:07:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:07:06 INFO - Installing distribute.............................................................................................................................................................................................done. 16:07:09 INFO - Installing pip.................done. 16:07:09 INFO - Return code: 0 16:07:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:07: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')]} 16:07:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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')]} 16:07:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07: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', 'pip>=1.5'] in /builds/slave/test/build 16:07: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 pip>=1.5 16:07:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:09 INFO - 'HOME': '/Users/cltbld', 16:07:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:09 INFO - 'LOGNAME': 'cltbld', 16:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:09 INFO - 'MOZ_NO_REMOTE': '1', 16:07:09 INFO - 'NO_EM_RESTART': '1', 16:07:09 INFO - 'PAGER': '/bin/cat', 16:07:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:09 INFO - 'PWD': '/builds/slave/test', 16:07:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:09 INFO - 'SHELL': '/bin/bash', 16:07:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:09 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:09 INFO - 'USER': 'cltbld', 16:07:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:09 INFO - 'XPC_FLAGS': '0x0', 16:07:09 INFO - 'XPC_SERVICE_NAME': '0', 16:07:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:09 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 16:07:09 INFO - Cleaning up... 16:07:09 INFO - Return code: 0 16:07:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:07: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')]} 16:07:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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')]} 16:07:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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', '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 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07: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', 'psutil>=0.7.1'] in /builds/slave/test/build 16:07: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 psutil>=0.7.1 16:07:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:09 INFO - 'HOME': '/Users/cltbld', 16:07:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:09 INFO - 'LOGNAME': 'cltbld', 16:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:09 INFO - 'MOZ_NO_REMOTE': '1', 16:07:09 INFO - 'NO_EM_RESTART': '1', 16:07:09 INFO - 'PAGER': '/bin/cat', 16:07:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:09 INFO - 'PWD': '/builds/slave/test', 16:07:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:09 INFO - 'SHELL': '/bin/bash', 16:07:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:09 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:09 INFO - 'USER': 'cltbld', 16:07:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:09 INFO - 'XPC_FLAGS': '0x0', 16:07:09 INFO - 'XPC_SERVICE_NAME': '0', 16:07:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:09 INFO - Downloading/unpacking psutil>=0.7.1 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:07:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:07:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:07:12 INFO - Installing collected packages: psutil 16:07:12 INFO - Running setup.py install for psutil 16:07:12 INFO - building 'psutil._psutil_osx' extension 16:07:12 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 16:07:12 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 16:07:12 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 16:07:12 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 16:07:12 INFO - building 'psutil._psutil_posix' extension 16:07:12 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 16:07:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:07:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:07:12 INFO - ^ 16:07:12 INFO - 1 warning generated. 16:07:12 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 16:07:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:07:13 INFO - Successfully installed psutil 16:07:13 INFO - Cleaning up... 16:07:13 INFO - Return code: 0 16:07:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:07: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')]} 16:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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')]} 16:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:13 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 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07:13 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 16:07:13 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 16:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:13 INFO - 'HOME': '/Users/cltbld', 16:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:13 INFO - 'LOGNAME': 'cltbld', 16:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:13 INFO - 'MOZ_NO_REMOTE': '1', 16:07:13 INFO - 'NO_EM_RESTART': '1', 16:07:13 INFO - 'PAGER': '/bin/cat', 16:07:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:13 INFO - 'PWD': '/builds/slave/test', 16:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:13 INFO - 'SHELL': '/bin/bash', 16:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:13 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:13 INFO - 'USER': 'cltbld', 16:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:13 INFO - 'XPC_FLAGS': '0x0', 16:07:13 INFO - 'XPC_SERVICE_NAME': '0', 16:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:07:13 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 16:07:13 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 16:07:13 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 16:07:13 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 16:07:13 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 16:07:13 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 16:07:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:07:15 INFO - Installing collected packages: mozsystemmonitor 16:07:15 INFO - Running setup.py install for mozsystemmonitor 16:07:15 INFO - Successfully installed mozsystemmonitor 16:07:15 INFO - Cleaning up... 16:07:16 INFO - Return code: 0 16:07:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:16 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 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07:16 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 16:07:16 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 16:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:16 INFO - 'HOME': '/Users/cltbld', 16:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:16 INFO - 'LOGNAME': 'cltbld', 16:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:16 INFO - 'MOZ_NO_REMOTE': '1', 16:07:16 INFO - 'NO_EM_RESTART': '1', 16:07:16 INFO - 'PAGER': '/bin/cat', 16:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:16 INFO - 'PWD': '/builds/slave/test', 16:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:16 INFO - 'SHELL': '/bin/bash', 16:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:16 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:16 INFO - 'USER': 'cltbld', 16:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:16 INFO - 'XPC_FLAGS': '0x0', 16:07:16 INFO - 'XPC_SERVICE_NAME': '0', 16:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:16 INFO - Downloading/unpacking blobuploader==1.2.4 16:07:16 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 16:07:16 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 16:07:16 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 16:07:16 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 16:07:16 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 16:07:16 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 16:07:18 INFO - Downloading blobuploader-1.2.4.tar.gz 16:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:07:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:07:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:07:19 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 16:07:19 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 16:07:19 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 16:07:19 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 16:07:19 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 16:07:19 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 16:07:19 INFO - Downloading docopt-0.6.1.tar.gz 16:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:07:19 INFO - Installing collected packages: blobuploader, requests, docopt 16:07:19 INFO - Running setup.py install for blobuploader 16:07:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:07:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:07:20 INFO - Running setup.py install for requests 16:07:20 INFO - Running setup.py install for docopt 16:07:20 INFO - Successfully installed blobuploader requests docopt 16:07:20 INFO - Cleaning up... 16:07:20 INFO - Return code: 0 16:07:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:20 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 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07:20 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 16:07:20 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 16:07:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:20 INFO - 'HOME': '/Users/cltbld', 16:07:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:20 INFO - 'LOGNAME': 'cltbld', 16:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:20 INFO - 'MOZ_NO_REMOTE': '1', 16:07:20 INFO - 'NO_EM_RESTART': '1', 16:07:20 INFO - 'PAGER': '/bin/cat', 16:07:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:20 INFO - 'PWD': '/builds/slave/test', 16:07:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:20 INFO - 'SHELL': '/bin/bash', 16:07:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:20 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:20 INFO - 'USER': 'cltbld', 16:07:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:20 INFO - 'XPC_FLAGS': '0x0', 16:07:20 INFO - 'XPC_SERVICE_NAME': '0', 16:07:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-1bIqfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xUog0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Vvo10r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-7sZNLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-mzT5oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-udFSfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-JISVKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-m215ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:07:21 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-TTfSo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-odsbpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-urliIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-FW4JD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Jd8Vsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-_jOchx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-7zKXnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-6IMfg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:07:22 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-nL1QBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:07:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:07:22 INFO - Running setup.py install for manifestparser 16:07:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:07:22 INFO - Running setup.py install for mozcrash 16:07:23 INFO - Running setup.py install for mozdebug 16:07:23 INFO - Running setup.py install for mozdevice 16:07:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:07:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:07:23 INFO - Running setup.py install for mozfile 16:07:23 INFO - Running setup.py install for mozhttpd 16:07:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:07:23 INFO - Running setup.py install for mozinfo 16:07:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:07:23 INFO - Running setup.py install for mozInstall 16:07:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:07:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:07:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:07:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:07:24 INFO - Running setup.py install for mozleak 16:07:24 INFO - Running setup.py install for mozlog 16:07:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:07:24 INFO - Running setup.py install for moznetwork 16:07:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:07:24 INFO - Running setup.py install for mozprocess 16:07:24 INFO - Running setup.py install for mozprofile 16:07:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:07:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:07:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:07:24 INFO - Running setup.py install for mozrunner 16:07:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:07:24 INFO - Running setup.py install for mozscreenshot 16:07:25 INFO - Running setup.py install for moztest 16:07:25 INFO - Running setup.py install for mozversion 16:07:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:07:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:07:25 INFO - Cleaning up... 16:07:25 INFO - Return code: 0 16:07:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:25 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 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07:25 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 16:07:25 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 16:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:25 INFO - 'HOME': '/Users/cltbld', 16:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:25 INFO - 'LOGNAME': 'cltbld', 16:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:25 INFO - 'MOZ_NO_REMOTE': '1', 16:07:25 INFO - 'NO_EM_RESTART': '1', 16:07:25 INFO - 'PAGER': '/bin/cat', 16:07:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:25 INFO - 'PWD': '/builds/slave/test', 16:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:25 INFO - 'SHELL': '/bin/bash', 16:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:25 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:25 INFO - 'USER': 'cltbld', 16:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:25 INFO - 'XPC_FLAGS': '0x0', 16:07:25 INFO - 'XPC_SERVICE_NAME': '0', 16:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:07:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-22stfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:07:25 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)) 16:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:07:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-eN6Zj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:07:25 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)) 16:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:07:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-raDFXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:07:25 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)) 16:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-2kcgJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-4SnPy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-_KFKXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-8daggE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ZpG70R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-bzRPcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-G2hpYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-i1sXg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Mj8IgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:07:26 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)) 16:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:07:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-E6YY8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:07:27 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)) 16:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:07:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-V690QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:07:27 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)) 16:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-jWqXmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:27 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)) 16:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:07:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xeFvB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:07:27 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)) 16:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:07:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-SrR4hC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:07:27 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)) 16:07:27 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)) 16:07:27 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)) 16:07:27 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)) 16:07:27 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)) 16:07:27 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)) 16:07:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07:29 INFO - Downloading blessings-1.6.tar.gz 16:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:07:29 INFO - Installing collected packages: blessings 16:07:29 INFO - Running setup.py install for blessings 16:07:30 INFO - Successfully installed blessings 16:07:30 INFO - Cleaning up... 16:07:30 INFO - Return code: 0 16:07:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:07:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:07:30 INFO - Reading from file tmpfile_stdout 16:07:30 INFO - Current package versions: 16:07:30 INFO - blessings == 1.6 16:07:30 INFO - blobuploader == 1.2.4 16:07:30 INFO - docopt == 0.6.1 16:07:30 INFO - manifestparser == 1.1 16:07:30 INFO - mozInstall == 1.12 16:07:30 INFO - mozcrash == 0.17 16:07:30 INFO - mozdebug == 0.1 16:07:30 INFO - mozdevice == 0.48 16:07:30 INFO - mozfile == 1.2 16:07:30 INFO - mozhttpd == 0.7 16:07:30 INFO - mozinfo == 0.9 16:07:30 INFO - mozleak == 0.1 16:07:30 INFO - mozlog == 3.1 16:07:30 INFO - moznetwork == 0.27 16:07:30 INFO - mozprocess == 0.22 16:07:30 INFO - mozprofile == 0.28 16:07:30 INFO - mozrunner == 6.11 16:07:30 INFO - mozscreenshot == 0.1 16:07:30 INFO - mozsystemmonitor == 0.0 16:07:30 INFO - moztest == 0.7 16:07:30 INFO - mozversion == 1.4 16:07:30 INFO - psutil == 3.1.1 16:07:30 INFO - requests == 1.2.3 16:07:30 INFO - wsgiref == 0.1.2 16:07:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:07:30 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')]} 16:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:30 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')]} 16:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:30 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 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07:30 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 16:07:30 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 16:07:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:30 INFO - 'HOME': '/Users/cltbld', 16:07:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:30 INFO - 'LOGNAME': 'cltbld', 16:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:30 INFO - 'MOZ_NO_REMOTE': '1', 16:07:30 INFO - 'NO_EM_RESTART': '1', 16:07:30 INFO - 'PAGER': '/bin/cat', 16:07:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:30 INFO - 'PWD': '/builds/slave/test', 16:07:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:30 INFO - 'SHELL': '/bin/bash', 16:07:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:30 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:30 INFO - 'USER': 'cltbld', 16:07:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:30 INFO - 'XPC_FLAGS': '0x0', 16:07:30 INFO - 'XPC_SERVICE_NAME': '0', 16:07:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 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)) 16:07:30 INFO - Cleaning up... 16:07:30 INFO - Return code: 0 16:07:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:07:30 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')]} 16:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:30 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')]} 16:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:30 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 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07:30 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 16:07:30 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 16:07:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:30 INFO - 'HOME': '/Users/cltbld', 16:07:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:30 INFO - 'LOGNAME': 'cltbld', 16:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:30 INFO - 'MOZ_NO_REMOTE': '1', 16:07:30 INFO - 'NO_EM_RESTART': '1', 16:07:30 INFO - 'PAGER': '/bin/cat', 16:07:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:30 INFO - 'PWD': '/builds/slave/test', 16:07:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:30 INFO - 'SHELL': '/bin/bash', 16:07:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:30 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:30 INFO - 'USER': 'cltbld', 16:07:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:30 INFO - 'XPC_FLAGS': '0x0', 16:07:30 INFO - 'XPC_SERVICE_NAME': '0', 16:07:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:30 INFO - Downloading/unpacking jsonschema 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:07:33 INFO - Downloading/unpacking functools32 (from jsonschema) 16:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:33 INFO - Downloading functools32-3.2.3-2.tar.gz 16:07:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:07:33 INFO - warning: no files found matching '*.txt' 16:07:33 INFO - no previously-included directories found matching 'build' 16:07:33 INFO - no previously-included directories found matching 'dist' 16:07:33 INFO - no previously-included directories found matching '.git*' 16:07:33 INFO - Installing collected packages: jsonschema, functools32 16:07:33 INFO - Running setup.py install for functools32 16:07:34 INFO - warning: no files found matching '*.txt' 16:07:34 INFO - no previously-included directories found matching 'build' 16:07:34 INFO - no previously-included directories found matching 'dist' 16:07:34 INFO - no previously-included directories found matching '.git*' 16:07:34 INFO - Successfully installed jsonschema functools32 16:07:34 INFO - Cleaning up... 16:07:34 INFO - Return code: 0 16:07:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf82c4d2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, '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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 16:07:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:07:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:07:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:34 INFO - 'HOME': '/Users/cltbld', 16:07:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:34 INFO - 'LOGNAME': 'cltbld', 16:07:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:34 INFO - 'MOZ_NO_REMOTE': '1', 16:07:34 INFO - 'NO_EM_RESTART': '1', 16:07:34 INFO - 'PAGER': '/bin/cat', 16:07:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:34 INFO - 'PWD': '/builds/slave/test', 16:07:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:34 INFO - 'SHELL': '/bin/bash', 16:07:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:07:34 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:07:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:34 INFO - 'USER': 'cltbld', 16:07:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:34 INFO - 'XPC_FLAGS': '0x0', 16:07:34 INFO - 'XPC_SERVICE_NAME': '0', 16:07:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:34 INFO - Downloading/unpacking flake8 16:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:07:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:07:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:07:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:07:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:07:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:07:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:07:38 INFO - no previously-included directories found matching 'docs/_build' 16:07:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:07:38 INFO - Running setup.py install for pep8 16:07:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:07:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:07:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:07:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:07:38 INFO - no previously-included directories found matching 'docs/_build' 16:07:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:07:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:07:38 INFO - Cleaning up... 16:07:38 INFO - Return code: 0 16:07:38 INFO - Running post-action listener: _resource_record_post_action 16:07:38 INFO - Running post-action listener: _start_resource_monitoring 16:07:38 INFO - Starting resource monitoring. 16:07:38 INFO - ##### 16:07:38 INFO - ##### Finished create-virtualenv step (success) 16:07:38 INFO - ##### 16:07:38 INFO - ##### 16:07:38 INFO - ##### Running install step. 16:07:38 INFO - ##### 16:07:38 INFO - Running pre-action listener: _resource_record_pre_action 16:07:38 INFO - Running main action method: install 16:07:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:07:38 INFO - Reading from file tmpfile_stdout 16:07:38 INFO - Detecting whether we're running mozinstall >=1.0... 16:07:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:07:38 INFO - Reading from file tmpfile_stdout 16:07:38 INFO - Output received: 16:07:38 INFO - Usage: mozinstall [options] installer 16:07:38 INFO - Options: 16:07:38 INFO - -h, --help show this help message and exit 16:07:38 INFO - -d DEST, --destination=DEST 16:07:38 INFO - Directory to install application into. [default: 16:07:38 INFO - "/builds/slave/test"] 16:07:38 INFO - --app=APP Application being installed. [default: firefox] 16:07:38 INFO - mkdir: /builds/slave/test/build/application 16:07:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:08:12 INFO - Reading from file tmpfile_stdout 16:08:12 INFO - Output received: 16:08:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:08:12 INFO - Running post-action listener: _resource_record_post_action 16:08:12 INFO - ##### 16:08:12 INFO - ##### Finished install step (success) 16:08:12 INFO - ##### 16:08:12 INFO - ##### 16:08:12 INFO - ##### Running run-tests step. 16:08:12 INFO - ##### 16:08:12 INFO - Running pre-action listener: _resource_record_pre_action 16:08:12 INFO - Running main action method: run_tests 16:08:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:08:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:08:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:08:12 INFO - 2016-05-06 16:08:12.452 screenresolution[1918:17400] starting screenresolution argv=screenresolution get 16:08:12 INFO - 2016-05-06 16:08:12.455 screenresolution[1918:17400] Display 0: 1600x1200x32@60 16:08:12 INFO - 2016-05-06 16:08:12.461 screenresolution[1919:17402] starting screenresolution argv=screenresolution list 16:08:12 INFO - Available Modes on Display 0 16:08:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:08:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:08:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:08:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:08:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:08:12 INFO - Intel Iris: 16:08:12 INFO - Chipset Model: Intel Iris 16:08:12 INFO - Type: GPU 16:08:12 INFO - Bus: Built-In 16:08:12 INFO - VRAM (Dynamic, Max): 1536 MB 16:08:12 INFO - Vendor: Intel (0x8086) 16:08:12 INFO - Device ID: 0x0a2e 16:08:12 INFO - Revision ID: 0x0009 16:08:12 INFO - Displays: 16:08:12 INFO - Display: 16:08:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:08:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:08:12 INFO - Display Serial Number: 1600x1200 60 16:08:12 INFO - Main Display: Yes 16:08:12 INFO - Mirror: Off 16:08:12 INFO - Online: Yes 16:08:12 INFO - Rotation: Supported 16:08:12 INFO - Return code: 0 16:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:08:12 INFO - Python 2.7.3 16:08:12 INFO - Return code: 0 16:08:12 INFO - grabbing minidump binary from tooltool 16:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:12 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 0x109861e00>, '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 0x10968adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10985cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:08:12 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 16:08:12 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 16:08:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:08:12 INFO - Return code: 0 16:08:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:08:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:08:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:08:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:08:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:08:13 INFO - Return code: 0 16:08:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 16:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0037 --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 16:08:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aG8XUQYgGW/Render', 16:08:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:08:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:08:13 INFO - 'HOME': '/Users/cltbld', 16:08:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:08:13 INFO - 'LOGNAME': 'cltbld', 16:08:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:08:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:13 INFO - 'MOZ_NO_REMOTE': '1', 16:08:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:08:13 INFO - 'NO_EM_RESTART': '1', 16:08:13 INFO - 'PAGER': '/bin/cat', 16:08:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:13 INFO - 'PWD': '/builds/slave/test', 16:08:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:08:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:08:13 INFO - 'SHELL': '/bin/bash', 16:08:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wa5LQVdY1W/Listeners', 16:08:13 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 16:08:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:08:13 INFO - 'USER': 'cltbld', 16:08:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:08:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:13 INFO - 'XPC_FLAGS': '0x0', 16:08:13 INFO - 'XPC_SERVICE_NAME': '0', 16:08:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:08:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 16:08:14 INFO - mozversion application_buildid: 20160506135800 16:08:14 INFO - mozversion application_changeset: 0a55f3f89efd80dadf3d951e1a0d073a66fec9a0 16:08:14 INFO - mozversion application_display_name: Nightly 16:08:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:08:14 INFO - mozversion application_name: Firefox 16:08:14 INFO - mozversion application_remotingname: firefox 16:08:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:08:14 INFO - mozversion application_vendor: Mozilla 16:08:14 INFO - mozversion application_version: 49.0a1 16:08:14 INFO - mozversion platform_buildid: 20160506135800 16:08:14 INFO - mozversion platform_changeset: 0a55f3f89efd80dadf3d951e1a0d073a66fec9a0 16:08:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:08:14 INFO - mozversion platform_version: 49.0a1 16:08:14 INFO - using testdate: 1462576094 16:08:14 INFO - actual date: 1462576094 16:08:14 INFO - starting webserver on 'localhost:54199' 16:08:14 INFO - SUITE-START | Running 2 tests 16:08:14 INFO - TEST-START | dromaeo_css 16:08:14 INFO - operating with platform_type : mac_ 16:08:14 INFO - Initialising browser for dromaeo_css test... 16:08:14 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmp5kgBIk/profile http://localhost:54199/getInfo.html) 16:08:16 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 16:08:16 INFO - PROCESS | 1937 | colorDepth:24 16:08:16 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 16:08:16 INFO - PROCESS | 1937 | __metrics 16:08:16 INFO - PROCESS | 1937 | 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" 16:08:16 ERROR - PROCESS | 1937 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:08:16 INFO - TEST-INFO | 1937: exit 0 16:08:16 INFO - Browser initialized. 16:08:16 INFO - Running cycle 1/1 for dromaeo_css test... 16:08:16 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmp5kgBIk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:28 INFO - PROCESS | 1939 | RSS: Main: 238854144 16:08:28 INFO - PROCESS | 1939 | 16:09:20 INFO - PROCESS | 1939 | 1462576160691 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] 16:11:30 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:54199/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54199/tests/dromaeo/cssquery-ext.html) 16:11:32 INFO - PROCESS | 1939 | RSS: Main: 435335168 16:11:32 INFO - PROCESS | 1939 | 16:14:36 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:54199/tests/dromaeo/cssquery-ext.html (next: http://localhost:54199/tests/dromaeo/cssquery-jquery.html) 16:14:36 INFO - PROCESS | 1939 | RSS: Main: 378859520 16:14:36 INFO - PROCESS | 1939 | 16:17:39 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:54199/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54199/tests/dromaeo/cssquery-mootools.html) 16:17:39 INFO - PROCESS | 1939 | RSS: Main: 373653504 16:17:39 INFO - PROCESS | 1939 | 16:20:42 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:54199/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54199/tests/dromaeo/cssquery-prototype.html) 16:20:42 INFO - PROCESS | 1939 | RSS: Main: 370241536 16:20:42 INFO - PROCESS | 1939 | 16:23:46 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:54199/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54199/tests/dromaeo/cssquery-yui.html) 16:23:46 INFO - PROCESS | 1939 | RSS: Main: 344608768 16:23:46 INFO - PROCESS | 1939 | 16:26:49 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:54199/tests/dromaeo/cssquery-yui.html (next: http://localhost:54199/tests/dromaeo/cssquery-dojo.html) 16:26:50 INFO - PROCESS | 1939 | RSS: Main: 379404288 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | __start_tp_report 16:26:50 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 16:26:50 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 16:26:50 INFO - PROCESS | 1939 | |i|pagename|runs| 16:26:50 INFO - PROCESS | 1939 | |0;dojo.html;3386.153461634591;3408.982955085224;3441.3977553928066;3445.087051931237;3492.3015396920623;5603.299587551556;5675.091985282355;5738.368779434264;5743.167240750094;5760.855978600537;4738.720545452726;5658.321001479818;5742.052561327376;5756.050251708473;5758.165066065418;5160.458151894044;5203.713795741232;5207.6875387476875;5209.791608335662;5269.65747226431;5272.894542109156;5277.736113194344;5278.815241466549;5289.100852855005;5293.708846013467;5182.352205974254;5188.792448302082;5216.9305292414965;5221.06020916235;5292.576593872481;5281.392639846409;5292.126799078186;5307.071262529042;5307.946920530784;5353.170258609922;5078.4413714491375;5086.3896332440345;5160.92258616121;5199.532042116228;5241.449647786965;5522.944770552283;5581.413951535071;5648.774049038075;5677.829665110054;5683.204351390808;6173.969130154365;6175.135481032659;6191.473724733403;6243.65659888706;6259.812205633838;6300.5589608727405;6318.778842740528;6328.019157030668;6328.71520781566;6332.176817013758;5696.658200507983;5739.512141467939;5774.105013722796;5812.041013232769;5828.446297601721;6286.559940804192;6310.116583678288;6325.335839736854;6325.620462772248;6341.968290158612;6120.721420533169;6194.163787888668;6198.380161983766;6200.720967556471;6200.81397558074;5143.202803565453;5903.675297858573;6184.412480814315;6251.531135164881;6264.906026409608;6166.075088736726;6232.8130156095385;6236.064590311399;6241.375862413813;6247.375262473716;6243.719032643542;6272.780452684226;6278.623282603058;6283.497320214372;6294.276158241769;4867.853964381075;4903.313536104948;4905.7301848397965;4917.041176970528;4928.236123400879;4411.625011873963;4436.911261774746;4442.378067070613;4446.866594002185;4465.799039043251;5486.698231597221;5651.26533550644;5670.829875103754;5709.543236541067;5721.942780572224;6121.910283036266;6189.442950134509;6203.534734894901;6212.502999760009;6257.217847769029;5662.971685141548;5684.516816070681;5689.829305120853;5699.5155411790465;5735.283089613798;4572.862814115582;5407.864803379947;5408.8377348679605;5419;5461.017016936989;5307.65500242483;6188.4739797116845;6230.7507699693315;6310.11658367838;6328.6835658216905;6164.3835616438;6322.968385158045;6333.809985700436;6346.587471814318;6351.237851457855;6250.343713909974;6296.874062518632;6336.493080553452;6339.366063393623;6343.841403964938;5983.910241346296;6120.44915957566;6144.508439324753;6164.89031974249;6222.719977601109;6251.281102673251;6279.748810047547;6280.842978925563;6290.402411770874;6318.9052164216655;5926.970365148147;6117.755289788359;6133.754649813957;6136.417040381157;6148.877022459616;5935.317438494456;5955.285365756137;6020.397960203944;6078.6048906820915;6093.48205402528;5021.794769255425;5927.110933360031;5956.13636022783;5963.463288304073;5978.073398623221;6076.756929722762;6078.179445774764;6102.023676211785;6103.542234332354;6104.359042300494;5912.201852749824;5944.227250457413;5959.374265702038;5980.820575382745;6006.369331220334 16:26:50 INFO - PROCESS | 1939 | |1;ext.html;512.5003121956096;521.8623400985852;526.1213772999091;535.3762866260806;541.0747621567119;29463.852680736592;30417.935372261974;41921.371179432295;42868.57131428685;43388.34917476233;40797.79601101998;42992;43146.35280470795;43289.13421731564;43416.56583434164;9574.80850382992;9774.511274436285;9787.6084956602;9787.657431989881;9815.214782817358;8550.957245213796;8781.209691127797;11589.942050289737;11819.995300399434;11842.348670823101;11613.941930290337;11723.706907327343;11731.354775487387;11731.706707332341;11741.41292935354;8530.74407767768;10916.962888525577;11632.709182270426;11725.355105469196;11731.824022639667;10247.897521024768;11508.539658413654;11510.42447877611;11517.193796434252;11536.307821530734;39546.80226598877;39939.80180594558;40035.399469007985;40059.399109013386;40070.39894401586;3193.1059303395086;3210.325831575372;3487.2502411981545;3650.5254316938895;4044.8179831907637;28415.57376639352;28505.287367815763;28754.856225718948;28889.711102888912;29070.854645726846;2379.214859096494;2598.843514635988;2710.0514819813106;2735.343517555792;2735.603337516069;445030;452261;452681;452682;453912.730436349;58946.52633684107;59326.40673593295;59597;59678;59779.10331345033;62818.37181628217;62866.0570091449;62939;62943.6852815733;63104.68447657732;16773.161341932853;16988.660226795393;17010.064446455563;17013.48959531216;17016.319347225974;10833.566657333777;11994.940025299993;12238.938805305916;13198.670033249246;13294.468221271234;14470.829375593534;14780.408783648534;15722.449714259947;15943.890941266574;16117.032978021356;7099.574025558483;7210.2789721027475;7255.927440725525;7314.926850731531;7435.776926692208;795.5982228974447;895.9462432254086;931.5063016601213;1132.6262333429947;1153.1236260442124;28014;29046.564301535494;29705.554416683768;30078.69921300803;30164.24589385283;1139.663799179244;1157.9710507237219;1196.9820452693216;1204.9518019279305;1216.920900141488;15079.321430535652;15178.772318415233;15223.847761522464;15547.766783498257;15717.685646287238;610692;616183;620777;620924;623900;59765;59781.103283450786;59806.700966494886;59908.10137847936;59930.40069599248;1332.3271747767315;1475.4614565683448;1514.099111028936;1561.3832536148384;1561.7501199807975;29529.409411812074;30841.8457907709;31019.84490077535;31205.375892482476;31374.215644609067;51467;51997.48002520078;52428;52462.950740984204;52541;483817;488740;490413;491697;492739;35428.29143417169;37165.25669486649;37715;38389.424158637084;39738.60261397465;46092.53907461017;46175.07649847052;46454.53545464502;46737.831554211414;46811.76594117007;33253;38046.429303561024;38048.80975595215;38472.653457128865;39144.80427597958;37536.49854005918;37830.43254351242;38077.42883856689;38367.808160959015;38487.807560963134;36344.636553634125;37018;37048;37801.81099094597;37898.43152352772;31700.841495792374;32117.35765284728;33784;35339.646603533634;35588.822055889555;24459.510809783347;24769.62845557354;24888.626670599595;25500.23499295024;25597.61603575985 16:26:50 INFO - PROCESS | 1939 | |2;jquery.html;394.4359565820876;400.3754143536084;400.97794621295856;402.58131543195094;423.9978800105999;20028;20694.379168624928;21450.57098858022;21478.677819832716;21939.122435102618;12126;12278.201916875398;12587.370631468435;12634;12652.114351995364;4597.506234413965;5381.219723140132;5511.3937466878815;5568.972155139219;5611.494965453108;5964.493018093467;6133.01871700528;6135.662538560399;6139.754409823624;6144.5698801083945;5999.430054144849;6086.391360863922;6101;6101.0848372744;6124.2650881894015;5863.149843272701;5998.790042348541;6054.819310234506;6094.9390506094815;6105.511559075263;5197.896042079137;5335.973320133413;5360.383555891083;5551.944480555183;5559.360673522516;13652.97602679793;13867.029307948447;13880.722385552232;18093.638127237515;26649.733502665113;2072.9792702072937;2134.285014520138;2252;2334.995951740751;2469.4567195217023;9731.318807683465;9754.853677194847;10364.63723769672;12048.518059277618;14768.483103091448;1704.6590681863554;1733.453962001978;1741.8432341089363;1755.5523341547823;1770.2476447509885;130541.6945830539;131794.3410282952;131840.02239966407;132859.33570332182;133645;9678.370905891095;10305.536250868729;10337;10571.092949438966;10677.09244714208;10733.282674772;12819.29493877827;12925;12932.159409638532;12933.741325173633;8299.875501867476;8480.194381533744;8484.830303394021;8590.78523036929;8594.140585941355;7308.378787803096;8844.690435834715;8881.689140880038;8882.467051976902;8997.055309192572;3399.8300084996145;4016.4376987221813;4082.6937979651643;4089.2434899543678;4133.0700592366475;1620.8460196281335;1766.70849309865;1983.633027889845;1983.9008049597462;2003.4190084875515;870.7170169694748;909.3997961345481;919.5540163020929;971.32007594684;1004.3371374892682;20587.867667278442;20985.370438886857;21178.78821211799;21223.102491689955;21325.040373183245;935.3499317974079;935.8362286599818;938.7559234598917;949.8385274503213;1056.9207309451822;10686.251962362716;10968.012878840942;11035.724106897393;11085.83371249432;11106.666799996014;612389.5044198271;622818;636502.8174859096;640123;641432;133918;142758.2862085683;148272;149020;151550;996.835522138839;1027.1679939249232;1050.9894901051046;1067.546292825546;1069.9144068474347;18639.627207456047;18811.90594047021;19935.700964485255;19957.800421995595;19982.90008549948;52869;53879.730601346746;55903;56063.43936560745;56086.71956640354;72150;75541;77179.2282077172;78883.60558197173;80002.3999520018;8206.261436470746;8282.751517454486;8359.707410240613;8559;8817.779555511164;11010.559577616805;12785;13171;13334.733305334034;13353.866461335263;5495.203195536705;6970.686319115551;11223.831642525533;11321.848848718717;11360.715982100513;10165.54255058539;11139;11312.547498099984;11466.713332166762;11491.8276225855;11410;11515.366654834064;11565.190436669689;11642.6507204784;11807.468663909973;8323.583820809057;8403.95798021006;8430.030546486987;8489.83020339602;8573.614187361709;10507.106895913934;11190.88809111931;11447.02700270453;11490;11575.536978520764 16:26:50 INFO - PROCESS | 1939 | |3;mootools.html;1366.3100134432113;1395.2535393564444;1592.0447731361185;1724.9827501724988;1935.8257756801886;7744.419168562352;8510.361722870786;8712.956435217831;8737.519436431001;8839.646414143426;8409.747707568768;8651.48091114532;8656.394052416334;8656.437331573443;8658.220760131588;2153.224839057938;2385.2486466762916;2435.610302351624;2465.2111324376206;2465.543874383236;2487.1543675150447;2515;2515.408878913454;2516.19481765835;2525.848449093051;2406.3262286559793;2459.88930498128;2469.6789417375808;2476.913308034219;2477.1206221791276;2120.109553987329;2352.811775058008;2412.384841865322;2433.8174636902304;2442.914497992562;2437.5612389769835;2457.508498300347;2472.6291056341693;2475.7276699563213;2481.789047930928;8668.696595619185;8746.518941458216;8756.518391488466;8760.605772740242;8771.386002979794;5245.606579506514;5413.675179489204;5463.535599474009;5465.508104270595;5470.753816078286;3379.9493007604906;3437.656234376567;3460.8961731148106;3644.2347107107275;3649.8905032849057;1034.3328553083295;1046.3146638951498;1049.9527521261562;1052.7420781908481;1052.8473371361185;11643.825342619783;11756.882431175663;11756.94121529387;11767.588134415255;11770.293782373084;4568.994821139344;4592.402987611589;4722.905541889143;4725.952740472551;4736.5973892219545;9221.861672074925;9231.861522077174;9232.8615070774;9241.353105282566;9242.72271831846;5303.28405665238;5323.973380133075;5327.7336133193185;5333.813316533902;5338.119210330329;4386.912261754747;4530.728156310632;4798.760061996886;4939.407271127488;4970.701757894538;3333.8999830005137;3770.9064371332556;3823.4073718573663;3885.1646895917515;3919.2945269851703;976.858355538443;1145.5990403358924;1147.7419963363684;1151.3897634253858;1190.6606617114255;773.5010917957998;826.8594338962391;874.2001069021901;902.6344330546008;983.680303901235;5176.922346164811;5455.1271796512365;5735.598508104476;6090.817275481742;6120.354687655692;462.9467611224685;466.7316293131463;467.231404339859;473.6305681568382;481.4295060353492;3947.309220886391;3968.503937007874;4045.4134150548007;4054.3715724062818;4106.055607210358;10268.435236062089;10695.73260668474;10708.411037393018;10716.196285278635;10827.78344433107;10502.527386267637;10653.520591573242;10679.359238445562;10808.405537695502;10811.135109191246;7996.200379961957;8175.100738918714;8229.094799572042;8264.710735124361;8304.8339033219;5887.087501437284;6019.036954087325;6038.818835434944;6083.361247068994;6107.297660768891;9495.05049495045;9748.658796942073;9990.850137247802;10104.242181836245;10176.949115254376;10028.69913902584;10094.192464602667;10239.43683097407;10345.844812327972;10359.430231337348;6120.387961203844;6649.168853893263;6886;6891.793246202622;7004.754833580801;8322.16778322163;8350.290225330671;8427.241548260687;8428.45215061019;8440.957795210985;7472.0286362772495;7517.736879209201;7612.619369031416;7631.580263085361;7700.653470593725;7288.927110728825;7364.374028207662;7380.704771809067;7498;7502.812429689301;7030.191527974144;7068.893966590608;7113.2175460698245;7120.1099862517185;7127.750528731677;3979.840806367713;4786;4793.185158523129;5010.3737032870895;5128.1794912813775;4979.507790999342;5005.574526165171;5079.6952182869145;5156.1750119980625;5304.07178743726 16:26:50 INFO - PROCESS | 1939 | |4;prototype.html;308.4201700802491;315.25599584979443;322.27166603476144;323.1742896898425;327.27671845222056;4566.954330456694;5143.074246635609;5251.107311757001;5260.447652996438;5271.341082364705;4238.173556156551;4430.62339701125;5182.5382943102;5204.973975130129;5211.973940130294;3715.9628403716024;3779.603141670126;3814.9237015259678;3858.1897801461587;3990.102226998931;3432.759706820511;3492.738044646649;3791.9572117667726;3875.593062728415;4026.7919624112797;3309.602847658284;3931.76409415436;3935.232629637222;3944.2900277949957;3958.2875082485284;3135.9494569319304;3497.318022985519;3722.516072910518;3982.761034337949;3984.402339649047;3494.2662040971427;3497.8425970831117;3512.1921957949817;3518.683318501347;3521.7358698097487;4969.552740253394;5004.423606563921;5025.497450254945;5029.371328583927;5051.343325367716;3712.832922518493;4496.123255965088;4561.110583436198;4590.3344015117955;4602.815887364502;5149.356330458692;5371.865703357408;5422.810201642924;5461.590380721462;5466.562674985991;3274.345130973815;3698.223373091654;3743.5507739071486;3871.1676989447406;3929.8231579578987;4702.412198475191;5155.793768249266;5282.471752824726;5326.680399176062;5672.432756724335;3847.403652433821;3855.9974406654474;3916.4321173429694;3925.666318362914;3940.802959851996;3432.9656703432647;3973.543042550086;3975.7018223633345;4031.234065527599;4045.231406032903;3708.313961917;4029.1135950090934;4287.655628609689;4572.359869618256;4617.953820461752;4798.2082956312515;5299.364076310791;5383.81156659515;5472.069748142825;5511.106668998807;1168.9713052513735;1271.4850485553366;1279.3411393132358;1284.4797856867983;1314.9802752958522;729.4273994913967;776.2858170483169;796.7689370082727;833.4874052457695;833.6623667414707;4873.390826146731;4923.975380123076;5065.290859279704;5099.36257967754;5172.379314482287;5562.137868630369;5596.768710883599;5619;5624.01926978702;5703;4408.58118478744;4710.458297295786;5069.188929771292;5124.48755124492;5246.3704355477585;2463.6674049003514;2608.1612463213387;2617.5811870100692;2816;2822.4778415993105;5191.480851914778;5337.359516858002;5533.7786488540005;5587.720613969285;5619.971900140473;4481.417415735934;4853.830115945925;5314.502183973526;5389.245505629215;5534.976029434568;5544.63959842617;5603.971980140154;5631.352394474688;5648.689322087324;5667.093265077568;4738.786754596121;5035.924461133012;5380.811671591632;5428.37573679032;5458.863528411663;4605.098787763687;4607.93088103672;4814.0874770763185;4862.696513585432;5008.899822003466;4785.377900872864;4862.635302352267;4869.70781753096;5292.603054770984;5335.199720042025;3161.5930910744496;3260.527223552619;3273.7544684148306;3417.6753208445157;3511.2099777549847;4969.35398398206;5151.6908985461;5281.498257665516;5311.654742441729;5389.119833719716;4830.420349557935;4960.958198778153;5169.508896654812;5238.607104467256;5240.161574148118;4845.885446347289;5079.3650793650795;5125.2568377585785;5175.275461435327;5200.373811225307;4022.8534867562603;4620.976895115503;4822.831200907952;4881.780319885543;5108.591312694901;4581.702189357815;4631.745254011062;4908.63185261114;5442.102053161184;5447.828716825743;4552.11233809404;5326.0945639240645;5350.384705758732;5371.140617501181;5391.838244852661 16:26:50 INFO - PROCESS | 1939 | |5;yui.html;662.6587307536619;739.914556300228;750.5909279442703;751.56784848712;759.5670467833337;14395.496157634485;15092.622684432894;15120.470783522578;15377.923110384432;15386.076835389857;14364.92817535911;14397.712045759079;14414.783778243334;14425.711485770278;14462.493812716557;912.7353067610385;924.7919218175924;931.2782593490032;932.1610550504532;935.2798345274135;732.3957734868728;755.403231447156;918.0314767919842;920.5305294299889;920.903305152956;948.7628092976756;977.4086677560081;979.4466126638462;980.2060331131806;982.3614650477211;962.7352478068552;970.8495183246608;971.5579411367881;972.747085757701;973.8441849304148;559.7509108446743;704.8907419350008;721.6896734404204;722.8156820010863;726.8764310067302;14771.409143634242;14825.925870370687;14826.33281502335;14834.703305933928;14837.851621483755;12108.091893108052;12160.939195304056;12213.145079844415;12351.59568674432;12644.55744048963;10690.99224497909;10729.624463143831;11153.609623663133;11216.495257713423;11435.313881167156;3910.4720862683457;4005.61946615074;4126.66986641068;4147.6474499667565;4266.466691663542;62273;74681;75453.86819197716;76358.61820690916;78155.21844781536;9291.163795258459;9574.856377154349;9669.371490853073;9699.466529340812;10149.949250253701;18638.440846774618;21226.36320910375;21369.572608547744;21377.893110534347;21392.893035534722;7395.445341599401;7429.8142546436775;7506.3244308012545;7538.3592394645975;7557.4331925105835;3994.380870965005;4022.7184097113504;4034.8991275218355;4055.513338399411;4105.486814148231;3403.9319213616086;3448.1207292140343;3498.0555250082843;3504.6144924058335;3505.4040813061843;633.0409429713987;640.6316368088368;644.458654730029;655.8163714160042;668.3116390118186;510.9616778741609;521.5292874095092;549.1214057508035;555.5860883641632;562.7242651100931;7835.138134805167;7857.803554911173;7867.6459559319965;7908.406869484812;7996.160403157701;502.7436007636132;509.78079425847244;522.5140619224157;526.5392781316348;574.9367569567344;3570.9821450892578;3729.7544669505223;3760.7367484276383;3768.8304026318883;3797.6582107610448;78841;81535;81758.18241817506;85697.14302857016;87304;77355.61322193353;78554;78582.60708696535;78616.60691696504;78626.21373786303;69472.95790563045;71054;71330.9300360495;71928;72985;6735.764248251287;7978.880316795369;8057.227409969797;8344.58277086131;8432.36757243197;37130.8143459281;37171.442428364135;39265;39973.60026399699;41758.95602609959;53543.46456535385;58889.70555147197;58926.06911082057;60287;60563.69718151381;10813.24307298513;10984.450777460936;11184.440777960906;11552.364619945984;12035.217710848767;9627.700260465028;11260.662180134608;12105.818412723993;12371.505139794668;12493.625191244277;8864.911350886667;11108.833367499656;11235.71910702239;11403.03074238699;12259.570915017932;9488.95255523718;11108.055815255793;11207.66377008691;11268.718282043015;11275.830862537232;10967.890321096687;11047.668569942914;11050.171237157407;11056.557737690724;11062.004419602274;9261.72214833556;9419.199368053793;9482.194013508653;9594.472304023067;9849.852252216078;9323.766905827408;9452.716418507456;9485.620575176916;9507.477088760184;9613.51932403363 16:26:50 INFO - PROCESS | 1939 | __end_tp_report 16:26:50 INFO - PROCESS | 1939 | __start_cc_report 16:26:50 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,2005 16:26:50 INFO - PROCESS | 1939 | __end_cc_report 16:26:50 INFO - PROCESS | 1939 | __startTimestamp1462577210153__endTimestamp 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | ------- Summary: start ------- 16:26:50 INFO - PROCESS | 1939 | Number of tests: 6 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | [#0] dojo.html Cycles:165 Average:5723.94 Median:5935.32 stddev:642.27 (10.8%) stddev-sans-first:617.50 16:26:50 INFO - PROCESS | 1939 | Values: 3386.2 3409.0 3441.4 3445.1 3492.3 5603.3 5675.1 5738.4 5743.2 5760.9 4738.7 5658.3 5742.1 5756.1 5758.2 5160.5 5203.7 5207.7 5209.8 5269.7 5272.9 5277.7 5278.8 5289.1 5293.7 5182.4 5188.8 5216.9 5221.1 5292.6 5281.4 5292.1 5307.1 5307.9 5353.2 5078.4 5086.4 5160.9 5199.5 5241.4 5522.9 5581.4 5648.8 5677.8 5683.2 6174.0 6175.1 6191.5 6243.7 6259.8 6300.6 6318.8 6328.0 6328.7 6332.2 5696.7 5739.5 5774.1 5812.0 5828.4 6286.6 6310.1 6325.3 6325.6 6342.0 6120.7 6194.2 6198.4 6200.7 6200.8 5143.2 5903.7 6184.4 6251.5 6264.9 6166.1 6232.8 6236.1 6241.4 6247.4 6243.7 6272.8 6278.6 6283.5 6294.3 4867.9 4903.3 4905.7 4917.0 4928.2 4411.6 4436.9 4442.4 4446.9 4465.8 5486.7 5651.3 5670.8 5709.5 5721.9 6121.9 6189.4 6203.5 6212.5 6257.2 5663.0 5684.5 5689.8 5699.5 5735.3 4572.9 5407.9 5408.8 5419.0 5461.0 5307.7 6188.5 6230.8 6310.1 6328.7 6164.4 6323.0 6333.8 6346.6 6351.2 6250.3 6296.9 6336.5 6339.4 6343.8 5983.9 6120.4 6144.5 6164.9 6222.7 6251.3 6279.7 6280.8 6290.4 6318.9 5927.0 6117.8 6133.8 6136.4 6148.9 5935.3 5955.3 6020.4 6078.6 6093.5 5021.8 5927.1 5956.1 5963.5 5978.1 6076.8 6078.2 6102.0 6103.5 6104.4 5912.2 5944.2 5959.4 5980.8 6006.4 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | [#1] ext.html Cycles:180 Average:66395.41 Median:37350.88 stddev:139795.78 (374.3%) stddev-sans-first:140100.42 16:26:50 INFO - PROCESS | 1939 | Values: 512.5 521.9 526.1 535.4 541.1 29463.9 30417.9 41921.4 42868.6 43388.3 40797.8 42992.0 43146.4 43289.1 43416.6 9574.8 9774.5 9787.6 9787.7 9815.2 8551.0 8781.2 11589.9 11820.0 11842.3 11613.9 11723.7 11731.4 11731.7 11741.4 8530.7 10917.0 11632.7 11725.4 11731.8 10247.9 11508.5 11510.4 11517.2 11536.3 39546.8 39939.8 40035.4 40059.4 40070.4 3193.1 3210.3 3487.3 3650.5 4044.8 28415.6 28505.3 28754.9 28889.7 29070.9 2379.2 2598.8 2710.1 2735.3 2735.6 445030.0 452261.0 452681.0 452682.0 453912.7 58946.5 59326.4 59597.0 59678.0 59779.1 62818.4 62866.1 62939.0 62943.7 63104.7 16773.2 16988.7 17010.1 17013.5 17016.3 10833.6 11994.9 12238.9 13198.7 13294.5 14470.8 14780.4 15722.4 15943.9 16117.0 7099.6 7210.3 7255.9 7314.9 7435.8 795.6 895.9 931.5 1132.6 1153.1 28014.0 29046.6 29705.6 30078.7 30164.2 1139.7 1158.0 1197.0 1205.0 1216.9 15079.3 15178.8 15223.8 15547.8 15717.7 610692.0 616183.0 620777.0 620924.0 623900.0 59765.0 59781.1 59806.7 59908.1 59930.4 1332.3 1475.5 1514.1 1561.4 1561.8 29529.4 30841.8 31019.8 31205.4 31374.2 51467.0 51997.5 52428.0 52463.0 52541.0 483817.0 488740.0 490413.0 491697.0 492739.0 35428.3 37165.3 37715.0 38389.4 39738.6 46092.5 46175.1 46454.5 46737.8 46811.8 33253.0 38046.4 38048.8 38472.7 39144.8 37536.5 37830.4 38077.4 38367.8 38487.8 36344.6 37018.0 37048.0 37801.8 37898.4 31700.8 32117.4 33784.0 35339.6 35588.8 24459.5 24769.6 24888.6 25500.2 25597.6 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | [#2] jquery.html Cycles:180 Average:36334.39 Median:21708.90 stddev:105860.26 (487.6%) stddev-sans-first:106122.83 16:26:50 INFO - PROCESS | 1939 | Values: 394.4 400.4 401.0 402.6 424.0 20028.0 20694.4 21450.6 21478.7 21939.1 12126.0 12278.2 12587.4 12634.0 12652.1 4597.5 5381.2 5511.4 5569.0 5611.5 5964.5 6133.0 6135.7 6139.8 6144.6 5999.4 6086.4 6101.0 6101.1 6124.3 5863.1 5998.8 6054.8 6094.9 6105.5 5197.9 5336.0 5360.4 5551.9 5559.4 13653.0 13867.0 13880.7 18093.6 26649.7 2073.0 2134.3 2252.0 2335.0 2469.5 9731.3 9754.9 10364.6 12048.5 14768.5 1704.7 1733.5 1741.8 1755.6 1770.2 130541.7 131794.3 131840.0 132859.3 133645.0 9678.4 10305.5 10337.0 10571.1 10677.1 10733.3 12819.3 12925.0 12932.2 12933.7 8299.9 8480.2 8484.8 8590.8 8594.1 7308.4 8844.7 8881.7 8882.5 8997.1 3399.8 4016.4 4082.7 4089.2 4133.1 1620.8 1766.7 1983.6 1983.9 2003.4 870.7 909.4 919.6 971.3 1004.3 20587.9 20985.4 21178.8 21223.1 21325.0 935.3 935.8 938.8 949.8 1056.9 10686.3 10968.0 11035.7 11085.8 11106.7 612389.5 622818.0 636502.8 640123.0 641432.0 133918.0 142758.3 148272.0 149020.0 151550.0 996.8 1027.2 1051.0 1067.5 1069.9 18639.6 18811.9 19935.7 19957.8 19982.9 52869.0 53879.7 55903.0 56063.4 56086.7 72150.0 75541.0 77179.2 78883.6 80002.4 8206.3 8282.8 8359.7 8559.0 8817.8 11010.6 12785.0 13171.0 13334.7 13353.9 5495.2 6970.7 11223.8 11321.8 11360.7 10165.5 11139.0 11312.5 11466.7 11491.8 11410.0 11515.4 11565.2 11642.7 11807.5 8323.6 8404.0 8430.0 8489.8 8573.6 10507.1 11190.9 11447.0 11490.0 11575.5 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | [#3] mootools.html Cycles:180 Average:5665.35 Median:2640.09 stddev:3189.45 (120.8%) stddev-sans-first:3182.03 16:26:50 INFO - PROCESS | 1939 | Values: 1366.3 1395.3 1592.0 1725.0 1935.8 7744.4 8510.4 8713.0 8737.5 8839.6 8409.7 8651.5 8656.4 8656.4 8658.2 2153.2 2385.2 2435.6 2465.2 2465.5 2487.2 2515.0 2515.4 2516.2 2525.8 2406.3 2459.9 2469.7 2476.9 2477.1 2120.1 2352.8 2412.4 2433.8 2442.9 2437.6 2457.5 2472.6 2475.7 2481.8 8668.7 8746.5 8756.5 8760.6 8771.4 5245.6 5413.7 5463.5 5465.5 5470.8 3379.9 3437.7 3460.9 3644.2 3649.9 1034.3 1046.3 1050.0 1052.7 1052.8 11643.8 11756.9 11756.9 11767.6 11770.3 4569.0 4592.4 4722.9 4726.0 4736.6 9221.9 9231.9 9232.9 9241.4 9242.7 5303.3 5324.0 5327.7 5333.8 5338.1 4386.9 4530.7 4798.8 4939.4 4970.7 3333.9 3770.9 3823.4 3885.2 3919.3 976.9 1145.6 1147.7 1151.4 1190.7 773.5 826.9 874.2 902.6 983.7 5176.9 5455.1 5735.6 6090.8 6120.4 462.9 466.7 467.2 473.6 481.4 3947.3 3968.5 4045.4 4054.4 4106.1 10268.4 10695.7 10708.4 10716.2 10827.8 10502.5 10653.5 10679.4 10808.4 10811.1 7996.2 8175.1 8229.1 8264.7 8304.8 5887.1 6019.0 6038.8 6083.4 6107.3 9495.1 9748.7 9990.9 10104.2 10176.9 10028.7 10094.2 10239.4 10345.8 10359.4 6120.4 6649.2 6886.0 6891.8 7004.8 8322.2 8350.3 8427.2 8428.5 8441.0 7472.0 7517.7 7612.6 7631.6 7700.7 7288.9 7364.4 7380.7 7498.0 7502.8 7030.2 7068.9 7113.2 7120.1 7127.8 3979.8 4786.0 4793.2 5010.4 5128.2 4979.5 5005.6 5079.7 5156.2 5304.1 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | [#4] prototype.html Cycles:180 Average:4304.32 Median:4849.86 stddev:1309.57 (27.0%) stddev-sans-first:1278.44 16:26:50 INFO - PROCESS | 1939 | Values: 308.4 315.3 322.3 323.2 327.3 4567.0 5143.1 5251.1 5260.4 5271.3 4238.2 4430.6 5182.5 5205.0 5212.0 3716.0 3779.6 3814.9 3858.2 3990.1 3432.8 3492.7 3792.0 3875.6 4026.8 3309.6 3931.8 3935.2 3944.3 3958.3 3135.9 3497.3 3722.5 3982.8 3984.4 3494.3 3497.8 3512.2 3518.7 3521.7 4969.6 5004.4 5025.5 5029.4 5051.3 3712.8 4496.1 4561.1 4590.3 4602.8 5149.4 5371.9 5422.8 5461.6 5466.6 3274.3 3698.2 3743.6 3871.2 3929.8 4702.4 5155.8 5282.5 5326.7 5672.4 3847.4 3856.0 3916.4 3925.7 3940.8 3433.0 3973.5 3975.7 4031.2 4045.2 3708.3 4029.1 4287.7 4572.4 4618.0 4798.2 5299.4 5383.8 5472.1 5511.1 1169.0 1271.5 1279.3 1284.5 1315.0 729.4 776.3 796.8 833.5 833.7 4873.4 4924.0 5065.3 5099.4 5172.4 5562.1 5596.8 5619.0 5624.0 5703.0 4408.6 4710.5 5069.2 5124.5 5246.4 2463.7 2608.2 2617.6 2816.0 2822.5 5191.5 5337.4 5533.8 5587.7 5620.0 4481.4 4853.8 5314.5 5389.2 5535.0 5544.6 5604.0 5631.4 5648.7 5667.1 4738.8 5035.9 5380.8 5428.4 5458.9 4605.1 4607.9 4814.1 4862.7 5008.9 4785.4 4862.6 4869.7 5292.6 5335.2 3161.6 3260.5 3273.8 3417.7 3511.2 4969.4 5151.7 5281.5 5311.7 5389.1 4830.4 4961.0 5169.5 5238.6 5240.2 4845.9 5079.4 5125.3 5175.3 5200.4 4022.9 4621.0 4822.8 4881.8 5108.6 4581.7 4631.7 4908.6 5442.1 5447.8 4552.1 5326.1 5350.4 5371.1 5391.8 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - PROCESS | 1939 | [#5] yui.html Cycles:180 Average:17277.27 Median:58907.89 stddev:23791.12 (40.4%) stddev-sans-first:23825.15 16:26:50 INFO - PROCESS | 1939 | Values: 662.7 739.9 750.6 751.6 759.6 14395.5 15092.6 15120.5 15377.9 15386.1 14364.9 14397.7 14414.8 14425.7 14462.5 912.7 924.8 931.3 932.2 935.3 732.4 755.4 918.0 920.5 920.9 948.8 977.4 979.4 980.2 982.4 962.7 970.8 971.6 972.7 973.8 559.8 704.9 721.7 722.8 726.9 14771.4 14825.9 14826.3 14834.7 14837.9 12108.1 12160.9 12213.1 12351.6 12644.6 10691.0 10729.6 11153.6 11216.5 11435.3 3910.5 4005.6 4126.7 4147.6 4266.5 62273.0 74681.0 75453.9 76358.6 78155.2 9291.2 9574.9 9669.4 9699.5 10149.9 18638.4 21226.4 21369.6 21377.9 21392.9 7395.4 7429.8 7506.3 7538.4 7557.4 3994.4 4022.7 4034.9 4055.5 4105.5 3403.9 3448.1 3498.1 3504.6 3505.4 633.0 640.6 644.5 655.8 668.3 511.0 521.5 549.1 555.6 562.7 7835.1 7857.8 7867.6 7908.4 7996.2 502.7 509.8 522.5 526.5 574.9 3571.0 3729.8 3760.7 3768.8 3797.7 78841.0 81535.0 81758.2 85697.1 87304.0 77355.6 78554.0 78582.6 78616.6 78626.2 69473.0 71054.0 71330.9 71928.0 72985.0 6735.8 7978.9 8057.2 8344.6 8432.4 37130.8 37171.4 39265.0 39973.6 41759.0 53543.5 58889.7 58926.1 60287.0 60563.7 10813.2 10984.5 11184.4 11552.4 12035.2 9627.7 11260.7 12105.8 12371.5 12493.6 8864.9 11108.8 11235.7 11403.0 12259.6 9489.0 11108.1 11207.7 11268.7 11275.8 10967.9 11047.7 11050.2 11056.6 11062.0 9261.7 9419.2 9482.2 9594.5 9849.9 9323.8 9452.7 9485.6 9507.5 9613.5 16:26:50 INFO - PROCESS | 1939 | -------- Summary: end -------- 16:26:50 INFO - PROCESS | 1939 | 16:26:50 INFO - TEST-INFO | 1939: exit 0 16:26:50 INFO - TEST-OK | dromaeo_css | took 1116686ms 16:26:50 INFO - TEST-START | kraken 16:26:50 INFO - operating with platform_type : mac_ 16:26:50 INFO - Initialising browser for kraken test... 16:26:50 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpqGbyUU/profile http://localhost:54199/getInfo.html) 16:26:53 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 16:26:53 INFO - PROCESS | 1943 | colorDepth:24 16:26:53 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 16:26:53 INFO - PROCESS | 1943 | __metrics 16:26:53 INFO - PROCESS | 1943 | 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" 16:26:53 ERROR - PROCESS | 1943 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:26:53 INFO - TEST-INFO | 1943: exit 0 16:26:53 INFO - Browser initialized. 16:26:53 INFO - Running cycle 1/1 for kraken test... 16:26:53 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpqGbyUU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:27:05 INFO - PROCESS | 1945 | RSS: Main: 239198208 16:27:05 INFO - PROCESS | 1945 | 16:27:08 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54199/tests/kraken/driver.html?testName=audio-beat-detection) 16:27:09 INFO - PROCESS | 1945 | RSS: Main: 286793728 16:27:09 INFO - PROCESS | 1945 | 16:27:12 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54199/tests/kraken/driver.html?testName=audio-dft) 16:27:12 INFO - PROCESS | 1945 | RSS: Main: 295501824 16:27:12 INFO - PROCESS | 1945 | 16:27:16 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54199/tests/kraken/driver.html?testName=audio-fft) 16:27:17 INFO - PROCESS | 1945 | RSS: Main: 383619072 16:27:17 INFO - PROCESS | 1945 | 16:27:19 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54199/tests/kraken/driver.html?testName=audio-oscillator) 16:27:19 INFO - PROCESS | 1945 | RSS: Main: 281829376 16:27:19 INFO - PROCESS | 1945 | 16:27:21 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54199/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:27:22 INFO - PROCESS | 1945 | RSS: Main: 285798400 16:27:22 INFO - PROCESS | 1945 | 16:27:25 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54199/tests/kraken/driver.html?testName=imaging-darkroom) 16:27:25 INFO - PROCESS | 1945 | RSS: Main: 320307200 16:27:25 INFO - PROCESS | 1945 | 16:27:28 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54199/tests/kraken/driver.html?testName=imaging-desaturate) 16:27:29 INFO - PROCESS | 1945 | RSS: Main: 324419584 16:27:29 INFO - PROCESS | 1945 | 16:27:32 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54199/tests/kraken/driver.html?testName=json-parse-financial) 16:27:32 INFO - PROCESS | 1945 | RSS: Main: 324882432 16:27:32 INFO - PROCESS | 1945 | 16:27:34 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54199/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:27:35 INFO - PROCESS | 1945 | RSS: Main: 312778752 16:27:35 INFO - PROCESS | 1945 | 16:27:37 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:27:37 INFO - PROCESS | 1945 | RSS: Main: 319602688 16:27:37 INFO - PROCESS | 1945 | 16:27:39 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:27:40 INFO - PROCESS | 1945 | RSS: Main: 321458176 16:27:40 INFO - PROCESS | 1945 | 16:27:43 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:27:44 INFO - PROCESS | 1945 | RSS: Main: 327417856 16:27:44 INFO - PROCESS | 1945 | 16:27:46 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:27:47 INFO - PROCESS | 1945 | RSS: Main: 325459968 16:27:47 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54199/tests/kraken/driver.html?testName=ai-astar) 16:27:49 INFO - PROCESS | 1945 | RSS: Main: 371740672 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | __start_tp_report 16:27:49 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 16:27:49 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 16:27:49 INFO - PROCESS | 1945 | |i|pagename|runs| 16:27:49 INFO - PROCESS | 1945 | |0;ai-astar;113;110;112;112;112;114;112;112;113;111 16:27:49 INFO - PROCESS | 1945 | |1;audio-beat-detection;175;142;142;165;120;142;139;117;171;117 16:27:49 INFO - PROCESS | 1945 | |2;audio-dft;190;185;183;198;272;188;181;226;188;181 16:27:49 INFO - PROCESS | 1945 | |3;audio-fft;78;74;58;107;58;66;59;77;139;75 16:27:49 INFO - PROCESS | 1945 | |4;audio-oscillator;96;100;100;81;79;78;104;81;79;101 16:27:49 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;89;85;87;86;87;83;88;85;87;85 16:27:49 INFO - PROCESS | 1945 | |6;imaging-darkroom;93;93;94;94;94;93;94;93;93;94 16:27:49 INFO - PROCESS | 1945 | |7;imaging-desaturate;84;84;84;86;84;84;83;97;142;134 16:27:49 INFO - PROCESS | 1945 | |8;json-parse-financial;57;59;58;58;59;57;59;76;58;58 16:27:49 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;64;74;64;74;70;68;68;67;68;68 16:27:49 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;85;85;87;84;82;82;86;85;85;86 16:27:49 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;149;152;154;152;135;137;135;137;182;180 16:27:49 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;169;168;166;167;167;169;168;167;166;166 16:27:49 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;66;67;67;68;66;65;68;66;66;65 16:27:49 INFO - PROCESS | 1945 | __end_tp_report 16:27:49 INFO - PROCESS | 1945 | __start_cc_report 16:27:49 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,1988 16:27:49 INFO - PROCESS | 1945 | __end_cc_report 16:27:49 INFO - PROCESS | 1945 | __startTimestamp1462577269500__endTimestamp 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | ------- Summary: start ------- 16:27:49 INFO - PROCESS | 1945 | Number of tests: 14 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:1.10 (1.0%) stddev-sans-first:1.12 16:27:49 INFO - PROCESS | 1945 | Values: 113.0 110.0 112.0 112.0 112.0 114.0 112.0 112.0 113.0 111.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:142.00 stddev:21.58 (15.2%) stddev-sans-first:19.54 16:27:49 INFO - PROCESS | 1945 | Values: 175.0 142.0 142.0 165.0 120.0 142.0 139.0 117.0 171.0 117.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:199.20 Median:189.00 stddev:28.83 (15.3%) stddev-sans-first:30.39 16:27:49 INFO - PROCESS | 1945 | Values: 190.0 185.0 183.0 198.0 272.0 188.0 181.0 226.0 188.0 181.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:79.10 Median:70.00 stddev:25.56 (36.5%) stddev-sans-first:27.11 16:27:49 INFO - PROCESS | 1945 | Values: 78.0 74.0 58.0 107.0 58.0 66.0 59.0 77.0 139.0 75.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:79.00 stddev:11.06 (14.0%) stddev-sans-first:11.51 16:27:49 INFO - PROCESS | 1945 | Values: 96.0 100.0 100.0 81.0 79.0 78.0 104.0 81.0 79.0 101.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.75 (2.0%) stddev-sans-first:1.54 16:27:49 INFO - PROCESS | 1945 | Values: 89.0 85.0 87.0 86.0 87.0 83.0 88.0 85.0 87.0 85.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 16:27:49 INFO - PROCESS | 1945 | Values: 93.0 93.0 94.0 94.0 94.0 93.0 94.0 93.0 93.0 94.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:84.00 stddev:22.48 (26.8%) stddev-sans-first:23.41 16:27:49 INFO - PROCESS | 1945 | Values: 84.0 84.0 84.0 86.0 84.0 84.0 83.0 97.0 142.0 134.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:58.50 stddev:5.70 (9.8%) stddev-sans-first:5.95 16:27:49 INFO - PROCESS | 1945 | Values: 57.0 59.0 58.0 58.0 59.0 57.0 59.0 76.0 58.0 58.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:68.00 stddev:3.44 (5.1%) stddev-sans-first:3.24 16:27:49 INFO - PROCESS | 1945 | Values: 64.0 74.0 64.0 74.0 70.0 68.0 68.0 67.0 68.0 68.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.00 stddev:1.64 (1.9%) stddev-sans-first:1.73 16:27:49 INFO - PROCESS | 1945 | Values: 85.0 85.0 87.0 84.0 82.0 82.0 86.0 85.0 85.0 86.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:152.00 stddev:17.38 (11.4%) stddev-sans-first:18.42 16:27:49 INFO - PROCESS | 1945 | Values: 149.0 152.0 154.0 152.0 135.0 137.0 135.0 137.0 182.0 180.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:1.16 (0.7%) stddev-sans-first:1.05 16:27:49 INFO - PROCESS | 1945 | Values: 169.0 168.0 166.0 167.0 167.0 169.0 168.0 167.0 166.0 166.0 16:27:49 INFO - PROCESS | 1945 | 16:27:49 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.07 (1.6%) stddev-sans-first:1.13 16:27:49 INFO - PROCESS | 1945 | Values: 66.0 67.0 67.0 68.0 66.0 65.0 68.0 66.0 66.0 65.0 16:27:49 INFO - PROCESS | 1945 | -------- Summary: end -------- 16:27:49 INFO - PROCESS | 1945 | 16:27:50 INFO - TEST-INFO | 1945: exit 0 16:27:50 INFO - TEST-OK | kraken | took 59566ms 16:27:50 INFO - SUITE-END | took 1176s 16:27:50 INFO - Completed test suite (00:19:36) 16:27:50 INFO - javascript benchmark 16:27:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.153461634591, 3408.982955085224, 3441.3977553928066, 3445.087051931237, 3492.3015396920623, 5603.299587551556, 5675.091985282355, 5738.368779434264, 5743.167240750094, 5760.855978600537, 4738.720545452726, 5658.321001479818, 5742.052561327376, 5756.050251708473, 5758.165066065418, 5160.458151894044, 5203.713795741232, 5207.6875387476875, 5209.791608335662, 5269.65747226431, 5272.894542109156, 5277.736113194344, 5278.815241466549, 5289.100852855005, 5293.708846013467, 5182.352205974254, 5188.792448302082, 5216.9305292414965, 5221.06020916235, 5292.576593872481, 5281.392639846409, 5292.126799078186, 5307.071262529042, 5307.946920530784, 5353.170258609922, 5078.4413714491375, 5086.3896332440345, 5160.92258616121, 5199.532042116228, 5241.449647786965, 5522.944770552283, 5581.413951535071, 5648.774049038075, 5677.829665110054, 5683.204351390808, 6173.969130154365, 6175.135481032659, 6191.473724733403, 6243.65659888706, 6259.812205633838, 6300.5589608727405, 6318.778842740528, 6328.019157030668, 6328.71520781566, 6332.176817013758, 5696.658200507983, 5739.512141467939, 5774.105013722796, 5812.041013232769, 5828.446297601721, 6286.559940804192, 6310.116583678288, 6325.335839736854, 6325.620462772248, 6341.968290158612, 6120.721420533169, 6194.163787888668, 6198.380161983766, 6200.720967556471, 6200.81397558074, 5143.202803565453, 5903.675297858573, 6184.412480814315, 6251.531135164881, 6264.906026409608, 6166.075088736726, 6232.8130156095385, 6236.064590311399, 6241.375862413813, 6247.375262473716, 6243.719032643542, 6272.780452684226, 6278.623282603058, 6283.497320214372, 6294.276158241769, 4867.853964381075, 4903.313536104948, 4905.7301848397965, 4917.041176970528, 4928.236123400879, 4411.625011873963, 4436.911261774746, 4442.378067070613, 4446.866594002185, 4465.799039043251, 5486.698231597221, 5651.26533550644, 5670.829875103754, 5709.543236541067, 5721.942780572224, 6121.910283036266, 6189.442950134509, 6203.534734894901, 6212.502999760009, 6257.217847769029, 5662.971685141548, 5684.516816070681, 5689.829305120853, 5699.5155411790465, 5735.283089613798, 4572.862814115582, 5407.864803379947, 5408.8377348679605, 5419.0, 5461.017016936989, 5307.65500242483, 6188.4739797116845, 6230.7507699693315, 6310.11658367838, 6328.6835658216905, 6164.3835616438, 6322.968385158045, 6333.809985700436, 6346.587471814318, 6351.237851457855, 6250.343713909974, 6296.874062518632, 6336.493080553452, 6339.366063393623, 6343.841403964938, 5983.910241346296, 6120.44915957566, 6144.508439324753, 6164.89031974249, 6222.719977601109, 6251.281102673251, 6279.748810047547, 6280.842978925563, 6290.402411770874, 6318.9052164216655, 5926.970365148147, 6117.755289788359, 6133.754649813957, 6136.417040381157, 6148.877022459616, 5935.317438494456, 5955.285365756137, 6020.397960203944, 6078.6048906820915, 6093.48205402528, 5021.794769255425, 5927.110933360031, 5956.13636022783, 5963.463288304073, 5978.073398623221, 6076.756929722762, 6078.179445774764, 6102.023676211785, 6103.542234332354, 6104.359042300494, 5912.201852749824, 5944.227250457413, 5959.374265702038, 5980.820575382745, 6006.369331220334], "value": 5684.375530978828, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.5003121956096, 521.8623400985852, 526.1213772999091, 535.3762866260806, 541.0747621567119, 29463.852680736592, 30417.935372261974, 41921.371179432295, 42868.57131428685, 43388.34917476233, 40797.79601101998, 42992.0, 43146.35280470795, 43289.13421731564, 43416.56583434164, 9574.80850382992, 9774.511274436285, 9787.6084956602, 9787.657431989881, 9815.214782817358, 8550.957245213796, 8781.209691127797, 11589.942050289737, 11819.995300399434, 11842.348670823101, 11613.941930290337, 11723.706907327343, 11731.354775487387, 11731.706707332341, 11741.41292935354, 8530.74407767768, 10916.962888525577, 11632.709182270426, 11725.355105469196, 11731.824022639667, 10247.897521024768, 11508.539658413654, 11510.42447877611, 11517.193796434252, 11536.307821530734, 39546.80226598877, 39939.80180594558, 40035.399469007985, 40059.399109013386, 40070.39894401586, 3193.1059303395086, 3210.325831575372, 3487.2502411981545, 3650.5254316938895, 4044.8179831907637, 28415.57376639352, 28505.287367815763, 28754.856225718948, 28889.711102888912, 29070.854645726846, 2379.214859096494, 2598.843514635988, 2710.0514819813106, 2735.343517555792, 2735.603337516069, 445030.0, 452261.0, 452681.0, 452682.0, 453912.730436349, 58946.52633684107, 59326.40673593295, 59597.0, 59678.0, 59779.10331345033, 62818.37181628217, 62866.0570091449, 62939.0, 62943.6852815733, 63104.68447657732, 16773.161341932853, 16988.660226795393, 17010.064446455563, 17013.48959531216, 17016.319347225974, 10833.566657333777, 11994.940025299993, 12238.938805305916, 13198.670033249246, 13294.468221271234, 14470.829375593534, 14780.408783648534, 15722.449714259947, 15943.890941266574, 16117.032978021356, 7099.574025558483, 7210.2789721027475, 7255.927440725525, 7314.926850731531, 7435.776926692208, 795.5982228974447, 895.9462432254086, 931.5063016601213, 1132.6262333429947, 1153.1236260442124, 28014.0, 29046.564301535494, 29705.554416683768, 30078.69921300803, 30164.24589385283, 1139.663799179244, 1157.9710507237219, 1196.9820452693216, 1204.9518019279305, 1216.920900141488, 15079.321430535652, 15178.772318415233, 15223.847761522464, 15547.766783498257, 15717.685646287238, 610692.0, 616183.0, 620777.0, 620924.0, 623900.0, 59765.0, 59781.103283450786, 59806.700966494886, 59908.10137847936, 59930.40069599248, 1332.3271747767315, 1475.4614565683448, 1514.099111028936, 1561.3832536148384, 1561.7501199807975, 29529.409411812074, 30841.8457907709, 31019.84490077535, 31205.375892482476, 31374.215644609067, 51467.0, 51997.48002520078, 52428.0, 52462.950740984204, 52541.0, 483817.0, 488740.0, 490413.0, 491697.0, 492739.0, 35428.29143417169, 37165.25669486649, 37715.0, 38389.424158637084, 39738.60261397465, 46092.53907461017, 46175.07649847052, 46454.53545464502, 46737.831554211414, 46811.76594117007, 33253.0, 38046.429303561024, 38048.80975595215, 38472.653457128865, 39144.80427597958, 37536.49854005918, 37830.43254351242, 38077.42883856689, 38367.808160959015, 38487.807560963134, 36344.636553634125, 37018.0, 37048.0, 37801.81099094597, 37898.43152352772, 31700.841495792374, 32117.35765284728, 33784.0, 35339.646603533634, 35588.822055889555, 24459.510809783347, 24769.62845557354, 24888.626670599595, 25500.23499295024, 25597.61603575985], "value": 20172.515988264942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.4359565820876, 400.3754143536084, 400.97794621295856, 402.58131543195094, 423.9978800105999, 20028.0, 20694.379168624928, 21450.57098858022, 21478.677819832716, 21939.122435102618, 12126.0, 12278.201916875398, 12587.370631468435, 12634.0, 12652.114351995364, 4597.506234413965, 5381.219723140132, 5511.3937466878815, 5568.972155139219, 5611.494965453108, 5964.493018093467, 6133.01871700528, 6135.662538560399, 6139.754409823624, 6144.5698801083945, 5999.430054144849, 6086.391360863922, 6101.0, 6101.0848372744, 6124.2650881894015, 5863.149843272701, 5998.790042348541, 6054.819310234506, 6094.9390506094815, 6105.511559075263, 5197.896042079137, 5335.973320133413, 5360.383555891083, 5551.944480555183, 5559.360673522516, 13652.97602679793, 13867.029307948447, 13880.722385552232, 18093.638127237515, 26649.733502665113, 2072.9792702072937, 2134.285014520138, 2252.0, 2334.995951740751, 2469.4567195217023, 9731.318807683465, 9754.853677194847, 10364.63723769672, 12048.518059277618, 14768.483103091448, 1704.6590681863554, 1733.453962001978, 1741.8432341089363, 1755.5523341547823, 1770.2476447509885, 130541.6945830539, 131794.3410282952, 131840.02239966407, 132859.33570332182, 133645.0, 9678.370905891095, 10305.536250868729, 10337.0, 10571.092949438966, 10677.09244714208, 10733.282674772, 12819.29493877827, 12925.0, 12932.159409638532, 12933.741325173633, 8299.875501867476, 8480.194381533744, 8484.830303394021, 8590.78523036929, 8594.140585941355, 7308.378787803096, 8844.690435834715, 8881.689140880038, 8882.467051976902, 8997.055309192572, 3399.8300084996145, 4016.4376987221813, 4082.6937979651643, 4089.2434899543678, 4133.0700592366475, 1620.8460196281335, 1766.70849309865, 1983.633027889845, 1983.9008049597462, 2003.4190084875515, 870.7170169694748, 909.3997961345481, 919.5540163020929, 971.32007594684, 1004.3371374892682, 20587.867667278442, 20985.370438886857, 21178.78821211799, 21223.102491689955, 21325.040373183245, 935.3499317974079, 935.8362286599818, 938.7559234598917, 949.8385274503213, 1056.9207309451822, 10686.251962362716, 10968.012878840942, 11035.724106897393, 11085.83371249432, 11106.666799996014, 612389.5044198271, 622818.0, 636502.8174859096, 640123.0, 641432.0, 133918.0, 142758.2862085683, 148272.0, 149020.0, 151550.0, 996.835522138839, 1027.1679939249232, 1050.9894901051046, 1067.546292825546, 1069.9144068474347, 18639.627207456047, 18811.90594047021, 19935.700964485255, 19957.800421995595, 19982.90008549948, 52869.0, 53879.730601346746, 55903.0, 56063.43936560745, 56086.71956640354, 72150.0, 75541.0, 77179.2282077172, 78883.60558197173, 80002.3999520018, 8206.261436470746, 8282.751517454486, 8359.707410240613, 8559.0, 8817.779555511164, 11010.559577616805, 12785.0, 13171.0, 13334.733305334034, 13353.866461335263, 5495.203195536705, 6970.686319115551, 11223.831642525533, 11321.848848718717, 11360.715982100513, 10165.54255058539, 11139.0, 11312.547498099984, 11466.713332166762, 11491.8276225855, 11410.0, 11515.366654834064, 11565.190436669689, 11642.6507204784, 11807.468663909973, 8323.583820809057, 8403.95798021006, 8430.030546486987, 8489.83020339602, 8573.614187361709, 10507.106895913934, 11190.88809111931, 11447.02700270453, 11490.0, 11575.536978520764], "value": 9369.806253424158, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.3100134432113, 1395.2535393564444, 1592.0447731361185, 1724.9827501724988, 1935.8257756801886, 7744.419168562352, 8510.361722870786, 8712.956435217831, 8737.519436431001, 8839.646414143426, 8409.747707568768, 8651.48091114532, 8656.394052416334, 8656.437331573443, 8658.220760131588, 2153.224839057938, 2385.2486466762916, 2435.610302351624, 2465.2111324376206, 2465.543874383236, 2487.1543675150447, 2515.0, 2515.408878913454, 2516.19481765835, 2525.848449093051, 2406.3262286559793, 2459.88930498128, 2469.6789417375808, 2476.913308034219, 2477.1206221791276, 2120.109553987329, 2352.811775058008, 2412.384841865322, 2433.8174636902304, 2442.914497992562, 2437.5612389769835, 2457.508498300347, 2472.6291056341693, 2475.7276699563213, 2481.789047930928, 8668.696595619185, 8746.518941458216, 8756.518391488466, 8760.605772740242, 8771.386002979794, 5245.606579506514, 5413.675179489204, 5463.535599474009, 5465.508104270595, 5470.753816078286, 3379.9493007604906, 3437.656234376567, 3460.8961731148106, 3644.2347107107275, 3649.8905032849057, 1034.3328553083295, 1046.3146638951498, 1049.9527521261562, 1052.7420781908481, 1052.8473371361185, 11643.825342619783, 11756.882431175663, 11756.94121529387, 11767.588134415255, 11770.293782373084, 4568.994821139344, 4592.402987611589, 4722.905541889143, 4725.952740472551, 4736.5973892219545, 9221.861672074925, 9231.861522077174, 9232.8615070774, 9241.353105282566, 9242.72271831846, 5303.28405665238, 5323.973380133075, 5327.7336133193185, 5333.813316533902, 5338.119210330329, 4386.912261754747, 4530.728156310632, 4798.760061996886, 4939.407271127488, 4970.701757894538, 3333.8999830005137, 3770.9064371332556, 3823.4073718573663, 3885.1646895917515, 3919.2945269851703, 976.858355538443, 1145.5990403358924, 1147.7419963363684, 1151.3897634253858, 1190.6606617114255, 773.5010917957998, 826.8594338962391, 874.2001069021901, 902.6344330546008, 983.680303901235, 5176.922346164811, 5455.1271796512365, 5735.598508104476, 6090.817275481742, 6120.354687655692, 462.9467611224685, 466.7316293131463, 467.231404339859, 473.6305681568382, 481.4295060353492, 3947.309220886391, 3968.503937007874, 4045.4134150548007, 4054.3715724062818, 4106.055607210358, 10268.435236062089, 10695.73260668474, 10708.411037393018, 10716.196285278635, 10827.78344433107, 10502.527386267637, 10653.520591573242, 10679.359238445562, 10808.405537695502, 10811.135109191246, 7996.200379961957, 8175.100738918714, 8229.094799572042, 8264.710735124361, 8304.8339033219, 5887.087501437284, 6019.036954087325, 6038.818835434944, 6083.361247068994, 6107.297660768891, 9495.05049495045, 9748.658796942073, 9990.850137247802, 10104.242181836245, 10176.949115254376, 10028.69913902584, 10094.192464602667, 10239.43683097407, 10345.844812327972, 10359.430231337348, 6120.387961203844, 6649.168853893263, 6886.0, 6891.793246202622, 7004.754833580801, 8322.16778322163, 8350.290225330671, 8427.241548260687, 8428.45215061019, 8440.957795210985, 7472.0286362772495, 7517.736879209201, 7612.619369031416, 7631.580263085361, 7700.653470593725, 7288.927110728825, 7364.374028207662, 7380.704771809067, 7498.0, 7502.812429689301, 7030.191527974144, 7068.893966590608, 7113.2175460698245, 7120.1099862517185, 7127.750528731677, 3979.840806367713, 4786.0, 4793.185158523129, 5010.3737032870895, 5128.1794912813775, 4979.507790999342, 5005.574526165171, 5079.6952182869145, 5156.1750119980625, 5304.07178743726], "value": 4477.135831378009, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.4201700802491, 315.25599584979443, 322.27166603476144, 323.1742896898425, 327.27671845222056, 4566.954330456694, 5143.074246635609, 5251.107311757001, 5260.447652996438, 5271.341082364705, 4238.173556156551, 4430.62339701125, 5182.5382943102, 5204.973975130129, 5211.973940130294, 3715.9628403716024, 3779.603141670126, 3814.9237015259678, 3858.1897801461587, 3990.102226998931, 3432.759706820511, 3492.738044646649, 3791.9572117667726, 3875.593062728415, 4026.7919624112797, 3309.602847658284, 3931.76409415436, 3935.232629637222, 3944.2900277949957, 3958.2875082485284, 3135.9494569319304, 3497.318022985519, 3722.516072910518, 3982.761034337949, 3984.402339649047, 3494.2662040971427, 3497.8425970831117, 3512.1921957949817, 3518.683318501347, 3521.7358698097487, 4969.552740253394, 5004.423606563921, 5025.497450254945, 5029.371328583927, 5051.343325367716, 3712.832922518493, 4496.123255965088, 4561.110583436198, 4590.3344015117955, 4602.815887364502, 5149.356330458692, 5371.865703357408, 5422.810201642924, 5461.590380721462, 5466.562674985991, 3274.345130973815, 3698.223373091654, 3743.5507739071486, 3871.1676989447406, 3929.8231579578987, 4702.412198475191, 5155.793768249266, 5282.471752824726, 5326.680399176062, 5672.432756724335, 3847.403652433821, 3855.9974406654474, 3916.4321173429694, 3925.666318362914, 3940.802959851996, 3432.9656703432647, 3973.543042550086, 3975.7018223633345, 4031.234065527599, 4045.231406032903, 3708.313961917, 4029.1135950090934, 4287.655628609689, 4572.359869618256, 4617.953820461752, 4798.2082956312515, 5299.364076310791, 5383.81156659515, 5472.069748142825, 5511.106668998807, 1168.9713052513735, 1271.4850485553366, 1279.3411393132358, 1284.4797856867983, 1314.9802752958522, 729.4273994913967, 776.2858170483169, 796.7689370082727, 833.4874052457695, 833.6623667414707, 4873.390826146731, 4923.975380123076, 5065.290859279704, 5099.36257967754, 5172.379314482287, 5562.137868630369, 5596.768710883599, 5619.0, 5624.01926978702, 5703.0, 4408.58118478744, 4710.458297295786, 5069.188929771292, 5124.48755124492, 5246.3704355477585, 2463.6674049003514, 2608.1612463213387, 2617.5811870100692, 2816.0, 2822.4778415993105, 5191.480851914778, 5337.359516858002, 5533.7786488540005, 5587.720613969285, 5619.971900140473, 4481.417415735934, 4853.830115945925, 5314.502183973526, 5389.245505629215, 5534.976029434568, 5544.63959842617, 5603.971980140154, 5631.352394474688, 5648.689322087324, 5667.093265077568, 4738.786754596121, 5035.924461133012, 5380.811671591632, 5428.37573679032, 5458.863528411663, 4605.098787763687, 4607.93088103672, 4814.0874770763185, 4862.696513585432, 5008.899822003466, 4785.377900872864, 4862.635302352267, 4869.70781753096, 5292.603054770984, 5335.199720042025, 3161.5930910744496, 3260.527223552619, 3273.7544684148306, 3417.6753208445157, 3511.2099777549847, 4969.35398398206, 5151.6908985461, 5281.498257665516, 5311.654742441729, 5389.119833719716, 4830.420349557935, 4960.958198778153, 5169.508896654812, 5238.607104467256, 5240.161574148118, 4845.885446347289, 5079.3650793650795, 5125.2568377585785, 5175.275461435327, 5200.373811225307, 4022.8534867562603, 4620.976895115503, 4822.831200907952, 4881.780319885543, 5108.591312694901, 4581.702189357815, 4631.745254011062, 4908.63185261114, 5442.102053161184, 5447.828716825743, 4552.11233809404, 5326.0945639240645, 5350.384705758732, 5371.140617501181, 5391.838244852661], "value": 3889.980394855164, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.6587307536619, 739.914556300228, 750.5909279442703, 751.56784848712, 759.5670467833337, 14395.496157634485, 15092.622684432894, 15120.470783522578, 15377.923110384432, 15386.076835389857, 14364.92817535911, 14397.712045759079, 14414.783778243334, 14425.711485770278, 14462.493812716557, 912.7353067610385, 924.7919218175924, 931.2782593490032, 932.1610550504532, 935.2798345274135, 732.3957734868728, 755.403231447156, 918.0314767919842, 920.5305294299889, 920.903305152956, 948.7628092976756, 977.4086677560081, 979.4466126638462, 980.2060331131806, 982.3614650477211, 962.7352478068552, 970.8495183246608, 971.5579411367881, 972.747085757701, 973.8441849304148, 559.7509108446743, 704.8907419350008, 721.6896734404204, 722.8156820010863, 726.8764310067302, 14771.409143634242, 14825.925870370687, 14826.33281502335, 14834.703305933928, 14837.851621483755, 12108.091893108052, 12160.939195304056, 12213.145079844415, 12351.59568674432, 12644.55744048963, 10690.99224497909, 10729.624463143831, 11153.609623663133, 11216.495257713423, 11435.313881167156, 3910.4720862683457, 4005.61946615074, 4126.66986641068, 4147.6474499667565, 4266.466691663542, 62273.0, 74681.0, 75453.86819197716, 76358.61820690916, 78155.21844781536, 9291.163795258459, 9574.856377154349, 9669.371490853073, 9699.466529340812, 10149.949250253701, 18638.440846774618, 21226.36320910375, 21369.572608547744, 21377.893110534347, 21392.893035534722, 7395.445341599401, 7429.8142546436775, 7506.3244308012545, 7538.3592394645975, 7557.4331925105835, 3994.380870965005, 4022.7184097113504, 4034.8991275218355, 4055.513338399411, 4105.486814148231, 3403.9319213616086, 3448.1207292140343, 3498.0555250082843, 3504.6144924058335, 3505.4040813061843, 633.0409429713987, 640.6316368088368, 644.458654730029, 655.8163714160042, 668.3116390118186, 510.9616778741609, 521.5292874095092, 549.1214057508035, 555.5860883641632, 562.7242651100931, 7835.138134805167, 7857.803554911173, 7867.6459559319965, 7908.406869484812, 7996.160403157701, 502.7436007636132, 509.78079425847244, 522.5140619224157, 526.5392781316348, 574.9367569567344, 3570.9821450892578, 3729.7544669505223, 3760.7367484276383, 3768.8304026318883, 3797.6582107610448, 78841.0, 81535.0, 81758.18241817506, 85697.14302857016, 87304.0, 77355.61322193353, 78554.0, 78582.60708696535, 78616.60691696504, 78626.21373786303, 69472.95790563045, 71054.0, 71330.9300360495, 71928.0, 72985.0, 6735.764248251287, 7978.880316795369, 8057.227409969797, 8344.58277086131, 8432.36757243197, 37130.8143459281, 37171.442428364135, 39265.0, 39973.60026399699, 41758.95602609959, 53543.46456535385, 58889.70555147197, 58926.06911082057, 60287.0, 60563.69718151381, 10813.24307298513, 10984.450777460936, 11184.440777960906, 11552.364619945984, 12035.217710848767, 9627.700260465028, 11260.662180134608, 12105.818412723993, 12371.505139794668, 12493.625191244277, 8864.911350886667, 11108.833367499656, 11235.71910702239, 11403.03074238699, 12259.570915017932, 9488.95255523718, 11108.055815255793, 11207.66377008691, 11268.718282043015, 11275.830862537232, 10967.890321096687, 11047.668569942914, 11050.171237157407, 11056.557737690724, 11062.004419602274, 9261.72214833556, 9419.199368053793, 9482.194013508653, 9594.472304023067, 9849.852252216078, 9323.766905827408, 9452.716418507456, 9485.620575176916, 9507.477088760184, 9613.51932403363], "value": 6757.113282342296, "unit": "score"}], "name": "dromaeo_css", "value": 7084.707936608316, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 112.0, 112.0, 112.0, 114.0, 112.0, 112.0, 113.0, 111.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 142.0, 142.0, 165.0, 120.0, 142.0, 139.0, 117.0, 171.0, 117.0], "value": 143.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 185.0, 183.0, 198.0, 272.0, 188.0, 181.0, 226.0, 188.0, 181.0], "value": 199.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 107.0, 58.0, 66.0, 59.0, 77.0, 139.0, 75.0], "value": 79.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 100.0, 100.0, 81.0, 79.0, 78.0, 104.0, 81.0, 79.0, 101.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 86.0, 87.0, 83.0, 88.0, 85.0, 87.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 86.0, 84.0, 84.0, 83.0, 97.0, 142.0, 134.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 58.0, 58.0, 59.0, 57.0, 59.0, 76.0, 58.0, 58.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 64.0, 74.0, 70.0, 68.0, 68.0, 67.0, 68.0, 68.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 84.0, 82.0, 82.0, 86.0, 85.0, 85.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 154.0, 152.0, 135.0, 137.0, 135.0, 137.0, 182.0, 180.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 166.0, 167.0, 167.0, 169.0, 168.0, 167.0, 166.0, 166.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 68.0, 66.0, 65.0, 68.0, 66.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1497.3, "alertThreshold": 2.0}]} 16:27:50 INFO - Return code: 0 16:27:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:27:50 ERROR - # TBPL SUCCESS # 16:27:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:27:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:27:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:27:50 INFO - 2016-05-06 16:27:50.813 screenresolution[1949:19294] starting screenresolution argv=screenresolution get 16:27:50 INFO - 2016-05-06 16:27:50.816 screenresolution[1949:19294] Display 0: 1600x1200x32@60 16:27:50 INFO - 2016-05-06 16:27:50.822 screenresolution[1950:19297] starting screenresolution argv=screenresolution list 16:27:50 INFO - Available Modes on Display 0 16:27:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:27:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:27:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:27:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:27:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:27:51 INFO - Intel Iris: 16:27:51 INFO - Chipset Model: Intel Iris 16:27:51 INFO - Type: GPU 16:27:51 INFO - Bus: Built-In 16:27:51 INFO - VRAM (Dynamic, Max): 1536 MB 16:27:51 INFO - Vendor: Intel (0x8086) 16:27:51 INFO - Device ID: 0x0a2e 16:27:51 INFO - Revision ID: 0x0009 16:27:51 INFO - Displays: 16:27:51 INFO - Display: 16:27:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:27:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:27:51 INFO - Display Serial Number: 1600x1200 60 16:27:51 INFO - Main Display: Yes 16:27:51 INFO - Mirror: Off 16:27:51 INFO - Online: Yes 16:27:51 INFO - Rotation: Supported 16:27:51 INFO - Return code: 0 16:27:51 INFO - Running post-action listener: _resource_record_post_action 16:27:51 INFO - ##### 16:27:51 INFO - ##### Finished run-tests step (success) 16:27:51 INFO - ##### 16:27:51 INFO - Running post-run listener: _resource_record_post_run 16:27:52 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1662976; Write bytes: 697813504; Read time: 8; Write time: 2121 16:27:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375714304; Write bytes: 370427392; Read time: 28164; Write time: 759 16:27:52 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1662976; Write bytes: 298210304; Read time: 8; Write time: 1304 16:27:52 INFO - Running post-run listener: _upload_blobber_files 16:27:52 INFO - Blob upload gear active. 16:27:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:27:52 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 16:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:27:52 INFO - (blobuploader) - INFO - Open directory for files ... 16:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:27:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:27:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:27:53 INFO - (blobuploader) - INFO - Done attempting. 16:27:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:27:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:27:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:27:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:27:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:27:54 INFO - (blobuploader) - INFO - Done attempting. 16:27:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:27:54 INFO - Return code: 0 16:27:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:27:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:27:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/390f63698255a28a22a50672b94d16f95fdd1d67069870a7ae1f590782e2819cb21c31f4a17c18363913fd06ae5203c75a92f59e433dbc8a62544ebc827cfa6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b778acef84f7cb4fe34fe87680694f5489d5e7cac709fbc3a4434bc39a706232bed816928cccc282e075181b3cef55bbce1ec4eddead7e58202cbbea5a98937"} 16:27:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:27:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:27:54 INFO - Contents: 16:27:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/390f63698255a28a22a50672b94d16f95fdd1d67069870a7ae1f590782e2819cb21c31f4a17c18363913fd06ae5203c75a92f59e433dbc8a62544ebc827cfa6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b778acef84f7cb4fe34fe87680694f5489d5e7cac709fbc3a4434bc39a706232bed816928cccc282e075181b3cef55bbce1ec4eddead7e58202cbbea5a98937"} 16:27:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:27:54 INFO - Copying logs to upload dir... 16:27:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:27:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.178317 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-06 16:27:54.531339) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 16:27:54.535910) ========= 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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/390f63698255a28a22a50672b94d16f95fdd1d67069870a7ae1f590782e2819cb21c31f4a17c18363913fd06ae5203c75a92f59e433dbc8a62544ebc827cfa6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b778acef84f7cb4fe34fe87680694f5489d5e7cac709fbc3a4434bc39a706232bed816928cccc282e075181b3cef55bbce1ec4eddead7e58202cbbea5a98937"} build_url:https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/PUDr29G1RTCWwBZERoZoDA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/390f63698255a28a22a50672b94d16f95fdd1d67069870a7ae1f590782e2819cb21c31f4a17c18363913fd06ae5203c75a92f59e433dbc8a62544ebc827cfa6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b778acef84f7cb4fe34fe87680694f5489d5e7cac709fbc3a4434bc39a706232bed816928cccc282e075181b3cef55bbce1ec4eddead7e58202cbbea5a98937"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 16:27:54.563525) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:27:54.563866) ========= 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.aG8XUQYgGW/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.Wa5LQVdY1W/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.005226 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:27:54.612624) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 16:27:54.612953) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 16:27:54.613275) ========= ========= Total master_lag: 0.23 =========