builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0083 starttime: 1464793917.32 results: success (0) buildid: 20160601060643 builduid: 1224d87f6f2440c3806e4cf859374f43 revision: 08084e9e30d23d2154b0e994a6e2733633c381fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.315708) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.316192) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.316481) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.339531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.339955) ========= 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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-01 08:11:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 70.6M=0s 2016-06-01 08:11:57 (70.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098554 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.477303) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.477617) ========= 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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.027024 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:57.526629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 08:11:57.526986) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 08084e9e30d23d2154b0e994a6e2733633c381fb --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 08084e9e30d23d2154b0e994a6e2733633c381fb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R6mvJDDhsd/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.FM01UQJ6zU/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-01 08:11:57,602 truncating revision to first 12 chars 2016-06-01 08:11:57,602 Setting DEBUG logging. 2016-06-01 08:11:57,602 attempt 1/10 2016-06-01 08:11:57,602 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/08084e9e30d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 08:11:58,296 unpacking tar archive at: fx-team-08084e9e30d2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944473 ========= master_lag: 0.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 08:11:58.768040) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:58.768408) ========= script_repo_revision: 08084e9e30d23d2154b0e994a6e2733633c381fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:58.768910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:58.769187) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 08:11:58.786506) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-01 08:11:58.786885) ========= /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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:11:58 INFO - MultiFileLogger online at 20160601 08:11:58 in /builds/slave/test 08:11:58 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 08:11:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:58 INFO - {'append_to_log': False, 08:11:58 INFO - 'base_work_dir': '/builds/slave/test', 08:11:58 INFO - 'blob_upload_branch': 'Fx-Team', 08:11:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:58 INFO - 'branch': 'Fx-Team', 08:11:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:58 INFO - 'config_files': ('talos/mac_config.py',), 08:11:58 INFO - 'default_actions': ('clobber', 08:11:58 INFO - 'read-buildbot-config', 08:11:58 INFO - 'download-and-extract', 08:11:58 INFO - 'populate-webroot', 08:11:58 INFO - 'create-virtualenv', 08:11:58 INFO - 'install', 08:11:58 INFO - 'run-tests'), 08:11:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:58 INFO - 'download_minidump_stackwalk': True, 08:11:58 INFO - 'download_symbols': 'ondemand', 08:11:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:58 INFO - 'installer_path': 'installer.exe', 08:11:58 INFO - 'log_level': 'info', 08:11:58 INFO - 'log_name': 'talos', 08:11:58 INFO - 'log_to_console': True, 08:11:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:11:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:11:58 INFO - 'opt_config_files': (), 08:11:58 INFO - 'pip_index': False, 08:11:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:11:58 INFO - 'cmd': ('bash', 08:11:58 INFO - '-c', 08:11:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:11:58 INFO - 'enabled': True, 08:11:58 INFO - 'halt_on_failure': False, 08:11:58 INFO - 'name': 'check_screen_resolution'},), 08:11:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:11:58 INFO - 'cmd': ('bash', 08:11:58 INFO - '-c', 08:11:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:11:58 INFO - 'enabled': True, 08:11:58 INFO - 'halt_on_failure': False, 08:11:58 INFO - 'name': 'check_screen_resolution'},), 08:11:58 INFO - 'run_cmd_checks_enabled': True, 08:11:58 INFO - 'sps_profile': False, 08:11:58 INFO - 'sps_profile_interval': 0, 08:11:58 INFO - 'suite': 'dromaeojs', 08:11:58 INFO - 'system_bits': '32', 08:11:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:58 INFO - 'title': 't-yosemite-r7-0083', 08:11:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:58 INFO - 'use_talos_json': True, 08:11:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:58 INFO - 'work_dir': 'build'} 08:11:58 INFO - ##### 08:11:58 INFO - ##### Running clobber step. 08:11:58 INFO - ##### 08:11:58 INFO - Running pre-action listener: _resource_record_pre_action 08:11:58 INFO - Running main action method: clobber 08:11:58 INFO - rmtree: /builds/slave/test/build 08:11:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:12:00 INFO - Running post-action listener: _resource_record_post_action 08:12:00 INFO - ##### 08:12:00 INFO - ##### Finished clobber step (success) 08:12:00 INFO - ##### 08:12:00 INFO - ##### 08:12:00 INFO - ##### Running read-buildbot-config step. 08:12:00 INFO - ##### 08:12:00 INFO - Running pre-action listener: _resource_record_pre_action 08:12:00 INFO - Running main action method: read_buildbot_config 08:12:00 INFO - Using buildbot properties: 08:12:00 INFO - { 08:12:00 INFO - "project": "", 08:12:00 INFO - "product": "firefox", 08:12:00 INFO - "script_repo_revision": "production", 08:12:00 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:12:00 INFO - "repository": "", 08:12:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:12:00 INFO - "buildid": "20160601060643", 08:12:00 INFO - "pgo_build": "False", 08:12:00 INFO - "basedir": "/builds/slave/test", 08:12:00 INFO - "buildnumber": 738, 08:12:00 INFO - "slavename": "t-yosemite-r7-0083", 08:12:00 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:12:00 INFO - "platform": "yosemite_r7", 08:12:00 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:12:00 INFO - "branch": "fx-team", 08:12:00 INFO - "repo_path": "integration/fx-team", 08:12:00 INFO - "revision": "08084e9e30d23d2154b0e994a6e2733633c381fb", 08:12:00 INFO - "stage_platform": "macosx64", 08:12:00 INFO - "builduid": "1224d87f6f2440c3806e4cf859374f43", 08:12:00 INFO - "slavebuilddir": "test" 08:12:00 INFO - } 08:12:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:12:00 INFO - Running post-action listener: _resource_record_post_action 08:12:00 INFO - ##### 08:12:00 INFO - ##### Finished read-buildbot-config step (success) 08:12:00 INFO - ##### 08:12:00 INFO - ##### 08:12:00 INFO - ##### Running download-and-extract step. 08:12:00 INFO - ##### 08:12:00 INFO - Running pre-action listener: _resource_record_pre_action 08:12:00 INFO - Running main action method: download_and_extract 08:12:00 INFO - mkdir: /builds/slave/test/build/tests 08:12: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')]} 08:12:00 INFO - https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:12:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:12:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:12:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:02 INFO - Downloaded 1199 bytes. 08:12:02 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:12:02 INFO - Using the following test package requirements: 08:12:02 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:12:02 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:12:02 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:12:02 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'jsshell-mac.zip'], 08:12:02 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:12:02 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:12:02 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:12:02 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:12:02 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:12:02 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:12:02 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:12:02 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:02 INFO - https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:12:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:12:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:12:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:03 INFO - Downloaded 18687202 bytes. 08:12:03 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'] 08:12:03 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 08:12:06 INFO - Return code: 0 08:12:06 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 08:12:07 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')]} 08:12:07 INFO - https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:12:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:12:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:12:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:08 INFO - Downloaded 18687202 bytes. 08:12:08 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'] 08:12:08 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 08:12:11 INFO - Return code: 0 08:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:11 INFO - https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:12:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:12:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:12:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:13 INFO - Downloaded 10834055 bytes. 08:12:13 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'] 08:12:13 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 08:12:13 INFO - Return code: 0 08:12: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')]} 08:12:13 INFO - https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:12:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:12:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:12:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:12:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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 08:12:18 INFO - Downloaded 110992387 bytes. 08:12:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:12:18 INFO - mkdir: /builds/slave/test/properties 08:12:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:12:18 INFO - Writing to file /builds/slave/test/properties/build_url 08:12:18 INFO - Contents: 08:12:18 INFO - build_url:https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:12:19 INFO - Running post-action listener: _resource_record_post_action 08:12:19 INFO - Running post-action listener: set_extra_try_arguments 08:12:19 INFO - ##### 08:12:19 INFO - ##### Finished download-and-extract step (success) 08:12:19 INFO - ##### 08:12:19 INFO - ##### 08:12:19 INFO - ##### Running populate-webroot step. 08:12:19 INFO - ##### 08:12:19 INFO - Running pre-action listener: _resource_record_pre_action 08:12:19 INFO - Running main action method: populate_webroot 08:12:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:12:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:12:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:12:19 INFO - '--tppagecycles', 08:12:19 INFO - '7'], 08:12:19 INFO - 'tests': ['tsvgm']}}, 08:12:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:12:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:12:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:12:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:12:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['damp', 'tps']}, 08:12:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'tests': ['damp', 'tps']}, 08:12:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['dromaeo_dom']}, 08:12:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:12:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['a11yr', 08:12:19 INFO - 'ts_paint', 08:12:19 INFO - 'tpaint', 08:12:19 INFO - 'sessionrestore', 08:12:19 INFO - 'sessionrestore_no_auto_restore', 08:12:19 INFO - 'tabpaint']}, 08:12:19 INFO - 'other-e10s': {'tests': ['a11yr', 08:12:19 INFO - 'ts_paint', 08:12:19 INFO - 'tpaint', 08:12:19 INFO - 'sessionrestore', 08:12:19 INFO - 'sessionrestore_no_auto_restore', 08:12:19 INFO - 'tabpaint']}, 08:12:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:12:19 INFO - 'ts_paint', 08:12:19 INFO - 'tpaint', 08:12:19 INFO - 'sessionrestore', 08:12:19 INFO - 'sessionrestore_no_auto_restore', 08:12:19 INFO - 'tabpaint']}, 08:12:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:12:19 INFO - 'ts_paint', 08:12:19 INFO - 'tpaint', 08:12:19 INFO - 'sessionrestore', 08:12:19 INFO - 'sessionrestore_no_auto_restore', 08:12:19 INFO - 'tabpaint']}, 08:12:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['a11yr', 08:12:19 INFO - 'ts_paint', 08:12:19 INFO - 'tpaint', 08:12:19 INFO - 'sessionrestore', 08:12:19 INFO - 'sessionrestore_no_auto_restore', 08:12:19 INFO - 'tabpaint']}, 08:12:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['a11yr', 08:12:19 INFO - 'ts_paint', 08:12:19 INFO - 'tpaint', 08:12:19 INFO - 'sessionrestore', 08:12:19 INFO - 'sessionrestore_no_auto_restore', 08:12:19 INFO - 'tabpaint']}, 08:12:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['tsvgx', 08:12:19 INFO - 'tsvgr_opacity', 08:12:19 INFO - 'tart', 08:12:19 INFO - 'tscrollx', 08:12:19 INFO - 'cart']}, 08:12:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:12:19 INFO - 'tsvgr_opacity', 08:12:19 INFO - 'tart', 08:12:19 INFO - 'tscrollx', 08:12:19 INFO - 'cart']}, 08:12:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'talos_options': ['--disable-e10s'], 08:12:19 INFO - 'tests': ['tp5o']}, 08:12:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'tests': ['tp5o']}, 08:12:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'talos_options': ['--disable-e10s', 08:12:19 INFO - '--xperf_path', 08:12:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:19 INFO - 'tests': ['tp5n']}, 08:12:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:19 INFO - 'talos_options': ['--xperf_path', 08:12:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:19 INFO - 'tests': ['tp5n']}}, 08:12:19 INFO - 'talos.zip': {'path': '', 08:12:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:12:19 INFO - Running post-action listener: _resource_record_post_action 08:12:19 INFO - ##### 08:12:19 INFO - ##### Finished populate-webroot step (success) 08:12:19 INFO - ##### 08:12:19 INFO - ##### 08:12:19 INFO - ##### Running create-virtualenv step. 08:12:19 INFO - ##### 08:12:19 INFO - Running pre-action listener: _resource_record_pre_action 08:12:19 INFO - Running main action method: create_virtualenv 08:12:19 INFO - Creating virtualenv /builds/slave/test/build/venv 08:12:19 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 08:12:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:12:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:12:19 INFO - Using real prefix '/tools/python27' 08:12:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:12:20 INFO - Installing distribute.............................................................................................................................................................................................done. 08:12:23 INFO - Installing pip.................done. 08:12:23 INFO - Return code: 0 08:12:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:23 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 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:23 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 08:12:23 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 08:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:23 INFO - 'HOME': '/Users/cltbld', 08:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:23 INFO - 'LOGNAME': 'cltbld', 08:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:23 INFO - 'MOZ_NO_REMOTE': '1', 08:12:23 INFO - 'NO_EM_RESTART': '1', 08:12:23 INFO - 'PAGER': '/bin/cat', 08:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:23 INFO - 'PWD': '/builds/slave/test', 08:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:23 INFO - 'SHELL': '/bin/bash', 08:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:23 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:23 INFO - 'USER': 'cltbld', 08:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:23 INFO - 'XPC_FLAGS': '0x0', 08:12:23 INFO - 'XPC_SERVICE_NAME': '0', 08:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:23 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 08:12:23 INFO - Cleaning up... 08:12:23 INFO - Return code: 0 08:12:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:23 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:23 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>=3.1.1'] in /builds/slave/test/build 08:12:23 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>=3.1.1 08:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:23 INFO - 'HOME': '/Users/cltbld', 08:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:23 INFO - 'LOGNAME': 'cltbld', 08:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:23 INFO - 'MOZ_NO_REMOTE': '1', 08:12:23 INFO - 'NO_EM_RESTART': '1', 08:12:23 INFO - 'PAGER': '/bin/cat', 08:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:23 INFO - 'PWD': '/builds/slave/test', 08:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:23 INFO - 'SHELL': '/bin/bash', 08:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:23 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:23 INFO - 'USER': 'cltbld', 08:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:23 INFO - 'XPC_FLAGS': '0x0', 08:12:23 INFO - 'XPC_SERVICE_NAME': '0', 08:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:24 INFO - Downloading/unpacking psutil>=3.1.1 08:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:12:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:12:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:12:26 INFO - Installing collected packages: psutil 08:12:26 INFO - Running setup.py install for psutil 08:12:26 INFO - building 'psutil._psutil_osx' extension 08:12:26 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 08:12:27 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 08:12:27 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 08:12:27 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 08:12:27 INFO - building 'psutil._psutil_posix' extension 08:12:27 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 08:12:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:12:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:12:27 INFO - ^ 08:12:27 INFO - 1 warning generated. 08:12:27 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 08:12:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:12:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:12:27 INFO - Successfully installed psutil 08:12:27 INFO - Cleaning up... 08:12:27 INFO - Return code: 0 08:12:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:12:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:12:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:27 INFO - 'HOME': '/Users/cltbld', 08:12:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:27 INFO - 'LOGNAME': 'cltbld', 08:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:27 INFO - 'MOZ_NO_REMOTE': '1', 08:12:27 INFO - 'NO_EM_RESTART': '1', 08:12:27 INFO - 'PAGER': '/bin/cat', 08:12:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:27 INFO - 'PWD': '/builds/slave/test', 08:12:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:27 INFO - 'SHELL': '/bin/bash', 08:12:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:27 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:27 INFO - 'USER': 'cltbld', 08:12:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:27 INFO - 'XPC_FLAGS': '0x0', 08:12:27 INFO - 'XPC_SERVICE_NAME': '0', 08:12:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:12:30 INFO - Installing collected packages: mozsystemmonitor 08:12:30 INFO - Running setup.py install for mozsystemmonitor 08:12:30 INFO - Successfully installed mozsystemmonitor 08:12:30 INFO - Cleaning up... 08:12:30 INFO - Return code: 0 08:12:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:12: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')]} 08:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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')]} 08:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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', '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 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:12: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 blobuploader==1.2.4 08:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:30 INFO - 'HOME': '/Users/cltbld', 08:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:30 INFO - 'LOGNAME': 'cltbld', 08:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:30 INFO - 'MOZ_NO_REMOTE': '1', 08:12:30 INFO - 'NO_EM_RESTART': '1', 08:12:30 INFO - 'PAGER': '/bin/cat', 08:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:30 INFO - 'PWD': '/builds/slave/test', 08:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:30 INFO - 'SHELL': '/bin/bash', 08:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:30 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:30 INFO - 'USER': 'cltbld', 08:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:30 INFO - 'XPC_FLAGS': '0x0', 08:12:30 INFO - 'XPC_SERVICE_NAME': '0', 08:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:31 INFO - Downloading/unpacking blobuploader==1.2.4 08:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:33 INFO - Downloading blobuploader-1.2.4.tar.gz 08:12:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:12:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:12:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12:34 INFO - Downloading docopt-0.6.1.tar.gz 08:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:12:34 INFO - Installing collected packages: blobuploader, requests, docopt 08:12:34 INFO - Running setup.py install for blobuploader 08:12:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:12:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:12:34 INFO - Running setup.py install for requests 08:12:35 INFO - Running setup.py install for docopt 08:12:35 INFO - Successfully installed blobuploader requests docopt 08:12:35 INFO - Cleaning up... 08:12:35 INFO - Return code: 0 08:12:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:35 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')]} 08:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:35 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')]} 08:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:35 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 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:35 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 08:12:35 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 08:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:35 INFO - 'HOME': '/Users/cltbld', 08:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:35 INFO - 'LOGNAME': 'cltbld', 08:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:35 INFO - 'MOZ_NO_REMOTE': '1', 08:12:35 INFO - 'NO_EM_RESTART': '1', 08:12:35 INFO - 'PAGER': '/bin/cat', 08:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:35 INFO - 'PWD': '/builds/slave/test', 08:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:35 INFO - 'SHELL': '/bin/bash', 08:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:35 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:35 INFO - 'USER': 'cltbld', 08:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:35 INFO - 'XPC_FLAGS': '0x0', 08:12:35 INFO - 'XPC_SERVICE_NAME': '0', 08:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-qldBg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-1tzuIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-KtEcgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-f3UWPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-SL7gs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-YjDLjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-3U1JQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-vaaGBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ohgzMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ubu089-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9EWjyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-xIndsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9NEJsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-omkGNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-MM0zQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-NsFrhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-U_nYNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:12:37 INFO - Running setup.py install for manifestparser 08:12:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:12:37 INFO - Running setup.py install for mozcrash 08:12:37 INFO - Running setup.py install for mozdebug 08:12:38 INFO - Running setup.py install for mozdevice 08:12:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Running setup.py install for mozfile 08:12:38 INFO - Running setup.py install for mozhttpd 08:12:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Running setup.py install for mozinfo 08:12:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Running setup.py install for mozInstall 08:12:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:12:38 INFO - Running setup.py install for mozleak 08:12:38 INFO - Running setup.py install for mozlog 08:12:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:12:39 INFO - Running setup.py install for moznetwork 08:12:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:12:39 INFO - Running setup.py install for mozprocess 08:12:39 INFO - Running setup.py install for mozprofile 08:12:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:12:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:12:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:12:39 INFO - Running setup.py install for mozrunner 08:12:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:12:39 INFO - Running setup.py install for mozscreenshot 08:12:39 INFO - Running setup.py install for moztest 08:12:40 INFO - Running setup.py install for mozversion 08:12:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:12:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:12:40 INFO - Cleaning up... 08:12:40 INFO - Return code: 0 08:12:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:40 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 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:40 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 08:12:40 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 08:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:40 INFO - 'HOME': '/Users/cltbld', 08:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:40 INFO - 'LOGNAME': 'cltbld', 08:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:40 INFO - 'MOZ_NO_REMOTE': '1', 08:12:40 INFO - 'NO_EM_RESTART': '1', 08:12:40 INFO - 'PAGER': '/bin/cat', 08:12:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:40 INFO - 'PWD': '/builds/slave/test', 08:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:40 INFO - 'SHELL': '/bin/bash', 08:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:40 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:40 INFO - 'USER': 'cltbld', 08:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:40 INFO - 'XPC_FLAGS': '0x0', 08:12:40 INFO - 'XPC_SERVICE_NAME': '0', 08:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-jjHYUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:40 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)) 08:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ThFsOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:40 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)) 08:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-FsebUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:40 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)) 08:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Cz4stI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:40 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)) 08:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-rzH3Xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-viuZYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-mm0LKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9euHlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-xV3riP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-v6MPqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-vpxIKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-o3R90d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-bcZ61Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:41 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)) 08:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-WLRP1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:42 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)) 08:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-TLl99e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:42 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)) 08:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-eFtSA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:42 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)) 08:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LCbuh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:42 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)) 08:12:42 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)) 08:12:42 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)) 08:12:42 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)) 08:12:42 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)) 08:12:42 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)) 08:12:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:12:42 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 08:12:42 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 08:12:42 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 08:12:42 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 08:12:42 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 08:12:42 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 08:12:44 INFO - Downloading blessings-1.6.tar.gz 08:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:12:44 INFO - Installing collected packages: blessings 08:12:44 INFO - Running setup.py install for blessings 08:12:45 INFO - Successfully installed blessings 08:12:45 INFO - Cleaning up... 08:12:45 INFO - Return code: 0 08:12:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:12:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:45 INFO - Reading from file tmpfile_stdout 08:12:45 INFO - Current package versions: 08:12:45 INFO - blessings == 1.6 08:12:45 INFO - blobuploader == 1.2.4 08:12:45 INFO - docopt == 0.6.1 08:12:45 INFO - manifestparser == 1.1 08:12:45 INFO - mozInstall == 1.12 08:12:45 INFO - mozcrash == 0.17 08:12:45 INFO - mozdebug == 0.1 08:12:45 INFO - mozdevice == 0.48 08:12:45 INFO - mozfile == 1.2 08:12:45 INFO - mozhttpd == 0.7 08:12:45 INFO - mozinfo == 0.9 08:12:45 INFO - mozleak == 0.1 08:12:45 INFO - mozlog == 3.2 08:12:45 INFO - moznetwork == 0.27 08:12:45 INFO - mozprocess == 0.22 08:12:45 INFO - mozprofile == 0.28 08:12:45 INFO - mozrunner == 6.11 08:12:45 INFO - mozscreenshot == 0.1 08:12:45 INFO - mozsystemmonitor == 0.3 08:12:45 INFO - moztest == 0.7 08:12:45 INFO - mozversion == 1.4 08:12:45 INFO - psutil == 3.1.1 08:12:45 INFO - requests == 1.2.3 08:12:45 INFO - wsgiref == 0.1.2 08:12:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:45 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 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:45 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 08:12:45 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 08:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:45 INFO - 'HOME': '/Users/cltbld', 08:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:45 INFO - 'LOGNAME': 'cltbld', 08:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:45 INFO - 'MOZ_NO_REMOTE': '1', 08:12:45 INFO - 'NO_EM_RESTART': '1', 08:12:45 INFO - 'PAGER': '/bin/cat', 08:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:45 INFO - 'PWD': '/builds/slave/test', 08:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:45 INFO - 'SHELL': '/bin/bash', 08:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:45 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:45 INFO - 'USER': 'cltbld', 08:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:45 INFO - 'XPC_FLAGS': '0x0', 08:12:45 INFO - 'XPC_SERVICE_NAME': '0', 08:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 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)) 08:12:45 INFO - Cleaning up... 08:12:45 INFO - Return code: 0 08:12:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:45 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 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:45 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 08:12:45 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 08:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:45 INFO - 'HOME': '/Users/cltbld', 08:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:45 INFO - 'LOGNAME': 'cltbld', 08:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:45 INFO - 'MOZ_NO_REMOTE': '1', 08:12:45 INFO - 'NO_EM_RESTART': '1', 08:12:45 INFO - 'PAGER': '/bin/cat', 08:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:45 INFO - 'PWD': '/builds/slave/test', 08:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:45 INFO - 'SHELL': '/bin/bash', 08:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:45 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:45 INFO - 'USER': 'cltbld', 08:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:45 INFO - 'XPC_FLAGS': '0x0', 08:12:45 INFO - 'XPC_SERVICE_NAME': '0', 08:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:45 INFO - Downloading/unpacking jsonschema 08:12:45 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 08:12:45 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 08:12:45 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 08:12:45 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 08:12:45 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 08:12:45 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 08:12:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:12:48 INFO - Downloading/unpacking functools32 (from jsonschema) 08:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:48 INFO - Downloading functools32-3.2.3-2.tar.gz 08:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:12:48 INFO - warning: no files found matching '*.txt' 08:12:48 INFO - no previously-included directories found matching 'build' 08:12:48 INFO - no previously-included directories found matching 'dist' 08:12:48 INFO - no previously-included directories found matching '.git*' 08:12:48 INFO - Installing collected packages: jsonschema, functools32 08:12:48 INFO - Running setup.py install for functools32 08:12:48 INFO - warning: no files found matching '*.txt' 08:12:48 INFO - no previously-included directories found matching 'build' 08:12:48 INFO - no previously-included directories found matching 'dist' 08:12:48 INFO - no previously-included directories found matching '.git*' 08:12:48 INFO - Successfully installed jsonschema functools32 08:12:48 INFO - Cleaning up... 08:12:49 INFO - Return code: 0 08:12:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:12: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')]} 08:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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')]} 08:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986acbfcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, '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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 08:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:12:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:12:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:49 INFO - 'HOME': '/Users/cltbld', 08:12:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:49 INFO - 'LOGNAME': 'cltbld', 08:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:49 INFO - 'MOZ_NO_REMOTE': '1', 08:12:49 INFO - 'NO_EM_RESTART': '1', 08:12:49 INFO - 'PAGER': '/bin/cat', 08:12:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:49 INFO - 'PWD': '/builds/slave/test', 08:12:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:49 INFO - 'SHELL': '/bin/bash', 08:12:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:12:49 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:12:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:49 INFO - 'USER': 'cltbld', 08:12:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:49 INFO - 'XPC_FLAGS': '0x0', 08:12:49 INFO - 'XPC_SERVICE_NAME': '0', 08:12:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:49 INFO - Downloading/unpacking flake8 08:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:12:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:12:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:12:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:12:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:12:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:12:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:12:53 INFO - no previously-included directories found matching 'docs/_build' 08:12:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:12:53 INFO - Running setup.py install for pep8 08:12:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:12:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:12:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:12:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:12:53 INFO - no previously-included directories found matching 'docs/_build' 08:12:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:12:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:12:53 INFO - Cleaning up... 08:12:53 INFO - Return code: 0 08:12:53 INFO - Running post-action listener: _resource_record_post_action 08:12:53 INFO - Running post-action listener: _start_resource_monitoring 08:12:53 INFO - Starting resource monitoring. 08:12:53 INFO - ##### 08:12:53 INFO - ##### Finished create-virtualenv step (success) 08:12:53 INFO - ##### 08:12:53 INFO - ##### 08:12:53 INFO - ##### Running install step. 08:12:53 INFO - ##### 08:12:53 INFO - Running pre-action listener: _resource_record_pre_action 08:12:53 INFO - Running main action method: install 08:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:53 INFO - Reading from file tmpfile_stdout 08:12:53 INFO - Detecting whether we're running mozinstall >=1.0... 08:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:12:53 INFO - Reading from file tmpfile_stdout 08:12:53 INFO - Output received: 08:12:53 INFO - Usage: mozinstall [options] installer 08:12:53 INFO - Options: 08:12:53 INFO - -h, --help show this help message and exit 08:12:53 INFO - -d DEST, --destination=DEST 08:12:53 INFO - Directory to install application into. [default: 08:12:53 INFO - "/builds/slave/test"] 08:12:53 INFO - --app=APP Application being installed. [default: firefox] 08:12:53 INFO - mkdir: /builds/slave/test/build/application 08:12:53 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'] 08:12:53 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 08:13:27 INFO - Reading from file tmpfile_stdout 08:13:27 INFO - Output received: 08:13:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:13:27 INFO - Running post-action listener: _resource_record_post_action 08:13:27 INFO - ##### 08:13:27 INFO - ##### Finished install step (success) 08:13:27 INFO - ##### 08:13:27 INFO - ##### 08:13:27 INFO - ##### Running run-tests step. 08:13:27 INFO - ##### 08:13:27 INFO - Running pre-action listener: _resource_record_pre_action 08:13:27 INFO - Running main action method: run_tests 08:13:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:13:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:13:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:13:27 INFO - 2016-06-01 08:13:27.816 screenresolution[1637:7551] starting screenresolution argv=screenresolution get 08:13:27 INFO - 2016-06-01 08:13:27.819 screenresolution[1637:7551] Display 0: 1600x1200x32@60 08:13:27 INFO - 2016-06-01 08:13:27.824 screenresolution[1638:7553] starting screenresolution argv=screenresolution list 08:13:27 INFO - Available Modes on Display 0 08:13:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:13:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:13:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:13:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:13:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:13:28 INFO - Intel Iris: 08:13:28 INFO - Chipset Model: Intel Iris 08:13:28 INFO - Type: GPU 08:13:28 INFO - Bus: Built-In 08:13:28 INFO - VRAM (Dynamic, Max): 1536 MB 08:13:28 INFO - Vendor: Intel (0x8086) 08:13:28 INFO - Device ID: 0x0a2e 08:13:28 INFO - Revision ID: 0x0009 08:13:28 INFO - Displays: 08:13:28 INFO - Display: 08:13:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:13:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:13:28 INFO - Display Serial Number: 1600x1200 60 08:13:28 INFO - Main Display: Yes 08:13:28 INFO - Mirror: Off 08:13:28 INFO - Online: Yes 08:13:28 INFO - Rotation: Supported 08:13:28 INFO - Return code: 0 08:13:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:13:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:13:28 INFO - Python 2.7.3 08:13:28 INFO - Return code: 0 08:13:28 INFO - grabbing minidump binary from tooltool 08:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:28 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a14b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:13:28 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 08:13:28 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 08:13:28 INFO - Calling ['/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'] with output_timeout 600 08:13:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:13:28 INFO - Return code: 0 08:13:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:13:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:13:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:13:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:13:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:13:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:13:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:13:29 INFO - Return code: 0 08:13:29 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/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 08:13:29 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/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0083 --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 08:13:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6mvJDDhsd/Render', 08:13:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:13:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:13:29 INFO - 'HOME': '/Users/cltbld', 08:13:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:13:29 INFO - 'LOGNAME': 'cltbld', 08:13:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:13:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:29 INFO - 'MOZ_NO_REMOTE': '1', 08:13:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:13:29 INFO - 'NO_EM_RESTART': '1', 08:13:29 INFO - 'PAGER': '/bin/cat', 08:13:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:29 INFO - 'PWD': '/builds/slave/test', 08:13:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:13:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:13:29 INFO - 'SHELL': '/bin/bash', 08:13:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FM01UQJ6zU/Listeners', 08:13:29 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 08:13:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:13:29 INFO - 'USER': 'cltbld', 08:13:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:13:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:29 INFO - 'XPC_FLAGS': '0x0', 08:13:29 INFO - 'XPC_SERVICE_NAME': '0', 08:13:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:13:29 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/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 08:13:29 INFO - mozversion application_buildid: 20160601060643 08:13:29 INFO - mozversion application_changeset: 08084e9e30d23d2154b0e994a6e2733633c381fb 08:13:29 INFO - mozversion application_display_name: Nightly 08:13:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:13:29 INFO - mozversion application_name: Firefox 08:13:29 INFO - mozversion application_remotingname: firefox 08:13:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:13:29 INFO - mozversion application_vendor: Mozilla 08:13:29 INFO - mozversion application_version: 49.0a1 08:13:29 INFO - mozversion platform_buildid: 20160601060643 08:13:29 INFO - mozversion platform_changeset: 08084e9e30d23d2154b0e994a6e2733633c381fb 08:13:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:13:29 INFO - mozversion platform_version: 49.0a1 08:13:29 INFO - using testdate: 1464794009 08:13:29 INFO - actual date: 1464794009 08:13:29 INFO - starting webserver on 'localhost:49269' 08:13:29 INFO - SUITE-START | Running 2 tests 08:13:29 INFO - TEST-START | dromaeo_css 08:13:29 INFO - operating with platform_type : mac_ 08:13:29 INFO - Initialising browser for dromaeo_css test... 08:13:29 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpvys0Zg/profile http://localhost:49269/getInfo.html) 08:13:31 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 08:13:31 INFO - PROCESS | 1656 | colorDepth:24 08:13:31 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 08:13:31 INFO - PROCESS | 1656 | __metrics 08:13:31 INFO - PROCESS | 1656 | 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" 08:13:31 ERROR - PROCESS | 1656 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:13:32 INFO - TEST-INFO | 1656: exit 0 08:13:32 INFO - Browser initialized. 08:13:32 INFO - Running cycle 1/1 for dromaeo_css test... 08:13:32 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpvys0Zg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:13:44 INFO - PROCESS | 1658 | RSS: Main: 241491968 08:13:44 INFO - PROCESS | 1658 | 08:14:35 INFO - PROCESS | 1658 | 1464794075664 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] 08:16:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 08:16:48 INFO - PROCESS | 1658 | RSS: Main: 376455168 08:16:48 INFO - PROCESS | 1658 | 08:19:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 08:19:52 INFO - PROCESS | 1658 | RSS: Main: 376717312 08:19:52 INFO - PROCESS | 1658 | 08:22:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 08:22:55 INFO - PROCESS | 1658 | RSS: Main: 375291904 08:22:55 INFO - PROCESS | 1658 | 08:25:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 08:25:58 INFO - PROCESS | 1658 | RSS: Main: 366686208 08:25:58 INFO - PROCESS | 1658 | 08:29:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 08:29:02 INFO - PROCESS | 1658 | RSS: Main: 335794176 08:29:02 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 08:32:05 INFO - PROCESS | 1658 | RSS: Main: 377470976 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | __start_tp_report 08:32:05 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 08:32:05 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 08:32:05 INFO - PROCESS | 1658 | |i|pagename|runs| 08:32:05 INFO - PROCESS | 1658 | |0;dojo.html;3410.7271418286537;3433.244686169042;3439.7764145330566;3453.1539772755677;3505.6669616386434;5556.499915007648;5573;5638.633488823224;5667.829965101043;5681.403452637475;5541.418151094137;5636.661800291985;5672.347680016799;5674.914876276849;5719.628224165426;4160.7087503874745;4176.958230417703;4674.766261686919;5191.195364718475;5231.65994210377;5192.454792246816;5211.687298762086;5230.476952304777;5240.135377662681;5248.8687782805355;5061.063703214881;5097.337346144977;5123.129068058421;5123.769430375642;5190.221466779975;5153.665011774223;5154.07226699194;5169.2504586835075;5174.689518628894;5180.171172612401;5024.321716568253;5049.596032317406;5116.232565115225;5132.717700526469;5132.974335128338;5514.310711161105;5598.944010559883;5618.943810561924;5656.236408084897;5674.148877668342;6209.099680546341;6215.87568248637;6218.5025197984505;6228.781992630282;6238.970569855968;5377.865553361158;6035.849103772397;6145.170401995762;6269.5611307208055;6298.244210694746;5321.361436627552;5434.592405569598;5776.855578610526;5806.44838740319;5814.651120932673;6161.938380616226;6313.40022697843;6320.957042088004;6334.366563343721;6339.461145802658;6041.848953776191;6099.725994698907;6101.481374083164;6106.603070800473;6146.279041868284;6237.407446292595;6240.5319601030105;6241.594296370721;6247.406496382836;6250.968745156245;5954.761809527657;6015.428534289235;6188.690565471798;6191.349908259568;6205.596636218631;6106.023036314168;6110.103144028831;6140.2982687617305;6164.845878853064;6189.566730328924;4856.781444834991;4860.465348811628;4867.9269810952865;4877.5366340197625;4881.609471242292;4366.519682834885;4376.737395756265;4419.248727716296;4423.071155057474;4433.933490997638;5480.986017586759;5531.059719847635;5549.306336707911;5559.305086864142;5603.047481928082;6148.723307451175;6154.846128846725;6162.784302549389;6163.291221509584;6170.969145154246;5454.400015998237;5520.1443776214755;5539.833804985857;5552.861178470571;5555.805546805923;5257.8159764408065;5310.654807437504;5332.3867755207875;5333.57331413486;5333.653312534673;6225.221847269091;6299.748010079728;6322.051692246107;6333.429991300805;6388.936110638834;6208.937910620836;6214.40963108504;6216.471599914058;6219;6226.59527130735;6246.1255424239735;6260.186175797299;6307.180066591313;6343.397377249154;6364.109024736448;6028.216331876828;6104.822969073627;6113.266408031065;6129.939558357133;6139.952147134979;6152.907706384318;6170.845728856814;6196.0705894116245;6199.752009919553;6219;5942.494887934574;5961.672108033927;6020.217371741645;6060.090986352083;6084.6349219047;4959.404871415453;6024.126501657148;6054.485368743706;6074.149619053248;6104.023356262976;5032.823851165338;5952.958232309242;5975.43233392827;5978.850528736816;5999.220101386792;6036.486898613667;6045.1234570987845;6058.333583305921;6097.542684298606;6098.725557349841;5783.855403614775;5813.360530341574;5844.47399734026;5872.677002764718;5916.378780228186 08:32:05 INFO - PROCESS | 1658 | |1;ext.html;480.7524125075586;508.2427183496591;520.7058012223093;526.9341332333457;552.6601140298717;32048.198795030137;42865.78567107164;44180.558194418045;44278.778606106964;46376.76811615937;45279.32081018779;45918.08163836721;45961.54038459623;46083;46230.30654540176;8630.741077767678;9473.763155921088;9732.513374331254;9732.805343893118;9732.854007189897;8675.69635062773;11493.540258389654;11727.7068073298;11779.9987001105;11977.461014254379;11745.765084698302;11844.052475801915;11864.7627047459;11874.643760687193;11881.643550693492;8605.698800541952;11667.883321166806;11703.18077734559;11794.997425218824;11967.401629918557;11963.940180299043;12052.276863388224;12075.154739168262;12092.516299348015;12103.697407564794;41777.582224177975;42330.78834605838;42413.78793106015;42447.78776106131;42541.57458425407;2980.2847316643847;3432.639572844865;3458.706009989154;3466.5493485846932;3532.1875968527124;31996.360072798645;32385.676143238736;33092.00723978285;33180.336393272;33252.83373583117;3356.2448449098756;3381.8140002299865;3392.0332705179153;3411.1131105912395;3426.194844211608;490617;491688;492005;492247.0775292237;492367;65278.02082968759;65484.34515654878;65487.34512654813;65561.67219163873;65599.34400655932;68737.31262687409;68867;68869.65565172142;68935;68979.65510172518;15344.769828452581;17063.06153161588;17304.740428893576;17323.913380433016;17344.392946246822;10607;11925.344106074075;12242.693932651577;13119.081664283409;13159.67100822487;18651.253949841856;18705;18727.157277922255;18802.717959230624;18810.24759009652;4076.316880962256;6459.644719540376;6947.054824649106;7008.159020917421;7231.096112985877;903.6656437118309;1003.7641154328727;1009.2279406254221;1073.592035026685;1107.7286064914147;29181.56227656587;31042.068737937898;31217.531737023965;31323.522124771185;31524.842375787975;1067.2209287220373;1134.5291703942771;1136.909047276216;1214.6477521518661;1259.8362212912264;13307.534236301683;14129.646758830906;14785.482508112165;15272.389104435699;15992;690259;698474;698943;699551;700585;61704.07443888345;63260;63263.05105423422;63445.682771585845;63505;1287.5171810571037;1323.616151316127;1324.1260767893145;1413.9787903181461;1435.1532595768547;28034.71965280321;32205;32252.032439026865;33500.83249583736;34214.97355079352;56569.151462728914;60182.69908650429;60635;60680.69659651673;60828;620919;626837;627604;627831;628473;43884;43942.90142746457;44921.775391122836;44991.55008449873;45131.09737805291;49654.751726241135;50425.243621346446;50629;51299.74350128225;52068;36906.8154659225;41251.1749765009;41471;41863.58136418597;42980.35529467123;40229;40273;40626.85915995759;40731.59268407278;41674.374884377365;39331;39407;40034.79982600185;40036;40083.799581001906;32015.83992080118;35062.82468587641;36006.27987440289;36262.818685907456;36368.9994100654;25979.350516237246;26185;26430.942762290062;26474.205773826816;26802.62055079183 08:32:05 INFO - PROCESS | 1658 | |2;jquery.html;381.454520036348;385.78974458919885;386.66167103784187;399.56846605665885;404.3247776213723;19700.408987730374;21288.57422851542;21547.92260386982;21577;22308.330750077523;20410.89794551029;20531.38405847827;21204.833734144617;21232.681509777365;21431.678524822102;5664.518515926141;5666.716664166775;6054.939450605481;6057.515398768088;6118.816435506942;3835.8273877675433;4066.654334381581;5754.223179870727;6017.669028203447;6085.300190478108;6121.693915304239;6129.754809807602;6134.6012509186985;6141.201643786301;6142.907856382158;6368.458681012119;6376.171097757262;6603.900941485882;6679.66601669917;6685.732570697191;3720.8883733488037;5404.540614047772;5740.368559458456;5740.569457290678;5785.450382213683;13511.864881351257;13763.793543096861;13780.793288100687;21619.35141945744;22769.772302277095;1980.3068925875973;2048.3342913553006;2122.8407869409702;2167.4689701023194;2214.125420458926;9991.550380232908;10017.84973225395;10057.346272492265;10317.277790554665;10513.684589462327;1584.865286450653;1648.4230519318262;1663.6007358233937;1698.541393823673;1743.7471566622896;112085;118540.81459185471;118798;120631.79368206294;120989;11390.487428065757;11551.76896462066;11590.826137607943;11598.71003224926;11732;14587.927060364842;14640.341184646717;14657.413703451955;14705;14707.852921470649;8165.1426600207315;8349.4155409122;8352.457090289112;8452.661893524313;8460.238578527962;7135.357817796424;7608.467407281437;8048.356131509465;8097.514149151069;8211.01467823853;4443.555644435531;4530.411046563926;4531.13908357411;4592.908141837144;4595.379623750818;1582.272154808778;1777.235788610884;1824.1351680202604;1831.766210882067;2006.5084054406764;867.6789587852535;921.9078092190728;1014.7818219082933;1022.9437380943971;1033.2405681823852;20170.29403970854;21157.25949591757;21321.36035918925;21334.253301134388;21556.529255715497;896.2471523919938;936.241644268145;957.1194501059042;958.9760255993656;959.9376040557341;9958.448200087863;10477.020799601445;11259.211855170059;11307.095432365391;11361.886381136248;539901;560651;565683;573913;578645;128825.35587322191;130974;132725;133895.99156012668;135553;953.5899563187896;961.4904100826573;976.5166242709762;976.5751897924425;992.3599278465493;15613.453529126426;18514.259429623202;18804.435866924014;18923.90538047301;18959.526011849815;36712.043874077724;50814;52561.7371913138;52848.47151528436;53331.73334133304;74912.62543687464;76137;76614.61692691724;76899.61550192213;77355.47748323121;8160.591970401338;8209.096999330188;8480.83038339242;8482.872756908528;8508.787280318042;11778.705532361759;12602.49590016389;12949.158304710163;13103.951683865078;13116.73766524645;10855.565777369133;11096;11275.985161335519;11302.152338574475;11473.770524589294;10978.725531861768;11034.613788517363;11141.442927853737;11160.665180044612;11249.887501125211;11219.439028048728;11249.606263780728;11488.655340339803;11518.36648984314;11598.362090085126;8144.104148543774;8182.631781569963;8205.056418511947;8215.876761848458;8243.752687419386;10193.286469947032;11207.103431725605;11304.321740695585;11329.433528323716;11334.773304533699 08:32:05 INFO - PROCESS | 1658 | |3;mootools.html;1389.2984043058254;1546.5128484527372;1579.1551519936831;1800.981990180098;1966.6656668366377;7360.705571777123;7899.328557072648;8602;8840.51377174256;9009.504477253764;8578.742637720863;8838.77903052424;8843;8848.867266990985;8850.247728943048;2105.589410065038;2313.063209400187;2365.704286964125;2399.184277345702;2407.614781634939;1994.3119623729863;2420.080369459603;2441.4506735984437;2446.3272600035043;2459.56957532432;2380.8928598213124;2391.6053851114543;2422.842515236504;2423.866687332196;2427.3688840901405;2076.6573515369946;2157;2161.6109100361928;2168.132746901252;2316.8609883406966;2153.7831125414245;2182.159868450642;2344.6483027546005;2420;2424.527217192648;7401.8149546261775;8944.552772361421;8959.328050396247;8962.775930601721;8969.596368163448;5200.947990520084;5375.22059301403;5381.327334083239;5390.029794636964;5405.243265942771;3463.3246516929207;3587;3624.0577449862963;3635.4910312556262;3652.9269414611827;1074.983875241872;1095.3811096730321;1106.2366966792888;1113.154002957758;1125.3135587291827;11919.999999999913;11923;11925.284482930965;11957.222780519238;11959.461824217931;4758.1673207188605;4790.592799612003;4812.302216178635;4825.469198388175;4853.369062021915;8370.916290837014;9982.35114717541;10111.898881011242;10115.039071288216;10123.1901447884;5387.568994480433;5390.0836857734275;5391.16436952273;5391.353037635509;5396.082665946798;4387.692861499664;4438;4559.430071241095;4573.63410927125;4890.290907818347;3298.8515516801804;3372.578427696538;4034.7982600870428;4082.5713300103657;4119.114390406077;1311.8425788905392;1312.9540466083831;1319.1491487990384;1324.4768316515115;1330.4345653097396;839.7312859884901;898.9820203596022;903.9593218305192;916.2120576304372;936.3960245641523;5639;5894.970525147347;5938.435848594377;6149.0776383542825;6189.783357582463;922.6217250927087;928.767808047988;970.9563069661882;985.3152059318704;989.7674046599044;3915.882523524299;4032.6370626643156;4043.8989025274013;4045.4336392905016;4082.897927551835;10518.211134164969;10544.789104218025;10944.343339399502;10965.122790176767;11042.171837112248;10908.836367454494;10929.781404371868;11014.229003969645;11020.393878336608;11056.336619802838;8111.878321825177;8320.8335833283;8385.371097167737;8401.075881653012;8412.15878412166;5818.76724930998;5961.433663801932;5985.880282394241;6034.456898879122;6044.33512313654;10253.48732563354;10386.532606032897;10407.895921040996;10418.791624167323;10424.374537527772;9885.604575816888;10279.331843430153;10295.07344339013;10438.63464778729;10442.582296708046;7038.155421349471;7262.74580389705;7429.07136607924;7699.961500192463;7701.6534255957495;8568.657253709782;8579.613917373857;8613;8690.522021288889;8761.868571971298;7576.507527010726;7588.506747061423;7646.923530764621;7729.729459468892;7750.147483776893;6170.6914654268;6968.6864091115;7491.625418728932;7510.4464482076355;7619.54282743015;7076.610786406797;7182.102237220348;7191.532550384209;7192.748253811092;7202.423806095605;4928.383952005999;5045.646804723781;5089.847304580869;5129.692218466904;5142.6914385137015;4698.859034228979;5061.670991385548;5130.256112863539;5185.688858668492;5239 08:32:05 INFO - PROCESS | 1658 | |4;prototype.html;308.23711314496626;314.8520195508112;317.1785076651472;321.3572854291417;323.6472245252674;5352.625316227865;5399.838004859851;5411.350637923455;5430.72846357682;5485.890282194354;4117.46472958515;4435.911281774363;5103.081445339837;5175.70607348163;5407.351117865862;3241.205904553388;3520.683138517533;4007.4790277263915;4034.273830710471;4072.450219220397;3295.555100061497;3951.802409879509;4049.514058312992;4072;4077.8776636700945;4003.0792917628964;4032.4757781488365;4048.3725022621543;4065.6137666921595;4072.6538244249273;3260.4131256373844;3826.368649172883;3998.200359928026;4077.3272410052305;4089.284375234322;2845.9651349285155;3533.6289689582736;3536.133647256413;3545.7517973741847;3548.5919119301093;4252.4897012358715;5180.497655679848;5203.869903252411;5207.656554550467;5251.36983561975;3812.8093595320124;4164.68764842638;4618.9076218475775;4641.7911193996015;4663.064828096336;4775.71345719258;5534.972325138348;5546.140348246028;5618.7190640468225;5654.236678048452;3221.695213438561;3275.8689652413873;3821.8280177392085;3848.7113466490127;3867.323218436762;4880.755962201912;5783.392743761929;5839.503642190376;5969;5970.373110823344;3212.8273180289025;3792.6017768133947;3803.4627796149293;3810.48558444612;3815.0843797488956;3437.951424815405;3562.982185089058;3625.782453052825;3719.1445967427644;3882.495275614152;4311.417958575615;4551.613112885376;4552.157850797546;4556.476005259437;4622.907541849144;4939.604831613462;5192.81825136126;5322.365429112614;5334.866628334323;5642.71786410686;1195.9162858599989;1296.7925131978839;1306.8956731561816;1342.3959218351781;1348.426918559628;772.6445834916002;805.3798575097143;814.8736945773414;816.0819078536646;833.7498750374984;4278.636315913182;4343.891402714894;4966.683828785374;5059.696418214845;5097.439281679012;4649.790759415767;5791.971040144772;5798.5071268941765;5816.505597024216;5819.418058194231;5007.29751884361;5216.921746173811;5251.789928402821;5261.815836445706;5298.417174110845;3018.381231847466;3022.0082118702767;3023.1988523041414;3035.5598438226775;3050.5881705969855;5619.26949496571;5994.6403215807195;6020.69896505173;6061.909071363933;6074.301455332693;5306.495882891119;5343.62594618373;5586.748596313095;5983.9401605983385;5986.970065149733;5583.581231407742;5866.589338746332;6051.546134039877;6055.757769689163;6057.636541807506;5129.846104616868;5314.374583425875;5595.461248156626;5650;5808.448197421239;4994.775235114356;5283.498067683564;5552.805651802168;5661.150827375926;5698.686572238401;5158.793648254028;5379.488948549882;5711.714414279352;5755.942440575708;6034.366391528826;3220.3559288141996;3606.441001644749;3821.4649949006607;3829.4064420014943;3906.9218615628097;5758.165066065313;5801.82594522165;5826.184334193132;5861.6189947653265;5888.440598143169;5295.4439783822145;5659.943400565941;5675.829725108253;5694.1458781183155;5754.9424505754405;4300.956990430056;4988.775505102207;5445.863853403696;5644.52021578154;5734.569907256889;5109.335786347751;5520.91718624229;5528.153001204302;5549.72251387421;5553.527950124285;4484.887877803081;4488.640908727229;4664.277037059262;5620.634658747168;5692.231548740867;4901.460839307744;5306.514176030718;5559;5571.498565129158;5604.271444712162 08:32:05 INFO - PROCESS | 1658 | |5;yui.html;718.7089228862311;776.2547953964194;828.5567221536479;855.2260204514912;859.3554833874598;14684.779728304084;14897;14904.850951490482;14906.552803415887;14920.403183872646;13828.377723002464;13900.721985560283;13902.582922512316;13930.651733706662;13935.372908219155;704.6758491094077;730.1785491322263;871.7646235516406;877.9473231606108;898.3172788680617;871.442276942754;884.4295429448002;886.5301390263171;886.6542048601051;888.4402826219473;945.877035985321;949.952502374882;950.4677380666814;951.1202138022336;953.4470007395694;741.0514541387006;767.808047988003;768.6387397922967;771.4060173666246;945.6217512994789;573.4466240078344;672.6939242644585;696.5681277607865;696.9756058537978;697.5919087333865;14447;14513.984021118526;14517.927410362987;14526.927365363212;14552.563423097217;13268.469261229635;13469.797953030713;13585.59243222705;13640.86359136406;13905.721885562334;11979.161458697894;12141.696457588543;12159.756804863942;12257.448414821265;12750.171238869443;4455.866324010285;4506.076254367847;4532.79602417892;4629.675922685414;4689.155951928651;75746;76080.61959690166;82575;82706.58646706789;84054.57972710159;10422.739431514121;10533.210009249184;10614.469276536141;10913.618023369145;10913.618023369303;19745.703814442793;20346.491337716674;20375;20513.589728205352;20742.962851857348;8016.278534931767;8125.593720314079;8227.259546640831;8306.54314012723;8313.501189928502;4581.633469322446;4582.541745825458;4606.723596584216;4639.095376401569;4664.440267167962;3058.694130586968;3558.2705545363137;3655.250673611903;3657.085728567858;3657.3233951718485;679.1917618034523;689.8758223519715;693.1508901595505;707.4340527577917;708.2917082917083;618.811262564922;696.474162007681;701.5580184483845;704.1022696062561;721.8844984802406;7928.6035698214855;9299.302552308603;9414.246860251164;9434.339596228336;9588.520573971273;530.655074201772;653.0824192010233;669.1434963247052;688.1535711075305;697.3026973026973;4300.397944287803;4429.844955426544;4456.598906098467;4480.507144214133;4549.431321084864;90486.09513904908;94265.52867235757;94610;95441.5227923856;95501.5224923871;78727.60636196897;80724.59637701773;81450;83833.16166838375;86375;71405;72269;72947;73455.53088938192;73601;8800.119988001148;8822.51476168817;8822.735317940473;8933.717579250835;9061.592228349871;38883;41485.75542733723;44279;46036.76981615071;47007.58977230552;50074.49925500698;59296.407035929085;59399.70300148472;61367;61402.38597613967;11653.417329133339;11660.883391166319;11676.649700508999;11819.699833018189;12344.259344439362;13524.391402387113;14086.788698169741;14421.527874460277;14704.338304776096;15044;13351.17484347066;13356.131851429625;13476;13520.18878867271;13633.045686802225;11773.470193841127;13407.798883016958;13584.524541640994;13676.247806370746;13704.314784260549;12520.561780337644;13230.536931207362;13613.455461781417;13694.863051369359;13754.862451375759;11412.48643811047;11447.713807154554;11478.426078696199;11592.826107608224;11674.124440667154;11094.278871873625;11264.66206013821;11426.771464570827;11471.827922581335;11607.129465290305 08:32:05 INFO - PROCESS | 1658 | __end_tp_report 08:32:05 INFO - PROCESS | 1658 | __start_cc_report 08:32:05 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1431 08:32:05 INFO - PROCESS | 1658 | __end_cc_report 08:32:05 INFO - PROCESS | 1658 | __startTimestamp1464795125902__endTimestamp 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | ------- Summary: start ------- 08:32:05 INFO - PROCESS | 1658 | Number of tests: 6 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5663.35 Median:5916.38 stddev:655.29 (11.1%) stddev-sans-first:633.02 08:32:05 INFO - PROCESS | 1658 | Values: 3410.7 3433.2 3439.8 3453.2 3505.7 5556.5 5573.0 5638.6 5667.8 5681.4 5541.4 5636.7 5672.3 5674.9 5719.6 4160.7 4177.0 4674.8 5191.2 5231.7 5192.5 5211.7 5230.5 5240.1 5248.9 5061.1 5097.3 5123.1 5123.8 5190.2 5153.7 5154.1 5169.3 5174.7 5180.2 5024.3 5049.6 5116.2 5132.7 5133.0 5514.3 5598.9 5618.9 5656.2 5674.1 6209.1 6215.9 6218.5 6228.8 6239.0 5377.9 6035.8 6145.2 6269.6 6298.2 5321.4 5434.6 5776.9 5806.4 5814.7 6161.9 6313.4 6321.0 6334.4 6339.5 6041.8 6099.7 6101.5 6106.6 6146.3 6237.4 6240.5 6241.6 6247.4 6251.0 5954.8 6015.4 6188.7 6191.3 6205.6 6106.0 6110.1 6140.3 6164.8 6189.6 4856.8 4860.5 4867.9 4877.5 4881.6 4366.5 4376.7 4419.2 4423.1 4433.9 5481.0 5531.1 5549.3 5559.3 5603.0 6148.7 6154.8 6162.8 6163.3 6171.0 5454.4 5520.1 5539.8 5552.9 5555.8 5257.8 5310.7 5332.4 5333.6 5333.7 6225.2 6299.7 6322.1 6333.4 6388.9 6208.9 6214.4 6216.5 6219.0 6226.6 6246.1 6260.2 6307.2 6343.4 6364.1 6028.2 6104.8 6113.3 6129.9 6140.0 6152.9 6170.8 6196.1 6199.8 6219.0 5942.5 5961.7 6020.2 6060.1 6084.6 4959.4 6024.1 6054.5 6074.1 6104.0 5032.8 5953.0 5975.4 5978.9 5999.2 6036.5 6045.1 6058.3 6097.5 6098.7 5783.9 5813.4 5844.5 5872.7 5916.4 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:75341.70 Median:39720.90 stddev:163315.22 (411.2%) stddev-sans-first:163676.64 08:32:05 INFO - PROCESS | 1658 | Values: 480.8 508.2 520.7 526.9 552.7 32048.2 42865.8 44180.6 44278.8 46376.8 45279.3 45918.1 45961.5 46083.0 46230.3 8630.7 9473.8 9732.5 9732.8 9732.9 8675.7 11493.5 11727.7 11780.0 11977.5 11745.8 11844.1 11864.8 11874.6 11881.6 8605.7 11667.9 11703.2 11795.0 11967.4 11963.9 12052.3 12075.2 12092.5 12103.7 41777.6 42330.8 42413.8 42447.8 42541.6 2980.3 3432.6 3458.7 3466.5 3532.2 31996.4 32385.7 33092.0 33180.3 33252.8 3356.2 3381.8 3392.0 3411.1 3426.2 490617.0 491688.0 492005.0 492247.1 492367.0 65278.0 65484.3 65487.3 65561.7 65599.3 68737.3 68867.0 68869.7 68935.0 68979.7 15344.8 17063.1 17304.7 17323.9 17344.4 10607.0 11925.3 12242.7 13119.1 13159.7 18651.3 18705.0 18727.2 18802.7 18810.2 4076.3 6459.6 6947.1 7008.2 7231.1 903.7 1003.8 1009.2 1073.6 1107.7 29181.6 31042.1 31217.5 31323.5 31524.8 1067.2 1134.5 1136.9 1214.6 1259.8 13307.5 14129.6 14785.5 15272.4 15992.0 690259.0 698474.0 698943.0 699551.0 700585.0 61704.1 63260.0 63263.1 63445.7 63505.0 1287.5 1323.6 1324.1 1414.0 1435.2 28034.7 32205.0 32252.0 33500.8 34215.0 56569.2 60182.7 60635.0 60680.7 60828.0 620919.0 626837.0 627604.0 627831.0 628473.0 43884.0 43942.9 44921.8 44991.6 45131.1 49654.8 50425.2 50629.0 51299.7 52068.0 36906.8 41251.2 41471.0 41863.6 42980.4 40229.0 40273.0 40626.9 40731.6 41674.4 39331.0 39407.0 40034.8 40036.0 40083.8 32015.8 35062.8 36006.3 36262.8 36369.0 25979.4 26185.0 26430.9 26474.2 26802.6 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:33848.93 Median:21598.18 stddev:94583.17 (437.9%) stddev-sans-first:94815.12 08:32:05 INFO - PROCESS | 1658 | Values: 381.5 385.8 386.7 399.6 404.3 19700.4 21288.6 21547.9 21577.0 22308.3 20410.9 20531.4 21204.8 21232.7 21431.7 5664.5 5666.7 6054.9 6057.5 6118.8 3835.8 4066.7 5754.2 6017.7 6085.3 6121.7 6129.8 6134.6 6141.2 6142.9 6368.5 6376.2 6603.9 6679.7 6685.7 3720.9 5404.5 5740.4 5740.6 5785.5 13511.9 13763.8 13780.8 21619.4 22769.8 1980.3 2048.3 2122.8 2167.5 2214.1 9991.6 10017.8 10057.3 10317.3 10513.7 1584.9 1648.4 1663.6 1698.5 1743.7 112085.0 118540.8 118798.0 120631.8 120989.0 11390.5 11551.8 11590.8 11598.7 11732.0 14587.9 14640.3 14657.4 14705.0 14707.9 8165.1 8349.4 8352.5 8452.7 8460.2 7135.4 7608.5 8048.4 8097.5 8211.0 4443.6 4530.4 4531.1 4592.9 4595.4 1582.3 1777.2 1824.1 1831.8 2006.5 867.7 921.9 1014.8 1022.9 1033.2 20170.3 21157.3 21321.4 21334.3 21556.5 896.2 936.2 957.1 959.0 959.9 9958.4 10477.0 11259.2 11307.1 11361.9 539901.0 560651.0 565683.0 573913.0 578645.0 128825.4 130974.0 132725.0 133896.0 135553.0 953.6 961.5 976.5 976.6 992.4 15613.5 18514.3 18804.4 18923.9 18959.5 36712.0 50814.0 52561.7 52848.5 53331.7 74912.6 76137.0 76614.6 76899.6 77355.5 8160.6 8209.1 8480.8 8482.9 8508.8 11778.7 12602.5 12949.2 13104.0 13116.7 10855.6 11096.0 11276.0 11302.2 11473.8 10978.7 11034.6 11141.4 11160.7 11249.9 11219.4 11249.6 11488.7 11518.4 11598.4 8144.1 8182.6 8205.1 8215.9 8243.8 10193.3 11207.1 11304.3 11329.4 11334.8 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5759.67 Median:4839.42 stddev:3256.24 (67.3%) stddev-sans-first:3248.81 08:32:05 INFO - PROCESS | 1658 | Values: 1389.3 1546.5 1579.2 1801.0 1966.7 7360.7 7899.3 8602.0 8840.5 9009.5 8578.7 8838.8 8843.0 8848.9 8850.2 2105.6 2313.1 2365.7 2399.2 2407.6 1994.3 2420.1 2441.5 2446.3 2459.6 2380.9 2391.6 2422.8 2423.9 2427.4 2076.7 2157.0 2161.6 2168.1 2316.9 2153.8 2182.2 2344.6 2420.0 2424.5 7401.8 8944.6 8959.3 8962.8 8969.6 5200.9 5375.2 5381.3 5390.0 5405.2 3463.3 3587.0 3624.1 3635.5 3652.9 1075.0 1095.4 1106.2 1113.2 1125.3 11920.0 11923.0 11925.3 11957.2 11959.5 4758.2 4790.6 4812.3 4825.5 4853.4 8370.9 9982.4 10111.9 10115.0 10123.2 5387.6 5390.1 5391.2 5391.4 5396.1 4387.7 4438.0 4559.4 4573.6 4890.3 3298.9 3372.6 4034.8 4082.6 4119.1 1311.8 1313.0 1319.1 1324.5 1330.4 839.7 899.0 904.0 916.2 936.4 5639.0 5895.0 5938.4 6149.1 6189.8 922.6 928.8 971.0 985.3 989.8 3915.9 4032.6 4043.9 4045.4 4082.9 10518.2 10544.8 10944.3 10965.1 11042.2 10908.8 10929.8 11014.2 11020.4 11056.3 8111.9 8320.8 8385.4 8401.1 8412.2 5818.8 5961.4 5985.9 6034.5 6044.3 10253.5 10386.5 10407.9 10418.8 10424.4 9885.6 10279.3 10295.1 10438.6 10442.6 7038.2 7262.7 7429.1 7700.0 7701.7 8568.7 8579.6 8613.0 8690.5 8761.9 7576.5 7588.5 7646.9 7729.7 7750.1 6170.7 6968.7 7491.6 7510.4 7619.5 7076.6 7182.1 7191.5 7192.7 7202.4 4928.4 5045.6 5089.8 5129.7 5142.7 4698.9 5061.7 5130.3 5185.7 5239.0 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4491.90 Median:5106.21 stddev:1417.06 (27.8%) stddev-sans-first:1385.80 08:32:05 INFO - PROCESS | 1658 | Values: 308.2 314.9 317.2 321.4 323.6 5352.6 5399.8 5411.4 5430.7 5485.9 4117.5 4435.9 5103.1 5175.7 5407.4 3241.2 3520.7 4007.5 4034.3 4072.5 3295.6 3951.8 4049.5 4072.0 4077.9 4003.1 4032.5 4048.4 4065.6 4072.7 3260.4 3826.4 3998.2 4077.3 4089.3 2846.0 3533.6 3536.1 3545.8 3548.6 4252.5 5180.5 5203.9 5207.7 5251.4 3812.8 4164.7 4618.9 4641.8 4663.1 4775.7 5535.0 5546.1 5618.7 5654.2 3221.7 3275.9 3821.8 3848.7 3867.3 4880.8 5783.4 5839.5 5969.0 5970.4 3212.8 3792.6 3803.5 3810.5 3815.1 3438.0 3563.0 3625.8 3719.1 3882.5 4311.4 4551.6 4552.2 4556.5 4622.9 4939.6 5192.8 5322.4 5334.9 5642.7 1195.9 1296.8 1306.9 1342.4 1348.4 772.6 805.4 814.9 816.1 833.7 4278.6 4343.9 4966.7 5059.7 5097.4 4649.8 5792.0 5798.5 5816.5 5819.4 5007.3 5216.9 5251.8 5261.8 5298.4 3018.4 3022.0 3023.2 3035.6 3050.6 5619.3 5994.6 6020.7 6061.9 6074.3 5306.5 5343.6 5586.7 5983.9 5987.0 5583.6 5866.6 6051.5 6055.8 6057.6 5129.8 5314.4 5595.5 5650.0 5808.4 4994.8 5283.5 5552.8 5661.2 5698.7 5158.8 5379.5 5711.7 5755.9 6034.4 3220.4 3606.4 3821.5 3829.4 3906.9 5758.2 5801.8 5826.2 5861.6 5888.4 5295.4 5659.9 5675.8 5694.1 5754.9 4301.0 4988.8 5445.9 5644.5 5734.6 5109.3 5520.9 5528.2 5549.7 5553.5 4484.9 4488.6 4664.3 5620.6 5692.2 4901.5 5306.5 5559.0 5571.5 5604.3 08:32:05 INFO - PROCESS | 1658 | 08:32:05 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:18630.04 Median:4634.39 stddev:25417.30 (548.5%) stddev-sans-first:25453.02 08:32:05 INFO - PROCESS | 1658 | Values: 718.7 776.3 828.6 855.2 859.4 14684.8 14897.0 14904.9 14906.6 14920.4 13828.4 13900.7 13902.6 13930.7 13935.4 704.7 730.2 871.8 877.9 898.3 871.4 884.4 886.5 886.7 888.4 945.9 950.0 950.5 951.1 953.4 741.1 767.8 768.6 771.4 945.6 573.4 672.7 696.6 697.0 697.6 14447.0 14514.0 14517.9 14526.9 14552.6 13268.5 13469.8 13585.6 13640.9 13905.7 11979.2 12141.7 12159.8 12257.4 12750.2 4455.9 4506.1 4532.8 4629.7 4689.2 75746.0 76080.6 82575.0 82706.6 84054.6 10422.7 10533.2 10614.5 10913.6 10913.6 19745.7 20346.5 20375.0 20513.6 20743.0 8016.3 8125.6 8227.3 8306.5 8313.5 4581.6 4582.5 4606.7 4639.1 4664.4 3058.7 3558.3 3655.3 3657.1 3657.3 679.2 689.9 693.2 707.4 708.3 618.8 696.5 701.6 704.1 721.9 7928.6 9299.3 9414.2 9434.3 9588.5 530.7 653.1 669.1 688.2 697.3 4300.4 4429.8 4456.6 4480.5 4549.4 90486.1 94265.5 94610.0 95441.5 95501.5 78727.6 80724.6 81450.0 83833.2 86375.0 71405.0 72269.0 72947.0 73455.5 73601.0 8800.1 8822.5 8822.7 8933.7 9061.6 38883.0 41485.8 44279.0 46036.8 47007.6 50074.5 59296.4 59399.7 61367.0 61402.4 11653.4 11660.9 11676.6 11819.7 12344.3 13524.4 14086.8 14421.5 14704.3 15044.0 13351.2 13356.1 13476.0 13520.2 13633.0 11773.5 13407.8 13584.5 13676.2 13704.3 12520.6 13230.5 13613.5 13694.9 13754.9 11412.5 11447.7 11478.4 11592.8 11674.1 11094.3 11264.7 11426.8 11471.8 11607.1 08:32:05 INFO - PROCESS | 1658 | -------- Summary: end -------- 08:32:05 INFO - PROCESS | 1658 | 08:32:06 INFO - TEST-INFO | 1658: exit 0 08:32:06 INFO - TEST-OK | dromaeo_css | took 1116973ms 08:32:06 INFO - TEST-START | kraken 08:32:06 INFO - operating with platform_type : mac_ 08:32:06 INFO - Initialising browser for kraken test... 08:32:06 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmp2jnF5h/profile http://localhost:49269/getInfo.html) 08:32:08 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 08:32:08 INFO - PROCESS | 1669 | colorDepth:24 08:32:08 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 08:32:08 INFO - PROCESS | 1669 | __metrics 08:32:08 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:32:08 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:32:09 INFO - TEST-INFO | 1669: exit 0 08:32:09 INFO - Browser initialized. 08:32:09 INFO - Running cycle 1/1 for kraken test... 08:32:09 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmp2jnF5h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:21 INFO - PROCESS | 1670 | RSS: Main: 241684480 08:32:21 INFO - PROCESS | 1670 | 08:32:24 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 08:32:25 INFO - PROCESS | 1670 | RSS: Main: 291966976 08:32:25 INFO - PROCESS | 1670 | 08:32:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 08:32:28 INFO - PROCESS | 1670 | RSS: Main: 301649920 08:32:28 INFO - PROCESS | 1670 | 08:32:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 08:32:32 INFO - PROCESS | 1670 | RSS: Main: 387739648 08:32:32 INFO - PROCESS | 1670 | 08:32:34 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 08:32:35 INFO - PROCESS | 1670 | RSS: Main: 287416320 08:32:35 INFO - PROCESS | 1670 | 08:32:37 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:32:38 INFO - PROCESS | 1670 | RSS: Main: 291704832 08:32:38 INFO - PROCESS | 1670 | 08:32:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 08:32:41 INFO - PROCESS | 1670 | RSS: Main: 326344704 08:32:41 INFO - PROCESS | 1670 | 08:32:44 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 08:32:45 INFO - PROCESS | 1670 | RSS: Main: 328613888 08:32:45 INFO - PROCESS | 1670 | 08:32:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 08:32:48 INFO - PROCESS | 1670 | RSS: Main: 328454144 08:32:48 INFO - PROCESS | 1670 | 08:32:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:32:50 INFO - PROCESS | 1670 | RSS: Main: 309952512 08:32:50 INFO - PROCESS | 1670 | 08:32:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:32:53 INFO - PROCESS | 1670 | RSS: Main: 316981248 08:32:53 INFO - PROCESS | 1670 | 08:32:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:32:56 INFO - PROCESS | 1670 | RSS: Main: 320483328 08:32:56 INFO - PROCESS | 1670 | 08:32:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:32:59 INFO - PROCESS | 1670 | RSS: Main: 322916352 08:32:59 INFO - PROCESS | 1670 | 08:33:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:33:03 INFO - PROCESS | 1670 | RSS: Main: 323244032 08:33:03 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 08:33:05 INFO - PROCESS | 1670 | RSS: Main: 367288320 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | __start_tp_report 08:33:05 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 08:33:05 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 08:33:05 INFO - PROCESS | 1670 | |i|pagename|runs| 08:33:05 INFO - PROCESS | 1670 | |0;ai-astar;112;105;112;116;158;166;111;112;112;112 08:33:05 INFO - PROCESS | 1670 | |1;audio-beat-detection;170;142;139;168;115;138;135;117;165;128 08:33:05 INFO - PROCESS | 1670 | |2;audio-dft;184;186;183;183;180;182;182;180;182;184 08:33:05 INFO - PROCESS | 1670 | |3;audio-fft;78;73;58;87;58;57;68;83;110;79 08:33:05 INFO - PROCESS | 1670 | |4;audio-oscillator;85;87;93;73;71;68;94;70;68;101 08:33:05 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;90;87;91;88;90;85;91;72;116;131 08:33:05 INFO - PROCESS | 1670 | |6;imaging-darkroom;96;96;96;95;94;98;96;96;94;96 08:33:05 INFO - PROCESS | 1670 | |7;imaging-desaturate;85;86;129;139;87;87;85;86;85;84 08:33:05 INFO - PROCESS | 1670 | |8;json-parse-financial;57;56;54;57;54;55;55;56;55;57 08:33:05 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;65;75;66;76;71;71;68;69;70;71 08:33:05 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;86;87;95;108;109;86;87;122;88 08:33:05 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;141;139;128;124;139;153;146;135;151;141 08:33:05 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;164;166;169;165;161;167;165;164;165;161 08:33:05 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;67;64;63;66;66;65;64;65;66;65 08:33:05 INFO - PROCESS | 1670 | __end_tp_report 08:33:05 INFO - PROCESS | 1670 | __start_cc_report 08:33:05 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1976 08:33:05 INFO - PROCESS | 1670 | __end_cc_report 08:33:05 INFO - PROCESS | 1670 | __startTimestamp1464795185322__endTimestamp 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | ------- Summary: start ------- 08:33:05 INFO - PROCESS | 1670 | Number of tests: 14 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:121.60 Median:112.00 stddev:21.54 (19.2%) stddev-sans-first:22.57 08:33:05 INFO - PROCESS | 1670 | Values: 112.0 105.0 112.0 116.0 158.0 166.0 111.0 112.0 112.0 112.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:140.50 stddev:20.03 (14.3%) stddev-sans-first:18.45 08:33:05 INFO - PROCESS | 1670 | Values: 170.0 142.0 139.0 168.0 115.0 138.0 135.0 117.0 165.0 128.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.84 (1.0%) stddev-sans-first:1.88 08:33:05 INFO - PROCESS | 1670 | Values: 184.0 186.0 183.0 183.0 180.0 182.0 182.0 180.0 182.0 184.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:75.10 Median:75.50 stddev:16.37 (21.7%) stddev-sans-first:17.33 08:33:05 INFO - PROCESS | 1670 | Values: 78.0 73.0 58.0 87.0 58.0 57.0 68.0 83.0 110.0 79.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:79.00 stddev:12.42 (15.7%) stddev-sans-first:13.09 08:33:05 INFO - PROCESS | 1670 | Values: 85.0 87.0 93.0 73.0 71.0 68.0 94.0 70.0 68.0 101.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:89.00 stddev:16.84 (18.9%) stddev-sans-first:17.80 08:33:05 INFO - PROCESS | 1670 | Values: 90.0 87.0 91.0 88.0 90.0 85.0 91.0 72.0 116.0 131.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:1.16 (1.2%) stddev-sans-first:1.22 08:33:05 INFO - PROCESS | 1670 | Values: 96.0 96.0 96.0 95.0 94.0 98.0 96.0 96.0 94.0 96.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:85.50 stddev:20.55 (24.0%) stddev-sans-first:21.46 08:33:05 INFO - PROCESS | 1670 | Values: 85.0 86.0 129.0 139.0 87.0 87.0 85.0 86.0 85.0 84.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.17 (2.1%) stddev-sans-first:1.13 08:33:05 INFO - PROCESS | 1670 | Values: 57.0 56.0 54.0 57.0 54.0 55.0 55.0 56.0 55.0 57.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:3.49 (4.9%) stddev-sans-first:3.15 08:33:05 INFO - PROCESS | 1670 | Values: 65.0 75.0 66.0 76.0 71.0 71.0 68.0 69.0 70.0 71.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:95.40 Median:86.50 stddev:12.96 (15.0%) stddev-sans-first:13.30 08:33:05 INFO - PROCESS | 1670 | Values: 86.0 86.0 87.0 95.0 108.0 109.0 86.0 87.0 122.0 88.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:141.00 stddev:9.15 (6.5%) stddev-sans-first:9.70 08:33:05 INFO - PROCESS | 1670 | Values: 141.0 139.0 128.0 124.0 139.0 153.0 146.0 135.0 151.0 141.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:165.00 stddev:2.45 (1.5%) stddev-sans-first:2.59 08:33:05 INFO - PROCESS | 1670 | Values: 164.0 166.0 169.0 165.0 161.0 167.0 165.0 164.0 165.0 161.0 08:33:05 INFO - PROCESS | 1670 | 08:33:05 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:1.20 (1.8%) stddev-sans-first:1.05 08:33:05 INFO - PROCESS | 1670 | Values: 67.0 64.0 63.0 66.0 66.0 65.0 64.0 65.0 66.0 65.0 08:33:05 INFO - PROCESS | 1670 | -------- Summary: end -------- 08:33:05 INFO - PROCESS | 1670 | 08:33:06 INFO - TEST-INFO | 1670: exit 0 08:33:06 INFO - TEST-OK | kraken | took 59680ms 08:33:06 INFO - SUITE-END | took 1176s 08:33:06 INFO - Completed test suite (00:19:36) 08:33:06 INFO - javascript benchmark 08:33:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3410.7271418286537, 3433.244686169042, 3439.7764145330566, 3453.1539772755677, 3505.6669616386434, 5556.499915007648, 5573.0, 5638.633488823224, 5667.829965101043, 5681.403452637475, 5541.418151094137, 5636.661800291985, 5672.347680016799, 5674.914876276849, 5719.628224165426, 4160.7087503874745, 4176.958230417703, 4674.766261686919, 5191.195364718475, 5231.65994210377, 5192.454792246816, 5211.687298762086, 5230.476952304777, 5240.135377662681, 5248.8687782805355, 5061.063703214881, 5097.337346144977, 5123.129068058421, 5123.769430375642, 5190.221466779975, 5153.665011774223, 5154.07226699194, 5169.2504586835075, 5174.689518628894, 5180.171172612401, 5024.321716568253, 5049.596032317406, 5116.232565115225, 5132.717700526469, 5132.974335128338, 5514.310711161105, 5598.944010559883, 5618.943810561924, 5656.236408084897, 5674.148877668342, 6209.099680546341, 6215.87568248637, 6218.5025197984505, 6228.781992630282, 6238.970569855968, 5377.865553361158, 6035.849103772397, 6145.170401995762, 6269.5611307208055, 6298.244210694746, 5321.361436627552, 5434.592405569598, 5776.855578610526, 5806.44838740319, 5814.651120932673, 6161.938380616226, 6313.40022697843, 6320.957042088004, 6334.366563343721, 6339.461145802658, 6041.848953776191, 6099.725994698907, 6101.481374083164, 6106.603070800473, 6146.279041868284, 6237.407446292595, 6240.5319601030105, 6241.594296370721, 6247.406496382836, 6250.968745156245, 5954.761809527657, 6015.428534289235, 6188.690565471798, 6191.349908259568, 6205.596636218631, 6106.023036314168, 6110.103144028831, 6140.2982687617305, 6164.845878853064, 6189.566730328924, 4856.781444834991, 4860.465348811628, 4867.9269810952865, 4877.5366340197625, 4881.609471242292, 4366.519682834885, 4376.737395756265, 4419.248727716296, 4423.071155057474, 4433.933490997638, 5480.986017586759, 5531.059719847635, 5549.306336707911, 5559.305086864142, 5603.047481928082, 6148.723307451175, 6154.846128846725, 6162.784302549389, 6163.291221509584, 6170.969145154246, 5454.400015998237, 5520.1443776214755, 5539.833804985857, 5552.861178470571, 5555.805546805923, 5257.8159764408065, 5310.654807437504, 5332.3867755207875, 5333.57331413486, 5333.653312534673, 6225.221847269091, 6299.748010079728, 6322.051692246107, 6333.429991300805, 6388.936110638834, 6208.937910620836, 6214.40963108504, 6216.471599914058, 6219.0, 6226.59527130735, 6246.1255424239735, 6260.186175797299, 6307.180066591313, 6343.397377249154, 6364.109024736448, 6028.216331876828, 6104.822969073627, 6113.266408031065, 6129.939558357133, 6139.952147134979, 6152.907706384318, 6170.845728856814, 6196.0705894116245, 6199.752009919553, 6219.0, 5942.494887934574, 5961.672108033927, 6020.217371741645, 6060.090986352083, 6084.6349219047, 4959.404871415453, 6024.126501657148, 6054.485368743706, 6074.149619053248, 6104.023356262976, 5032.823851165338, 5952.958232309242, 5975.43233392827, 5978.850528736816, 5999.220101386792, 6036.486898613667, 6045.1234570987845, 6058.333583305921, 6097.542684298606, 6098.725557349841, 5783.855403614775, 5813.360530341574, 5844.47399734026, 5872.677002764718, 5916.378780228186], "value": 5622.13414362956, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.7524125075586, 508.2427183496591, 520.7058012223093, 526.9341332333457, 552.6601140298717, 32048.198795030137, 42865.78567107164, 44180.558194418045, 44278.778606106964, 46376.76811615937, 45279.32081018779, 45918.08163836721, 45961.54038459623, 46083.0, 46230.30654540176, 8630.741077767678, 9473.763155921088, 9732.513374331254, 9732.805343893118, 9732.854007189897, 8675.69635062773, 11493.540258389654, 11727.7068073298, 11779.9987001105, 11977.461014254379, 11745.765084698302, 11844.052475801915, 11864.7627047459, 11874.643760687193, 11881.643550693492, 8605.698800541952, 11667.883321166806, 11703.18077734559, 11794.997425218824, 11967.401629918557, 11963.940180299043, 12052.276863388224, 12075.154739168262, 12092.516299348015, 12103.697407564794, 41777.582224177975, 42330.78834605838, 42413.78793106015, 42447.78776106131, 42541.57458425407, 2980.2847316643847, 3432.639572844865, 3458.706009989154, 3466.5493485846932, 3532.1875968527124, 31996.360072798645, 32385.676143238736, 33092.00723978285, 33180.336393272, 33252.83373583117, 3356.2448449098756, 3381.8140002299865, 3392.0332705179153, 3411.1131105912395, 3426.194844211608, 490617.0, 491688.0, 492005.0, 492247.0775292237, 492367.0, 65278.02082968759, 65484.34515654878, 65487.34512654813, 65561.67219163873, 65599.34400655932, 68737.31262687409, 68867.0, 68869.65565172142, 68935.0, 68979.65510172518, 15344.769828452581, 17063.06153161588, 17304.740428893576, 17323.913380433016, 17344.392946246822, 10607.0, 11925.344106074075, 12242.693932651577, 13119.081664283409, 13159.67100822487, 18651.253949841856, 18705.0, 18727.157277922255, 18802.717959230624, 18810.24759009652, 4076.316880962256, 6459.644719540376, 6947.054824649106, 7008.159020917421, 7231.096112985877, 903.6656437118309, 1003.7641154328727, 1009.2279406254221, 1073.592035026685, 1107.7286064914147, 29181.56227656587, 31042.068737937898, 31217.531737023965, 31323.522124771185, 31524.842375787975, 1067.2209287220373, 1134.5291703942771, 1136.909047276216, 1214.6477521518661, 1259.8362212912264, 13307.534236301683, 14129.646758830906, 14785.482508112165, 15272.389104435699, 15992.0, 690259.0, 698474.0, 698943.0, 699551.0, 700585.0, 61704.07443888345, 63260.0, 63263.05105423422, 63445.682771585845, 63505.0, 1287.5171810571037, 1323.616151316127, 1324.1260767893145, 1413.9787903181461, 1435.1532595768547, 28034.71965280321, 32205.0, 32252.032439026865, 33500.83249583736, 34214.97355079352, 56569.151462728914, 60182.69908650429, 60635.0, 60680.69659651673, 60828.0, 620919.0, 626837.0, 627604.0, 627831.0, 628473.0, 43884.0, 43942.90142746457, 44921.775391122836, 44991.55008449873, 45131.09737805291, 49654.751726241135, 50425.243621346446, 50629.0, 51299.74350128225, 52068.0, 36906.8154659225, 41251.1749765009, 41471.0, 41863.58136418597, 42980.35529467123, 40229.0, 40273.0, 40626.85915995759, 40731.59268407278, 41674.374884377365, 39331.0, 39407.0, 40034.79982600185, 40036.0, 40083.799581001906, 32015.83992080118, 35062.82468587641, 36006.27987440289, 36262.818685907456, 36368.9994100654, 25979.350516237246, 26185.0, 26430.942762290062, 26474.205773826816, 26802.62055079183], "value": 21392.40289377231, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.454520036348, 385.78974458919885, 386.66167103784187, 399.56846605665885, 404.3247776213723, 19700.408987730374, 21288.57422851542, 21547.92260386982, 21577.0, 22308.330750077523, 20410.89794551029, 20531.38405847827, 21204.833734144617, 21232.681509777365, 21431.678524822102, 5664.518515926141, 5666.716664166775, 6054.939450605481, 6057.515398768088, 6118.816435506942, 3835.8273877675433, 4066.654334381581, 5754.223179870727, 6017.669028203447, 6085.300190478108, 6121.693915304239, 6129.754809807602, 6134.6012509186985, 6141.201643786301, 6142.907856382158, 6368.458681012119, 6376.171097757262, 6603.900941485882, 6679.66601669917, 6685.732570697191, 3720.8883733488037, 5404.540614047772, 5740.368559458456, 5740.569457290678, 5785.450382213683, 13511.864881351257, 13763.793543096861, 13780.793288100687, 21619.35141945744, 22769.772302277095, 1980.3068925875973, 2048.3342913553006, 2122.8407869409702, 2167.4689701023194, 2214.125420458926, 9991.550380232908, 10017.84973225395, 10057.346272492265, 10317.277790554665, 10513.684589462327, 1584.865286450653, 1648.4230519318262, 1663.6007358233937, 1698.541393823673, 1743.7471566622896, 112085.0, 118540.81459185471, 118798.0, 120631.79368206294, 120989.0, 11390.487428065757, 11551.76896462066, 11590.826137607943, 11598.71003224926, 11732.0, 14587.927060364842, 14640.341184646717, 14657.413703451955, 14705.0, 14707.852921470649, 8165.1426600207315, 8349.4155409122, 8352.457090289112, 8452.661893524313, 8460.238578527962, 7135.357817796424, 7608.467407281437, 8048.356131509465, 8097.514149151069, 8211.01467823853, 4443.555644435531, 4530.411046563926, 4531.13908357411, 4592.908141837144, 4595.379623750818, 1582.272154808778, 1777.235788610884, 1824.1351680202604, 1831.766210882067, 2006.5084054406764, 867.6789587852535, 921.9078092190728, 1014.7818219082933, 1022.9437380943971, 1033.2405681823852, 20170.29403970854, 21157.25949591757, 21321.36035918925, 21334.253301134388, 21556.529255715497, 896.2471523919938, 936.241644268145, 957.1194501059042, 958.9760255993656, 959.9376040557341, 9958.448200087863, 10477.020799601445, 11259.211855170059, 11307.095432365391, 11361.886381136248, 539901.0, 560651.0, 565683.0, 573913.0, 578645.0, 128825.35587322191, 130974.0, 132725.0, 133895.99156012668, 135553.0, 953.5899563187896, 961.4904100826573, 976.5166242709762, 976.5751897924425, 992.3599278465493, 15613.453529126426, 18514.259429623202, 18804.435866924014, 18923.90538047301, 18959.526011849815, 36712.043874077724, 50814.0, 52561.7371913138, 52848.47151528436, 53331.73334133304, 74912.62543687464, 76137.0, 76614.61692691724, 76899.61550192213, 77355.47748323121, 8160.591970401338, 8209.096999330188, 8480.83038339242, 8482.872756908528, 8508.787280318042, 11778.705532361759, 12602.49590016389, 12949.158304710163, 13103.951683865078, 13116.73766524645, 10855.565777369133, 11096.0, 11275.985161335519, 11302.152338574475, 11473.770524589294, 10978.725531861768, 11034.613788517363, 11141.442927853737, 11160.665180044612, 11249.887501125211, 11219.439028048728, 11249.606263780728, 11488.655340339803, 11518.36648984314, 11598.362090085126, 8144.104148543774, 8182.631781569963, 8205.056418511947, 8215.876761848458, 8243.752687419386, 10193.286469947032, 11207.103431725605, 11304.321740695585, 11329.433528323716, 11334.773304533699], "value": 9396.433354043522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.2984043058254, 1546.5128484527372, 1579.1551519936831, 1800.981990180098, 1966.6656668366377, 7360.705571777123, 7899.328557072648, 8602.0, 8840.51377174256, 9009.504477253764, 8578.742637720863, 8838.77903052424, 8843.0, 8848.867266990985, 8850.247728943048, 2105.589410065038, 2313.063209400187, 2365.704286964125, 2399.184277345702, 2407.614781634939, 1994.3119623729863, 2420.080369459603, 2441.4506735984437, 2446.3272600035043, 2459.56957532432, 2380.8928598213124, 2391.6053851114543, 2422.842515236504, 2423.866687332196, 2427.3688840901405, 2076.6573515369946, 2157.0, 2161.6109100361928, 2168.132746901252, 2316.8609883406966, 2153.7831125414245, 2182.159868450642, 2344.6483027546005, 2420.0, 2424.527217192648, 7401.8149546261775, 8944.552772361421, 8959.328050396247, 8962.775930601721, 8969.596368163448, 5200.947990520084, 5375.22059301403, 5381.327334083239, 5390.029794636964, 5405.243265942771, 3463.3246516929207, 3587.0, 3624.0577449862963, 3635.4910312556262, 3652.9269414611827, 1074.983875241872, 1095.3811096730321, 1106.2366966792888, 1113.154002957758, 1125.3135587291827, 11919.999999999913, 11923.0, 11925.284482930965, 11957.222780519238, 11959.461824217931, 4758.1673207188605, 4790.592799612003, 4812.302216178635, 4825.469198388175, 4853.369062021915, 8370.916290837014, 9982.35114717541, 10111.898881011242, 10115.039071288216, 10123.1901447884, 5387.568994480433, 5390.0836857734275, 5391.16436952273, 5391.353037635509, 5396.082665946798, 4387.692861499664, 4438.0, 4559.430071241095, 4573.63410927125, 4890.290907818347, 3298.8515516801804, 3372.578427696538, 4034.7982600870428, 4082.5713300103657, 4119.114390406077, 1311.8425788905392, 1312.9540466083831, 1319.1491487990384, 1324.4768316515115, 1330.4345653097396, 839.7312859884901, 898.9820203596022, 903.9593218305192, 916.2120576304372, 936.3960245641523, 5639.0, 5894.970525147347, 5938.435848594377, 6149.0776383542825, 6189.783357582463, 922.6217250927087, 928.767808047988, 970.9563069661882, 985.3152059318704, 989.7674046599044, 3915.882523524299, 4032.6370626643156, 4043.8989025274013, 4045.4336392905016, 4082.897927551835, 10518.211134164969, 10544.789104218025, 10944.343339399502, 10965.122790176767, 11042.171837112248, 10908.836367454494, 10929.781404371868, 11014.229003969645, 11020.393878336608, 11056.336619802838, 8111.878321825177, 8320.8335833283, 8385.371097167737, 8401.075881653012, 8412.15878412166, 5818.76724930998, 5961.433663801932, 5985.880282394241, 6034.456898879122, 6044.33512313654, 10253.48732563354, 10386.532606032897, 10407.895921040996, 10418.791624167323, 10424.374537527772, 9885.604575816888, 10279.331843430153, 10295.07344339013, 10438.63464778729, 10442.582296708046, 7038.155421349471, 7262.74580389705, 7429.07136607924, 7699.961500192463, 7701.6534255957495, 8568.657253709782, 8579.613917373857, 8613.0, 8690.522021288889, 8761.868571971298, 7576.507527010726, 7588.506747061423, 7646.923530764621, 7729.729459468892, 7750.147483776893, 6170.6914654268, 6968.6864091115, 7491.625418728932, 7510.4464482076355, 7619.54282743015, 7076.610786406797, 7182.102237220348, 7191.532550384209, 7192.748253811092, 7202.423806095605, 4928.383952005999, 5045.646804723781, 5089.847304580869, 5129.692218466904, 5142.6914385137015, 4698.859034228979, 5061.670991385548, 5130.256112863539, 5185.688858668492, 5239.0], "value": 4620.878910644409, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.23711314496626, 314.8520195508112, 317.1785076651472, 321.3572854291417, 323.6472245252674, 5352.625316227865, 5399.838004859851, 5411.350637923455, 5430.72846357682, 5485.890282194354, 4117.46472958515, 4435.911281774363, 5103.081445339837, 5175.70607348163, 5407.351117865862, 3241.205904553388, 3520.683138517533, 4007.4790277263915, 4034.273830710471, 4072.450219220397, 3295.555100061497, 3951.802409879509, 4049.514058312992, 4072.0, 4077.8776636700945, 4003.0792917628964, 4032.4757781488365, 4048.3725022621543, 4065.6137666921595, 4072.6538244249273, 3260.4131256373844, 3826.368649172883, 3998.200359928026, 4077.3272410052305, 4089.284375234322, 2845.9651349285155, 3533.6289689582736, 3536.133647256413, 3545.7517973741847, 3548.5919119301093, 4252.4897012358715, 5180.497655679848, 5203.869903252411, 5207.656554550467, 5251.36983561975, 3812.8093595320124, 4164.68764842638, 4618.9076218475775, 4641.7911193996015, 4663.064828096336, 4775.71345719258, 5534.972325138348, 5546.140348246028, 5618.7190640468225, 5654.236678048452, 3221.695213438561, 3275.8689652413873, 3821.8280177392085, 3848.7113466490127, 3867.323218436762, 4880.755962201912, 5783.392743761929, 5839.503642190376, 5969.0, 5970.373110823344, 3212.8273180289025, 3792.6017768133947, 3803.4627796149293, 3810.48558444612, 3815.0843797488956, 3437.951424815405, 3562.982185089058, 3625.782453052825, 3719.1445967427644, 3882.495275614152, 4311.417958575615, 4551.613112885376, 4552.157850797546, 4556.476005259437, 4622.907541849144, 4939.604831613462, 5192.81825136126, 5322.365429112614, 5334.866628334323, 5642.71786410686, 1195.9162858599989, 1296.7925131978839, 1306.8956731561816, 1342.3959218351781, 1348.426918559628, 772.6445834916002, 805.3798575097143, 814.8736945773414, 816.0819078536646, 833.7498750374984, 4278.636315913182, 4343.891402714894, 4966.683828785374, 5059.696418214845, 5097.439281679012, 4649.790759415767, 5791.971040144772, 5798.5071268941765, 5816.505597024216, 5819.418058194231, 5007.29751884361, 5216.921746173811, 5251.789928402821, 5261.815836445706, 5298.417174110845, 3018.381231847466, 3022.0082118702767, 3023.1988523041414, 3035.5598438226775, 3050.5881705969855, 5619.26949496571, 5994.6403215807195, 6020.69896505173, 6061.909071363933, 6074.301455332693, 5306.495882891119, 5343.62594618373, 5586.748596313095, 5983.9401605983385, 5986.970065149733, 5583.581231407742, 5866.589338746332, 6051.546134039877, 6055.757769689163, 6057.636541807506, 5129.846104616868, 5314.374583425875, 5595.461248156626, 5650.0, 5808.448197421239, 4994.775235114356, 5283.498067683564, 5552.805651802168, 5661.150827375926, 5698.686572238401, 5158.793648254028, 5379.488948549882, 5711.714414279352, 5755.942440575708, 6034.366391528826, 3220.3559288141996, 3606.441001644749, 3821.4649949006607, 3829.4064420014943, 3906.9218615628097, 5758.165066065313, 5801.82594522165, 5826.184334193132, 5861.6189947653265, 5888.440598143169, 5295.4439783822145, 5659.943400565941, 5675.829725108253, 5694.1458781183155, 5754.9424505754405, 4300.956990430056, 4988.775505102207, 5445.863853403696, 5644.52021578154, 5734.569907256889, 5109.335786347751, 5520.91718624229, 5528.153001204302, 5549.72251387421, 5553.527950124285, 4484.887877803081, 4488.640908727229, 4664.277037059262, 5620.634658747168, 5692.231548740867, 4901.460839307744, 5306.514176030718, 5559.0, 5571.498565129158, 5604.271444712162], "value": 4042.113367499569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.7089228862311, 776.2547953964194, 828.5567221536479, 855.2260204514912, 859.3554833874598, 14684.779728304084, 14897.0, 14904.850951490482, 14906.552803415887, 14920.403183872646, 13828.377723002464, 13900.721985560283, 13902.582922512316, 13930.651733706662, 13935.372908219155, 704.6758491094077, 730.1785491322263, 871.7646235516406, 877.9473231606108, 898.3172788680617, 871.442276942754, 884.4295429448002, 886.5301390263171, 886.6542048601051, 888.4402826219473, 945.877035985321, 949.952502374882, 950.4677380666814, 951.1202138022336, 953.4470007395694, 741.0514541387006, 767.808047988003, 768.6387397922967, 771.4060173666246, 945.6217512994789, 573.4466240078344, 672.6939242644585, 696.5681277607865, 696.9756058537978, 697.5919087333865, 14447.0, 14513.984021118526, 14517.927410362987, 14526.927365363212, 14552.563423097217, 13268.469261229635, 13469.797953030713, 13585.59243222705, 13640.86359136406, 13905.721885562334, 11979.161458697894, 12141.696457588543, 12159.756804863942, 12257.448414821265, 12750.171238869443, 4455.866324010285, 4506.076254367847, 4532.79602417892, 4629.675922685414, 4689.155951928651, 75746.0, 76080.61959690166, 82575.0, 82706.58646706789, 84054.57972710159, 10422.739431514121, 10533.210009249184, 10614.469276536141, 10913.618023369145, 10913.618023369303, 19745.703814442793, 20346.491337716674, 20375.0, 20513.589728205352, 20742.962851857348, 8016.278534931767, 8125.593720314079, 8227.259546640831, 8306.54314012723, 8313.501189928502, 4581.633469322446, 4582.541745825458, 4606.723596584216, 4639.095376401569, 4664.440267167962, 3058.694130586968, 3558.2705545363137, 3655.250673611903, 3657.085728567858, 3657.3233951718485, 679.1917618034523, 689.8758223519715, 693.1508901595505, 707.4340527577917, 708.2917082917083, 618.811262564922, 696.474162007681, 701.5580184483845, 704.1022696062561, 721.8844984802406, 7928.6035698214855, 9299.302552308603, 9414.246860251164, 9434.339596228336, 9588.520573971273, 530.655074201772, 653.0824192010233, 669.1434963247052, 688.1535711075305, 697.3026973026973, 4300.397944287803, 4429.844955426544, 4456.598906098467, 4480.507144214133, 4549.431321084864, 90486.09513904908, 94265.52867235757, 94610.0, 95441.5227923856, 95501.5224923871, 78727.60636196897, 80724.59637701773, 81450.0, 83833.16166838375, 86375.0, 71405.0, 72269.0, 72947.0, 73455.53088938192, 73601.0, 8800.119988001148, 8822.51476168817, 8822.735317940473, 8933.717579250835, 9061.592228349871, 38883.0, 41485.75542733723, 44279.0, 46036.76981615071, 47007.58977230552, 50074.49925500698, 59296.407035929085, 59399.70300148472, 61367.0, 61402.38597613967, 11653.417329133339, 11660.883391166319, 11676.649700508999, 11819.699833018189, 12344.259344439362, 13524.391402387113, 14086.788698169741, 14421.527874460277, 14704.338304776096, 15044.0, 13351.17484347066, 13356.131851429625, 13476.0, 13520.18878867271, 13633.045686802225, 11773.470193841127, 13407.798883016958, 13584.524541640994, 13676.247806370746, 13704.314784260549, 12520.561780337644, 13230.536931207362, 13613.455461781417, 13694.863051369359, 13754.862451375759, 11412.48643811047, 11447.713807154554, 11478.426078696199, 11592.826107608224, 11674.124440667154, 11094.278871873625, 11264.66206013821, 11426.771464570827, 11471.827922581335, 11607.129465290305], "value": 7320.959516565128, "unit": "score"}], "name": "dromaeo_css", "value": 7325.6407252899035, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 116.0, 158.0, 166.0, 111.0, 112.0, 112.0, 112.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 142.0, 139.0, 168.0, 115.0, 138.0, 135.0, 117.0, 165.0, 128.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 183.0, 183.0, 180.0, 182.0, 182.0, 180.0, 182.0, 184.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 58.0, 87.0, 58.0, 57.0, 68.0, 83.0, 110.0, 79.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 93.0, 73.0, 71.0, 68.0, 94.0, 70.0, 68.0, 101.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 90.0, 85.0, 91.0, 72.0, 116.0, 131.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 94.0, 98.0, 96.0, 96.0, 94.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 129.0, 139.0, 87.0, 87.0, 85.0, 86.0, 85.0, 84.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 54.0, 57.0, 54.0, 55.0, 55.0, 56.0, 55.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 66.0, 76.0, 71.0, 71.0, 68.0, 69.0, 70.0, 71.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 95.0, 108.0, 109.0, 86.0, 87.0, 122.0, 88.0], "value": 95.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 128.0, 124.0, 139.0, 153.0, 146.0, 135.0, 151.0, 141.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 169.0, 165.0, 161.0, 167.0, 165.0, 164.0, 165.0, 161.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 66.0, 66.0, 65.0, 64.0, 65.0, 66.0, 65.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1477.8000000000002, "alertThreshold": 2.0}]} 08:33:06 INFO - Return code: 0 08:33:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:33:06 ERROR - # TBPL SUCCESS # 08:33:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:33:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:33:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:33:06 INFO - 2016-06-01 08:33:06.605 screenresolution[1674:9937] starting screenresolution argv=screenresolution get 08:33:06 INFO - 2016-06-01 08:33:06.608 screenresolution[1674:9937] Display 0: 1600x1200x32@60 08:33:06 INFO - 2016-06-01 08:33:06.614 screenresolution[1675:9941] starting screenresolution argv=screenresolution list 08:33:06 INFO - Available Modes on Display 0 08:33:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:33:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:33:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:33:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:33:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:33:06 INFO - Intel Iris: 08:33:06 INFO - Chipset Model: Intel Iris 08:33:06 INFO - Type: GPU 08:33:06 INFO - Bus: Built-In 08:33:06 INFO - VRAM (Dynamic, Max): 1536 MB 08:33:06 INFO - Vendor: Intel (0x8086) 08:33:06 INFO - Device ID: 0x0a2e 08:33:06 INFO - Revision ID: 0x0009 08:33:06 INFO - Displays: 08:33:06 INFO - Display: 08:33:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:33:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:33:06 INFO - Display Serial Number: 1600x1200 60 08:33:06 INFO - Main Display: Yes 08:33:06 INFO - Mirror: Off 08:33:06 INFO - Online: Yes 08:33:06 INFO - Rotation: Supported 08:33:06 INFO - Return code: 0 08:33:06 INFO - Running post-action listener: _resource_record_post_action 08:33:06 INFO - ##### 08:33:06 INFO - ##### Finished run-tests step (success) 08:33:06 INFO - ##### 08:33:06 INFO - Running post-run listener: _resource_record_post_run 08:33:06 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38020608; Write bytes: 696010240; Read time: 394; Write time: 2344 08:33:06 INFO - TinderboxPrint: CPU usage
53.6% 08:33:06 INFO - TinderboxPrint: I/O read bytes / time
38,020,608 / 394 08:33:06 INFO - TinderboxPrint: I/O write bytes / time
696,010,240 / 2,344 08:33:06 INFO - TinderboxPrint: CPU idle
2,251.3 (46.4%) 08:33:06 INFO - TinderboxPrint: CPU system
963.3 (19.9%) 08:33:06 INFO - TinderboxPrint: CPU user
1,635.0 (33.7%) 08:33:06 INFO - TinderboxPrint: Swap in / out
437,403,648 / 0 08:33:06 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393196032; Write bytes: 387393536; Read time: 29479; Write time: 994 08:33:06 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36185600; Write bytes: 293162496; Read time: 372; Write time: 1313 08:33:07 INFO - Running post-run listener: _upload_blobber_files 08:33:07 INFO - Blob upload gear active. 08:33:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:33:07 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 08:33:07 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'] 08:33:07 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 08:33:07 INFO - (blobuploader) - INFO - Open directory for files ... 08:33:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:33:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:33:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:33:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:33:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:33:08 INFO - (blobuploader) - INFO - Done attempting. 08:33:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:33:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:33:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:33:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:33:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:33:09 INFO - (blobuploader) - INFO - Done attempting. 08:33:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:33:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:33:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:33:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:33:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:33:12 INFO - (blobuploader) - INFO - Done attempting. 08:33:12 INFO - (blobuploader) - INFO - Iteration through files over. 08:33:12 INFO - Return code: 0 08:33:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:33:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:33:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb74bb2227590cd9e4e12f789b5862263aac5b8e7395515e861d8974a89f00b181e69d8c18b4d0b233f616c4772e04615f7176d1b939daa8d28337ac1ede126c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4486d5055f6accde04d064e3d0edc71cdf1786d0104a61f9e09605f094c88967d1b9f699c9a43c7c91a8590f3d0eb1573086e029078ef4210f6ac2f3638a4302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8420e32531e062c5708d89fb1e7a6ff5fb1e7215536cdf6376b721a5bfcc8ae6a38c448b5013dd11a5c468601b78555309af322b6cc1a86237b387a067b2da4a"} 08:33:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:33:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:33:12 INFO - Contents: 08:33:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb74bb2227590cd9e4e12f789b5862263aac5b8e7395515e861d8974a89f00b181e69d8c18b4d0b233f616c4772e04615f7176d1b939daa8d28337ac1ede126c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4486d5055f6accde04d064e3d0edc71cdf1786d0104a61f9e09605f094c88967d1b9f699c9a43c7c91a8590f3d0eb1573086e029078ef4210f6ac2f3638a4302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8420e32531e062c5708d89fb1e7a6ff5fb1e7215536cdf6376b721a5bfcc8ae6a38c448b5013dd11a5c468601b78555309af322b6cc1a86237b387a067b2da4a"} 08:33:12 INFO - Running post-run listener: copy_logs_to_upload_dir 08:33:12 INFO - Copying logs to upload dir... 08:33:12 INFO - mkdir: /builds/slave/test/build/upload/logs 08:33:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.331423 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-01 08:33:12.158319) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 08:33:12.162166) ========= 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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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/eb74bb2227590cd9e4e12f789b5862263aac5b8e7395515e861d8974a89f00b181e69d8c18b4d0b233f616c4772e04615f7176d1b939daa8d28337ac1ede126c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4486d5055f6accde04d064e3d0edc71cdf1786d0104a61f9e09605f094c88967d1b9f699c9a43c7c91a8590f3d0eb1573086e029078ef4210f6ac2f3638a4302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8420e32531e062c5708d89fb1e7a6ff5fb1e7215536cdf6376b721a5bfcc8ae6a38c448b5013dd11a5c468601b78555309af322b6cc1a86237b387a067b2da4a"} build_url:https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010820 build_url: 'https://queue.taskcluster.net/v1/task/WtSDVYs9SQ-4ixeHTKi4aw/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/eb74bb2227590cd9e4e12f789b5862263aac5b8e7395515e861d8974a89f00b181e69d8c18b4d0b233f616c4772e04615f7176d1b939daa8d28337ac1ede126c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4486d5055f6accde04d064e3d0edc71cdf1786d0104a61f9e09605f094c88967d1b9f699c9a43c7c91a8590f3d0eb1573086e029078ef4210f6ac2f3638a4302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8420e32531e062c5708d89fb1e7a6ff5fb1e7215536cdf6376b721a5bfcc8ae6a38c448b5013dd11a5c468601b78555309af322b6cc1a86237b387a067b2da4a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 08:33:12.195613) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:33:12.195954) ========= 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.R6mvJDDhsd/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.FM01UQJ6zU/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.005184 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:33:12.244668) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 08:33:12.244992) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 08:33:12.245337) ========= ========= Total master_lag: 0.46 =========