builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0042 starttime: 1464099908.98 results: success (0) buildid: 20160524053206 builduid: 6d05eaf58fb94ff79e582b62e10f8f6b revision: 9caf438f4b916b1a88bf70cf46736502237f0dc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:08.983415) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:08.984172) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:08.984455) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:09.010364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:09.010720) ========= 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.BNPTfTCTEm/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.0yEDB24krq/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 07:25:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2016-05-24 07:25:09 (15.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:09.172054) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:09.172394) ========= 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.BNPTfTCTEm/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.0yEDB24krq/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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.027484 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:09.220736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:25:09.221123) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9caf438f4b916b1a88bf70cf46736502237f0dc7 --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 9caf438f4b916b1a88bf70cf46736502237f0dc7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BNPTfTCTEm/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.0yEDB24krq/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 07:25:09,295 truncating revision to first 12 chars 2016-05-24 07:25:09,295 Setting DEBUG logging. 2016-05-24 07:25:09,295 attempt 1/10 2016-05-24 07:25:09,295 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9caf438f4b91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 07:25:10,370 unpacking tar archive at: fx-team-9caf438f4b91/testing/mozharness/ program finished with exit code 0 elapsedTime=1.323721 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:25:10.563767) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:10.564113) ========= script_repo_revision: 9caf438f4b916b1a88bf70cf46736502237f0dc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:10.564590) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:10.564873) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:25:10.580299) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-24 07:25:10.580597) ========= /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.BNPTfTCTEm/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.0yEDB24krq/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:25:10 INFO - MultiFileLogger online at 20160524 07:25:10 in /builds/slave/test 07:25:10 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 07:25:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:25:10 INFO - {'append_to_log': False, 07:25:10 INFO - 'base_work_dir': '/builds/slave/test', 07:25:10 INFO - 'blob_upload_branch': 'Fx-Team', 07:25:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:25:10 INFO - 'branch': 'Fx-Team', 07:25:10 INFO - 'buildbot_json_path': 'buildprops.json', 07:25:10 INFO - 'config_files': ('talos/mac_config.py',), 07:25:10 INFO - 'default_actions': ('clobber', 07:25:10 INFO - 'read-buildbot-config', 07:25:10 INFO - 'download-and-extract', 07:25:10 INFO - 'populate-webroot', 07:25:10 INFO - 'create-virtualenv', 07:25:10 INFO - 'install', 07:25:10 INFO - 'run-tests'), 07:25:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:25:10 INFO - 'download_minidump_stackwalk': True, 07:25:10 INFO - 'download_symbols': 'ondemand', 07:25:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:25:10 INFO - 'tooltool.py': '/tools/tooltool.py', 07:25:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:25:10 INFO - '/tools/misc-python/virtualenv.py')}, 07:25:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:25:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:25:10 INFO - 'installer_path': 'installer.exe', 07:25:10 INFO - 'log_level': 'info', 07:25:10 INFO - 'log_name': 'talos', 07:25:10 INFO - 'log_to_console': True, 07:25:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:25:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:25:10 INFO - 'opt_config_files': (), 07:25:10 INFO - 'pip_index': False, 07:25:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:25:10 INFO - 'cmd': ('bash', 07:25:10 INFO - '-c', 07:25:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:25:10 INFO - 'enabled': True, 07:25:10 INFO - 'halt_on_failure': False, 07:25:10 INFO - 'name': 'check_screen_resolution'},), 07:25:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:25:10 INFO - 'cmd': ('bash', 07:25:10 INFO - '-c', 07:25:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:25:10 INFO - 'enabled': True, 07:25:10 INFO - 'halt_on_failure': False, 07:25:10 INFO - 'name': 'check_screen_resolution'},), 07:25:10 INFO - 'run_cmd_checks_enabled': True, 07:25:10 INFO - 'sps_profile': False, 07:25:10 INFO - 'sps_profile_interval': 0, 07:25:10 INFO - 'suite': 'dromaeojs', 07:25:10 INFO - 'system_bits': '32', 07:25:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:25:10 INFO - 'title': 't-yosemite-r7-0042', 07:25:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:25:10 INFO - 'use_talos_json': True, 07:25:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:25:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:25:10 INFO - 'work_dir': 'build'} 07:25:10 INFO - ##### 07:25:10 INFO - ##### Running clobber step. 07:25:10 INFO - ##### 07:25:10 INFO - Running pre-action listener: _resource_record_pre_action 07:25:10 INFO - Running main action method: clobber 07:25:10 INFO - rmtree: /builds/slave/test/build 07:25:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:25:12 INFO - Running post-action listener: _resource_record_post_action 07:25:12 INFO - ##### 07:25:12 INFO - ##### Finished clobber step (success) 07:25:12 INFO - ##### 07:25:12 INFO - ##### 07:25:12 INFO - ##### Running read-buildbot-config step. 07:25:12 INFO - ##### 07:25:12 INFO - Running pre-action listener: _resource_record_pre_action 07:25:12 INFO - Running main action method: read_buildbot_config 07:25:12 INFO - Using buildbot properties: 07:25:12 INFO - { 07:25:12 INFO - "project": "", 07:25:12 INFO - "product": "firefox", 07:25:12 INFO - "script_repo_revision": "production", 07:25:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:25:12 INFO - "repository": "", 07:25:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:25:12 INFO - "buildid": "20160524053206", 07:25:12 INFO - "pgo_build": "False", 07:25:12 INFO - "basedir": "/builds/slave/test", 07:25:12 INFO - "buildnumber": 28, 07:25:12 INFO - "slavename": "t-yosemite-r7-0042", 07:25:12 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:25:12 INFO - "platform": "yosemite_r7", 07:25:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:25:12 INFO - "branch": "fx-team", 07:25:12 INFO - "repo_path": "integration/fx-team", 07:25:12 INFO - "revision": "9caf438f4b916b1a88bf70cf46736502237f0dc7", 07:25:12 INFO - "stage_platform": "macosx64", 07:25:12 INFO - "builduid": "6d05eaf58fb94ff79e582b62e10f8f6b", 07:25:12 INFO - "slavebuilddir": "test" 07:25:12 INFO - } 07:25:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:25:12 INFO - Running post-action listener: _resource_record_post_action 07:25:12 INFO - ##### 07:25:12 INFO - ##### Finished read-buildbot-config step (success) 07:25:12 INFO - ##### 07:25:12 INFO - ##### 07:25:12 INFO - ##### Running download-and-extract step. 07:25:12 INFO - ##### 07:25:12 INFO - Running pre-action listener: _resource_record_pre_action 07:25:12 INFO - Running main action method: download_and_extract 07:25:12 INFO - mkdir: /builds/slave/test/build/tests 07:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:12 INFO - https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:25:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:25:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:25:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:15 INFO - Downloaded 1199 bytes. 07:25:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:25:15 INFO - Using the following test package requirements: 07:25:15 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:25:15 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:25:15 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:25:15 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'jsshell-mac.zip'], 07:25:15 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:25:15 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:25:15 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:25:15 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:25:15 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:25:15 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:25:15 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:25:15 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:15 INFO - https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:25:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:25:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:25:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:17 INFO - Downloaded 18630559 bytes. 07:25:17 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'] 07:25:17 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 07:25:20 INFO - Return code: 0 07:25:20 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 07:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:20 INFO - https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:25:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:25:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:25:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:28 INFO - Downloaded 18630559 bytes. 07:25:28 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'] 07:25:28 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 07:25:32 INFO - Return code: 0 07:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:32 INFO - https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:25:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:25:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:25:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:33 INFO - Downloaded 10840359 bytes. 07:25:33 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'] 07:25:33 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 07:25:34 INFO - Return code: 0 07:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:34 INFO - https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:25:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:25:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:25:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:25:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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 07:25:39 INFO - Downloaded 110143599 bytes. 07:25:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:25:39 INFO - mkdir: /builds/slave/test/properties 07:25:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:25:39 INFO - Writing to file /builds/slave/test/properties/build_url 07:25:39 INFO - Contents: 07:25:39 INFO - build_url:https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:25:40 INFO - Running post-action listener: _resource_record_post_action 07:25:40 INFO - Running post-action listener: set_extra_try_arguments 07:25:40 INFO - ##### 07:25:40 INFO - ##### Finished download-and-extract step (success) 07:25:40 INFO - ##### 07:25:40 INFO - ##### 07:25:40 INFO - ##### Running populate-webroot step. 07:25:40 INFO - ##### 07:25:40 INFO - Running pre-action listener: _resource_record_pre_action 07:25:40 INFO - Running main action method: populate_webroot 07:25:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:25:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:25:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:25:40 INFO - '--tppagecycles', 07:25:40 INFO - '7'], 07:25:40 INFO - 'tests': ['tsvgm']}}, 07:25:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:25:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:25:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:25:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:25:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['damp', 'tps']}, 07:25:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'tests': ['damp', 'tps']}, 07:25:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['dromaeo_dom']}, 07:25:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:25:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['a11yr', 07:25:40 INFO - 'ts_paint', 07:25:40 INFO - 'tpaint', 07:25:40 INFO - 'sessionrestore', 07:25:40 INFO - 'sessionrestore_no_auto_restore', 07:25:40 INFO - 'tabpaint']}, 07:25:40 INFO - 'other-e10s': {'tests': ['a11yr', 07:25:40 INFO - 'ts_paint', 07:25:40 INFO - 'tpaint', 07:25:40 INFO - 'sessionrestore', 07:25:40 INFO - 'sessionrestore_no_auto_restore', 07:25:40 INFO - 'tabpaint']}, 07:25:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:25:40 INFO - 'ts_paint', 07:25:40 INFO - 'tpaint', 07:25:40 INFO - 'sessionrestore', 07:25:40 INFO - 'sessionrestore_no_auto_restore', 07:25:40 INFO - 'tabpaint']}, 07:25:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:25:40 INFO - 'ts_paint', 07:25:40 INFO - 'tpaint', 07:25:40 INFO - 'sessionrestore', 07:25:40 INFO - 'sessionrestore_no_auto_restore', 07:25:40 INFO - 'tabpaint']}, 07:25:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['a11yr', 07:25:40 INFO - 'ts_paint', 07:25:40 INFO - 'tpaint', 07:25:40 INFO - 'sessionrestore', 07:25:40 INFO - 'sessionrestore_no_auto_restore', 07:25:40 INFO - 'tabpaint']}, 07:25:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['a11yr', 07:25:40 INFO - 'ts_paint', 07:25:40 INFO - 'tpaint', 07:25:40 INFO - 'sessionrestore', 07:25:40 INFO - 'sessionrestore_no_auto_restore', 07:25:40 INFO - 'tabpaint']}, 07:25:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['tsvgx', 07:25:40 INFO - 'tsvgr_opacity', 07:25:40 INFO - 'tart', 07:25:40 INFO - 'tscrollx', 07:25:40 INFO - 'cart']}, 07:25:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:25:40 INFO - 'tsvgr_opacity', 07:25:40 INFO - 'tart', 07:25:40 INFO - 'tscrollx', 07:25:40 INFO - 'cart']}, 07:25:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'talos_options': ['--disable-e10s'], 07:25:40 INFO - 'tests': ['tp5o']}, 07:25:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'tests': ['tp5o']}, 07:25:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'talos_options': ['--disable-e10s', 07:25:40 INFO - '--xperf_path', 07:25:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:40 INFO - 'tests': ['tp5n']}, 07:25:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:40 INFO - 'talos_options': ['--xperf_path', 07:25:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:40 INFO - 'tests': ['tp5n']}}, 07:25:40 INFO - 'talos.zip': {'path': '', 07:25:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:25:40 INFO - Running post-action listener: _resource_record_post_action 07:25:40 INFO - ##### 07:25:40 INFO - ##### Finished populate-webroot step (success) 07:25:40 INFO - ##### 07:25:40 INFO - ##### 07:25:40 INFO - ##### Running create-virtualenv step. 07:25:40 INFO - ##### 07:25:40 INFO - Running pre-action listener: _resource_record_pre_action 07:25:40 INFO - Running main action method: create_virtualenv 07:25:40 INFO - Creating virtualenv /builds/slave/test/build/venv 07:25:40 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 07:25:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:25:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:25:40 INFO - Using real prefix '/tools/python27' 07:25:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:25:41 INFO - Installing distribute.............................................................................................................................................................................................done. 07:25:44 INFO - Installing pip.................done. 07:25:44 INFO - Return code: 0 07:25:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 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 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:25:44 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 07:25:44 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 07:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:44 INFO - 'HOME': '/Users/cltbld', 07:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:44 INFO - 'LOGNAME': 'cltbld', 07:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:44 INFO - 'MOZ_NO_REMOTE': '1', 07:25:44 INFO - 'NO_EM_RESTART': '1', 07:25:44 INFO - 'PAGER': '/bin/cat', 07:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:44 INFO - 'PWD': '/builds/slave/test', 07:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:44 INFO - 'SHELL': '/bin/bash', 07:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:25:44 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:44 INFO - 'USER': 'cltbld', 07:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:44 INFO - 'XPC_FLAGS': '0x0', 07:25:44 INFO - 'XPC_SERVICE_NAME': '0', 07:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:44 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 07:25:44 INFO - Cleaning up... 07:25:44 INFO - Return code: 0 07:25:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 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 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:25:44 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 07:25:44 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 07:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:44 INFO - 'HOME': '/Users/cltbld', 07:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:44 INFO - 'LOGNAME': 'cltbld', 07:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:44 INFO - 'MOZ_NO_REMOTE': '1', 07:25:44 INFO - 'NO_EM_RESTART': '1', 07:25:44 INFO - 'PAGER': '/bin/cat', 07:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:44 INFO - 'PWD': '/builds/slave/test', 07:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:44 INFO - 'SHELL': '/bin/bash', 07:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:25:44 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:44 INFO - 'USER': 'cltbld', 07:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:44 INFO - 'XPC_FLAGS': '0x0', 07:25:44 INFO - 'XPC_SERVICE_NAME': '0', 07:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:44 INFO - Downloading/unpacking psutil>=3.1.1 07:25:44 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 07:25:44 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 07:25:44 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 07:25:44 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 07:25:44 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 07:25:44 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 07:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:25:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:47 INFO - Installing collected packages: psutil 07:25:47 INFO - Running setup.py install for psutil 07:25:47 INFO - building 'psutil._psutil_osx' extension 07:25:47 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 07:25:48 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 07:25:48 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 07:25:48 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 07:25:48 INFO - building 'psutil._psutil_posix' extension 07:25:48 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 07:25:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:25:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:25:48 INFO - ^ 07:25:48 INFO - 1 warning generated. 07:25:48 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 07:25:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:49 INFO - Successfully installed psutil 07:25:49 INFO - Cleaning up... 07:25:49 INFO - Return code: 0 07:25:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:25: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')]} 07:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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')]} 07:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:25: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:25: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 mozsystemmonitor==0.3 07:25:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:25:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:49 INFO - 'HOME': '/Users/cltbld', 07:25:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:49 INFO - 'LOGNAME': 'cltbld', 07:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:49 INFO - 'MOZ_NO_REMOTE': '1', 07:25:49 INFO - 'NO_EM_RESTART': '1', 07:25:49 INFO - 'PAGER': '/bin/cat', 07:25:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:49 INFO - 'PWD': '/builds/slave/test', 07:25:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:49 INFO - 'SHELL': '/bin/bash', 07:25:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:25:49 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:25:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:49 INFO - 'USER': 'cltbld', 07:25:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:49 INFO - 'XPC_FLAGS': '0x0', 07:25:49 INFO - 'XPC_SERVICE_NAME': '0', 07:25:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:25:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:25:52 INFO - Installing collected packages: mozsystemmonitor 07:25:52 INFO - Running setup.py install for mozsystemmonitor 07:25:52 INFO - Successfully installed mozsystemmonitor 07:25:52 INFO - Cleaning up... 07:25:52 INFO - Return code: 0 07:25:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:25:52 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')]} 07:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:52 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')]} 07:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:52 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 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:25:52 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 07:25:52 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 07:25:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:25:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:52 INFO - 'HOME': '/Users/cltbld', 07:25:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:52 INFO - 'LOGNAME': 'cltbld', 07:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:52 INFO - 'MOZ_NO_REMOTE': '1', 07:25:52 INFO - 'NO_EM_RESTART': '1', 07:25:52 INFO - 'PAGER': '/bin/cat', 07:25:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:52 INFO - 'PWD': '/builds/slave/test', 07:25:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:52 INFO - 'SHELL': '/bin/bash', 07:25:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:25:52 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:25:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:52 INFO - 'USER': 'cltbld', 07:25:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:52 INFO - 'XPC_FLAGS': '0x0', 07:25:52 INFO - 'XPC_SERVICE_NAME': '0', 07:25:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:52 INFO - Downloading/unpacking blobuploader==1.2.4 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:55 INFO - Downloading blobuploader-1.2.4.tar.gz 07:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:25:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:25:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:56 INFO - Downloading docopt-0.6.1.tar.gz 07:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:25:56 INFO - Installing collected packages: blobuploader, requests, docopt 07:25:56 INFO - Running setup.py install for blobuploader 07:25:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:25:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:25:56 INFO - Running setup.py install for requests 07:25:57 INFO - Running setup.py install for docopt 07:25:57 INFO - Successfully installed blobuploader requests docopt 07:25:57 INFO - Cleaning up... 07:25:57 INFO - Return code: 0 07:25:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:57 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')]} 07:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:57 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')]} 07:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:57 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 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:25:57 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 07:25:57 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 07:25:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:25:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:57 INFO - 'HOME': '/Users/cltbld', 07:25:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:57 INFO - 'LOGNAME': 'cltbld', 07:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:57 INFO - 'MOZ_NO_REMOTE': '1', 07:25:57 INFO - 'NO_EM_RESTART': '1', 07:25:57 INFO - 'PAGER': '/bin/cat', 07:25:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:57 INFO - 'PWD': '/builds/slave/test', 07:25:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:57 INFO - 'SHELL': '/bin/bash', 07:25:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:25:57 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:25:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:57 INFO - 'USER': 'cltbld', 07:25:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:57 INFO - 'XPC_FLAGS': '0x0', 07:25:57 INFO - 'XPC_SERVICE_NAME': '0', 07:25:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:57 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-LHbHim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:57 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-asaHEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:57 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-LibMNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:57 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-nUQ5gI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-B7jlN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-0csQaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-PsKmjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-y7I7jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-wBDHlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-gD571d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-eNSCRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-edjUjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:58 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-5ACDs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:59 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-JcwF2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:59 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-1swC11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:59 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-G9jcuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:59 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-JwsnVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:25:59 INFO - Running setup.py install for manifestparser 07:25:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:25:59 INFO - Running setup.py install for mozcrash 07:25:59 INFO - Running setup.py install for mozdebug 07:25:59 INFO - Running setup.py install for mozdevice 07:26:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Running setup.py install for mozfile 07:26:00 INFO - Running setup.py install for mozhttpd 07:26:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Running setup.py install for mozinfo 07:26:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Running setup.py install for mozInstall 07:26:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Running setup.py install for mozleak 07:26:00 INFO - Running setup.py install for mozlog 07:26:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:26:00 INFO - Running setup.py install for moznetwork 07:26:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:26:01 INFO - Running setup.py install for mozprocess 07:26:01 INFO - Running setup.py install for mozprofile 07:26:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:26:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:26:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:26:01 INFO - Running setup.py install for mozrunner 07:26:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:26:01 INFO - Running setup.py install for mozscreenshot 07:26:01 INFO - Running setup.py install for moztest 07:26:01 INFO - Running setup.py install for mozversion 07:26:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:26:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:26:01 INFO - Cleaning up... 07:26:01 INFO - Return code: 0 07:26:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:01 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 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:26:01 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 07:26:01 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 07:26:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:26:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:26:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:26:01 INFO - 'HOME': '/Users/cltbld', 07:26:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:26:01 INFO - 'LOGNAME': 'cltbld', 07:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:01 INFO - 'MOZ_NO_REMOTE': '1', 07:26:01 INFO - 'NO_EM_RESTART': '1', 07:26:01 INFO - 'PAGER': '/bin/cat', 07:26:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:01 INFO - 'PWD': '/builds/slave/test', 07:26:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:26:01 INFO - 'SHELL': '/bin/bash', 07:26:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:26:01 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:26:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:26:01 INFO - 'USER': 'cltbld', 07:26:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:26:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:01 INFO - 'XPC_FLAGS': '0x0', 07:26:01 INFO - 'XPC_SERVICE_NAME': '0', 07:26:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:26:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-y9jC7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:26:02 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)) 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-J1HP1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:26:02 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)) 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-MgWwN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:26:02 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)) 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-95oZAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:26:02 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)) 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-M6QOOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:26:02 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)) 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-zoQ6Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:26:02 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)) 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-1ktaOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:26:02 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)) 07:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:26:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-nR7g3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-w76pBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-qOeOat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-ZMscMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-dw1G6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-d0JQiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-anYPHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-YPnOXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-oJ09qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:26:03 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)) 07:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:26:03 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-iZwO_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:26:04 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)) 07:26:04 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)) 07:26:04 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)) 07:26:04 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)) 07:26:04 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)) 07:26:04 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)) 07:26:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:26:04 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 07:26:04 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 07:26:04 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 07:26:04 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 07:26:04 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 07:26:04 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 07:26:06 INFO - Downloading blessings-1.6.tar.gz 07:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:26:06 INFO - Installing collected packages: blessings 07:26:06 INFO - Running setup.py install for blessings 07:26:07 INFO - Successfully installed blessings 07:26:07 INFO - Cleaning up... 07:26:07 INFO - Return code: 0 07:26:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:26:07 INFO - Reading from file tmpfile_stdout 07:26:07 INFO - Current package versions: 07:26:07 INFO - blessings == 1.6 07:26:07 INFO - blobuploader == 1.2.4 07:26:07 INFO - docopt == 0.6.1 07:26:07 INFO - manifestparser == 1.1 07:26:07 INFO - mozInstall == 1.12 07:26:07 INFO - mozcrash == 0.17 07:26:07 INFO - mozdebug == 0.1 07:26:07 INFO - mozdevice == 0.48 07:26:07 INFO - mozfile == 1.2 07:26:07 INFO - mozhttpd == 0.7 07:26:07 INFO - mozinfo == 0.9 07:26:07 INFO - mozleak == 0.1 07:26:07 INFO - mozlog == 3.2 07:26:07 INFO - moznetwork == 0.27 07:26:07 INFO - mozprocess == 0.22 07:26:07 INFO - mozprofile == 0.28 07:26:07 INFO - mozrunner == 6.11 07:26:07 INFO - mozscreenshot == 0.1 07:26:07 INFO - mozsystemmonitor == 0.3 07:26:07 INFO - moztest == 0.7 07:26:07 INFO - mozversion == 1.4 07:26:07 INFO - psutil == 3.1.1 07:26:07 INFO - requests == 1.2.3 07:26:07 INFO - wsgiref == 0.1.2 07:26:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:26: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')]} 07:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26: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')]} 07:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:07 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 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:26:07 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 07:26:07 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 07:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:26:07 INFO - 'HOME': '/Users/cltbld', 07:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:26:07 INFO - 'LOGNAME': 'cltbld', 07:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:07 INFO - 'MOZ_NO_REMOTE': '1', 07:26:07 INFO - 'NO_EM_RESTART': '1', 07:26:07 INFO - 'PAGER': '/bin/cat', 07:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:07 INFO - 'PWD': '/builds/slave/test', 07:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:26:07 INFO - 'SHELL': '/bin/bash', 07:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:26:07 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:26:07 INFO - 'USER': 'cltbld', 07:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:07 INFO - 'XPC_FLAGS': '0x0', 07:26:07 INFO - 'XPC_SERVICE_NAME': '0', 07:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 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)) 07:26:08 INFO - Cleaning up... 07:26:08 INFO - Return code: 0 07:26:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:26:08 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')]} 07:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:08 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')]} 07:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:08 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 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:26:08 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 07:26:08 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 07:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:26:08 INFO - 'HOME': '/Users/cltbld', 07:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:26:08 INFO - 'LOGNAME': 'cltbld', 07:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:08 INFO - 'MOZ_NO_REMOTE': '1', 07:26:08 INFO - 'NO_EM_RESTART': '1', 07:26:08 INFO - 'PAGER': '/bin/cat', 07:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:08 INFO - 'PWD': '/builds/slave/test', 07:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:26:08 INFO - 'SHELL': '/bin/bash', 07:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:26:08 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:26:08 INFO - 'USER': 'cltbld', 07:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:08 INFO - 'XPC_FLAGS': '0x0', 07:26:08 INFO - 'XPC_SERVICE_NAME': '0', 07:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:08 INFO - Downloading/unpacking jsonschema 07:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:26:10 INFO - Downloading/unpacking functools32 (from jsonschema) 07:26:10 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 07:26:10 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 07:26:10 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 07:26:10 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 07:26:10 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 07:26:10 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 07:26:11 INFO - Downloading functools32-3.2.3-2.tar.gz 07:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:26:11 INFO - warning: no files found matching '*.txt' 07:26:11 INFO - no previously-included directories found matching 'build' 07:26:11 INFO - no previously-included directories found matching 'dist' 07:26:11 INFO - no previously-included directories found matching '.git*' 07:26:11 INFO - Installing collected packages: jsonschema, functools32 07:26:11 INFO - Running setup.py install for functools32 07:26:11 INFO - warning: no files found matching '*.txt' 07:26:11 INFO - no previously-included directories found matching 'build' 07:26:11 INFO - no previously-included directories found matching 'dist' 07:26:11 INFO - no previously-included directories found matching '.git*' 07:26:11 INFO - Successfully installed jsonschema functools32 07:26:11 INFO - Cleaning up... 07:26:11 INFO - Return code: 0 07:26:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:26: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')]} 07:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26: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')]} 07:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10303da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103043570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0db4b3390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, '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.BNPTfTCTEm/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.0yEDB24krq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 07:26:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 07:26:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 07:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:26:11 INFO - 'HOME': '/Users/cltbld', 07:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:26:11 INFO - 'LOGNAME': 'cltbld', 07:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:11 INFO - 'MOZ_NO_REMOTE': '1', 07:26:11 INFO - 'NO_EM_RESTART': '1', 07:26:11 INFO - 'PAGER': '/bin/cat', 07:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:11 INFO - 'PWD': '/builds/slave/test', 07:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:26:11 INFO - 'SHELL': '/bin/bash', 07:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:26:11 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:26:11 INFO - 'USER': 'cltbld', 07:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:11 INFO - 'XPC_FLAGS': '0x0', 07:26:11 INFO - 'XPC_SERVICE_NAME': '0', 07:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:11 INFO - Downloading/unpacking flake8 07:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:26:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:26:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:26:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:26:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:26:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:26:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:26:15 INFO - no previously-included directories found matching 'docs/_build' 07:26:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:26:15 INFO - Running setup.py install for pep8 07:26:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:26:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:26:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:26:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:26:15 INFO - no previously-included directories found matching 'docs/_build' 07:26:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:26:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:26:15 INFO - Cleaning up... 07:26:16 INFO - Return code: 0 07:26:16 INFO - Running post-action listener: _resource_record_post_action 07:26:16 INFO - Running post-action listener: _start_resource_monitoring 07:26:16 INFO - Starting resource monitoring. 07:26:16 INFO - ##### 07:26:16 INFO - ##### Finished create-virtualenv step (success) 07:26:16 INFO - ##### 07:26:16 INFO - ##### 07:26:16 INFO - ##### Running install step. 07:26:16 INFO - ##### 07:26:16 INFO - Running pre-action listener: _resource_record_pre_action 07:26:16 INFO - Running main action method: install 07:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:26:16 INFO - Reading from file tmpfile_stdout 07:26:16 INFO - Detecting whether we're running mozinstall >=1.0... 07:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:26:16 INFO - Reading from file tmpfile_stdout 07:26:16 INFO - Output received: 07:26:16 INFO - Usage: mozinstall [options] installer 07:26:16 INFO - Options: 07:26:16 INFO - -h, --help show this help message and exit 07:26:16 INFO - -d DEST, --destination=DEST 07:26:16 INFO - Directory to install application into. [default: 07:26:16 INFO - "/builds/slave/test"] 07:26:16 INFO - --app=APP Application being installed. [default: firefox] 07:26:16 INFO - mkdir: /builds/slave/test/build/application 07:26:16 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'] 07:26:16 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 07:26:50 INFO - Reading from file tmpfile_stdout 07:26:50 INFO - Output received: 07:26:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:26:50 INFO - Running post-action listener: _resource_record_post_action 07:26:50 INFO - ##### 07:26:50 INFO - ##### Finished install step (success) 07:26:50 INFO - ##### 07:26:50 INFO - ##### 07:26:50 INFO - ##### Running run-tests step. 07:26:50 INFO - ##### 07:26:50 INFO - Running pre-action listener: _resource_record_pre_action 07:26:50 INFO - Running main action method: run_tests 07:26:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:26:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:26:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:26:50 INFO - 2016-05-24 07:26:50.062 screenresolution[1638:7670] starting screenresolution argv=screenresolution get 07:26:50 INFO - 2016-05-24 07:26:50.065 screenresolution[1638:7670] Display 0: 1600x1200x32@60 07:26:50 INFO - 2016-05-24 07:26:50.070 screenresolution[1639:7672] starting screenresolution argv=screenresolution list 07:26:50 INFO - Available Modes on Display 0 07:26:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:26:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:26:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:26:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:26:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:26:50 INFO - Intel Iris: 07:26:50 INFO - Chipset Model: Intel Iris 07:26:50 INFO - Type: GPU 07:26:50 INFO - Bus: Built-In 07:26:50 INFO - VRAM (Dynamic, Max): 1536 MB 07:26:50 INFO - Vendor: Intel (0x8086) 07:26:50 INFO - Device ID: 0x0a2e 07:26:50 INFO - Revision ID: 0x0009 07:26:50 INFO - Displays: 07:26:50 INFO - Display: 07:26:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:26:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:26:50 INFO - Display Serial Number: 1600x1200 60 07:26:50 INFO - Main Display: Yes 07:26:50 INFO - Mirror: Off 07:26:50 INFO - Online: Yes 07:26:50 INFO - Rotation: Supported 07:26:50 INFO - Return code: 0 07:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:26:50 INFO - Python 2.7.3 07:26:50 INFO - Return code: 0 07:26:50 INFO - grabbing minidump binary from tooltool 07:26:50 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')]} 07:26:50 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 0x102ee1d30>, '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 0x102d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:26:50 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 07:26:50 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 07:26:50 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 07:26:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:26:50 INFO - Return code: 0 07:26:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:26:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:26:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:26:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:26:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:26:51 INFO - Return code: 0 07:26:51 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/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--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 07:26:51 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/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0042 --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 07:26:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNPTfTCTEm/Render', 07:26:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:26:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:26:51 INFO - 'HOME': '/Users/cltbld', 07:26:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:26:51 INFO - 'LOGNAME': 'cltbld', 07:26:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:26:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:51 INFO - 'MOZ_NO_REMOTE': '1', 07:26:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:26:51 INFO - 'NO_EM_RESTART': '1', 07:26:51 INFO - 'PAGER': '/bin/cat', 07:26:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:51 INFO - 'PWD': '/builds/slave/test', 07:26:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:26:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:26:51 INFO - 'SHELL': '/bin/bash', 07:26:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0yEDB24krq/Listeners', 07:26:51 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:26:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:26:51 INFO - 'USER': 'cltbld', 07:26:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:26:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:51 INFO - 'XPC_FLAGS': '0x0', 07:26:51 INFO - 'XPC_SERVICE_NAME': '0', 07:26:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:26:51 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/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--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 07:26:51 INFO - mozversion application_buildid: 20160524053206 07:26:51 INFO - mozversion application_changeset: 9caf438f4b916b1a88bf70cf46736502237f0dc7 07:26:51 INFO - mozversion application_display_name: Nightly 07:26:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:26:51 INFO - mozversion application_name: Firefox 07:26:51 INFO - mozversion application_remotingname: firefox 07:26:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:26:51 INFO - mozversion application_vendor: Mozilla 07:26:51 INFO - mozversion application_version: 49.0a1 07:26:51 INFO - mozversion platform_buildid: 20160524053206 07:26:51 INFO - mozversion platform_changeset: 9caf438f4b916b1a88bf70cf46736502237f0dc7 07:26:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:26:51 INFO - mozversion platform_version: 49.0a1 07:26:51 INFO - using testdate: 1464100011 07:26:51 INFO - actual date: 1464100011 07:26:51 INFO - starting webserver on 'localhost:49278' 07:26:51 INFO - SUITE-START | Running 2 tests 07:26:51 INFO - TEST-START | dromaeo_css 07:26:51 INFO - operating with platform_type : mac_ 07:26:51 INFO - Initialising browser for dromaeo_css test... 07:26:51 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpj6SC_W/profile http://localhost:49278/getInfo.html) 07:26:54 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 07:26:54 INFO - PROCESS | 1657 | colorDepth:24 07:26:54 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 07:26:54 INFO - PROCESS | 1657 | __metrics 07:26:54 INFO - PROCESS | 1657 | 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" 07:26:54 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:26:54 INFO - TEST-INFO | 1657: exit 0 07:26:54 INFO - Browser initialized. 07:26:54 INFO - Running cycle 1/1 for dromaeo_css test... 07:26:54 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpj6SC_W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:27:06 INFO - PROCESS | 1659 | RSS: Main: 238866432 07:27:06 INFO - PROCESS | 1659 | 07:27:58 INFO - PROCESS | 1659 | 1464100078360 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] 07:30:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 07:30:09 INFO - PROCESS | 1659 | RSS: Main: 348749824 07:30:09 INFO - PROCESS | 1659 | 07:33:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 07:33:13 INFO - PROCESS | 1659 | RSS: Main: 367226880 07:33:13 INFO - PROCESS | 1659 | 07:36:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 07:36:16 INFO - PROCESS | 1659 | RSS: Main: 368766976 07:36:16 INFO - PROCESS | 1659 | 07:39:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 07:39:19 INFO - PROCESS | 1659 | RSS: Main: 367345664 07:39:19 INFO - PROCESS | 1659 | 07:42:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 07:42:23 INFO - PROCESS | 1659 | RSS: Main: 333045760 07:42:23 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 07:45:26 INFO - PROCESS | 1659 | RSS: Main: 374898688 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | __start_tp_report 07:45:26 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 07:45:26 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 07:45:26 INFO - PROCESS | 1659 | |i|pagename|runs| 07:45:26 INFO - PROCESS | 1659 | |0;dojo.html;3250.821204833734;3467;3488.0930957950927;3501.684848363647;3537.062678390227;5545.057340252157;5549.112142057272;5580.135079062741;5668.971655141724;5712.0289550776315;5565.860853478665;5617.494425501704;5680.801171958982;5683.460071293231;5752.050911599581;4271.380649805777;5062.367204099488;5110.897782044357;5195.610329225323;5268.130758424855;5283.973580132113;5289.497497737709;5308.097623404011;5326.3075800145925;5359.330083739533;5100.974495127501;5137.588992880561;5146;5201.401838788531;5214.843554693365;5073.6955782652785;5153.974230128825;5179.249008893689;5190.32525771651;5199.818006369759;4430.911381772379;4690.437147542282;5105.106606343912;5190.140528891465;5239.947600523984;5545.140503222007;5588.776448942038;5590.692511911843;5639.548836093103;5658.547316214693;5980.162777211194;6045.653287703272;6119.959606866843;6152.969235153841;6158.168647232611;6232.376762323776;6271.498280137578;6283.528735344822;6307.842303942392;6326.145970293997;5786.276715410574;5831.037878750001;5841;5847.970760146172;5860.531157507389;6190.969045154746;6255.40573645503;6302.716377763011;6323.715432805535;6327.303996560375;6061.900952513432;6086.391360863967;6123.938760612336;6137.0794380842335;6147.293061297919;6098.14625952367;6265.561410701207;6271.247450305992;6274.937250627435;6307.747690092437;6101.328853826076;6112.969435152885;6144.4945988232275;6145.770845830852;6236.719347629368;6180.81457556274;6226.968865155736;6246;6248.562600617913;6260.092286618415;4815.446223684251;4947.802087916515;4954.083494553519;4968;4987.226979818134;4373.584509471595;4382.583654552813;4395.824167033283;4409.5149533551285;4448.621867141264;5668.206451096849;5706.058500347479;5723.027085395493;5751.712414379264;5758.107493338539;6118.296395914438;6119.593952096689;6274.843128921722;6288.151099601587;6315.305316415191;5600;5640.351359593617;5670.914936275959;5749.195112684144;5752.798652047159;5373.194020896896;5381.623286369917;5385.138377859602;5391.3799913009725;5411.918821217685;6291.496680265568;6297.622142671455;6304.495640348853;6311.558190926591;6313.905291420632;5976.282846058501;6107.006761825686;6118.296395914349;6149.323574406722;6203.2556093269095;6278.591891527036;6305.653189074645;6308.27454842699;6320.178376810985;6402.90395644075;6059.515238780975;6186.288576813724;6202.069689546604;6204.7828326008375;6216.937830621636;4777.808887644455;6117.492485019787;6130.18412495751;6150.231221097363;6298.370162983665;6085.300190478151;6087.117367981707;6094.512439004958;6099.969500152471;6100.542459315658;5902.822915312548;6039.094135879654;6070.878582428415;6107.083937409247;6110.419510146529;4979.850604481871;5857.824265272048;5875.089361149029;5927.4665280125;5958.702064896825;6065.769805680119;6068.2969554008905;6089.878202435838;6099.268087829489;6101.267847858286;5697.287839020122;5824.21373114641;5932.406759324033;5943.167956486009;5969.104634304889 07:45:26 INFO - PROCESS | 1659 | |1;ext.html;493.5557997801978;510.0385772818237;515.9561437277831;530.8938212357529;547.1054825360534;25138.748612513893;31154.2211444714;41945.580544194585;42893;43009.78495107528;44995.325070123894;45246.773766131126;45274.09451810962;45394.09211815762;45602;8920.375573709844;9427.858582121273;9613.279004074704;9855.605775768961;9889.159421449149;8591.957040214791;8839.24866386358;11599.536018559247;11845.70385740359;11867.643970680894;11943.34311612861;11976.820347694791;12004.519819207222;12031.759364812699;12119.636410907688;8666.913330866673;11684.357360345135;11736.29582225065;11812.173147879652;11822.467988940518;11654.592089276835;12055.517779288817;12056.19157126438;12058.879411205864;12063.974562162139;41244.38133428001;41540.376894346606;41855.74432767022;41863.58136418627;41883;3203.423383790917;3325.8503367348526;3364.814935178564;3466.532018177539;3549.4512624208296;29159.854200729074;29267.560986585217;29735.40529189404;29890.551641725393;30225;2710;2720.564709646447;2729.727027297274;2756.641636587231;2821.604975303459;351932;352481;352763;536046;538096;47034.5296547037;62315;62565.37434625596;62787;62909;35204.823975879954;36102.458463123075;36132.81933590315;65202;65311.67344163249;16707.832921670626;16798.664026719398;16968.830311696973;16980;16993.40523081686;11626.88373116258;11912.821307680391;12666.430010649543;12818.230906145662;12915.935420322838;19468.51645730659;19776.330195423434;20244;20245.291414800413;20564.38306850797;7652.403370415896;7659.348955338859;7690.384769218449;7736.226377362219;7777.688892444352;873.908239634842;950.7005293332717;963.3978763272954;1071.603506702525;1126.6000569797604;30252.092437226478;30324;30339.62038021904;30487.69512304893;30491;843.3295530053622;1040.4173662748885;1063.4576366053368;1114.5820317380983;1116.2298014370222;15300.234988250542;15331.386744530093;15404.306806193748;15717.921410392875;15808.288627011812;687902;944492;958783;958941;959224;60499.395006050254;60652;60998.08502872461;61203;61221.38778612246;1205.5780476833213;1228.91397602167;1238.9159485450232;1440.7262620102147;1458.8190654970674;29256.26859328534;30196;30722.231944201572;30846.53730194004;31600;59769;60307.698461509164;60636.78726425535;60673;60724;603888;606357;609503;609643;610136;31090.533641994934;36070;42602.78698606487;43626.78186609047;43997.78001109974;47424.52575474201;47550.76224618855;47587.52412475831;47653.28520072132;49121.263181051596;38676.80661596674;38680.41979370368;38953.41569876397;39564.2087158261;40164.7991760051;37047;37957.810210948766;38224.808875955445;38674.6132538671;38971.44114235512;37797;38297.425538616386;38996.220075598896;39034.80482597569;39188.80405597954;29047.7095229045;31419.84290078535;34075.31849363048;34171.97484075482;34225.65774342225;22556.887215564475;23444.06223751031;24046;24396;24966.37584060413 07:45:26 INFO - PROCESS | 1659 | |2;jquery.html;378.732993239766;379.1412450798941;385.7511904821392;398.51580329899167;407.02720498009745;19656;21152.4711882203;22183.66724499134;22211.44471388214;22281.777182228172;20047.49881252966;20769.376918692462;21147.259845905395;21212.89393553034;21794.564108717816;4591.9540804592025;5186.844394668156;5425.457454254582;5426.131818908975;5434.347878254615;3881.3013657541633;4087.9182416351655;5354.330708661418;5781.884362312751;6153.876922461571;6156.076588511737;6169.167162433081;6169.537284703665;6170;6179.412955769195;4958;6067.726952287114;6076.088586712029;6078.969605151968;6083.8174854754425;3753.7560058596378;5566.081596536566;5798.3911689272445;5910.054391297372;5918.348981612063;13648.863511364858;13745.518906838313;13753;22418.32745017652;25777;2014.5165160361407;2084.8957552122333;2118.700941037278;2209.5249521352825;2295.104909085458;9888.110070093726;9899.15857152143;10018.398896066186;10229.59081636741;10263.89736102637;1706.3004168291066;1768.9469315920544;1784.1436110666823;1803.0443864751703;1807.8824876382994;129709.35145324212;132635;133773.6622633771;133935;135708;11357.488912998935;11528.884711152949;11731.23746956445;11754.471048802825;11937;15039.47361842352;15098.849011509965;15117;15149.848501515064;15151.318190681446;7365.668544915492;8731.345149113842;8836.425632333878;8844.115588441104;8871.556422178865;7120.359167674934;8750.03749587545;8761.56192190388;8875.511846848356;8899.688510902217;4544.59136657572;4614.700044497097;4625.144348295509;4630.560096790799;4646.395968524138;1749.422690512128;1804.9587424018655;1812.7734033245845;1876.6434377468238;1912.235105957628;760.8136006678288;882.618667037311;946.2477330522252;949.2975198353307;971.4074414607084;19666.60666786685;20367.694484582742;20680.896595516926;20714.896425517774;20738.377848664564;938.7559234599054;943.4622265308845;948.4593781544453;974.1524873360222;1079.4494807648;10972.890271097347;11123.16576256784;11247.144221203409;11274;11394.430278486043;585736;586643;615465;619622;626105;129349.35325323313;129401.35299323632;130044;130277;134524;1008.6570566007446;1011.246621267146;1055.5144633468537;1084.5011294804322;1087.521490544174;17483.388081417088;18420.723689144404;18648.44054678362;18707.438776836716;18878.96165710899;38918.027049323995;49564.752176238886;49828.25257621211;51337.22994155165;52288.69278268074;75272;75503.62248188724;76636.85044724222;77563;78041.82937255832;8120.83758324842;8162.469439486415;8234.505929644001;8439.49363038195;8565.357598180286;9848.409095454295;11922.4038798058;12536.686582835137;12686.429110690226;12972.22166669965;6280.717367718372;10595.947020265157;10604.787904241717;11117.555297787998;11297.661070167907;10499.530065790643;10793.29843560166;10816.513256903301;10983;11253.15601329887;10876;10900.727481813017;10906.34561926287;10995.945020274847;11467.827982580435;6949.687264073028;8139.633716482654;8147.226013528705;8307.252347288773;8557.058723540531;9458.905410946078;10828.891711082788;11115.055220306042;11131.05386042163;11170.832437513607 07:45:26 INFO - PROCESS | 1659 | |3;mootools.html;1372.553919976007;1521.3762357433454;1559.750439929611;1768.4252617899185;1921.8366438852697;7678.96160519198;8493.150684931512;8600.827983440327;8711.041785403608;8817.779555511099;7049.7885063448175;8516.31869450444;8552.572371381422;8621.267192288664;8646.827063458728;2080.980319643379;2165.577712346093;2297.827662925279;2337.684412604298;2378.6075297575967;1971.3100414854757;2376.227725989052;2398.1486572266854;2403.8918248678765;2404.0984630763464;2334.043042352636;2349.2247558305803;2376.6316220985773;2382.4282172278704;2388.34320561846;2024.4635171679442;2265.6148454762565;2318.40880575453;2351.094828491026;2352.5294941011866;2353.8116950643907;2354.611489104313;2371.5256948610177;2375.4417711837705;2377.1442280778906;8403.789905252357;8478.491290522588;8482.491050536926;8489.66041358345;8513.148685131498;5164.354455693038;5344.331958505187;5347;5347.51872331492;5353.57171426285;3169.1443310306317;3250;3302.075418882717;3312.2381852173894;3332.1169889979174;993.746594618375;993.8857031441404;1009.6769033909152;1018.9032041956002;1029.5161274201114;11322.037626801732;11440.084793216605;11479.368634725086;11488.02351800098;11524.654260372203;4445.1998640245065;4524.524924882906;4606.516315786861;4623.098495793355;4630.745309008037;8971.955140224387;8986.595603197871;9105.317101217435;9134.406263592846;9154.908450915538;4920.975395123001;5159.329287192641;5233.555147812479;5254.264402983586;5255.710935898486;4367.366731823931;4523.932141017887;4761.666683332202;4884.731339776346;4887.04702583;3687.170386663033;3751.1559899023046;3788.3749181384765;3821.522309711286;3849.191669749328;1141.560499207811;1145.7536629624672;1149.8045332293364;1153.5731779241567;1159.837622732818;753.5892938348613;866.2290561400358;900.3883049341605;903.290916630442;952.1002652493465;5497.697626630494;5689.77240910359;5718.51392631631;5873.706314684248;6084.056971169476;836.1387770596295;929.4330458420358;931.3015238571072;943.8631398447187;983.8278301297245;3571.4107172316794;3861.054041759731;3866.2460820139804;3906.628870257378;3910.8435662573747;10234.6417875374;10475.16198704117;10627.628033018962;10660.946695266473;10671.413072281099;10392.168626509861;10539.894601054044;10592.788144237073;10622.46887655614;10628.14974802014;7851.882221766678;8009.399295052895;8067.677292908335;8176.3867709922;8220.301274391624;5538.6399884007415;5634.323881134126;5655.066913959151;5701.372848986525;5867.471927526543;9457.858132128162;9689.224862011164;9918.305718599917;9920.851187232052;9978.555253962826;9579.856302155335;9956.800863982824;9958.850617240601;9994.350367226107;10135.935726748585;5536.474034966672;6249.718762655601;6290.9056364155485;6492.188476440445;6818.795436136924;8201.671933122609;8309.501429914224;8380.994280686356;8382.455140415854;8395.748127556182;7240.782776516713;7367.926320736939;7380.18817930038;7478.775636730907;7535.660895259617;7121.181064177686;7263.4915555910575;7271.600061996641;7317.634118294171;7323.450741194325;6862.553933994274;6910.861782764216;6933.168019837652;6949.652517374212;6955.930440695731;4127;4674.22875225584;4740.4785473597185;4752.239641657318;4977.4773648767805;4795.400574928134;4854.975725121351;4860.805567777249;4878;4904.166291730343 07:45:26 INFO - PROCESS | 1659 | |4;prototype.html;301.6169464779728;303.87237360308677;315.7994673382402;320.13244108466057;323.13561223726526;5168.457311982244;5324.65389749666;5337.491379051844;5381.5963802714805;5467.343918729753;4286.892827679309;4329.1601069545395;4340.370646256299;4657.464391594967;5240.580753539717;4033.7378070425475;4044.635982761551;4048.7773172475504;4058.5738497457783;4059.5534491205945;3585.515955346034;3762.830672619739;3865.4781604483314;4054.2702313583545;4071.30787766079;3977.065389633434;4006.5392479864895;4012.953393769184;4054.6553542948886;4060;3529.8941031769086;4005.6380830517473;4015.156817068419;4026.4564283821746;4036.1120553478186;3045.6649768525444;3493.7205023598303;3494.930101397958;3498.702610278129;3503.4219353806593;4701.952980470186;5130.3074084998425;5146;5150.686574923408;5154.38147422308;4549.47681016684;4551.817927282905;4565.1098035883015;4588.934979259347;4607.746573938432;5395.7032363220005;5555.416681248492;5610.523105536035;5613.270274864282;5619.662820230758;3267.4118658641437;3534.7348948828685;3834.041489627593;3853.749506282111;3901.707371947087;5170.45710200431;5906.527477801808;5936.951751645675;5941.187937679007;5952.375000624916;3345.6821601947777;3889.86385476507;3910.1231408923877;3924.50943632046;3943.8028098594955;3744.2137151198554;3788.753731007476;3972.980135099364;3977.428915578388;4000.6597789740763;3916.3146449371247;4127.9174416511505;4518.006038671487;4569.543045695404;4606.456836959589;5186.170212765939;5539.002979463655;5637.43625637433;5668.858278543069;5684;1121.61864965912;1266.7269394258894;1309.6706842554815;1320.504810695989;1360.2994457854213;773.1572585881304;791.3313250303485;799.4403917257943;816.6488409983646;823.8970128733908;4085.264652362544;4294.527601963781;4393.692441529126;4417.648616113126;4425.565938733684;4134.586541345841;4722.3707820801765;4859.659823812299;4879.902022045082;4895.947371315185;3663.314875157457;3795.2352156246925;3964.444977703124;4137.076878514558;4385.342198670225;2117.7246957895377;2209.889505524717;2297.3688681036315;2379.58357287474;2417.6615273861676;4487.833163377545;4665.906681866344;4829.8847243527725;4854.660173787801;4899.191633380524;4221.0030878076295;4249.915001699948;4436.0906014267;4932.432770231398;4948.564916174341;4578.6108180805;5004.449510553836;5306.46935306474;6048.788292409832;6102.328743838263;5112.386513618241;5765.798197063251;5823.679697616672;5845.941540584539;5848.912266316093;5187.651210685324;5481.382287690106;5782.2194003808945;5792.5655575832825;5821.621594596338;4928.433230178575;5796.1305804129715;5968.895754285471;6022.187004754477;6024.548158888013;3460.705840003628;3835.6356146166067;3862.7253006508345;3882.9417558737205;3960.7623542587535;4523.796429160761;5521.337439507285;5778.150991682621;5806.519337568893;5817.331006934088;4973.328600638867;5006.774695138654;5367.543758780547;5612.8877422450505;5642.492175704042;5185.299984502195;5197.7661005254095;5502.394736579053;5669.943300566942;5705.4579814917515;4592.908141837212;4673.4625518064495;5352.090144675337;5569.582281328835;5599.780126427239;5241.292425522505;5625.296837895263;5674.524623597812;5684.914726279192;5687.74869528703;4686.437627484587;4739.033301179972;5346.893062138658;5562.527185189304;5602.831915042555 07:45:26 INFO - PROCESS | 1659 | |5;yui.html;642.3897297567311;656.1896058367918;702.9121359830021;711.7366574367481;733.2520828754668;14649.414023439063;15072.246387680614;15323.616909577253;15932.601684957854;16782.07698576578;15485.612859678513;15818.446354377598;15868.206589670499;15896.125713085805;15937.840621593781;712.8111050571603;739.9038125043737;912.9087091290868;923.3905622289291;931.3108299858123;882.4528792148876;923.2060428031887;923.4320892651004;926.1016813690709;929.9349045566813;934.8878134623855;936.059260443257;956.550421301987;965.8985806490323;969.5636963366478;745.8023623739713;921.2491819167357;959.8752162218937;960.8318544254728;961.2406199102666;715.048984850148;715.1846894540229;716.7025684340992;717.4009701898921;718.105958082191;14744.189069601167;14758.631034224123;14768.335424905905;14786.778198327034;14818.925905370405;11956.880431195663;11980;12116.81824772629;12261.26432414058;12269.202501837352;10666.253362264644;10814.29707069038;10930.234883558154;11371.886281137165;11451.083913286917;3978.8806335809973;3981.2435637229123;3982.681385489154;4120.835166593332;4136.4415803866395;75162;75686.99999999945;76646;76910.61544692241;77007;7514.849703006019;9167.449953002708;9262.629494820267;9372.95313523428;9575.425474471554;20272;20399.59200816005;20418.489537761674;20425;20450.590988180153;7186.38915692172;7257.891131632924;7260.165081015645;7298.56208627484;7335.046443962251;3856.093817952779;3890.844366225376;3892.163184915257;3892.9805350973065;3906.0820707133803;3357.7145942594666;3466.116140384186;3467.6185619581825;3467.994281658341;3477.23500829817;610.7709608896664;624.2976651267325;626.0234034905562;648.4779752299349;648.970796314167;473.38460001997265;551.7186235020089;583.8336074218912;587.9059350503899;597.2295738497377;7606.353459955855;8140.552269625109;8151.103378628346;8238.217369349903;8285.792855178668;444.86431638349967;496.7143892261964;503.2878476955141;536.6350881400607;558.1571826541951;3275.623303320101;3736.458213559073;3815.904602384963;3840.6543411093135;3878.8448462061765;68921;74948;75825;76802.61598691971;77506.22493775103;66376;66583.66708166525;68106;68111.63776724438;68202;61671;61714.691426543475;62374;62426.68786656038;62774.37225627685;7439.888401673871;7459;7518.173000969999;7614.695412183451;7678.846423071619;40602.187956240115;40724.796376017934;40849.38725919173;41233;41714.374284386366;52882;54526.454735452135;54594.18108728293;55026.44973550213;55451;9781.021897810162;9948.701538953843;10069.295149339769;10089.243306751876;10231.334963227664;10584.631880978775;10927.235093543693;11133.332000080021;11276.154288428565;11892.524299027942;7741.612919353897;10479.318844275404;10941.56233750641;10949.781004380027;11291.604793832177;11365.829512557153;11457.427128643702;11716;11733.061355091742;11796.351200684045;11068.391238481961;11308.717282068013;11316.924892135234;11576.768464630828;11653.30080195191;9347.579358929;9492.525373731423;9664.661736839176;9677.516124193902;9850.261230407605;8052.235037671412;9494.85757713621;9567.952160239154;9597;9718.319717619699 07:45:26 INFO - PROCESS | 1659 | __end_tp_report 07:45:26 INFO - PROCESS | 1659 | __start_cc_report 07:45:26 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1132 07:45:26 INFO - PROCESS | 1659 | __end_cc_report 07:45:26 INFO - PROCESS | 1659 | __startTimestamp1464101126322__endTimestamp 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | ------- Summary: start ------- 07:45:26 INFO - PROCESS | 1659 | Number of tests: 6 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5699.14 Median:5927.47 stddev:648.08 (10.9%) stddev-sans-first:620.96 07:45:26 INFO - PROCESS | 1659 | Values: 3250.8 3467.0 3488.1 3501.7 3537.1 5545.1 5549.1 5580.1 5669.0 5712.0 5565.9 5617.5 5680.8 5683.5 5752.1 4271.4 5062.4 5110.9 5195.6 5268.1 5284.0 5289.5 5308.1 5326.3 5359.3 5101.0 5137.6 5146.0 5201.4 5214.8 5073.7 5154.0 5179.2 5190.3 5199.8 4430.9 4690.4 5105.1 5190.1 5239.9 5545.1 5588.8 5590.7 5639.5 5658.5 5980.2 6045.7 6120.0 6153.0 6158.2 6232.4 6271.5 6283.5 6307.8 6326.1 5786.3 5831.0 5841.0 5848.0 5860.5 6191.0 6255.4 6302.7 6323.7 6327.3 6061.9 6086.4 6123.9 6137.1 6147.3 6098.1 6265.6 6271.2 6274.9 6307.7 6101.3 6113.0 6144.5 6145.8 6236.7 6180.8 6227.0 6246.0 6248.6 6260.1 4815.4 4947.8 4954.1 4968.0 4987.2 4373.6 4382.6 4395.8 4409.5 4448.6 5668.2 5706.1 5723.0 5751.7 5758.1 6118.3 6119.6 6274.8 6288.2 6315.3 5600.0 5640.4 5670.9 5749.2 5752.8 5373.2 5381.6 5385.1 5391.4 5411.9 6291.5 6297.6 6304.5 6311.6 6313.9 5976.3 6107.0 6118.3 6149.3 6203.3 6278.6 6305.7 6308.3 6320.2 6402.9 6059.5 6186.3 6202.1 6204.8 6216.9 4777.8 6117.5 6130.2 6150.2 6298.4 6085.3 6087.1 6094.5 6100.0 6100.5 5902.8 6039.1 6070.9 6107.1 6110.4 4979.9 5857.8 5875.1 5927.5 5958.7 6065.8 6068.3 6089.9 6099.3 6101.3 5697.3 5824.2 5932.4 5943.2 5969.1 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:77124.79 Median:36086.23 stddev:183334.00 (508.0%) stddev-sans-first:183758.02 07:45:26 INFO - PROCESS | 1659 | Values: 493.6 510.0 516.0 530.9 547.1 25138.7 31154.2 41945.6 42893.0 43009.8 44995.3 45246.8 45274.1 45394.1 45602.0 8920.4 9427.9 9613.3 9855.6 9889.2 8592.0 8839.2 11599.5 11845.7 11867.6 11943.3 11976.8 12004.5 12031.8 12119.6 8666.9 11684.4 11736.3 11812.2 11822.5 11654.6 12055.5 12056.2 12058.9 12064.0 41244.4 41540.4 41855.7 41863.6 41883.0 3203.4 3325.9 3364.8 3466.5 3549.5 29159.9 29267.6 29735.4 29890.6 30225.0 2710.0 2720.6 2729.7 2756.6 2821.6 351932.0 352481.0 352763.0 536046.0 538096.0 47034.5 62315.0 62565.4 62787.0 62909.0 35204.8 36102.5 36132.8 65202.0 65311.7 16707.8 16798.7 16968.8 16980.0 16993.4 11626.9 11912.8 12666.4 12818.2 12915.9 19468.5 19776.3 20244.0 20245.3 20564.4 7652.4 7659.3 7690.4 7736.2 7777.7 873.9 950.7 963.4 1071.6 1126.6 30252.1 30324.0 30339.6 30487.7 30491.0 843.3 1040.4 1063.5 1114.6 1116.2 15300.2 15331.4 15404.3 15717.9 15808.3 687902.0 944492.0 958783.0 958941.0 959224.0 60499.4 60652.0 60998.1 61203.0 61221.4 1205.6 1228.9 1238.9 1440.7 1458.8 29256.3 30196.0 30722.2 30846.5 31600.0 59769.0 60307.7 60636.8 60673.0 60724.0 603888.0 606357.0 609503.0 609643.0 610136.0 31090.5 36070.0 42602.8 43626.8 43997.8 47424.5 47550.8 47587.5 47653.3 49121.3 38676.8 38680.4 38953.4 39564.2 40164.8 37047.0 37957.8 38224.8 38674.6 38971.4 37797.0 38297.4 38996.2 39034.8 39188.8 29047.7 31419.8 34075.3 34172.0 34225.7 22556.9 23444.1 24046.0 24396.0 24966.4 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35337.15 Median:22197.56 stddev:101685.93 (458.1%) stddev-sans-first:101937.31 07:45:26 INFO - PROCESS | 1659 | Values: 378.7 379.1 385.8 398.5 407.0 19656.0 21152.5 22183.7 22211.4 22281.8 20047.5 20769.4 21147.3 21212.9 21794.6 4592.0 5186.8 5425.5 5426.1 5434.3 3881.3 4087.9 5354.3 5781.9 6153.9 6156.1 6169.2 6169.5 6170.0 6179.4 4958.0 6067.7 6076.1 6079.0 6083.8 3753.8 5566.1 5798.4 5910.1 5918.3 13648.9 13745.5 13753.0 22418.3 25777.0 2014.5 2084.9 2118.7 2209.5 2295.1 9888.1 9899.2 10018.4 10229.6 10263.9 1706.3 1768.9 1784.1 1803.0 1807.9 129709.4 132635.0 133773.7 133935.0 135708.0 11357.5 11528.9 11731.2 11754.5 11937.0 15039.5 15098.8 15117.0 15149.8 15151.3 7365.7 8731.3 8836.4 8844.1 8871.6 7120.4 8750.0 8761.6 8875.5 8899.7 4544.6 4614.7 4625.1 4630.6 4646.4 1749.4 1805.0 1812.8 1876.6 1912.2 760.8 882.6 946.2 949.3 971.4 19666.6 20367.7 20680.9 20714.9 20738.4 938.8 943.5 948.5 974.2 1079.4 10972.9 11123.2 11247.1 11274.0 11394.4 585736.0 586643.0 615465.0 619622.0 626105.0 129349.4 129401.4 130044.0 130277.0 134524.0 1008.7 1011.2 1055.5 1084.5 1087.5 17483.4 18420.7 18648.4 18707.4 18879.0 38918.0 49564.8 49828.3 51337.2 52288.7 75272.0 75503.6 76636.9 77563.0 78041.8 8120.8 8162.5 8234.5 8439.5 8565.4 9848.4 11922.4 12536.7 12686.4 12972.2 6280.7 10595.9 10604.8 11117.6 11297.7 10499.5 10793.3 10816.5 10983.0 11253.2 10876.0 10900.7 10906.3 10995.9 11467.8 6949.7 8139.6 8147.2 8307.3 8557.1 9458.9 10828.9 11115.1 11131.1 11170.8 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5542.04 Median:5161.84 stddev:3119.43 (60.4%) stddev-sans-first:3112.44 07:45:26 INFO - PROCESS | 1659 | Values: 1372.6 1521.4 1559.8 1768.4 1921.8 7679.0 8493.2 8600.8 8711.0 8817.8 7049.8 8516.3 8552.6 8621.3 8646.8 2081.0 2165.6 2297.8 2337.7 2378.6 1971.3 2376.2 2398.1 2403.9 2404.1 2334.0 2349.2 2376.6 2382.4 2388.3 2024.5 2265.6 2318.4 2351.1 2352.5 2353.8 2354.6 2371.5 2375.4 2377.1 8403.8 8478.5 8482.5 8489.7 8513.1 5164.4 5344.3 5347.0 5347.5 5353.6 3169.1 3250.0 3302.1 3312.2 3332.1 993.7 993.9 1009.7 1018.9 1029.5 11322.0 11440.1 11479.4 11488.0 11524.7 4445.2 4524.5 4606.5 4623.1 4630.7 8972.0 8986.6 9105.3 9134.4 9154.9 4921.0 5159.3 5233.6 5254.3 5255.7 4367.4 4523.9 4761.7 4884.7 4887.0 3687.2 3751.2 3788.4 3821.5 3849.2 1141.6 1145.8 1149.8 1153.6 1159.8 753.6 866.2 900.4 903.3 952.1 5497.7 5689.8 5718.5 5873.7 6084.1 836.1 929.4 931.3 943.9 983.8 3571.4 3861.1 3866.2 3906.6 3910.8 10234.6 10475.2 10627.6 10660.9 10671.4 10392.2 10539.9 10592.8 10622.5 10628.1 7851.9 8009.4 8067.7 8176.4 8220.3 5538.6 5634.3 5655.1 5701.4 5867.5 9457.9 9689.2 9918.3 9920.9 9978.6 9579.9 9956.8 9958.9 9994.4 10135.9 5536.5 6249.7 6290.9 6492.2 6818.8 8201.7 8309.5 8381.0 8382.5 8395.7 7240.8 7367.9 7380.2 7478.8 7535.7 7121.2 7263.5 7271.6 7317.6 7323.5 6862.6 6910.9 6933.2 6949.7 6955.9 4127.0 4674.2 4740.5 4752.2 4977.5 4795.4 4855.0 4860.8 4878.0 4904.2 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4356.77 Median:4632.61 stddev:1372.79 (29.6%) stddev-sans-first:1342.48 07:45:26 INFO - PROCESS | 1659 | Values: 301.6 303.9 315.8 320.1 323.1 5168.5 5324.7 5337.5 5381.6 5467.3 4286.9 4329.2 4340.4 4657.5 5240.6 4033.7 4044.6 4048.8 4058.6 4059.6 3585.5 3762.8 3865.5 4054.3 4071.3 3977.1 4006.5 4013.0 4054.7 4060.0 3529.9 4005.6 4015.2 4026.5 4036.1 3045.7 3493.7 3494.9 3498.7 3503.4 4702.0 5130.3 5146.0 5150.7 5154.4 4549.5 4551.8 4565.1 4588.9 4607.7 5395.7 5555.4 5610.5 5613.3 5619.7 3267.4 3534.7 3834.0 3853.7 3901.7 5170.5 5906.5 5937.0 5941.2 5952.4 3345.7 3889.9 3910.1 3924.5 3943.8 3744.2 3788.8 3973.0 3977.4 4000.7 3916.3 4127.9 4518.0 4569.5 4606.5 5186.2 5539.0 5637.4 5668.9 5684.0 1121.6 1266.7 1309.7 1320.5 1360.3 773.2 791.3 799.4 816.6 823.9 4085.3 4294.5 4393.7 4417.6 4425.6 4134.6 4722.4 4859.7 4879.9 4895.9 3663.3 3795.2 3964.4 4137.1 4385.3 2117.7 2209.9 2297.4 2379.6 2417.7 4487.8 4665.9 4829.9 4854.7 4899.2 4221.0 4249.9 4436.1 4932.4 4948.6 4578.6 5004.4 5306.5 6048.8 6102.3 5112.4 5765.8 5823.7 5845.9 5848.9 5187.7 5481.4 5782.2 5792.6 5821.6 4928.4 5796.1 5968.9 6022.2 6024.5 3460.7 3835.6 3862.7 3882.9 3960.8 4523.8 5521.3 5778.2 5806.5 5817.3 4973.3 5006.8 5367.5 5612.9 5642.5 5185.3 5197.8 5502.4 5669.9 5705.5 4592.9 4673.5 5352.1 5569.6 5599.8 5241.3 5625.3 5674.5 5684.9 5687.7 4686.4 4739.0 5346.9 5562.5 5602.8 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:16472.24 Median:592.57 stddev:21932.89 (3701.3%) stddev-sans-first:21962.21 07:45:26 INFO - PROCESS | 1659 | Values: 642.4 656.2 702.9 711.7 733.3 14649.4 15072.2 15323.6 15932.6 16782.1 15485.6 15818.4 15868.2 15896.1 15937.8 712.8 739.9 912.9 923.4 931.3 882.5 923.2 923.4 926.1 929.9 934.9 936.1 956.6 965.9 969.6 745.8 921.2 959.9 960.8 961.2 715.0 715.2 716.7 717.4 718.1 14744.2 14758.6 14768.3 14786.8 14818.9 11956.9 11980.0 12116.8 12261.3 12269.2 10666.3 10814.3 10930.2 11371.9 11451.1 3978.9 3981.2 3982.7 4120.8 4136.4 75162.0 75687.0 76646.0 76910.6 77007.0 7514.8 9167.4 9262.6 9373.0 9575.4 20272.0 20399.6 20418.5 20425.0 20450.6 7186.4 7257.9 7260.2 7298.6 7335.0 3856.1 3890.8 3892.2 3893.0 3906.1 3357.7 3466.1 3467.6 3468.0 3477.2 610.8 624.3 626.0 648.5 649.0 473.4 551.7 583.8 587.9 597.2 7606.4 8140.6 8151.1 8238.2 8285.8 444.9 496.7 503.3 536.6 558.2 3275.6 3736.5 3815.9 3840.7 3878.8 68921.0 74948.0 75825.0 76802.6 77506.2 66376.0 66583.7 68106.0 68111.6 68202.0 61671.0 61714.7 62374.0 62426.7 62774.4 7439.9 7459.0 7518.2 7614.7 7678.8 40602.2 40724.8 40849.4 41233.0 41714.4 52882.0 54526.5 54594.2 55026.4 55451.0 9781.0 9948.7 10069.3 10089.2 10231.3 10584.6 10927.2 11133.3 11276.2 11892.5 7741.6 10479.3 10941.6 10949.8 11291.6 11365.8 11457.4 11716.0 11733.1 11796.4 11068.4 11308.7 11316.9 11576.8 11653.3 9347.6 9492.5 9664.7 9677.5 9850.3 8052.2 9494.9 9568.0 9597.0 9718.3 07:45:26 INFO - PROCESS | 1659 | -------- Summary: end -------- 07:45:26 INFO - PROCESS | 1659 | 07:45:26 INFO - TEST-INFO | 1659: exit 0 07:45:26 INFO - TEST-OK | dromaeo_css | took 1115150ms 07:45:26 INFO - TEST-START | kraken 07:45:26 INFO - operating with platform_type : mac_ 07:45:26 INFO - Initialising browser for kraken test... 07:45:26 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpMKBZCt/profile http://localhost:49278/getInfo.html) 07:45:29 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 07:45:29 INFO - PROCESS | 1669 | colorDepth:24 07:45:29 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 07:45:29 INFO - PROCESS | 1669 | __metrics 07:45:29 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" 07:45:29 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:45:29 INFO - TEST-INFO | 1669: exit 0 07:45:29 INFO - Browser initialized. 07:45:29 INFO - Running cycle 1/1 for kraken test... 07:45:29 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpMKBZCt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:45:41 INFO - PROCESS | 1671 | RSS: Main: 238809088 07:45:41 INFO - PROCESS | 1671 | 07:45:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 07:45:45 INFO - PROCESS | 1671 | RSS: Main: 287490048 07:45:45 INFO - PROCESS | 1671 | 07:45:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 07:45:49 INFO - PROCESS | 1671 | RSS: Main: 295837696 07:45:49 INFO - PROCESS | 1671 | 07:45:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 07:45:53 INFO - PROCESS | 1671 | RSS: Main: 385929216 07:45:53 INFO - PROCESS | 1671 | 07:45:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 07:45:56 INFO - PROCESS | 1671 | RSS: Main: 283951104 07:45:56 INFO - PROCESS | 1671 | 07:45:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:45:58 INFO - PROCESS | 1671 | RSS: Main: 288374784 07:45:58 INFO - PROCESS | 1671 | 07:46:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 07:46:02 INFO - PROCESS | 1671 | RSS: Main: 326926336 07:46:02 INFO - PROCESS | 1671 | 07:46:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 07:46:06 INFO - PROCESS | 1671 | RSS: Main: 330530816 07:46:06 INFO - PROCESS | 1671 | 07:46:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 07:46:09 INFO - PROCESS | 1671 | RSS: Main: 326844416 07:46:09 INFO - PROCESS | 1671 | 07:46:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:46:12 INFO - PROCESS | 1671 | RSS: Main: 316514304 07:46:12 INFO - PROCESS | 1671 | 07:46:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:46:15 INFO - PROCESS | 1671 | RSS: Main: 323244032 07:46:15 INFO - PROCESS | 1671 | 07:46:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:46:18 INFO - PROCESS | 1671 | RSS: Main: 326995968 07:46:18 INFO - PROCESS | 1671 | 07:46:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:46:21 INFO - PROCESS | 1671 | RSS: Main: 330903552 07:46:21 INFO - PROCESS | 1671 | 07:46:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:46:25 INFO - PROCESS | 1671 | RSS: Main: 328749056 07:46:25 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 07:46:27 INFO - PROCESS | 1671 | RSS: Main: 375816192 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | __start_tp_report 07:46:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 07:46:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 07:46:27 INFO - PROCESS | 1671 | |i|pagename|runs| 07:46:27 INFO - PROCESS | 1671 | |0;ai-astar;113;106;113;111;111;112;117;162;157;113 07:46:27 INFO - PROCESS | 1671 | |1;audio-beat-detection;156;121;116;200;118;117;139;133;159;142 07:46:27 INFO - PROCESS | 1671 | |2;audio-dft;192;195;214;183;183;182;184;184;181;179 07:46:27 INFO - PROCESS | 1671 | |3;audio-fft;79;56;61;117;59;56;65;61;81;56 07:46:27 INFO - PROCESS | 1671 | |4;audio-oscillator;99;78;140;79;88;76;102;79;77;108 07:46:27 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;86;86;85;86;86;88;84;87;84 07:46:27 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;109;148;94;92;93;94;94;98;93 07:46:27 INFO - PROCESS | 1671 | |7;imaging-desaturate;83;86;83;91;85;85;86;86;86;84 07:46:27 INFO - PROCESS | 1671 | |8;json-parse-financial;58;58;59;56;58;56;56;158;90;89 07:46:27 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;72;70;75;72;101;99;99;75;69 07:46:27 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;85;85;84;84;88;105;106;84;85;86 07:46:27 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;142;152;139;138;145;155;132;134;222;144 07:46:27 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;163;221;173;164;165;165;161;162;160;164 07:46:27 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;67;67;67;67;65;66;65;66;66 07:46:27 INFO - PROCESS | 1671 | __end_tp_report 07:46:27 INFO - PROCESS | 1671 | __start_cc_report 07:46:27 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2312 07:46:27 INFO - PROCESS | 1671 | __end_cc_report 07:46:27 INFO - PROCESS | 1671 | __startTimestamp1464101187375__endTimestamp 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | ------- Summary: start ------- 07:46:27 INFO - PROCESS | 1671 | Number of tests: 14 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:121.50 Median:113.00 stddev:20.24 (17.9%) stddev-sans-first:21.24 07:46:27 INFO - PROCESS | 1671 | Values: 113.0 106.0 113.0 111.0 111.0 112.0 117.0 162.0 157.0 113.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:140.50 stddev:26.29 (18.7%) stddev-sans-first:27.25 07:46:27 INFO - PROCESS | 1671 | Values: 156.0 121.0 116.0 200.0 118.0 117.0 139.0 133.0 159.0 142.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:187.70 Median:184.00 stddev:10.48 (5.7%) stddev-sans-first:11.00 07:46:27 INFO - PROCESS | 1671 | Values: 192.0 195.0 214.0 183.0 183.0 182.0 184.0 184.0 181.0 179.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:69.10 Median:61.00 stddev:19.15 (31.4%) stddev-sans-first:19.97 07:46:27 INFO - PROCESS | 1671 | Values: 79.0 56.0 61.0 117.0 59.0 56.0 65.0 61.0 81.0 56.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:78.50 stddev:20.38 (26.0%) stddev-sans-first:21.48 07:46:27 INFO - PROCESS | 1671 | Values: 99.0 78.0 140.0 79.0 88.0 76.0 102.0 79.0 77.0 108.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:86.00 stddev:1.41 (1.6%) stddev-sans-first:1.30 07:46:27 INFO - PROCESS | 1671 | Values: 88.0 86.0 86.0 85.0 86.0 86.0 88.0 84.0 87.0 84.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:93.50 stddev:17.33 (18.5%) stddev-sans-first:18.15 07:46:27 INFO - PROCESS | 1671 | Values: 93.0 109.0 148.0 94.0 92.0 93.0 94.0 94.0 98.0 93.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:2.27 (2.6%) stddev-sans-first:2.22 07:46:27 INFO - PROCESS | 1671 | Values: 83.0 86.0 83.0 91.0 85.0 85.0 86.0 86.0 86.0 84.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:73.80 Median:58.00 stddev:32.49 (56.0%) stddev-sans-first:33.96 07:46:27 INFO - PROCESS | 1671 | Values: 58.0 58.0 59.0 56.0 58.0 56.0 56.0 158.0 90.0 89.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:73.50 stddev:14.21 (19.3%) stddev-sans-first:13.90 07:46:27 INFO - PROCESS | 1671 | Values: 64.0 72.0 70.0 75.0 72.0 101.0 99.0 99.0 75.0 69.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:85.00 stddev:8.68 (10.2%) stddev-sans-first:9.07 07:46:27 INFO - PROCESS | 1671 | Values: 85.0 85.0 84.0 84.0 88.0 105.0 106.0 84.0 85.0 86.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:144.50 stddev:26.21 (18.1%) stddev-sans-first:27.63 07:46:27 INFO - PROCESS | 1671 | Values: 142.0 152.0 139.0 138.0 145.0 155.0 132.0 134.0 222.0 144.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:164.50 stddev:18.34 (11.1%) stddev-sans-first:19.28 07:46:27 INFO - PROCESS | 1671 | Values: 163.0 221.0 173.0 164.0 165.0 165.0 161.0 162.0 160.0 164.0 07:46:27 INFO - PROCESS | 1671 | 07:46:27 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:0.79 (1.2%) stddev-sans-first:0.83 07:46:27 INFO - PROCESS | 1671 | Values: 66.0 67.0 67.0 67.0 67.0 65.0 66.0 65.0 66.0 66.0 07:46:27 INFO - PROCESS | 1671 | -------- Summary: end -------- 07:46:27 INFO - PROCESS | 1671 | 07:46:28 INFO - TEST-INFO | 1671: exit 0 07:46:28 INFO - TEST-OK | kraken | took 61255ms 07:46:28 INFO - SUITE-END | took 1176s 07:46:28 INFO - Completed test suite (00:19:36) 07:46:28 INFO - javascript benchmark 07:46:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3250.821204833734, 3467.0, 3488.0930957950927, 3501.684848363647, 3537.062678390227, 5545.057340252157, 5549.112142057272, 5580.135079062741, 5668.971655141724, 5712.0289550776315, 5565.860853478665, 5617.494425501704, 5680.801171958982, 5683.460071293231, 5752.050911599581, 4271.380649805777, 5062.367204099488, 5110.897782044357, 5195.610329225323, 5268.130758424855, 5283.973580132113, 5289.497497737709, 5308.097623404011, 5326.3075800145925, 5359.330083739533, 5100.974495127501, 5137.588992880561, 5146.0, 5201.401838788531, 5214.843554693365, 5073.6955782652785, 5153.974230128825, 5179.249008893689, 5190.32525771651, 5199.818006369759, 4430.911381772379, 4690.437147542282, 5105.106606343912, 5190.140528891465, 5239.947600523984, 5545.140503222007, 5588.776448942038, 5590.692511911843, 5639.548836093103, 5658.547316214693, 5980.162777211194, 6045.653287703272, 6119.959606866843, 6152.969235153841, 6158.168647232611, 6232.376762323776, 6271.498280137578, 6283.528735344822, 6307.842303942392, 6326.145970293997, 5786.276715410574, 5831.037878750001, 5841.0, 5847.970760146172, 5860.531157507389, 6190.969045154746, 6255.40573645503, 6302.716377763011, 6323.715432805535, 6327.303996560375, 6061.900952513432, 6086.391360863967, 6123.938760612336, 6137.0794380842335, 6147.293061297919, 6098.14625952367, 6265.561410701207, 6271.247450305992, 6274.937250627435, 6307.747690092437, 6101.328853826076, 6112.969435152885, 6144.4945988232275, 6145.770845830852, 6236.719347629368, 6180.81457556274, 6226.968865155736, 6246.0, 6248.562600617913, 6260.092286618415, 4815.446223684251, 4947.802087916515, 4954.083494553519, 4968.0, 4987.226979818134, 4373.584509471595, 4382.583654552813, 4395.824167033283, 4409.5149533551285, 4448.621867141264, 5668.206451096849, 5706.058500347479, 5723.027085395493, 5751.712414379264, 5758.107493338539, 6118.296395914438, 6119.593952096689, 6274.843128921722, 6288.151099601587, 6315.305316415191, 5600.0, 5640.351359593617, 5670.914936275959, 5749.195112684144, 5752.798652047159, 5373.194020896896, 5381.623286369917, 5385.138377859602, 5391.3799913009725, 5411.918821217685, 6291.496680265568, 6297.622142671455, 6304.495640348853, 6311.558190926591, 6313.905291420632, 5976.282846058501, 6107.006761825686, 6118.296395914349, 6149.323574406722, 6203.2556093269095, 6278.591891527036, 6305.653189074645, 6308.27454842699, 6320.178376810985, 6402.90395644075, 6059.515238780975, 6186.288576813724, 6202.069689546604, 6204.7828326008375, 6216.937830621636, 4777.808887644455, 6117.492485019787, 6130.18412495751, 6150.231221097363, 6298.370162983665, 6085.300190478151, 6087.117367981707, 6094.512439004958, 6099.969500152471, 6100.542459315658, 5902.822915312548, 6039.094135879654, 6070.878582428415, 6107.083937409247, 6110.419510146529, 4979.850604481871, 5857.824265272048, 5875.089361149029, 5927.4665280125, 5958.702064896825, 6065.769805680119, 6068.2969554008905, 6089.878202435838, 6099.268087829489, 6101.267847858286, 5697.287839020122, 5824.21373114641, 5932.406759324033, 5943.167956486009, 5969.104634304889], "value": 5658.788292439187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.5557997801978, 510.0385772818237, 515.9561437277831, 530.8938212357529, 547.1054825360534, 25138.748612513893, 31154.2211444714, 41945.580544194585, 42893.0, 43009.78495107528, 44995.325070123894, 45246.773766131126, 45274.09451810962, 45394.09211815762, 45602.0, 8920.375573709844, 9427.858582121273, 9613.279004074704, 9855.605775768961, 9889.159421449149, 8591.957040214791, 8839.24866386358, 11599.536018559247, 11845.70385740359, 11867.643970680894, 11943.34311612861, 11976.820347694791, 12004.519819207222, 12031.759364812699, 12119.636410907688, 8666.913330866673, 11684.357360345135, 11736.29582225065, 11812.173147879652, 11822.467988940518, 11654.592089276835, 12055.517779288817, 12056.19157126438, 12058.879411205864, 12063.974562162139, 41244.38133428001, 41540.376894346606, 41855.74432767022, 41863.58136418627, 41883.0, 3203.423383790917, 3325.8503367348526, 3364.814935178564, 3466.532018177539, 3549.4512624208296, 29159.854200729074, 29267.560986585217, 29735.40529189404, 29890.551641725393, 30225.0, 2710.0, 2720.564709646447, 2729.727027297274, 2756.641636587231, 2821.604975303459, 351932.0, 352481.0, 352763.0, 536046.0, 538096.0, 47034.5296547037, 62315.0, 62565.37434625596, 62787.0, 62909.0, 35204.823975879954, 36102.458463123075, 36132.81933590315, 65202.0, 65311.67344163249, 16707.832921670626, 16798.664026719398, 16968.830311696973, 16980.0, 16993.40523081686, 11626.88373116258, 11912.821307680391, 12666.430010649543, 12818.230906145662, 12915.935420322838, 19468.51645730659, 19776.330195423434, 20244.0, 20245.291414800413, 20564.38306850797, 7652.403370415896, 7659.348955338859, 7690.384769218449, 7736.226377362219, 7777.688892444352, 873.908239634842, 950.7005293332717, 963.3978763272954, 1071.603506702525, 1126.6000569797604, 30252.092437226478, 30324.0, 30339.62038021904, 30487.69512304893, 30491.0, 843.3295530053622, 1040.4173662748885, 1063.4576366053368, 1114.5820317380983, 1116.2298014370222, 15300.234988250542, 15331.386744530093, 15404.306806193748, 15717.921410392875, 15808.288627011812, 687902.0, 944492.0, 958783.0, 958941.0, 959224.0, 60499.395006050254, 60652.0, 60998.08502872461, 61203.0, 61221.38778612246, 1205.5780476833213, 1228.91397602167, 1238.9159485450232, 1440.7262620102147, 1458.8190654970674, 29256.26859328534, 30196.0, 30722.231944201572, 30846.53730194004, 31600.0, 59769.0, 60307.698461509164, 60636.78726425535, 60673.0, 60724.0, 603888.0, 606357.0, 609503.0, 609643.0, 610136.0, 31090.533641994934, 36070.0, 42602.78698606487, 43626.78186609047, 43997.78001109974, 47424.52575474201, 47550.76224618855, 47587.52412475831, 47653.28520072132, 49121.263181051596, 38676.80661596674, 38680.41979370368, 38953.41569876397, 39564.2087158261, 40164.7991760051, 37047.0, 37957.810210948766, 38224.808875955445, 38674.6132538671, 38971.44114235512, 37797.0, 38297.425538616386, 38996.220075598896, 39034.80482597569, 39188.80405597954, 29047.7095229045, 31419.84290078535, 34075.31849363048, 34171.97484075482, 34225.65774342225, 22556.887215564475, 23444.06223751031, 24046.0, 24396.0, 24966.37584060413], "value": 20634.236633108478, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.732993239766, 379.1412450798941, 385.7511904821392, 398.51580329899167, 407.02720498009745, 19656.0, 21152.4711882203, 22183.66724499134, 22211.44471388214, 22281.777182228172, 20047.49881252966, 20769.376918692462, 21147.259845905395, 21212.89393553034, 21794.564108717816, 4591.9540804592025, 5186.844394668156, 5425.457454254582, 5426.131818908975, 5434.347878254615, 3881.3013657541633, 4087.9182416351655, 5354.330708661418, 5781.884362312751, 6153.876922461571, 6156.076588511737, 6169.167162433081, 6169.537284703665, 6170.0, 6179.412955769195, 4958.0, 6067.726952287114, 6076.088586712029, 6078.969605151968, 6083.8174854754425, 3753.7560058596378, 5566.081596536566, 5798.3911689272445, 5910.054391297372, 5918.348981612063, 13648.863511364858, 13745.518906838313, 13753.0, 22418.32745017652, 25777.0, 2014.5165160361407, 2084.8957552122333, 2118.700941037278, 2209.5249521352825, 2295.104909085458, 9888.110070093726, 9899.15857152143, 10018.398896066186, 10229.59081636741, 10263.89736102637, 1706.3004168291066, 1768.9469315920544, 1784.1436110666823, 1803.0443864751703, 1807.8824876382994, 129709.35145324212, 132635.0, 133773.6622633771, 133935.0, 135708.0, 11357.488912998935, 11528.884711152949, 11731.23746956445, 11754.471048802825, 11937.0, 15039.47361842352, 15098.849011509965, 15117.0, 15149.848501515064, 15151.318190681446, 7365.668544915492, 8731.345149113842, 8836.425632333878, 8844.115588441104, 8871.556422178865, 7120.359167674934, 8750.03749587545, 8761.56192190388, 8875.511846848356, 8899.688510902217, 4544.59136657572, 4614.700044497097, 4625.144348295509, 4630.560096790799, 4646.395968524138, 1749.422690512128, 1804.9587424018655, 1812.7734033245845, 1876.6434377468238, 1912.235105957628, 760.8136006678288, 882.618667037311, 946.2477330522252, 949.2975198353307, 971.4074414607084, 19666.60666786685, 20367.694484582742, 20680.896595516926, 20714.896425517774, 20738.377848664564, 938.7559234599054, 943.4622265308845, 948.4593781544453, 974.1524873360222, 1079.4494807648, 10972.890271097347, 11123.16576256784, 11247.144221203409, 11274.0, 11394.430278486043, 585736.0, 586643.0, 615465.0, 619622.0, 626105.0, 129349.35325323313, 129401.35299323632, 130044.0, 130277.0, 134524.0, 1008.6570566007446, 1011.246621267146, 1055.5144633468537, 1084.5011294804322, 1087.521490544174, 17483.388081417088, 18420.723689144404, 18648.44054678362, 18707.438776836716, 18878.96165710899, 38918.027049323995, 49564.752176238886, 49828.25257621211, 51337.22994155165, 52288.69278268074, 75272.0, 75503.62248188724, 76636.85044724222, 77563.0, 78041.82937255832, 8120.83758324842, 8162.469439486415, 8234.505929644001, 8439.49363038195, 8565.357598180286, 9848.409095454295, 11922.4038798058, 12536.686582835137, 12686.429110690226, 12972.22166669965, 6280.717367718372, 10595.947020265157, 10604.787904241717, 11117.555297787998, 11297.661070167907, 10499.530065790643, 10793.29843560166, 10816.513256903301, 10983.0, 11253.15601329887, 10876.0, 10900.727481813017, 10906.34561926287, 10995.945020274847, 11467.827982580435, 6949.687264073028, 8139.633716482654, 8147.226013528705, 8307.252347288773, 8557.058723540531, 9458.905410946078, 10828.891711082788, 11115.055220306042, 11131.05386042163, 11170.832437513607], "value": 9392.380543855998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.553919976007, 1521.3762357433454, 1559.750439929611, 1768.4252617899185, 1921.8366438852697, 7678.96160519198, 8493.150684931512, 8600.827983440327, 8711.041785403608, 8817.779555511099, 7049.7885063448175, 8516.31869450444, 8552.572371381422, 8621.267192288664, 8646.827063458728, 2080.980319643379, 2165.577712346093, 2297.827662925279, 2337.684412604298, 2378.6075297575967, 1971.3100414854757, 2376.227725989052, 2398.1486572266854, 2403.8918248678765, 2404.0984630763464, 2334.043042352636, 2349.2247558305803, 2376.6316220985773, 2382.4282172278704, 2388.34320561846, 2024.4635171679442, 2265.6148454762565, 2318.40880575453, 2351.094828491026, 2352.5294941011866, 2353.8116950643907, 2354.611489104313, 2371.5256948610177, 2375.4417711837705, 2377.1442280778906, 8403.789905252357, 8478.491290522588, 8482.491050536926, 8489.66041358345, 8513.148685131498, 5164.354455693038, 5344.331958505187, 5347.0, 5347.51872331492, 5353.57171426285, 3169.1443310306317, 3250.0, 3302.075418882717, 3312.2381852173894, 3332.1169889979174, 993.746594618375, 993.8857031441404, 1009.6769033909152, 1018.9032041956002, 1029.5161274201114, 11322.037626801732, 11440.084793216605, 11479.368634725086, 11488.02351800098, 11524.654260372203, 4445.1998640245065, 4524.524924882906, 4606.516315786861, 4623.098495793355, 4630.745309008037, 8971.955140224387, 8986.595603197871, 9105.317101217435, 9134.406263592846, 9154.908450915538, 4920.975395123001, 5159.329287192641, 5233.555147812479, 5254.264402983586, 5255.710935898486, 4367.366731823931, 4523.932141017887, 4761.666683332202, 4884.731339776346, 4887.04702583, 3687.170386663033, 3751.1559899023046, 3788.3749181384765, 3821.522309711286, 3849.191669749328, 1141.560499207811, 1145.7536629624672, 1149.8045332293364, 1153.5731779241567, 1159.837622732818, 753.5892938348613, 866.2290561400358, 900.3883049341605, 903.290916630442, 952.1002652493465, 5497.697626630494, 5689.77240910359, 5718.51392631631, 5873.706314684248, 6084.056971169476, 836.1387770596295, 929.4330458420358, 931.3015238571072, 943.8631398447187, 983.8278301297245, 3571.4107172316794, 3861.054041759731, 3866.2460820139804, 3906.628870257378, 3910.8435662573747, 10234.6417875374, 10475.16198704117, 10627.628033018962, 10660.946695266473, 10671.413072281099, 10392.168626509861, 10539.894601054044, 10592.788144237073, 10622.46887655614, 10628.14974802014, 7851.882221766678, 8009.399295052895, 8067.677292908335, 8176.3867709922, 8220.301274391624, 5538.6399884007415, 5634.323881134126, 5655.066913959151, 5701.372848986525, 5867.471927526543, 9457.858132128162, 9689.224862011164, 9918.305718599917, 9920.851187232052, 9978.555253962826, 9579.856302155335, 9956.800863982824, 9958.850617240601, 9994.350367226107, 10135.935726748585, 5536.474034966672, 6249.718762655601, 6290.9056364155485, 6492.188476440445, 6818.795436136924, 8201.671933122609, 8309.501429914224, 8380.994280686356, 8382.455140415854, 8395.748127556182, 7240.782776516713, 7367.926320736939, 7380.18817930038, 7478.775636730907, 7535.660895259617, 7121.181064177686, 7263.4915555910575, 7271.600061996641, 7317.634118294171, 7323.450741194325, 6862.553933994274, 6910.861782764216, 6933.168019837652, 6949.652517374212, 6955.930440695731, 4127.0, 4674.22875225584, 4740.4785473597185, 4752.239641657318, 4977.4773648767805, 4795.400574928134, 4854.975725121351, 4860.805567777249, 4878.0, 4904.166291730343], "value": 4449.063843978611, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.6169464779728, 303.87237360308677, 315.7994673382402, 320.13244108466057, 323.13561223726526, 5168.457311982244, 5324.65389749666, 5337.491379051844, 5381.5963802714805, 5467.343918729753, 4286.892827679309, 4329.1601069545395, 4340.370646256299, 4657.464391594967, 5240.580753539717, 4033.7378070425475, 4044.635982761551, 4048.7773172475504, 4058.5738497457783, 4059.5534491205945, 3585.515955346034, 3762.830672619739, 3865.4781604483314, 4054.2702313583545, 4071.30787766079, 3977.065389633434, 4006.5392479864895, 4012.953393769184, 4054.6553542948886, 4060.0, 3529.8941031769086, 4005.6380830517473, 4015.156817068419, 4026.4564283821746, 4036.1120553478186, 3045.6649768525444, 3493.7205023598303, 3494.930101397958, 3498.702610278129, 3503.4219353806593, 4701.952980470186, 5130.3074084998425, 5146.0, 5150.686574923408, 5154.38147422308, 4549.47681016684, 4551.817927282905, 4565.1098035883015, 4588.934979259347, 4607.746573938432, 5395.7032363220005, 5555.416681248492, 5610.523105536035, 5613.270274864282, 5619.662820230758, 3267.4118658641437, 3534.7348948828685, 3834.041489627593, 3853.749506282111, 3901.707371947087, 5170.45710200431, 5906.527477801808, 5936.951751645675, 5941.187937679007, 5952.375000624916, 3345.6821601947777, 3889.86385476507, 3910.1231408923877, 3924.50943632046, 3943.8028098594955, 3744.2137151198554, 3788.753731007476, 3972.980135099364, 3977.428915578388, 4000.6597789740763, 3916.3146449371247, 4127.9174416511505, 4518.006038671487, 4569.543045695404, 4606.456836959589, 5186.170212765939, 5539.002979463655, 5637.43625637433, 5668.858278543069, 5684.0, 1121.61864965912, 1266.7269394258894, 1309.6706842554815, 1320.504810695989, 1360.2994457854213, 773.1572585881304, 791.3313250303485, 799.4403917257943, 816.6488409983646, 823.8970128733908, 4085.264652362544, 4294.527601963781, 4393.692441529126, 4417.648616113126, 4425.565938733684, 4134.586541345841, 4722.3707820801765, 4859.659823812299, 4879.902022045082, 4895.947371315185, 3663.314875157457, 3795.2352156246925, 3964.444977703124, 4137.076878514558, 4385.342198670225, 2117.7246957895377, 2209.889505524717, 2297.3688681036315, 2379.58357287474, 2417.6615273861676, 4487.833163377545, 4665.906681866344, 4829.8847243527725, 4854.660173787801, 4899.191633380524, 4221.0030878076295, 4249.915001699948, 4436.0906014267, 4932.432770231398, 4948.564916174341, 4578.6108180805, 5004.449510553836, 5306.46935306474, 6048.788292409832, 6102.328743838263, 5112.386513618241, 5765.798197063251, 5823.679697616672, 5845.941540584539, 5848.912266316093, 5187.651210685324, 5481.382287690106, 5782.2194003808945, 5792.5655575832825, 5821.621594596338, 4928.433230178575, 5796.1305804129715, 5968.895754285471, 6022.187004754477, 6024.548158888013, 3460.705840003628, 3835.6356146166067, 3862.7253006508345, 3882.9417558737205, 3960.7623542587535, 4523.796429160761, 5521.337439507285, 5778.150991682621, 5806.519337568893, 5817.331006934088, 4973.328600638867, 5006.774695138654, 5367.543758780547, 5612.8877422450505, 5642.492175704042, 5185.299984502195, 5197.7661005254095, 5502.394736579053, 5669.943300566942, 5705.4579814917515, 4592.908141837212, 4673.4625518064495, 5352.090144675337, 5569.582281328835, 5599.780126427239, 5241.292425522505, 5625.296837895263, 5674.524623597812, 5684.914726279192, 5687.74869528703, 4686.437627484587, 4739.033301179972, 5346.893062138658, 5562.527185189304, 5602.831915042555], "value": 3921.208118564221, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.3897297567311, 656.1896058367918, 702.9121359830021, 711.7366574367481, 733.2520828754668, 14649.414023439063, 15072.246387680614, 15323.616909577253, 15932.601684957854, 16782.07698576578, 15485.612859678513, 15818.446354377598, 15868.206589670499, 15896.125713085805, 15937.840621593781, 712.8111050571603, 739.9038125043737, 912.9087091290868, 923.3905622289291, 931.3108299858123, 882.4528792148876, 923.2060428031887, 923.4320892651004, 926.1016813690709, 929.9349045566813, 934.8878134623855, 936.059260443257, 956.550421301987, 965.8985806490323, 969.5636963366478, 745.8023623739713, 921.2491819167357, 959.8752162218937, 960.8318544254728, 961.2406199102666, 715.048984850148, 715.1846894540229, 716.7025684340992, 717.4009701898921, 718.105958082191, 14744.189069601167, 14758.631034224123, 14768.335424905905, 14786.778198327034, 14818.925905370405, 11956.880431195663, 11980.0, 12116.81824772629, 12261.26432414058, 12269.202501837352, 10666.253362264644, 10814.29707069038, 10930.234883558154, 11371.886281137165, 11451.083913286917, 3978.8806335809973, 3981.2435637229123, 3982.681385489154, 4120.835166593332, 4136.4415803866395, 75162.0, 75686.99999999945, 76646.0, 76910.61544692241, 77007.0, 7514.849703006019, 9167.449953002708, 9262.629494820267, 9372.95313523428, 9575.425474471554, 20272.0, 20399.59200816005, 20418.489537761674, 20425.0, 20450.590988180153, 7186.38915692172, 7257.891131632924, 7260.165081015645, 7298.56208627484, 7335.046443962251, 3856.093817952779, 3890.844366225376, 3892.163184915257, 3892.9805350973065, 3906.0820707133803, 3357.7145942594666, 3466.116140384186, 3467.6185619581825, 3467.994281658341, 3477.23500829817, 610.7709608896664, 624.2976651267325, 626.0234034905562, 648.4779752299349, 648.970796314167, 473.38460001997265, 551.7186235020089, 583.8336074218912, 587.9059350503899, 597.2295738497377, 7606.353459955855, 8140.552269625109, 8151.103378628346, 8238.217369349903, 8285.792855178668, 444.86431638349967, 496.7143892261964, 503.2878476955141, 536.6350881400607, 558.1571826541951, 3275.623303320101, 3736.458213559073, 3815.904602384963, 3840.6543411093135, 3878.8448462061765, 68921.0, 74948.0, 75825.0, 76802.61598691971, 77506.22493775103, 66376.0, 66583.66708166525, 68106.0, 68111.63776724438, 68202.0, 61671.0, 61714.691426543475, 62374.0, 62426.68786656038, 62774.37225627685, 7439.888401673871, 7459.0, 7518.173000969999, 7614.695412183451, 7678.846423071619, 40602.187956240115, 40724.796376017934, 40849.38725919173, 41233.0, 41714.374284386366, 52882.0, 54526.454735452135, 54594.18108728293, 55026.44973550213, 55451.0, 9781.021897810162, 9948.701538953843, 10069.295149339769, 10089.243306751876, 10231.334963227664, 10584.631880978775, 10927.235093543693, 11133.332000080021, 11276.154288428565, 11892.524299027942, 7741.612919353897, 10479.318844275404, 10941.56233750641, 10949.781004380027, 11291.604793832177, 11365.829512557153, 11457.427128643702, 11716.0, 11733.061355091742, 11796.351200684045, 11068.391238481961, 11308.717282068013, 11316.924892135234, 11576.768464630828, 11653.30080195191, 9347.579358929, 9492.525373731423, 9664.661736839176, 9677.516124193902, 9850.261230407605, 8052.235037671412, 9494.85757713621, 9567.952160239154, 9597.0, 9718.319717619699], "value": 6614.551278259777, "unit": "score"}], "name": "dromaeo_css", "value": 7085.782087943375, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 113.0, 111.0, 111.0, 112.0, 117.0, 162.0, 157.0, 113.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 121.0, 116.0, 200.0, 118.0, 117.0, 139.0, 133.0, 159.0, 142.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 195.0, 214.0, 183.0, 183.0, 182.0, 184.0, 184.0, 181.0, 179.0], "value": 187.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 56.0, 61.0, 117.0, 59.0, 56.0, 65.0, 61.0, 81.0, 56.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 78.0, 140.0, 79.0, 88.0, 76.0, 102.0, 79.0, 77.0, 108.0], "value": 92.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 85.0, 86.0, 86.0, 88.0, 84.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 109.0, 148.0, 94.0, 92.0, 93.0, 94.0, 94.0, 98.0, 93.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 83.0, 91.0, 85.0, 85.0, 86.0, 86.0, 86.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 56.0, 58.0, 56.0, 56.0, 158.0, 90.0, 89.0], "value": 73.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 70.0, 75.0, 72.0, 101.0, 99.0, 99.0, 75.0, 69.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 88.0, 105.0, 106.0, 84.0, 85.0, 86.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 152.0, 139.0, 138.0, 145.0, 155.0, 132.0, 134.0, 222.0, 144.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 221.0, 173.0, 164.0, 165.0, 165.0, 161.0, 162.0, 160.0, 164.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 67.0, 65.0, 66.0, 65.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1512.1999999999998, "alertThreshold": 2.0}]} 07:46:28 INFO - Return code: 0 07:46:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:46:28 ERROR - # TBPL SUCCESS # 07:46:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:46:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:46:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:46:28 INFO - 2016-05-24 07:46:28.676 screenresolution[1675:10289] starting screenresolution argv=screenresolution get 07:46:28 INFO - 2016-05-24 07:46:28.679 screenresolution[1675:10289] Display 0: 1600x1200x32@60 07:46:28 INFO - 2016-05-24 07:46:28.685 screenresolution[1676:10293] starting screenresolution argv=screenresolution list 07:46:28 INFO - Available Modes on Display 0 07:46:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:46:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:46:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:46:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:46:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:46:28 INFO - Intel Iris: 07:46:28 INFO - Chipset Model: Intel Iris 07:46:28 INFO - Type: GPU 07:46:28 INFO - Bus: Built-In 07:46:28 INFO - VRAM (Dynamic, Max): 1536 MB 07:46:28 INFO - Vendor: Intel (0x8086) 07:46:28 INFO - Device ID: 0x0a2e 07:46:28 INFO - Revision ID: 0x0009 07:46:28 INFO - Displays: 07:46:28 INFO - Display: 07:46:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:46:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:46:28 INFO - Display Serial Number: 1600x1200 60 07:46:28 INFO - Main Display: Yes 07:46:28 INFO - Mirror: Off 07:46:28 INFO - Online: Yes 07:46:28 INFO - Rotation: Supported 07:46:28 INFO - Return code: 0 07:46:28 INFO - Running post-action listener: _resource_record_post_action 07:46:28 INFO - ##### 07:46:28 INFO - ##### Finished run-tests step (success) 07:46:28 INFO - ##### 07:46:28 INFO - Running post-run listener: _resource_record_post_run 07:46:28 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38127104; Write bytes: 696852480; Read time: 397; Write time: 1937 07:46:28 INFO - TinderboxPrint: CPU usage
53.8% 07:46:28 INFO - TinderboxPrint: I/O read bytes / time
38,127,104 / 397 07:46:28 INFO - TinderboxPrint: I/O write bytes / time
696,852,480 / 1,937 07:46:28 INFO - TinderboxPrint: CPU idle
2,239.1 (46.2%) 07:46:28 INFO - TinderboxPrint: CPU system
968.7 (20.0%) 07:46:28 INFO - TinderboxPrint: CPU user
1,641.8 (33.9%) 07:46:28 INFO - TinderboxPrint: Swap in / out
434,257,920 / 0 07:46:28 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375566848; Write bytes: 369579520; Read time: 28140; Write time: 722 07:46:28 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37168640; Write bytes: 298146304; Read time: 382; Write time: 1159 07:46:29 INFO - Running post-run listener: _upload_blobber_files 07:46:29 INFO - Blob upload gear active. 07:46:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:46:29 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 07:46:29 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'] 07:46:29 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 07:46:29 INFO - (blobuploader) - INFO - Open directory for files ... 07:46:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:46:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:46:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:30 INFO - (blobuploader) - INFO - Done attempting. 07:46:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:46:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:46:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:31 INFO - (blobuploader) - INFO - Done attempting. 07:46:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:46:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:46:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:34 INFO - (blobuploader) - INFO - Done attempting. 07:46:34 INFO - (blobuploader) - INFO - Iteration through files over. 07:46:34 INFO - Return code: 0 07:46:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:46:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:46:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea07fe25eca5936e977edf6ff2dd15d6637e0e863ed70a9863c56c8eea07914da27d407f21b2dd87cb709c0941f6fdeb087a9ff694408149b6da88e8e63e1c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f0f20f1a3e29a1b16e5201c52f871b3d978265a30d3b80efa937d5122b6206f97be01b183faeef25f4b7a8f05a7ce6022db8e85ae831d691cc7cf7d6990f80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c141bd87be9bdf6a4715b7be263c83bb27b82f1a12b977ef72d48a166dbec6e683cc914a633d2e5d38492307da930323e52d67fb675ce174e3af5a03655cc0b8"} 07:46:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:46:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:46:34 INFO - Contents: 07:46:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea07fe25eca5936e977edf6ff2dd15d6637e0e863ed70a9863c56c8eea07914da27d407f21b2dd87cb709c0941f6fdeb087a9ff694408149b6da88e8e63e1c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f0f20f1a3e29a1b16e5201c52f871b3d978265a30d3b80efa937d5122b6206f97be01b183faeef25f4b7a8f05a7ce6022db8e85ae831d691cc7cf7d6990f80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c141bd87be9bdf6a4715b7be263c83bb27b82f1a12b977ef72d48a166dbec6e683cc914a633d2e5d38492307da930323e52d67fb675ce174e3af5a03655cc0b8"} 07:46:34 INFO - Running post-run listener: copy_logs_to_upload_dir 07:46:34 INFO - Copying logs to upload dir... 07:46:34 INFO - mkdir: /builds/slave/test/build/upload/logs 07:46:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.705257 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-24 07:46:34.320431) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 07:46:34.323909) ========= 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.BNPTfTCTEm/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.0yEDB24krq/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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/ea07fe25eca5936e977edf6ff2dd15d6637e0e863ed70a9863c56c8eea07914da27d407f21b2dd87cb709c0941f6fdeb087a9ff694408149b6da88e8e63e1c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f0f20f1a3e29a1b16e5201c52f871b3d978265a30d3b80efa937d5122b6206f97be01b183faeef25f4b7a8f05a7ce6022db8e85ae831d691cc7cf7d6990f80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c141bd87be9bdf6a4715b7be263c83bb27b82f1a12b977ef72d48a166dbec6e683cc914a633d2e5d38492307da930323e52d67fb675ce174e3af5a03655cc0b8"} build_url:https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/JkVRd_SMRJ6eaMKnrjawOg/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/ea07fe25eca5936e977edf6ff2dd15d6637e0e863ed70a9863c56c8eea07914da27d407f21b2dd87cb709c0941f6fdeb087a9ff694408149b6da88e8e63e1c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f0f20f1a3e29a1b16e5201c52f871b3d978265a30d3b80efa937d5122b6206f97be01b183faeef25f4b7a8f05a7ce6022db8e85ae831d691cc7cf7d6990f80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c141bd87be9bdf6a4715b7be263c83bb27b82f1a12b977ef72d48a166dbec6e683cc914a633d2e5d38492307da930323e52d67fb675ce174e3af5a03655cc0b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 07:46:34.355242) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:46:34.355547) ========= 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.BNPTfTCTEm/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.0yEDB24krq/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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.004981 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:46:34.402923) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 07:46:34.403206) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 07:46:34.403503) ========= ========= Total master_lag: 0.16 =========