builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0306 starttime: 1463601569.99 results: success (0) buildid: 20160517065136 builduid: ab760662d3a1428fa4c9e673ad9c7baf revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:29.992737) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:29.993157) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:29.993446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:30.013375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:30.013742) ========= 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.D24dXcTy6O/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.29S5ni59xV/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-18 12:59:30-- 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% 73.3M=0s 2016-05-18 12:59:30 (73.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139172 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:30.427533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:30.427873) ========= 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.D24dXcTy6O/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.29S5ni59xV/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.026206 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:30.469945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 12:59:30.470294) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --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 3780a3a6b83aeda143f9562829c830410a0c961e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.D24dXcTy6O/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.29S5ni59xV/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-18 12:59:30,554 truncating revision to first 12 chars 2016-05-18 12:59:30,554 Setting DEBUG logging. 2016-05-18 12:59:30,554 attempt 1/10 2016-05-18 12:59:30,554 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3780a3a6b83a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 12:59:31,764 unpacking tar archive at: mozilla-central-3780a3a6b83a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458889 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 12:59:31.947462) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:31.947818) ========= script_repo_revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:31.948219) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:31.948490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:31.961685) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-18 12:59:31.962107) ========= /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.D24dXcTy6O/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.29S5ni59xV/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 12:59:32 INFO - MultiFileLogger online at 20160518 12:59:32 in /builds/slave/test 12:59:32 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 12:59:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:59:32 INFO - {'append_to_log': False, 12:59:32 INFO - 'base_work_dir': '/builds/slave/test', 12:59:32 INFO - 'blob_upload_branch': 'Firefox', 12:59:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:59:32 INFO - 'branch': 'Firefox', 12:59:32 INFO - 'buildbot_json_path': 'buildprops.json', 12:59:32 INFO - 'config_files': ('talos/mac_config.py',), 12:59:32 INFO - 'default_actions': ('clobber', 12:59:32 INFO - 'read-buildbot-config', 12:59:32 INFO - 'download-and-extract', 12:59:32 INFO - 'populate-webroot', 12:59:32 INFO - 'create-virtualenv', 12:59:32 INFO - 'install', 12:59:32 INFO - 'run-tests'), 12:59:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:59:32 INFO - 'download_minidump_stackwalk': True, 12:59:32 INFO - 'download_symbols': 'ondemand', 12:59:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:59:32 INFO - 'tooltool.py': '/tools/tooltool.py', 12:59:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:59:32 INFO - '/tools/misc-python/virtualenv.py')}, 12:59:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:59:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:59:32 INFO - 'installer_path': 'installer.exe', 12:59:32 INFO - 'log_level': 'info', 12:59:32 INFO - 'log_name': 'talos', 12:59:32 INFO - 'log_to_console': True, 12:59:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:59:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:59:32 INFO - 'opt_config_files': (), 12:59:32 INFO - 'pip_index': False, 12:59:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:59:32 INFO - 'cmd': ('bash', 12:59:32 INFO - '-c', 12:59:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:59:32 INFO - 'enabled': True, 12:59:32 INFO - 'halt_on_failure': False, 12:59:32 INFO - 'name': 'check_screen_resolution'},), 12:59:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:59:32 INFO - 'cmd': ('bash', 12:59:32 INFO - '-c', 12:59:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:59:32 INFO - 'enabled': True, 12:59:32 INFO - 'halt_on_failure': False, 12:59:32 INFO - 'name': 'check_screen_resolution'},), 12:59:32 INFO - 'run_cmd_checks_enabled': True, 12:59:32 INFO - 'sps_profile': False, 12:59:32 INFO - 'sps_profile_interval': 0, 12:59:32 INFO - 'suite': 'dromaeojs', 12:59:32 INFO - 'system_bits': '32', 12:59:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:59:32 INFO - 'title': 't-yosemite-r7-0306', 12:59:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:59:32 INFO - 'use_talos_json': True, 12:59:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:59:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:59:32 INFO - 'work_dir': 'build'} 12:59:32 INFO - ##### 12:59:32 INFO - ##### Running clobber step. 12:59:32 INFO - ##### 12:59:32 INFO - Running pre-action listener: _resource_record_pre_action 12:59:32 INFO - Running main action method: clobber 12:59:32 INFO - rmtree: /builds/slave/test/build 12:59:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:59:33 INFO - Running post-action listener: _resource_record_post_action 12:59:33 INFO - ##### 12:59:33 INFO - ##### Finished clobber step (success) 12:59:33 INFO - ##### 12:59:33 INFO - ##### 12:59:33 INFO - ##### Running read-buildbot-config step. 12:59:33 INFO - ##### 12:59:33 INFO - Running pre-action listener: _resource_record_pre_action 12:59:33 INFO - Running main action method: read_buildbot_config 12:59:33 INFO - Using buildbot properties: 12:59:33 INFO - { 12:59:33 INFO - "project": "", 12:59:33 INFO - "product": "firefox", 12:59:33 INFO - "script_repo_revision": "production", 12:59:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:59:33 INFO - "repository": "", 12:59:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:59:33 INFO - "buildid": "20160517065136", 12:59:33 INFO - "pgo_build": "False", 12:59:33 INFO - "basedir": "/builds/slave/test", 12:59:33 INFO - "buildnumber": 243, 12:59:33 INFO - "slavename": "t-yosemite-r7-0306", 12:59:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:59:33 INFO - "platform": "yosemite_r7", 12:59:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:59:33 INFO - "branch": "mozilla-central", 12:59:33 INFO - "repo_path": "mozilla-central", 12:59:33 INFO - "revision": "3780a3a6b83aeda143f9562829c830410a0c961e", 12:59:33 INFO - "stage_platform": "macosx64", 12:59:33 INFO - "builduid": "ab760662d3a1428fa4c9e673ad9c7baf", 12:59:33 INFO - "slavebuilddir": "test" 12:59:33 INFO - } 12:59:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 12:59:33 INFO - Running post-action listener: _resource_record_post_action 12:59:33 INFO - ##### 12:59:33 INFO - ##### Finished read-buildbot-config step (success) 12:59:33 INFO - ##### 12:59:33 INFO - ##### 12:59:33 INFO - ##### Running download-and-extract step. 12:59:33 INFO - ##### 12:59:33 INFO - Running pre-action listener: _resource_record_pre_action 12:59:33 INFO - Running main action method: download_and_extract 12:59:33 INFO - mkdir: /builds/slave/test/build/tests 12:59:33 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')]} 12:59:33 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:59:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:59:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:59:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:35 INFO - Downloaded 1199 bytes. 12:59:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:59:35 INFO - Using the following test package requirements: 12:59:35 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:59:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:59:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:59:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'jsshell-mac.zip'], 12:59:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:59:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:59:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:59:35 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:59:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:59:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:59:35 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:59:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:35 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:59:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:59:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:59:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:37 INFO - Downloaded 18267690 bytes. 12:59:37 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'] 12:59:37 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 12:59:40 INFO - Return code: 0 12:59:40 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 12:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:40 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:59:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:59:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:59:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:42 INFO - Downloaded 18267690 bytes. 12:59:42 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'] 12:59:42 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 12:59:46 INFO - Return code: 0 12:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:46 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:59:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:59:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:59:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:48 INFO - Downloaded 10840194 bytes. 12:59:48 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'] 12:59:48 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 12:59:48 INFO - Return code: 0 12:59: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')]} 12:59:48 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:59:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:59:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:59:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:59:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/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 12:59:55 INFO - Downloaded 109992951 bytes. 12:59:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:59:55 INFO - mkdir: /builds/slave/test/properties 12:59:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:59:55 INFO - Writing to file /builds/slave/test/properties/build_url 12:59:55 INFO - Contents: 12:59:55 INFO - build_url:https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:59:56 INFO - Running post-action listener: _resource_record_post_action 12:59:56 INFO - Running post-action listener: set_extra_try_arguments 12:59:56 INFO - ##### 12:59:56 INFO - ##### Finished download-and-extract step (success) 12:59:56 INFO - ##### 12:59:56 INFO - ##### 12:59:56 INFO - ##### Running populate-webroot step. 12:59:56 INFO - ##### 12:59:56 INFO - Running pre-action listener: _resource_record_pre_action 12:59:56 INFO - Running main action method: populate_webroot 12:59:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:59:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:59:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:59:56 INFO - '--tppagecycles', 12:59:56 INFO - '7'], 12:59:56 INFO - 'tests': ['tsvgm']}}, 12:59:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:59:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:59:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:59:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:59:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['damp', 'tps']}, 12:59:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'tests': ['damp', 'tps']}, 12:59:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['dromaeo_dom']}, 12:59:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:59:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['a11yr', 12:59:56 INFO - 'ts_paint', 12:59:56 INFO - 'tpaint', 12:59:56 INFO - 'sessionrestore', 12:59:56 INFO - 'sessionrestore_no_auto_restore', 12:59:56 INFO - 'tabpaint']}, 12:59:56 INFO - 'other-e10s': {'tests': ['a11yr', 12:59:56 INFO - 'ts_paint', 12:59:56 INFO - 'tpaint', 12:59:56 INFO - 'sessionrestore', 12:59:56 INFO - 'sessionrestore_no_auto_restore', 12:59:56 INFO - 'tabpaint']}, 12:59:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:59:56 INFO - 'ts_paint', 12:59:56 INFO - 'tpaint', 12:59:56 INFO - 'sessionrestore', 12:59:56 INFO - 'sessionrestore_no_auto_restore', 12:59:56 INFO - 'tabpaint']}, 12:59:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:59:56 INFO - 'ts_paint', 12:59:56 INFO - 'tpaint', 12:59:56 INFO - 'sessionrestore', 12:59:56 INFO - 'sessionrestore_no_auto_restore', 12:59:56 INFO - 'tabpaint']}, 12:59:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['a11yr', 12:59:56 INFO - 'ts_paint', 12:59:56 INFO - 'tpaint', 12:59:56 INFO - 'sessionrestore', 12:59:56 INFO - 'sessionrestore_no_auto_restore', 12:59:56 INFO - 'tabpaint']}, 12:59:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['a11yr', 12:59:56 INFO - 'ts_paint', 12:59:56 INFO - 'tpaint', 12:59:56 INFO - 'sessionrestore', 12:59:56 INFO - 'sessionrestore_no_auto_restore', 12:59:56 INFO - 'tabpaint']}, 12:59:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['tsvgx', 12:59:56 INFO - 'tsvgr_opacity', 12:59:56 INFO - 'tart', 12:59:56 INFO - 'tscrollx', 12:59:56 INFO - 'cart']}, 12:59:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:59:56 INFO - 'tsvgr_opacity', 12:59:56 INFO - 'tart', 12:59:56 INFO - 'tscrollx', 12:59:56 INFO - 'cart']}, 12:59:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'talos_options': ['--disable-e10s'], 12:59:56 INFO - 'tests': ['tp5o']}, 12:59:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'tests': ['tp5o']}, 12:59:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'talos_options': ['--disable-e10s', 12:59:56 INFO - '--xperf_path', 12:59:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:56 INFO - 'tests': ['tp5n']}, 12:59:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:56 INFO - 'talos_options': ['--xperf_path', 12:59:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:56 INFO - 'tests': ['tp5n']}}, 12:59:56 INFO - 'talos.zip': {'path': '', 12:59:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:59:56 INFO - Running post-action listener: _resource_record_post_action 12:59:56 INFO - ##### 12:59:56 INFO - ##### Finished populate-webroot step (success) 12:59:56 INFO - ##### 12:59:56 INFO - ##### 12:59:56 INFO - ##### Running create-virtualenv step. 12:59:56 INFO - ##### 12:59:56 INFO - Running pre-action listener: _resource_record_pre_action 12:59:56 INFO - Running main action method: create_virtualenv 12:59:56 INFO - Creating virtualenv /builds/slave/test/build/venv 12:59:56 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 12:59:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:59:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:59:56 INFO - Using real prefix '/tools/python27' 12:59:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:59:57 INFO - Installing distribute.............................................................................................................................................................................................done. 13:00:00 INFO - Installing pip.................done. 13:00:00 INFO - Return code: 0 13:00:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:00 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:00 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 13:00:00 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 13:00:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:00 INFO - 'HOME': '/Users/cltbld', 13:00:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:00 INFO - 'LOGNAME': 'cltbld', 13:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:00 INFO - 'MOZ_NO_REMOTE': '1', 13:00:00 INFO - 'NO_EM_RESTART': '1', 13:00:00 INFO - 'PAGER': '/bin/cat', 13:00:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:00 INFO - 'PWD': '/builds/slave/test', 13:00:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:00 INFO - 'SHELL': '/bin/bash', 13:00:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:00 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:00 INFO - 'USER': 'cltbld', 13:00:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:00 INFO - 'XPC_FLAGS': '0x0', 13:00:00 INFO - 'XPC_SERVICE_NAME': '0', 13:00:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:00 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 13:00:00 INFO - Cleaning up... 13:00:00 INFO - Return code: 0 13:00:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:00 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:00 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 13:00:00 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 13:00:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:00 INFO - 'HOME': '/Users/cltbld', 13:00:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:00 INFO - 'LOGNAME': 'cltbld', 13:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:00 INFO - 'MOZ_NO_REMOTE': '1', 13:00:00 INFO - 'NO_EM_RESTART': '1', 13:00:00 INFO - 'PAGER': '/bin/cat', 13:00:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:00 INFO - 'PWD': '/builds/slave/test', 13:00:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:00 INFO - 'SHELL': '/bin/bash', 13:00:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:00 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:00 INFO - 'USER': 'cltbld', 13:00:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:00 INFO - 'XPC_FLAGS': '0x0', 13:00:00 INFO - 'XPC_SERVICE_NAME': '0', 13:00:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:00 INFO - Downloading/unpacking psutil>=3.1.1 13:00:00 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 13:00:00 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 13:00:00 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 13:00:00 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 13:00:00 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 13:00:00 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 13:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:00:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:03 INFO - Installing collected packages: psutil 13:00:03 INFO - Running setup.py install for psutil 13:00:03 INFO - building 'psutil._psutil_osx' extension 13:00:03 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 13:00:04 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 13:00:04 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 13:00:04 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 13:00:04 INFO - building 'psutil._psutil_posix' extension 13:00:04 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 13:00:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:00:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:00:04 INFO - ^ 13:00:04 INFO - 1 warning generated. 13:00:04 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 13:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:04 INFO - Successfully installed psutil 13:00:04 INFO - Cleaning up... 13:00:04 INFO - Return code: 0 13:00:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:04 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:04 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 13:00:04 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 13:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:04 INFO - 'HOME': '/Users/cltbld', 13:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:04 INFO - 'LOGNAME': 'cltbld', 13:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:04 INFO - 'MOZ_NO_REMOTE': '1', 13:00:04 INFO - 'NO_EM_RESTART': '1', 13:00:04 INFO - 'PAGER': '/bin/cat', 13:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:04 INFO - 'PWD': '/builds/slave/test', 13:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:04 INFO - 'SHELL': '/bin/bash', 13:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:04 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:04 INFO - 'USER': 'cltbld', 13:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:04 INFO - 'XPC_FLAGS': '0x0', 13:00:04 INFO - 'XPC_SERVICE_NAME': '0', 13:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:00:05 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 13:00:05 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 13:00:05 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 13:00:05 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 13:00:05 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 13:00:05 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 13:00:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:00:07 INFO - Installing collected packages: mozsystemmonitor 13:00:07 INFO - Running setup.py install for mozsystemmonitor 13:00:07 INFO - Successfully installed mozsystemmonitor 13:00:07 INFO - Cleaning up... 13:00:07 INFO - Return code: 0 13:00:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:07 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:07 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 13:00:07 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 13:00:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:07 INFO - 'HOME': '/Users/cltbld', 13:00:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:07 INFO - 'LOGNAME': 'cltbld', 13:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:07 INFO - 'MOZ_NO_REMOTE': '1', 13:00:07 INFO - 'NO_EM_RESTART': '1', 13:00:07 INFO - 'PAGER': '/bin/cat', 13:00:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:07 INFO - 'PWD': '/builds/slave/test', 13:00:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:07 INFO - 'SHELL': '/bin/bash', 13:00:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:07 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:07 INFO - 'USER': 'cltbld', 13:00:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:07 INFO - 'XPC_FLAGS': '0x0', 13:00:07 INFO - 'XPC_SERVICE_NAME': '0', 13:00:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:08 INFO - Downloading/unpacking blobuploader==1.2.4 13:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:10 INFO - Downloading blobuploader-1.2.4.tar.gz 13:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:00:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:00:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:11 INFO - Downloading docopt-0.6.1.tar.gz 13:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:00:11 INFO - Installing collected packages: blobuploader, requests, docopt 13:00:11 INFO - Running setup.py install for blobuploader 13:00:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:00:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:00:11 INFO - Running setup.py install for requests 13:00:12 INFO - Running setup.py install for docopt 13:00:12 INFO - Successfully installed blobuploader requests docopt 13:00:12 INFO - Cleaning up... 13:00:12 INFO - Return code: 0 13:00:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:12 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:12 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 13:00:12 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 13:00:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:12 INFO - 'HOME': '/Users/cltbld', 13:00:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:12 INFO - 'LOGNAME': 'cltbld', 13:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:12 INFO - 'MOZ_NO_REMOTE': '1', 13:00:12 INFO - 'NO_EM_RESTART': '1', 13:00:12 INFO - 'PAGER': '/bin/cat', 13:00:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:12 INFO - 'PWD': '/builds/slave/test', 13:00:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:12 INFO - 'SHELL': '/bin/bash', 13:00:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:12 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:12 INFO - 'USER': 'cltbld', 13:00:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:12 INFO - 'XPC_FLAGS': '0x0', 13:00:12 INFO - 'XPC_SERVICE_NAME': '0', 13:00:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-b8NeMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-RNlqVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-iJlwed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-pFEDc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-h1qkeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-B2ig3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-VPvJbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-FBGtD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-mVfJS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-CtT44Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-EYVk8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-lbd9KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Eg5Q82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-CVGjVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-9MP1TE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ytorUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-UH5jkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:00:14 INFO - Running setup.py install for manifestparser 13:00:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:00:14 INFO - Running setup.py install for mozcrash 13:00:14 INFO - Running setup.py install for mozdebug 13:00:15 INFO - Running setup.py install for mozdevice 13:00:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Running setup.py install for mozfile 13:00:15 INFO - Running setup.py install for mozhttpd 13:00:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Running setup.py install for mozinfo 13:00:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Running setup.py install for mozInstall 13:00:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:00:15 INFO - Running setup.py install for mozleak 13:00:15 INFO - Running setup.py install for mozlog 13:00:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:00:16 INFO - Running setup.py install for moznetwork 13:00:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:00:16 INFO - Running setup.py install for mozprocess 13:00:16 INFO - Running setup.py install for mozprofile 13:00:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:00:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:00:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:00:16 INFO - Running setup.py install for mozrunner 13:00:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:00:16 INFO - Running setup.py install for mozscreenshot 13:00:16 INFO - Running setup.py install for moztest 13:00:17 INFO - Running setup.py install for mozversion 13:00:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:00:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:00:17 INFO - Cleaning up... 13:00:17 INFO - Return code: 0 13:00:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:17 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')]} 13:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:17 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')]} 13:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:17 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:17 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 13:00:17 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 13:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:17 INFO - 'HOME': '/Users/cltbld', 13:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:17 INFO - 'LOGNAME': 'cltbld', 13:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:17 INFO - 'MOZ_NO_REMOTE': '1', 13:00:17 INFO - 'NO_EM_RESTART': '1', 13:00:17 INFO - 'PAGER': '/bin/cat', 13:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:17 INFO - 'PWD': '/builds/slave/test', 13:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:17 INFO - 'SHELL': '/bin/bash', 13:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:17 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:17 INFO - 'USER': 'cltbld', 13:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:17 INFO - 'XPC_FLAGS': '0x0', 13:00:17 INFO - 'XPC_SERVICE_NAME': '0', 13:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:17 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Ojhfgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:17 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)) 13:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:17 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-WwAs5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:17 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)) 13:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:17 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-REqdUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:17 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)) 13:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:17 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-6zPIZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:17 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)) 13:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:17 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ppFoho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-D3HEaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-mNoGCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-4VegzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-i0s2lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-1xdilt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ts5Flv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-MQTWTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-gug3GA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-_DLccS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:18 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)) 13:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Nsipq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:19 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)) 13:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-KLpXff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:19 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)) 13:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-O8fdoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:19 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)) 13:00:19 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)) 13:00:19 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)) 13:00:19 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)) 13:00:19 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)) 13:00:19 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)) 13:00:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:21 INFO - Downloading blessings-1.6.tar.gz 13:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:00:21 INFO - Installing collected packages: blessings 13:00:21 INFO - Running setup.py install for blessings 13:00:21 INFO - Successfully installed blessings 13:00:21 INFO - Cleaning up... 13:00:22 INFO - Return code: 0 13:00:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:22 INFO - Reading from file tmpfile_stdout 13:00:22 INFO - Current package versions: 13:00:22 INFO - blessings == 1.6 13:00:22 INFO - blobuploader == 1.2.4 13:00:22 INFO - docopt == 0.6.1 13:00:22 INFO - manifestparser == 1.1 13:00:22 INFO - mozInstall == 1.12 13:00:22 INFO - mozcrash == 0.17 13:00:22 INFO - mozdebug == 0.1 13:00:22 INFO - mozdevice == 0.48 13:00:22 INFO - mozfile == 1.2 13:00:22 INFO - mozhttpd == 0.7 13:00:22 INFO - mozinfo == 0.9 13:00:22 INFO - mozleak == 0.1 13:00:22 INFO - mozlog == 3.2 13:00:22 INFO - moznetwork == 0.27 13:00:22 INFO - mozprocess == 0.22 13:00:22 INFO - mozprofile == 0.28 13:00:22 INFO - mozrunner == 6.11 13:00:22 INFO - mozscreenshot == 0.1 13:00:22 INFO - mozsystemmonitor == 0.3 13:00:22 INFO - moztest == 0.7 13:00:22 INFO - mozversion == 1.4 13:00:22 INFO - psutil == 3.1.1 13:00:22 INFO - requests == 1.2.3 13:00:22 INFO - wsgiref == 0.1.2 13:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:22 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')]} 13:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:22 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')]} 13:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:22 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:22 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 13:00:22 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 13:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:22 INFO - 'HOME': '/Users/cltbld', 13:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:22 INFO - 'LOGNAME': 'cltbld', 13:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:22 INFO - 'MOZ_NO_REMOTE': '1', 13:00:22 INFO - 'NO_EM_RESTART': '1', 13:00:22 INFO - 'PAGER': '/bin/cat', 13:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:22 INFO - 'PWD': '/builds/slave/test', 13:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:22 INFO - 'SHELL': '/bin/bash', 13:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:22 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:22 INFO - 'USER': 'cltbld', 13:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:22 INFO - 'XPC_FLAGS': '0x0', 13:00:22 INFO - 'XPC_SERVICE_NAME': '0', 13:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 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)) 13:00:22 INFO - Cleaning up... 13:00:22 INFO - Return code: 0 13:00:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:00:22 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')]} 13:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:22 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')]} 13:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:22 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:22 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 13:00:22 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 13:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:22 INFO - 'HOME': '/Users/cltbld', 13:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:22 INFO - 'LOGNAME': 'cltbld', 13:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:22 INFO - 'MOZ_NO_REMOTE': '1', 13:00:22 INFO - 'NO_EM_RESTART': '1', 13:00:22 INFO - 'PAGER': '/bin/cat', 13:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:22 INFO - 'PWD': '/builds/slave/test', 13:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:22 INFO - 'SHELL': '/bin/bash', 13:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:22 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:22 INFO - 'USER': 'cltbld', 13:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:22 INFO - 'XPC_FLAGS': '0x0', 13:00:22 INFO - 'XPC_SERVICE_NAME': '0', 13:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:22 INFO - Downloading/unpacking jsonschema 13:00:22 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 13:00:22 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 13:00:22 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 13:00:22 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 13:00:22 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 13:00:22 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 13:00:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:00:25 INFO - Downloading/unpacking functools32 (from jsonschema) 13:00:25 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 13:00:25 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 13:00:25 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 13:00:25 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 13:00:25 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 13:00:25 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 13:00:25 INFO - Downloading functools32-3.2.3-2.tar.gz 13:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:00:25 INFO - warning: no files found matching '*.txt' 13:00:25 INFO - no previously-included directories found matching 'build' 13:00:25 INFO - no previously-included directories found matching 'dist' 13:00:25 INFO - no previously-included directories found matching '.git*' 13:00:25 INFO - Installing collected packages: jsonschema, functools32 13:00:25 INFO - Running setup.py install for functools32 13:00:25 INFO - warning: no files found matching '*.txt' 13:00:25 INFO - no previously-included directories found matching 'build' 13:00:25 INFO - no previously-included directories found matching 'dist' 13:00:25 INFO - no previously-included directories found matching '.git*' 13:00:25 INFO - Successfully installed jsonschema functools32 13:00:25 INFO - Cleaning up... 13:00:26 INFO - Return code: 0 13:00:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:00:26 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')]} 13:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:26 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')]} 13:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:26 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 0x1073b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb40bcb1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, '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.D24dXcTy6O/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.29S5ni59xV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 13:00:26 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 13:00:26 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 13:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:26 INFO - 'HOME': '/Users/cltbld', 13:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:26 INFO - 'LOGNAME': 'cltbld', 13:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:26 INFO - 'MOZ_NO_REMOTE': '1', 13:00:26 INFO - 'NO_EM_RESTART': '1', 13:00:26 INFO - 'PAGER': '/bin/cat', 13:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:26 INFO - 'PWD': '/builds/slave/test', 13:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:26 INFO - 'SHELL': '/bin/bash', 13:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:00:26 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:26 INFO - 'USER': 'cltbld', 13:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:26 INFO - 'XPC_FLAGS': '0x0', 13:00:26 INFO - 'XPC_SERVICE_NAME': '0', 13:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:26 INFO - Downloading/unpacking flake8 13:00:26 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 13:00:26 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 13:00:26 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 13:00:26 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 13:00:26 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 13:00:26 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 13:00:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:00:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:00:29 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 13:00:29 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 13:00:29 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 13:00:29 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 13:00:29 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 13:00:29 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 13:00:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:00:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:00:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:00:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:00:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:00:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:00:30 INFO - no previously-included directories found matching 'docs/_build' 13:00:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:00:30 INFO - Running setup.py install for pep8 13:00:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:00:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:00:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:00:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:00:31 INFO - no previously-included directories found matching 'docs/_build' 13:00:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:00:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:00:31 INFO - Cleaning up... 13:00:31 INFO - Return code: 0 13:00:31 INFO - Running post-action listener: _resource_record_post_action 13:00:31 INFO - Running post-action listener: _start_resource_monitoring 13:00:31 INFO - Starting resource monitoring. 13:00:31 INFO - ##### 13:00:31 INFO - ##### Finished create-virtualenv step (success) 13:00:31 INFO - ##### 13:00:31 INFO - ##### 13:00:31 INFO - ##### Running install step. 13:00:31 INFO - ##### 13:00:31 INFO - Running pre-action listener: _resource_record_pre_action 13:00:31 INFO - Running main action method: install 13:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:31 INFO - Reading from file tmpfile_stdout 13:00:31 INFO - Detecting whether we're running mozinstall >=1.0... 13:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:00:31 INFO - Reading from file tmpfile_stdout 13:00:31 INFO - Output received: 13:00:31 INFO - Usage: mozinstall [options] installer 13:00:31 INFO - Options: 13:00:31 INFO - -h, --help show this help message and exit 13:00:31 INFO - -d DEST, --destination=DEST 13:00:31 INFO - Directory to install application into. [default: 13:00:31 INFO - "/builds/slave/test"] 13:00:31 INFO - --app=APP Application being installed. [default: firefox] 13:00:31 INFO - mkdir: /builds/slave/test/build/application 13:00:31 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'] 13:00:31 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 13:01:05 INFO - Reading from file tmpfile_stdout 13:01:05 INFO - Output received: 13:01:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:01:05 INFO - Running post-action listener: _resource_record_post_action 13:01:05 INFO - ##### 13:01:05 INFO - ##### Finished install step (success) 13:01:05 INFO - ##### 13:01:05 INFO - ##### 13:01:05 INFO - ##### Running run-tests step. 13:01:05 INFO - ##### 13:01:05 INFO - Running pre-action listener: _resource_record_pre_action 13:01:05 INFO - Running main action method: run_tests 13:01:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:01:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:01:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:01:05 INFO - 2016-05-18 13:01:05.044 screenresolution[1645:7612] starting screenresolution argv=screenresolution get 13:01:05 INFO - 2016-05-18 13:01:05.047 screenresolution[1645:7612] Display 0: 1600x1200x32@60 13:01:05 INFO - 2016-05-18 13:01:05.053 screenresolution[1646:7614] starting screenresolution argv=screenresolution list 13:01:05 INFO - Available Modes on Display 0 13:01:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:01:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:01:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:01:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:01:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:01:05 INFO - Intel Iris: 13:01:05 INFO - Chipset Model: Intel Iris 13:01:05 INFO - Type: GPU 13:01:05 INFO - Bus: Built-In 13:01:05 INFO - VRAM (Dynamic, Max): 1536 MB 13:01:05 INFO - Vendor: Intel (0x8086) 13:01:05 INFO - Device ID: 0x0a2e 13:01:05 INFO - Revision ID: 0x0009 13:01:05 INFO - Displays: 13:01:05 INFO - Display: 13:01:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:01:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:01:05 INFO - Display Serial Number: 1600x1200 60 13:01:05 INFO - Main Display: Yes 13:01:05 INFO - Mirror: Off 13:01:05 INFO - Online: Yes 13:01:05 INFO - Rotation: Supported 13:01:05 INFO - Return code: 0 13:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:01:05 INFO - Python 2.7.3 13:01:05 INFO - Return code: 0 13:01:05 INFO - grabbing minidump binary from tooltool 13:01:05 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')]} 13:01:05 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 0x107258d30>, '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 0x10707cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10724fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:01:05 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 13:01:05 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 13:01:05 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 13:01:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:01:05 INFO - Return code: 0 13:01:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:01:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:01:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:01:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:01:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:01:06 INFO - Return code: 0 13:01:06 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/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 13:01:06 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/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0306 --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 13:01:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D24dXcTy6O/Render', 13:01:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:06 INFO - 'HOME': '/Users/cltbld', 13:01:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:06 INFO - 'LOGNAME': 'cltbld', 13:01:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:01:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:06 INFO - 'MOZ_NO_REMOTE': '1', 13:01:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:01:06 INFO - 'NO_EM_RESTART': '1', 13:01:06 INFO - 'PAGER': '/bin/cat', 13:01:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:06 INFO - 'PWD': '/builds/slave/test', 13:01:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:01:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:06 INFO - 'SHELL': '/bin/bash', 13:01:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29S5ni59xV/Listeners', 13:01:06 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 13:01:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:06 INFO - 'USER': 'cltbld', 13:01:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:06 INFO - 'XPC_FLAGS': '0x0', 13:01:06 INFO - 'XPC_SERVICE_NAME': '0', 13:01:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:06 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/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 13:01:06 INFO - mozversion application_buildid: 20160517065136 13:01:06 INFO - mozversion application_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:01:06 INFO - mozversion application_display_name: Nightly 13:01:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:01:06 INFO - mozversion application_name: Firefox 13:01:06 INFO - mozversion application_remotingname: firefox 13:01:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:01:06 INFO - mozversion application_vendor: Mozilla 13:01:06 INFO - mozversion application_version: 49.0a1 13:01:06 INFO - mozversion platform_buildid: 20160517065136 13:01:06 INFO - mozversion platform_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:01:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:01:06 INFO - mozversion platform_version: 49.0a1 13:01:06 INFO - using testdate: 1463601666 13:01:06 INFO - actual date: 1463601666 13:01:06 INFO - starting webserver on 'localhost:49279' 13:01:06 INFO - SUITE-START | Running 2 tests 13:01:06 INFO - TEST-START | dromaeo_css 13:01:06 INFO - operating with platform_type : mac_ 13:01:06 INFO - Initialising browser for dromaeo_css test... 13:01:06 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpHQz9yr/profile http://localhost:49279/getInfo.html) 13:01:09 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 13:01:09 INFO - PROCESS | 1664 | colorDepth:24 13:01:09 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 13:01:09 INFO - PROCESS | 1664 | __metrics 13:01:09 INFO - PROCESS | 1664 | 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" 13:01:09 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:01:09 INFO - TEST-INFO | 1664: exit 0 13:01:09 INFO - Browser initialized. 13:01:09 INFO - Running cycle 1/1 for dromaeo_css test... 13:01:09 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpHQz9yr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:21 INFO - PROCESS | 1666 | RSS: Main: 239132672 13:01:21 INFO - PROCESS | 1666 | 13:02:12 INFO - PROCESS | 1666 | 1463601732952 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] 13:04:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 13:04:26 INFO - PROCESS | 1666 | RSS: Main: 371544064 13:04:26 INFO - PROCESS | 1666 | 13:07:29 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 13:07:29 INFO - PROCESS | 1666 | RSS: Main: 376414208 13:07:29 INFO - PROCESS | 1666 | 13:10:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 13:10:32 INFO - PROCESS | 1666 | RSS: Main: 371376128 13:10:32 INFO - PROCESS | 1666 | 13:13:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 13:13:35 INFO - PROCESS | 1666 | RSS: Main: 368746496 13:13:35 INFO - PROCESS | 1666 | 13:16:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 13:16:39 INFO - PROCESS | 1666 | RSS: Main: 335015936 13:16:39 INFO - PROCESS | 1666 | 13:19:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 13:19:43 INFO - PROCESS | 1666 | RSS: Main: 382984192 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | __start_tp_report 13:19:43 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 13:19:43 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 13:19:43 INFO - PROCESS | 1666 | |i|pagename|runs| 13:19:43 INFO - PROCESS | 1666 | |0;dojo.html;3466.9826650866726;3477.217626034142;3505.9298814023705;3515.4902539131817;3563.9109022274456;5641.915371269424;5681.23303354047;5693.28833895763;5778.248827652399;5789.623674461168;4860.2709593560985;5791;5792.971035144829;5796.536277097822;5801.82594522165;5177.741112944338;5217.739113044351;5241.371035475749;5250.7637156328055;5305.602079844008;4172.374143878412;5286.814961476349;5316.282301889234;5317.680939143664;5333.546648534879;5172.767225474844;5189.040027594907;5213.921791173136;5221.373435187783;5235.842924712265;5296.576273898098;5299.54953828925;5315.867103322448;5318.25544423781;5376.892462150774;5092.898142037176;5101.438841727407;5121.180611102243;5228.47715228478;5231.895362092775;5624.91562626561;5629.8592535186945;5675.716214189274;5699.173619825144;5726.255586773693;6311.179546658951;6318.810435686937;6321.968390158066;6356.2054743157105;6369.904451433232;6196.659183744892;6278.089676996809;6344.1435406220035;6358.58669186506;6379.106925030499;5923.851903702442;5954.4343287388065;5965.910511342333;5970.31341395745;5981.700914954322;6341.175647165931;6351.396617321347;6355.777547785805;6360;6361.681915904186;6104.694765261719;6111.938880611137;6121.209103132296;6192.071189321638;6280.466160376419;5143.434222235551;6196.411340922605;6291.056341548804;6302.338254483305;6353.68231588428;6271.404216599416;6289.0252010938375;6289.654069026156;6318.557700960981;6325.114483972247;6251.874962500725;6262.718177682015;6294.937050629526;6320;6325.3674632537295;4900.313956046085;4910.852674419773;4926.064047830901;4947.604191664658;4961.826336078292;4420.314851198005;4422.181896349186;4426.3581780641625;4443.533428989909;4490.528494508095;5652.293463317085;5670.149477578314;5692.9146062809095;5706.494695567409;5756.309242890796;5969.940300596939;6192.535559832941;6263.906041409383;6284.308726040132;6288.748450062038;5764.452377024176;5782.10377391505;5793.275840519935;5806.82579522615;5823.854403639943;5341.278927344836;5450.727463626803;5459.454054594588;5470.425605311465;5475.780968761284;5900.350961394329;6255.311915689362;6308.968455157695;6334.398232167937;6352.428281454691;6292.276388215414;6338.968305158444;6350.237971443272;6352.205974253218;6387.712552935222;6286.5599408041;6314.147590075465;6325.905111423424;6336.936630633634;6342.334054924351;6097.4036925702085;6160.691965401802;6199.008158694587;6232.875342493034;6312.179416675803;6151.784687535915;6351.142595749515;6352.523560732871;6363.172787537591;6382.521310901608;6010.7295171716505;6183.907241391293;6221.346758590283;6221.813345599639;6236.781712640036;6081.057436097412;6105.763021687103;6110.5587591967305;6186.443220110032;6192.164057852132;5974.790882319098;5983.880322393615;6000.969995149996;6010.4891084258325;6048.818535443944;6010.609310394985;6109.877802443837;6166.044263139221;6185.8144255672405;6233.937660623517;5959.28488581373;6023.066424704178;6023.879522409615;6038.849028774316;6046.244219472566 13:19:43 INFO - PROCESS | 1666 | |1;ext.html;511.6495200787463;513.2275924733277;532.3930718980363;538.8922215556889;564.6104188110205;30755.538666919987;42325.78837105814;43414.131717365635;43433.56566434335;43482;45435;45678.77160614201;45696.77151614246;45842.77078614602;45918.54081459193;8627.568621568927;9708.466034368124;9890.505474726271;9894.01059894012;9906.603735850558;8758.824823503526;9388.436693798394;11673.124515661317;11698.239614425078;11802.173847830656;11734.29594224345;11779.352135632536;11809.055275577975;11817.054635629172;11820.231684940494;8640.870386944202;11549.249298795552;11794.05647548194;11827.34949577773;11886.643400697949;11663.475143618558;11757.294562326288;11757.58848440309;11766.941165294118;11771.293722376684;41395.586044139476;41694.58305416937;41695.374569381485;41734;41754;3179.537412790119;3298.09302441828;3433.7939723616664;3473.426884564044;3481.8085005324697;29577.704222957924;29664.8516757417;29728.108156755334;30250.54624180639;31106.377872442423;2613.3335999320243;2645.206438068591;2672.2517695045417;2687.086390627196;2720.387912719642;513248;516130;517753.4112329452;517754;517905.410472949;36040.81979590138;66238.66880665663;67629;67658.66170669213;67801.66099169472;36382.09044773902;38132.186781321645;38132.809335953694;38168;38203;14675.92662036683;14763.77854332186;14785.630359241106;14789;14841.925790370979;11531.654050378502;11742.647720568397;11749.177557570887;11969.46137423818;12712.42794074269;7918.604069796487;8211.95894020538;8416.957915210507;8427.367947403969;8461.746147615582;3439.0370696205105;3443.466262729281;3448.2930999145115;3453.0131436307415;3479.1302174456387;966.3235734985539;1014.5688082564881;1072.8819829818713;1108.5288752280248;1121.7083558274908;27599.034033809123;29082;30340.24149396283;30508.389832203233;31119;1055.762453447968;1084.9077828384518;1142.046391263288;1168.9123315751183;1214.732758793064;15415.768763468557;15787.447439339569;15836.366545338058;16037.59906002336;16085.988147794596;630905;708839;720155;721840.3907980531;725134;63664;64262.6786866072;64298.035529467095;64374.67812660907;64390.678046609464;1332.393760838816;1377.8897688185123;1457.3733294683389;1473.351725240912;1483.6216764724927;29673.437647047547;31606.577704003834;31808.39183152506;31844.52233216457;33029.50455743118;54249.45750542602;54281.45718542922;54848.90302194014;54887.17669234885;54940.17589736077;566135;573547;574042;575640;576360;23097.88451057734;23969.52060958806;26104;26527.602085969113;27518.862405687843;36741;39074;39607;39683.2063358737;46445.071098577166;37736.433953490174;39241.01897452587;39787.2042559153;40463.59536404716;40796.18407631772;38554.22891542209;38670;38932;39573.40639890461;40077.59922400739;38183.809080954416;39065.702772694414;39367.80316098401;39529;39638;33750.49374259339;34235.14412139716;34928.65071349254;35073;35090.473642895886;21703.782962170175;23800.80995950161;25662.743372566783;25815.225543233733;25896.61155082713 13:19:43 INFO - PROCESS | 1666 | |2;jquery.html;359.81649358826996;375.69005570404414;403.19964869733576;416.5272415808057;421.58263319313886;21108.472288192785;21752.456188595293;22075.44811379714;22086.779132208674;22999.77000229997;20887.373378798624;21045.473863153427;21771.564568708618;22066.66899996497;22074.66887996681;5882.91175632367;6135.570510064297;6156.7537298508005;6160.414760597759;6164.691765411734;6034.728437220336;6035.939640603582;6115.541334399915;6121.234845644294;6146.078088286748;5923.141144534062;6077.361877002917;6082.695865206744;6113.174721412619;6120.2043734314475;5912.260967379078;6001.729922153513;6057.42454466825;6077;6079.361667024943;5274.208868669692;5282.471752824726;5298.443663415363;5303.761330740126;5306.946930530684;26669.066582669515;26896;27389.904403823824;27537.724622753918;27664.446711065866;2154.569086182754;2364.7871691547844;2409.373562873648;2685.7314268572986;2712.742289482496;15222.46721364747;15283.159426231552;15529.611759705986;15672.764908526382;15922.76115858263;1749.3352526040023;1813.655405472969;1827.4243613261913;1838.8528917686554;1847.0764617691154;82987.58506207491;95111.52444237804;104463.47768261186;105749.47125264401;109020;11410.201285909907;11534.538618455168;11545.076393888468;11612.94193529027;11738;12946.417411216518;14447.566573002827;14533.34599943005;14572.251329840487;14584.562463126123;8462.957685211657;8858.424202426822;8898.911010889939;8906.86639700405;9047.230985366186;8882.333824963154;8902.42134261271;8907.821843563092;9035.819283614292;9054.637814487347;5842.503387212049;6800.285969973181;6970.860582788316;6971.755988540377;7003.089598352181;1657.685039842426;1750.8949463032004;1820.8164692949688;1900.8859468431663;1941.0876887862682;981.9754506137261;1003.3027046202819;1012.473513772834;1041.2294901772634;1097.8078836203633;21482.462938426663;22000.33998980033;22114.115435382726;22151.667724984138;22223.66664500034;840.8570543007703;954.4654993203828;988.357567581078;996.531630133836;1068.3216157739892;11035.282706624044;11798.764024719458;11820.763584728258;11825.290482571074;11873.821892671616;609446;611668;613615;625119;629526;117430;120276;120382.42210610663;121535.39232303781;122052.38973805074;1056.2447849787438;1060.6393826099008;1087.6682611803317;1091.6233899304727;1093.33853018924;19123.617527649647;19150.61698765989;19440.2223911042;19503.51241218924;19596.510087247934;22620;35663.82168089143;47648.2852757202;48123.75938120287;50008.49991500184;70823.64588177027;73507.89738153825;78126;80690;83034.92412689731;7231.240719724353;7593.5823529706395;7681.61591920413;7770.53376797394;7950.125486196398;9728.756781080247;11693.590724324607;12008.219465734699;12105.334206618369;12594.937025314815;10530.578776849146;10627.415492148006;10757.031867131996;10826.242163048511;10946.233763636788;10382.532786024496;10545;10590.523426445983;10735;10792.784144316913;10568.947155264175;10866.565337386732;10934.835977460503;11132.276402033842;11142.220044596801;7210.495265331377;7586.444778820218;7637;7669.118051424158;7911.28798408123;9732.951335243562;10664.360138391721;10936.070434013198;10967.287126336763;11017.889821101686 13:19:43 INFO - PROCESS | 1666 | |3;mootools.html;1245.8816412440815;1488.181500174904;1613.1611561987763;1884.0579710144918;2039.7246371739811;8187.304079153271;8893.644254229832;9142.542872856364;9143.588538515765;9254.537273136333;8773.034966153718;8919.598618062186;8940.19538241558;9027.232685221765;9080.182783549495;2418.842775219614;2446.71862735785;2451.044092803808;2453.362125847275;2453.6319552067152;2392.078105426419;2441.8534887906694;2447.4737931344757;2449.2284930246915;2451.5464639041834;2412.2160297903165;2422.4186195313173;2435.537247922889;2452.865092419916;2453.8036957043396;2388.796952259043;2394.9281521554294;2402.0511897800448;2404.7715467030603;2432.3554258121612;2337.135259953811;2405.458771776354;2407.518496300747;2408.0849277274697;2413.0589070262613;7783.844323113506;8459.98480182382;9176.174144326977;9246.26029917605;9247.075292470767;5301.920471192935;5520.861978450571;5525.696086715229;5572.108462645998;5574.498295153456;2944.1903476543907;3365.7546707718056;3444.156181735466;3508.4912687660403;3510.350585141757;904.7738065483629;928.4754113925678;942.9858552121724;1542.159523059935;1615.1601167393007;12174.939125304405;12202.328871912041;12259.387030648431;12292.692682682915;12299.56951506702;4584.61030812378;4646.024334889644;4719.834805781781;4724.574788269072;4740.170470167707;9693.273004524688;9708.368956017835;9740.123388894892;9744.512774361254;9745.805083898424;5478.342598888079;5480.260164877691;5482.945170548243;5487.396386397493;5496.477834605706;4966.180580204298;5011.6241281904;5061.6962982221185;5123.38519377677;5148.742562871841;3607.1162565171703;3650.105724097613;3680.963190368062;3717.033571271489;3742.0831896185064;1094.2559059839227;1115.2583531951161;1119.6529075986307;1125.836753670713;1130.1410927694847;901.8872640919885;923.8337099322187;926.3422969691594;927.142393286221;940.3229674634252;5857.47282744555;6035.758569657164;6088.6042407243385;6146.938530614726;6250.968745156245;858.3090612057229;992.156666833186;1034.5034383495813;1079.4440862955585;1089.9564017439372;3966.6231707987695;4062.471878655756;4072.6741860651077;4130.091379896418;4143.316352801754;8913.42062765931;10985.560577576967;11318.547258109584;11343.149263805248;11354.375509347065;11066.557337706563;11175.105991520659;11191.608293709682;11228.382438965771;11260.831087533694;8066.23370779775;8383.455075420079;8438.409311348269;8544.060153383123;8577.614007369682;6007.789727359522;6013.548983826318;6062.878367502026;6116.5718399711595;6179.382061793965;10001.2999090063;10370.222233332379;10398.324108932895;10401.94799026;10406.843897341394;9811.166050885755;10095.798084038424;10125.848112278469;10551.261411701107;10560.894391055992;8367.456115352483;8432.156784321518;8513.489190648581;8544;8546.2308392245;8403.453775504573;8423.663053477792;8481.194286542768;8491.490510569385;8499.575021249037;7422.109346878193;7670.7698769037015;7690.2694244046725;7762.107357653942;7805.375569954504;7537.472376933562;7549.018627578599;7651.88522172179;7663;7713.382929365749;6656.267810540933;6787.762428315183;7026.051483049723;7072.5402848814665;7075.186353569406;4073.674106071448;4693.460252071056;4869.147899117711;4898.461169271449;5109.3613298337705;4819.783109759999;4962.851114466572;5009.298698182184;5025.195968644999;5196.636235463632 13:19:43 INFO - PROCESS | 1666 | |4;prototype.html;309.6949504737833;310.35755985110814;322.7692200076945;327.91310302769756;330.82631618400336;4611.723296602207;5299.920501192485;5521.917171242435;5522.392536820947;5578.429804924841;5221.817236396727;5448.42791506892;5456.61803673743;5473.549509380017;5501.661793737656;3705.925881482369;4041.97979010106;4042.3128068228325;4169.499660040799;4206.495220573535;3521.5421995140596;3619.7466177367596;3867.943347297033;4222.746635201897;4236.724612900151;3922.3134052357473;4084.264832330179;4114.280000999828;4157.958420415803;4158.106007208449;4132.772697501636;4149.066460046495;4160.604742549453;4170.436991006206;4196.181744559812;3281.442154833684;3505.1587618971516;3626.220362622049;3644.562652481719;3653.1962968146963;4163.39630753542;4610.9308360374625;4963.975180124113;5240.764165612557;5242.79028838846;4130.315295058206;4173.373993900909;4455.643548516111;4692.052798088789;4712.717236965759;4637.744924029177;4770.189067858472;5330.946690533084;5747.913781293284;5758.62568933018;3717.4204438266343;3919.980400097981;3935.626115519021;3943.5464921534103;3949.768380864802;5499.552699048885;6034.728437220336;6062.907157987312;6078.684134264678;6092.573519853612;3604.675579197885;3640.0171953572385;3722.7207959403154;3773.339665558516;3984.541777695602;3177.7616678749187;3478.426059700121;3646.690031347365;4034.1528279061727;4081;4016.176683779818;4207.99128623036;4422.579854913778;4518.615917646971;4730.716157030589;4917.434495033117;5547.420449490925;5812.505936995318;5819.941800582024;5846.94153058464;1227.9930457025152;1300.9414576343897;1352.7970804379224;1372.9519466818613;1378.4348417149122;717.824133087397;810.6352141536332;837.4716143038978;840.2815592668303;851.6891334662804;4045.8179381927885;4506.707064040893;4508.797104130256;4513.796879140446;4547.226971414867;4292.994903567515;4415.51429342772;4561.989087202497;4887.413510378778;5033.521815427529;4089.7750623715388;4237.406763053175;4431.667624928144;4480.7535585542455;4529.818807247673;2291.2518513432688;2551.2523921113725;2654.2700757291514;2661.1106114658423;2665.3469899874653;4593.678442509059;5087.211482220262;5097.596547659493;5199.818006369759;5227.45111763267;4437.644988400984;4457.665675074382;4507.414036175276;4961.727105009187;5111.923321150186;4306;5148.922766158586;5217.721658193766;5219.895602087937;5224.843254702365;4446;4809.615230781599;5009.373828271466;5015.774290156879;5033.395992480926;4286.971126929452;4398.780060997002;4840.951590484191;4955.0089982004465;4959;4387.610285725562;4833.516648335139;5211.280277508489;5211.801285704233;5329.387120481194;3232.3858466891215;3378.239896023375;3437.8281085945105;3446.5864096308605;3455.7235421166747;4477.365761496965;4521.977390113028;4584.896517616781;4976.30331753562;5182.870428239325;4561.631510546889;5321.654092484131;5635.267415236157;5675.836453527099;5768.451997060272;4911.017796440655;5613.410591887793;5709.685967271674;5731.254936858182;5736.827895163152;5599.0683214291;5601.719914004365;5613.550915926634;5614.69119198448;5648.689322087161;4459.7993090310365;4976.898678854203;5387.976284505931;5416.539594134542;5796.768129274782;5371.731413429391;5672.468433523009;5688.715564221689;5714.942850571441;5720.3421606515785 13:19:43 INFO - PROCESS | 1666 | |5;yui.html;712.6899798587615;733.1715161867089;736.0468193689172;769.4383100336757;777.6306254529096;14738.63153421165;15551.922240388782;15569.922150389246;15574.84425155748;15581.220938953064;14220.431182752704;14288.78566821496;14355.497557585486;14368.20974846383;14392.49626263081;700.912385951756;716.3373879161754;895.7760559860035;900.3292547052439;920.8066306075699;903.9864402033942;912.7946212102257;922.0364718868765;923.4597760310193;925.3661242049218;982.3614650477211;982.5578489679637;983.0709979069782;983.9803203935917;984.2224642532391;738.2285511640349;939.248601119102;953.4327075390132;955.9474228917406;978.4129522286643;710.0698085508001;712.4051417066755;712.9037579926747;713.2368365848544;714.5569746757044;14784.260786960609;14810.777838332433;14824.629384265372;14838.183899885502;14861.925690371587;12313.07651926109;12383.876161238453;12430.192037517623;12548.874511254862;12645.49418023278;10504.684859454228;10806.405647689375;10823.291078903045;10930;11186.832197517044;3983.5618082010956;4014.9197016059516;4063.9593604063875;4110.0752330725645;4149.875503734893;83714.74427883586;84969.57515212447;86313.70529442001;87118.56440717819;87181;9451;9466.95266523663;9540.379875308083;9721.104384645068;10136.797264054678;21678.34964951054;21716.457088572595;21716.565668686853;21718.565628687655;21722.13111475555;7164.92835071653;7312.5246858954;7508.0990281166605;7580.05249343832;7623.923760762433;3952.545457272393;3969.186316805048;3995.820188091543;4075.4701888754867;4094.7952602369764;3356.3455126250146;3453.32660131277;3454.240067185215;3454.3954807908517;3457.515947767264;568.6474385880698;629.6788637794758;642.4539141729585;646.544186348623;649.9837504062342;487.3542556113093;612.0024360292783;612.9754809807647;617.3209469583422;632.6966449114839;6866.279040700654;7426.2945020223;7516.060492438445;7605.087389513294;7657.502262352929;585.5052480653841;588.5350573047244;604.2718524178355;629.2794750011211;632.6899819088613;3572.5712914450432;3958.188571342868;3976.1451287973223;3977.76133431995;4176.0395109125075;88090;90552.5472372634;97566;98691;99003.50498247462;73589.5282094355;80110.59944700239;81555.59222203851;81804.77292840612;85048.57475712706;71594.28405715876;71965;73687;74947.50104997978;76325.85511217336;7627.580483073484;7743.264389882952;7881.290683838482;7890.644920978684;7893.605319734105;44129.55870441254;44264;45635.54364456313;45900.540994589624;47041;59550.106748399674;64367;64424;70673.29326706668;70866.64566677134;11555.4800033997;11630.244034137753;11660.591879284184;11775.52897884075;11917;11903.821442678194;11987.64037078889;12086.758264834478;12117.030637548798;12950.352482375654;11752.76494470123;12007.579734709243;12020.33888136161;12204.206726562745;12554.309512976874;11775.76448471043;11799.882001179878;11945.940270298941;12000.03999680041;12193.573224937085;11414.82877756851;11468.541258349573;11717.355545445082;11724.58963936258;11791.823122652995;9428.057194280791;9698.85451718239;9734.269929755155;10023.849642255518;10112.59549618007;9160.404573702688;9781.902180978099;9784.168345690696;9819.312648114563;9826.901730982885 13:19:43 INFO - PROCESS | 1666 | __end_tp_report 13:19:43 INFO - PROCESS | 1666 | __start_cc_report 13:19:43 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1312 13:19:43 INFO - PROCESS | 1666 | __end_cc_report 13:19:43 INFO - PROCESS | 1666 | __startTimestamp1463602783040__endTimestamp 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | ------- Summary: start ------- 13:19:43 INFO - PROCESS | 1666 | Number of tests: 6 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5777.82 Median:6010.49 stddev:651.20 (10.8%) stddev-sans-first:627.46 13:19:43 INFO - PROCESS | 1666 | Values: 3467.0 3477.2 3505.9 3515.5 3563.9 5641.9 5681.2 5693.3 5778.2 5789.6 4860.3 5791.0 5793.0 5796.5 5801.8 5177.7 5217.7 5241.4 5250.8 5305.6 4172.4 5286.8 5316.3 5317.7 5333.5 5172.8 5189.0 5213.9 5221.4 5235.8 5296.6 5299.5 5315.9 5318.3 5376.9 5092.9 5101.4 5121.2 5228.5 5231.9 5624.9 5629.9 5675.7 5699.2 5726.3 6311.2 6318.8 6322.0 6356.2 6369.9 6196.7 6278.1 6344.1 6358.6 6379.1 5923.9 5954.4 5965.9 5970.3 5981.7 6341.2 6351.4 6355.8 6360.0 6361.7 6104.7 6111.9 6121.2 6192.1 6280.5 5143.4 6196.4 6291.1 6302.3 6353.7 6271.4 6289.0 6289.7 6318.6 6325.1 6251.9 6262.7 6294.9 6320.0 6325.4 4900.3 4910.9 4926.1 4947.6 4961.8 4420.3 4422.2 4426.4 4443.5 4490.5 5652.3 5670.1 5692.9 5706.5 5756.3 5969.9 6192.5 6263.9 6284.3 6288.7 5764.5 5782.1 5793.3 5806.8 5823.9 5341.3 5450.7 5459.5 5470.4 5475.8 5900.4 6255.3 6309.0 6334.4 6352.4 6292.3 6339.0 6350.2 6352.2 6387.7 6286.6 6314.1 6325.9 6336.9 6342.3 6097.4 6160.7 6199.0 6232.9 6312.2 6151.8 6351.1 6352.5 6363.2 6382.5 6010.7 6183.9 6221.3 6221.8 6236.8 6081.1 6105.8 6110.6 6186.4 6192.2 5974.8 5983.9 6001.0 6010.5 6048.8 6010.6 6109.9 6166.0 6185.8 6233.9 5959.3 6023.1 6023.9 6038.8 6046.2 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:71958.87 Median:35081.74 stddev:161427.17 (460.1%) stddev-sans-first:161790.89 13:19:43 INFO - PROCESS | 1666 | Values: 511.6 513.2 532.4 538.9 564.6 30755.5 42325.8 43414.1 43433.6 43482.0 45435.0 45678.8 45696.8 45842.8 45918.5 8627.6 9708.5 9890.5 9894.0 9906.6 8758.8 9388.4 11673.1 11698.2 11802.2 11734.3 11779.4 11809.1 11817.1 11820.2 8640.9 11549.2 11794.1 11827.3 11886.6 11663.5 11757.3 11757.6 11766.9 11771.3 41395.6 41694.6 41695.4 41734.0 41754.0 3179.5 3298.1 3433.8 3473.4 3481.8 29577.7 29664.9 29728.1 30250.5 31106.4 2613.3 2645.2 2672.3 2687.1 2720.4 513248.0 516130.0 517753.4 517754.0 517905.4 36040.8 66238.7 67629.0 67658.7 67801.7 36382.1 38132.2 38132.8 38168.0 38203.0 14675.9 14763.8 14785.6 14789.0 14841.9 11531.7 11742.6 11749.2 11969.5 12712.4 7918.6 8212.0 8417.0 8427.4 8461.7 3439.0 3443.5 3448.3 3453.0 3479.1 966.3 1014.6 1072.9 1108.5 1121.7 27599.0 29082.0 30340.2 30508.4 31119.0 1055.8 1084.9 1142.0 1168.9 1214.7 15415.8 15787.4 15836.4 16037.6 16086.0 630905.0 708839.0 720155.0 721840.4 725134.0 63664.0 64262.7 64298.0 64374.7 64390.7 1332.4 1377.9 1457.4 1473.4 1483.6 29673.4 31606.6 31808.4 31844.5 33029.5 54249.5 54281.5 54848.9 54887.2 54940.2 566135.0 573547.0 574042.0 575640.0 576360.0 23097.9 23969.5 26104.0 26527.6 27518.9 36741.0 39074.0 39607.0 39683.2 46445.1 37736.4 39241.0 39787.2 40463.6 40796.2 38554.2 38670.0 38932.0 39573.4 40077.6 38183.8 39065.7 39367.8 39529.0 39638.0 33750.5 34235.1 34928.7 35073.0 35090.5 21703.8 23800.8 25662.7 25815.2 25896.6 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34842.54 Median:22421.83 stddev:102309.57 (456.3%) stddev-sans-first:102563.81 13:19:43 INFO - PROCESS | 1666 | Values: 359.8 375.7 403.2 416.5 421.6 21108.5 21752.5 22075.4 22086.8 22999.8 20887.4 21045.5 21771.6 22066.7 22074.7 5882.9 6135.6 6156.8 6160.4 6164.7 6034.7 6035.9 6115.5 6121.2 6146.1 5923.1 6077.4 6082.7 6113.2 6120.2 5912.3 6001.7 6057.4 6077.0 6079.4 5274.2 5282.5 5298.4 5303.8 5306.9 26669.1 26896.0 27389.9 27537.7 27664.4 2154.6 2364.8 2409.4 2685.7 2712.7 15222.5 15283.2 15529.6 15672.8 15922.8 1749.3 1813.7 1827.4 1838.9 1847.1 82987.6 95111.5 104463.5 105749.5 109020.0 11410.2 11534.5 11545.1 11612.9 11738.0 12946.4 14447.6 14533.3 14572.3 14584.6 8463.0 8858.4 8898.9 8906.9 9047.2 8882.3 8902.4 8907.8 9035.8 9054.6 5842.5 6800.3 6970.9 6971.8 7003.1 1657.7 1750.9 1820.8 1900.9 1941.1 982.0 1003.3 1012.5 1041.2 1097.8 21482.5 22000.3 22114.1 22151.7 22223.7 840.9 954.5 988.4 996.5 1068.3 11035.3 11798.8 11820.8 11825.3 11873.8 609446.0 611668.0 613615.0 625119.0 629526.0 117430.0 120276.0 120382.4 121535.4 122052.4 1056.2 1060.6 1087.7 1091.6 1093.3 19123.6 19150.6 19440.2 19503.5 19596.5 22620.0 35663.8 47648.3 48123.8 50008.5 70823.6 73507.9 78126.0 80690.0 83034.9 7231.2 7593.6 7681.6 7770.5 7950.1 9728.8 11693.6 12008.2 12105.3 12594.9 10530.6 10627.4 10757.0 10826.2 10946.2 10382.5 10545.0 10590.5 10735.0 10792.8 10568.9 10866.6 10934.8 11132.3 11142.2 7210.5 7586.4 7637.0 7669.1 7911.3 9733.0 10664.4 10936.1 10967.3 11017.9 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5845.24 Median:5010.46 stddev:3308.72 (66.0%) stddev-sans-first:3299.94 13:19:43 INFO - PROCESS | 1666 | Values: 1245.9 1488.2 1613.2 1884.1 2039.7 8187.3 8893.6 9142.5 9143.6 9254.5 8773.0 8919.6 8940.2 9027.2 9080.2 2418.8 2446.7 2451.0 2453.4 2453.6 2392.1 2441.9 2447.5 2449.2 2451.5 2412.2 2422.4 2435.5 2452.9 2453.8 2388.8 2394.9 2402.1 2404.8 2432.4 2337.1 2405.5 2407.5 2408.1 2413.1 7783.8 8460.0 9176.2 9246.3 9247.1 5301.9 5520.9 5525.7 5572.1 5574.5 2944.2 3365.8 3444.2 3508.5 3510.4 904.8 928.5 943.0 1542.2 1615.2 12174.9 12202.3 12259.4 12292.7 12299.6 4584.6 4646.0 4719.8 4724.6 4740.2 9693.3 9708.4 9740.1 9744.5 9745.8 5478.3 5480.3 5482.9 5487.4 5496.5 4966.2 5011.6 5061.7 5123.4 5148.7 3607.1 3650.1 3681.0 3717.0 3742.1 1094.3 1115.3 1119.7 1125.8 1130.1 901.9 923.8 926.3 927.1 940.3 5857.5 6035.8 6088.6 6146.9 6251.0 858.3 992.2 1034.5 1079.4 1090.0 3966.6 4062.5 4072.7 4130.1 4143.3 8913.4 10985.6 11318.5 11343.1 11354.4 11066.6 11175.1 11191.6 11228.4 11260.8 8066.2 8383.5 8438.4 8544.1 8577.6 6007.8 6013.5 6062.9 6116.6 6179.4 10001.3 10370.2 10398.3 10401.9 10406.8 9811.2 10095.8 10125.8 10551.3 10560.9 8367.5 8432.2 8513.5 8544.0 8546.2 8403.5 8423.7 8481.2 8491.5 8499.6 7422.1 7670.8 7690.3 7762.1 7805.4 7537.5 7549.0 7651.9 7663.0 7713.4 6656.3 6787.8 7026.1 7072.5 7075.2 4073.7 4693.5 4869.1 4898.5 5109.4 4819.8 4962.9 5009.3 5025.2 5196.6 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4319.15 Median:4554.43 stddev:1318.94 (29.0%) stddev-sans-first:1287.85 13:19:43 INFO - PROCESS | 1666 | Values: 309.7 310.4 322.8 327.9 330.8 4611.7 5299.9 5521.9 5522.4 5578.4 5221.8 5448.4 5456.6 5473.5 5501.7 3705.9 4042.0 4042.3 4169.5 4206.5 3521.5 3619.7 3867.9 4222.7 4236.7 3922.3 4084.3 4114.3 4158.0 4158.1 4132.8 4149.1 4160.6 4170.4 4196.2 3281.4 3505.2 3626.2 3644.6 3653.2 4163.4 4610.9 4964.0 5240.8 5242.8 4130.3 4173.4 4455.6 4692.1 4712.7 4637.7 4770.2 5330.9 5747.9 5758.6 3717.4 3920.0 3935.6 3943.5 3949.8 5499.6 6034.7 6062.9 6078.7 6092.6 3604.7 3640.0 3722.7 3773.3 3984.5 3177.8 3478.4 3646.7 4034.2 4081.0 4016.2 4208.0 4422.6 4518.6 4730.7 4917.4 5547.4 5812.5 5819.9 5846.9 1228.0 1300.9 1352.8 1373.0 1378.4 717.8 810.6 837.5 840.3 851.7 4045.8 4506.7 4508.8 4513.8 4547.2 4293.0 4415.5 4562.0 4887.4 5033.5 4089.8 4237.4 4431.7 4480.8 4529.8 2291.3 2551.3 2654.3 2661.1 2665.3 4593.7 5087.2 5097.6 5199.8 5227.5 4437.6 4457.7 4507.4 4961.7 5111.9 4306.0 5148.9 5217.7 5219.9 5224.8 4446.0 4809.6 5009.4 5015.8 5033.4 4287.0 4398.8 4841.0 4955.0 4959.0 4387.6 4833.5 5211.3 5211.8 5329.4 3232.4 3378.2 3437.8 3446.6 3455.7 4477.4 4522.0 4584.9 4976.3 5182.9 4561.6 5321.7 5635.3 5675.8 5768.5 4911.0 5613.4 5709.7 5731.3 5736.8 5599.1 5601.7 5613.6 5614.7 5648.7 4459.8 4976.9 5388.0 5416.5 5796.8 5371.7 5672.5 5688.7 5714.9 5720.3 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18596.61 Median:615.15 stddev:26381.31 (4288.6%) stddev-sans-first:26421.14 13:19:43 INFO - PROCESS | 1666 | Values: 712.7 733.2 736.0 769.4 777.6 14738.6 15551.9 15569.9 15574.8 15581.2 14220.4 14288.8 14355.5 14368.2 14392.5 700.9 716.3 895.8 900.3 920.8 904.0 912.8 922.0 923.5 925.4 982.4 982.6 983.1 984.0 984.2 738.2 939.2 953.4 955.9 978.4 710.1 712.4 712.9 713.2 714.6 14784.3 14810.8 14824.6 14838.2 14861.9 12313.1 12383.9 12430.2 12548.9 12645.5 10504.7 10806.4 10823.3 10930.0 11186.8 3983.6 4014.9 4064.0 4110.1 4149.9 83714.7 84969.6 86313.7 87118.6 87181.0 9451.0 9467.0 9540.4 9721.1 10136.8 21678.3 21716.5 21716.6 21718.6 21722.1 7164.9 7312.5 7508.1 7580.1 7623.9 3952.5 3969.2 3995.8 4075.5 4094.8 3356.3 3453.3 3454.2 3454.4 3457.5 568.6 629.7 642.5 646.5 650.0 487.4 612.0 613.0 617.3 632.7 6866.3 7426.3 7516.1 7605.1 7657.5 585.5 588.5 604.3 629.3 632.7 3572.6 3958.2 3976.1 3977.8 4176.0 88090.0 90552.5 97566.0 98691.0 99003.5 73589.5 80110.6 81555.6 81804.8 85048.6 71594.3 71965.0 73687.0 74947.5 76325.9 7627.6 7743.3 7881.3 7890.6 7893.6 44129.6 44264.0 45635.5 45900.5 47041.0 59550.1 64367.0 64424.0 70673.3 70866.6 11555.5 11630.2 11660.6 11775.5 11917.0 11903.8 11987.6 12086.8 12117.0 12950.4 11752.8 12007.6 12020.3 12204.2 12554.3 11775.8 11799.9 11945.9 12000.0 12193.6 11414.8 11468.5 11717.4 11724.6 11791.8 9428.1 9698.9 9734.3 10023.8 10112.6 9160.4 9781.9 9784.2 9819.3 9826.9 13:19:43 INFO - PROCESS | 1666 | -------- Summary: end -------- 13:19:43 INFO - PROCESS | 1666 | 13:19:43 INFO - TEST-INFO | 1666: exit 0 13:19:43 INFO - TEST-OK | dromaeo_css | took 1116916ms 13:19:43 INFO - TEST-START | kraken 13:19:43 INFO - operating with platform_type : mac_ 13:19:43 INFO - Initialising browser for kraken test... 13:19:43 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpE5iveL/profile http://localhost:49279/getInfo.html) 13:19:45 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 13:19:45 INFO - PROCESS | 1676 | colorDepth:24 13:19:45 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 13:19:45 INFO - PROCESS | 1676 | __metrics 13:19:45 INFO - PROCESS | 1676 | 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" 13:19:45 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:19:46 INFO - TEST-INFO | 1676: exit 0 13:19:46 INFO - Browser initialized. 13:19:46 INFO - Running cycle 1/1 for kraken test... 13:19:46 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpE5iveL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:19:58 INFO - PROCESS | 1678 | RSS: Main: 239468544 13:19:58 INFO - PROCESS | 1678 | 13:20:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 13:20:02 INFO - PROCESS | 1678 | RSS: Main: 289550336 13:20:02 INFO - PROCESS | 1678 | 13:20:04 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 13:20:05 INFO - PROCESS | 1678 | RSS: Main: 299196416 13:20:05 INFO - PROCESS | 1678 | 13:20:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 13:20:09 INFO - PROCESS | 1678 | RSS: Main: 390742016 13:20:09 INFO - PROCESS | 1678 | 13:20:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 13:20:12 INFO - PROCESS | 1678 | RSS: Main: 288448512 13:20:12 INFO - PROCESS | 1678 | 13:20:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:20:15 INFO - PROCESS | 1678 | RSS: Main: 292327424 13:20:15 INFO - PROCESS | 1678 | 13:20:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 13:20:18 INFO - PROCESS | 1678 | RSS: Main: 329936896 13:20:18 INFO - PROCESS | 1678 | 13:20:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 13:20:22 INFO - PROCESS | 1678 | RSS: Main: 329478144 13:20:22 INFO - PROCESS | 1678 | 13:20:25 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 13:20:26 INFO - PROCESS | 1678 | RSS: Main: 330461184 13:20:26 INFO - PROCESS | 1678 | 13:20:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:20:28 INFO - PROCESS | 1678 | RSS: Main: 316923904 13:20:28 INFO - PROCESS | 1678 | 13:20:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:20:30 INFO - PROCESS | 1678 | RSS: Main: 323506176 13:20:30 INFO - PROCESS | 1678 | 13:20:33 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:20:33 INFO - PROCESS | 1678 | RSS: Main: 325427200 13:20:33 INFO - PROCESS | 1678 | 13:20:36 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:20:37 INFO - PROCESS | 1678 | RSS: Main: 330063872 13:20:37 INFO - PROCESS | 1678 | 13:20:40 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:20:41 INFO - PROCESS | 1678 | RSS: Main: 327745536 13:20:41 INFO - PROCESS | 1678 | 13:20:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 13:20:43 INFO - PROCESS | 1678 | RSS: Main: 374185984 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | __start_tp_report 13:20:43 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 13:20:43 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 13:20:43 INFO - PROCESS | 1678 | |i|pagename|runs| 13:20:43 INFO - PROCESS | 1678 | |0;ai-astar;110;105;111;110;110;111;110;116;89;154 13:20:43 INFO - PROCESS | 1678 | |1;audio-beat-detection;153;153;170;207;114;138;136;117;166;117 13:20:43 INFO - PROCESS | 1678 | |2;audio-dft;186;186;184;183;182;184;185;187;180;182 13:20:43 INFO - PROCESS | 1678 | |3;audio-fft;78;73;67;125;57;60;56;60;107;48 13:20:43 INFO - PROCESS | 1678 | |4;audio-oscillator;94;97;132;80;82;78;102;82;80;102 13:20:43 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;88;84;88;84;87;84;87;84;90;84 13:20:43 INFO - PROCESS | 1678 | |6;imaging-darkroom;93;92;93;93;92;93;93;92;93;92 13:20:43 INFO - PROCESS | 1678 | |7;imaging-desaturate;83;87;83;87;85;83;87;86;85;85 13:20:43 INFO - PROCESS | 1678 | |8;json-parse-financial;57;55;56;56;55;54;56;119;58;54 13:20:43 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;63;69;71;68;69;68;68;67;67;78 13:20:43 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;88;85;85;86;86;84;86;84;85;87 13:20:43 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;155;142;151;126;131;134;135;133;214;139 13:20:43 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;163;212;222;160;162;163;159;161;162;153 13:20:43 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;58;80;59;65;67;78;83;85;63;64 13:20:43 INFO - PROCESS | 1678 | __end_tp_report 13:20:43 INFO - PROCESS | 1678 | __start_cc_report 13:20:43 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,2363 13:20:43 INFO - PROCESS | 1678 | __end_cc_report 13:20:43 INFO - PROCESS | 1678 | __startTimestamp1463602843164__endTimestamp 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | ------- Summary: start ------- 13:20:43 INFO - PROCESS | 1678 | Number of tests: 14 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:112.60 Median:111.00 stddev:16.24 (14.6%) stddev-sans-first:17.19 13:20:43 INFO - PROCESS | 1678 | Values: 110.0 105.0 111.0 110.0 110.0 111.0 110.0 116.0 89.0 154.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:153.00 stddev:29.16 (19.1%) stddev-sans-first:30.85 13:20:43 INFO - PROCESS | 1678 | Values: 153.0 153.0 170.0 207.0 114.0 138.0 136.0 117.0 166.0 117.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:2.18 (1.2%) stddev-sans-first:2.18 13:20:43 INFO - PROCESS | 1678 | Values: 186.0 186.0 184.0 183.0 182.0 184.0 185.0 187.0 180.0 182.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:73.10 Median:60.00 stddev:24.56 (40.9%) stddev-sans-first:25.99 13:20:43 INFO - PROCESS | 1678 | Values: 78.0 73.0 67.0 125.0 57.0 60.0 56.0 60.0 107.0 48.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:81.00 stddev:16.68 (20.6%) stddev-sans-first:17.69 13:20:43 INFO - PROCESS | 1678 | Values: 94.0 97.0 132.0 80.0 82.0 78.0 102.0 82.0 80.0 102.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:2.26 (2.6%) stddev-sans-first:2.28 13:20:43 INFO - PROCESS | 1678 | Values: 88.0 84.0 88.0 84.0 87.0 84.0 87.0 84.0 90.0 84.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 13:20:43 INFO - PROCESS | 1678 | Values: 93.0 92.0 93.0 93.0 92.0 93.0 93.0 92.0 93.0 92.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.66 (1.9%) stddev-sans-first:1.58 13:20:43 INFO - PROCESS | 1678 | Values: 83.0 87.0 83.0 87.0 85.0 83.0 87.0 86.0 85.0 85.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:56.00 stddev:20.07 (35.8%) stddev-sans-first:21.20 13:20:43 INFO - PROCESS | 1678 | Values: 57.0 55.0 56.0 56.0 55.0 54.0 56.0 119.0 58.0 54.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:68.50 stddev:3.82 (5.6%) stddev-sans-first:3.43 13:20:43 INFO - PROCESS | 1678 | Values: 63.0 69.0 71.0 68.0 69.0 68.0 68.0 67.0 67.0 78.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:1.26 (1.5%) stddev-sans-first:1.00 13:20:43 INFO - PROCESS | 1678 | Values: 88.0 85.0 85.0 86.0 86.0 84.0 86.0 84.0 85.0 87.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:140.50 stddev:25.50 (18.2%) stddev-sans-first:26.84 13:20:43 INFO - PROCESS | 1678 | Values: 155.0 142.0 151.0 126.0 131.0 134.0 135.0 133.0 214.0 139.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:162.50 stddev:24.17 (14.9%) stddev-sans-first:25.43 13:20:43 INFO - PROCESS | 1678 | Values: 163.0 212.0 222.0 160.0 162.0 163.0 159.0 161.0 162.0 153.0 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:72.50 stddev:10.23 (14.1%) stddev-sans-first:9.85 13:20:43 INFO - PROCESS | 1678 | Values: 58.0 80.0 59.0 65.0 67.0 78.0 83.0 85.0 63.0 64.0 13:20:43 INFO - PROCESS | 1678 | -------- Summary: end -------- 13:20:43 INFO - PROCESS | 1678 | 13:20:43 INFO - TEST-INFO | 1678: exit 0 13:20:44 INFO - TEST-OK | kraken | took 60358ms 13:20:44 INFO - SUITE-END | took 1177s 13:20:44 INFO - Completed test suite (00:19:37) 13:20:44 INFO - javascript benchmark 13:20:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3466.9826650866726, 3477.217626034142, 3505.9298814023705, 3515.4902539131817, 3563.9109022274456, 5641.915371269424, 5681.23303354047, 5693.28833895763, 5778.248827652399, 5789.623674461168, 4860.2709593560985, 5791.0, 5792.971035144829, 5796.536277097822, 5801.82594522165, 5177.741112944338, 5217.739113044351, 5241.371035475749, 5250.7637156328055, 5305.602079844008, 4172.374143878412, 5286.814961476349, 5316.282301889234, 5317.680939143664, 5333.546648534879, 5172.767225474844, 5189.040027594907, 5213.921791173136, 5221.373435187783, 5235.842924712265, 5296.576273898098, 5299.54953828925, 5315.867103322448, 5318.25544423781, 5376.892462150774, 5092.898142037176, 5101.438841727407, 5121.180611102243, 5228.47715228478, 5231.895362092775, 5624.91562626561, 5629.8592535186945, 5675.716214189274, 5699.173619825144, 5726.255586773693, 6311.179546658951, 6318.810435686937, 6321.968390158066, 6356.2054743157105, 6369.904451433232, 6196.659183744892, 6278.089676996809, 6344.1435406220035, 6358.58669186506, 6379.106925030499, 5923.851903702442, 5954.4343287388065, 5965.910511342333, 5970.31341395745, 5981.700914954322, 6341.175647165931, 6351.396617321347, 6355.777547785805, 6360.0, 6361.681915904186, 6104.694765261719, 6111.938880611137, 6121.209103132296, 6192.071189321638, 6280.466160376419, 5143.434222235551, 6196.411340922605, 6291.056341548804, 6302.338254483305, 6353.68231588428, 6271.404216599416, 6289.0252010938375, 6289.654069026156, 6318.557700960981, 6325.114483972247, 6251.874962500725, 6262.718177682015, 6294.937050629526, 6320.0, 6325.3674632537295, 4900.313956046085, 4910.852674419773, 4926.064047830901, 4947.604191664658, 4961.826336078292, 4420.314851198005, 4422.181896349186, 4426.3581780641625, 4443.533428989909, 4490.528494508095, 5652.293463317085, 5670.149477578314, 5692.9146062809095, 5706.494695567409, 5756.309242890796, 5969.940300596939, 6192.535559832941, 6263.906041409383, 6284.308726040132, 6288.748450062038, 5764.452377024176, 5782.10377391505, 5793.275840519935, 5806.82579522615, 5823.854403639943, 5341.278927344836, 5450.727463626803, 5459.454054594588, 5470.425605311465, 5475.780968761284, 5900.350961394329, 6255.311915689362, 6308.968455157695, 6334.398232167937, 6352.428281454691, 6292.276388215414, 6338.968305158444, 6350.237971443272, 6352.205974253218, 6387.712552935222, 6286.5599408041, 6314.147590075465, 6325.905111423424, 6336.936630633634, 6342.334054924351, 6097.4036925702085, 6160.691965401802, 6199.008158694587, 6232.875342493034, 6312.179416675803, 6151.784687535915, 6351.142595749515, 6352.523560732871, 6363.172787537591, 6382.521310901608, 6010.7295171716505, 6183.907241391293, 6221.346758590283, 6221.813345599639, 6236.781712640036, 6081.057436097412, 6105.763021687103, 6110.5587591967305, 6186.443220110032, 6192.164057852132, 5974.790882319098, 5983.880322393615, 6000.969995149996, 6010.4891084258325, 6048.818535443944, 6010.609310394985, 6109.877802443837, 6166.044263139221, 6185.8144255672405, 6233.937660623517, 5959.28488581373, 6023.066424704178, 6023.879522409615, 6038.849028774316, 6046.244219472566], "value": 5737.222618454288, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.6495200787463, 513.2275924733277, 532.3930718980363, 538.8922215556889, 564.6104188110205, 30755.538666919987, 42325.78837105814, 43414.131717365635, 43433.56566434335, 43482.0, 45435.0, 45678.77160614201, 45696.77151614246, 45842.77078614602, 45918.54081459193, 8627.568621568927, 9708.466034368124, 9890.505474726271, 9894.01059894012, 9906.603735850558, 8758.824823503526, 9388.436693798394, 11673.124515661317, 11698.239614425078, 11802.173847830656, 11734.29594224345, 11779.352135632536, 11809.055275577975, 11817.054635629172, 11820.231684940494, 8640.870386944202, 11549.249298795552, 11794.05647548194, 11827.34949577773, 11886.643400697949, 11663.475143618558, 11757.294562326288, 11757.58848440309, 11766.941165294118, 11771.293722376684, 41395.586044139476, 41694.58305416937, 41695.374569381485, 41734.0, 41754.0, 3179.537412790119, 3298.09302441828, 3433.7939723616664, 3473.426884564044, 3481.8085005324697, 29577.704222957924, 29664.8516757417, 29728.108156755334, 30250.54624180639, 31106.377872442423, 2613.3335999320243, 2645.206438068591, 2672.2517695045417, 2687.086390627196, 2720.387912719642, 513248.0, 516130.0, 517753.4112329452, 517754.0, 517905.410472949, 36040.81979590138, 66238.66880665663, 67629.0, 67658.66170669213, 67801.66099169472, 36382.09044773902, 38132.186781321645, 38132.809335953694, 38168.0, 38203.0, 14675.92662036683, 14763.77854332186, 14785.630359241106, 14789.0, 14841.925790370979, 11531.654050378502, 11742.647720568397, 11749.177557570887, 11969.46137423818, 12712.42794074269, 7918.604069796487, 8211.95894020538, 8416.957915210507, 8427.367947403969, 8461.746147615582, 3439.0370696205105, 3443.466262729281, 3448.2930999145115, 3453.0131436307415, 3479.1302174456387, 966.3235734985539, 1014.5688082564881, 1072.8819829818713, 1108.5288752280248, 1121.7083558274908, 27599.034033809123, 29082.0, 30340.24149396283, 30508.389832203233, 31119.0, 1055.762453447968, 1084.9077828384518, 1142.046391263288, 1168.9123315751183, 1214.732758793064, 15415.768763468557, 15787.447439339569, 15836.366545338058, 16037.59906002336, 16085.988147794596, 630905.0, 708839.0, 720155.0, 721840.3907980531, 725134.0, 63664.0, 64262.6786866072, 64298.035529467095, 64374.67812660907, 64390.678046609464, 1332.393760838816, 1377.8897688185123, 1457.3733294683389, 1473.351725240912, 1483.6216764724927, 29673.437647047547, 31606.577704003834, 31808.39183152506, 31844.52233216457, 33029.50455743118, 54249.45750542602, 54281.45718542922, 54848.90302194014, 54887.17669234885, 54940.17589736077, 566135.0, 573547.0, 574042.0, 575640.0, 576360.0, 23097.88451057734, 23969.52060958806, 26104.0, 26527.602085969113, 27518.862405687843, 36741.0, 39074.0, 39607.0, 39683.2063358737, 46445.071098577166, 37736.433953490174, 39241.01897452587, 39787.2042559153, 40463.59536404716, 40796.18407631772, 38554.22891542209, 38670.0, 38932.0, 39573.40639890461, 40077.59922400739, 38183.809080954416, 39065.702772694414, 39367.80316098401, 39529.0, 39638.0, 33750.49374259339, 34235.14412139716, 34928.65071349254, 35073.0, 35090.473642895886, 21703.782962170175, 23800.80995950161, 25662.743372566783, 25815.225543233733, 25896.61155082713], "value": 19331.544422825293, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.81649358826996, 375.69005570404414, 403.19964869733576, 416.5272415808057, 421.58263319313886, 21108.472288192785, 21752.456188595293, 22075.44811379714, 22086.779132208674, 22999.77000229997, 20887.373378798624, 21045.473863153427, 21771.564568708618, 22066.66899996497, 22074.66887996681, 5882.91175632367, 6135.570510064297, 6156.7537298508005, 6160.414760597759, 6164.691765411734, 6034.728437220336, 6035.939640603582, 6115.541334399915, 6121.234845644294, 6146.078088286748, 5923.141144534062, 6077.361877002917, 6082.695865206744, 6113.174721412619, 6120.2043734314475, 5912.260967379078, 6001.729922153513, 6057.42454466825, 6077.0, 6079.361667024943, 5274.208868669692, 5282.471752824726, 5298.443663415363, 5303.761330740126, 5306.946930530684, 26669.066582669515, 26896.0, 27389.904403823824, 27537.724622753918, 27664.446711065866, 2154.569086182754, 2364.7871691547844, 2409.373562873648, 2685.7314268572986, 2712.742289482496, 15222.46721364747, 15283.159426231552, 15529.611759705986, 15672.764908526382, 15922.76115858263, 1749.3352526040023, 1813.655405472969, 1827.4243613261913, 1838.8528917686554, 1847.0764617691154, 82987.58506207491, 95111.52444237804, 104463.47768261186, 105749.47125264401, 109020.0, 11410.201285909907, 11534.538618455168, 11545.076393888468, 11612.94193529027, 11738.0, 12946.417411216518, 14447.566573002827, 14533.34599943005, 14572.251329840487, 14584.562463126123, 8462.957685211657, 8858.424202426822, 8898.911010889939, 8906.86639700405, 9047.230985366186, 8882.333824963154, 8902.42134261271, 8907.821843563092, 9035.819283614292, 9054.637814487347, 5842.503387212049, 6800.285969973181, 6970.860582788316, 6971.755988540377, 7003.089598352181, 1657.685039842426, 1750.8949463032004, 1820.8164692949688, 1900.8859468431663, 1941.0876887862682, 981.9754506137261, 1003.3027046202819, 1012.473513772834, 1041.2294901772634, 1097.8078836203633, 21482.462938426663, 22000.33998980033, 22114.115435382726, 22151.667724984138, 22223.66664500034, 840.8570543007703, 954.4654993203828, 988.357567581078, 996.531630133836, 1068.3216157739892, 11035.282706624044, 11798.764024719458, 11820.763584728258, 11825.290482571074, 11873.821892671616, 609446.0, 611668.0, 613615.0, 625119.0, 629526.0, 117430.0, 120276.0, 120382.42210610663, 121535.39232303781, 122052.38973805074, 1056.2447849787438, 1060.6393826099008, 1087.6682611803317, 1091.6233899304727, 1093.33853018924, 19123.617527649647, 19150.61698765989, 19440.2223911042, 19503.51241218924, 19596.510087247934, 22620.0, 35663.82168089143, 47648.2852757202, 48123.75938120287, 50008.49991500184, 70823.64588177027, 73507.89738153825, 78126.0, 80690.0, 83034.92412689731, 7231.240719724353, 7593.5823529706395, 7681.61591920413, 7770.53376797394, 7950.125486196398, 9728.756781080247, 11693.590724324607, 12008.219465734699, 12105.334206618369, 12594.937025314815, 10530.578776849146, 10627.415492148006, 10757.031867131996, 10826.242163048511, 10946.233763636788, 10382.532786024496, 10545.0, 10590.523426445983, 10735.0, 10792.784144316913, 10568.947155264175, 10866.565337386732, 10934.835977460503, 11132.276402033842, 11142.220044596801, 7210.495265331377, 7586.444778820218, 7637.0, 7669.118051424158, 7911.28798408123, 9732.951335243562, 10664.360138391721, 10936.070434013198, 10967.287126336763, 11017.889821101686], "value": 9783.254187581475, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1245.8816412440815, 1488.181500174904, 1613.1611561987763, 1884.0579710144918, 2039.7246371739811, 8187.304079153271, 8893.644254229832, 9142.542872856364, 9143.588538515765, 9254.537273136333, 8773.034966153718, 8919.598618062186, 8940.19538241558, 9027.232685221765, 9080.182783549495, 2418.842775219614, 2446.71862735785, 2451.044092803808, 2453.362125847275, 2453.6319552067152, 2392.078105426419, 2441.8534887906694, 2447.4737931344757, 2449.2284930246915, 2451.5464639041834, 2412.2160297903165, 2422.4186195313173, 2435.537247922889, 2452.865092419916, 2453.8036957043396, 2388.796952259043, 2394.9281521554294, 2402.0511897800448, 2404.7715467030603, 2432.3554258121612, 2337.135259953811, 2405.458771776354, 2407.518496300747, 2408.0849277274697, 2413.0589070262613, 7783.844323113506, 8459.98480182382, 9176.174144326977, 9246.26029917605, 9247.075292470767, 5301.920471192935, 5520.861978450571, 5525.696086715229, 5572.108462645998, 5574.498295153456, 2944.1903476543907, 3365.7546707718056, 3444.156181735466, 3508.4912687660403, 3510.350585141757, 904.7738065483629, 928.4754113925678, 942.9858552121724, 1542.159523059935, 1615.1601167393007, 12174.939125304405, 12202.328871912041, 12259.387030648431, 12292.692682682915, 12299.56951506702, 4584.61030812378, 4646.024334889644, 4719.834805781781, 4724.574788269072, 4740.170470167707, 9693.273004524688, 9708.368956017835, 9740.123388894892, 9744.512774361254, 9745.805083898424, 5478.342598888079, 5480.260164877691, 5482.945170548243, 5487.396386397493, 5496.477834605706, 4966.180580204298, 5011.6241281904, 5061.6962982221185, 5123.38519377677, 5148.742562871841, 3607.1162565171703, 3650.105724097613, 3680.963190368062, 3717.033571271489, 3742.0831896185064, 1094.2559059839227, 1115.2583531951161, 1119.6529075986307, 1125.836753670713, 1130.1410927694847, 901.8872640919885, 923.8337099322187, 926.3422969691594, 927.142393286221, 940.3229674634252, 5857.47282744555, 6035.758569657164, 6088.6042407243385, 6146.938530614726, 6250.968745156245, 858.3090612057229, 992.156666833186, 1034.5034383495813, 1079.4440862955585, 1089.9564017439372, 3966.6231707987695, 4062.471878655756, 4072.6741860651077, 4130.091379896418, 4143.316352801754, 8913.42062765931, 10985.560577576967, 11318.547258109584, 11343.149263805248, 11354.375509347065, 11066.557337706563, 11175.105991520659, 11191.608293709682, 11228.382438965771, 11260.831087533694, 8066.23370779775, 8383.455075420079, 8438.409311348269, 8544.060153383123, 8577.614007369682, 6007.789727359522, 6013.548983826318, 6062.878367502026, 6116.5718399711595, 6179.382061793965, 10001.2999090063, 10370.222233332379, 10398.324108932895, 10401.94799026, 10406.843897341394, 9811.166050885755, 10095.798084038424, 10125.848112278469, 10551.261411701107, 10560.894391055992, 8367.456115352483, 8432.156784321518, 8513.489190648581, 8544.0, 8546.2308392245, 8403.453775504573, 8423.663053477792, 8481.194286542768, 8491.490510569385, 8499.575021249037, 7422.109346878193, 7670.7698769037015, 7690.2694244046725, 7762.107357653942, 7805.375569954504, 7537.472376933562, 7549.018627578599, 7651.88522172179, 7663.0, 7713.382929365749, 6656.267810540933, 6787.762428315183, 7026.051483049723, 7072.5402848814665, 7075.186353569406, 4073.674106071448, 4693.460252071056, 4869.147899117711, 4898.461169271449, 5109.3613298337705, 4819.783109759999, 4962.851114466572, 5009.298698182184, 5025.195968644999, 5196.636235463632], "value": 4684.948849788763, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.6949504737833, 310.35755985110814, 322.7692200076945, 327.91310302769756, 330.82631618400336, 4611.723296602207, 5299.920501192485, 5521.917171242435, 5522.392536820947, 5578.429804924841, 5221.817236396727, 5448.42791506892, 5456.61803673743, 5473.549509380017, 5501.661793737656, 3705.925881482369, 4041.97979010106, 4042.3128068228325, 4169.499660040799, 4206.495220573535, 3521.5421995140596, 3619.7466177367596, 3867.943347297033, 4222.746635201897, 4236.724612900151, 3922.3134052357473, 4084.264832330179, 4114.280000999828, 4157.958420415803, 4158.106007208449, 4132.772697501636, 4149.066460046495, 4160.604742549453, 4170.436991006206, 4196.181744559812, 3281.442154833684, 3505.1587618971516, 3626.220362622049, 3644.562652481719, 3653.1962968146963, 4163.39630753542, 4610.9308360374625, 4963.975180124113, 5240.764165612557, 5242.79028838846, 4130.315295058206, 4173.373993900909, 4455.643548516111, 4692.052798088789, 4712.717236965759, 4637.744924029177, 4770.189067858472, 5330.946690533084, 5747.913781293284, 5758.62568933018, 3717.4204438266343, 3919.980400097981, 3935.626115519021, 3943.5464921534103, 3949.768380864802, 5499.552699048885, 6034.728437220336, 6062.907157987312, 6078.684134264678, 6092.573519853612, 3604.675579197885, 3640.0171953572385, 3722.7207959403154, 3773.339665558516, 3984.541777695602, 3177.7616678749187, 3478.426059700121, 3646.690031347365, 4034.1528279061727, 4081.0, 4016.176683779818, 4207.99128623036, 4422.579854913778, 4518.615917646971, 4730.716157030589, 4917.434495033117, 5547.420449490925, 5812.505936995318, 5819.941800582024, 5846.94153058464, 1227.9930457025152, 1300.9414576343897, 1352.7970804379224, 1372.9519466818613, 1378.4348417149122, 717.824133087397, 810.6352141536332, 837.4716143038978, 840.2815592668303, 851.6891334662804, 4045.8179381927885, 4506.707064040893, 4508.797104130256, 4513.796879140446, 4547.226971414867, 4292.994903567515, 4415.51429342772, 4561.989087202497, 4887.413510378778, 5033.521815427529, 4089.7750623715388, 4237.406763053175, 4431.667624928144, 4480.7535585542455, 4529.818807247673, 2291.2518513432688, 2551.2523921113725, 2654.2700757291514, 2661.1106114658423, 2665.3469899874653, 4593.678442509059, 5087.211482220262, 5097.596547659493, 5199.818006369759, 5227.45111763267, 4437.644988400984, 4457.665675074382, 4507.414036175276, 4961.727105009187, 5111.923321150186, 4306.0, 5148.922766158586, 5217.721658193766, 5219.895602087937, 5224.843254702365, 4446.0, 4809.615230781599, 5009.373828271466, 5015.774290156879, 5033.395992480926, 4286.971126929452, 4398.780060997002, 4840.951590484191, 4955.0089982004465, 4959.0, 4387.610285725562, 4833.516648335139, 5211.280277508489, 5211.801285704233, 5329.387120481194, 3232.3858466891215, 3378.239896023375, 3437.8281085945105, 3446.5864096308605, 3455.7235421166747, 4477.365761496965, 4521.977390113028, 4584.896517616781, 4976.30331753562, 5182.870428239325, 4561.631510546889, 5321.654092484131, 5635.267415236157, 5675.836453527099, 5768.451997060272, 4911.017796440655, 5613.410591887793, 5709.685967271674, 5731.254936858182, 5736.827895163152, 5599.0683214291, 5601.719914004365, 5613.550915926634, 5614.69119198448, 5648.689322087161, 4459.7993090310365, 4976.898678854203, 5387.976284505931, 5416.539594134542, 5796.768129274782, 5371.731413429391, 5672.468433523009, 5688.715564221689, 5714.942850571441, 5720.3421606515785], "value": 3907.9502525689854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.6899798587615, 733.1715161867089, 736.0468193689172, 769.4383100336757, 777.6306254529096, 14738.63153421165, 15551.922240388782, 15569.922150389246, 15574.84425155748, 15581.220938953064, 14220.431182752704, 14288.78566821496, 14355.497557585486, 14368.20974846383, 14392.49626263081, 700.912385951756, 716.3373879161754, 895.7760559860035, 900.3292547052439, 920.8066306075699, 903.9864402033942, 912.7946212102257, 922.0364718868765, 923.4597760310193, 925.3661242049218, 982.3614650477211, 982.5578489679637, 983.0709979069782, 983.9803203935917, 984.2224642532391, 738.2285511640349, 939.248601119102, 953.4327075390132, 955.9474228917406, 978.4129522286643, 710.0698085508001, 712.4051417066755, 712.9037579926747, 713.2368365848544, 714.5569746757044, 14784.260786960609, 14810.777838332433, 14824.629384265372, 14838.183899885502, 14861.925690371587, 12313.07651926109, 12383.876161238453, 12430.192037517623, 12548.874511254862, 12645.49418023278, 10504.684859454228, 10806.405647689375, 10823.291078903045, 10930.0, 11186.832197517044, 3983.5618082010956, 4014.9197016059516, 4063.9593604063875, 4110.0752330725645, 4149.875503734893, 83714.74427883586, 84969.57515212447, 86313.70529442001, 87118.56440717819, 87181.0, 9451.0, 9466.95266523663, 9540.379875308083, 9721.104384645068, 10136.797264054678, 21678.34964951054, 21716.457088572595, 21716.565668686853, 21718.565628687655, 21722.13111475555, 7164.92835071653, 7312.5246858954, 7508.0990281166605, 7580.05249343832, 7623.923760762433, 3952.545457272393, 3969.186316805048, 3995.820188091543, 4075.4701888754867, 4094.7952602369764, 3356.3455126250146, 3453.32660131277, 3454.240067185215, 3454.3954807908517, 3457.515947767264, 568.6474385880698, 629.6788637794758, 642.4539141729585, 646.544186348623, 649.9837504062342, 487.3542556113093, 612.0024360292783, 612.9754809807647, 617.3209469583422, 632.6966449114839, 6866.279040700654, 7426.2945020223, 7516.060492438445, 7605.087389513294, 7657.502262352929, 585.5052480653841, 588.5350573047244, 604.2718524178355, 629.2794750011211, 632.6899819088613, 3572.5712914450432, 3958.188571342868, 3976.1451287973223, 3977.76133431995, 4176.0395109125075, 88090.0, 90552.5472372634, 97566.0, 98691.0, 99003.50498247462, 73589.5282094355, 80110.59944700239, 81555.59222203851, 81804.77292840612, 85048.57475712706, 71594.28405715876, 71965.0, 73687.0, 74947.50104997978, 76325.85511217336, 7627.580483073484, 7743.264389882952, 7881.290683838482, 7890.644920978684, 7893.605319734105, 44129.55870441254, 44264.0, 45635.54364456313, 45900.540994589624, 47041.0, 59550.106748399674, 64367.0, 64424.0, 70673.29326706668, 70866.64566677134, 11555.4800033997, 11630.244034137753, 11660.591879284184, 11775.52897884075, 11917.0, 11903.821442678194, 11987.64037078889, 12086.758264834478, 12117.030637548798, 12950.352482375654, 11752.76494470123, 12007.579734709243, 12020.33888136161, 12204.206726562745, 12554.309512976874, 11775.76448471043, 11799.882001179878, 11945.940270298941, 12000.03999680041, 12193.573224937085, 11414.82877756851, 11468.541258349573, 11717.355545445082, 11724.58963936258, 11791.823122652995, 9428.057194280791, 9698.85451718239, 9734.269929755155, 10023.849642255518, 10112.59549618007, 9160.404573702688, 9781.902180978099, 9784.168345690696, 9819.312648114563, 9826.901730982885], "value": 6980.290132070664, "unit": "score"}], "name": "dromaeo_css", "value": 7194.559771786105, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 111.0, 110.0, 110.0, 111.0, 110.0, 116.0, 89.0, 154.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 170.0, 207.0, 114.0, 138.0, 136.0, 117.0, 166.0, 117.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 183.0, 182.0, 184.0, 185.0, 187.0, 180.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 67.0, 125.0, 57.0, 60.0, 56.0, 60.0, 107.0, 48.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 132.0, 80.0, 82.0, 78.0, 102.0, 82.0, 80.0, 102.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 84.0, 87.0, 84.0, 87.0, 84.0, 90.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 83.0, 87.0, 85.0, 83.0, 87.0, 86.0, 85.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 56.0, 56.0, 55.0, 54.0, 56.0, 119.0, 58.0, 54.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 71.0, 68.0, 69.0, 68.0, 68.0, 67.0, 67.0, 78.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 86.0, 86.0, 84.0, 86.0, 84.0, 85.0, 87.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 142.0, 151.0, 126.0, 131.0, 134.0, 135.0, 133.0, 214.0, 139.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 212.0, 222.0, 160.0, 162.0, 163.0, 159.0, 161.0, 162.0, 153.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 80.0, 59.0, 65.0, 67.0, 78.0, 83.0, 85.0, 63.0, 64.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1477.6000000000001, "alertThreshold": 2.0}]} 13:20:44 INFO - Return code: 0 13:20:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:20:44 ERROR - # TBPL SUCCESS # 13:20:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:20:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:20:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:20:44 INFO - 2016-05-18 13:20:44.483 screenresolution[1682:10105] starting screenresolution argv=screenresolution get 13:20:44 INFO - 2016-05-18 13:20:44.485 screenresolution[1682:10105] Display 0: 1600x1200x32@60 13:20:44 INFO - 2016-05-18 13:20:44.491 screenresolution[1683:10108] starting screenresolution argv=screenresolution list 13:20:44 INFO - Available Modes on Display 0 13:20:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:20:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:20:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:20:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:20:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:20:44 INFO - Intel Iris: 13:20:44 INFO - Chipset Model: Intel Iris 13:20:44 INFO - Type: GPU 13:20:44 INFO - Bus: Built-In 13:20:44 INFO - VRAM (Dynamic, Max): 1536 MB 13:20:44 INFO - Vendor: Intel (0x8086) 13:20:44 INFO - Device ID: 0x0a2e 13:20:44 INFO - Revision ID: 0x0009 13:20:44 INFO - Displays: 13:20:44 INFO - Display: 13:20:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:20:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:20:44 INFO - Display Serial Number: 1600x1200 60 13:20:44 INFO - Main Display: Yes 13:20:44 INFO - Mirror: Off 13:20:44 INFO - Online: Yes 13:20:44 INFO - Rotation: Supported 13:20:44 INFO - Return code: 0 13:20:44 INFO - Running post-action listener: _resource_record_post_action 13:20:44 INFO - ##### 13:20:44 INFO - ##### Finished run-tests step (success) 13:20:44 INFO - ##### 13:20:44 INFO - Running post-run listener: _resource_record_post_run 13:20:44 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 36820480; Write bytes: 696507904; Read time: 259; Write time: 1933 13:20:44 INFO - TinderboxPrint: CPU usage
54.0% 13:20:44 INFO - TinderboxPrint: I/O read bytes / time
36,820,480 / 259 13:20:44 INFO - TinderboxPrint: I/O write bytes / time
696,507,904 / 1,933 13:20:44 INFO - TinderboxPrint: CPU idle
2,234.5 (46.0%) 13:20:44 INFO - TinderboxPrint: CPU system
970.8 (20.0%) 13:20:44 INFO - TinderboxPrint: CPU user
1,648.4 (34.0%) 13:20:44 INFO - TinderboxPrint: Swap in / out
431,947,776 / 0 13:20:44 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378479104; Write bytes: 373069312; Read time: 28352; Write time: 762 13:20:44 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35866112; Write bytes: 298567680; Read time: 245; Write time: 1114 13:20:45 INFO - Running post-run listener: _upload_blobber_files 13:20:45 INFO - Blob upload gear active. 13:20:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:20:45 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 13:20:45 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'] 13:20:45 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 13:20:45 INFO - (blobuploader) - INFO - Open directory for files ... 13:20:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:20:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:20:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:46 INFO - (blobuploader) - INFO - Done attempting. 13:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:20:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:20:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:48 INFO - (blobuploader) - INFO - Done attempting. 13:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:20:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:51 INFO - (blobuploader) - INFO - Done attempting. 13:20:51 INFO - (blobuploader) - INFO - Iteration through files over. 13:20:51 INFO - Return code: 0 13:20:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:20:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:20:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f908a77ef704eb472c1e0fec9ecc64cbb2d4d80b9a34d3deb390430e0d8697eac97a279c4edfe854d3dcfce1bc0130dffa94d09192149669390d4fa1ece63516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c0eccda6e89dd19c6ee6894829d5283cd60944aa5f6828ee90668caf827585988d0f2684a868db2da08e34258fe3b2ae83c54418fccb02bc2cf7536229c7991", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52259411e6b8c97a4ad76372a562a408c7c63f1e21ce31d5c9b490ac1865c7de4f6e0c9d55a7865d439285ccf1c46e108ac968179f42978fe679f1f07b3e3083"} 13:20:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:20:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:20:51 INFO - Contents: 13:20:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f908a77ef704eb472c1e0fec9ecc64cbb2d4d80b9a34d3deb390430e0d8697eac97a279c4edfe854d3dcfce1bc0130dffa94d09192149669390d4fa1ece63516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c0eccda6e89dd19c6ee6894829d5283cd60944aa5f6828ee90668caf827585988d0f2684a868db2da08e34258fe3b2ae83c54418fccb02bc2cf7536229c7991", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52259411e6b8c97a4ad76372a562a408c7c63f1e21ce31d5c9b490ac1865c7de4f6e0c9d55a7865d439285ccf1c46e108ac968179f42978fe679f1f07b3e3083"} 13:20:51 INFO - Running post-run listener: copy_logs_to_upload_dir 13:20:51 INFO - Copying logs to upload dir... 13:20:51 INFO - mkdir: /builds/slave/test/build/upload/logs 13:20:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.407455 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-18 13:20:51.409466) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:51.413149) ========= 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.D24dXcTy6O/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.29S5ni59xV/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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/f908a77ef704eb472c1e0fec9ecc64cbb2d4d80b9a34d3deb390430e0d8697eac97a279c4edfe854d3dcfce1bc0130dffa94d09192149669390d4fa1ece63516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c0eccda6e89dd19c6ee6894829d5283cd60944aa5f6828ee90668caf827585988d0f2684a868db2da08e34258fe3b2ae83c54418fccb02bc2cf7536229c7991", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52259411e6b8c97a4ad76372a562a408c7c63f1e21ce31d5c9b490ac1865c7de4f6e0c9d55a7865d439285ccf1c46e108ac968179f42978fe679f1f07b3e3083"} build_url:https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010952 build_url: 'https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/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/f908a77ef704eb472c1e0fec9ecc64cbb2d4d80b9a34d3deb390430e0d8697eac97a279c4edfe854d3dcfce1bc0130dffa94d09192149669390d4fa1ece63516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c0eccda6e89dd19c6ee6894829d5283cd60944aa5f6828ee90668caf827585988d0f2684a868db2da08e34258fe3b2ae83c54418fccb02bc2cf7536229c7991", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52259411e6b8c97a4ad76372a562a408c7c63f1e21ce31d5c9b490ac1865c7de4f6e0c9d55a7865d439285ccf1c46e108ac968179f42978fe679f1f07b3e3083"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:51.440572) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:51.440866) ========= 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.D24dXcTy6O/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.29S5ni59xV/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.005009 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:51.489214) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:20:51.489494) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:20:51.489798) ========= ========= Total master_lag: 0.41 =========