builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0094 starttime: 1463660048.27 results: success (0) buildid: 20160519030232 builduid: d7675fa0f1774f7fb325a557b878cee8 revision: c4449eab07d39e20ea315603f1b1863eeed7dcfe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.275044) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.275459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.275742) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.295652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.296053) ========= 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.kbGRnFhsD7/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.zmBXOrkEir/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-19 05:14:08-- 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% 33.7M=0s 2016-05-19 05:14:08 (33.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137544 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.452862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.453287) ========= 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.kbGRnFhsD7/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.zmBXOrkEir/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.027218 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:08.503956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 05:14:08.504459) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c4449eab07d39e20ea315603f1b1863eeed7dcfe --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 c4449eab07d39e20ea315603f1b1863eeed7dcfe --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kbGRnFhsD7/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.zmBXOrkEir/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-19 05:14:08,581 truncating revision to first 12 chars 2016-05-19 05:14:08,581 Setting DEBUG logging. 2016-05-19 05:14:08,581 attempt 1/10 2016-05-19 05:14:08,581 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c4449eab07d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 05:14:10,120 unpacking tar archive at: mozilla-central-c4449eab07d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.780941 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 05:14:10.373638) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:10.373970) ========= script_repo_revision: c4449eab07d39e20ea315603f1b1863eeed7dcfe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:10.374369) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:10.374627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 05:14:10.388629) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-19 05:14:10.388965) ========= /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.kbGRnFhsD7/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.zmBXOrkEir/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:10 INFO - MultiFileLogger online at 20160519 05:14:10 in /builds/slave/test 05:14:10 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 05:14:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:14:10 INFO - {'append_to_log': False, 05:14:10 INFO - 'base_work_dir': '/builds/slave/test', 05:14:10 INFO - 'blob_upload_branch': 'Firefox', 05:14:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:14:10 INFO - 'branch': 'Firefox', 05:14:10 INFO - 'buildbot_json_path': 'buildprops.json', 05:14:10 INFO - 'config_files': ('talos/mac_config.py',), 05:14:10 INFO - 'default_actions': ('clobber', 05:14:10 INFO - 'read-buildbot-config', 05:14:10 INFO - 'download-and-extract', 05:14:10 INFO - 'populate-webroot', 05:14:10 INFO - 'create-virtualenv', 05:14:10 INFO - 'install', 05:14:10 INFO - 'run-tests'), 05:14:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:14:10 INFO - 'download_minidump_stackwalk': True, 05:14:10 INFO - 'download_symbols': 'ondemand', 05:14:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:14:10 INFO - 'tooltool.py': '/tools/tooltool.py', 05:14:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:14:10 INFO - '/tools/misc-python/virtualenv.py')}, 05:14:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:14:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:14:10 INFO - 'installer_path': 'installer.exe', 05:14:10 INFO - 'log_level': 'info', 05:14:10 INFO - 'log_name': 'talos', 05:14:10 INFO - 'log_to_console': True, 05:14:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:14:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:14:10 INFO - 'opt_config_files': (), 05:14:10 INFO - 'pip_index': False, 05:14:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:10 INFO - 'cmd': ('bash', 05:14:10 INFO - '-c', 05:14:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:10 INFO - 'enabled': True, 05:14:10 INFO - 'halt_on_failure': False, 05:14:10 INFO - 'name': 'check_screen_resolution'},), 05:14:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:10 INFO - 'cmd': ('bash', 05:14:10 INFO - '-c', 05:14:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:10 INFO - 'enabled': True, 05:14:10 INFO - 'halt_on_failure': False, 05:14:10 INFO - 'name': 'check_screen_resolution'},), 05:14:10 INFO - 'run_cmd_checks_enabled': True, 05:14:10 INFO - 'sps_profile': False, 05:14:10 INFO - 'sps_profile_interval': 0, 05:14:10 INFO - 'suite': 'dromaeojs', 05:14:10 INFO - 'system_bits': '32', 05:14:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:14:10 INFO - 'title': 't-yosemite-r7-0094', 05:14:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:14:10 INFO - 'use_talos_json': True, 05:14:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:14:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:14:10 INFO - 'work_dir': 'build'} 05:14:10 INFO - ##### 05:14:10 INFO - ##### Running clobber step. 05:14:10 INFO - ##### 05:14:10 INFO - Running pre-action listener: _resource_record_pre_action 05:14:10 INFO - Running main action method: clobber 05:14:10 INFO - rmtree: /builds/slave/test/build 05:14:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:14:12 INFO - Running post-action listener: _resource_record_post_action 05:14:12 INFO - ##### 05:14:12 INFO - ##### Finished clobber step (success) 05:14:12 INFO - ##### 05:14:12 INFO - ##### 05:14:12 INFO - ##### Running read-buildbot-config step. 05:14:12 INFO - ##### 05:14:12 INFO - Running pre-action listener: _resource_record_pre_action 05:14:12 INFO - Running main action method: read_buildbot_config 05:14:12 INFO - Using buildbot properties: 05:14:12 INFO - { 05:14:12 INFO - "project": "", 05:14:12 INFO - "product": "firefox", 05:14:12 INFO - "script_repo_revision": "production", 05:14:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:14:12 INFO - "repository": "", 05:14:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:14:12 INFO - "buildid": "20160519030232", 05:14:12 INFO - "stage_platform": "macosx64", 05:14:12 INFO - "basedir": "/builds/slave/test", 05:14:12 INFO - "nightly_build": "True", 05:14:12 INFO - "buildnumber": 244, 05:14:12 INFO - "slavename": "t-yosemite-r7-0094", 05:14:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:14:12 INFO - "platform": "yosemite_r7", 05:14:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:14:12 INFO - "branch": "mozilla-central", 05:14:12 INFO - "repo_path": "mozilla-central", 05:14:12 INFO - "revision": "c4449eab07d39e20ea315603f1b1863eeed7dcfe", 05:14:12 INFO - "pgo_build": "False", 05:14:12 INFO - "builduid": "d7675fa0f1774f7fb325a557b878cee8", 05:14:12 INFO - "slavebuilddir": "test" 05:14:12 INFO - } 05:14:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:14:12 INFO - Running post-action listener: _resource_record_post_action 05:14:12 INFO - ##### 05:14:12 INFO - ##### Finished read-buildbot-config step (success) 05:14:12 INFO - ##### 05:14:12 INFO - ##### 05:14:12 INFO - ##### Running download-and-extract step. 05:14:12 INFO - ##### 05:14:12 INFO - Running pre-action listener: _resource_record_pre_action 05:14:12 INFO - Running main action method: download_and_extract 05:14:12 INFO - mkdir: /builds/slave/test/build/tests 05:14: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')]} 05:14:12 INFO - https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:14:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:14:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:14:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:14 INFO - Downloaded 1199 bytes. 05:14:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:14:14 INFO - Using the following test package requirements: 05:14:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:14:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:14:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:14:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'jsshell-mac.zip'], 05:14:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:14:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:14:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:14:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:14:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:14:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:14:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:14:14 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')]} 05:14:14 INFO - https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:15 INFO - Downloaded 18629094 bytes. 05:14:15 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'] 05:14:15 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 05:14:18 INFO - Return code: 0 05:14:18 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 05:14:18 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')]} 05:14:18 INFO - https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:20 INFO - Downloaded 18629094 bytes. 05:14:20 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'] 05:14:20 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 05:14:23 INFO - Return code: 0 05:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:23 INFO - https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:14:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:14:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:14:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:25 INFO - Downloaded 10840194 bytes. 05:14:25 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'] 05:14:25 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 05:14:25 INFO - Return code: 0 05:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:25 INFO - https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:14:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHTQ5joASAauHNgXvScVjg/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 05:14:31 INFO - Downloaded 110446533 bytes. 05:14:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:31 INFO - mkdir: /builds/slave/test/properties 05:14:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:14:31 INFO - Writing to file /builds/slave/test/properties/build_url 05:14:31 INFO - Contents: 05:14:31 INFO - build_url:https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:32 INFO - Running post-action listener: _resource_record_post_action 05:14:32 INFO - Running post-action listener: set_extra_try_arguments 05:14:32 INFO - ##### 05:14:32 INFO - ##### Finished download-and-extract step (success) 05:14:32 INFO - ##### 05:14:32 INFO - ##### 05:14:32 INFO - ##### Running populate-webroot step. 05:14:32 INFO - ##### 05:14:32 INFO - Running pre-action listener: _resource_record_pre_action 05:14:32 INFO - Running main action method: populate_webroot 05:14:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:14:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:14:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:14:32 INFO - '--tppagecycles', 05:14:32 INFO - '7'], 05:14:32 INFO - 'tests': ['tsvgm']}}, 05:14:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:14:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:14:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:14:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:14:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['damp', 'tps']}, 05:14:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'tests': ['damp', 'tps']}, 05:14:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['dromaeo_dom']}, 05:14:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:14:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['a11yr', 05:14:32 INFO - 'ts_paint', 05:14:32 INFO - 'tpaint', 05:14:32 INFO - 'sessionrestore', 05:14:32 INFO - 'sessionrestore_no_auto_restore', 05:14:32 INFO - 'tabpaint']}, 05:14:32 INFO - 'other-e10s': {'tests': ['a11yr', 05:14:32 INFO - 'ts_paint', 05:14:32 INFO - 'tpaint', 05:14:32 INFO - 'sessionrestore', 05:14:32 INFO - 'sessionrestore_no_auto_restore', 05:14:32 INFO - 'tabpaint']}, 05:14:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:14:32 INFO - 'ts_paint', 05:14:32 INFO - 'tpaint', 05:14:32 INFO - 'sessionrestore', 05:14:32 INFO - 'sessionrestore_no_auto_restore', 05:14:32 INFO - 'tabpaint']}, 05:14:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:14:32 INFO - 'ts_paint', 05:14:32 INFO - 'tpaint', 05:14:32 INFO - 'sessionrestore', 05:14:32 INFO - 'sessionrestore_no_auto_restore', 05:14:32 INFO - 'tabpaint']}, 05:14:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['a11yr', 05:14:32 INFO - 'ts_paint', 05:14:32 INFO - 'tpaint', 05:14:32 INFO - 'sessionrestore', 05:14:32 INFO - 'sessionrestore_no_auto_restore', 05:14:32 INFO - 'tabpaint']}, 05:14:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['a11yr', 05:14:32 INFO - 'ts_paint', 05:14:32 INFO - 'tpaint', 05:14:32 INFO - 'sessionrestore', 05:14:32 INFO - 'sessionrestore_no_auto_restore', 05:14:32 INFO - 'tabpaint']}, 05:14:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['tsvgx', 05:14:32 INFO - 'tsvgr_opacity', 05:14:32 INFO - 'tart', 05:14:32 INFO - 'tscrollx', 05:14:32 INFO - 'cart']}, 05:14:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:14:32 INFO - 'tsvgr_opacity', 05:14:32 INFO - 'tart', 05:14:32 INFO - 'tscrollx', 05:14:32 INFO - 'cart']}, 05:14:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'talos_options': ['--disable-e10s'], 05:14:32 INFO - 'tests': ['tp5o']}, 05:14:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'tests': ['tp5o']}, 05:14:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'talos_options': ['--disable-e10s', 05:14:32 INFO - '--xperf_path', 05:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:32 INFO - 'tests': ['tp5n']}, 05:14:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:32 INFO - 'talos_options': ['--xperf_path', 05:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:32 INFO - 'tests': ['tp5n']}}, 05:14:32 INFO - 'talos.zip': {'path': '', 05:14:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:14:32 INFO - Running post-action listener: _resource_record_post_action 05:14:32 INFO - ##### 05:14:32 INFO - ##### Finished populate-webroot step (success) 05:14:32 INFO - ##### 05:14:32 INFO - ##### 05:14:32 INFO - ##### Running create-virtualenv step. 05:14:32 INFO - ##### 05:14:32 INFO - Running pre-action listener: _resource_record_pre_action 05:14:32 INFO - Running main action method: create_virtualenv 05:14:32 INFO - Creating virtualenv /builds/slave/test/build/venv 05:14:32 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 05:14:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:14:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:14:32 INFO - Using real prefix '/tools/python27' 05:14:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:14:33 INFO - Installing distribute.............................................................................................................................................................................................done. 05:14:35 INFO - Installing pip.................done. 05:14:35 INFO - Return code: 0 05:14:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:14: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')]} 05:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14: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')]} 05:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:35 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:35 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 05:14:35 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 05:14:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:35 INFO - 'HOME': '/Users/cltbld', 05:14:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:35 INFO - 'LOGNAME': 'cltbld', 05:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:35 INFO - 'MOZ_NO_REMOTE': '1', 05:14:35 INFO - 'NO_EM_RESTART': '1', 05:14:35 INFO - 'PAGER': '/bin/cat', 05:14:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:35 INFO - 'PWD': '/builds/slave/test', 05:14:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:35 INFO - 'SHELL': '/bin/bash', 05:14:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:35 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:35 INFO - 'USER': 'cltbld', 05:14:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:35 INFO - 'XPC_FLAGS': '0x0', 05:14:35 INFO - 'XPC_SERVICE_NAME': '0', 05:14:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:36 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 05:14:36 INFO - Cleaning up... 05:14:36 INFO - Return code: 0 05:14:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:14:36 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')]} 05:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:36 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')]} 05:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:36 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:36 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 05:14:36 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 05:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:36 INFO - 'HOME': '/Users/cltbld', 05:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:36 INFO - 'LOGNAME': 'cltbld', 05:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:36 INFO - 'MOZ_NO_REMOTE': '1', 05:14:36 INFO - 'NO_EM_RESTART': '1', 05:14:36 INFO - 'PAGER': '/bin/cat', 05:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:36 INFO - 'PWD': '/builds/slave/test', 05:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:36 INFO - 'SHELL': '/bin/bash', 05:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:36 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:36 INFO - 'USER': 'cltbld', 05:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:36 INFO - 'XPC_FLAGS': '0x0', 05:14:36 INFO - 'XPC_SERVICE_NAME': '0', 05:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:36 INFO - Downloading/unpacking psutil>=3.1.1 05:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:14:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:39 INFO - Installing collected packages: psutil 05:14:39 INFO - Running setup.py install for psutil 05:14:39 INFO - building 'psutil._psutil_osx' extension 05:14:39 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 05:14:39 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 05:14:39 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 05:14:39 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 05:14:40 INFO - building 'psutil._psutil_posix' extension 05:14:40 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 05:14:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:14:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:14:40 INFO - ^ 05:14:40 INFO - 1 warning generated. 05:14:40 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 05:14:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:40 INFO - Successfully installed psutil 05:14:40 INFO - Cleaning up... 05:14:40 INFO - Return code: 0 05:14:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:14: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')]} 05:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14: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')]} 05:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:40 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:40 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 05:14:40 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 05:14:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:40 INFO - 'HOME': '/Users/cltbld', 05:14:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:40 INFO - 'LOGNAME': 'cltbld', 05:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:40 INFO - 'MOZ_NO_REMOTE': '1', 05:14:40 INFO - 'NO_EM_RESTART': '1', 05:14:40 INFO - 'PAGER': '/bin/cat', 05:14:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:40 INFO - 'PWD': '/builds/slave/test', 05:14:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:40 INFO - 'SHELL': '/bin/bash', 05:14:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:40 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:40 INFO - 'USER': 'cltbld', 05:14:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:40 INFO - 'XPC_FLAGS': '0x0', 05:14:40 INFO - 'XPC_SERVICE_NAME': '0', 05:14:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:14:40 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 05:14:40 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 05:14:40 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 05:14:40 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 05:14:40 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 05:14:40 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 05:14:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:14:43 INFO - Installing collected packages: mozsystemmonitor 05:14:43 INFO - Running setup.py install for mozsystemmonitor 05:14:43 INFO - Successfully installed mozsystemmonitor 05:14:43 INFO - Cleaning up... 05:14:43 INFO - Return code: 0 05:14:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:14:43 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')]} 05:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:43 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')]} 05:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:43 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:43 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 05:14:43 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 05:14:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:43 INFO - 'HOME': '/Users/cltbld', 05:14:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:43 INFO - 'LOGNAME': 'cltbld', 05:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:43 INFO - 'MOZ_NO_REMOTE': '1', 05:14:43 INFO - 'NO_EM_RESTART': '1', 05:14:43 INFO - 'PAGER': '/bin/cat', 05:14:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:43 INFO - 'PWD': '/builds/slave/test', 05:14:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:43 INFO - 'SHELL': '/bin/bash', 05:14:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:43 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:43 INFO - 'USER': 'cltbld', 05:14:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:43 INFO - 'XPC_FLAGS': '0x0', 05:14:43 INFO - 'XPC_SERVICE_NAME': '0', 05:14:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:43 INFO - Downloading/unpacking blobuploader==1.2.4 05:14:43 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 05:14:43 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 05:14:43 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 05:14:43 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 05:14:43 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 05:14:43 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 05:14:46 INFO - Downloading blobuploader-1.2.4.tar.gz 05:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:14:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:14:46 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 05:14:46 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 05:14:46 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 05:14:46 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 05:14:46 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 05:14:46 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 05:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:46 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 05:14:46 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 05:14:46 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 05:14:46 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 05:14:46 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 05:14:46 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 05:14:47 INFO - Downloading docopt-0.6.1.tar.gz 05:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:47 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:47 INFO - Running setup.py install for blobuploader 05:14:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:47 INFO - Running setup.py install for requests 05:14:47 INFO - Running setup.py install for docopt 05:14:48 INFO - Successfully installed blobuploader requests docopt 05:14:48 INFO - Cleaning up... 05:14:48 INFO - Return code: 0 05:14:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14: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')]} 05:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14: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')]} 05:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:48 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:48 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 05:14:48 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 05:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:48 INFO - 'HOME': '/Users/cltbld', 05:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:48 INFO - 'LOGNAME': 'cltbld', 05:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:48 INFO - 'MOZ_NO_REMOTE': '1', 05:14:48 INFO - 'NO_EM_RESTART': '1', 05:14:48 INFO - 'PAGER': '/bin/cat', 05:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:48 INFO - 'PWD': '/builds/slave/test', 05:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:48 INFO - 'SHELL': '/bin/bash', 05:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:48 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:48 INFO - 'USER': 'cltbld', 05:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:48 INFO - 'XPC_FLAGS': '0x0', 05:14:48 INFO - 'XPC_SERVICE_NAME': '0', 05:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-SkmJeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Srsluq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Crio7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-56maMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-2In1Xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-VI9NRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-WDoitu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-GtJoNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-9dgkOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BtpEVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-EdITr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gOvNHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ss2XR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-UJg_QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-DC1MnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-MwukoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:50 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-OdG1zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:50 INFO - Running setup.py install for manifestparser 05:14:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:50 INFO - Running setup.py install for mozcrash 05:14:50 INFO - Running setup.py install for mozdebug 05:14:50 INFO - Running setup.py install for mozdevice 05:14:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:50 INFO - Running setup.py install for mozfile 05:14:50 INFO - Running setup.py install for mozhttpd 05:14:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Running setup.py install for mozinfo 05:14:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Running setup.py install for mozInstall 05:14:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Running setup.py install for mozleak 05:14:51 INFO - Running setup.py install for mozlog 05:14:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Running setup.py install for moznetwork 05:14:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:51 INFO - Running setup.py install for mozprocess 05:14:51 INFO - Running setup.py install for mozprofile 05:14:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:52 INFO - Running setup.py install for mozrunner 05:14:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:52 INFO - Running setup.py install for mozscreenshot 05:14:52 INFO - Running setup.py install for moztest 05:14:52 INFO - Running setup.py install for mozversion 05:14:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:52 INFO - Cleaning up... 05:14:52 INFO - Return code: 0 05:14:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:52 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:52 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 05:14:52 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 05:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:52 INFO - 'HOME': '/Users/cltbld', 05:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:52 INFO - 'LOGNAME': 'cltbld', 05:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:52 INFO - 'MOZ_NO_REMOTE': '1', 05:14:52 INFO - 'NO_EM_RESTART': '1', 05:14:52 INFO - 'PAGER': '/bin/cat', 05:14:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:52 INFO - 'PWD': '/builds/slave/test', 05:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:52 INFO - 'SHELL': '/bin/bash', 05:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:52 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:52 INFO - 'USER': 'cltbld', 05:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:52 INFO - 'XPC_FLAGS': '0x0', 05:14:52 INFO - 'XPC_SERVICE_NAME': '0', 05:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-j6BMYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-QB7to0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-8QzPF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-3VP7RN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xzo1On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Kp4dec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-JXeAku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gqwjwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-rRCzpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:53 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)) 05:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-8Adz41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:54 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)) 05:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-1OzHJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:54 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)) 05:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-5uWHX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:54 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)) 05:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gGytn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:54 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)) 05:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-3hL52i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:54 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)) 05:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-SPClXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:54 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)) 05:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-DpVUxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:54 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)) 05:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-q98U8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:54 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)) 05:14:54 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)) 05:14:54 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)) 05:14:54 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)) 05:14:54 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)) 05:14:54 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)) 05:14:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:54 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 05:14:54 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 05:14:54 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 05:14:54 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 05:14:54 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 05:14:54 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 05:14:57 INFO - Downloading blessings-1.6.tar.gz 05:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:57 INFO - Installing collected packages: blessings 05:14:57 INFO - Running setup.py install for blessings 05:14:57 INFO - Successfully installed blessings 05:14:57 INFO - Cleaning up... 05:14:57 INFO - Return code: 0 05:14:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:57 INFO - Reading from file tmpfile_stdout 05:14:57 INFO - Current package versions: 05:14:57 INFO - blessings == 1.6 05:14:57 INFO - blobuploader == 1.2.4 05:14:57 INFO - docopt == 0.6.1 05:14:57 INFO - manifestparser == 1.1 05:14:57 INFO - mozInstall == 1.12 05:14:57 INFO - mozcrash == 0.17 05:14:57 INFO - mozdebug == 0.1 05:14:57 INFO - mozdevice == 0.48 05:14:57 INFO - mozfile == 1.2 05:14:57 INFO - mozhttpd == 0.7 05:14:57 INFO - mozinfo == 0.9 05:14:57 INFO - mozleak == 0.1 05:14:57 INFO - mozlog == 3.2 05:14:57 INFO - moznetwork == 0.27 05:14:57 INFO - mozprocess == 0.22 05:14:57 INFO - mozprofile == 0.28 05:14:57 INFO - mozrunner == 6.11 05:14:57 INFO - mozscreenshot == 0.1 05:14:57 INFO - mozsystemmonitor == 0.3 05:14:57 INFO - moztest == 0.7 05:14:57 INFO - mozversion == 1.4 05:14:57 INFO - psutil == 3.1.1 05:14:57 INFO - requests == 1.2.3 05:14:57 INFO - wsgiref == 0.1.2 05:14:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:57 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:57 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 05:14:57 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 05:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:57 INFO - 'HOME': '/Users/cltbld', 05:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:57 INFO - 'LOGNAME': 'cltbld', 05:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:57 INFO - 'MOZ_NO_REMOTE': '1', 05:14:57 INFO - 'NO_EM_RESTART': '1', 05:14:57 INFO - 'PAGER': '/bin/cat', 05:14:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:57 INFO - 'PWD': '/builds/slave/test', 05:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:57 INFO - 'SHELL': '/bin/bash', 05:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:57 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:57 INFO - 'USER': 'cltbld', 05:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:57 INFO - 'XPC_FLAGS': '0x0', 05:14:57 INFO - 'XPC_SERVICE_NAME': '0', 05:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 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)) 05:14:58 INFO - Cleaning up... 05:14:58 INFO - Return code: 0 05:14:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:58 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:14:58 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 05:14:58 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 05:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:58 INFO - 'HOME': '/Users/cltbld', 05:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:58 INFO - 'LOGNAME': 'cltbld', 05:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:58 INFO - 'MOZ_NO_REMOTE': '1', 05:14:58 INFO - 'NO_EM_RESTART': '1', 05:14:58 INFO - 'PAGER': '/bin/cat', 05:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:58 INFO - 'PWD': '/builds/slave/test', 05:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:58 INFO - 'SHELL': '/bin/bash', 05:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:14:58 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:58 INFO - 'USER': 'cltbld', 05:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:58 INFO - 'XPC_FLAGS': '0x0', 05:14:58 INFO - 'XPC_SERVICE_NAME': '0', 05:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:58 INFO - Downloading/unpacking jsonschema 05:14:58 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 05:14:58 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 05:14:58 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 05:14:58 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 05:14:58 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 05:14:58 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 05:15:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:15:00 INFO - Downloading/unpacking functools32 (from jsonschema) 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15:01 INFO - Downloading functools32-3.2.3-2.tar.gz 05:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:15:01 INFO - warning: no files found matching '*.txt' 05:15:01 INFO - no previously-included directories found matching 'build' 05:15:01 INFO - no previously-included directories found matching 'dist' 05:15:01 INFO - no previously-included directories found matching '.git*' 05:15:01 INFO - Installing collected packages: jsonschema, functools32 05:15:01 INFO - Running setup.py install for functools32 05:15:01 INFO - warning: no files found matching '*.txt' 05:15:01 INFO - no previously-included directories found matching 'build' 05:15:01 INFO - no previously-included directories found matching 'dist' 05:15:01 INFO - no previously-included directories found matching '.git*' 05:15:01 INFO - Successfully installed jsonschema functools32 05:15:01 INFO - Cleaning up... 05:15:01 INFO - Return code: 0 05:15:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:15:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:01 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 0x1009b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5405c00f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, '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.kbGRnFhsD7/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.zmBXOrkEir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:15:01 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 05:15:01 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 05:15:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:15:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:01 INFO - 'HOME': '/Users/cltbld', 05:15:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:01 INFO - 'LOGNAME': 'cltbld', 05:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:01 INFO - 'MOZ_NO_REMOTE': '1', 05:15:01 INFO - 'NO_EM_RESTART': '1', 05:15:01 INFO - 'PAGER': '/bin/cat', 05:15:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:01 INFO - 'PWD': '/builds/slave/test', 05:15:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:01 INFO - 'SHELL': '/bin/bash', 05:15:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:15:01 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:15:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:01 INFO - 'USER': 'cltbld', 05:15:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:01 INFO - 'XPC_FLAGS': '0x0', 05:15:01 INFO - 'XPC_SERVICE_NAME': '0', 05:15:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:01 INFO - Downloading/unpacking flake8 05:15:01 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 05:15:01 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 05:15:01 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 05:15:01 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 05:15:01 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 05:15:01 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 05:15:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:15:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:15:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:15:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:05 INFO - no previously-included directories found matching 'docs/_build' 05:15:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:15:05 INFO - Running setup.py install for pep8 05:15:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:05 INFO - no previously-included directories found matching 'docs/_build' 05:15:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:15:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:15:06 INFO - Cleaning up... 05:15:06 INFO - Return code: 0 05:15:06 INFO - Running post-action listener: _resource_record_post_action 05:15:06 INFO - Running post-action listener: _start_resource_monitoring 05:15:06 INFO - Starting resource monitoring. 05:15:06 INFO - ##### 05:15:06 INFO - ##### Finished create-virtualenv step (success) 05:15:06 INFO - ##### 05:15:06 INFO - ##### 05:15:06 INFO - ##### Running install step. 05:15:06 INFO - ##### 05:15:06 INFO - Running pre-action listener: _resource_record_pre_action 05:15:06 INFO - Running main action method: install 05:15:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:15:06 INFO - Reading from file tmpfile_stdout 05:15:06 INFO - Detecting whether we're running mozinstall >=1.0... 05:15:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:15:06 INFO - Reading from file tmpfile_stdout 05:15:06 INFO - Output received: 05:15:06 INFO - Usage: mozinstall [options] installer 05:15:06 INFO - Options: 05:15:06 INFO - -h, --help show this help message and exit 05:15:06 INFO - -d DEST, --destination=DEST 05:15:06 INFO - Directory to install application into. [default: 05:15:06 INFO - "/builds/slave/test"] 05:15:06 INFO - --app=APP Application being installed. [default: firefox] 05:15:06 INFO - mkdir: /builds/slave/test/build/application 05:15:06 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'] 05:15:06 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 05:15:40 INFO - Reading from file tmpfile_stdout 05:15:40 INFO - Output received: 05:15:40 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:15:40 INFO - Running post-action listener: _resource_record_post_action 05:15:40 INFO - ##### 05:15:40 INFO - ##### Finished install step (success) 05:15:40 INFO - ##### 05:15:40 INFO - ##### 05:15:40 INFO - ##### Running run-tests step. 05:15:40 INFO - ##### 05:15:40 INFO - Running pre-action listener: _resource_record_pre_action 05:15:40 INFO - Running main action method: run_tests 05:15:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:40 INFO - 2016-05-19 05:15:40.034 screenresolution[1645:7569] starting screenresolution argv=screenresolution get 05:15:40 INFO - 2016-05-19 05:15:40.036 screenresolution[1645:7569] Display 0: 1600x1200x32@60 05:15:40 INFO - 2016-05-19 05:15:40.042 screenresolution[1646:7571] starting screenresolution argv=screenresolution list 05:15:40 INFO - Available Modes on Display 0 05:15:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:15:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:15:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:15:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:15:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:15:40 INFO - Intel Iris: 05:15:40 INFO - Chipset Model: Intel Iris 05:15:40 INFO - Type: GPU 05:15:40 INFO - Bus: Built-In 05:15:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:40 INFO - Vendor: Intel (0x8086) 05:15:40 INFO - Device ID: 0x0a2e 05:15:40 INFO - Revision ID: 0x0009 05:15:40 INFO - Displays: 05:15:40 INFO - Display: 05:15:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:15:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:40 INFO - Display Serial Number: 1600x1200 60 05:15:40 INFO - Main Display: Yes 05:15:40 INFO - Mirror: Off 05:15:40 INFO - Online: Yes 05:15:40 INFO - Rotation: Supported 05:15:40 INFO - Return code: 0 05:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:15:40 INFO - Python 2.7.3 05:15:40 INFO - Return code: 0 05:15:40 INFO - grabbing minidump binary from tooltool 05:15: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')]} 05:15:40 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 0x10085ad30>, '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 0x10067edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100851b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:15:40 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 05:15:40 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 05:15:40 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 05:15:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:15:40 INFO - Return code: 0 05:15:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:15:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:15:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:15:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:15:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:15:41 INFO - Return code: 0 05:15:41 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 05:15:41 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --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 05:15:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kbGRnFhsD7/Render', 05:15:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:41 INFO - 'HOME': '/Users/cltbld', 05:15:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:41 INFO - 'LOGNAME': 'cltbld', 05:15:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:15:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:41 INFO - 'MOZ_NO_REMOTE': '1', 05:15:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:15:41 INFO - 'NO_EM_RESTART': '1', 05:15:41 INFO - 'PAGER': '/bin/cat', 05:15:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:41 INFO - 'PWD': '/builds/slave/test', 05:15:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:15:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:41 INFO - 'SHELL': '/bin/bash', 05:15:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmBXOrkEir/Listeners', 05:15:41 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:15:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:41 INFO - 'USER': 'cltbld', 05:15:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:41 INFO - 'XPC_FLAGS': '0x0', 05:15:41 INFO - 'XPC_SERVICE_NAME': '0', 05:15:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:41 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 05:15:41 INFO - mozversion application_buildid: 20160519030232 05:15:41 INFO - mozversion application_changeset: c4449eab07d39e20ea315603f1b1863eeed7dcfe 05:15:41 INFO - mozversion application_display_name: Nightly 05:15:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:15:41 INFO - mozversion application_name: Firefox 05:15:41 INFO - mozversion application_remotingname: firefox 05:15:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:15:41 INFO - mozversion application_vendor: Mozilla 05:15:41 INFO - mozversion application_version: 49.0a1 05:15:41 INFO - mozversion platform_buildid: 20160519030232 05:15:41 INFO - mozversion platform_changeset: c4449eab07d39e20ea315603f1b1863eeed7dcfe 05:15:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:15:41 INFO - mozversion platform_version: 49.0a1 05:15:41 INFO - using testdate: 1463660141 05:15:41 INFO - actual date: 1463660141 05:15:41 INFO - starting webserver on 'localhost:49278' 05:15:41 INFO - SUITE-START | Running 2 tests 05:15:41 INFO - TEST-START | dromaeo_css 05:15:41 INFO - operating with platform_type : mac_ 05:15:41 INFO - Initialising browser for dromaeo_css test... 05:15:41 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpKf1QVV/profile http://localhost:49278/getInfo.html) 05:15:44 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 05:15:44 INFO - PROCESS | 1664 | colorDepth:24 05:15:44 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 05:15:44 INFO - PROCESS | 1664 | __metrics 05:15:44 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" 05:15:44 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:15:44 INFO - TEST-INFO | 1664: exit 0 05:15:44 INFO - Browser initialized. 05:15:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:15:44 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpKf1QVV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:56 INFO - PROCESS | 1667 | RSS: Main: 238927872 05:15:56 INFO - PROCESS | 1667 | 05:16:48 INFO - PROCESS | 1667 | 1463660208313 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] 05:18:58 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 05:18:59 INFO - PROCESS | 1667 | RSS: Main: 351956992 05:18:59 INFO - PROCESS | 1667 | 05:22:02 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 05:22:03 INFO - PROCESS | 1667 | RSS: Main: 369111040 05:22:03 INFO - PROCESS | 1667 | 05:25:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 05:25:06 INFO - PROCESS | 1667 | RSS: Main: 369766400 05:25:06 INFO - PROCESS | 1667 | 05:28:08 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 05:28:08 INFO - PROCESS | 1667 | RSS: Main: 364666880 05:28:08 INFO - PROCESS | 1667 | 05:31:12 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 05:31:12 INFO - PROCESS | 1667 | RSS: Main: 336056320 05:31:12 INFO - PROCESS | 1667 | 05:34:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 05:34:16 INFO - PROCESS | 1667 | RSS: Main: 421695488 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | __start_tp_report 05:34:16 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 05:34:16 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 05:34:16 INFO - PROCESS | 1667 | |i|pagename|runs| 05:34:16 INFO - PROCESS | 1667 | |0;dojo.html;3448.5344478495394;3460.1522626956394;3487.3897068013093;3496.0910163357526;3510.9122271943197;5727.856803579907;5730.828075157741;5748.683822389767;5790.884182316351;5812.970935145318;5798.362180160179;5847.79532716355;5926.9407305926925;5934.406559344064;5980.1926739890105;5167.9483205168035;5201.0378080055125;5263.447338029509;5285.180796976475;5367.946320536784;5320.042392369372;5372.435894231088;5390.434004429537;5390.703511306896;5419.376771671269;5187.688738675692;5203.349581302337;5242.8689282767855;5264.815731449381;5269.262303277545;5253.6322457428;5253.86865328366;5262.394824595182;5300.761465734051;5322.33470816148;5251.133562962106;5260.973695131538;5319.388270348921;5329.600279979017;5331.2269720890645;5463.180522921554;5556.499915007628;5642.943570564283;5741.1388291756575;5775;6268;6290.182276304051;6299.96050651642;6317.8104656859905;6322.4942004639515;5835.795747148872;6245.718942647592;6253.78111766086;6278.6860656966965;6284.685765711696;5763.9135412968835;5882.823515294591;5953.910691339634;5965.105234214816;6010.669413182273;6320.5575609706875;6361.777337793155;6377.426031657174;6382.5851319665;6387.265464471552;6248.4064013918605;6269.373062693785;6274.152989346471;6279.591826531261;6279.874402511924;6188.0717892315615;6208.689565521706;6278.6860656966965;6316.30520642729;6322.430981211713;6195.783147589813;6245.125682404467;6296.055591661196;6315.715792789336;6326.2724786649205;6258.311585725566;6296.961001434712;6301.558890877594;6307.779227727099;6313.43179113882;4928.359313289321;5002.774875130555;5041.269015992661;5062.164742817394;5110.718910459886;4479.372887795711;4511.142882852248;4585.151746926829;4611.216093264153;4649.4420669519195;5815.854603634943;5820;5822.912656310159;5831.79588714393;5838.591298609142;6233.836126351103;6287.49700023997;6288.371162883675;6291.811245662638;6298.275698294755;4839.782209800567;5635.070213414822;5667.574931880125;5720.856978575569;5797;5463.535599474009;5495.972520137454;5500.889982200333;5505.119180931112;5513.669179849222;6451.161349024596;6455.386738259952;6457.192850893638;6461.06314584392;6477;6212.378762123752;6223.782167624111;6276.372362763687;6290.685465726604;6305.991041433532;6437.742490300336;6443.581167224115;6460.709268082854;6461.773837915651;6473.449756770538;6231.3145553988115;6239.438450539473;6268.216472940882;6271.498280137669;6303.558750887577;5790.218320526675;6190.938090619036;6224.346443623354;6275.278342990615;6277.937220627735;6252.968735156477;6266.248050234001;6270.7491700331475;6274.184356033869;6301.747930082745;6080.361562035922;6233.937660623517;6242.65665388408;6251.906221406592;6266.060090986206;6028.608140470856;6069.757209711739;6095.451409373178;6111.755529778759;6124.601900876429;5185.637005409585;5253.23828044939;6191.19514463117;6208.596441231305;6240;5932.555058370725;5979.133025711334;6036.517078633611;6068.150458935665;6126.693665316806 05:34:16 INFO - PROCESS | 1667 | |1;ext.html;483.5527137397907;516.1612379882689;521.9582433405328;523.9371275446945;533.8078291814949;25777;41546.79226603867;42581.99999999996;42653;45106.54893451065;41777.791111044404;42199.94500137498;42336.57663423365;42409.36385954213;42549.361759573556;8538.78653033676;9379.71860844174;9590.904090959071;9597.424154550748;9607.279454040974;8562.61468233931;11454.48454819535;11680.474378652938;11694.239874408137;11911.940440297787;11673.1828771986;11751.47118379675;11766.529338826436;11767.882321176765;11769.999100152952;8586.012608550034;11512;11610.6516804496;11804.173707840455;11817.409129543532;11484.138689598314;11582.247153934968;11589.246698964625;11609.76780464386;11619.593314233962;40192.79903600492;40604.59395406038;40717;40776.59223407758;40874;3354.1446931032674;3439.5012723155;3776.0371105367954;3836.9424458633143;3852.942205866886;28600.284992875135;29574.408511829643;29979.400411991857;30088.398232035237;30488;2788.9023884163958;2798.3144129688153;2814.535601625729;2866.226118947893;2872.755815755663;517127;518881;520486;520906.395468024;521089.3945530286;50448.747756260986;59544.106838397456;59956.400435995085;60317;60394.39605603975;36094;36792;36806.44790328147;38205;38216.8089159558;17401;17587.384441544484;17589.384371546934;17596.648067038586;17638.382656607213;12111.576094836639;12315.93842030784;13175.143615665143;13314.600561982963;13408.932955335356;16325.59186020359;19622;19832.50418739514;19871.40385788429;19885;6766.560173588701;7848.254415830487;7939.761807145795;7966.920330796734;8025.99025365488;939.3189937295406;1052.552665117322;1107.396468924438;1121.5009320852143;1137.0676045642495;29092.563611545844;29910.70089299079;29932.850335748182;30297.93957211487;30572.54141187884;1135.4890299365318;1147.2886810177743;1165.516310731054;1195.2529668956902;1197.2457351868268;15204.315805788767;15251.627353538235;15409.075455472483;15497.922510387376;15503.845100918434;623397;710960;716807;719908;721664;46757.766211168724;58409;60084.69957650184;60316.698416508516;60846;1123.455124264731;1227.2881728597213;1359.7484465374134;1465.157534417714;1484.5768955847532;29602.703972959996;30479.847600761856;30610.693893061674;30766.692333076382;31112.377752444372;49327.753361232964;50132;50642.74678626583;50657.49342506675;50738.238926416874;535057.3247133739;535968;536064;537153;537162;21470.67793983123;23356.64965025492;26344;26657;27614.861925690242;45316;46712.53287467218;46782.45434848416;46889.53110468852;48863.267050994975;38509.807450962566;38810.02974925649;38935.22129557336;39513.67781155001;39541;37882.81058594689;37921;38531.42202866903;38766;39089.41365879571;34469;37808.43287350747;37861.05347366228;38340.61659383371;38534.42198367083;32963.505547417284;33107.834460827544;33788.66211337855;33850.66149338475;34259.31481370309;23501.75911370639;24657.876710616332;24794.132205373448;25129;25342.98628054857 05:34:16 INFO - PROCESS | 1667 | |2;jquery.html;378.79545045675354;394.50686641697877;398.18372336709746;400.31945692323023;421.28802324072313;14764.852351476482;20384.69422958655;21179.47051323714;21507.67738483924;21512.569748605038;15972.408317205085;18349.26602935881;20203.797962020377;20697.68953465699;20712.585748285026;5833.300003999515;6060.333363330053;6073.969630151843;6074.787382441616;6086.269647642289;5788.681622510781;6040.909386359208;6055.273367195942;6075.392460753934;6079.483243924272;5199.610029247821;5226.42509323976;5913.083472061816;5962.672053037081;5978.671173085479;5813.360530341659;5878.970605146968;5985.281766188042;5993.130996005577;6015.157877897098;5262.710550919698;5279.52484276417;5281.128613778722;5284.577233821324;5291.206319052135;25777.484450310887;26307.473850523074;27364.31589210266;27401.86299068492;27492.58761118585;2181.214762685432;2305.5388922215625;2413.0468464956243;2585.7802086822453;2907.4933852528497;13706.040577159603;14862;15374.923125384414;15510;15594.766078508832;1723.8534724548429;1791.704368779163;1796.4880009197316;1824.8905065695967;1840.300685739424;121249.15003399854;123019;124366.512669747;125512.37243813723;125551.3722431391;11041.889581104086;11288.548458061585;11496.54013839437;11541.596044138583;11641.88358116425;14613.561593152222;14689.926550367394;14698.412063517553;14707.264636768119;14711.19088450124;7306.232845551141;7731.961340193376;8809.295256379473;8878.86681699775;9005.324600654978;9039.909600904039;9042.683506077256;9048.321375896834;9107.590158442887;9170.66148132222;6833.958888294661;6960.199577048603;6998.610169994454;7009.859802803915;7100.360967512948;1595.5612206643266;1860.8790428622367;1894.895780732073;1903.5526651236949;1928.344362916587;809.5628360685282;894.190757364586;967.3373738988815;1021.3514418344405;1044.5090807320548;21318.680219796715;22173.002214900367;22215.333539993502;22278;22438.990245438996;989.2877128467492;998.6554638649654;1002.7192386131894;1051.5530899367739;1087.516055355375;11412.600558980395;11514.596989105341;11754.588589399329;11825.645230643095;11851.466683999068;578551;594816;626407;634566;636657;116890.831091688;120767.50789905268;127427;128587.35706321409;130631;990.5632144923425;1014.2899970021015;1031.376017509411;1032.850236715687;1035.425338936882;15448.459303924308;19294.03529823475;19370.709439358703;19380.806191938464;19762.50593735168;51969.22046169235;52332.430027099246;53389;55483.16775248455;55491;50219;66215;76774.23225767894;83506;88070;5547;5988.850278743066;6398.648074355769;7748.876412920208;8361.498310101413;7137.85724285501;9364.906350936404;12021.519139234682;12101.273923564262;12387.876121238673;10663.146948244277;10854.34873907568;10965.945170274097;11079.833802492807;11367.658970230907;9130.543472826464;9811.901880981099;10270.178385729274;10651.360918344924;11093.611723589636;7330.816729581803;10911.618093366695;11470.025047871024;11482.82775758381;11595.478203480694;6821.317868213139;8227.54748488815;8230.259276665129;8361.205685459872;8652.524111173945;8108.189181081844;10079.89920100819;10467.214958877963;10598.735031624272;11098.167637427387 05:34:16 INFO - PROCESS | 1667 | |3;mootools.html;1450.586582823894;1611.1944027986003;1638.4674980631294;1926.4702206893103;2046.7441569803784;8133.9186608133905;8621.267192288647;8631.35264855136;8633.870491942618;8952.283817294618;8986.2810975122;8989.325800564951;9187.35688501805;9189.632414703421;9204.493752843591;2131.509752756867;2472.913448029319;2484.1429706751187;2524.8737563121863;2525.0657256815;2481.689788776403;2496.8127390445698;2523.356544081266;2524.5582023145876;2525.5075260324243;2491.115653942851;2507.147569826259;2530.772230499255;2530.9620355694587;2535.3915060385466;2464.3099932019063;2485.378655336166;2499.212747984388;2502.0867383404925;2506.5613517634342;2463.0763463701114;2490.6264060391086;2499.600063989771;2500.037485568051;2506.1604362538574;9119.544022798833;9189.908100918972;9208.3093767967;9232.723018309462;9249.768755781091;5437.592180586472;5466.34403871534;5646.322441307069;5648.15277708347;5686.118651608966;3648;3664.303782281358;3690.128287681395;3735.6077611850906;3791.696664266852;941.9576119074658;1047.5600247895902;1055.5144633468615;1068.551208492435;1080.4165750494724;11813.173077884552;12203.511859525697;12361.32012739299;12445.751084978341;12449.439775210047;4766.737829419415;4824.059308434892;4886.242632391985;4895.681780684244;4906.730129842822;9561.426314421156;9674.661386851567;9682.515874206261;9688.75778105553;9698.903010969942;5551.084071128219;5551.1673249012165;5555.50000499957;5557.833265001976;5558.499735023867;4547.681662283675;4555.612772914273;4593.265077587636;5106.208537676666;5115.71863547501;3522.5772907251294;3853.96146038536;3955;3995.300822356076;4028.2144981728866;1062.9202809789297;1222.3949145173196;1238.6965193527644;1238.869918658528;1253.254313683357;881.1540920716056;911.3893691226893;946.2335508238349;979.6571200079915;1022.1414012229762;6014.278286605635;6046.669732658808;6063.393660633901;6249.875002499924;6270.372962703693;474.50414317038775;486.2317538289494;489.4322585800454;491.92621106834264;494.2882249560621;4045.8786236412957;4112.712110152261;4156.521999969982;4207.6423504002505;4216.494020717472;10970.835437468444;11352.772944541228;11405.714857128638;11417.257878237888;11447.198696091195;10483.47582620866;11237.719057023476;11345.829812552818;11391.715207119889;11399.430028498542;8312.085670576233;8610.95694521536;8676.91323086761;8689.522076285864;8693.826123477622;6042.214512113397;6156.5998210116195;6296.622202667854;6363.790879733021;6378.394052564999;9613.086756758097;10126;10446.425446600206;10471.528781204712;10676.199285053497;10401.68794936153;10434.739131521772;10549.261551691308;10666.89333106659;10754.354738715389;7877.487963282369;7931.048274207132;8019.1579884111325;8020.197980202167;8027.59862006909;8233.053198882206;8578.742637720878;8625.827483450421;8673.392862499564;8680.131986801522;7779.299863012356;7833.942417773756;7845.293923546908;7880.56656883854;7992.880106798519;7722.459427839997;7751.224877512203;7756.767296981099;7867.645955931882;7894.21057894206;7164.247753985756;7323.377512911462;7356.190819009797;7357.1907090218965;7368.1526624438875;4784.258439941815;4822.035592881508;5062.291279220838;5078.4667609900425;5164.561012313995;5088.618353623419;5108.182690769459;5123.923141152811;5192.3769147702515;5446.782128714807 05:34:16 INFO - PROCESS | 1667 | |4;prototype.html;314.9763767717421;320.6280714371328;329.8861892647037;331.3985117012622;333.65466741922086;4792.305115758221;5395.892082158354;5399.406065332815;5407.945920540803;5468.179773034042;4713.128071306811;5035.269885866547;5369.62412631116;5380.085385484467;5440.6735595864275;3598.8740394086212;3687.446882967563;3908.3160446921815;4034.1931613677234;4135.648469880064;4032.9596704033024;4093.5087789465306;4104.42538044674;4135.524414692304;4152.916941661165;4062.2687916175078;4068.2677118118727;4082.203970225807;4087.6934229932726;4091.936096614872;3243.4323993301077;3295.6374798772117;3771.528558930134;3823.2735780201674;3941.8371580383714;3015.2914065194664;3398.315330888595;3553.2766608195006;3572.8749493767855;3617.330793803155;5115.539601435851;5149.330587023663;5149.897002059975;5164.690118592859;5203.297554830087;4270.807813648394;4605.08888811143;4651.5813576778255;4689.280217473857;4693.718376897363;4854.878628034292;4873.343063358475;4976.810203510444;5582.218489411445;5594.524465420444;3203.9679603203904;3382.4080785862375;3387.9322413551836;3776.2258736958856;3858.9807050964846;5360.65155764874;5378.95599672126;5546.112621980504;5812.651240925558;5910.468057874812;3292.3579901918897;3874.8450061997205;3877.3214687429586;3885.7668539887695;3894.5910679378258;3483.7909725416557;3645.2345007548183;3933.645971862546;3951.308521008812;3961.7622942623534;3818.7899665518107;4158.856314513533;4472.508024117345;4548.431446069241;4627.3521706961055;4433.246348120827;5253.817890974577;5380.219868119101;5654.604177707603;5684.77260909559;1105.309181761399;1332.9003572052902;1339.912905661129;1348.8988325875598;1349.7098123903409;697.9581225126509;816.9910160951221;834.7996480844554;835.6197929941862;839.1482645115213;4663.720176789404;4966.602671786249;5107.297209523066;5181.637285390062;5184.844454666366;5634;5670.886582268332;5846.970765146232;5874.823755287348;5899.233099697012;4415.977920110379;4585.587297143206;5182.922256166161;5318.441563635763;5347.25138480613;2827.3214428537412;3008.4127650220794;3015.703247603508;3033.605631267921;3045.512717965115;4659.2312268475325;5679.778847547797;5936.673482958478;6027.276726792726;6043.36544662804;5207.84376468699;5585.3297604287745;5890.852728681732;5901.5868889177345;5923.614965027259;5085.796568137381;5280.339957505312;5935.347111817783;6007.309159446632;6014.458698717136;4468.664850136318;5083.415407228216;5337.879045400509;5379.669366160122;5691.288588926384;4811.542903424169;4906.571247232642;5690.548910028082;5695.430456954271;5720.055181238233;5599.468050535193;5842.766289348378;5931.436513531207;6033.58210820448;6056.818295451144;3427.9485807712404;3582.4922554211635;3858.401947698111;3892.4161375793856;4007.358822588372;4893.632977526629;5050;5164.96700659862;5729.885402292014;5807.94192058074;4777.283407488765;4902.803887844446;5381.596380271417;5567.77728890856;5571.721413929368;4854.572713643179;5047.974760126176;5446.291982042309;5513.8345849624575;5662;5001.57486613642;5225.242339860775;5517.310336207974;5529.640573362719;5543.805966791143;4939.5554400104165;4946.431160416598;5129.153689641167;5396;5703.62926409782;4700.388949436551;5102.617303702312;5462.3445186577865;5549.556035517229;5550.611457197957 05:34:16 INFO - PROCESS | 1667 | |5;yui.html;673.5823789250666;691.1567887177641;704.8264639375437;737.8118579762163;746.3130942220282;15063.246837658127;15311.234438278083;15508.922455387721;15535.922320388383;16544.255508502116;16181.595460113504;16578.50264492067;16580.66838663226;16588.17059147044;16610.66778664426;719.3310221494008;926.7451450851003;927.679950417105;936.0171819589438;936.6019441737268;740.3928778401713;903.0743487924864;934.7756538430796;935.0742764662969;935.4060171790928;987.4618333008527;987.5457289646771;991.182274623435;992.6575331510618;996.9900300996969;773.1765669561898;947.7630592351912;987.0228473810947;988.7429268390234;989.0900371658056;729.3508777188307;731.9670614822293;732.2677322677323;732.8570928668912;733.5158795195199;15576.766348504781;15647.374105035786;15667.451639192688;15668.21658917061;15688.607784805472;12242.959348455392;12297.69255768604;12394.256344619353;12485.375731213402;12585.622431326983;10123.949380253125;10618.628348007782;10921.672349829518;11030.669079927615;11233.438328083645;3353.9496907546404;3973.642372186488;3978.76127432355;4036.777977211252;4141.482314710661;87940;89059.10940890573;89677;90624.54687726585;90919.09080909172;9264.305177111744;9453.621855125855;9544.093311135432;9721.46531940736;9860.26048046385;21563.892180539;21571.892140539196;21579.67630485544;21590.136394544043;21617.243396481048;7368.373688236453;7405.444591655647;7456.4780465367985;7472.364848987788;7550.131734850453;4120.711550191518;4129.339305711132;4218.987443013654;4254.638355739797;4322.416473776063;3488.5639295088113;3591.9102022449647;3591.9102022449647;3596.9640303597153;3597.964020359815;638.3201889987149;651.7002178997619;658.7002913674266;659.8086554899025;660.428729149292;518.8832512684615;522.0863488894435;568.709957921463;571.3800526428871;571.4171545024052;7735.419843511759;7873.685052597832;7967.442279040411;8050.355971522265;8207.897735718108;498.551303826559;542.7448949614359;563.0484481226714;573.6873403994833;576.3775122867377;3684.393120880242;3732.5147730794824;3757.4363845423404;3848.5958974307855;3976.1451287973223;93798;98751.01248987418;100377.49811250897;100945.99054009512;101869;65180;84843.15156848475;85175;85672;87116.5644171775;31038.068857934297;31131.68868311288;61626.69186654038;76281.61859190669;76595.61702191565;6314.715837787402;7419.146798118286;7436.330730234305;7465.3654439371085;7480.513766605153;46136;46480.7675961618;47152.05695885995;47769;48350.75824620854;60490;63797.68101159464;64608.35391646023;64655.353446464935;65331;9674.03259674027;9750.512474376112;9940.30417870771;9962.950185249027;10039.548220330249;10462.267641265038;10959.12327013821;10997.61508347236;11324.037456816262;11620.535178592761;9949.054839790208;10394.116500097274;10423.166146708396;10492.003259690317;10560.788784224425;9954.402735835873;10426.426546540013;10458.372497650165;10459.84310235332;10472.581096756045;9612.567434465325;9920.305578609428;9993.500324983575;10115.291929564859;10127.89872101299;7644.808879777827;8491.787705307417;8819;8845.646174152962;9012.324075694218;8123.47197432165;8606.311495080503;8696.906396100767;8772.956135219283;8833.955830220808 05:34:16 INFO - PROCESS | 1667 | __end_tp_report 05:34:16 INFO - PROCESS | 1667 | __start_cc_report 05:34:16 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1156 05:34:16 INFO - PROCESS | 1667 | __end_cc_report 05:34:16 INFO - PROCESS | 1667 | __startTimestamp1463661256139__endTimestamp 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | ------- Summary: start ------- 05:34:16 INFO - PROCESS | 1667 | Number of tests: 6 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5812.98 Median:6036.52 stddev:636.97 (10.6%) stddev-sans-first:611.32 05:34:16 INFO - PROCESS | 1667 | Values: 3448.5 3460.2 3487.4 3496.1 3510.9 5727.9 5730.8 5748.7 5790.9 5813.0 5798.4 5847.8 5926.9 5934.4 5980.2 5167.9 5201.0 5263.4 5285.2 5367.9 5320.0 5372.4 5390.4 5390.7 5419.4 5187.7 5203.3 5242.9 5264.8 5269.3 5253.6 5253.9 5262.4 5300.8 5322.3 5251.1 5261.0 5319.4 5329.6 5331.2 5463.2 5556.5 5642.9 5741.1 5775.0 6268.0 6290.2 6300.0 6317.8 6322.5 5835.8 6245.7 6253.8 6278.7 6284.7 5763.9 5882.8 5953.9 5965.1 6010.7 6320.6 6361.8 6377.4 6382.6 6387.3 6248.4 6269.4 6274.2 6279.6 6279.9 6188.1 6208.7 6278.7 6316.3 6322.4 6195.8 6245.1 6296.1 6315.7 6326.3 6258.3 6297.0 6301.6 6307.8 6313.4 4928.4 5002.8 5041.3 5062.2 5110.7 4479.4 4511.1 4585.2 4611.2 4649.4 5815.9 5820.0 5822.9 5831.8 5838.6 6233.8 6287.5 6288.4 6291.8 6298.3 4839.8 5635.1 5667.6 5720.9 5797.0 5463.5 5496.0 5500.9 5505.1 5513.7 6451.2 6455.4 6457.2 6461.1 6477.0 6212.4 6223.8 6276.4 6290.7 6306.0 6437.7 6443.6 6460.7 6461.8 6473.4 6231.3 6239.4 6268.2 6271.5 6303.6 5790.2 6190.9 6224.3 6275.3 6277.9 6253.0 6266.2 6270.7 6274.2 6301.7 6080.4 6233.9 6242.7 6251.9 6266.1 6028.6 6069.8 6095.5 6111.8 6124.6 5185.6 5253.2 6191.2 6208.6 6240.0 5932.6 5979.1 6036.5 6068.2 6126.7 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:70924.10 Median:35281.50 stddev:158124.95 (448.2%) stddev-sans-first:158480.09 05:34:16 INFO - PROCESS | 1667 | Values: 483.6 516.2 522.0 523.9 533.8 25777.0 41546.8 42582.0 42653.0 45106.5 41777.8 42199.9 42336.6 42409.4 42549.4 8538.8 9379.7 9590.9 9597.4 9607.3 8562.6 11454.5 11680.5 11694.2 11911.9 11673.2 11751.5 11766.5 11767.9 11770.0 8586.0 11512.0 11610.7 11804.2 11817.4 11484.1 11582.2 11589.2 11609.8 11619.6 40192.8 40604.6 40717.0 40776.6 40874.0 3354.1 3439.5 3776.0 3836.9 3852.9 28600.3 29574.4 29979.4 30088.4 30488.0 2788.9 2798.3 2814.5 2866.2 2872.8 517127.0 518881.0 520486.0 520906.4 521089.4 50448.7 59544.1 59956.4 60317.0 60394.4 36094.0 36792.0 36806.4 38205.0 38216.8 17401.0 17587.4 17589.4 17596.6 17638.4 12111.6 12315.9 13175.1 13314.6 13408.9 16325.6 19622.0 19832.5 19871.4 19885.0 6766.6 7848.3 7939.8 7966.9 8026.0 939.3 1052.6 1107.4 1121.5 1137.1 29092.6 29910.7 29932.9 30297.9 30572.5 1135.5 1147.3 1165.5 1195.3 1197.2 15204.3 15251.6 15409.1 15497.9 15503.8 623397.0 710960.0 716807.0 719908.0 721664.0 46757.8 58409.0 60084.7 60316.7 60846.0 1123.5 1227.3 1359.7 1465.2 1484.6 29602.7 30479.8 30610.7 30766.7 31112.4 49327.8 50132.0 50642.7 50657.5 50738.2 535057.3 535968.0 536064.0 537153.0 537162.0 21470.7 23356.6 26344.0 26657.0 27614.9 45316.0 46712.5 46782.5 46889.5 48863.3 38509.8 38810.0 38935.2 39513.7 39541.0 37882.8 37921.0 38531.4 38766.0 39089.4 34469.0 37808.4 37861.1 38340.6 38534.4 32963.5 33107.8 33788.7 33850.7 34259.3 23501.8 24657.9 24794.1 25129.0 25343.0 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:35533.74 Median:14095.27 stddev:102456.22 (726.9%) stddev-sans-first:102709.63 05:34:16 INFO - PROCESS | 1667 | Values: 378.8 394.5 398.2 400.3 421.3 14764.9 20384.7 21179.5 21507.7 21512.6 15972.4 18349.3 20203.8 20697.7 20712.6 5833.3 6060.3 6074.0 6074.8 6086.3 5788.7 6040.9 6055.3 6075.4 6079.5 5199.6 5226.4 5913.1 5962.7 5978.7 5813.4 5879.0 5985.3 5993.1 6015.2 5262.7 5279.5 5281.1 5284.6 5291.2 25777.5 26307.5 27364.3 27401.9 27492.6 2181.2 2305.5 2413.0 2585.8 2907.5 13706.0 14862.0 15374.9 15510.0 15594.8 1723.9 1791.7 1796.5 1824.9 1840.3 121249.2 123019.0 124366.5 125512.4 125551.4 11041.9 11288.5 11496.5 11541.6 11641.9 14613.6 14689.9 14698.4 14707.3 14711.2 7306.2 7732.0 8809.3 8878.9 9005.3 9039.9 9042.7 9048.3 9107.6 9170.7 6834.0 6960.2 6998.6 7009.9 7100.4 1595.6 1860.9 1894.9 1903.6 1928.3 809.6 894.2 967.3 1021.4 1044.5 21318.7 22173.0 22215.3 22278.0 22439.0 989.3 998.7 1002.7 1051.6 1087.5 11412.6 11514.6 11754.6 11825.6 11851.5 578551.0 594816.0 626407.0 634566.0 636657.0 116890.8 120767.5 127427.0 128587.4 130631.0 990.6 1014.3 1031.4 1032.9 1035.4 15448.5 19294.0 19370.7 19380.8 19762.5 51969.2 52332.4 53389.0 55483.2 55491.0 50219.0 66215.0 76774.2 83506.0 88070.0 5547.0 5988.9 6398.6 7748.9 8361.5 7137.9 9364.9 12021.5 12101.3 12387.9 10663.1 10854.3 10965.9 11079.8 11367.7 9130.5 9811.9 10270.2 10651.4 11093.6 7330.8 10911.6 11470.0 11482.8 11595.5 6821.3 8227.5 8230.3 8361.2 8652.5 8108.2 10079.9 10467.2 10598.7 11098.2 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5920.79 Median:4901.21 stddev:3346.72 (68.3%) stddev-sans-first:3339.25 05:34:16 INFO - PROCESS | 1667 | Values: 1450.6 1611.2 1638.5 1926.5 2046.7 8133.9 8621.3 8631.4 8633.9 8952.3 8986.3 8989.3 9187.4 9189.6 9204.5 2131.5 2472.9 2484.1 2524.9 2525.1 2481.7 2496.8 2523.4 2524.6 2525.5 2491.1 2507.1 2530.8 2531.0 2535.4 2464.3 2485.4 2499.2 2502.1 2506.6 2463.1 2490.6 2499.6 2500.0 2506.2 9119.5 9189.9 9208.3 9232.7 9249.8 5437.6 5466.3 5646.3 5648.2 5686.1 3648.0 3664.3 3690.1 3735.6 3791.7 942.0 1047.6 1055.5 1068.6 1080.4 11813.2 12203.5 12361.3 12445.8 12449.4 4766.7 4824.1 4886.2 4895.7 4906.7 9561.4 9674.7 9682.5 9688.8 9698.9 5551.1 5551.2 5555.5 5557.8 5558.5 4547.7 4555.6 4593.3 5106.2 5115.7 3522.6 3854.0 3955.0 3995.3 4028.2 1062.9 1222.4 1238.7 1238.9 1253.3 881.2 911.4 946.2 979.7 1022.1 6014.3 6046.7 6063.4 6249.9 6270.4 474.5 486.2 489.4 491.9 494.3 4045.9 4112.7 4156.5 4207.6 4216.5 10970.8 11352.8 11405.7 11417.3 11447.2 10483.5 11237.7 11345.8 11391.7 11399.4 8312.1 8611.0 8676.9 8689.5 8693.8 6042.2 6156.6 6296.6 6363.8 6378.4 9613.1 10126.0 10446.4 10471.5 10676.2 10401.7 10434.7 10549.3 10666.9 10754.4 7877.5 7931.0 8019.2 8020.2 8027.6 8233.1 8578.7 8625.8 8673.4 8680.1 7779.3 7833.9 7845.3 7880.6 7992.9 7722.5 7751.2 7756.8 7867.6 7894.2 7164.2 7323.4 7356.2 7357.2 7368.2 4784.3 4822.0 5062.3 5078.5 5164.6 5088.6 5108.2 5123.9 5192.4 5446.8 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4470.82 Median:4989.19 stddev:1384.01 (27.7%) stddev-sans-first:1352.28 05:34:16 INFO - PROCESS | 1667 | Values: 315.0 320.6 329.9 331.4 333.7 4792.3 5395.9 5399.4 5407.9 5468.2 4713.1 5035.3 5369.6 5380.1 5440.7 3598.9 3687.4 3908.3 4034.2 4135.6 4033.0 4093.5 4104.4 4135.5 4152.9 4062.3 4068.3 4082.2 4087.7 4091.9 3243.4 3295.6 3771.5 3823.3 3941.8 3015.3 3398.3 3553.3 3572.9 3617.3 5115.5 5149.3 5149.9 5164.7 5203.3 4270.8 4605.1 4651.6 4689.3 4693.7 4854.9 4873.3 4976.8 5582.2 5594.5 3204.0 3382.4 3387.9 3776.2 3859.0 5360.7 5379.0 5546.1 5812.7 5910.5 3292.4 3874.8 3877.3 3885.8 3894.6 3483.8 3645.2 3933.6 3951.3 3961.8 3818.8 4158.9 4472.5 4548.4 4627.4 4433.2 5253.8 5380.2 5654.6 5684.8 1105.3 1332.9 1339.9 1348.9 1349.7 698.0 817.0 834.8 835.6 839.1 4663.7 4966.6 5107.3 5181.6 5184.8 5634.0 5670.9 5847.0 5874.8 5899.2 4416.0 4585.6 5182.9 5318.4 5347.3 2827.3 3008.4 3015.7 3033.6 3045.5 4659.2 5679.8 5936.7 6027.3 6043.4 5207.8 5585.3 5890.9 5901.6 5923.6 5085.8 5280.3 5935.3 6007.3 6014.5 4468.7 5083.4 5337.9 5379.7 5691.3 4811.5 4906.6 5690.5 5695.4 5720.1 5599.5 5842.8 5931.4 6033.6 6056.8 3427.9 3582.5 3858.4 3892.4 4007.4 4893.6 5050.0 5165.0 5729.9 5807.9 4777.3 4902.8 5381.6 5567.8 5571.7 4854.6 5048.0 5446.3 5513.8 5662.0 5001.6 5225.2 5517.3 5529.6 5543.8 4939.6 4946.4 5129.2 5396.0 5703.6 4700.4 5102.6 5462.3 5549.6 5550.6 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18127.28 Median:32623.34 stddev:26457.46 (81.1%) stddev-sans-first:26499.22 05:34:16 INFO - PROCESS | 1667 | Values: 673.6 691.2 704.8 737.8 746.3 15063.2 15311.2 15508.9 15535.9 16544.3 16181.6 16578.5 16580.7 16588.2 16610.7 719.3 926.7 927.7 936.0 936.6 740.4 903.1 934.8 935.1 935.4 987.5 987.5 991.2 992.7 997.0 773.2 947.8 987.0 988.7 989.1 729.4 732.0 732.3 732.9 733.5 15576.8 15647.4 15667.5 15668.2 15688.6 12243.0 12297.7 12394.3 12485.4 12585.6 10123.9 10618.6 10921.7 11030.7 11233.4 3353.9 3973.6 3978.8 4036.8 4141.5 87940.0 89059.1 89677.0 90624.5 90919.1 9264.3 9453.6 9544.1 9721.5 9860.3 21563.9 21571.9 21579.7 21590.1 21617.2 7368.4 7405.4 7456.5 7472.4 7550.1 4120.7 4129.3 4219.0 4254.6 4322.4 3488.6 3591.9 3591.9 3597.0 3598.0 638.3 651.7 658.7 659.8 660.4 518.9 522.1 568.7 571.4 571.4 7735.4 7873.7 7967.4 8050.4 8207.9 498.6 542.7 563.0 573.7 576.4 3684.4 3732.5 3757.4 3848.6 3976.1 93798.0 98751.0 100377.5 100946.0 101869.0 65180.0 84843.2 85175.0 85672.0 87116.6 31038.1 31131.7 61626.7 76281.6 76595.6 6314.7 7419.1 7436.3 7465.4 7480.5 46136.0 46480.8 47152.1 47769.0 48350.8 60490.0 63797.7 64608.4 64655.4 65331.0 9674.0 9750.5 9940.3 9963.0 10039.5 10462.3 10959.1 10997.6 11324.0 11620.5 9949.1 10394.1 10423.2 10492.0 10560.8 9954.4 10426.4 10458.4 10459.8 10472.6 9612.6 9920.3 9993.5 10115.3 10127.9 7644.8 8491.8 8819.0 8845.6 9012.3 8123.5 8606.3 8696.9 8773.0 8834.0 05:34:16 INFO - PROCESS | 1667 | -------- Summary: end -------- 05:34:16 INFO - PROCESS | 1667 | 05:34:16 INFO - TEST-INFO | 1667: exit 0 05:34:16 INFO - TEST-OK | dromaeo_css | took 1115006ms 05:34:16 INFO - TEST-START | kraken 05:34:16 INFO - operating with platform_type : mac_ 05:34:16 INFO - Initialising browser for kraken test... 05:34:16 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp0kJRAf/profile http://localhost:49278/getInfo.html) 05:34:18 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 05:34:18 INFO - PROCESS | 1678 | colorDepth:24 05:34:18 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 05:34:18 INFO - PROCESS | 1678 | __metrics 05:34:19 INFO - PROCESS | 1678 | 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" 05:34:19 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:34:19 INFO - TEST-INFO | 1678: exit 0 05:34:19 INFO - Browser initialized. 05:34:19 INFO - Running cycle 1/1 for kraken test... 05:34:19 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp0kJRAf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:34:31 INFO - PROCESS | 1681 | RSS: Main: 239050752 05:34:31 INFO - PROCESS | 1681 | 05:34:34 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 05:34:35 INFO - PROCESS | 1681 | RSS: Main: 287612928 05:34:35 INFO - PROCESS | 1681 | 05:34:38 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 05:34:38 INFO - PROCESS | 1681 | RSS: Main: 296132608 05:34:38 INFO - PROCESS | 1681 | 05:34:42 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 05:34:43 INFO - PROCESS | 1681 | RSS: Main: 384860160 05:34:43 INFO - PROCESS | 1681 | 05:34:45 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 05:34:45 INFO - PROCESS | 1681 | RSS: Main: 284065792 05:34:45 INFO - PROCESS | 1681 | 05:34:47 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:48 INFO - PROCESS | 1681 | RSS: Main: 289112064 05:34:48 INFO - PROCESS | 1681 | 05:34:51 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:52 INFO - PROCESS | 1681 | RSS: Main: 325632000 05:34:52 INFO - PROCESS | 1681 | 05:34:55 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:56 INFO - PROCESS | 1681 | RSS: Main: 328142848 05:34:56 INFO - PROCESS | 1681 | 05:34:58 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 05:34:59 INFO - PROCESS | 1681 | RSS: Main: 328536064 05:34:59 INFO - PROCESS | 1681 | 05:35:01 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:35:01 INFO - PROCESS | 1681 | RSS: Main: 315830272 05:35:01 INFO - PROCESS | 1681 | 05:35:03 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:35:04 INFO - PROCESS | 1681 | RSS: Main: 323575808 05:35:04 INFO - PROCESS | 1681 | 05:35:06 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:35:07 INFO - PROCESS | 1681 | RSS: Main: 325795840 05:35:07 INFO - PROCESS | 1681 | 05:35:10 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:35:11 INFO - PROCESS | 1681 | RSS: Main: 330670080 05:35:11 INFO - PROCESS | 1681 | 05:35:14 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:35:14 INFO - PROCESS | 1681 | RSS: Main: 328609792 05:35:14 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 05:35:16 INFO - PROCESS | 1681 | RSS: Main: 374730752 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | __start_tp_report 05:35:16 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 05:35:16 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 05:35:16 INFO - PROCESS | 1681 | |i|pagename|runs| 05:35:16 INFO - PROCESS | 1681 | |0;ai-astar;112;105;110;111;110;113;112;111;112;109 05:35:16 INFO - PROCESS | 1681 | |1;audio-beat-detection;153;115;139;191;117;143;159;105;190;140 05:35:16 INFO - PROCESS | 1681 | |2;audio-dft;183;187;183;186;184;187;182;182;183;185 05:35:16 INFO - PROCESS | 1681 | |3;audio-fft;79;73;56;125;57;61;56;60;110;56 05:35:16 INFO - PROCESS | 1681 | |4;audio-oscillator;93;100;132;81;80;76;102;80;79;102 05:35:16 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;87;84;87;84;86;83;88;84;87;83 05:35:16 INFO - PROCESS | 1681 | |6;imaging-darkroom;93;93;94;94;144;150;92;92;92;92 05:35:16 INFO - PROCESS | 1681 | |7;imaging-desaturate;86;85;86;86;85;86;86;86;84;86 05:35:16 INFO - PROCESS | 1681 | |8;json-parse-financial;53;55;57;55;54;55;56;116;54;54 05:35:16 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;62;70;75;78;71;70;67;66;66;67 05:35:16 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;93;103;81;84;80;100;86;84;84;85 05:35:16 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;123;140;147;142;126;134;136;145;236;123 05:35:16 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;160;212;219;161;163;160;163;159;165;166 05:35:16 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;66;66;65;66;66;65;67;78;85;82 05:35:16 INFO - PROCESS | 1681 | __end_tp_report 05:35:16 INFO - PROCESS | 1681 | __start_cc_report 05:35:16 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,2302 05:35:16 INFO - PROCESS | 1681 | __end_cc_report 05:35:16 INFO - PROCESS | 1681 | __startTimestamp1463661316781__endTimestamp 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | ------- Summary: start ------- 05:35:16 INFO - PROCESS | 1681 | Number of tests: 14 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:2.27 (2.0%) stddev-sans-first:2.35 05:35:16 INFO - PROCESS | 1681 | Values: 112.0 105.0 110.0 111.0 110.0 113.0 112.0 111.0 112.0 109.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:148.00 stddev:29.34 (19.8%) stddev-sans-first:30.99 05:35:16 INFO - PROCESS | 1681 | Values: 153.0 115.0 139.0 191.0 117.0 143.0 159.0 105.0 190.0 140.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:1.93 (1.0%) stddev-sans-first:2.00 05:35:16 INFO - PROCESS | 1681 | Values: 183.0 187.0 183.0 186.0 184.0 187.0 182.0 182.0 183.0 185.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:73.30 Median:58.50 stddev:24.82 (42.4%) stddev-sans-first:26.24 05:35:16 INFO - PROCESS | 1681 | Values: 79.0 73.0 56.0 125.0 57.0 61.0 56.0 60.0 110.0 56.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:79.50 stddev:17.31 (21.8%) stddev-sans-first:18.36 05:35:16 INFO - PROCESS | 1681 | Values: 93.0 100.0 132.0 81.0 80.0 76.0 102.0 80.0 79.0 102.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.50 stddev:1.89 (2.2%) stddev-sans-first:1.90 05:35:16 INFO - PROCESS | 1681 | Values: 87.0 84.0 87.0 84.0 86.0 83.0 88.0 84.0 87.0 83.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:92.50 stddev:22.93 (24.8%) stddev-sans-first:24.00 05:35:16 INFO - PROCESS | 1681 | Values: 93.0 93.0 94.0 94.0 144.0 150.0 92.0 92.0 92.0 92.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 05:35:16 INFO - PROCESS | 1681 | Values: 86.0 85.0 86.0 86.0 85.0 86.0 86.0 86.0 84.0 86.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:55.00 stddev:19.39 (35.3%) stddev-sans-first:20.36 05:35:16 INFO - PROCESS | 1681 | Values: 53.0 55.0 57.0 55.0 54.0 55.0 56.0 116.0 54.0 54.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:70.00 stddev:4.69 (6.7%) stddev-sans-first:4.18 05:35:16 INFO - PROCESS | 1681 | Values: 62.0 70.0 75.0 78.0 71.0 70.0 67.0 66.0 66.0 67.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:84.00 stddev:7.94 (9.5%) stddev-sans-first:8.22 05:35:16 INFO - PROCESS | 1681 | Values: 93.0 103.0 81.0 84.0 80.0 100.0 86.0 84.0 84.0 85.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:141.00 stddev:33.08 (23.5%) stddev-sans-first:34.10 05:35:16 INFO - PROCESS | 1681 | Values: 123.0 140.0 147.0 142.0 126.0 134.0 136.0 145.0 236.0 123.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:164.00 stddev:22.68 (13.8%) stddev-sans-first:23.57 05:35:16 INFO - PROCESS | 1681 | Values: 160.0 212.0 219.0 161.0 163.0 160.0 163.0 159.0 165.0 166.0 05:35:16 INFO - PROCESS | 1681 | 05:35:16 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:66.50 stddev:7.83 (11.8%) stddev-sans-first:8.13 05:35:16 INFO - PROCESS | 1681 | Values: 66.0 66.0 65.0 66.0 66.0 65.0 67.0 78.0 85.0 82.0 05:35:16 INFO - PROCESS | 1681 | -------- Summary: end -------- 05:35:16 INFO - PROCESS | 1681 | 05:35:17 INFO - TEST-INFO | 1681: exit 0 05:35:17 INFO - TEST-OK | kraken | took 60924ms 05:35:17 INFO - SUITE-END | took 1175s 05:35:17 INFO - Completed test suite (00:19:36) 05:35:17 INFO - javascript benchmark 05:35:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3448.5344478495394, 3460.1522626956394, 3487.3897068013093, 3496.0910163357526, 3510.9122271943197, 5727.856803579907, 5730.828075157741, 5748.683822389767, 5790.884182316351, 5812.970935145318, 5798.362180160179, 5847.79532716355, 5926.9407305926925, 5934.406559344064, 5980.1926739890105, 5167.9483205168035, 5201.0378080055125, 5263.447338029509, 5285.180796976475, 5367.946320536784, 5320.042392369372, 5372.435894231088, 5390.434004429537, 5390.703511306896, 5419.376771671269, 5187.688738675692, 5203.349581302337, 5242.8689282767855, 5264.815731449381, 5269.262303277545, 5253.6322457428, 5253.86865328366, 5262.394824595182, 5300.761465734051, 5322.33470816148, 5251.133562962106, 5260.973695131538, 5319.388270348921, 5329.600279979017, 5331.2269720890645, 5463.180522921554, 5556.499915007628, 5642.943570564283, 5741.1388291756575, 5775.0, 6268.0, 6290.182276304051, 6299.96050651642, 6317.8104656859905, 6322.4942004639515, 5835.795747148872, 6245.718942647592, 6253.78111766086, 6278.6860656966965, 6284.685765711696, 5763.9135412968835, 5882.823515294591, 5953.910691339634, 5965.105234214816, 6010.669413182273, 6320.5575609706875, 6361.777337793155, 6377.426031657174, 6382.5851319665, 6387.265464471552, 6248.4064013918605, 6269.373062693785, 6274.152989346471, 6279.591826531261, 6279.874402511924, 6188.0717892315615, 6208.689565521706, 6278.6860656966965, 6316.30520642729, 6322.430981211713, 6195.783147589813, 6245.125682404467, 6296.055591661196, 6315.715792789336, 6326.2724786649205, 6258.311585725566, 6296.961001434712, 6301.558890877594, 6307.779227727099, 6313.43179113882, 4928.359313289321, 5002.774875130555, 5041.269015992661, 5062.164742817394, 5110.718910459886, 4479.372887795711, 4511.142882852248, 4585.151746926829, 4611.216093264153, 4649.4420669519195, 5815.854603634943, 5820.0, 5822.912656310159, 5831.79588714393, 5838.591298609142, 6233.836126351103, 6287.49700023997, 6288.371162883675, 6291.811245662638, 6298.275698294755, 4839.782209800567, 5635.070213414822, 5667.574931880125, 5720.856978575569, 5797.0, 5463.535599474009, 5495.972520137454, 5500.889982200333, 5505.119180931112, 5513.669179849222, 6451.161349024596, 6455.386738259952, 6457.192850893638, 6461.06314584392, 6477.0, 6212.378762123752, 6223.782167624111, 6276.372362763687, 6290.685465726604, 6305.991041433532, 6437.742490300336, 6443.581167224115, 6460.709268082854, 6461.773837915651, 6473.449756770538, 6231.3145553988115, 6239.438450539473, 6268.216472940882, 6271.498280137669, 6303.558750887577, 5790.218320526675, 6190.938090619036, 6224.346443623354, 6275.278342990615, 6277.937220627735, 6252.968735156477, 6266.248050234001, 6270.7491700331475, 6274.184356033869, 6301.747930082745, 6080.361562035922, 6233.937660623517, 6242.65665388408, 6251.906221406592, 6266.060090986206, 6028.608140470856, 6069.757209711739, 6095.451409373178, 6111.755529778759, 6124.601900876429, 5185.637005409585, 5253.23828044939, 6191.19514463117, 6208.596441231305, 6240.0, 5932.555058370725, 5979.133025711334, 6036.517078633611, 6068.150458935665, 6126.693665316806], "value": 5773.616093586096, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.5527137397907, 516.1612379882689, 521.9582433405328, 523.9371275446945, 533.8078291814949, 25777.0, 41546.79226603867, 42581.99999999996, 42653.0, 45106.54893451065, 41777.791111044404, 42199.94500137498, 42336.57663423365, 42409.36385954213, 42549.361759573556, 8538.78653033676, 9379.71860844174, 9590.904090959071, 9597.424154550748, 9607.279454040974, 8562.61468233931, 11454.48454819535, 11680.474378652938, 11694.239874408137, 11911.940440297787, 11673.1828771986, 11751.47118379675, 11766.529338826436, 11767.882321176765, 11769.999100152952, 8586.012608550034, 11512.0, 11610.6516804496, 11804.173707840455, 11817.409129543532, 11484.138689598314, 11582.247153934968, 11589.246698964625, 11609.76780464386, 11619.593314233962, 40192.79903600492, 40604.59395406038, 40717.0, 40776.59223407758, 40874.0, 3354.1446931032674, 3439.5012723155, 3776.0371105367954, 3836.9424458633143, 3852.942205866886, 28600.284992875135, 29574.408511829643, 29979.400411991857, 30088.398232035237, 30488.0, 2788.9023884163958, 2798.3144129688153, 2814.535601625729, 2866.226118947893, 2872.755815755663, 517127.0, 518881.0, 520486.0, 520906.395468024, 521089.3945530286, 50448.747756260986, 59544.106838397456, 59956.400435995085, 60317.0, 60394.39605603975, 36094.0, 36792.0, 36806.44790328147, 38205.0, 38216.8089159558, 17401.0, 17587.384441544484, 17589.384371546934, 17596.648067038586, 17638.382656607213, 12111.576094836639, 12315.93842030784, 13175.143615665143, 13314.600561982963, 13408.932955335356, 16325.59186020359, 19622.0, 19832.50418739514, 19871.40385788429, 19885.0, 6766.560173588701, 7848.254415830487, 7939.761807145795, 7966.920330796734, 8025.99025365488, 939.3189937295406, 1052.552665117322, 1107.396468924438, 1121.5009320852143, 1137.0676045642495, 29092.563611545844, 29910.70089299079, 29932.850335748182, 30297.93957211487, 30572.54141187884, 1135.4890299365318, 1147.2886810177743, 1165.516310731054, 1195.2529668956902, 1197.2457351868268, 15204.315805788767, 15251.627353538235, 15409.075455472483, 15497.922510387376, 15503.845100918434, 623397.0, 710960.0, 716807.0, 719908.0, 721664.0, 46757.766211168724, 58409.0, 60084.69957650184, 60316.698416508516, 60846.0, 1123.455124264731, 1227.2881728597213, 1359.7484465374134, 1465.157534417714, 1484.5768955847532, 29602.703972959996, 30479.847600761856, 30610.693893061674, 30766.692333076382, 31112.377752444372, 49327.753361232964, 50132.0, 50642.74678626583, 50657.49342506675, 50738.238926416874, 535057.3247133739, 535968.0, 536064.0, 537153.0, 537162.0, 21470.67793983123, 23356.64965025492, 26344.0, 26657.0, 27614.861925690242, 45316.0, 46712.53287467218, 46782.45434848416, 46889.53110468852, 48863.267050994975, 38509.807450962566, 38810.02974925649, 38935.22129557336, 39513.67781155001, 39541.0, 37882.81058594689, 37921.0, 38531.42202866903, 38766.0, 39089.41365879571, 34469.0, 37808.43287350747, 37861.05347366228, 38340.61659383371, 38534.42198367083, 32963.505547417284, 33107.834460827544, 33788.66211337855, 33850.66149338475, 34259.31481370309, 23501.75911370639, 24657.876710616332, 24794.132205373448, 25129.0, 25342.98628054857], "value": 20132.87197879873, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.79545045675354, 394.50686641697877, 398.18372336709746, 400.31945692323023, 421.28802324072313, 14764.852351476482, 20384.69422958655, 21179.47051323714, 21507.67738483924, 21512.569748605038, 15972.408317205085, 18349.26602935881, 20203.797962020377, 20697.68953465699, 20712.585748285026, 5833.300003999515, 6060.333363330053, 6073.969630151843, 6074.787382441616, 6086.269647642289, 5788.681622510781, 6040.909386359208, 6055.273367195942, 6075.392460753934, 6079.483243924272, 5199.610029247821, 5226.42509323976, 5913.083472061816, 5962.672053037081, 5978.671173085479, 5813.360530341659, 5878.970605146968, 5985.281766188042, 5993.130996005577, 6015.157877897098, 5262.710550919698, 5279.52484276417, 5281.128613778722, 5284.577233821324, 5291.206319052135, 25777.484450310887, 26307.473850523074, 27364.31589210266, 27401.86299068492, 27492.58761118585, 2181.214762685432, 2305.5388922215625, 2413.0468464956243, 2585.7802086822453, 2907.4933852528497, 13706.040577159603, 14862.0, 15374.923125384414, 15510.0, 15594.766078508832, 1723.8534724548429, 1791.704368779163, 1796.4880009197316, 1824.8905065695967, 1840.300685739424, 121249.15003399854, 123019.0, 124366.512669747, 125512.37243813723, 125551.3722431391, 11041.889581104086, 11288.548458061585, 11496.54013839437, 11541.596044138583, 11641.88358116425, 14613.561593152222, 14689.926550367394, 14698.412063517553, 14707.264636768119, 14711.19088450124, 7306.232845551141, 7731.961340193376, 8809.295256379473, 8878.86681699775, 9005.324600654978, 9039.909600904039, 9042.683506077256, 9048.321375896834, 9107.590158442887, 9170.66148132222, 6833.958888294661, 6960.199577048603, 6998.610169994454, 7009.859802803915, 7100.360967512948, 1595.5612206643266, 1860.8790428622367, 1894.895780732073, 1903.5526651236949, 1928.344362916587, 809.5628360685282, 894.190757364586, 967.3373738988815, 1021.3514418344405, 1044.5090807320548, 21318.680219796715, 22173.002214900367, 22215.333539993502, 22278.0, 22438.990245438996, 989.2877128467492, 998.6554638649654, 1002.7192386131894, 1051.5530899367739, 1087.516055355375, 11412.600558980395, 11514.596989105341, 11754.588589399329, 11825.645230643095, 11851.466683999068, 578551.0, 594816.0, 626407.0, 634566.0, 636657.0, 116890.831091688, 120767.50789905268, 127427.0, 128587.35706321409, 130631.0, 990.5632144923425, 1014.2899970021015, 1031.376017509411, 1032.850236715687, 1035.425338936882, 15448.459303924308, 19294.03529823475, 19370.709439358703, 19380.806191938464, 19762.50593735168, 51969.22046169235, 52332.430027099246, 53389.0, 55483.16775248455, 55491.0, 50219.0, 66215.0, 76774.23225767894, 83506.0, 88070.0, 5547.0, 5988.850278743066, 6398.648074355769, 7748.876412920208, 8361.498310101413, 7137.85724285501, 9364.906350936404, 12021.519139234682, 12101.273923564262, 12387.876121238673, 10663.146948244277, 10854.34873907568, 10965.945170274097, 11079.833802492807, 11367.658970230907, 9130.543472826464, 9811.901880981099, 10270.178385729274, 10651.360918344924, 11093.611723589636, 7330.816729581803, 10911.618093366695, 11470.025047871024, 11482.82775758381, 11595.478203480694, 6821.317868213139, 8227.54748488815, 8230.259276665129, 8361.205685459872, 8652.524111173945, 8108.189181081844, 10079.89920100819, 10467.214958877963, 10598.735031624272, 11098.167637427387], "value": 9714.725743020512, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.586582823894, 1611.1944027986003, 1638.4674980631294, 1926.4702206893103, 2046.7441569803784, 8133.9186608133905, 8621.267192288647, 8631.35264855136, 8633.870491942618, 8952.283817294618, 8986.2810975122, 8989.325800564951, 9187.35688501805, 9189.632414703421, 9204.493752843591, 2131.509752756867, 2472.913448029319, 2484.1429706751187, 2524.8737563121863, 2525.0657256815, 2481.689788776403, 2496.8127390445698, 2523.356544081266, 2524.5582023145876, 2525.5075260324243, 2491.115653942851, 2507.147569826259, 2530.772230499255, 2530.9620355694587, 2535.3915060385466, 2464.3099932019063, 2485.378655336166, 2499.212747984388, 2502.0867383404925, 2506.5613517634342, 2463.0763463701114, 2490.6264060391086, 2499.600063989771, 2500.037485568051, 2506.1604362538574, 9119.544022798833, 9189.908100918972, 9208.3093767967, 9232.723018309462, 9249.768755781091, 5437.592180586472, 5466.34403871534, 5646.322441307069, 5648.15277708347, 5686.118651608966, 3648.0, 3664.303782281358, 3690.128287681395, 3735.6077611850906, 3791.696664266852, 941.9576119074658, 1047.5600247895902, 1055.5144633468615, 1068.551208492435, 1080.4165750494724, 11813.173077884552, 12203.511859525697, 12361.32012739299, 12445.751084978341, 12449.439775210047, 4766.737829419415, 4824.059308434892, 4886.242632391985, 4895.681780684244, 4906.730129842822, 9561.426314421156, 9674.661386851567, 9682.515874206261, 9688.75778105553, 9698.903010969942, 5551.084071128219, 5551.1673249012165, 5555.50000499957, 5557.833265001976, 5558.499735023867, 4547.681662283675, 4555.612772914273, 4593.265077587636, 5106.208537676666, 5115.71863547501, 3522.5772907251294, 3853.96146038536, 3955.0, 3995.300822356076, 4028.2144981728866, 1062.9202809789297, 1222.3949145173196, 1238.6965193527644, 1238.869918658528, 1253.254313683357, 881.1540920716056, 911.3893691226893, 946.2335508238349, 979.6571200079915, 1022.1414012229762, 6014.278286605635, 6046.669732658808, 6063.393660633901, 6249.875002499924, 6270.372962703693, 474.50414317038775, 486.2317538289494, 489.4322585800454, 491.92621106834264, 494.2882249560621, 4045.8786236412957, 4112.712110152261, 4156.521999969982, 4207.6423504002505, 4216.494020717472, 10970.835437468444, 11352.772944541228, 11405.714857128638, 11417.257878237888, 11447.198696091195, 10483.47582620866, 11237.719057023476, 11345.829812552818, 11391.715207119889, 11399.430028498542, 8312.085670576233, 8610.95694521536, 8676.91323086761, 8689.522076285864, 8693.826123477622, 6042.214512113397, 6156.5998210116195, 6296.622202667854, 6363.790879733021, 6378.394052564999, 9613.086756758097, 10126.0, 10446.425446600206, 10471.528781204712, 10676.199285053497, 10401.68794936153, 10434.739131521772, 10549.261551691308, 10666.89333106659, 10754.354738715389, 7877.487963282369, 7931.048274207132, 8019.1579884111325, 8020.197980202167, 8027.59862006909, 8233.053198882206, 8578.742637720878, 8625.827483450421, 8673.392862499564, 8680.131986801522, 7779.299863012356, 7833.942417773756, 7845.293923546908, 7880.56656883854, 7992.880106798519, 7722.459427839997, 7751.224877512203, 7756.767296981099, 7867.645955931882, 7894.21057894206, 7164.247753985756, 7323.377512911462, 7356.190819009797, 7357.1907090218965, 7368.1526624438875, 4784.258439941815, 4822.035592881508, 5062.291279220838, 5078.4667609900425, 5164.561012313995, 5088.618353623419, 5108.182690769459, 5123.923141152811, 5192.3769147702515, 5446.782128714807], "value": 4670.589611595715, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.9763767717421, 320.6280714371328, 329.8861892647037, 331.3985117012622, 333.65466741922086, 4792.305115758221, 5395.892082158354, 5399.406065332815, 5407.945920540803, 5468.179773034042, 4713.128071306811, 5035.269885866547, 5369.62412631116, 5380.085385484467, 5440.6735595864275, 3598.8740394086212, 3687.446882967563, 3908.3160446921815, 4034.1931613677234, 4135.648469880064, 4032.9596704033024, 4093.5087789465306, 4104.42538044674, 4135.524414692304, 4152.916941661165, 4062.2687916175078, 4068.2677118118727, 4082.203970225807, 4087.6934229932726, 4091.936096614872, 3243.4323993301077, 3295.6374798772117, 3771.528558930134, 3823.2735780201674, 3941.8371580383714, 3015.2914065194664, 3398.315330888595, 3553.2766608195006, 3572.8749493767855, 3617.330793803155, 5115.539601435851, 5149.330587023663, 5149.897002059975, 5164.690118592859, 5203.297554830087, 4270.807813648394, 4605.08888811143, 4651.5813576778255, 4689.280217473857, 4693.718376897363, 4854.878628034292, 4873.343063358475, 4976.810203510444, 5582.218489411445, 5594.524465420444, 3203.9679603203904, 3382.4080785862375, 3387.9322413551836, 3776.2258736958856, 3858.9807050964846, 5360.65155764874, 5378.95599672126, 5546.112621980504, 5812.651240925558, 5910.468057874812, 3292.3579901918897, 3874.8450061997205, 3877.3214687429586, 3885.7668539887695, 3894.5910679378258, 3483.7909725416557, 3645.2345007548183, 3933.645971862546, 3951.308521008812, 3961.7622942623534, 3818.7899665518107, 4158.856314513533, 4472.508024117345, 4548.431446069241, 4627.3521706961055, 4433.246348120827, 5253.817890974577, 5380.219868119101, 5654.604177707603, 5684.77260909559, 1105.309181761399, 1332.9003572052902, 1339.912905661129, 1348.8988325875598, 1349.7098123903409, 697.9581225126509, 816.9910160951221, 834.7996480844554, 835.6197929941862, 839.1482645115213, 4663.720176789404, 4966.602671786249, 5107.297209523066, 5181.637285390062, 5184.844454666366, 5634.0, 5670.886582268332, 5846.970765146232, 5874.823755287348, 5899.233099697012, 4415.977920110379, 4585.587297143206, 5182.922256166161, 5318.441563635763, 5347.25138480613, 2827.3214428537412, 3008.4127650220794, 3015.703247603508, 3033.605631267921, 3045.512717965115, 4659.2312268475325, 5679.778847547797, 5936.673482958478, 6027.276726792726, 6043.36544662804, 5207.84376468699, 5585.3297604287745, 5890.852728681732, 5901.5868889177345, 5923.614965027259, 5085.796568137381, 5280.339957505312, 5935.347111817783, 6007.309159446632, 6014.458698717136, 4468.664850136318, 5083.415407228216, 5337.879045400509, 5379.669366160122, 5691.288588926384, 4811.542903424169, 4906.571247232642, 5690.548910028082, 5695.430456954271, 5720.055181238233, 5599.468050535193, 5842.766289348378, 5931.436513531207, 6033.58210820448, 6056.818295451144, 3427.9485807712404, 3582.4922554211635, 3858.401947698111, 3892.4161375793856, 4007.358822588372, 4893.632977526629, 5050.0, 5164.96700659862, 5729.885402292014, 5807.94192058074, 4777.283407488765, 4902.803887844446, 5381.596380271417, 5567.77728890856, 5571.721413929368, 4854.572713643179, 5047.974760126176, 5446.291982042309, 5513.8345849624575, 5662.0, 5001.57486613642, 5225.242339860775, 5517.310336207974, 5529.640573362719, 5543.805966791143, 4939.5554400104165, 4946.431160416598, 5129.153689641167, 5396.0, 5703.62926409782, 4700.388949436551, 5102.617303702312, 5462.3445186577865, 5549.556035517229, 5550.611457197957], "value": 4032.3959119375354, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.5823789250666, 691.1567887177641, 704.8264639375437, 737.8118579762163, 746.3130942220282, 15063.246837658127, 15311.234438278083, 15508.922455387721, 15535.922320388383, 16544.255508502116, 16181.595460113504, 16578.50264492067, 16580.66838663226, 16588.17059147044, 16610.66778664426, 719.3310221494008, 926.7451450851003, 927.679950417105, 936.0171819589438, 936.6019441737268, 740.3928778401713, 903.0743487924864, 934.7756538430796, 935.0742764662969, 935.4060171790928, 987.4618333008527, 987.5457289646771, 991.182274623435, 992.6575331510618, 996.9900300996969, 773.1765669561898, 947.7630592351912, 987.0228473810947, 988.7429268390234, 989.0900371658056, 729.3508777188307, 731.9670614822293, 732.2677322677323, 732.8570928668912, 733.5158795195199, 15576.766348504781, 15647.374105035786, 15667.451639192688, 15668.21658917061, 15688.607784805472, 12242.959348455392, 12297.69255768604, 12394.256344619353, 12485.375731213402, 12585.622431326983, 10123.949380253125, 10618.628348007782, 10921.672349829518, 11030.669079927615, 11233.438328083645, 3353.9496907546404, 3973.642372186488, 3978.76127432355, 4036.777977211252, 4141.482314710661, 87940.0, 89059.10940890573, 89677.0, 90624.54687726585, 90919.09080909172, 9264.305177111744, 9453.621855125855, 9544.093311135432, 9721.46531940736, 9860.26048046385, 21563.892180539, 21571.892140539196, 21579.67630485544, 21590.136394544043, 21617.243396481048, 7368.373688236453, 7405.444591655647, 7456.4780465367985, 7472.364848987788, 7550.131734850453, 4120.711550191518, 4129.339305711132, 4218.987443013654, 4254.638355739797, 4322.416473776063, 3488.5639295088113, 3591.9102022449647, 3591.9102022449647, 3596.9640303597153, 3597.964020359815, 638.3201889987149, 651.7002178997619, 658.7002913674266, 659.8086554899025, 660.428729149292, 518.8832512684615, 522.0863488894435, 568.709957921463, 571.3800526428871, 571.4171545024052, 7735.419843511759, 7873.685052597832, 7967.442279040411, 8050.355971522265, 8207.897735718108, 498.551303826559, 542.7448949614359, 563.0484481226714, 573.6873403994833, 576.3775122867377, 3684.393120880242, 3732.5147730794824, 3757.4363845423404, 3848.5958974307855, 3976.1451287973223, 93798.0, 98751.01248987418, 100377.49811250897, 100945.99054009512, 101869.0, 65180.0, 84843.15156848475, 85175.0, 85672.0, 87116.5644171775, 31038.068857934297, 31131.68868311288, 61626.69186654038, 76281.61859190669, 76595.61702191565, 6314.715837787402, 7419.146798118286, 7436.330730234305, 7465.3654439371085, 7480.513766605153, 46136.0, 46480.7675961618, 47152.05695885995, 47769.0, 48350.75824620854, 60490.0, 63797.68101159464, 64608.35391646023, 64655.353446464935, 65331.0, 9674.03259674027, 9750.512474376112, 9940.30417870771, 9962.950185249027, 10039.548220330249, 10462.267641265038, 10959.12327013821, 10997.61508347236, 11324.037456816262, 11620.535178592761, 9949.054839790208, 10394.116500097274, 10423.166146708396, 10492.003259690317, 10560.788784224425, 9954.402735835873, 10426.426546540013, 10458.372497650165, 10459.84310235332, 10472.581096756045, 9612.567434465325, 9920.305578609428, 9993.500324983575, 10115.291929564859, 10127.89872101299, 7644.808879777827, 8491.787705307417, 8819.0, 8845.646174152962, 9012.324075694218, 8123.47197432165, 8606.311495080503, 8696.906396100767, 8772.956135219283, 8833.955830220808], "value": 6781.20272139286, "unit": "score"}], "name": "dromaeo_css", "value": 7241.780581512619, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 110.0, 111.0, 110.0, 113.0, 112.0, 111.0, 112.0, 109.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 115.0, 139.0, 191.0, 117.0, 143.0, 159.0, 105.0, 190.0, 140.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 183.0, 186.0, 184.0, 187.0, 182.0, 182.0, 183.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 56.0, 125.0, 57.0, 61.0, 56.0, 60.0, 110.0, 56.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 100.0, 132.0, 81.0, 80.0, 76.0, 102.0, 80.0, 79.0, 102.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 83.0, 88.0, 84.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 144.0, 150.0, 92.0, 92.0, 92.0, 92.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 86.0, 85.0, 86.0, 86.0, 86.0, 84.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 57.0, 55.0, 54.0, 55.0, 56.0, 116.0, 54.0, 54.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 70.0, 75.0, 78.0, 71.0, 70.0, 67.0, 66.0, 66.0, 67.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 103.0, 81.0, 84.0, 80.0, 100.0, 86.0, 84.0, 84.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 140.0, 147.0, 142.0, 126.0, 134.0, 136.0, 145.0, 236.0, 123.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 212.0, 219.0, 161.0, 163.0, 160.0, 163.0, 159.0, 165.0, 166.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 67.0, 78.0, 85.0, 82.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1486.8999999999999, "alertThreshold": 2.0}]} 05:35:17 INFO - Return code: 0 05:35:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:35:18 ERROR - # TBPL SUCCESS # 05:35:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:35:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:35:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:35:18 INFO - 2016-05-19 05:35:18.067 screenresolution[1685:9976] starting screenresolution argv=screenresolution get 05:35:18 INFO - 2016-05-19 05:35:18.070 screenresolution[1685:9976] Display 0: 1600x1200x32@60 05:35:18 INFO - 2016-05-19 05:35:18.076 screenresolution[1686:9979] starting screenresolution argv=screenresolution list 05:35:18 INFO - Available Modes on Display 0 05:35:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:35:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:35:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:35:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:35:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:35:18 INFO - Intel Iris: 05:35:18 INFO - Chipset Model: Intel Iris 05:35:18 INFO - Type: GPU 05:35:18 INFO - Bus: Built-In 05:35:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:35:18 INFO - Vendor: Intel (0x8086) 05:35:18 INFO - Device ID: 0x0a2e 05:35:18 INFO - Revision ID: 0x0009 05:35:18 INFO - Displays: 05:35:18 INFO - Display: 05:35:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:35:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:35:18 INFO - Display Serial Number: 1600x1200 60 05:35:18 INFO - Main Display: Yes 05:35:18 INFO - Mirror: Off 05:35:18 INFO - Online: Yes 05:35:18 INFO - Rotation: Supported 05:35:18 INFO - Return code: 0 05:35:18 INFO - Running post-action listener: _resource_record_post_action 05:35:18 INFO - ##### 05:35:18 INFO - ##### Finished run-tests step (success) 05:35:18 INFO - ##### 05:35:18 INFO - Running post-run listener: _resource_record_post_run 05:35:18 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37922304; Write bytes: 696992768; Read time: 394; Write time: 2036 05:35:18 INFO - TinderboxPrint: CPU usage
53.7% 05:35:18 INFO - TinderboxPrint: I/O read bytes / time
37,922,304 / 394 05:35:18 INFO - TinderboxPrint: I/O write bytes / time
696,992,768 / 2,036 05:35:18 INFO - TinderboxPrint: CPU idle
2,241.4 (46.3%) 05:35:18 INFO - TinderboxPrint: CPU system
968.0 (20.0%) 05:35:18 INFO - TinderboxPrint: CPU user
1,636.3 (33.8%) 05:35:18 INFO - TinderboxPrint: Swap in / out
433,643,520 / 0 05:35:18 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 376920576; Write bytes: 371430912; Read time: 28031; Write time: 693 05:35:18 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36967936; Write bytes: 298376704; Read time: 379; Write time: 1283 05:35:18 INFO - Running post-run listener: _upload_blobber_files 05:35:18 INFO - Blob upload gear active. 05:35:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:35:18 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 05:35:18 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'] 05:35:18 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 05:35:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:35:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:35:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:35:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:20 INFO - (blobuploader) - INFO - Done attempting. 05:35:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:35:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:35:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:21 INFO - (blobuploader) - INFO - Done attempting. 05:35:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:35:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:35:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:23 INFO - (blobuploader) - INFO - Done attempting. 05:35:23 INFO - (blobuploader) - INFO - Iteration through files over. 05:35:24 INFO - Return code: 0 05:35:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:35:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:35:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/443c46f51b3296b5bf968673755109af47e79a4e99423f451a064fdc04519bf5c4199b5f8db3046173982a7c6f387c1e6a8414fd4f5bedab2a0de4d63290f016", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/399abe60f8ea4c309d38a1a8551157b6f11f69250869a8efc8a8ef4543fa4724f28626bbae4a41762cdf881e936446401a30a62a92172f620974b976a7b7cab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa676153e7b535be62477794bcd8ee6132b1936716df0e973811a8f1795b7a201f8daba10594e6693ce5e117b946f270f76be0f7bb6607871e194c665314754"} 05:35:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:35:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:35:24 INFO - Contents: 05:35:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/443c46f51b3296b5bf968673755109af47e79a4e99423f451a064fdc04519bf5c4199b5f8db3046173982a7c6f387c1e6a8414fd4f5bedab2a0de4d63290f016", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/399abe60f8ea4c309d38a1a8551157b6f11f69250869a8efc8a8ef4543fa4724f28626bbae4a41762cdf881e936446401a30a62a92172f620974b976a7b7cab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa676153e7b535be62477794bcd8ee6132b1936716df0e973811a8f1795b7a201f8daba10594e6693ce5e117b946f270f76be0f7bb6607871e194c665314754"} 05:35:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:35:24 INFO - Copying logs to upload dir... 05:35:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:35:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.636495 ========= master_lag: 1.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-19 05:35:25.369642) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-19 05:35:25.373132) ========= 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.kbGRnFhsD7/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.zmBXOrkEir/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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/443c46f51b3296b5bf968673755109af47e79a4e99423f451a064fdc04519bf5c4199b5f8db3046173982a7c6f387c1e6a8414fd4f5bedab2a0de4d63290f016", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/399abe60f8ea4c309d38a1a8551157b6f11f69250869a8efc8a8ef4543fa4724f28626bbae4a41762cdf881e936446401a30a62a92172f620974b976a7b7cab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa676153e7b535be62477794bcd8ee6132b1936716df0e973811a8f1795b7a201f8daba10594e6693ce5e117b946f270f76be0f7bb6607871e194c665314754"} build_url:https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010594 build_url: 'https://queue.taskcluster.net/v1/task/XHTQ5joASAauHNgXvScVjg/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/443c46f51b3296b5bf968673755109af47e79a4e99423f451a064fdc04519bf5c4199b5f8db3046173982a7c6f387c1e6a8414fd4f5bedab2a0de4d63290f016", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/399abe60f8ea4c309d38a1a8551157b6f11f69250869a8efc8a8ef4543fa4724f28626bbae4a41762cdf881e936446401a30a62a92172f620974b976a7b7cab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa676153e7b535be62477794bcd8ee6132b1936716df0e973811a8f1795b7a201f8daba10594e6693ce5e117b946f270f76be0f7bb6607871e194c665314754"}' ========= master_lag: 1.83 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-19 05:35:27.209906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:35:27.210254) ========= 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.kbGRnFhsD7/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.zmBXOrkEir/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005571 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:35:27.236187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 05:35:27.236495) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 05:35:27.239847) ========= ========= Total master_lag: 3.32 =========