builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0008 starttime: 1464124093.09 results: success (0) buildid: 20160524121141 builduid: bf73eb0b4cd4495eadfdce1a47397e59 revision: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.087601) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.088145) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.088437) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.157270) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.157664) ========= 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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:08:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-05-24 14:08:13 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.458889 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.665374) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.665705) ========= 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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.020760 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:13.731881) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 14:08:13.732254) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7U7oVFRIq0/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.TTaSIb0gKH/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:08:13,802 truncating revision to first 12 chars 2016-05-24 14:08:13,802 Setting DEBUG logging. 2016-05-24 14:08:13,802 attempt 1/10 2016-05-24 14:08:13,802 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5511d54a3f17?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 14:08:15,361 unpacking tar archive at: mozilla-central-5511d54a3f17/testing/mozharness/ program finished with exit code 0 elapsedTime=1.814237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 14:08:15.566568) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:15.566867) ========= script_repo_revision: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:15.567246) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:15.567529) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 14:08:15.583391) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 54 secs) (at 2016-05-24 14:08:15.583682) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7U7oVFRIq0/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.TTaSIb0gKH/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:08:15 INFO - MultiFileLogger online at 20160524 14:08:15 in /builds/slave/test 14:08:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:08:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:08:15 INFO - {'append_to_log': False, 14:08:15 INFO - 'base_work_dir': '/builds/slave/test', 14:08:15 INFO - 'blob_upload_branch': 'Firefox', 14:08:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:08:15 INFO - 'branch': 'Firefox', 14:08:15 INFO - 'buildbot_json_path': 'buildprops.json', 14:08:15 INFO - 'config_files': ('talos/mac_config.py',), 14:08:15 INFO - 'default_actions': ('clobber', 14:08:15 INFO - 'read-buildbot-config', 14:08:15 INFO - 'download-and-extract', 14:08:15 INFO - 'populate-webroot', 14:08:15 INFO - 'create-virtualenv', 14:08:15 INFO - 'install', 14:08:15 INFO - 'run-tests'), 14:08:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:08:15 INFO - 'download_minidump_stackwalk': True, 14:08:15 INFO - 'download_symbols': 'ondemand', 14:08:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:08:15 INFO - 'tooltool.py': '/tools/tooltool.py', 14:08:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:08:15 INFO - '/tools/misc-python/virtualenv.py')}, 14:08:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:08:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:08:15 INFO - 'installer_path': 'installer.exe', 14:08:15 INFO - 'log_level': 'info', 14:08:15 INFO - 'log_name': 'talos', 14:08:15 INFO - 'log_to_console': True, 14:08:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:08:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:08:15 INFO - 'opt_config_files': (), 14:08:15 INFO - 'pip_index': False, 14:08:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:08:15 INFO - 'cmd': ('bash', 14:08:15 INFO - '-c', 14:08:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:08:15 INFO - 'enabled': True, 14:08:15 INFO - 'halt_on_failure': False, 14:08:15 INFO - 'name': 'check_screen_resolution'},), 14:08:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:08:15 INFO - 'cmd': ('bash', 14:08:15 INFO - '-c', 14:08:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:08:15 INFO - 'enabled': True, 14:08:15 INFO - 'halt_on_failure': False, 14:08:15 INFO - 'name': 'check_screen_resolution'},), 14:08:15 INFO - 'run_cmd_checks_enabled': True, 14:08:15 INFO - 'sps_profile': False, 14:08:15 INFO - 'sps_profile_interval': 0, 14:08:15 INFO - 'suite': 'dromaeojs', 14:08:15 INFO - 'system_bits': '32', 14:08:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:08:15 INFO - 'title': 't-yosemite-r7-0008', 14:08:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:08:15 INFO - 'use_talos_json': True, 14:08:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:08:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:08:15 INFO - 'work_dir': 'build'} 14:08:15 INFO - ##### 14:08:15 INFO - ##### Running clobber step. 14:08:15 INFO - ##### 14:08:15 INFO - Running pre-action listener: _resource_record_pre_action 14:08:15 INFO - Running main action method: clobber 14:08:15 INFO - rmtree: /builds/slave/test/build 14:08:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:08:16 INFO - Running post-action listener: _resource_record_post_action 14:08:16 INFO - ##### 14:08:16 INFO - ##### Finished clobber step (success) 14:08:16 INFO - ##### 14:08:16 INFO - ##### 14:08:16 INFO - ##### Running read-buildbot-config step. 14:08:16 INFO - ##### 14:08:16 INFO - Running pre-action listener: _resource_record_pre_action 14:08:16 INFO - Running main action method: read_buildbot_config 14:08:16 INFO - Using buildbot properties: 14:08:16 INFO - { 14:08:16 INFO - "project": "", 14:08:16 INFO - "product": "firefox", 14:08:16 INFO - "script_repo_revision": "production", 14:08:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:08:16 INFO - "repository": "", 14:08:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:08:16 INFO - "buildid": "20160524121141", 14:08:16 INFO - "pgo_build": "False", 14:08:16 INFO - "basedir": "/builds/slave/test", 14:08:16 INFO - "buildnumber": 252, 14:08:16 INFO - "slavename": "t-yosemite-r7-0008", 14:08:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:08:16 INFO - "platform": "yosemite_r7", 14:08:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:08:16 INFO - "branch": "mozilla-central", 14:08:16 INFO - "repo_path": "mozilla-central", 14:08:16 INFO - "revision": "5511d54a3f172c1d68f98cc55dce4de1d0ba1b51", 14:08:16 INFO - "stage_platform": "macosx64", 14:08:16 INFO - "builduid": "bf73eb0b4cd4495eadfdce1a47397e59", 14:08:16 INFO - "slavebuilddir": "test" 14:08:16 INFO - } 14:08:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:08:16 INFO - Running post-action listener: _resource_record_post_action 14:08:16 INFO - ##### 14:08:16 INFO - ##### Finished read-buildbot-config step (success) 14:08:16 INFO - ##### 14:08:16 INFO - ##### 14:08:16 INFO - ##### Running download-and-extract step. 14:08:16 INFO - ##### 14:08:16 INFO - Running pre-action listener: _resource_record_pre_action 14:08:16 INFO - Running main action method: download_and_extract 14:08:16 INFO - mkdir: /builds/slave/test/build/tests 14:08:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:16 INFO - https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:08:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:08:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:08:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:08:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:08:37 INFO - Downloaded 1199 bytes. 14:08:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:08:37 INFO - Using the following test package requirements: 14:08:37 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:08:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:08:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:08:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'jsshell-mac.zip'], 14:08:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:08:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:08:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:08:37 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:08:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:08:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:37 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:08:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:37 INFO - https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:08:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:08:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:08:47 INFO - Downloaded 18632928 bytes. 14:08:47 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'] 14:08:47 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 14:08:51 INFO - Return code: 0 14:08:51 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 14:08:51 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')]} 14:08:51 INFO - https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:08:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:09:02 INFO - Downloaded 18632928 bytes. 14:09:02 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'] 14:09:02 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 14:09:06 INFO - Return code: 0 14:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:06 INFO - https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:09:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:09:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:09:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:09:08 INFO - Downloaded 10840359 bytes. 14:09:08 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'] 14:09:08 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 14:09:08 INFO - Return code: 0 14:09: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')]} 14:09:08 INFO - https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:09:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:09:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:09:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PWhWo3FBTfCB5-RFy72yFg/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 14:09:15 INFO - Downloaded 110247765 bytes. 14:09:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:09:15 INFO - mkdir: /builds/slave/test/properties 14:09:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:09:15 INFO - Writing to file /builds/slave/test/properties/build_url 14:09:15 INFO - Contents: 14:09:15 INFO - build_url:https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:09:16 INFO - Running post-action listener: _resource_record_post_action 14:09:16 INFO - Running post-action listener: set_extra_try_arguments 14:09:16 INFO - ##### 14:09:16 INFO - ##### Finished download-and-extract step (success) 14:09:16 INFO - ##### 14:09:16 INFO - ##### 14:09:16 INFO - ##### Running populate-webroot step. 14:09:16 INFO - ##### 14:09:16 INFO - Running pre-action listener: _resource_record_pre_action 14:09:16 INFO - Running main action method: populate_webroot 14:09:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:09:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:09:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:09:16 INFO - '--tppagecycles', 14:09:16 INFO - '7'], 14:09:16 INFO - 'tests': ['tsvgm']}}, 14:09:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:09:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:09:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:09:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:09:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['damp', 'tps']}, 14:09:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'tests': ['damp', 'tps']}, 14:09:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['dromaeo_dom']}, 14:09:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:09:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['a11yr', 14:09:16 INFO - 'ts_paint', 14:09:16 INFO - 'tpaint', 14:09:16 INFO - 'sessionrestore', 14:09:16 INFO - 'sessionrestore_no_auto_restore', 14:09:16 INFO - 'tabpaint']}, 14:09:16 INFO - 'other-e10s': {'tests': ['a11yr', 14:09:16 INFO - 'ts_paint', 14:09:16 INFO - 'tpaint', 14:09:16 INFO - 'sessionrestore', 14:09:16 INFO - 'sessionrestore_no_auto_restore', 14:09:16 INFO - 'tabpaint']}, 14:09:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:09:16 INFO - 'ts_paint', 14:09:16 INFO - 'tpaint', 14:09:16 INFO - 'sessionrestore', 14:09:16 INFO - 'sessionrestore_no_auto_restore', 14:09:16 INFO - 'tabpaint']}, 14:09:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:09:16 INFO - 'ts_paint', 14:09:16 INFO - 'tpaint', 14:09:16 INFO - 'sessionrestore', 14:09:16 INFO - 'sessionrestore_no_auto_restore', 14:09:16 INFO - 'tabpaint']}, 14:09:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['a11yr', 14:09:16 INFO - 'ts_paint', 14:09:16 INFO - 'tpaint', 14:09:16 INFO - 'sessionrestore', 14:09:16 INFO - 'sessionrestore_no_auto_restore', 14:09:16 INFO - 'tabpaint']}, 14:09:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['a11yr', 14:09:16 INFO - 'ts_paint', 14:09:16 INFO - 'tpaint', 14:09:16 INFO - 'sessionrestore', 14:09:16 INFO - 'sessionrestore_no_auto_restore', 14:09:16 INFO - 'tabpaint']}, 14:09:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['tsvgx', 14:09:16 INFO - 'tsvgr_opacity', 14:09:16 INFO - 'tart', 14:09:16 INFO - 'tscrollx', 14:09:16 INFO - 'cart']}, 14:09:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:09:16 INFO - 'tsvgr_opacity', 14:09:16 INFO - 'tart', 14:09:16 INFO - 'tscrollx', 14:09:16 INFO - 'cart']}, 14:09:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'talos_options': ['--disable-e10s'], 14:09:16 INFO - 'tests': ['tp5o']}, 14:09:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'tests': ['tp5o']}, 14:09:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'talos_options': ['--disable-e10s', 14:09:16 INFO - '--xperf_path', 14:09:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:16 INFO - 'tests': ['tp5n']}, 14:09:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:16 INFO - 'talos_options': ['--xperf_path', 14:09:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:16 INFO - 'tests': ['tp5n']}}, 14:09:16 INFO - 'talos.zip': {'path': '', 14:09:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:09:16 INFO - Running post-action listener: _resource_record_post_action 14:09:16 INFO - ##### 14:09:16 INFO - ##### Finished populate-webroot step (success) 14:09:16 INFO - ##### 14:09:16 INFO - ##### 14:09:16 INFO - ##### Running create-virtualenv step. 14:09:16 INFO - ##### 14:09:16 INFO - Running pre-action listener: _resource_record_pre_action 14:09:16 INFO - Running main action method: create_virtualenv 14:09:16 INFO - Creating virtualenv /builds/slave/test/build/venv 14:09:16 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 14:09:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:09:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:09:17 INFO - Using real prefix '/tools/python27' 14:09:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:09:17 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:20 INFO - Installing pip.................done. 14:09:20 INFO - Return code: 0 14:09:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 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')]} 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 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 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:21 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 14:09:21 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 14:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:21 INFO - 'HOME': '/Users/cltbld', 14:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:21 INFO - 'LOGNAME': 'cltbld', 14:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:21 INFO - 'MOZ_NO_REMOTE': '1', 14:09:21 INFO - 'NO_EM_RESTART': '1', 14:09:21 INFO - 'PAGER': '/bin/cat', 14:09:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:21 INFO - 'PWD': '/builds/slave/test', 14:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:21 INFO - 'SHELL': '/bin/bash', 14:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:21 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:21 INFO - 'USER': 'cltbld', 14:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:21 INFO - 'XPC_FLAGS': '0x0', 14:09:21 INFO - 'XPC_SERVICE_NAME': '0', 14:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:21 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 14:09:21 INFO - Cleaning up... 14:09:21 INFO - Return code: 0 14:09:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:09:21 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')]} 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 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')]} 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 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 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:21 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 14:09:21 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 14:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:21 INFO - 'HOME': '/Users/cltbld', 14:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:21 INFO - 'LOGNAME': 'cltbld', 14:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:21 INFO - 'MOZ_NO_REMOTE': '1', 14:09:21 INFO - 'NO_EM_RESTART': '1', 14:09:21 INFO - 'PAGER': '/bin/cat', 14:09:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:21 INFO - 'PWD': '/builds/slave/test', 14:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:21 INFO - 'SHELL': '/bin/bash', 14:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:21 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:21 INFO - 'USER': 'cltbld', 14:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:21 INFO - 'XPC_FLAGS': '0x0', 14:09:21 INFO - 'XPC_SERVICE_NAME': '0', 14:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:21 INFO - Downloading/unpacking psutil>=3.1.1 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:26 INFO - Installing collected packages: psutil 14:09:26 INFO - Running setup.py install for psutil 14:09:26 INFO - building 'psutil._psutil_osx' extension 14:09:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:09:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:09:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:09:26 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 14:09:26 INFO - building 'psutil._psutil_posix' extension 14:09:26 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 14:09:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:09:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:09:26 INFO - ^ 14:09:26 INFO - 1 warning generated. 14:09:26 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 14:09:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:27 INFO - Successfully installed psutil 14:09:27 INFO - Cleaning up... 14:09:27 INFO - Return code: 0 14:09:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:09:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:27 INFO - 'HOME': '/Users/cltbld', 14:09:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:27 INFO - 'LOGNAME': 'cltbld', 14:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:27 INFO - 'MOZ_NO_REMOTE': '1', 14:09:27 INFO - 'NO_EM_RESTART': '1', 14:09:27 INFO - 'PAGER': '/bin/cat', 14:09:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:27 INFO - 'PWD': '/builds/slave/test', 14:09:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:27 INFO - 'SHELL': '/bin/bash', 14:09:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:27 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:27 INFO - 'USER': 'cltbld', 14:09:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:27 INFO - 'XPC_FLAGS': '0x0', 14:09:27 INFO - 'XPC_SERVICE_NAME': '0', 14:09:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:09:29 INFO - Installing collected packages: mozsystemmonitor 14:09:29 INFO - Running setup.py install for mozsystemmonitor 14:09:30 INFO - Successfully installed mozsystemmonitor 14:09:30 INFO - Cleaning up... 14:09:30 INFO - Return code: 0 14:09:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:09:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:30 INFO - 'HOME': '/Users/cltbld', 14:09:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:30 INFO - 'LOGNAME': 'cltbld', 14:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:30 INFO - 'MOZ_NO_REMOTE': '1', 14:09:30 INFO - 'NO_EM_RESTART': '1', 14:09:30 INFO - 'PAGER': '/bin/cat', 14:09:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:30 INFO - 'PWD': '/builds/slave/test', 14:09:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:30 INFO - 'SHELL': '/bin/bash', 14:09:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:30 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:30 INFO - 'USER': 'cltbld', 14:09:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:30 INFO - 'XPC_FLAGS': '0x0', 14:09:30 INFO - 'XPC_SERVICE_NAME': '0', 14:09:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:30 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:32 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:32 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 14:09:32 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 14:09:32 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 14:09:32 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 14:09:32 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 14:09:32 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 14:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:09:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:33 INFO - Downloading docopt-0.6.1.tar.gz 14:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:34 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:34 INFO - Running setup.py install for blobuploader 14:09:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:09:34 INFO - Running setup.py install for requests 14:09:34 INFO - Running setup.py install for docopt 14:09:34 INFO - Successfully installed blobuploader requests docopt 14:09:34 INFO - Cleaning up... 14:09:34 INFO - Return code: 0 14:09:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:34 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 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:34 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 14:09:34 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 14:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:34 INFO - 'HOME': '/Users/cltbld', 14:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:34 INFO - 'LOGNAME': 'cltbld', 14:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:34 INFO - 'MOZ_NO_REMOTE': '1', 14:09:34 INFO - 'NO_EM_RESTART': '1', 14:09:34 INFO - 'PAGER': '/bin/cat', 14:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:34 INFO - 'PWD': '/builds/slave/test', 14:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:34 INFO - 'SHELL': '/bin/bash', 14:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:34 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:34 INFO - 'USER': 'cltbld', 14:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:34 INFO - 'XPC_FLAGS': '0x0', 14:09:34 INFO - 'XPC_SERVICE_NAME': '0', 14:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-bPIjRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-616JIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-woQExf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-PPvEwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Hgt1Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ZWCrKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-f1SmGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:35 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-rus7tX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Kv8d_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-_33TrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-c_gOUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-jq77tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-sXROUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-nZXRNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-lsMalc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-WJismz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:36 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-tXmCra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:36 INFO - Running setup.py install for manifestparser 14:09:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:09:37 INFO - Running setup.py install for mozcrash 14:09:37 INFO - Running setup.py install for mozdebug 14:09:37 INFO - Running setup.py install for mozdevice 14:09:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:09:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:09:37 INFO - Running setup.py install for mozfile 14:09:37 INFO - Running setup.py install for mozhttpd 14:09:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:09:37 INFO - Running setup.py install for mozinfo 14:09:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Running setup.py install for mozInstall 14:09:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Running setup.py install for mozleak 14:09:38 INFO - Running setup.py install for mozlog 14:09:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Running setup.py install for moznetwork 14:09:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Running setup.py install for mozprocess 14:09:38 INFO - Running setup.py install for mozprofile 14:09:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Running setup.py install for mozrunner 14:09:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:09:39 INFO - Running setup.py install for mozscreenshot 14:09:39 INFO - Running setup.py install for moztest 14:09:39 INFO - Running setup.py install for mozversion 14:09:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:09:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:39 INFO - Cleaning up... 14:09:39 INFO - Return code: 0 14:09:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:39 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')]} 14:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:39 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')]} 14:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:39 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 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:39 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 14:09:39 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 14:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:39 INFO - 'HOME': '/Users/cltbld', 14:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:39 INFO - 'LOGNAME': 'cltbld', 14:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:39 INFO - 'MOZ_NO_REMOTE': '1', 14:09:39 INFO - 'NO_EM_RESTART': '1', 14:09:39 INFO - 'PAGER': '/bin/cat', 14:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:39 INFO - 'PWD': '/builds/slave/test', 14:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:39 INFO - 'SHELL': '/bin/bash', 14:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:39 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:39 INFO - 'USER': 'cltbld', 14:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:39 INFO - 'XPC_FLAGS': '0x0', 14:09:39 INFO - 'XPC_SERVICE_NAME': '0', 14:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:39 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-SQI_QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:39 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)) 14:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:39 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-N7XDUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-qE9XJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Gg4Op5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-L5OpPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:40 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)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OtaaLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:40 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)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-jTyw04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:40 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)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-UjAI0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:40 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)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-7suAWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:40 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)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-U2wK8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:40 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)) 14:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:40 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-bnP88M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-LzdoMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-rmM6UR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-eKs6_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:41 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)) 14:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-gKjjrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:41 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)) 14:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-taXMtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:41 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)) 14:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ZlkYps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:41 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)) 14:09:41 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)) 14:09:41 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)) 14:09:41 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)) 14:09:41 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)) 14:09:41 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)) 14:09:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:09:41 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 14:09:41 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 14:09:41 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 14:09:41 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 14:09:41 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 14:09:41 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 14:09:44 INFO - Downloading blessings-1.6.tar.gz 14:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:09:44 INFO - Installing collected packages: blessings 14:09:44 INFO - Running setup.py install for blessings 14:09:44 INFO - Successfully installed blessings 14:09:44 INFO - Cleaning up... 14:09:44 INFO - Return code: 0 14:09:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:09:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:44 INFO - Reading from file tmpfile_stdout 14:09:44 INFO - Current package versions: 14:09:44 INFO - blessings == 1.6 14:09:44 INFO - blobuploader == 1.2.4 14:09:44 INFO - docopt == 0.6.1 14:09:44 INFO - manifestparser == 1.1 14:09:44 INFO - mozInstall == 1.12 14:09:44 INFO - mozcrash == 0.17 14:09:44 INFO - mozdebug == 0.1 14:09:44 INFO - mozdevice == 0.48 14:09:44 INFO - mozfile == 1.2 14:09:44 INFO - mozhttpd == 0.7 14:09:44 INFO - mozinfo == 0.9 14:09:44 INFO - mozleak == 0.1 14:09:44 INFO - mozlog == 3.2 14:09:44 INFO - moznetwork == 0.27 14:09:44 INFO - mozprocess == 0.22 14:09:44 INFO - mozprofile == 0.28 14:09:44 INFO - mozrunner == 6.11 14:09:44 INFO - mozscreenshot == 0.1 14:09:44 INFO - mozsystemmonitor == 0.3 14:09:44 INFO - moztest == 0.7 14:09:44 INFO - mozversion == 1.4 14:09:44 INFO - psutil == 3.1.1 14:09:44 INFO - requests == 1.2.3 14:09:44 INFO - wsgiref == 0.1.2 14:09:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:44 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 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:44 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 14:09:44 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 14:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:44 INFO - 'HOME': '/Users/cltbld', 14:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:44 INFO - 'LOGNAME': 'cltbld', 14:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:44 INFO - 'MOZ_NO_REMOTE': '1', 14:09:44 INFO - 'NO_EM_RESTART': '1', 14:09:44 INFO - 'PAGER': '/bin/cat', 14:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:44 INFO - 'PWD': '/builds/slave/test', 14:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:44 INFO - 'SHELL': '/bin/bash', 14:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:44 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:44 INFO - 'USER': 'cltbld', 14:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:44 INFO - 'XPC_FLAGS': '0x0', 14:09:44 INFO - 'XPC_SERVICE_NAME': '0', 14:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 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)) 14:09:44 INFO - Cleaning up... 14:09:45 INFO - Return code: 0 14:09:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:45 INFO - 'HOME': '/Users/cltbld', 14:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:45 INFO - 'LOGNAME': 'cltbld', 14:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:45 INFO - 'MOZ_NO_REMOTE': '1', 14:09:45 INFO - 'NO_EM_RESTART': '1', 14:09:45 INFO - 'PAGER': '/bin/cat', 14:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:45 INFO - 'PWD': '/builds/slave/test', 14:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:45 INFO - 'SHELL': '/bin/bash', 14:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:45 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:45 INFO - 'USER': 'cltbld', 14:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:45 INFO - 'XPC_FLAGS': '0x0', 14:09:45 INFO - 'XPC_SERVICE_NAME': '0', 14:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:45 INFO - Downloading/unpacking jsonschema 14:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:47 INFO - Downloading/unpacking functools32 (from jsonschema) 14:09:47 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 14:09:47 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 14:09:47 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 14:09:47 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 14:09:47 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 14:09:47 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 14:09:48 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:09:48 INFO - warning: no files found matching '*.txt' 14:09:48 INFO - no previously-included directories found matching 'build' 14:09:48 INFO - no previously-included directories found matching 'dist' 14:09:48 INFO - no previously-included directories found matching '.git*' 14:09:48 INFO - Installing collected packages: jsonschema, functools32 14:09:48 INFO - Running setup.py install for functools32 14:09:48 INFO - warning: no files found matching '*.txt' 14:09:48 INFO - no previously-included directories found matching 'build' 14:09:48 INFO - no previously-included directories found matching 'dist' 14:09:48 INFO - no previously-included directories found matching '.git*' 14:09:48 INFO - Successfully installed jsonschema functools32 14:09:48 INFO - Cleaning up... 14:09:48 INFO - Return code: 0 14:09:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:48 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 0x1008cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9fa48ed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, '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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 14:09:48 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 14:09:48 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 14:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:48 INFO - 'HOME': '/Users/cltbld', 14:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:48 INFO - 'LOGNAME': 'cltbld', 14:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:48 INFO - 'MOZ_NO_REMOTE': '1', 14:09:48 INFO - 'NO_EM_RESTART': '1', 14:09:48 INFO - 'PAGER': '/bin/cat', 14:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:48 INFO - 'PWD': '/builds/slave/test', 14:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:48 INFO - 'SHELL': '/bin/bash', 14:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:09:48 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:48 INFO - 'USER': 'cltbld', 14:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:48 INFO - 'XPC_FLAGS': '0x0', 14:09:48 INFO - 'XPC_SERVICE_NAME': '0', 14:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:48 INFO - Downloading/unpacking flake8 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:09:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:09:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:09:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:52 INFO - no previously-included directories found matching 'docs/_build' 14:09:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:09:52 INFO - Running setup.py install for pep8 14:09:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:52 INFO - no previously-included directories found matching 'docs/_build' 14:09:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:09:52 INFO - Cleaning up... 14:09:52 INFO - Return code: 0 14:09:52 INFO - Running post-action listener: _resource_record_post_action 14:09:52 INFO - Running post-action listener: _start_resource_monitoring 14:09:52 INFO - Starting resource monitoring. 14:09:52 INFO - ##### 14:09:52 INFO - ##### Finished create-virtualenv step (success) 14:09:52 INFO - ##### 14:09:52 INFO - ##### 14:09:52 INFO - ##### Running install step. 14:09:52 INFO - ##### 14:09:52 INFO - Running pre-action listener: _resource_record_pre_action 14:09:52 INFO - Running main action method: install 14:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:53 INFO - Reading from file tmpfile_stdout 14:09:53 INFO - Detecting whether we're running mozinstall >=1.0... 14:09:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:09:53 INFO - Reading from file tmpfile_stdout 14:09:53 INFO - Output received: 14:09:53 INFO - Usage: mozinstall [options] installer 14:09:53 INFO - Options: 14:09:53 INFO - -h, --help show this help message and exit 14:09:53 INFO - -d DEST, --destination=DEST 14:09:53 INFO - Directory to install application into. [default: 14:09:53 INFO - "/builds/slave/test"] 14:09:53 INFO - --app=APP Application being installed. [default: firefox] 14:09:53 INFO - mkdir: /builds/slave/test/build/application 14:09:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:10:27 INFO - Reading from file tmpfile_stdout 14:10:27 INFO - Output received: 14:10:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:10:27 INFO - Running post-action listener: _resource_record_post_action 14:10:27 INFO - ##### 14:10:27 INFO - ##### Finished install step (success) 14:10:27 INFO - ##### 14:10:27 INFO - ##### 14:10:27 INFO - ##### Running run-tests step. 14:10:27 INFO - ##### 14:10:27 INFO - Running pre-action listener: _resource_record_pre_action 14:10:27 INFO - Running main action method: run_tests 14:10:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:10:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:10:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:10:27 INFO - 2016-05-24 14:10:27.840 screenresolution[10810:201039] starting screenresolution argv=screenresolution get 14:10:27 INFO - 2016-05-24 14:10:27.842 screenresolution[10810:201039] Display 0: 1600x1200x32@60 14:10:27 INFO - 2016-05-24 14:10:27.848 screenresolution[10811:201041] starting screenresolution argv=screenresolution list 14:10:27 INFO - Available Modes on Display 0 14:10:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:10:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:10:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:10:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:10:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:10:28 INFO - Intel Iris: 14:10:28 INFO - Chipset Model: Intel Iris 14:10:28 INFO - Type: GPU 14:10:28 INFO - Bus: Built-In 14:10:28 INFO - VRAM (Dynamic, Max): 1536 MB 14:10:28 INFO - Vendor: Intel (0x8086) 14:10:28 INFO - Device ID: 0x0a2e 14:10:28 INFO - Revision ID: 0x0009 14:10:28 INFO - Displays: 14:10:28 INFO - Display: 14:10:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:10:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:10:28 INFO - Display Serial Number: 1600x1200 60 14:10:28 INFO - Main Display: Yes 14:10:28 INFO - Mirror: Off 14:10:28 INFO - Online: Yes 14:10:28 INFO - Rotation: Supported 14:10:28 INFO - Return code: 0 14:10:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:10:28 INFO - Python 2.7.3 14:10:28 INFO - Return code: 0 14:10:28 INFO - grabbing minidump binary from tooltool 14:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:10:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100771d30>, '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 0x100595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100768b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:10:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:10:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:10:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:10:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:10:28 INFO - Return code: 0 14:10:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:10:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:10:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:10:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:10:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:10:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:10:29 INFO - Return code: 0 14:10:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 14:10:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0008 --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 14:10:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7U7oVFRIq0/Render', 14:10:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:29 INFO - 'HOME': '/Users/cltbld', 14:10:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:29 INFO - 'LOGNAME': 'cltbld', 14:10:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:10:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:29 INFO - 'MOZ_NO_REMOTE': '1', 14:10:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:10:29 INFO - 'NO_EM_RESTART': '1', 14:10:29 INFO - 'PAGER': '/bin/cat', 14:10:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:29 INFO - 'PWD': '/builds/slave/test', 14:10:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:10:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:29 INFO - 'SHELL': '/bin/bash', 14:10:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TTaSIb0gKH/Listeners', 14:10:29 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 14:10:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:29 INFO - 'USER': 'cltbld', 14:10:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:29 INFO - 'XPC_FLAGS': '0x0', 14:10:29 INFO - 'XPC_SERVICE_NAME': '0', 14:10:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 14:10:29 INFO - mozversion application_buildid: 20160524121141 14:10:29 INFO - mozversion application_changeset: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 14:10:29 INFO - mozversion application_display_name: Nightly 14:10:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:10:29 INFO - mozversion application_name: Firefox 14:10:29 INFO - mozversion application_remotingname: firefox 14:10:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:10:29 INFO - mozversion application_vendor: Mozilla 14:10:29 INFO - mozversion application_version: 49.0a1 14:10:29 INFO - mozversion platform_buildid: 20160524121141 14:10:29 INFO - mozversion platform_changeset: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 14:10:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:10:29 INFO - mozversion platform_version: 49.0a1 14:10:29 INFO - using testdate: 1464124229 14:10:29 INFO - actual date: 1464124229 14:10:29 INFO - starting webserver on 'localhost:64431' 14:10:29 INFO - SUITE-START | Running 2 tests 14:10:29 INFO - TEST-START | dromaeo_css 14:10:29 INFO - operating with platform_type : mac_ 14:10:29 INFO - Initialising browser for dromaeo_css test... 14:10:29 INFO - TEST-INFO | started process 10829 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpeppJmP/profile http://localhost:64431/getInfo.html) 14:10:31 INFO - PROCESS | 10829 | __metrics Screen width/height:1600/1200 14:10:31 INFO - PROCESS | 10829 | colorDepth:24 14:10:31 INFO - PROCESS | 10829 | Browser inner width/height: 1024/689 14:10:31 INFO - PROCESS | 10829 | __metrics 14:10:31 INFO - PROCESS | 10829 | 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" 14:10:31 ERROR - PROCESS | 10829 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:10:32 INFO - TEST-INFO | 10829: exit 0 14:10:32 INFO - Browser initialized. 14:10:32 INFO - Running cycle 1/1 for dromaeo_css test... 14:10:32 INFO - TEST-INFO | started process 10832 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpeppJmP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:10:44 INFO - PROCESS | 10832 | RSS: Main: 239136768 14:10:44 INFO - PROCESS | 10832 | 14:11:36 INFO - PROCESS | 10832 | 1464124296132 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] 14:13:46 INFO - PROCESS | 10832 | Cycle 1(1): loaded http://localhost:64431/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64431/tests/dromaeo/cssquery-ext.html) 14:13:47 INFO - PROCESS | 10832 | RSS: Main: 328962048 14:13:47 INFO - PROCESS | 10832 | 14:16:50 INFO - PROCESS | 10832 | Cycle 1(1): loaded http://localhost:64431/tests/dromaeo/cssquery-ext.html (next: http://localhost:64431/tests/dromaeo/cssquery-jquery.html) 14:16:50 INFO - PROCESS | 10832 | RSS: Main: 375595008 14:16:50 INFO - PROCESS | 10832 | 14:19:52 INFO - PROCESS | 10832 | Cycle 1(1): loaded http://localhost:64431/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64431/tests/dromaeo/cssquery-mootools.html) 14:19:53 INFO - PROCESS | 10832 | RSS: Main: 371724288 14:19:53 INFO - PROCESS | 10832 | 14:22:55 INFO - PROCESS | 10832 | Cycle 1(1): loaded http://localhost:64431/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64431/tests/dromaeo/cssquery-prototype.html) 14:22:56 INFO - PROCESS | 10832 | RSS: Main: 368635904 14:22:56 INFO - PROCESS | 10832 | 14:25:59 INFO - PROCESS | 10832 | Cycle 1(1): loaded http://localhost:64431/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64431/tests/dromaeo/cssquery-yui.html) 14:26:00 INFO - PROCESS | 10832 | RSS: Main: 340865024 14:26:00 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | Cycle 1(1): loaded http://localhost:64431/tests/dromaeo/cssquery-yui.html (next: http://localhost:64431/tests/dromaeo/cssquery-dojo.html) 14:29:03 INFO - PROCESS | 10832 | RSS: Main: 413839360 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | __start_tp_report 14:29:03 INFO - PROCESS | 10832 | _x_x_mozilla_page_load 14:29:03 INFO - PROCESS | 10832 | _x_x_mozilla_page_load_details 14:29:03 INFO - PROCESS | 10832 | |i|pagename|runs| 14:29:03 INFO - PROCESS | 10832 | |0;dojo.html;3390.949135762964;3398.337324221777;3421.4867769834505;3423.452247640377;3471.6701913318225;5604.439556044404;5647.774089036444;5649.915251271229;5736.885262294752;5759.798407055754;5696.11710184921;5721.914171287434;5763.510101641355;5811.651300921948;5815.709214539277;5240.004399164156;5270;5346.946530534684;5359.598030147735;5365.946340536603;5284.550813180865;5352.91970620441;5355.4912283333215;5361.919571206435;5363.410024897258;5200.349956255468;5373.435789242112;5417.024935511626;5429.104197807357;5432.320959880015;5159.329287192679;5190.662606930575;5216.982688375768;5240.2401651760665;5252;5147.22791581262;5175.146100893348;5179.430262671103;5200.557952573998;5220.556252718486;5566.944330556683;5632.183333416631;5641.097424412074;5644;5663.348714897798;6087.026075827891;6136.723847426876;6178.826023055605;6224.221972253468;6245.843853903643;5292.9735351323;6243.875122497525;6272.623642581414;6296.905546416808;6319.526035547352;5859.619124756877;5862.472377486047;5876.500497457721;5906.822795316148;5918.289805223358;6171.660558669227;6197.349278325711;6243.937560624427;6269.122322874801;6287.559870808999;6162.075688646739;6167.537434692416;6172.722227499773;6182.381761823782;6194.969025154845;6126.877462450726;6127.908081378783;6129.1419201311855;6211.596246243979;6268.27914789805;5963.880722385528;5981.37195594465;6115.696722738819;6127.816165515042;6135.515116377874;6150.538709596798;6215.627062376181;6220.813375598739;6226.501879849601;6230.968845155745;4928.186849169847;4936.037472692862;4936.259561065797;4940.431850337257;4972.72650004246;4366.061296821199;4382.780860956939;4447.444069491314;4516.07420478801;4522.683412161117;5598.1602759586385;5638.097904335286;5664.122061080539;5678.205051292823;5683.460071293221;6188.1027251049245;6281.3404592518045;6295.968520157461;6298.08677741734;6303.432691057827;5622.578306626938;5665.680031094032;5687.573431992617;5709.971450142806;5739.966805974965;5298.470152984671;5351.384590772033;5403.243545903577;5408.080626293539;5414.972925135428;6212.844678883064;6324.715387807468;6331.620102793755;6343.111964324999;6348.238211414568;6268.811935641938;6284.4972402206795;6308.463780578702;6316.810495685137;6329;6198.946179149465;6207.317195108625;6226.283977342664;6325.588836725598;6339.746410143543;6241.032639940817;6241.750329986749;6250.656213908097;6256.655883926245;6284.4344009039405;6265.874682506415;6269.780557680459;6277.623342599459;6288.27684816252;6291.245050593958;5862;6037.396260374103;6055.576109672281;6075.848103797441;6092.817215483543;6034.939650603614;6051.878962420639;6080.452759251689;6096.634201947897;6099.634021958696;5908.202392677105;5927.170196172453;5927.31835838884;5933.584649074523;5970.910436343372;5894.086416605433;6053.606515576474;6080.817575472743;6117.204763380732;6152.415520525543;5974.492168165754;5983.640981541122;5994.55040871945;5998.4601385875485;6007.12896629995 14:29:03 INFO - PROCESS | 10832 | |1;ext.html;468.85365281885805;477.4056299906617;478.3303375274616;511.87970826855684;521.7391304347826;27505.44989100217;41233.3814992775;42070.36894446578;42339.941501462476;42403.727888163325;43745.562544374545;44282;44311.335329970076;44315.55684443154;44444;9505.904940950588;9652.98643642418;9655.806883862319;9690.951545242298;9731.708048758548;9477.95261023694;9525.142737153656;9542.522873856315;9657.71026869195;9684.418934863896;9570.712878613653;9619.519024048805;9655.662051828187;9687.70936871895;9697.95151024244;9608.279379046564;9666.661666841626;9669.081437263449;9676.903230967671;9684.75788105296;9459.621615135455;9535.713928582154;9577.521123943845;9582.664606738801;9608.991055939096;40444.797776011226;40625.59374406248;40792.38811417831;40844.1831163378;40923.590764092274;3448.2758620689683;3726.962730372689;3754.155315054118;3770.6794922431627;4070.1859628074208;28501.287467813265;28957.276068098254;29105.41789164205;29598.556021659693;29623.70376296231;2626.6716660417446;2694.90567830125;2709.7967652426146;2709.878055487508;2770.265879541923;517991;520347;520518.9999999962;521049.36851894506;521074;34039.489407658904;59634.70182649059;60052.39947600468;60447;60461.69769151126;34939.65060349415;36463.63536364656;36507;36547.63452365442;36603.08492287661;13808.723825523433;13982.790258146137;14067.155970641794;14165.78751318731;14208.360623771956;12376.566820161432;12479.500819967281;12484.18852774585;12491.812622810665;12497.50009999608;7889.132195458495;7908.248716371935;7953;7962.482438641469;8020.43856930021;3060.127863558875;3299.323638654118;3317.568716066944;3330.5004249362305;3344.6153692325206;735.9779206623809;924.5377311344328;1044.9341671495185;1082.8917108289108;1119.6641007697822;23693.644595331083;27985.020524281554;28081.859590702323;28512.857435713104;29435.705642943725;921.6543796076471;1050.7863417752508;1073.3345325897992;1105.0606984063643;1148.3741360958297;13159.775140537164;14054.929725351309;14882.181480018486;14937.327820248114;14992.25277380581;712351.3877123944;718411;721745;722095;722146;55764.72117639386;58353.70823145857;58837;59732.10401843976;60551.394486054574;1270.4092596942467;1309.0771006440627;1405.6977749783846;1405.943762249519;1434.3186986181481;29200;29438.969636062637;29457.852710736308;30544.541831872983;31391.21521961969;57807.843843122064;58003.12995305158;58258;58633.70683146728;58837.82324353574;545895;645224;646093;646271;649640;34914;41073.1785364297;42079.94800130021;42213.36679949742;42877.92659697324;44777.32834007427;45676;45977.31034034559;46236.8440788983;46794.29808552942;34555.65444345524;36884;37403.06492337604;38700;38776.260608589866;36440.635593643725;36890;37460.62539374681;37746.245075098894;37763.43354849734;26820.731792681825;36399.63600363962;36767;37059.62940370562;37608.87173384803;31036.534451982785;32399.70401183926;32426.189345266557;32675.673243268215;32675.836620816743;21530.569388611828;22800.771992279864;24221.273361799173;24514.877425612758;24726.258212253662 14:29:03 INFO - PROCESS | 10832 | |2;jquery.html;351.6834848636227;357.31930672069706;391.77472953052;403.34255164082543;407.618876350612;19718.901405492972;21728.674069888926;21984.120635174593;21996.450088747788;22150.77849221507;20005.59988800223;20519.589608207825;20822.791772082273;20863.895680521615;20890.05994730237;4834.9033019339595;5027.648064635477;5712.314522257335;5884.499817515516;5886.558508111887;5799.21710569072;5827.125931110325;5864.648121112726;5869.354371019206;5935.85160370992;5575.330960284771;5933.169356290123;5945.524358051345;5946.43508866657;5963.493103086243;5713.400092990218;5784.971075144597;5880.970595147019;5925.674087925162;5946.256717910262;5556.055470570012;5698.715064246771;5740.0815869460685;5746.942530574683;5747.942520574783;13300.268485233308;17171.82828171715;25244;25264;25818.22545323643;1923;1994.3917105282883;2006.7190593316948;2060.9793902060937;2285.8285628577755;8562.700305489278;8636.39545231834;9654.75863103421;10163.796724065478;10214.948925255327;1497.8951473396867;1637.5087473757826;1706.2407228783197;1711;1770.486558897918;101297.46756331077;122231;125008;126102;128314;11004;11095.83356249657;11406.486708098155;11424.371659558805;11561.30632162073;14516.782248266285;14629.195394253205;14755.040922340228;14771.482998095014;14782.852171478362;8576.356773242032;8583.05586385497;8624.094470080676;8652.610632521551;8739.825203495895;8491.448055876348;8537.615807288686;8626.309895208367;8630.482171069756;8664.826703466022;4485.775711214426;4547.431136257986;4561.292999585004;4575.290829921368;4596.770161491912;1893.7632795900513;1922.9711554326695;1927.2772710233662;1946.6496030714325;1970.881747095179;732.3847967707152;908.4322298563397;921.5023887101024;981.8036392721484;983.8179936711731;19412.611747765248;19962.60074798525;20176.697349539474;20283.79716202819;20461.181552737722;842.2840585502274;853.2619284319019;934.4486752815872;935.854942483916;946.7443790176751;10539.367637941748;10723;10772.353658780497;10807.189460790472;10811.729706757236;603179;604619;621956;622362;629053;123753;124646;134723;137658.62341376458;146116;946.4179529589253;990.3859607043535;1010.282699283517;1014.0974532665933;1014.2545229256638;17229.138543072546;17446.651066978844;17685.38101166453;17744;17867.553311167325;54200.45799541954;55160.44839551553;55288.72355638196;56608.71695641495;56703.71648141733;79351;79574.60212698899;80769.19230807616;81368.7794683092;82123;6845.315468453115;7724.497907635986;7789.376849851885;7854.45018848698;8182.140875208018;9254.398464099811;12023.098267629788;12425;12472.563460278847;12499.875001249871;5467.39858615544;8748.950125984922;10476.266661333939;10652.467376631292;10773.407462589319;5771.884562308815;8259.628316725642;10074.345167564084;10609.628662996758;10855.837162442413;8583.785405364915;10870.347779133277;10899.346039237671;11254.381009044253;11308.378039207922;7130.2869713028285;7222.74720384784;7361.079865016873;7455.515391499535;7586.317231449196;9682.370645907993;10749.731256718333;10800;10835.891641083803;10925.125989920945 14:29:03 INFO - PROCESS | 10832 | |3;mootools.html;1398.0143998481071;1543.9465510870543;1547.9721564750178;1800.3338764657071;1919.8176173263544;7797;8592.441491303061;8792.956035219831;8794.648214071429;8905.376623636332;8506.191911768372;8662.480251184934;8671.176238257356;8780.034196238425;8781.824363512726;2346.4603141277476;2374.121575017246;2378.4172877645;2378.750231225714;2380.5952987991996;1959.7648282206155;2344.402177444749;2356.2813341930705;2374.6912901322803;2376.0733314007553;2336.789688927996;2362.917297894574;2367.0413482539598;2371.81025517959;2372.383180373107;2294.9196778112855;2334.124703236286;2340.9648855267183;2348.941276468085;2354.411397150712;2047.4267205182573;2152.7298893652664;2228.8885555722313;2232.3414592695194;2256.0637335505908;7378.483506154571;8679.088695686925;8752.431091979;8761.51811650359;8800.779980500538;5248.606354523387;5453.045716999549;5456.127019676833;5456.836294911159;5456.91814622781;3458.602260740022;3567.108222944264;3604.8017359045243;3605.5853576838736;3637.0361854108683;1019.2050200843354;1035.1977217656301;1038.600138946511;1038.761084950458;1039.3140527252053;11456.71358216043;11505.194636375458;11613.825792613117;11616;11632;4519.706219095749;4591.081783643284;4606.861794146181;4681.391419115494;4690.788914498856;9122.498262595622;9229.815403692022;9244.90755092454;9252.8612070819;9260.814783704289;5181.4041385240425;5262.631615786858;5269.420363759983;5278.97360513195;5279.155335146435;4379.233634114017;4418.7127836690515;4562.680612357103;4906.337644418029;4915.90168196634;3820.8662696805477;3833.6933045356304;3877.728559000899;3889.1638297766117;3926.469926559935;1167.9532818687157;1174.6887074925153;1178.3165763856941;1180.5749930025183;1192.6183621241294;836.5942517878824;853.7908212487856;862.6980556805069;919.7424721077975;966.6278482784179;5000.149974504343;5481.502175040913;5672.688002159838;5787.942120578825;5924.978261086791;937.1986951156663;937.962481500746;967.8548217767391;975.2782940624029;1001.1440218612981;3542.6280240574883;3613.837377318027;3656.451532270181;3724.273766615538;3746.925061498755;10560.894391055992;10663.680089597325;10944.671659850217;10999.725006874893;11005;10740.355578665305;10926.071283940953;10932;10941.398223097647;10948.39783811882;6869.553479023847;8141.877871831927;8142.7557173284895;8347.54088525137;8356.791080222922;5615;5669.574781891457;5688.260526131577;5779.68211748341;5851.824445266649;9683;10035.397876127456;10086.142677872464;10228.590856365661;10288.948555257175;8542.06037335905;10124.645637402655;10312.968703129927;10396.896031039592;10406;7326.120865496175;7611.809704757426;7624.923750762643;7736.2650548197835;7737.961310193638;6967.512274140964;8094.15011423792;8221.753347399588;8346.916530834615;8391.538465384463;7368.410527157707;7481.962590187014;7511.9624401877645;7595.392368610387;7651.196624354363;7263.273672632695;7344.522606030591;7359.300866417681;7449.851002979802;7520;7035.753748618774;7043.542169758949;7119.715211391486;7128.536645118051;7134.357907788324;4735.644826637947;4928.2853986171085;4961.975190124026;4988.975055124701;5001.1498045331655;4882.6826256293225;4949.133901567139;4974.676646017997;5031.647784655039;5175.9741201293755 14:29:03 INFO - PROCESS | 10832 | |4;prototype.html;301.85360100351335;312.0606973011237;314.8032479700187;318.41888553390066;323.15656137481153;4536.364908912755;5287.656302340345;5287.748034958502;5312.9203061954;5319.787208511664;4459.063596644709;4669.953300467003;4941;4996;5256.237845512398;3828.617138286177;3899.9805000974957;3982.9885907616613;4098.7540747555095;4136.9793151034355;3349.1794510345;3698.500702405181;3959.663428608572;4023.7786921719294;4060.390941358805;3702.4816525686424;3959.6040395960463;3969;3972.4637173981578;4033.3748269018347;3262.5106234064842;3317.701406873381;3735.64511371422;3898.4152377143373;3991.2815693175216;3187.9043628691174;3367.922264875241;3473.6526347365316;3481.651834816523;3493.68556829884;4368.716033457815;5023.949760502384;5043.445221025684;5064.417591976933;5074.67014644047;3779.3386157422615;4255.170276780983;4288.421063156465;4509.233430316854;4562.840300589463;5020.573251273646;5401.648892821954;5454.945450545483;5473.781048758045;5501.972490137564;3386.5766779152605;3646.1249300168033;3845.903852403684;3871.2257548490416;3888.727789054767;5001.349824522788;5258.2638430619745;5760.884782304331;5775.422457754233;5792.957267691814;3176.888808891678;3878.146807702301;3902.1610353774076;3922.2743792398496;3935.370340745524;3672.485851980725;3955.64399204073;3973.384125460558;3993.520777506718;4012.9333393302472;4546;4567.588916997486;4596.3105534170145;4598.885027874263;4617.676762626651;4594.379758732528;4955.82654607087;5073.289739436482;5177.168895017735;5506.68719137963;1263.5198624522623;1350.9327631171266;1359.5649392194402;1361.1900918953206;1369.3906211735682;732.9409003989184;735.4704612678863;803.8191406933394;815.1318845429598;845.0830848529414;4880.243562247857;4962.255661650709;5025.723585202776;5163.741812909415;5213.504717051874;5006.723285562159;5453.781848726086;5710.942890571041;5711.543076553866;5735;3912.5109361329833;4529.977350113229;4581.954180458153;4956.405231372259;5126.923096153486;2847.174319447337;2912.417516496709;2970.5841182234503;3014.095771268611;3015.6833532479213;5237.319148510745;5278.81524146653;5838.153467747238;5876.5905978549445;5905.970470147622;4945;4997.525235102659;5365.946340536545;5781.4507621775865;5856.238688970407;4883.8290659826735;5010.5240002199735;5551.972885016285;5771.691323470645;5895.55783316253;5024.598032157492;5265.894682106413;5629.240052592848;5663;5723.313202415737;5123.590112790893;5201.947980520298;5386.622936394415;5539.28574998256;5590.776368945197;4655.743934083658;5524.640898341595;5565.833025009256;5601.767611125546;5629.9450700494845;3844.6539811417106;3868.400397938324;3878.650921417086;3885.961140388673;3901.161250331079;5262.473752624707;5614.792819543818;5616.444517744335;5629;5651.43485651448;4898.461169271306;4995.550400463976;5419.802316633205;5449.972750136224;5470.714382120278;4025.496812898388;5076.695398276115;5453.563714902878;5472.945270547243;5474.357692692097;5195.674768148312;5411.540019098421;5444.591655625764;5457.208704737712;5477.808276710296;4842.394700662418;4903.327686410986;4905.037984806106;5413.025655382069;5484.588655850907;5210.843674689765;5412.675239485486;5431.375391829832;5444.863878403072;5449.7275136243825 14:29:03 INFO - PROCESS | 10832 | |5;yui.html;630.7256343490582;659.6932426421714;700.4991431126743;707.9150501939768;723.4971694672198;14850.401179793533;15108.169050702207;15291.617709557268;15298.23508824557;15310.693786124284;13847.723045539109;13876.93061534691;14358.425662973495;14381.065230759994;14383.92808035961;714.5712572456537;731.3965978068088;890.4345740454826;891.4205766251956;909.3225546967485;885.9379843410964;913.8583519554479;913.8857642794651;915.0483497162943;915.5651065743785;929.3308817651314;939.4222553129811;948.8434408322653;958.6500927161605;966.0484422843529;748.7753673897836;916.4684482999844;950.8145911547251;959.5777857742571;960.8558716192557;701.9996504980434;702.0697575712151;702.764573867755;702.8699690557263;703.6763088979076;14501.274936253145;14622.707545849129;14747.115173089649;14806.851931480764;14936.32786524609;12024.69938251542;12185.634430967086;12349;12423.31671758053;12504.937475312656;10751.623693170779;10866.402347870864;10929.398883061429;10983.835242471368;11334.886651133465;3924.0778417071965;3956.66368358687;3980.900477488057;4078.7348822326453;4106.548279689232;72355;78619;79875;82002;82053.58973205155;9203.953980230055;9268.443893366419;9405.43567385959;9665.275104367201;9741.805163896683;21589.352319430127;21609.67585486219;21627.134914603554;21643.783562164175;21671.891640541697;7322.121345438582;7400.0379950606075;7428.1457632371885;7506.62466876654;7565.621718914143;3291.588551431071;3653.8903832885057;3981.960180398217;4062.3500239961463;4078.9796051019557;3332.7333813294877;3450.8447119879665;3468.8265586720563;3472.427049536798;3474.322507111089;594.242341015209;597.0954004682909;609.7560975609703;613.4969325153331;615.5721773367557;510.97700603472936;578.3060327606888;589.2339958054512;595.4164918380012;596.2318149296491;6944.4444444444325;7216.855662886713;7780.336368676849;8257.628406721711;8326.084130745614;514.7143335448787;544.042485226005;562.7186406796602;580.0168714029763;594.3729365519418;3790.639889210521;3793.1844653399653;3834.198432410753;3866.3233934061427;3883.611638836094;79557.60221198857;80021.59989200134;85361.57319213364;86659;87019;53779;60544.69727651333;68138.65930670414;72054;72630.910536342;65285.673571631836;65587.01619475712;65613.01580476297;65651;67270.99999999903;7534.284242996907;7631.847363052597;7740.41946853977;7785.455018148676;7799.337056350274;42380.788096060554;43652;44140.55859441365;44286.55713442824;44633.33050004317;57312.14031789443;58842.7057864708;59074.40925590689;59479.405205949115;60355.396446034974;10342.224333174892;10375.377477351383;10425.113865321533;10479.842802357818;11098.389588572705;10996.920862158608;11066.059384952368;11327.15046371509;11428.657140285804;11993.520259189534;10375.636852710119;10501.159907207253;10562.683119506428;10794.56821727122;11293.830592541282;10298.536565854405;10340.345544712809;10870.673879783619;10890;10976.286541374786;10124.645637402655;10559.683209503728;10656.200784941315;10671.733206669895;11191.496382662963;8386.322735453055;8548.401611887357;8704.91295087041;8800.91199088001;8899.421537600036;8749;8885.378023538291;8964.955175224082;9028.36801423894;9033.954830225806 14:29:03 INFO - PROCESS | 10832 | __end_tp_report 14:29:03 INFO - PROCESS | 10832 | __start_cc_report 14:29:03 INFO - PROCESS | 10832 | _x_x_mozilla_cycle_collect,1329 14:29:03 INFO - PROCESS | 10832 | __end_cc_report 14:29:03 INFO - PROCESS | 10832 | __startTimestamp1464125343427__endTimestamp 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | ------- Summary: start ------- 14:29:03 INFO - PROCESS | 10832 | Number of tests: 6 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | [#0] dojo.html Cycles:165 Average:5751.06 Median:5963.88 stddev:618.60 (10.4%) stddev-sans-first:592.14 14:29:03 INFO - PROCESS | 10832 | Values: 3390.9 3398.3 3421.5 3423.5 3471.7 5604.4 5647.8 5649.9 5736.9 5759.8 5696.1 5721.9 5763.5 5811.7 5815.7 5240.0 5270.0 5346.9 5359.6 5365.9 5284.6 5352.9 5355.5 5361.9 5363.4 5200.3 5373.4 5417.0 5429.1 5432.3 5159.3 5190.7 5217.0 5240.2 5252.0 5147.2 5175.1 5179.4 5200.6 5220.6 5566.9 5632.2 5641.1 5644.0 5663.3 6087.0 6136.7 6178.8 6224.2 6245.8 5293.0 6243.9 6272.6 6296.9 6319.5 5859.6 5862.5 5876.5 5906.8 5918.3 6171.7 6197.3 6243.9 6269.1 6287.6 6162.1 6167.5 6172.7 6182.4 6195.0 6126.9 6127.9 6129.1 6211.6 6268.3 5963.9 5981.4 6115.7 6127.8 6135.5 6150.5 6215.6 6220.8 6226.5 6231.0 4928.2 4936.0 4936.3 4940.4 4972.7 4366.1 4382.8 4447.4 4516.1 4522.7 5598.2 5638.1 5664.1 5678.2 5683.5 6188.1 6281.3 6296.0 6298.1 6303.4 5622.6 5665.7 5687.6 5710.0 5740.0 5298.5 5351.4 5403.2 5408.1 5415.0 6212.8 6324.7 6331.6 6343.1 6348.2 6268.8 6284.5 6308.5 6316.8 6329.0 6198.9 6207.3 6226.3 6325.6 6339.7 6241.0 6241.8 6250.7 6256.7 6284.4 6265.9 6269.8 6277.6 6288.3 6291.2 5862.0 6037.4 6055.6 6075.8 6092.8 6034.9 6051.9 6080.5 6096.6 6099.6 5908.2 5927.2 5927.3 5933.6 5970.9 5894.1 6053.6 6080.8 6117.2 6152.4 5974.5 5983.6 5994.6 5998.5 6007.1 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | [#1] ext.html Cycles:180 Average:73538.42 Median:22347.89 stddev:168559.63 (754.3%) stddev-sans-first:168943.21 14:29:03 INFO - PROCESS | 10832 | Values: 468.9 477.4 478.3 511.9 521.7 27505.4 41233.4 42070.4 42339.9 42403.7 43745.6 44282.0 44311.3 44315.6 44444.0 9505.9 9653.0 9655.8 9691.0 9731.7 9478.0 9525.1 9542.5 9657.7 9684.4 9570.7 9619.5 9655.7 9687.7 9698.0 9608.3 9666.7 9669.1 9676.9 9684.8 9459.6 9535.7 9577.5 9582.7 9609.0 40444.8 40625.6 40792.4 40844.2 40923.6 3448.3 3727.0 3754.2 3770.7 4070.2 28501.3 28957.3 29105.4 29598.6 29623.7 2626.7 2694.9 2709.8 2709.9 2770.3 517991.0 520347.0 520519.0 521049.4 521074.0 34039.5 59634.7 60052.4 60447.0 60461.7 34939.7 36463.6 36507.0 36547.6 36603.1 13808.7 13982.8 14067.2 14165.8 14208.4 12376.6 12479.5 12484.2 12491.8 12497.5 7889.1 7908.2 7953.0 7962.5 8020.4 3060.1 3299.3 3317.6 3330.5 3344.6 736.0 924.5 1044.9 1082.9 1119.7 23693.6 27985.0 28081.9 28512.9 29435.7 921.7 1050.8 1073.3 1105.1 1148.4 13159.8 14054.9 14882.2 14937.3 14992.3 712351.4 718411.0 721745.0 722095.0 722146.0 55764.7 58353.7 58837.0 59732.1 60551.4 1270.4 1309.1 1405.7 1405.9 1434.3 29200.0 29439.0 29457.9 30544.5 31391.2 57807.8 58003.1 58258.0 58633.7 58837.8 545895.0 645224.0 646093.0 646271.0 649640.0 34914.0 41073.2 42079.9 42213.4 42877.9 44777.3 45676.0 45977.3 46236.8 46794.3 34555.7 36884.0 37403.1 38700.0 38776.3 36440.6 36890.0 37460.6 37746.2 37763.4 26820.7 36399.6 36767.0 37059.6 37608.9 31036.5 32399.7 32426.2 32675.7 32675.8 21530.6 22800.8 24221.3 24514.9 24726.3 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | [#2] jquery.html Cycles:180 Average:35538.88 Median:397.56 stddev:103042.06 (25918.7%) stddev-sans-first:103297.25 14:29:03 INFO - PROCESS | 10832 | Values: 351.7 357.3 391.8 403.3 407.6 19718.9 21728.7 21984.1 21996.5 22150.8 20005.6 20519.6 20822.8 20863.9 20890.1 4834.9 5027.6 5712.3 5884.5 5886.6 5799.2 5827.1 5864.6 5869.4 5935.9 5575.3 5933.2 5945.5 5946.4 5963.5 5713.4 5785.0 5881.0 5925.7 5946.3 5556.1 5698.7 5740.1 5746.9 5747.9 13300.3 17171.8 25244.0 25264.0 25818.2 1923.0 1994.4 2006.7 2061.0 2285.8 8562.7 8636.4 9654.8 10163.8 10214.9 1497.9 1637.5 1706.2 1711.0 1770.5 101297.5 122231.0 125008.0 126102.0 128314.0 11004.0 11095.8 11406.5 11424.4 11561.3 14516.8 14629.2 14755.0 14771.5 14782.9 8576.4 8583.1 8624.1 8652.6 8739.8 8491.4 8537.6 8626.3 8630.5 8664.8 4485.8 4547.4 4561.3 4575.3 4596.8 1893.8 1923.0 1927.3 1946.6 1970.9 732.4 908.4 921.5 981.8 983.8 19412.6 19962.6 20176.7 20283.8 20461.2 842.3 853.3 934.4 935.9 946.7 10539.4 10723.0 10772.4 10807.2 10811.7 603179.0 604619.0 621956.0 622362.0 629053.0 123753.0 124646.0 134723.0 137658.6 146116.0 946.4 990.4 1010.3 1014.1 1014.3 17229.1 17446.7 17685.4 17744.0 17867.6 54200.5 55160.4 55288.7 56608.7 56703.7 79351.0 79574.6 80769.2 81368.8 82123.0 6845.3 7724.5 7789.4 7854.5 8182.1 9254.4 12023.1 12425.0 12472.6 12499.9 5467.4 8749.0 10476.3 10652.5 10773.4 5771.9 8259.6 10074.3 10609.6 10855.8 8583.8 10870.3 10899.3 11254.4 11308.4 7130.3 7222.7 7361.1 7455.5 7586.3 9682.4 10749.7 10800.0 10835.9 10925.1 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | [#3] mootools.html Cycles:180 Average:5648.40 Median:4922.09 stddev:3188.22 (64.8%) stddev-sans-first:3181.16 14:29:03 INFO - PROCESS | 10832 | Values: 1398.0 1543.9 1548.0 1800.3 1919.8 7797.0 8592.4 8793.0 8794.6 8905.4 8506.2 8662.5 8671.2 8780.0 8781.8 2346.5 2374.1 2378.4 2378.8 2380.6 1959.8 2344.4 2356.3 2374.7 2376.1 2336.8 2362.9 2367.0 2371.8 2372.4 2294.9 2334.1 2341.0 2348.9 2354.4 2047.4 2152.7 2228.9 2232.3 2256.1 7378.5 8679.1 8752.4 8761.5 8800.8 5248.6 5453.0 5456.1 5456.8 5456.9 3458.6 3567.1 3604.8 3605.6 3637.0 1019.2 1035.2 1038.6 1038.8 1039.3 11456.7 11505.2 11613.8 11616.0 11632.0 4519.7 4591.1 4606.9 4681.4 4690.8 9122.5 9229.8 9244.9 9252.9 9260.8 5181.4 5262.6 5269.4 5279.0 5279.2 4379.2 4418.7 4562.7 4906.3 4915.9 3820.9 3833.7 3877.7 3889.2 3926.5 1168.0 1174.7 1178.3 1180.6 1192.6 836.6 853.8 862.7 919.7 966.6 5000.1 5481.5 5672.7 5787.9 5925.0 937.2 938.0 967.9 975.3 1001.1 3542.6 3613.8 3656.5 3724.3 3746.9 10560.9 10663.7 10944.7 10999.7 11005.0 10740.4 10926.1 10932.0 10941.4 10948.4 6869.6 8141.9 8142.8 8347.5 8356.8 5615.0 5669.6 5688.3 5779.7 5851.8 9683.0 10035.4 10086.1 10228.6 10288.9 8542.1 10124.6 10313.0 10396.9 10406.0 7326.1 7611.8 7624.9 7736.3 7738.0 6967.5 8094.2 8221.8 8346.9 8391.5 7368.4 7482.0 7512.0 7595.4 7651.2 7263.3 7344.5 7359.3 7449.9 7520.0 7035.8 7043.5 7119.7 7128.5 7134.4 4735.6 4928.3 4962.0 4989.0 5001.1 4882.7 4949.1 4974.7 5031.6 5176.0 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | [#4] prototype.html Cycles:180 Average:4412.76 Median:4978.90 stddev:1339.43 (26.9%) stddev-sans-first:1307.16 14:29:03 INFO - PROCESS | 10832 | Values: 301.9 312.1 314.8 318.4 323.2 4536.4 5287.7 5287.7 5312.9 5319.8 4459.1 4670.0 4941.0 4996.0 5256.2 3828.6 3900.0 3983.0 4098.8 4137.0 3349.2 3698.5 3959.7 4023.8 4060.4 3702.5 3959.6 3969.0 3972.5 4033.4 3262.5 3317.7 3735.6 3898.4 3991.3 3187.9 3367.9 3473.7 3481.7 3493.7 4368.7 5023.9 5043.4 5064.4 5074.7 3779.3 4255.2 4288.4 4509.2 4562.8 5020.6 5401.6 5454.9 5473.8 5502.0 3386.6 3646.1 3845.9 3871.2 3888.7 5001.3 5258.3 5760.9 5775.4 5793.0 3176.9 3878.1 3902.2 3922.3 3935.4 3672.5 3955.6 3973.4 3993.5 4012.9 4546.0 4567.6 4596.3 4598.9 4617.7 4594.4 4955.8 5073.3 5177.2 5506.7 1263.5 1350.9 1359.6 1361.2 1369.4 732.9 735.5 803.8 815.1 845.1 4880.2 4962.3 5025.7 5163.7 5213.5 5006.7 5453.8 5710.9 5711.5 5735.0 3912.5 4530.0 4582.0 4956.4 5126.9 2847.2 2912.4 2970.6 3014.1 3015.7 5237.3 5278.8 5838.2 5876.6 5906.0 4945.0 4997.5 5365.9 5781.5 5856.2 4883.8 5010.5 5552.0 5771.7 5895.6 5024.6 5265.9 5629.2 5663.0 5723.3 5123.6 5201.9 5386.6 5539.3 5590.8 4655.7 5524.6 5565.8 5601.8 5629.9 3844.7 3868.4 3878.7 3886.0 3901.2 5262.5 5614.8 5616.4 5629.0 5651.4 4898.5 4995.6 5419.8 5450.0 5470.7 4025.5 5076.7 5453.6 5472.9 5474.4 5195.7 5411.5 5444.6 5457.2 5477.8 4842.4 4903.3 4905.0 5413.0 5484.6 5210.8 5412.7 5431.4 5444.9 5449.7 14:29:03 INFO - PROCESS | 10832 | 14:29:03 INFO - PROCESS | 10832 | [#5] yui.html Cycles:180 Average:16984.97 Median:29834.33 stddev:23287.85 (78.1%) stddev-sans-first:23320.80 14:29:03 INFO - PROCESS | 10832 | Values: 630.7 659.7 700.5 707.9 723.5 14850.4 15108.2 15291.6 15298.2 15310.7 13847.7 13876.9 14358.4 14381.1 14383.9 714.6 731.4 890.4 891.4 909.3 885.9 913.9 913.9 915.0 915.6 929.3 939.4 948.8 958.7 966.0 748.8 916.5 950.8 959.6 960.9 702.0 702.1 702.8 702.9 703.7 14501.3 14622.7 14747.1 14806.9 14936.3 12024.7 12185.6 12349.0 12423.3 12504.9 10751.6 10866.4 10929.4 10983.8 11334.9 3924.1 3956.7 3980.9 4078.7 4106.5 72355.0 78619.0 79875.0 82002.0 82053.6 9204.0 9268.4 9405.4 9665.3 9741.8 21589.4 21609.7 21627.1 21643.8 21671.9 7322.1 7400.0 7428.1 7506.6 7565.6 3291.6 3653.9 3982.0 4062.4 4079.0 3332.7 3450.8 3468.8 3472.4 3474.3 594.2 597.1 609.8 613.5 615.6 511.0 578.3 589.2 595.4 596.2 6944.4 7216.9 7780.3 8257.6 8326.1 514.7 544.0 562.7 580.0 594.4 3790.6 3793.2 3834.2 3866.3 3883.6 79557.6 80021.6 85361.6 86659.0 87019.0 53779.0 60544.7 68138.7 72054.0 72630.9 65285.7 65587.0 65613.0 65651.0 67271.0 7534.3 7631.8 7740.4 7785.5 7799.3 42380.8 43652.0 44140.6 44286.6 44633.3 57312.1 58842.7 59074.4 59479.4 60355.4 10342.2 10375.4 10425.1 10479.8 11098.4 10996.9 11066.1 11327.2 11428.7 11993.5 10375.6 10501.2 10562.7 10794.6 11293.8 10298.5 10340.3 10870.7 10890.0 10976.3 10124.6 10559.7 10656.2 10671.7 11191.5 8386.3 8548.4 8704.9 8800.9 8899.4 8749.0 8885.4 8965.0 9028.4 9034.0 14:29:03 INFO - PROCESS | 10832 | -------- Summary: end -------- 14:29:03 INFO - PROCESS | 10832 | 14:29:04 INFO - TEST-INFO | 10832: exit 0 14:29:04 INFO - TEST-OK | dromaeo_css | took 1114500ms 14:29:04 INFO - TEST-START | kraken 14:29:04 INFO - operating with platform_type : mac_ 14:29:04 INFO - Initialising browser for kraken test... 14:29:04 INFO - TEST-INFO | started process 10835 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpN0uCWs/profile http://localhost:64431/getInfo.html) 14:29:06 INFO - PROCESS | 10835 | __metrics Screen width/height:1600/1200 14:29:06 INFO - PROCESS | 10835 | colorDepth:24 14:29:06 INFO - PROCESS | 10835 | Browser inner width/height: 1024/689 14:29:06 INFO - PROCESS | 10835 | __metrics 14:29:06 INFO - PROCESS | 10835 | 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" 14:29:06 ERROR - PROCESS | 10835 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:29:07 INFO - TEST-INFO | 10835: exit 0 14:29:07 INFO - Browser initialized. 14:29:07 INFO - Running cycle 1/1 for kraken test... 14:29:07 INFO - TEST-INFO | started process 10837 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpN0uCWs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:18 INFO - PROCESS | 10837 | RSS: Main: 239243264 14:29:18 INFO - PROCESS | 10837 | 14:29:21 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64431/tests/kraken/driver.html?testName=audio-beat-detection) 14:29:22 INFO - PROCESS | 10837 | RSS: Main: 286785536 14:29:22 INFO - PROCESS | 10837 | 14:29:25 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64431/tests/kraken/driver.html?testName=audio-dft) 14:29:26 INFO - PROCESS | 10837 | RSS: Main: 295956480 14:29:26 INFO - PROCESS | 10837 | 14:29:29 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64431/tests/kraken/driver.html?testName=audio-fft) 14:29:30 INFO - PROCESS | 10837 | RSS: Main: 386269184 14:29:30 INFO - PROCESS | 10837 | 14:29:32 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64431/tests/kraken/driver.html?testName=audio-oscillator) 14:29:32 INFO - PROCESS | 10837 | RSS: Main: 284266496 14:29:32 INFO - PROCESS | 10837 | 14:29:35 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64431/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:29:35 INFO - PROCESS | 10837 | RSS: Main: 288792576 14:29:35 INFO - PROCESS | 10837 | 14:29:38 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64431/tests/kraken/driver.html?testName=imaging-darkroom) 14:29:39 INFO - PROCESS | 10837 | RSS: Main: 327737344 14:29:39 INFO - PROCESS | 10837 | 14:29:42 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64431/tests/kraken/driver.html?testName=imaging-desaturate) 14:29:42 INFO - PROCESS | 10837 | RSS: Main: 327315456 14:29:42 INFO - PROCESS | 10837 | 14:29:45 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64431/tests/kraken/driver.html?testName=json-parse-financial) 14:29:46 INFO - PROCESS | 10837 | RSS: Main: 327020544 14:29:46 INFO - PROCESS | 10837 | 14:29:47 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64431/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:29:48 INFO - PROCESS | 10837 | RSS: Main: 315555840 14:29:48 INFO - PROCESS | 10837 | 14:29:50 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:29:51 INFO - PROCESS | 10837 | RSS: Main: 321855488 14:29:51 INFO - PROCESS | 10837 | 14:29:53 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:29:54 INFO - PROCESS | 10837 | RSS: Main: 324141056 14:29:54 INFO - PROCESS | 10837 | 14:29:57 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:29:57 INFO - PROCESS | 10837 | RSS: Main: 328445952 14:29:57 INFO - PROCESS | 10837 | 14:30:00 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:30:01 INFO - PROCESS | 10837 | RSS: Main: 326299648 14:30:01 INFO - PROCESS | 10837 | 14:30:02 INFO - PROCESS | 10837 | Cycle 1(1): loaded http://localhost:64431/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64431/tests/kraken/driver.html?testName=ai-astar) 14:30:03 INFO - PROCESS | 10837 | RSS: Main: 372953088 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | __start_tp_report 14:30:03 INFO - PROCESS | 10837 | _x_x_mozilla_page_load 14:30:03 INFO - PROCESS | 10837 | _x_x_mozilla_page_load_details 14:30:03 INFO - PROCESS | 10837 | |i|pagename|runs| 14:30:03 INFO - PROCESS | 10837 | |0;ai-astar;116;148;162;89;163;163;163;112;113;113 14:30:03 INFO - PROCESS | 10837 | |1;audio-beat-detection;159;116;121;174;119;120;145;141;207;119 14:30:03 INFO - PROCESS | 10837 | |2;audio-dft;184;185;204;191;183;183;185;185;182;182 14:30:03 INFO - PROCESS | 10837 | |3;audio-fft;79;57;62;136;57;57;59;69;84;56 14:30:03 INFO - PROCESS | 10837 | |4;audio-oscillator;86;69;100;91;107;98;101;69;68;100 14:30:03 INFO - PROCESS | 10837 | |5;imaging-gaussian-blur;88;85;86;97;134;71;128;129;134;84 14:30:03 INFO - PROCESS | 10837 | |6;imaging-darkroom;92;93;94;94;93;93;94;94;94;92 14:30:03 INFO - PROCESS | 10837 | |7;imaging-desaturate;87;85;83;85;83;83;84;83;87;84 14:30:03 INFO - PROCESS | 10837 | |8;json-parse-financial;58;57;59;55;55;57;55;76;57;54 14:30:03 INFO - PROCESS | 10837 | |9;json-stringify-tinderbox;64;74;69;82;77;104;103;96;71;71 14:30:03 INFO - PROCESS | 10837 | |10;stanford-crypto-aes;98;106;102;71;98;107;103;104;105;86 14:30:03 INFO - PROCESS | 10837 | |11;stanford-crypto-ccm;142;141;146;127;145;130;142;141;192;138 14:30:03 INFO - PROCESS | 10837 | |12;stanford-crypto-pbkdf2;168;225;202;166;165;165;167;164;161;165 14:30:03 INFO - PROCESS | 10837 | |13;stanford-crypto-sha256-iterative;65;66;67;65;66;66;66;66;65;65 14:30:03 INFO - PROCESS | 10837 | __end_tp_report 14:30:03 INFO - PROCESS | 10837 | __start_cc_report 14:30:03 INFO - PROCESS | 10837 | _x_x_mozilla_cycle_collect,1952 14:30:03 INFO - PROCESS | 10837 | __end_cc_report 14:30:03 INFO - PROCESS | 10837 | __startTimestamp1464125403181__endTimestamp 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | ------- Summary: start ------- 14:30:03 INFO - PROCESS | 10837 | Number of tests: 14 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#0] ai-astar Cycles:10 Average:134.20 Median:162.50 stddev:28.32 (17.4%) stddev-sans-first:29.26 14:30:03 INFO - PROCESS | 10837 | Values: 116.0 148.0 162.0 89.0 163.0 163.0 163.0 112.0 113.0 113.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:143.00 stddev:30.20 (21.1%) stddev-sans-first:31.40 14:30:03 INFO - PROCESS | 10837 | Values: 159.0 116.0 121.0 174.0 119.0 120.0 145.0 141.0 207.0 119.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#2] audio-dft Cycles:10 Average:186.40 Median:185.00 stddev:6.70 (3.6%) stddev-sans-first:7.05 14:30:03 INFO - PROCESS | 10837 | Values: 184.0 185.0 204.0 191.0 183.0 183.0 185.0 185.0 182.0 182.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#3] audio-fft Cycles:10 Average:71.60 Median:60.50 stddev:24.71 (40.8%) stddev-sans-first:26.07 14:30:03 INFO - PROCESS | 10837 | Values: 79.0 57.0 62.0 136.0 57.0 57.0 59.0 69.0 84.0 56.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:69.00 stddev:15.07 (21.8%) stddev-sans-first:15.95 14:30:03 INFO - PROCESS | 10837 | Values: 86.0 69.0 100.0 91.0 107.0 98.0 101.0 69.0 68.0 100.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#5] imaging-gaussian-blur Cycles:10 Average:103.60 Median:84.50 stddev:24.67 (29.2%) stddev-sans-first:25.51 14:30:03 INFO - PROCESS | 10837 | Values: 88.0 85.0 86.0 97.0 134.0 71.0 128.0 129.0 134.0 84.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 14:30:03 INFO - PROCESS | 10837 | Values: 92.0 93.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 92.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.58 (1.9%) stddev-sans-first:1.36 14:30:03 INFO - PROCESS | 10837 | Values: 87.0 85.0 83.0 85.0 83.0 83.0 84.0 83.0 87.0 84.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:57.00 stddev:6.41 (11.3%) stddev-sans-first:6.80 14:30:03 INFO - PROCESS | 10837 | Values: 58.0 57.0 59.0 55.0 55.0 57.0 55.0 76.0 57.0 54.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#9] json-stringify-tinderbox Cycles:10 Average:81.10 Median:72.50 stddev:14.67 (20.2%) stddev-sans-first:14.20 14:30:03 INFO - PROCESS | 10837 | Values: 64.0 74.0 69.0 82.0 77.0 104.0 103.0 96.0 71.0 71.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#10] stanford-crypto-aes Cycles:10 Average:98.00 Median:89.00 stddev:11.27 (12.7%) stddev-sans-first:11.96 14:30:03 INFO - PROCESS | 10837 | Values: 98.0 106.0 102.0 71.0 98.0 107.0 103.0 104.0 105.0 86.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:142.00 stddev:17.81 (12.5%) stddev-sans-first:18.87 14:30:03 INFO - PROCESS | 10837 | Values: 142.0 141.0 146.0 127.0 145.0 130.0 142.0 141.0 192.0 138.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:166.50 stddev:21.19 (12.7%) stddev-sans-first:22.33 14:30:03 INFO - PROCESS | 10837 | Values: 168.0 225.0 202.0 166.0 165.0 165.0 167.0 164.0 161.0 165.0 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - PROCESS | 10837 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 14:30:03 INFO - PROCESS | 10837 | Values: 65.0 66.0 67.0 65.0 66.0 66.0 66.0 66.0 65.0 65.0 14:30:03 INFO - PROCESS | 10837 | -------- Summary: end -------- 14:30:03 INFO - PROCESS | 10837 | 14:30:03 INFO - TEST-INFO | 10837: exit 0 14:30:04 INFO - TEST-OK | kraken | took 59966ms 14:30:04 INFO - SUITE-END | took 1174s 14:30:04 INFO - Completed test suite (00:19:34) 14:30:04 INFO - javascript benchmark 14:30:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.949135762964, 3398.337324221777, 3421.4867769834505, 3423.452247640377, 3471.6701913318225, 5604.439556044404, 5647.774089036444, 5649.915251271229, 5736.885262294752, 5759.798407055754, 5696.11710184921, 5721.914171287434, 5763.510101641355, 5811.651300921948, 5815.709214539277, 5240.004399164156, 5270.0, 5346.946530534684, 5359.598030147735, 5365.946340536603, 5284.550813180865, 5352.91970620441, 5355.4912283333215, 5361.919571206435, 5363.410024897258, 5200.349956255468, 5373.435789242112, 5417.024935511626, 5429.104197807357, 5432.320959880015, 5159.329287192679, 5190.662606930575, 5216.982688375768, 5240.2401651760665, 5252.0, 5147.22791581262, 5175.146100893348, 5179.430262671103, 5200.557952573998, 5220.556252718486, 5566.944330556683, 5632.183333416631, 5641.097424412074, 5644.0, 5663.348714897798, 6087.026075827891, 6136.723847426876, 6178.826023055605, 6224.221972253468, 6245.843853903643, 5292.9735351323, 6243.875122497525, 6272.623642581414, 6296.905546416808, 6319.526035547352, 5859.619124756877, 5862.472377486047, 5876.500497457721, 5906.822795316148, 5918.289805223358, 6171.660558669227, 6197.349278325711, 6243.937560624427, 6269.122322874801, 6287.559870808999, 6162.075688646739, 6167.537434692416, 6172.722227499773, 6182.381761823782, 6194.969025154845, 6126.877462450726, 6127.908081378783, 6129.1419201311855, 6211.596246243979, 6268.27914789805, 5963.880722385528, 5981.37195594465, 6115.696722738819, 6127.816165515042, 6135.515116377874, 6150.538709596798, 6215.627062376181, 6220.813375598739, 6226.501879849601, 6230.968845155745, 4928.186849169847, 4936.037472692862, 4936.259561065797, 4940.431850337257, 4972.72650004246, 4366.061296821199, 4382.780860956939, 4447.444069491314, 4516.07420478801, 4522.683412161117, 5598.1602759586385, 5638.097904335286, 5664.122061080539, 5678.205051292823, 5683.460071293221, 6188.1027251049245, 6281.3404592518045, 6295.968520157461, 6298.08677741734, 6303.432691057827, 5622.578306626938, 5665.680031094032, 5687.573431992617, 5709.971450142806, 5739.966805974965, 5298.470152984671, 5351.384590772033, 5403.243545903577, 5408.080626293539, 5414.972925135428, 6212.844678883064, 6324.715387807468, 6331.620102793755, 6343.111964324999, 6348.238211414568, 6268.811935641938, 6284.4972402206795, 6308.463780578702, 6316.810495685137, 6329.0, 6198.946179149465, 6207.317195108625, 6226.283977342664, 6325.588836725598, 6339.746410143543, 6241.032639940817, 6241.750329986749, 6250.656213908097, 6256.655883926245, 6284.4344009039405, 6265.874682506415, 6269.780557680459, 6277.623342599459, 6288.27684816252, 6291.245050593958, 5862.0, 6037.396260374103, 6055.576109672281, 6075.848103797441, 6092.817215483543, 6034.939650603614, 6051.878962420639, 6080.452759251689, 6096.634201947897, 6099.634021958696, 5908.202392677105, 5927.170196172453, 5927.31835838884, 5933.584649074523, 5970.910436343372, 5894.086416605433, 6053.606515576474, 6080.817575472743, 6117.204763380732, 6152.415520525543, 5974.492168165754, 5983.640981541122, 5994.55040871945, 5998.4601385875485, 6007.12896629995], "value": 5712.350964492731, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.85365281885805, 477.4056299906617, 478.3303375274616, 511.87970826855684, 521.7391304347826, 27505.44989100217, 41233.3814992775, 42070.36894446578, 42339.941501462476, 42403.727888163325, 43745.562544374545, 44282.0, 44311.335329970076, 44315.55684443154, 44444.0, 9505.904940950588, 9652.98643642418, 9655.806883862319, 9690.951545242298, 9731.708048758548, 9477.95261023694, 9525.142737153656, 9542.522873856315, 9657.71026869195, 9684.418934863896, 9570.712878613653, 9619.519024048805, 9655.662051828187, 9687.70936871895, 9697.95151024244, 9608.279379046564, 9666.661666841626, 9669.081437263449, 9676.903230967671, 9684.75788105296, 9459.621615135455, 9535.713928582154, 9577.521123943845, 9582.664606738801, 9608.991055939096, 40444.797776011226, 40625.59374406248, 40792.38811417831, 40844.1831163378, 40923.590764092274, 3448.2758620689683, 3726.962730372689, 3754.155315054118, 3770.6794922431627, 4070.1859628074208, 28501.287467813265, 28957.276068098254, 29105.41789164205, 29598.556021659693, 29623.70376296231, 2626.6716660417446, 2694.90567830125, 2709.7967652426146, 2709.878055487508, 2770.265879541923, 517991.0, 520347.0, 520518.9999999962, 521049.36851894506, 521074.0, 34039.489407658904, 59634.70182649059, 60052.39947600468, 60447.0, 60461.69769151126, 34939.65060349415, 36463.63536364656, 36507.0, 36547.63452365442, 36603.08492287661, 13808.723825523433, 13982.790258146137, 14067.155970641794, 14165.78751318731, 14208.360623771956, 12376.566820161432, 12479.500819967281, 12484.18852774585, 12491.812622810665, 12497.50009999608, 7889.132195458495, 7908.248716371935, 7953.0, 7962.482438641469, 8020.43856930021, 3060.127863558875, 3299.323638654118, 3317.568716066944, 3330.5004249362305, 3344.6153692325206, 735.9779206623809, 924.5377311344328, 1044.9341671495185, 1082.8917108289108, 1119.6641007697822, 23693.644595331083, 27985.020524281554, 28081.859590702323, 28512.857435713104, 29435.705642943725, 921.6543796076471, 1050.7863417752508, 1073.3345325897992, 1105.0606984063643, 1148.3741360958297, 13159.775140537164, 14054.929725351309, 14882.181480018486, 14937.327820248114, 14992.25277380581, 712351.3877123944, 718411.0, 721745.0, 722095.0, 722146.0, 55764.72117639386, 58353.70823145857, 58837.0, 59732.10401843976, 60551.394486054574, 1270.4092596942467, 1309.0771006440627, 1405.6977749783846, 1405.943762249519, 1434.3186986181481, 29200.0, 29438.969636062637, 29457.852710736308, 30544.541831872983, 31391.21521961969, 57807.843843122064, 58003.12995305158, 58258.0, 58633.70683146728, 58837.82324353574, 545895.0, 645224.0, 646093.0, 646271.0, 649640.0, 34914.0, 41073.1785364297, 42079.94800130021, 42213.36679949742, 42877.92659697324, 44777.32834007427, 45676.0, 45977.31034034559, 46236.8440788983, 46794.29808552942, 34555.65444345524, 36884.0, 37403.06492337604, 38700.0, 38776.260608589866, 36440.635593643725, 36890.0, 37460.62539374681, 37746.245075098894, 37763.43354849734, 26820.731792681825, 36399.63600363962, 36767.0, 37059.62940370562, 37608.87173384803, 31036.534451982785, 32399.70401183926, 32426.189345266557, 32675.673243268215, 32675.836620816743, 21530.569388611828, 22800.771992279864, 24221.273361799173, 24514.877425612758, 24726.258212253662], "value": 18780.712641502025, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.6834848636227, 357.31930672069706, 391.77472953052, 403.34255164082543, 407.618876350612, 19718.901405492972, 21728.674069888926, 21984.120635174593, 21996.450088747788, 22150.77849221507, 20005.59988800223, 20519.589608207825, 20822.791772082273, 20863.895680521615, 20890.05994730237, 4834.9033019339595, 5027.648064635477, 5712.314522257335, 5884.499817515516, 5886.558508111887, 5799.21710569072, 5827.125931110325, 5864.648121112726, 5869.354371019206, 5935.85160370992, 5575.330960284771, 5933.169356290123, 5945.524358051345, 5946.43508866657, 5963.493103086243, 5713.400092990218, 5784.971075144597, 5880.970595147019, 5925.674087925162, 5946.256717910262, 5556.055470570012, 5698.715064246771, 5740.0815869460685, 5746.942530574683, 5747.942520574783, 13300.268485233308, 17171.82828171715, 25244.0, 25264.0, 25818.22545323643, 1923.0, 1994.3917105282883, 2006.7190593316948, 2060.9793902060937, 2285.8285628577755, 8562.700305489278, 8636.39545231834, 9654.75863103421, 10163.796724065478, 10214.948925255327, 1497.8951473396867, 1637.5087473757826, 1706.2407228783197, 1711.0, 1770.486558897918, 101297.46756331077, 122231.0, 125008.0, 126102.0, 128314.0, 11004.0, 11095.83356249657, 11406.486708098155, 11424.371659558805, 11561.30632162073, 14516.782248266285, 14629.195394253205, 14755.040922340228, 14771.482998095014, 14782.852171478362, 8576.356773242032, 8583.05586385497, 8624.094470080676, 8652.610632521551, 8739.825203495895, 8491.448055876348, 8537.615807288686, 8626.309895208367, 8630.482171069756, 8664.826703466022, 4485.775711214426, 4547.431136257986, 4561.292999585004, 4575.290829921368, 4596.770161491912, 1893.7632795900513, 1922.9711554326695, 1927.2772710233662, 1946.6496030714325, 1970.881747095179, 732.3847967707152, 908.4322298563397, 921.5023887101024, 981.8036392721484, 983.8179936711731, 19412.611747765248, 19962.60074798525, 20176.697349539474, 20283.79716202819, 20461.181552737722, 842.2840585502274, 853.2619284319019, 934.4486752815872, 935.854942483916, 946.7443790176751, 10539.367637941748, 10723.0, 10772.353658780497, 10807.189460790472, 10811.729706757236, 603179.0, 604619.0, 621956.0, 622362.0, 629053.0, 123753.0, 124646.0, 134723.0, 137658.62341376458, 146116.0, 946.4179529589253, 990.3859607043535, 1010.282699283517, 1014.0974532665933, 1014.2545229256638, 17229.138543072546, 17446.651066978844, 17685.38101166453, 17744.0, 17867.553311167325, 54200.45799541954, 55160.44839551553, 55288.72355638196, 56608.71695641495, 56703.71648141733, 79351.0, 79574.60212698899, 80769.19230807616, 81368.7794683092, 82123.0, 6845.315468453115, 7724.497907635986, 7789.376849851885, 7854.45018848698, 8182.140875208018, 9254.398464099811, 12023.098267629788, 12425.0, 12472.563460278847, 12499.875001249871, 5467.39858615544, 8748.950125984922, 10476.266661333939, 10652.467376631292, 10773.407462589319, 5771.884562308815, 8259.628316725642, 10074.345167564084, 10609.628662996758, 10855.837162442413, 8583.785405364915, 10870.347779133277, 10899.346039237671, 11254.381009044253, 11308.378039207922, 7130.2869713028285, 7222.74720384784, 7361.079865016873, 7455.515391499535, 7586.317231449196, 9682.370645907993, 10749.731256718333, 10800.0, 10835.891641083803, 10925.125989920945], "value": 9282.0813317924, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.0143998481071, 1543.9465510870543, 1547.9721564750178, 1800.3338764657071, 1919.8176173263544, 7797.0, 8592.441491303061, 8792.956035219831, 8794.648214071429, 8905.376623636332, 8506.191911768372, 8662.480251184934, 8671.176238257356, 8780.034196238425, 8781.824363512726, 2346.4603141277476, 2374.121575017246, 2378.4172877645, 2378.750231225714, 2380.5952987991996, 1959.7648282206155, 2344.402177444749, 2356.2813341930705, 2374.6912901322803, 2376.0733314007553, 2336.789688927996, 2362.917297894574, 2367.0413482539598, 2371.81025517959, 2372.383180373107, 2294.9196778112855, 2334.124703236286, 2340.9648855267183, 2348.941276468085, 2354.411397150712, 2047.4267205182573, 2152.7298893652664, 2228.8885555722313, 2232.3414592695194, 2256.0637335505908, 7378.483506154571, 8679.088695686925, 8752.431091979, 8761.51811650359, 8800.779980500538, 5248.606354523387, 5453.045716999549, 5456.127019676833, 5456.836294911159, 5456.91814622781, 3458.602260740022, 3567.108222944264, 3604.8017359045243, 3605.5853576838736, 3637.0361854108683, 1019.2050200843354, 1035.1977217656301, 1038.600138946511, 1038.761084950458, 1039.3140527252053, 11456.71358216043, 11505.194636375458, 11613.825792613117, 11616.0, 11632.0, 4519.706219095749, 4591.081783643284, 4606.861794146181, 4681.391419115494, 4690.788914498856, 9122.498262595622, 9229.815403692022, 9244.90755092454, 9252.8612070819, 9260.814783704289, 5181.4041385240425, 5262.631615786858, 5269.420363759983, 5278.97360513195, 5279.155335146435, 4379.233634114017, 4418.7127836690515, 4562.680612357103, 4906.337644418029, 4915.90168196634, 3820.8662696805477, 3833.6933045356304, 3877.728559000899, 3889.1638297766117, 3926.469926559935, 1167.9532818687157, 1174.6887074925153, 1178.3165763856941, 1180.5749930025183, 1192.6183621241294, 836.5942517878824, 853.7908212487856, 862.6980556805069, 919.7424721077975, 966.6278482784179, 5000.149974504343, 5481.502175040913, 5672.688002159838, 5787.942120578825, 5924.978261086791, 937.1986951156663, 937.962481500746, 967.8548217767391, 975.2782940624029, 1001.1440218612981, 3542.6280240574883, 3613.837377318027, 3656.451532270181, 3724.273766615538, 3746.925061498755, 10560.894391055992, 10663.680089597325, 10944.671659850217, 10999.725006874893, 11005.0, 10740.355578665305, 10926.071283940953, 10932.0, 10941.398223097647, 10948.39783811882, 6869.553479023847, 8141.877871831927, 8142.7557173284895, 8347.54088525137, 8356.791080222922, 5615.0, 5669.574781891457, 5688.260526131577, 5779.68211748341, 5851.824445266649, 9683.0, 10035.397876127456, 10086.142677872464, 10228.590856365661, 10288.948555257175, 8542.06037335905, 10124.645637402655, 10312.968703129927, 10396.896031039592, 10406.0, 7326.120865496175, 7611.809704757426, 7624.923750762643, 7736.2650548197835, 7737.961310193638, 6967.512274140964, 8094.15011423792, 8221.753347399588, 8346.916530834615, 8391.538465384463, 7368.410527157707, 7481.962590187014, 7511.9624401877645, 7595.392368610387, 7651.196624354363, 7263.273672632695, 7344.522606030591, 7359.300866417681, 7449.851002979802, 7520.0, 7035.753748618774, 7043.542169758949, 7119.715211391486, 7128.536645118051, 7134.357907788324, 4735.644826637947, 4928.2853986171085, 4961.975190124026, 4988.975055124701, 5001.1498045331655, 4882.6826256293225, 4949.133901567139, 4974.676646017997, 5031.647784655039, 5175.9741201293755], "value": 4528.873246529276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.85360100351335, 312.0606973011237, 314.8032479700187, 318.41888553390066, 323.15656137481153, 4536.364908912755, 5287.656302340345, 5287.748034958502, 5312.9203061954, 5319.787208511664, 4459.063596644709, 4669.953300467003, 4941.0, 4996.0, 5256.237845512398, 3828.617138286177, 3899.9805000974957, 3982.9885907616613, 4098.7540747555095, 4136.9793151034355, 3349.1794510345, 3698.500702405181, 3959.663428608572, 4023.7786921719294, 4060.390941358805, 3702.4816525686424, 3959.6040395960463, 3969.0, 3972.4637173981578, 4033.3748269018347, 3262.5106234064842, 3317.701406873381, 3735.64511371422, 3898.4152377143373, 3991.2815693175216, 3187.9043628691174, 3367.922264875241, 3473.6526347365316, 3481.651834816523, 3493.68556829884, 4368.716033457815, 5023.949760502384, 5043.445221025684, 5064.417591976933, 5074.67014644047, 3779.3386157422615, 4255.170276780983, 4288.421063156465, 4509.233430316854, 4562.840300589463, 5020.573251273646, 5401.648892821954, 5454.945450545483, 5473.781048758045, 5501.972490137564, 3386.5766779152605, 3646.1249300168033, 3845.903852403684, 3871.2257548490416, 3888.727789054767, 5001.349824522788, 5258.2638430619745, 5760.884782304331, 5775.422457754233, 5792.957267691814, 3176.888808891678, 3878.146807702301, 3902.1610353774076, 3922.2743792398496, 3935.370340745524, 3672.485851980725, 3955.64399204073, 3973.384125460558, 3993.520777506718, 4012.9333393302472, 4546.0, 4567.588916997486, 4596.3105534170145, 4598.885027874263, 4617.676762626651, 4594.379758732528, 4955.82654607087, 5073.289739436482, 5177.168895017735, 5506.68719137963, 1263.5198624522623, 1350.9327631171266, 1359.5649392194402, 1361.1900918953206, 1369.3906211735682, 732.9409003989184, 735.4704612678863, 803.8191406933394, 815.1318845429598, 845.0830848529414, 4880.243562247857, 4962.255661650709, 5025.723585202776, 5163.741812909415, 5213.504717051874, 5006.723285562159, 5453.781848726086, 5710.942890571041, 5711.543076553866, 5735.0, 3912.5109361329833, 4529.977350113229, 4581.954180458153, 4956.405231372259, 5126.923096153486, 2847.174319447337, 2912.417516496709, 2970.5841182234503, 3014.095771268611, 3015.6833532479213, 5237.319148510745, 5278.81524146653, 5838.153467747238, 5876.5905978549445, 5905.970470147622, 4945.0, 4997.525235102659, 5365.946340536545, 5781.4507621775865, 5856.238688970407, 4883.8290659826735, 5010.5240002199735, 5551.972885016285, 5771.691323470645, 5895.55783316253, 5024.598032157492, 5265.894682106413, 5629.240052592848, 5663.0, 5723.313202415737, 5123.590112790893, 5201.947980520298, 5386.622936394415, 5539.28574998256, 5590.776368945197, 4655.743934083658, 5524.640898341595, 5565.833025009256, 5601.767611125546, 5629.9450700494845, 3844.6539811417106, 3868.400397938324, 3878.650921417086, 3885.961140388673, 3901.161250331079, 5262.473752624707, 5614.792819543818, 5616.444517744335, 5629.0, 5651.43485651448, 4898.461169271306, 4995.550400463976, 5419.802316633205, 5449.972750136224, 5470.714382120278, 4025.496812898388, 5076.695398276115, 5453.563714902878, 5472.945270547243, 5474.357692692097, 5195.674768148312, 5411.540019098421, 5444.591655625764, 5457.208704737712, 5477.808276710296, 4842.394700662418, 4903.327686410986, 4905.037984806106, 5413.025655382069, 5484.588655850907, 5210.843674689765, 5412.675239485486, 5431.375391829832, 5444.863878403072, 5449.7275136243825], "value": 3985.921286754453, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.7256343490582, 659.6932426421714, 700.4991431126743, 707.9150501939768, 723.4971694672198, 14850.401179793533, 15108.169050702207, 15291.617709557268, 15298.23508824557, 15310.693786124284, 13847.723045539109, 13876.93061534691, 14358.425662973495, 14381.065230759994, 14383.92808035961, 714.5712572456537, 731.3965978068088, 890.4345740454826, 891.4205766251956, 909.3225546967485, 885.9379843410964, 913.8583519554479, 913.8857642794651, 915.0483497162943, 915.5651065743785, 929.3308817651314, 939.4222553129811, 948.8434408322653, 958.6500927161605, 966.0484422843529, 748.7753673897836, 916.4684482999844, 950.8145911547251, 959.5777857742571, 960.8558716192557, 701.9996504980434, 702.0697575712151, 702.764573867755, 702.8699690557263, 703.6763088979076, 14501.274936253145, 14622.707545849129, 14747.115173089649, 14806.851931480764, 14936.32786524609, 12024.69938251542, 12185.634430967086, 12349.0, 12423.31671758053, 12504.937475312656, 10751.623693170779, 10866.402347870864, 10929.398883061429, 10983.835242471368, 11334.886651133465, 3924.0778417071965, 3956.66368358687, 3980.900477488057, 4078.7348822326453, 4106.548279689232, 72355.0, 78619.0, 79875.0, 82002.0, 82053.58973205155, 9203.953980230055, 9268.443893366419, 9405.43567385959, 9665.275104367201, 9741.805163896683, 21589.352319430127, 21609.67585486219, 21627.134914603554, 21643.783562164175, 21671.891640541697, 7322.121345438582, 7400.0379950606075, 7428.1457632371885, 7506.62466876654, 7565.621718914143, 3291.588551431071, 3653.8903832885057, 3981.960180398217, 4062.3500239961463, 4078.9796051019557, 3332.7333813294877, 3450.8447119879665, 3468.8265586720563, 3472.427049536798, 3474.322507111089, 594.242341015209, 597.0954004682909, 609.7560975609703, 613.4969325153331, 615.5721773367557, 510.97700603472936, 578.3060327606888, 589.2339958054512, 595.4164918380012, 596.2318149296491, 6944.4444444444325, 7216.855662886713, 7780.336368676849, 8257.628406721711, 8326.084130745614, 514.7143335448787, 544.042485226005, 562.7186406796602, 580.0168714029763, 594.3729365519418, 3790.639889210521, 3793.1844653399653, 3834.198432410753, 3866.3233934061427, 3883.611638836094, 79557.60221198857, 80021.59989200134, 85361.57319213364, 86659.0, 87019.0, 53779.0, 60544.69727651333, 68138.65930670414, 72054.0, 72630.910536342, 65285.673571631836, 65587.01619475712, 65613.01580476297, 65651.0, 67270.99999999903, 7534.284242996907, 7631.847363052597, 7740.41946853977, 7785.455018148676, 7799.337056350274, 42380.788096060554, 43652.0, 44140.55859441365, 44286.55713442824, 44633.33050004317, 57312.14031789443, 58842.7057864708, 59074.40925590689, 59479.405205949115, 60355.396446034974, 10342.224333174892, 10375.377477351383, 10425.113865321533, 10479.842802357818, 11098.389588572705, 10996.920862158608, 11066.059384952368, 11327.15046371509, 11428.657140285804, 11993.520259189534, 10375.636852710119, 10501.159907207253, 10562.683119506428, 10794.56821727122, 11293.830592541282, 10298.536565854405, 10340.345544712809, 10870.673879783619, 10890.0, 10976.286541374786, 10124.645637402655, 10559.683209503728, 10656.200784941315, 10671.733206669895, 11191.496382662963, 8386.322735453055, 8548.401611887357, 8704.91295087041, 8800.91199088001, 8899.421537600036, 8749.0, 8885.378023538291, 8964.955175224082, 9028.36801423894, 9033.954830225806], "value": 6639.59781376855, "unit": "score"}], "name": "dromaeo_css", "value": 7016.916392024599, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 148.0, 162.0, 89.0, 163.0, 163.0, 163.0, 112.0, 113.0, 113.0], "value": 134.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 116.0, 121.0, 174.0, 119.0, 120.0, 145.0, 141.0, 207.0, 119.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 204.0, 191.0, 183.0, 183.0, 185.0, 185.0, 182.0, 182.0], "value": 186.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 62.0, 136.0, 57.0, 57.0, 59.0, 69.0, 84.0, 56.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 100.0, 91.0, 107.0, 98.0, 101.0, 69.0, 68.0, 100.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 86.0, 97.0, 134.0, 71.0, 128.0, 129.0, 134.0, 84.0], "value": 103.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 92.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 83.0, 85.0, 83.0, 83.0, 84.0, 83.0, 87.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 59.0, 55.0, 55.0, 57.0, 55.0, 76.0, 57.0, 54.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 69.0, 82.0, 77.0, 104.0, 103.0, 96.0, 71.0, 71.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 106.0, 102.0, 71.0, 98.0, 107.0, 103.0, 104.0, 105.0, 86.0], "value": 98.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 146.0, 127.0, 145.0, 130.0, 142.0, 141.0, 192.0, 138.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 225.0, 202.0, 166.0, 165.0, 165.0, 167.0, 164.0, 161.0, 165.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1526.8, "alertThreshold": 2.0}]} 14:30:04 INFO - Return code: 0 14:30:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:30:04 ERROR - # TBPL SUCCESS # 14:30:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:30:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:30:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:30:04 INFO - 2016-05-24 14:30:04.490 screenresolution[10842:203161] starting screenresolution argv=screenresolution get 14:30:04 INFO - 2016-05-24 14:30:04.493 screenresolution[10842:203161] Display 0: 1600x1200x32@60 14:30:04 INFO - 2016-05-24 14:30:04.499 screenresolution[10843:203164] starting screenresolution argv=screenresolution list 14:30:04 INFO - Available Modes on Display 0 14:30:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:30:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:30:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:30:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:30:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:30:04 INFO - Intel Iris: 14:30:04 INFO - Chipset Model: Intel Iris 14:30:04 INFO - Type: GPU 14:30:04 INFO - Bus: Built-In 14:30:04 INFO - VRAM (Dynamic, Max): 1536 MB 14:30:04 INFO - Vendor: Intel (0x8086) 14:30:04 INFO - Device ID: 0x0a2e 14:30:04 INFO - Revision ID: 0x0009 14:30:04 INFO - Displays: 14:30:04 INFO - Display: 14:30:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:30:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:30:04 INFO - Display Serial Number: 1600x1200 60 14:30:04 INFO - Main Display: Yes 14:30:04 INFO - Mirror: Off 14:30:04 INFO - Online: Yes 14:30:04 INFO - Rotation: Supported 14:30:04 INFO - Return code: 0 14:30:04 INFO - Running post-action listener: _resource_record_post_action 14:30:04 INFO - ##### 14:30:04 INFO - ##### Finished run-tests step (success) 14:30:04 INFO - ##### 14:30:04 INFO - Running post-run listener: _resource_record_post_run 14:30:04 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1667072; Write bytes: 697604096; Read time: 30; Write time: 1946 14:30:04 INFO - TinderboxPrint: CPU usage
53.6% 14:30:04 INFO - TinderboxPrint: I/O read bytes / time
1,667,072 / 30 14:30:04 INFO - TinderboxPrint: I/O write bytes / time
697,604,096 / 1,946 14:30:04 INFO - TinderboxPrint: CPU idle
2,246.1 (46.4%) 14:30:04 INFO - TinderboxPrint: CPU system
971.4 (20.0%) 14:30:04 INFO - TinderboxPrint: CPU user
1,628.2 (33.6%) 14:30:04 INFO - TinderboxPrint: Swap in / out
398,848,000 / 0 14:30:04 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 375548416; Write bytes: 369543168; Read time: 29059; Write time: 698 14:30:04 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1666560; Write bytes: 297478144; Read time: 30; Write time: 1181 14:30:05 INFO - Running post-run listener: _upload_blobber_files 14:30:05 INFO - Blob upload gear active. 14:30:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:30:05 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 14:30:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:30:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:30:05 INFO - (blobuploader) - INFO - Open directory for files ... 14:30:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:30:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:30:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:06 INFO - (blobuploader) - INFO - Done attempting. 14:30:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:30:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:30:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:07 INFO - (blobuploader) - INFO - Done attempting. 14:30:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:30:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:30:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:09 INFO - (blobuploader) - INFO - Done attempting. 14:30:09 INFO - (blobuploader) - INFO - Iteration through files over. 14:30:09 INFO - Return code: 0 14:30:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:30:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:30:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ef20f536084bed276c1f363ccb8a51c2bdad4aeaf559a84468b5a86f12e0b72681ea4f82e0029f0c131ca232bcf2b663664a9935bb38a5a02feca2222ced723", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7e868f430c2aebd37325bd84022e0cbe14c05668946ca181798cb8b78bc22f15d85f6c9a10d2b6df2945088259b195775333d2219bfd8e74db996cf9b8b492", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e367f867feff097a2cdfe35f7e09c63b7b3e3c3719e2a36d2fff39eac52f396d1a155def21836446bdde280fdb356e3ab0a4b2e32f068bb499a58986edb03b"} 14:30:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:30:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:30:09 INFO - Contents: 14:30:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ef20f536084bed276c1f363ccb8a51c2bdad4aeaf559a84468b5a86f12e0b72681ea4f82e0029f0c131ca232bcf2b663664a9935bb38a5a02feca2222ced723", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7e868f430c2aebd37325bd84022e0cbe14c05668946ca181798cb8b78bc22f15d85f6c9a10d2b6df2945088259b195775333d2219bfd8e74db996cf9b8b492", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e367f867feff097a2cdfe35f7e09c63b7b3e3c3719e2a36d2fff39eac52f396d1a155def21836446bdde280fdb356e3ab0a4b2e32f068bb499a58986edb03b"} 14:30:09 INFO - Running post-run listener: copy_logs_to_upload_dir 14:30:09 INFO - Copying logs to upload dir... 14:30:09 INFO - mkdir: /builds/slave/test/build/upload/logs 14:30:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1314.192245 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 54 secs) (at 2016-05-24 14:30:09.809931) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 14:30:09.813158) ========= 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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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/Firefox/sha512/0ef20f536084bed276c1f363ccb8a51c2bdad4aeaf559a84468b5a86f12e0b72681ea4f82e0029f0c131ca232bcf2b663664a9935bb38a5a02feca2222ced723", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7e868f430c2aebd37325bd84022e0cbe14c05668946ca181798cb8b78bc22f15d85f6c9a10d2b6df2945088259b195775333d2219bfd8e74db996cf9b8b492", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e367f867feff097a2cdfe35f7e09c63b7b3e3c3719e2a36d2fff39eac52f396d1a155def21836446bdde280fdb356e3ab0a4b2e32f068bb499a58986edb03b"} build_url:https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/PWhWo3FBTfCB5-RFy72yFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ef20f536084bed276c1f363ccb8a51c2bdad4aeaf559a84468b5a86f12e0b72681ea4f82e0029f0c131ca232bcf2b663664a9935bb38a5a02feca2222ced723", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7e868f430c2aebd37325bd84022e0cbe14c05668946ca181798cb8b78bc22f15d85f6c9a10d2b6df2945088259b195775333d2219bfd8e74db996cf9b8b492", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e367f867feff097a2cdfe35f7e09c63b7b3e3c3719e2a36d2fff39eac52f396d1a155def21836446bdde280fdb356e3ab0a4b2e32f068bb499a58986edb03b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 14:30:09.848073) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 14:30:09.848836) ========= 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.7U7oVFRIq0/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.TTaSIb0gKH/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.005357 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 14:30:09.901378) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 14:30:09.902138) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 14:30:09.902925) ========= ========= Total master_lag: 0.22 =========