builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0312 starttime: 1464800771.77 results: success (0) buildid: 20160601075442 builduid: 6ec167071a674293aaa469b2f4523092 revision: 5d2cb7fff024a1d3ed59ad2432882d158ce5ee91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.771167) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.771574) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.771847) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.793611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.794180) ========= 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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-01 10:06:11-- 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% 147M=0s 2016-06-01 10:06:11 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117839 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.933117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.933426) ========= 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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.019822 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:11.981847) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 10:06:11.982208) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5d2cb7fff024a1d3ed59ad2432882d158ce5ee91 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 5d2cb7fff024a1d3ed59ad2432882d158ce5ee91 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-01 10:06:12,062 truncating revision to first 12 chars 2016-06-01 10:06:12,062 Setting DEBUG logging. 2016-06-01 10:06:12,062 attempt 1/10 2016-06-01 10:06:12,062 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5d2cb7fff024?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 10:06:13,326 unpacking tar archive at: fx-team-5d2cb7fff024/testing/mozharness/ program finished with exit code 0 elapsedTime=1.791945 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 10:06:13.795462) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:13.795867) ========= script_repo_revision: 5d2cb7fff024a1d3ed59ad2432882d158ce5ee91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:13.796369) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:13.796897) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 10:06:13.814301) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-06-01 10:06:13.814573) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:06:13 INFO - MultiFileLogger online at 20160601 10:06:13 in /builds/slave/test 10:06:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:06:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:06:13 INFO - {'append_to_log': False, 10:06:13 INFO - 'base_work_dir': '/builds/slave/test', 10:06:13 INFO - 'blob_upload_branch': 'Fx-Team', 10:06:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:06:13 INFO - 'branch': 'Fx-Team', 10:06:13 INFO - 'buildbot_json_path': 'buildprops.json', 10:06:13 INFO - 'config_files': ('talos/mac_config.py',), 10:06:13 INFO - 'default_actions': ('clobber', 10:06:13 INFO - 'read-buildbot-config', 10:06:13 INFO - 'download-and-extract', 10:06:13 INFO - 'populate-webroot', 10:06:13 INFO - 'create-virtualenv', 10:06:13 INFO - 'install', 10:06:13 INFO - 'run-tests'), 10:06:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:06:13 INFO - 'download_minidump_stackwalk': True, 10:06:13 INFO - 'download_symbols': 'ondemand', 10:06:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:06:13 INFO - 'tooltool.py': '/tools/tooltool.py', 10:06:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:06:13 INFO - '/tools/misc-python/virtualenv.py')}, 10:06:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:06:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:06:13 INFO - 'installer_path': 'installer.exe', 10:06:13 INFO - 'log_level': 'info', 10:06:13 INFO - 'log_name': 'talos', 10:06:13 INFO - 'log_to_console': True, 10:06:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:06:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:06:14 INFO - 'opt_config_files': (), 10:06:14 INFO - 'pip_index': False, 10:06:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:06:14 INFO - 'cmd': ('bash', 10:06:14 INFO - '-c', 10:06:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:06:14 INFO - 'enabled': True, 10:06:14 INFO - 'halt_on_failure': False, 10:06:14 INFO - 'name': 'check_screen_resolution'},), 10:06:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:06:14 INFO - 'cmd': ('bash', 10:06:14 INFO - '-c', 10:06:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:06:14 INFO - 'enabled': True, 10:06:14 INFO - 'halt_on_failure': False, 10:06:14 INFO - 'name': 'check_screen_resolution'},), 10:06:14 INFO - 'run_cmd_checks_enabled': True, 10:06:14 INFO - 'sps_profile': False, 10:06:14 INFO - 'sps_profile_interval': 0, 10:06:14 INFO - 'suite': 'dromaeojs', 10:06:14 INFO - 'system_bits': '32', 10:06:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:06:14 INFO - 'title': 't-yosemite-r7-0312', 10:06:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:06:14 INFO - 'use_talos_json': True, 10:06:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:06:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:06:14 INFO - 'work_dir': 'build'} 10:06:14 INFO - ##### 10:06:14 INFO - ##### Running clobber step. 10:06:14 INFO - ##### 10:06:14 INFO - Running pre-action listener: _resource_record_pre_action 10:06:14 INFO - Running main action method: clobber 10:06:14 INFO - rmtree: /builds/slave/test/build 10:06:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:06:15 INFO - Running post-action listener: _resource_record_post_action 10:06:15 INFO - ##### 10:06:15 INFO - ##### Finished clobber step (success) 10:06:15 INFO - ##### 10:06:15 INFO - ##### 10:06:15 INFO - ##### Running read-buildbot-config step. 10:06:15 INFO - ##### 10:06:15 INFO - Running pre-action listener: _resource_record_pre_action 10:06:15 INFO - Running main action method: read_buildbot_config 10:06:15 INFO - Using buildbot properties: 10:06:15 INFO - { 10:06:15 INFO - "project": "", 10:06:15 INFO - "product": "firefox", 10:06:15 INFO - "script_repo_revision": "production", 10:06:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:06:15 INFO - "repository": "", 10:06:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:06:15 INFO - "buildid": "20160601075442", 10:06:15 INFO - "pgo_build": "False", 10:06:15 INFO - "basedir": "/builds/slave/test", 10:06:15 INFO - "buildnumber": 46, 10:06:15 INFO - "slavename": "t-yosemite-r7-0312", 10:06:15 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:06:15 INFO - "platform": "yosemite_r7", 10:06:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:06:15 INFO - "branch": "fx-team", 10:06:15 INFO - "repo_path": "integration/fx-team", 10:06:15 INFO - "revision": "5d2cb7fff024a1d3ed59ad2432882d158ce5ee91", 10:06:15 INFO - "stage_platform": "macosx64", 10:06:15 INFO - "builduid": "6ec167071a674293aaa469b2f4523092", 10:06:15 INFO - "slavebuilddir": "test" 10:06:15 INFO - } 10:06:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:06:15 INFO - Running post-action listener: _resource_record_post_action 10:06:15 INFO - ##### 10:06:15 INFO - ##### Finished read-buildbot-config step (success) 10:06:15 INFO - ##### 10:06:15 INFO - ##### 10:06:15 INFO - ##### Running download-and-extract step. 10:06:15 INFO - ##### 10:06:15 INFO - Running pre-action listener: _resource_record_pre_action 10:06:15 INFO - Running main action method: download_and_extract 10:06:15 INFO - mkdir: /builds/slave/test/build/tests 10:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:15 INFO - https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:06:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:06:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:06:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:17 INFO - Downloaded 1199 bytes. 10:06:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:06:17 INFO - Using the following test package requirements: 10:06:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:06:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:06:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:06:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'jsshell-mac.zip'], 10:06:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:06:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:06:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:06:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:06:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:06:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:06:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:06:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:06:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:17 INFO - https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:06:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:06:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:06:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:20 INFO - Downloaded 18696554 bytes. 10:06: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'] 10:06: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 10:06:23 INFO - Return code: 0 10:06:23 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 10:06: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')]} 10:06:23 INFO - https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:06:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:06:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:06:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:24 INFO - Downloaded 18696554 bytes. 10:06:24 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'] 10:06:24 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 10:06:27 INFO - Return code: 0 10:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:27 INFO - https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:06:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:06:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:06:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:29 INFO - Downloaded 10834055 bytes. 10:06:29 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'] 10:06:29 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 10:06:29 INFO - Return code: 0 10:06:29 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')]} 10:06:29 INFO - https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:06:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:06:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:06:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/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 10:06:34 INFO - Downloaded 111052914 bytes. 10:06:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:06:34 INFO - mkdir: /builds/slave/test/properties 10:06:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:06:34 INFO - Writing to file /builds/slave/test/properties/build_url 10:06:34 INFO - Contents: 10:06:34 INFO - build_url:https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:06:36 INFO - Running post-action listener: _resource_record_post_action 10:06:36 INFO - Running post-action listener: set_extra_try_arguments 10:06:36 INFO - ##### 10:06:36 INFO - ##### Finished download-and-extract step (success) 10:06:36 INFO - ##### 10:06:36 INFO - ##### 10:06:36 INFO - ##### Running populate-webroot step. 10:06:36 INFO - ##### 10:06:36 INFO - Running pre-action listener: _resource_record_pre_action 10:06:36 INFO - Running main action method: populate_webroot 10:06:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:06:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:06:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:06:36 INFO - '--tppagecycles', 10:06:36 INFO - '7'], 10:06:36 INFO - 'tests': ['tsvgm']}}, 10:06:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:06:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:06:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:06:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:06:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:06:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:06:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['damp', 'tps']}, 10:06:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'tests': ['damp', 'tps']}, 10:06:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['dromaeo_dom']}, 10:06:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:06:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['a11yr', 10:06:36 INFO - 'ts_paint', 10:06:36 INFO - 'tpaint', 10:06:36 INFO - 'sessionrestore', 10:06:36 INFO - 'sessionrestore_no_auto_restore', 10:06:36 INFO - 'tabpaint']}, 10:06:36 INFO - 'other-e10s': {'tests': ['a11yr', 10:06:36 INFO - 'ts_paint', 10:06:36 INFO - 'tpaint', 10:06:36 INFO - 'sessionrestore', 10:06:36 INFO - 'sessionrestore_no_auto_restore', 10:06:36 INFO - 'tabpaint']}, 10:06:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:06:36 INFO - 'ts_paint', 10:06:36 INFO - 'tpaint', 10:06:36 INFO - 'sessionrestore', 10:06:36 INFO - 'sessionrestore_no_auto_restore', 10:06:36 INFO - 'tabpaint']}, 10:06:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:06:36 INFO - 'ts_paint', 10:06:36 INFO - 'tpaint', 10:06:36 INFO - 'sessionrestore', 10:06:36 INFO - 'sessionrestore_no_auto_restore', 10:06:36 INFO - 'tabpaint']}, 10:06:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['a11yr', 10:06:36 INFO - 'ts_paint', 10:06:36 INFO - 'tpaint', 10:06:36 INFO - 'sessionrestore', 10:06:36 INFO - 'sessionrestore_no_auto_restore', 10:06:36 INFO - 'tabpaint']}, 10:06:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['a11yr', 10:06:36 INFO - 'ts_paint', 10:06:36 INFO - 'tpaint', 10:06:36 INFO - 'sessionrestore', 10:06:36 INFO - 'sessionrestore_no_auto_restore', 10:06:36 INFO - 'tabpaint']}, 10:06:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['tsvgx', 10:06:36 INFO - 'tsvgr_opacity', 10:06:36 INFO - 'tart', 10:06:36 INFO - 'tscrollx', 10:06:36 INFO - 'cart']}, 10:06:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:06:36 INFO - 'tsvgr_opacity', 10:06:36 INFO - 'tart', 10:06:36 INFO - 'tscrollx', 10:06:36 INFO - 'cart']}, 10:06:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'talos_options': ['--disable-e10s'], 10:06:36 INFO - 'tests': ['tp5o']}, 10:06:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'tests': ['tp5o']}, 10:06:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'talos_options': ['--disable-e10s', 10:06:36 INFO - '--xperf_path', 10:06:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:06:36 INFO - 'tests': ['tp5n']}, 10:06:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:36 INFO - 'talos_options': ['--xperf_path', 10:06:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:06:36 INFO - 'tests': ['tp5n']}}, 10:06:36 INFO - 'talos.zip': {'path': '', 10:06:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:06:36 INFO - Running post-action listener: _resource_record_post_action 10:06:36 INFO - ##### 10:06:36 INFO - ##### Finished populate-webroot step (success) 10:06:36 INFO - ##### 10:06:36 INFO - ##### 10:06:36 INFO - ##### Running create-virtualenv step. 10:06:36 INFO - ##### 10:06:36 INFO - Running pre-action listener: _resource_record_pre_action 10:06:36 INFO - Running main action method: create_virtualenv 10:06:36 INFO - Creating virtualenv /builds/slave/test/build/venv 10:06:36 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 10:06:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:06:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:06:36 INFO - Using real prefix '/tools/python27' 10:06:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:06:36 INFO - Installing distribute.............................................................................................................................................................................................done. 10:06:39 INFO - Installing pip.................done. 10:06:39 INFO - Return code: 0 10:06:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:39 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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:06:39 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 10:06:39 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 10:06:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:06:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:39 INFO - 'HOME': '/Users/cltbld', 10:06:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:39 INFO - 'LOGNAME': 'cltbld', 10:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:39 INFO - 'MOZ_NO_REMOTE': '1', 10:06:39 INFO - 'NO_EM_RESTART': '1', 10:06:39 INFO - 'PAGER': '/bin/cat', 10:06:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:39 INFO - 'PWD': '/builds/slave/test', 10:06:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:39 INFO - 'SHELL': '/bin/bash', 10:06:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:06:39 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:06:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:39 INFO - 'USER': 'cltbld', 10:06:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:39 INFO - 'XPC_FLAGS': '0x0', 10:06:39 INFO - 'XPC_SERVICE_NAME': '0', 10:06:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:40 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 10:06:40 INFO - Cleaning up... 10:06:40 INFO - Return code: 0 10:06:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:06: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')]} 10:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06: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')]} 10:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06: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', '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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:06: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 psutil>=3.1.1 10:06:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:06:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:40 INFO - 'HOME': '/Users/cltbld', 10:06:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:40 INFO - 'LOGNAME': 'cltbld', 10:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:40 INFO - 'MOZ_NO_REMOTE': '1', 10:06:40 INFO - 'NO_EM_RESTART': '1', 10:06:40 INFO - 'PAGER': '/bin/cat', 10:06:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:40 INFO - 'PWD': '/builds/slave/test', 10:06:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:40 INFO - 'SHELL': '/bin/bash', 10:06:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:06:40 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:06:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:40 INFO - 'USER': 'cltbld', 10:06:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:40 INFO - 'XPC_FLAGS': '0x0', 10:06:40 INFO - 'XPC_SERVICE_NAME': '0', 10:06:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:40 INFO - Downloading/unpacking psutil>=3.1.1 10:06: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 10:06: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 10:06: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 10:06: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 10:06: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 10:06: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 10:06:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:06:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:06:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:06:43 INFO - Installing collected packages: psutil 10:06:43 INFO - Running setup.py install for psutil 10:06:43 INFO - building 'psutil._psutil_osx' extension 10:06:43 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 10:06:43 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 10:06:43 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 10:06:43 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 10:06:43 INFO - building 'psutil._psutil_posix' extension 10:06:43 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 10:06:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:06:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:06:43 INFO - ^ 10:06:43 INFO - 1 warning generated. 10:06:43 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 10:06:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:06:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:06:44 INFO - Successfully installed psutil 10:06:44 INFO - Cleaning up... 10:06:44 INFO - Return code: 0 10:06:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:06:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:06:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:06:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:06:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:44 INFO - 'HOME': '/Users/cltbld', 10:06:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:44 INFO - 'LOGNAME': 'cltbld', 10:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:44 INFO - 'MOZ_NO_REMOTE': '1', 10:06:44 INFO - 'NO_EM_RESTART': '1', 10:06:44 INFO - 'PAGER': '/bin/cat', 10:06:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:44 INFO - 'PWD': '/builds/slave/test', 10:06:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:44 INFO - 'SHELL': '/bin/bash', 10:06:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:06:44 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:06:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:44 INFO - 'USER': 'cltbld', 10:06:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:44 INFO - 'XPC_FLAGS': '0x0', 10:06:44 INFO - 'XPC_SERVICE_NAME': '0', 10:06:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:06:46 INFO - Installing collected packages: mozsystemmonitor 10:06:46 INFO - Running setup.py install for mozsystemmonitor 10:06:47 INFO - Successfully installed mozsystemmonitor 10:06:47 INFO - Cleaning up... 10:06:47 INFO - Return code: 0 10:06:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:06:47 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')]} 10:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:47 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')]} 10:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:47 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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:06:47 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 10:06:47 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 10:06:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:06:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:47 INFO - 'HOME': '/Users/cltbld', 10:06:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:47 INFO - 'LOGNAME': 'cltbld', 10:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:47 INFO - 'MOZ_NO_REMOTE': '1', 10:06:47 INFO - 'NO_EM_RESTART': '1', 10:06:47 INFO - 'PAGER': '/bin/cat', 10:06:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:47 INFO - 'PWD': '/builds/slave/test', 10:06:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:47 INFO - 'SHELL': '/bin/bash', 10:06:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:06:47 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:06:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:47 INFO - 'USER': 'cltbld', 10:06:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:47 INFO - 'XPC_FLAGS': '0x0', 10:06:47 INFO - 'XPC_SERVICE_NAME': '0', 10:06:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:47 INFO - Downloading/unpacking blobuploader==1.2.4 10:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:49 INFO - Downloading blobuploader-1.2.4.tar.gz 10:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:06:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:06:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:06:50 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 10:06:50 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 10:06:50 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 10:06:50 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 10:06:50 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 10:06:50 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 10:06:50 INFO - Downloading docopt-0.6.1.tar.gz 10:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:06:51 INFO - Installing collected packages: blobuploader, requests, docopt 10:06:51 INFO - Running setup.py install for blobuploader 10:06:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:06:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:06:51 INFO - Running setup.py install for requests 10:06:51 INFO - Running setup.py install for docopt 10:06:51 INFO - Successfully installed blobuploader requests docopt 10:06:51 INFO - Cleaning up... 10:06:52 INFO - Return code: 0 10:06:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06: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')]} 10:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06: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')]} 10:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:52 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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:06:52 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 10:06:52 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 10:06:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:06:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:52 INFO - 'HOME': '/Users/cltbld', 10:06:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:52 INFO - 'LOGNAME': 'cltbld', 10:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:52 INFO - 'MOZ_NO_REMOTE': '1', 10:06:52 INFO - 'NO_EM_RESTART': '1', 10:06:52 INFO - 'PAGER': '/bin/cat', 10:06:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:52 INFO - 'PWD': '/builds/slave/test', 10:06:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:52 INFO - 'SHELL': '/bin/bash', 10:06:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:06:52 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:06:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:52 INFO - 'USER': 'cltbld', 10:06:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:52 INFO - 'XPC_FLAGS': '0x0', 10:06:52 INFO - 'XPC_SERVICE_NAME': '0', 10:06:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:52 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-xySKRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:52 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-cKtrYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:52 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-OkIlS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:52 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-rlC3tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:52 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-COoawG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:52 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-L4RwCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:52 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-8zfpP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-OKvcyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-jm_84j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-DeR9DL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ffQ_BQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-AZ3W8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Eq4CH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-B7KTjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zNEbf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-8l09aT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-eFhXUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:06:54 INFO - Running setup.py install for manifestparser 10:06:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:06:54 INFO - Running setup.py install for mozcrash 10:06:54 INFO - Running setup.py install for mozdebug 10:06:54 INFO - Running setup.py install for mozdevice 10:06:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:06:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:06:54 INFO - Running setup.py install for mozfile 10:06:54 INFO - Running setup.py install for mozhttpd 10:06:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:06:54 INFO - Running setup.py install for mozinfo 10:06:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:06:55 INFO - Running setup.py install for mozInstall 10:06:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:06:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:06:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:06:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:06:55 INFO - Running setup.py install for mozleak 10:06:55 INFO - Running setup.py install for mozlog 10:06:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:06:55 INFO - Running setup.py install for moznetwork 10:06:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:06:55 INFO - Running setup.py install for mozprocess 10:06:55 INFO - Running setup.py install for mozprofile 10:06:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:06:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:06:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:06:56 INFO - Running setup.py install for mozrunner 10:06:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:06:56 INFO - Running setup.py install for mozscreenshot 10:06:56 INFO - Running setup.py install for moztest 10:06:56 INFO - Running setup.py install for mozversion 10:06:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:06:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:06:56 INFO - Cleaning up... 10:06:56 INFO - Return code: 0 10:06:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06:56 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')]} 10:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:56 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')]} 10:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:56 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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:06:56 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 10:06:56 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 10:06:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:56 INFO - 'HOME': '/Users/cltbld', 10:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:56 INFO - 'LOGNAME': 'cltbld', 10:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:56 INFO - 'MOZ_NO_REMOTE': '1', 10:06:56 INFO - 'NO_EM_RESTART': '1', 10:06:56 INFO - 'PAGER': '/bin/cat', 10:06:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:56 INFO - 'PWD': '/builds/slave/test', 10:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:56 INFO - 'SHELL': '/bin/bash', 10:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:06:56 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:56 INFO - 'USER': 'cltbld', 10:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:56 INFO - 'XPC_FLAGS': '0x0', 10:06:56 INFO - 'XPC_SERVICE_NAME': '0', 10:06:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:56 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-8hH6Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-G0K9yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ksfWdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-AfmlKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-GjNbzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-EMDMf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-fAijDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-tJDk5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-P5bKmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:57 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)) 10:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:57 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-hu6RHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:58 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)) 10:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:58 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Swzx5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:58 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)) 10:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:58 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-5kkfXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:58 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)) 10:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:58 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-xA0TdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:58 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)) 10:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:58 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-9JUILP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:58 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)) 10:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:58 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ZuKWw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:58 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)) 10:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:58 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-AUcUmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:58 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)) 10:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:58 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-dAXJaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:58 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)) 10:06:58 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)) 10:06:58 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)) 10:06:58 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)) 10:06:58 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)) 10:06:58 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)) 10:06:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:06: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 10:06: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 10:06: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 10:06: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 10:06: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 10:06: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 10:07:01 INFO - Downloading blessings-1.6.tar.gz 10:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:07:01 INFO - Installing collected packages: blessings 10:07:01 INFO - Running setup.py install for blessings 10:07:01 INFO - Successfully installed blessings 10:07:01 INFO - Cleaning up... 10:07:01 INFO - Return code: 0 10:07:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:07:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:07:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:07:01 INFO - Reading from file tmpfile_stdout 10:07:01 INFO - Current package versions: 10:07:01 INFO - blessings == 1.6 10:07:01 INFO - blobuploader == 1.2.4 10:07:01 INFO - docopt == 0.6.1 10:07:01 INFO - manifestparser == 1.1 10:07:01 INFO - mozInstall == 1.12 10:07:01 INFO - mozcrash == 0.17 10:07:01 INFO - mozdebug == 0.1 10:07:01 INFO - mozdevice == 0.48 10:07:01 INFO - mozfile == 1.2 10:07:01 INFO - mozhttpd == 0.7 10:07:01 INFO - mozinfo == 0.9 10:07:01 INFO - mozleak == 0.1 10:07:01 INFO - mozlog == 3.2 10:07:01 INFO - moznetwork == 0.27 10:07:01 INFO - mozprocess == 0.22 10:07:01 INFO - mozprofile == 0.28 10:07:01 INFO - mozrunner == 6.11 10:07:01 INFO - mozscreenshot == 0.1 10:07:01 INFO - mozsystemmonitor == 0.3 10:07:01 INFO - moztest == 0.7 10:07:01 INFO - mozversion == 1.4 10:07:01 INFO - psutil == 3.1.1 10:07:01 INFO - requests == 1.2.3 10:07:01 INFO - wsgiref == 0.1.2 10:07:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:07: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')]} 10:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07: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')]} 10:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:01 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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:07:01 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 10:07:01 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 10:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:01 INFO - 'HOME': '/Users/cltbld', 10:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:01 INFO - 'LOGNAME': 'cltbld', 10:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:01 INFO - 'MOZ_NO_REMOTE': '1', 10:07:01 INFO - 'NO_EM_RESTART': '1', 10:07:01 INFO - 'PAGER': '/bin/cat', 10:07:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:01 INFO - 'PWD': '/builds/slave/test', 10:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:01 INFO - 'SHELL': '/bin/bash', 10:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:07:01 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:01 INFO - 'USER': 'cltbld', 10:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:01 INFO - 'XPC_FLAGS': '0x0', 10:07:01 INFO - 'XPC_SERVICE_NAME': '0', 10:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 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)) 10:07:02 INFO - Cleaning up... 10:07:02 INFO - Return code: 0 10:07:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:02 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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:07:02 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 10:07:02 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 10:07:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:07:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:02 INFO - 'HOME': '/Users/cltbld', 10:07:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:02 INFO - 'LOGNAME': 'cltbld', 10:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:02 INFO - 'MOZ_NO_REMOTE': '1', 10:07:02 INFO - 'NO_EM_RESTART': '1', 10:07:02 INFO - 'PAGER': '/bin/cat', 10:07:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:02 INFO - 'PWD': '/builds/slave/test', 10:07:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:02 INFO - 'SHELL': '/bin/bash', 10:07:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:07:02 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:07:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:02 INFO - 'USER': 'cltbld', 10:07:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:02 INFO - 'XPC_FLAGS': '0x0', 10:07:02 INFO - 'XPC_SERVICE_NAME': '0', 10:07:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:02 INFO - Downloading/unpacking jsonschema 10:07:02 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 10:07:02 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 10:07:02 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 10:07:02 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 10:07:02 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 10:07:02 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 10:07:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:07:04 INFO - Downloading/unpacking functools32 (from jsonschema) 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:05 INFO - Downloading functools32-3.2.3-2.tar.gz 10:07:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:07:05 INFO - warning: no files found matching '*.txt' 10:07:05 INFO - no previously-included directories found matching 'build' 10:07:05 INFO - no previously-included directories found matching 'dist' 10:07:05 INFO - no previously-included directories found matching '.git*' 10:07:05 INFO - Installing collected packages: jsonschema, functools32 10:07:05 INFO - Running setup.py install for functools32 10:07:05 INFO - warning: no files found matching '*.txt' 10:07:05 INFO - no previously-included directories found matching 'build' 10:07:05 INFO - no previously-included directories found matching 'dist' 10:07:05 INFO - no previously-included directories found matching '.git*' 10:07:05 INFO - Successfully installed jsonschema functools32 10:07:05 INFO - Cleaning up... 10:07:05 INFO - Return code: 0 10:07:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:05 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 0x105b6aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8372c74ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, '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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 10:07:05 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 10:07:05 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 10:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:05 INFO - 'HOME': '/Users/cltbld', 10:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:05 INFO - 'LOGNAME': 'cltbld', 10:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:05 INFO - 'MOZ_NO_REMOTE': '1', 10:07:05 INFO - 'NO_EM_RESTART': '1', 10:07:05 INFO - 'PAGER': '/bin/cat', 10:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:05 INFO - 'PWD': '/builds/slave/test', 10:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:05 INFO - 'SHELL': '/bin/bash', 10:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:07:05 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:05 INFO - 'USER': 'cltbld', 10:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:05 INFO - 'XPC_FLAGS': '0x0', 10:07:05 INFO - 'XPC_SERVICE_NAME': '0', 10:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:05 INFO - Downloading/unpacking flake8 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:07:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:07:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:07:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:07:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:07:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:07:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:07:09 INFO - no previously-included directories found matching 'docs/_build' 10:07:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:07:09 INFO - Running setup.py install for pep8 10:07:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:07:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:07:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:07:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:07:09 INFO - no previously-included directories found matching 'docs/_build' 10:07:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:07:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:07:09 INFO - Cleaning up... 10:07:09 INFO - Return code: 0 10:07:09 INFO - Running post-action listener: _resource_record_post_action 10:07:09 INFO - Running post-action listener: _start_resource_monitoring 10:07:09 INFO - Starting resource monitoring. 10:07:09 INFO - ##### 10:07:09 INFO - ##### Finished create-virtualenv step (success) 10:07:09 INFO - ##### 10:07:09 INFO - ##### 10:07:09 INFO - ##### Running install step. 10:07:09 INFO - ##### 10:07:09 INFO - Running pre-action listener: _resource_record_pre_action 10:07:09 INFO - Running main action method: install 10:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:07:10 INFO - Reading from file tmpfile_stdout 10:07:10 INFO - Detecting whether we're running mozinstall >=1.0... 10:07:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:07:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:07:10 INFO - Reading from file tmpfile_stdout 10:07:10 INFO - Output received: 10:07:10 INFO - Usage: mozinstall [options] installer 10:07:10 INFO - Options: 10:07:10 INFO - -h, --help show this help message and exit 10:07:10 INFO - -d DEST, --destination=DEST 10:07:10 INFO - Directory to install application into. [default: 10:07:10 INFO - "/builds/slave/test"] 10:07:10 INFO - --app=APP Application being installed. [default: firefox] 10:07:10 INFO - mkdir: /builds/slave/test/build/application 10:07:10 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'] 10:07:10 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 10:07:44 INFO - Reading from file tmpfile_stdout 10:07:44 INFO - Output received: 10:07:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:07:44 INFO - Running post-action listener: _resource_record_post_action 10:07:44 INFO - ##### 10:07:44 INFO - ##### Finished install step (success) 10:07:44 INFO - ##### 10:07:44 INFO - ##### 10:07:44 INFO - ##### Running run-tests step. 10:07:44 INFO - ##### 10:07:44 INFO - Running pre-action listener: _resource_record_pre_action 10:07:44 INFO - Running main action method: run_tests 10:07:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:44 INFO - 2016-06-01 10:07:44.212 screenresolution[1919:15966] starting screenresolution argv=screenresolution get 10:07:44 INFO - 2016-06-01 10:07:44.215 screenresolution[1919:15966] Display 0: 1600x1200x32@60 10:07:44 INFO - 2016-06-01 10:07:44.221 screenresolution[1920:15968] starting screenresolution argv=screenresolution list 10:07:44 INFO - Available Modes on Display 0 10:07:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:44 INFO - Intel Iris: 10:07:44 INFO - Chipset Model: Intel Iris 10:07:44 INFO - Type: GPU 10:07:44 INFO - Bus: Built-In 10:07:44 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:44 INFO - Vendor: Intel (0x8086) 10:07:44 INFO - Device ID: 0x0a2e 10:07:44 INFO - Revision ID: 0x0009 10:07:44 INFO - Displays: 10:07:44 INFO - Display: 10:07:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:44 INFO - Display Serial Number: 1600x1200 60 10:07:44 INFO - Main Display: Yes 10:07:44 INFO - Mirror: Off 10:07:44 INFO - Online: Yes 10:07:44 INFO - Rotation: Supported 10:07:44 INFO - Return code: 0 10:07:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:07:44 INFO - Python 2.7.3 10:07:44 INFO - Return code: 0 10:07:44 INFO - grabbing minidump binary from tooltool 10:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:44 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 0x105a0ed30>, '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 0x105832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a05b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:07:44 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 10:07:44 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 10:07:44 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 10:07:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:07:44 INFO - Return code: 0 10:07:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:07:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:07:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:07:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:07:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:07:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:07:45 INFO - Return code: 0 10:07:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 10:07:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --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 10:07:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lszXzQ0pfn/Render', 10:07:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:45 INFO - 'HOME': '/Users/cltbld', 10:07:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:45 INFO - 'LOGNAME': 'cltbld', 10:07:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:07:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:45 INFO - 'MOZ_NO_REMOTE': '1', 10:07:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:07:45 INFO - 'NO_EM_RESTART': '1', 10:07:45 INFO - 'PAGER': '/bin/cat', 10:07:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:45 INFO - 'PWD': '/builds/slave/test', 10:07:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:07:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:45 INFO - 'SHELL': '/bin/bash', 10:07:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rwrX4Hcqd/Listeners', 10:07:45 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 10:07:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:45 INFO - 'USER': 'cltbld', 10:07:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:45 INFO - 'XPC_FLAGS': '0x0', 10:07:45 INFO - 'XPC_SERVICE_NAME': '0', 10:07:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 10:07:45 INFO - mozversion application_buildid: 20160601075442 10:07:45 INFO - mozversion application_changeset: 5d2cb7fff024a1d3ed59ad2432882d158ce5ee91 10:07:45 INFO - mozversion application_display_name: Nightly 10:07:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:07:45 INFO - mozversion application_name: Firefox 10:07:45 INFO - mozversion application_remotingname: firefox 10:07:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:07:45 INFO - mozversion application_vendor: Mozilla 10:07:45 INFO - mozversion application_version: 49.0a1 10:07:45 INFO - mozversion platform_buildid: 20160601075442 10:07:45 INFO - mozversion platform_changeset: 5d2cb7fff024a1d3ed59ad2432882d158ce5ee91 10:07:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:07:45 INFO - mozversion platform_version: 49.0a1 10:07:45 INFO - using testdate: 1464800865 10:07:45 INFO - actual date: 1464800865 10:07:45 INFO - starting webserver on 'localhost:52620' 10:07:45 INFO - SUITE-START | Running 2 tests 10:07:45 INFO - TEST-START | dromaeo_css 10:07:45 INFO - operating with platform_type : mac_ 10:07:45 INFO - Initialising browser for dromaeo_css test... 10:07:45 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpABJsGg/profile http://localhost:52620/getInfo.html) 10:07:48 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 10:07:48 INFO - PROCESS | 1938 | colorDepth:24 10:07:48 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 10:07:48 INFO - PROCESS | 1938 | __metrics 10:07:48 INFO - PROCESS | 1938 | 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" 10:07:48 ERROR - PROCESS | 1938 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:07:49 INFO - TEST-INFO | 1938: exit 0 10:07:49 INFO - Browser initialized. 10:07:49 INFO - Running cycle 1/1 for dromaeo_css test... 10:07:49 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpABJsGg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:01 INFO - PROCESS | 1940 | RSS: Main: 241983488 10:08:01 INFO - PROCESS | 1940 | 10:08:51 INFO - PROCESS | 1940 | 1464800931986 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] 10:11:03 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52620/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52620/tests/dromaeo/cssquery-ext.html) 10:11:04 INFO - PROCESS | 1940 | RSS: Main: 353783808 10:11:04 INFO - PROCESS | 1940 | 10:14:07 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52620/tests/dromaeo/cssquery-ext.html (next: http://localhost:52620/tests/dromaeo/cssquery-jquery.html) 10:14:08 INFO - PROCESS | 1940 | RSS: Main: 377126912 10:14:08 INFO - PROCESS | 1940 | 10:17:10 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52620/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52620/tests/dromaeo/cssquery-mootools.html) 10:17:11 INFO - PROCESS | 1940 | RSS: Main: 369102848 10:17:11 INFO - PROCESS | 1940 | 10:20:13 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52620/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52620/tests/dromaeo/cssquery-prototype.html) 10:20:14 INFO - PROCESS | 1940 | RSS: Main: 373239808 10:20:14 INFO - PROCESS | 1940 | 10:23:17 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52620/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52620/tests/dromaeo/cssquery-yui.html) 10:23:18 INFO - PROCESS | 1940 | RSS: Main: 339841024 10:23:18 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52620/tests/dromaeo/cssquery-yui.html (next: http://localhost:52620/tests/dromaeo/cssquery-dojo.html) 10:26:21 INFO - PROCESS | 1940 | RSS: Main: 378220544 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | __start_tp_report 10:26:21 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 10:26:21 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 10:26:21 INFO - PROCESS | 1940 | |i|pagename|runs| 10:26:21 INFO - PROCESS | 1940 | |0;dojo.html;3449.3963556377626;3463.3939060664393;3484.059303987924;3509.771864828784;3537.893863184105;5640.887182256362;5690.430956904307;5721.456461636138;5755.366909639942;5804.73878675459;5689.687067211302;5696.060150075233;5706.087026075828;5827.125931110325;5836.124581312794;5087.982403519292;5133.512316329943;5175.327207463025;5183.429822719498;5183.792648294064;5183.922241166386;5207.244949482323;5210.7655155517905;5241.135212689921;5256.605754568403;5112.590992720573;5158.303629010074;5174.404943431516;5222.451642577532;5254.868628284305;5201.1678131498775;5217.660852044605;5218.582513398919;5225.268462415227;5225.268462415227;4393.978030109861;5047.217681259411;5152.613553983429;5166.664166829144;5192.221166825007;5582.27430434041;5603.523700485463;5651.1523271509195;5660.122680984413;5703.458171473703;6200.658963756991;6205.472534834545;6212.161358216628;6244.656543890084;6275.968620156916;5252.894942101137;6221.4089661482085;6257.124002639634;6286.685665716695;6313.179286692746;5708.600397972144;5759.366469688331;5760.36635970043;5829.125631155361;5870.853228669274;6318.4313411793155;6354.6822658867795;6363.840903977438;6377.808665740035;6383;6204.7828326008375;6207;6216.036514340284;6216.378362163748;6240.53196010292;6138.388398386059;6194.907076393858;6202.34875338092;6256.343083976208;6288.088227207029;5385.272988146628;6166.660833654192;6180.907286390708;6196.47329976948;6260.937390626126;6145.692715364214;6177.351378105324;6228.190335256388;6258.561900666909;6279.3720627937755;4867.221244600918;4975.825846095369;4986.675866068694;4987.600991920638;5017.799288028511;3807.7334586578672;4404.757738324423;4429.069895322018;4450.287953927421;4513.638908887281;5704.572157088235;5715.142728590745;5719.628224165416;5727.541796656257;5780.335261444903;6168.228971378578;6284.277308109626;6288.874222515524;6309.400606942425;6324.841878953063;5681.886362272731;5713.314402271754;5734.025215713339;5765.88468230633;5768.048272035151;5319.414864364917;5369.785208591613;5392.7573259202645;5401.270828438189;5412.025835349675;5382.3003009610065;6118.162361256178;6230.252369715663;6400;6433.646149461824;6190.659513726608;6254.843628909131;6257.249130104235;6279.71741271653;6287.591306565058;6416.13382193398;6425.58233714807;6427.196600424947;6449.935500644933;6452.193475815523;6196.287426945779;6247;6247.0629405589525;6260.092286618506;6263.248410190624;6156.6921653918025;6197.597156184834;6211.192544969125;6223.782167624111;6241.438270555672;5941.851453713519;6129.295131059986;6158.907616385668;6168.629882207082;6191.81424557264;5828.329742079545;6151.0773383992755;6156.938430615636;6178.845528861814;6190.071489276645;5939.613925094855;5942.910856337072;5959.1657167995645;6041.032113168888;6089.665068421279;6141.385861414002;6152.415520525543;6158.938410616016;6164.506839452743;6185.257769067561;5193.506616871391;5833.658258600461;5877.147813566923;5904.793332233351;6042.609680751727 10:26:21 INFO - PROCESS | 1940 | |1;ext.html;511.7850502788828;515.9896802063961;528.183955788307;543.1933578635723;561.0994354061729;26153.60769588455;34896.30207395854;41486;42529.57470425295;42536.787316063455;43602.78198609011;44053.7797311013;44126;44129.67610971668;44317.113657726826;8480;9520.095590918869;9735.075167859042;9737;9750.658726944523;8642;9261.305402094837;11753.41232938354;11776.293422394641;12156;11829.11281653875;11851.703707407298;11924.28454292741;11933.28400295985;11956.342401167933;8566.443181193203;8781.7804554886;8783.077776833468;11851.703707407298;12047.578334758286;8658.653653853838;11381.146414018982;11729.178957472981;11756.1182911282;12134.878651213552;41205;41713.374299385534;41730;41733.58266417327;41783.7910810444;3279;3418.8119653419053;3462.5844898612077;3474.913127171841;3554.5023696682483;31662.683373166205;31675.36649267002;32206.677933220602;32208.677913220803;32228.677713223038;3233.304839459504;3238.2228265216413;3242.0922141800334;3281.1140991932043;3384.7969121852766;511978;514490;515397;515716.42141789425;515786;62612;62752.058719119246;62816;62941.37058629447;63130;36314.8184259077;36909.446358304645;37031.62968370336;37040.81479592585;37109.44335834964;15986;15993.040417574983;15998.08023037243;16147.515574532783;16194.514164575081;11075.50160242791;11318.037966772916;12191.634250972485;12263.509459621693;12419.565315213924;19957.700634490495;20165.596688066158;20210.595788084156;20217.59564808725;20218.696719549218;6935.791926242221;6991.370776630128;8267.875981860278;8296.336293096538;8332.583370831435;781.7537475695251;977.9853302200472;991.1377101921374;1007.4257673126394;1073.4686330266568;30426;30665;31144.688553114178;31195.68804311973;31623.683763162535;963.6001059560344;1039.1319956918958;1048.5491238767252;1146.1060372909144;1177.4348312809727;15795.526134215992;16032;16061.357545698042;16079.035257884565;16277.372262773628;659617.4229546243;667104;669161;670520;672581.2741872617;55857.720711396185;60456;60546.09180862291;60605.39394606086;60655;1257.0948916779903;1375.9724805503834;1427.871491565764;1433.6344232220927;1447.3631602095045;24775.25674229776;31477.68522314839;31558.526622101144;31676.841615792695;32477.837610811795;50892.74553627208;51118.74440627922;51335;51410;51438.97122057463;524461;529549;529658;530803;533072;23616.7564081412;23733.406664832826;26110.34724131912;26190.95236190531;26249.55627440509;49030.264546031125;49147;49255.50744492509;50099;50297.24554131764;37776.055598610255;39690.40464393094;40467.595324046386;41176.794116029225;41272.380914285706;38417.03957401087;38870;39698.60301396949;40346.9913252171;40588;37544.81227593844;38848.41727374035;39335;39704.60295397009;39750.403743944444;33632.83183584066;34540.827295864365;34542.48186277158;35684.64315356813;35786.28427431489;24742.876285619175;25468.617970730083;25761.484770304112;25931.61102583425;26567.46865062649 10:26:21 INFO - PROCESS | 1940 | |2;jquery.html;370.5775416025731;381.9293430715318;384.94716949144095;394.04837317877343;399.60638770810726;19472.12375443105;21659.025343859525;21745.3476395708;21879.453013674665;21904.342869713895;20239.39281821544;20429.897850510763;21156.57686846262;21210;21441.356759297207;5614.27014488117;5622.8313150605545;6034.396560343975;6054.727537260834;6096.81709548712;3844.2888065707793;4066.3493840985448;4066.369712694537;5950.940490595103;5961.791337303195;5676.290463692038;6000.249968753906;6023;6041.516678665718;6056.848578785543;5918.585699001072;5990.041593345066;6021.909671354933;6025.0661147522205;6045.153678485016;3719.4420836874415;3928.9803550982347;5582.804601838957;5770.826875193751;5772.422757724236;11021.283616564897;12978.351082445934;12985.740285194337;13158.934205329007;14494.637634059127;1774.5120091974682;2080.3238947342174;2137.230596985084;2186.7485239197536;2211.0492488230057;9538.809223815555;9872.851907221397;10095.091441770202;10276.640317588923;10392.220583456196;1900.249401486419;1904.3334832808407;2045.2432599938118;2055.465578949484;2144.9356519304447;117899;123866.38066809608;124471.37764311211;125450;126713.36643316725;11414.828777568344;11643.88356116445;11684.18210725258;11702.239354441934;11836.034928299116;14574.417023318949;14631.122132672073;14656.853431465763;14689.706205875822;14704.33830477631;8412.705555305534;8413.368997325226;8444;8472.983242010874;8603.311735061305;6910.965445172742;8246.505209687439;8252.83494330122;8323.875141872877;8342.29090527312;3807.2575847709986;4515;4561.110583436264;4602.838900638461;4634.814607415733;1575.4013474879473;1785.580388608676;1792.1181787258906;1824.8813827101196;1927.1809480970812;792.8533221354068;858.2790456016976;924.8474001789764;1011.9392836429838;1042.7966546523508;20228;21058.78941210568;21195;21212.363629091153;21272.680909786366;860.3031544449017;910.41733290694;947.3557980573277;968.1818863060703;985.8669079674296;10849.349039057683;11083.980982441615;11206.719832004264;11213.831792523119;11214;594548;594591;596624;600472;611995.9400202971;69137;91202;128827;131882.34058829836;134441.3277933604;891.0421297105637;959.4243453927587;972.6255391674256;999.1906555689915;999.2605471950704;14091.648617086115;18424.723629145305;19246.941418222134;19725.309614163434;19885;48575.75712121417;50191.74904125456;51200.71998199926;53035;54043.18935215896;64319.39201519999;73444.7966561004;76131.59528243328;78414;78735;7027.402670773042;7211.098612673416;8263.462874913112;8320.542370169456;8554.871676924726;7251.601161936144;11213.383263920563;11650.53397864076;13171.419429668502;13390.995675324584;5853.326867410302;10949.233553651167;11381.658550243506;11397.373144476802;11606.24559403636;11073;11269.323840569592;11383.943080284545;11400.429978501208;11572.36352000648;8803.867941981003;11522.711932201762;11671.241369310968;11812.291262524275;11841.407929603658;8201.917980820115;8319.958400207961;8322.16778322163;8467;8529.530875801891;9582.233421326138;10719.303245289344;10803.209446824125;11388.658340249805;11545.249558778652 10:26:21 INFO - PROCESS | 1940 | |3;mootools.html;1419.5102689572093;1594.1949315595623;1612.8387161283872;1851.4445666300114;1977.6044791041795;7952.562609056499;8685.869711954318;8993.595288212038;9002.234810041155;9150.222231110363;8840.33697472689;8972.91027089729;8977.506237156953;8984.371094023421;8991;2082.458560774202;2162.10272736814;2170.815480684144;2366.337425520857;2401.711794584652;1861.236892873922;2186.49710566569;2299.0458959531775;2402.8077753779744;2403.8197135214928;2273.795358417742;2296.8621882687094;2301.470661747799;2304.343262170282;2304.8501847379866;1942.2910637617167;2033.5526184239443;2307;2384.2966324934187;2392.892319845611;2089.686547017944;2319.3853628788384;2389.904403823845;2395.724491683461;2412.3366074329524;8663.263622592087;8756;8760.561971901443;8762.649494020232;8803.031666516677;5240.423553409122;5423.701696406696;5425;5429.375621803503;5432.511074003359;3191.824449655268;3443.5351227584456;3544.131687736495;3626.474161246631;3720.8883733488037;1060.204846365226;1089.3627228071587;1107.4850194659523;1116.6984914073237;1117.5473933057044;9951.20390368776;11651.417429128509;11919.463624136848;11930.821037684442;11937.522499100025;4573.5426457354;4719.882002949954;4811.903761924812;4816.975915120472;4837.927431088537;10176.542055607515;10247.231457640706;10249.795004100026;10267.640632577824;10301.124404425707;5363.624546281801;5367.194920761917;5382.4886635769535;5383.703896285663;5383.865403364947;4322.243607368698;4363.80362883671;4767.880802979953;4808.2787581863;4921.581665558468;3788.905277368088;3987.302222111177;4015.5382131054716;4114.773687447219;4136.565660605654;1104.3429159649995;1261.243254047564;1282.4229096906429;1290.11627035481;1303.1659737767836;861.9138086191331;866.3502373220085;911.9772005699778;951.9666811661558;972.7324985629009;5774.566907481955;5915.733791979371;5922.940770592325;5933.199018132583;5997.790077347359;901.1754244866005;949.3591825517748;954.5942974235921;973.4208146153028;997.0677416615488;3748.9062773430337;3956.248312820555;3975.900602484961;4013.678905687538;4041.6362527372676;10557.736056598493;10876.510557024953;10972.451377431096;10973.725656858483;10974.506147223394;10870;10920.453977301104;10964.671059868217;10967.177461690406;10988.395638239814;6889.586624802528;8238.588070596446;8261.215184557457;8384.622691978875;8398.916010839814;6010.789622363372;6070;6104.511639068953;6130.846728831815;6178.598391104564;9987.850182247126;10216.387016779017;10237.129843963347;10248.180145588185;10251.077403033763;8404.705835295736;9814.411135331902;10054;10454;10454.68635940923;7040.964795176196;7638.1216160140075;7791.026121734783;7804.102528209329;7808.5314881107315;7178.138623365229;7785.922140778746;7843;8051.718189863327;8052.87920681202;7416.295451932057;7533.8116547086765;7556.168821429748;7661.770146895602;7677.846443071219;7179;7385.483016188815;7398.741044063432;7476.813079673052;7557.924420755722;7065.434765218873;7152.284771522806;7159.176694680178;7164.570125792469;7170.139583250043;4882.267659851051;4993.4756850530175;5001.399832020035;5028.748562571784;5097.337346145126;4919.631027672867;4949.421428677519;4974.825881094144;5179.974100129475;5186.299849520417 10:26:21 INFO - PROCESS | 1940 | |4;prototype.html;306.24663328212597;311.2064236197695;314.944884645187;318.83579956322495;322.29417575509615;5092.694438333702;5331.493508116732;5371.301730774999;5384.3807962084375;5405.729713514318;4282.828686852522;4819.734914579697;5046.293518907356;5292.259083728281;5319.175527793189;3767.6059857852565;3818.5035945327068;3969.503812023497;4020.336644453662;4025.859094931678;3562.8752993645226;3800.866969656063;3998.7800670963084;4039.151778126597;4056.6957478189106;3967.7222594418404;4004.819783109767;4007.398890166484;4011.3782363733667;4031.6976226782963;3540.990817616967;3825.5600605930395;3908.8045597720284;3950.1902110067513;4007.3788562772816;3498.8250587470525;3502.8423720932365;3506.912857014334;3507.9824600877087;3518.36669399508;4404.7357158570585;5048.394192696863;5060.544550990392;5081.060003899291;5087.465816089294;4330.891727706801;4384.027187765519;4442.662860159766;4595.310703394518;4605.723656580616;5014.924776128324;5499.780008799643;5509.586780991442;5520.91718624221;5521.723913804293;3849.5957924418094;3895.7078219133678;3900.4929359183407;3906.1211227473877;3909.765414075136;4911.729854857946;5457.126859702468;5789.928863160287;5824.30108386992;5887.411258874078;3367.4275373186615;3776;3917.804109794556;3945.835978386383;3956.643902048829;3360.277540328792;3882.223555288897;4004.3192657248337;4012.719109662354;4025.879223623296;4392.38506609075;4584.3352713856975;4585.908281834411;4599.505160822746;4635.466921304093;5354.732263386815;5355.4376790436445;5362.578916587107;5610.915836262459;5643.576731745054;1132.8753837077916;1136.3749937534324;1342.778441557132;1378.8624384882312;1379.4894590423671;700.425650966213;800.6077022259051;810.2626609785067;836.4270474724833;852.4331319672349;4762.214234651313;4893.804247830047;4937.7777999990085;5111.591072714174;5115.7442127893455;5130.56344223618;5188.792448302026;5312.184542192644;5535.806246781343;5643.717814109278;4709.398804406518;5066.670666406671;5088.643794934319;5113.488651134931;5198.4801519847715;2548.554003049459;2635.9060989689065;2880.6111174991106;3023.591815104977;3023.833689147074;5472.890542189134;5919.668074683162;5923.318818335861;5967.343592204854;6037.78867739627;5419.972900135474;5489.423610520837;5841.649501029951;5955.434233747787;5971.074483454984;5124.461931497139;5542.806001789918;5972.850678733066;5984.850378740479;6042.546808989343;5243.172484086562;5381.730913454233;5425.558498675533;5637.661740295595;5825.242718446663;5624.6625202487985;5625.718714064362;5654.830355089354;5710.486056254957;5719.48524632785;5091.66904151229;5610.410906854885;5887.61730487517;5932.762689492544;5972.177806204119;3281.245313577843;3695.1316440636965;3813.504244448204;3837.4819399380726;3856.8842934712;4752.524747525358;5116.820911268088;5702.60081794287;5753.108268218432;5823.301203855564;4648.140094082605;5042.319286896222;5594.76915078682;5601.271834661468;5602.663840169603;5366.946330536644;5589.664620122806;5595.356533998643;5611.80358687444;5656.2929633795775;4611.7317737622425;5307.840764777063;5425.484578964992;5434.673919564839;5482.862928426821;4754.787529180037;4856.465788763304;5447.618666693294;5491.807786727445;5654.6324488908085;4686.437627484724;4875.24433712775;4894.2658601210105;5579.218909352775;5581.5255703265675 10:26:21 INFO - PROCESS | 1940 | |5;yui.html;740.8333125046864;852.7441767469758;853.8078932240243;862.9050804411513;882.5807741322868;14660.047096938692;15377.69244615107;15392.153431561259;15411.922940385297;15429;13981.091229070129;14046.157230566174;14057.156570605744;14078.718425631507;14104;687.9071325371074;716.147784136878;717.5909731453074;718.0808565036762;718.7664009197006;712.3553184368155;852.8038551133243;885.8892638420198;886.7694399456155;891.2112780189547;954.3796532254021;956.6842941829179;963.4797209506858;964.4936408385619;964.864918911353;938.3900464697966;952.0859974424561;953.4660590069549;953.4803532074966;956.1251454918742;539.6114797345905;560.2744445942474;711.0471967563439;711.6263961420244;711.8825393810015;12242;12313.815292770616;12314.199577027464;13556.051076424654;14000.719985600232;13622.523211687543;14055.578332650037;14154.716905661931;14193.645158871008;14361.994660373777;11214.271072380268;11648.359340236284;11660.533578656843;11870.52517899283;11875.228110172897;3769.5288088988877;3793.886183414502;3830.7701537907815;3964.444977703124;3974.642282194616;83097.16902830955;84609.57695211546;85236.72144917831;85433.14566854315;86206.56896715538;10540;10607.045365917105;10623.628173013907;10771.084457821173;11055.115590752881;20576.691349629768;20626;20630;20631.69052464214;20638;7822.413319001098;7929.088154862149;7955.801104972306;7994.160613135653;8098.392620553482;4394.868153955386;4448.110377924428;4569.520200378913;4581.931271030937;4707.082118986765;2994.3412449261127;3078.7229149376217;3084.0901933929545;3087.7529797616135;3090.6136732908385;647.97732079377;652.4095693397483;655.0992385469979;663.7643636509066;674.3930462583714;536.1689381458723;612.6875293600207;679.1578442730978;697.2783169419627;705.530822003362;9156.771080723036;9551.188149007274;9578.760530986641;9583.520823958774;9690.612375505041;679.5650783498564;682.6962001909103;682.9043933849265;689.1282527602586;692.6017539914468;4202.3696445532805;4355.542668019875;4404.427424434803;4433.667474939392;4516.029053753393;84777;90520;91811.54094229486;91850.54074729717;92587.53706231425;73942.8908566372;75706.62146689341;77602.05994850061;80277.19722802813;81447.7782833258;61999;71092;73197.26802732011;73393.53212935712;74306.6284668584;8720.869186962327;9045.321600880092;9070.546472676471;9112.043235460182;9156.126767936128;42999;43412;44014.779926101444;45242.32136517889;45488.862778430805;59347;60008;61008.69495652493;61083;61678.69160654348;10136.391816491034;12143.75712485763;12568.622941311774;12846.550370736979;13326.200427974352;14236.217008064656;14391;14422.495212667507;14480.782788257973;15062.698746025237;13425.865741342852;13473.730525389241;13493.797593035915;13694;13753.656158596115;13444.663883402602;13464.528741494001;13593.932030339785;13920.860791391957;14048.929755351157;13023.479060837839;13129.803052954023;13158.638891507075;13253.403596838358;13266.933665331611;10274.640387586398;10440.582376704846;10441.582336706446;10616.734581635521;10622.044016038593;10411.895881041093;10707.325803372474;10771.46142692878;10796.298240614335;10872.293300935415 10:26:21 INFO - PROCESS | 1940 | __end_tp_report 10:26:21 INFO - PROCESS | 1940 | __start_cc_report 10:26:21 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1085 10:26:21 INFO - PROCESS | 1940 | __end_cc_report 10:26:21 INFO - PROCESS | 1940 | __startTimestamp1464801981884__endTimestamp 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | ------- Summary: start ------- 10:26:21 INFO - PROCESS | 1940 | Number of tests: 6 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5736.21 Median:5904.79 stddev:652.62 (11.1%) stddev-sans-first:629.49 10:26:21 INFO - PROCESS | 1940 | Values: 3449.4 3463.4 3484.1 3509.8 3537.9 5640.9 5690.4 5721.5 5755.4 5804.7 5689.7 5696.1 5706.1 5827.1 5836.1 5088.0 5133.5 5175.3 5183.4 5183.8 5183.9 5207.2 5210.8 5241.1 5256.6 5112.6 5158.3 5174.4 5222.5 5254.9 5201.2 5217.7 5218.6 5225.3 5225.3 4394.0 5047.2 5152.6 5166.7 5192.2 5582.3 5603.5 5651.2 5660.1 5703.5 6200.7 6205.5 6212.2 6244.7 6276.0 5252.9 6221.4 6257.1 6286.7 6313.2 5708.6 5759.4 5760.4 5829.1 5870.9 6318.4 6354.7 6363.8 6377.8 6383.0 6204.8 6207.0 6216.0 6216.4 6240.5 6138.4 6194.9 6202.3 6256.3 6288.1 5385.3 6166.7 6180.9 6196.5 6260.9 6145.7 6177.4 6228.2 6258.6 6279.4 4867.2 4975.8 4986.7 4987.6 5017.8 3807.7 4404.8 4429.1 4450.3 4513.6 5704.6 5715.1 5719.6 5727.5 5780.3 6168.2 6284.3 6288.9 6309.4 6324.8 5681.9 5713.3 5734.0 5765.9 5768.0 5319.4 5369.8 5392.8 5401.3 5412.0 5382.3 6118.2 6230.3 6400.0 6433.6 6190.7 6254.8 6257.2 6279.7 6287.6 6416.1 6425.6 6427.2 6449.9 6452.2 6196.3 6247.0 6247.1 6260.1 6263.2 6156.7 6197.6 6211.2 6223.8 6241.4 5941.9 6129.3 6158.9 6168.6 6191.8 5828.3 6151.1 6156.9 6178.8 6190.1 5939.6 5942.9 5959.2 6041.0 6089.7 6141.4 6152.4 6158.9 6164.5 6185.3 5193.5 5833.7 5877.1 5904.8 6042.6 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:70247.48 Median:37075.13 stddev:153590.96 (414.3%) stddev-sans-first:153932.58 10:26:21 INFO - PROCESS | 1940 | Values: 511.8 516.0 528.2 543.2 561.1 26153.6 34896.3 41486.0 42529.6 42536.8 43602.8 44053.8 44126.0 44129.7 44317.1 8480.0 9520.1 9735.1 9737.0 9750.7 8642.0 9261.3 11753.4 11776.3 12156.0 11829.1 11851.7 11924.3 11933.3 11956.3 8566.4 8781.8 8783.1 11851.7 12047.6 8658.7 11381.1 11729.2 11756.1 12134.9 41205.0 41713.4 41730.0 41733.6 41783.8 3279.0 3418.8 3462.6 3474.9 3554.5 31662.7 31675.4 32206.7 32208.7 32228.7 3233.3 3238.2 3242.1 3281.1 3384.8 511978.0 514490.0 515397.0 515716.4 515786.0 62612.0 62752.1 62816.0 62941.4 63130.0 36314.8 36909.4 37031.6 37040.8 37109.4 15986.0 15993.0 15998.1 16147.5 16194.5 11075.5 11318.0 12191.6 12263.5 12419.6 19957.7 20165.6 20210.6 20217.6 20218.7 6935.8 6991.4 8267.9 8296.3 8332.6 781.8 978.0 991.1 1007.4 1073.5 30426.0 30665.0 31144.7 31195.7 31623.7 963.6 1039.1 1048.5 1146.1 1177.4 15795.5 16032.0 16061.4 16079.0 16277.4 659617.4 667104.0 669161.0 670520.0 672581.3 55857.7 60456.0 60546.1 60605.4 60655.0 1257.1 1376.0 1427.9 1433.6 1447.4 24775.3 31477.7 31558.5 31676.8 32477.8 50892.7 51118.7 51335.0 51410.0 51439.0 524461.0 529549.0 529658.0 530803.0 533072.0 23616.8 23733.4 26110.3 26191.0 26249.6 49030.3 49147.0 49255.5 50099.0 50297.2 37776.1 39690.4 40467.6 41176.8 41272.4 38417.0 38870.0 39698.6 40347.0 40588.0 37544.8 38848.4 39335.0 39704.6 39750.4 33632.8 34540.8 34542.5 35684.6 35786.3 24742.9 25468.6 25761.5 25931.6 26567.5 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:34182.85 Median:3763.35 stddev:99938.73 (2655.6%) stddev-sans-first:100186.84 10:26:21 INFO - PROCESS | 1940 | Values: 370.6 381.9 384.9 394.0 399.6 19472.1 21659.0 21745.3 21879.5 21904.3 20239.4 20429.9 21156.6 21210.0 21441.4 5614.3 5622.8 6034.4 6054.7 6096.8 3844.3 4066.3 4066.4 5950.9 5961.8 5676.3 6000.2 6023.0 6041.5 6056.8 5918.6 5990.0 6021.9 6025.1 6045.2 3719.4 3929.0 5582.8 5770.8 5772.4 11021.3 12978.4 12985.7 13158.9 14494.6 1774.5 2080.3 2137.2 2186.7 2211.0 9538.8 9872.9 10095.1 10276.6 10392.2 1900.2 1904.3 2045.2 2055.5 2144.9 117899.0 123866.4 124471.4 125450.0 126713.4 11414.8 11643.9 11684.2 11702.2 11836.0 14574.4 14631.1 14656.9 14689.7 14704.3 8412.7 8413.4 8444.0 8473.0 8603.3 6911.0 8246.5 8252.8 8323.9 8342.3 3807.3 4515.0 4561.1 4602.8 4634.8 1575.4 1785.6 1792.1 1824.9 1927.2 792.9 858.3 924.8 1011.9 1042.8 20228.0 21058.8 21195.0 21212.4 21272.7 860.3 910.4 947.4 968.2 985.9 10849.3 11084.0 11206.7 11213.8 11214.0 594548.0 594591.0 596624.0 600472.0 611995.9 69137.0 91202.0 128827.0 131882.3 134441.3 891.0 959.4 972.6 999.2 999.3 14091.6 18424.7 19246.9 19725.3 19885.0 48575.8 50191.7 51200.7 53035.0 54043.2 64319.4 73444.8 76131.6 78414.0 78735.0 7027.4 7211.1 8263.5 8320.5 8554.9 7251.6 11213.4 11650.5 13171.4 13391.0 5853.3 10949.2 11381.7 11397.4 11606.2 11073.0 11269.3 11383.9 11400.4 11572.4 8803.9 11522.7 11671.2 11812.3 11841.4 8201.9 8320.0 8322.2 8467.0 8529.5 9582.2 10719.3 10803.2 11388.7 11545.2 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5734.38 Median:4900.95 stddev:3235.22 (66.0%) stddev-sans-first:3228.05 10:26:21 INFO - PROCESS | 1940 | Values: 1419.5 1594.2 1612.8 1851.4 1977.6 7952.6 8685.9 8993.6 9002.2 9150.2 8840.3 8972.9 8977.5 8984.4 8991.0 2082.5 2162.1 2170.8 2366.3 2401.7 1861.2 2186.5 2299.0 2402.8 2403.8 2273.8 2296.9 2301.5 2304.3 2304.9 1942.3 2033.6 2307.0 2384.3 2392.9 2089.7 2319.4 2389.9 2395.7 2412.3 8663.3 8756.0 8760.6 8762.6 8803.0 5240.4 5423.7 5425.0 5429.4 5432.5 3191.8 3443.5 3544.1 3626.5 3720.9 1060.2 1089.4 1107.5 1116.7 1117.5 9951.2 11651.4 11919.5 11930.8 11937.5 4573.5 4719.9 4811.9 4817.0 4837.9 10176.5 10247.2 10249.8 10267.6 10301.1 5363.6 5367.2 5382.5 5383.7 5383.9 4322.2 4363.8 4767.9 4808.3 4921.6 3788.9 3987.3 4015.5 4114.8 4136.6 1104.3 1261.2 1282.4 1290.1 1303.2 861.9 866.4 912.0 952.0 972.7 5774.6 5915.7 5922.9 5933.2 5997.8 901.2 949.4 954.6 973.4 997.1 3748.9 3956.2 3975.9 4013.7 4041.6 10557.7 10876.5 10972.5 10973.7 10974.5 10870.0 10920.5 10964.7 10967.2 10988.4 6889.6 8238.6 8261.2 8384.6 8398.9 6010.8 6070.0 6104.5 6130.8 6178.6 9987.9 10216.4 10237.1 10248.2 10251.1 8404.7 9814.4 10054.0 10454.0 10454.7 7041.0 7638.1 7791.0 7804.1 7808.5 7178.1 7785.9 7843.0 8051.7 8052.9 7416.3 7533.8 7556.2 7661.8 7677.8 7179.0 7385.5 7398.7 7476.8 7557.9 7065.4 7152.3 7159.2 7164.6 7170.1 4882.3 4993.5 5001.4 5028.7 5097.3 4919.6 4949.4 4974.8 5180.0 5186.3 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4493.96 Median:5063.61 stddev:1385.38 (27.4%) stddev-sans-first:1353.13 10:26:21 INFO - PROCESS | 1940 | Values: 306.2 311.2 314.9 318.8 322.3 5092.7 5331.5 5371.3 5384.4 5405.7 4282.8 4819.7 5046.3 5292.3 5319.2 3767.6 3818.5 3969.5 4020.3 4025.9 3562.9 3800.9 3998.8 4039.2 4056.7 3967.7 4004.8 4007.4 4011.4 4031.7 3541.0 3825.6 3908.8 3950.2 4007.4 3498.8 3502.8 3506.9 3508.0 3518.4 4404.7 5048.4 5060.5 5081.1 5087.5 4330.9 4384.0 4442.7 4595.3 4605.7 5014.9 5499.8 5509.6 5520.9 5521.7 3849.6 3895.7 3900.5 3906.1 3909.8 4911.7 5457.1 5789.9 5824.3 5887.4 3367.4 3776.0 3917.8 3945.8 3956.6 3360.3 3882.2 4004.3 4012.7 4025.9 4392.4 4584.3 4585.9 4599.5 4635.5 5354.7 5355.4 5362.6 5610.9 5643.6 1132.9 1136.4 1342.8 1378.9 1379.5 700.4 800.6 810.3 836.4 852.4 4762.2 4893.8 4937.8 5111.6 5115.7 5130.6 5188.8 5312.2 5535.8 5643.7 4709.4 5066.7 5088.6 5113.5 5198.5 2548.6 2635.9 2880.6 3023.6 3023.8 5472.9 5919.7 5923.3 5967.3 6037.8 5420.0 5489.4 5841.6 5955.4 5971.1 5124.5 5542.8 5972.9 5984.9 6042.5 5243.2 5381.7 5425.6 5637.7 5825.2 5624.7 5625.7 5654.8 5710.5 5719.5 5091.7 5610.4 5887.6 5932.8 5972.2 3281.2 3695.1 3813.5 3837.5 3856.9 4752.5 5116.8 5702.6 5753.1 5823.3 4648.1 5042.3 5594.8 5601.3 5602.7 5366.9 5589.7 5595.4 5611.8 5656.3 4611.7 5307.8 5425.5 5434.7 5482.9 4754.8 4856.5 5447.6 5491.8 5654.6 4686.4 4875.2 4894.3 5579.2 5581.5 10:26:21 INFO - PROCESS | 1940 | 10:26:21 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:18488.25 Median:45365.59 stddev:25182.78 (55.5%) stddev-sans-first:25218.17 10:26:21 INFO - PROCESS | 1940 | Values: 740.8 852.7 853.8 862.9 882.6 14660.0 15377.7 15392.2 15411.9 15429.0 13981.1 14046.2 14057.2 14078.7 14104.0 687.9 716.1 717.6 718.1 718.8 712.4 852.8 885.9 886.8 891.2 954.4 956.7 963.5 964.5 964.9 938.4 952.1 953.5 953.5 956.1 539.6 560.3 711.0 711.6 711.9 12242.0 12313.8 12314.2 13556.1 14000.7 13622.5 14055.6 14154.7 14193.6 14362.0 11214.3 11648.4 11660.5 11870.5 11875.2 3769.5 3793.9 3830.8 3964.4 3974.6 83097.2 84609.6 85236.7 85433.1 86206.6 10540.0 10607.0 10623.6 10771.1 11055.1 20576.7 20626.0 20630.0 20631.7 20638.0 7822.4 7929.1 7955.8 7994.2 8098.4 4394.9 4448.1 4569.5 4581.9 4707.1 2994.3 3078.7 3084.1 3087.8 3090.6 648.0 652.4 655.1 663.8 674.4 536.2 612.7 679.2 697.3 705.5 9156.8 9551.2 9578.8 9583.5 9690.6 679.6 682.7 682.9 689.1 692.6 4202.4 4355.5 4404.4 4433.7 4516.0 84777.0 90520.0 91811.5 91850.5 92587.5 73942.9 75706.6 77602.1 80277.2 81447.8 61999.0 71092.0 73197.3 73393.5 74306.6 8720.9 9045.3 9070.5 9112.0 9156.1 42999.0 43412.0 44014.8 45242.3 45488.9 59347.0 60008.0 61008.7 61083.0 61678.7 10136.4 12143.8 12568.6 12846.6 13326.2 14236.2 14391.0 14422.5 14480.8 15062.7 13425.9 13473.7 13493.8 13694.0 13753.7 13444.7 13464.5 13593.9 13920.9 14048.9 13023.5 13129.8 13158.6 13253.4 13266.9 10274.6 10440.6 10441.6 10616.7 10622.0 10411.9 10707.3 10771.5 10796.3 10872.3 10:26:21 INFO - PROCESS | 1940 | -------- Summary: end -------- 10:26:21 INFO - PROCESS | 1940 | 10:26:22 INFO - TEST-INFO | 1940: exit 0 10:26:22 INFO - TEST-OK | dromaeo_css | took 1116560ms 10:26:22 INFO - TEST-START | kraken 10:26:22 INFO - operating with platform_type : mac_ 10:26:22 INFO - Initialising browser for kraken test... 10:26:22 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpFZByBT/profile http://localhost:52620/getInfo.html) 10:26:24 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 10:26:24 INFO - PROCESS | 1943 | colorDepth:24 10:26:24 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 10:26:24 INFO - PROCESS | 1943 | __metrics 10:26:24 INFO - PROCESS | 1943 | 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" 10:26:24 ERROR - PROCESS | 1943 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:26:25 INFO - TEST-INFO | 1943: exit 0 10:26:25 INFO - Browser initialized. 10:26:25 INFO - Running cycle 1/1 for kraken test... 10:26:25 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpFZByBT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:26:37 INFO - PROCESS | 1944 | RSS: Main: 241864704 10:26:37 INFO - PROCESS | 1944 | 10:26:40 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52620/tests/kraken/driver.html?testName=audio-beat-detection) 10:26:41 INFO - PROCESS | 1944 | RSS: Main: 291500032 10:26:41 INFO - PROCESS | 1944 | 10:26:43 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52620/tests/kraken/driver.html?testName=audio-dft) 10:26:44 INFO - PROCESS | 1944 | RSS: Main: 302247936 10:26:44 INFO - PROCESS | 1944 | 10:26:47 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52620/tests/kraken/driver.html?testName=audio-fft) 10:26:48 INFO - PROCESS | 1944 | RSS: Main: 385331200 10:26:48 INFO - PROCESS | 1944 | 10:26:50 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52620/tests/kraken/driver.html?testName=audio-oscillator) 10:26:51 INFO - PROCESS | 1944 | RSS: Main: 287674368 10:26:51 INFO - PROCESS | 1944 | 10:26:53 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52620/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:26:54 INFO - PROCESS | 1944 | RSS: Main: 291229696 10:26:54 INFO - PROCESS | 1944 | 10:26:56 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52620/tests/kraken/driver.html?testName=imaging-darkroom) 10:26:57 INFO - PROCESS | 1944 | RSS: Main: 327827456 10:26:57 INFO - PROCESS | 1944 | 10:27:00 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52620/tests/kraken/driver.html?testName=imaging-desaturate) 10:27:01 INFO - PROCESS | 1944 | RSS: Main: 330940416 10:27:01 INFO - PROCESS | 1944 | 10:27:03 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52620/tests/kraken/driver.html?testName=json-parse-financial) 10:27:04 INFO - PROCESS | 1944 | RSS: Main: 327794688 10:27:04 INFO - PROCESS | 1944 | 10:27:05 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52620/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:27:06 INFO - PROCESS | 1944 | RSS: Main: 310034432 10:27:06 INFO - PROCESS | 1944 | 10:27:08 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:27:09 INFO - PROCESS | 1944 | RSS: Main: 317136896 10:27:09 INFO - PROCESS | 1944 | 10:27:11 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:27:12 INFO - PROCESS | 1944 | RSS: Main: 320532480 10:27:12 INFO - PROCESS | 1944 | 10:27:15 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:27:15 INFO - PROCESS | 1944 | RSS: Main: 325074944 10:27:15 INFO - PROCESS | 1944 | 10:27:18 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:27:19 INFO - PROCESS | 1944 | RSS: Main: 326459392 10:27:19 INFO - PROCESS | 1944 | 10:27:20 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52620/tests/kraken/driver.html?testName=ai-astar) 10:27:21 INFO - PROCESS | 1944 | RSS: Main: 370049024 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | __start_tp_report 10:27:21 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 10:27:21 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 10:27:21 INFO - PROCESS | 1944 | |i|pagename|runs| 10:27:21 INFO - PROCESS | 1944 | |0;ai-astar;114;107;114;112;113;113;113;113;113;113 10:27:21 INFO - PROCESS | 1944 | |1;audio-beat-detection;158;124;141;202;141;121;142;116;153;119 10:27:21 INFO - PROCESS | 1944 | |2;audio-dft;183;181;184;187;184;182;185;184;183;181 10:27:21 INFO - PROCESS | 1944 | |3;audio-fft;81;78;57;89;59;57;65;62;97;57 10:27:21 INFO - PROCESS | 1944 | |4;audio-oscillator;88;89;94;86;106;55;114;102;98;136 10:27:21 INFO - PROCESS | 1944 | |5;imaging-gaussian-blur;88;83;87;85;90;129;124;84;87;84 10:27:21 INFO - PROCESS | 1944 | |6;imaging-darkroom;93;94;94;94;92;92;93;106;149;93 10:27:21 INFO - PROCESS | 1944 | |7;imaging-desaturate;84;85;84;84;84;85;84;84;84;84 10:27:21 INFO - PROCESS | 1944 | |8;json-parse-financial;56;54;54;55;54;56;54;55;53;56 10:27:21 INFO - PROCESS | 1944 | |9;json-stringify-tinderbox;65;73;74;76;70;70;68;71;70;70 10:27:21 INFO - PROCESS | 1944 | |10;stanford-crypto-aes;86;95;107;108;102;86;85;88;122;87 10:27:21 INFO - PROCESS | 1944 | |11;stanford-crypto-ccm;139;140;137;136;137;146;144;143;153;148 10:27:21 INFO - PROCESS | 1944 | |12;stanford-crypto-pbkdf2;167;167;165;165;165;165;165;163;157;164 10:27:21 INFO - PROCESS | 1944 | |13;stanford-crypto-sha256-iterative;67;66;67;67;67;67;90;83;72;65 10:27:21 INFO - PROCESS | 1944 | __end_tp_report 10:27:21 INFO - PROCESS | 1944 | __start_cc_report 10:27:21 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,1937 10:27:21 INFO - PROCESS | 1944 | __end_cc_report 10:27:21 INFO - PROCESS | 1944 | __startTimestamp1464802041154__endTimestamp 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | ------- Summary: start ------- 10:27:21 INFO - PROCESS | 1944 | Number of tests: 14 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:2.01 (1.8%) stddev-sans-first:2.06 10:27:21 INFO - PROCESS | 1944 | Values: 114.0 107.0 114.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:141.50 stddev:25.71 (18.2%) stddev-sans-first:26.58 10:27:21 INFO - PROCESS | 1944 | Values: 158.0 124.0 141.0 202.0 141.0 121.0 142.0 116.0 153.0 119.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.84 (1.0%) stddev-sans-first:1.94 10:27:21 INFO - PROCESS | 1944 | Values: 183.0 181.0 184.0 187.0 184.0 182.0 185.0 184.0 183.0 181.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#3] audio-fft Cycles:10 Average:70.20 Median:71.50 stddev:14.88 (20.8%) stddev-sans-first:15.26 10:27:21 INFO - PROCESS | 1944 | Values: 81.0 78.0 57.0 89.0 59.0 57.0 65.0 62.0 97.0 57.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:87.00 stddev:20.96 (24.1%) stddev-sans-first:21.99 10:27:21 INFO - PROCESS | 1944 | Values: 88.0 89.0 94.0 86.0 106.0 55.0 114.0 102.0 98.0 136.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:86.00 stddev:17.25 (20.1%) stddev-sans-first:18.15 10:27:21 INFO - PROCESS | 1944 | Values: 88.0 83.0 87.0 85.0 90.0 129.0 124.0 84.0 87.0 84.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:93.00 stddev:17.70 (19.0%) stddev-sans-first:18.59 10:27:21 INFO - PROCESS | 1944 | Values: 93.0 94.0 94.0 94.0 92.0 92.0 93.0 106.0 149.0 93.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 10:27:21 INFO - PROCESS | 1944 | Values: 84.0 85.0 84.0 84.0 84.0 85.0 84.0 84.0 84.0 84.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.06 (1.9%) stddev-sans-first:1.01 10:27:21 INFO - PROCESS | 1944 | Values: 56.0 54.0 54.0 55.0 54.0 56.0 54.0 55.0 53.0 56.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:3.09 (4.4%) stddev-sans-first:2.50 10:27:21 INFO - PROCESS | 1944 | Values: 65.0 73.0 74.0 76.0 70.0 70.0 68.0 71.0 70.0 70.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#10] stanford-crypto-aes Cycles:10 Average:96.60 Median:86.00 stddev:12.65 (14.7%) stddev-sans-first:12.82 10:27:21 INFO - PROCESS | 1944 | Values: 86.0 95.0 107.0 108.0 102.0 86.0 85.0 88.0 122.0 87.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:143.50 stddev:5.54 (3.9%) stddev-sans-first:5.74 10:27:21 INFO - PROCESS | 1944 | Values: 139.0 140.0 137.0 136.0 137.0 146.0 144.0 143.0 153.0 148.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:165.00 stddev:2.83 (1.7%) stddev-sans-first:2.83 10:27:21 INFO - PROCESS | 1944 | Values: 167.0 167.0 165.0 165.0 165.0 165.0 165.0 163.0 157.0 164.0 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - PROCESS | 1944 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:67.00 stddev:8.48 (12.7%) stddev-sans-first:8.86 10:27:21 INFO - PROCESS | 1944 | Values: 67.0 66.0 67.0 67.0 67.0 67.0 90.0 83.0 72.0 65.0 10:27:21 INFO - PROCESS | 1944 | -------- Summary: end -------- 10:27:21 INFO - PROCESS | 1944 | 10:27:21 INFO - TEST-INFO | 1944: exit 0 10:27:21 INFO - TEST-OK | kraken | took 59517ms 10:27:21 INFO - SUITE-END | took 1176s 10:27:22 INFO - Completed test suite (00:19:36) 10:27:22 INFO - javascript benchmark 10:27:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3449.3963556377626, 3463.3939060664393, 3484.059303987924, 3509.771864828784, 3537.893863184105, 5640.887182256362, 5690.430956904307, 5721.456461636138, 5755.366909639942, 5804.73878675459, 5689.687067211302, 5696.060150075233, 5706.087026075828, 5827.125931110325, 5836.124581312794, 5087.982403519292, 5133.512316329943, 5175.327207463025, 5183.429822719498, 5183.792648294064, 5183.922241166386, 5207.244949482323, 5210.7655155517905, 5241.135212689921, 5256.605754568403, 5112.590992720573, 5158.303629010074, 5174.404943431516, 5222.451642577532, 5254.868628284305, 5201.1678131498775, 5217.660852044605, 5218.582513398919, 5225.268462415227, 5225.268462415227, 4393.978030109861, 5047.217681259411, 5152.613553983429, 5166.664166829144, 5192.221166825007, 5582.27430434041, 5603.523700485463, 5651.1523271509195, 5660.122680984413, 5703.458171473703, 6200.658963756991, 6205.472534834545, 6212.161358216628, 6244.656543890084, 6275.968620156916, 5252.894942101137, 6221.4089661482085, 6257.124002639634, 6286.685665716695, 6313.179286692746, 5708.600397972144, 5759.366469688331, 5760.36635970043, 5829.125631155361, 5870.853228669274, 6318.4313411793155, 6354.6822658867795, 6363.840903977438, 6377.808665740035, 6383.0, 6204.7828326008375, 6207.0, 6216.036514340284, 6216.378362163748, 6240.53196010292, 6138.388398386059, 6194.907076393858, 6202.34875338092, 6256.343083976208, 6288.088227207029, 5385.272988146628, 6166.660833654192, 6180.907286390708, 6196.47329976948, 6260.937390626126, 6145.692715364214, 6177.351378105324, 6228.190335256388, 6258.561900666909, 6279.3720627937755, 4867.221244600918, 4975.825846095369, 4986.675866068694, 4987.600991920638, 5017.799288028511, 3807.7334586578672, 4404.757738324423, 4429.069895322018, 4450.287953927421, 4513.638908887281, 5704.572157088235, 5715.142728590745, 5719.628224165416, 5727.541796656257, 5780.335261444903, 6168.228971378578, 6284.277308109626, 6288.874222515524, 6309.400606942425, 6324.841878953063, 5681.886362272731, 5713.314402271754, 5734.025215713339, 5765.88468230633, 5768.048272035151, 5319.414864364917, 5369.785208591613, 5392.7573259202645, 5401.270828438189, 5412.025835349675, 5382.3003009610065, 6118.162361256178, 6230.252369715663, 6400.0, 6433.646149461824, 6190.659513726608, 6254.843628909131, 6257.249130104235, 6279.71741271653, 6287.591306565058, 6416.13382193398, 6425.58233714807, 6427.196600424947, 6449.935500644933, 6452.193475815523, 6196.287426945779, 6247.0, 6247.0629405589525, 6260.092286618506, 6263.248410190624, 6156.6921653918025, 6197.597156184834, 6211.192544969125, 6223.782167624111, 6241.438270555672, 5941.851453713519, 6129.295131059986, 6158.907616385668, 6168.629882207082, 6191.81424557264, 5828.329742079545, 6151.0773383992755, 6156.938430615636, 6178.845528861814, 6190.071489276645, 5939.613925094855, 5942.910856337072, 5959.1657167995645, 6041.032113168888, 6089.665068421279, 6141.385861414002, 6152.415520525543, 6158.938410616016, 6164.506839452743, 6185.257769067561, 5193.506616871391, 5833.658258600461, 5877.147813566923, 5904.793332233351, 6042.609680751727], "value": 5695.360209805192, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.7850502788828, 515.9896802063961, 528.183955788307, 543.1933578635723, 561.0994354061729, 26153.60769588455, 34896.30207395854, 41486.0, 42529.57470425295, 42536.787316063455, 43602.78198609011, 44053.7797311013, 44126.0, 44129.67610971668, 44317.113657726826, 8480.0, 9520.095590918869, 9735.075167859042, 9737.0, 9750.658726944523, 8642.0, 9261.305402094837, 11753.41232938354, 11776.293422394641, 12156.0, 11829.11281653875, 11851.703707407298, 11924.28454292741, 11933.28400295985, 11956.342401167933, 8566.443181193203, 8781.7804554886, 8783.077776833468, 11851.703707407298, 12047.578334758286, 8658.653653853838, 11381.146414018982, 11729.178957472981, 11756.1182911282, 12134.878651213552, 41205.0, 41713.374299385534, 41730.0, 41733.58266417327, 41783.7910810444, 3279.0, 3418.8119653419053, 3462.5844898612077, 3474.913127171841, 3554.5023696682483, 31662.683373166205, 31675.36649267002, 32206.677933220602, 32208.677913220803, 32228.677713223038, 3233.304839459504, 3238.2228265216413, 3242.0922141800334, 3281.1140991932043, 3384.7969121852766, 511978.0, 514490.0, 515397.0, 515716.42141789425, 515786.0, 62612.0, 62752.058719119246, 62816.0, 62941.37058629447, 63130.0, 36314.8184259077, 36909.446358304645, 37031.62968370336, 37040.81479592585, 37109.44335834964, 15986.0, 15993.040417574983, 15998.08023037243, 16147.515574532783, 16194.514164575081, 11075.50160242791, 11318.037966772916, 12191.634250972485, 12263.509459621693, 12419.565315213924, 19957.700634490495, 20165.596688066158, 20210.595788084156, 20217.59564808725, 20218.696719549218, 6935.791926242221, 6991.370776630128, 8267.875981860278, 8296.336293096538, 8332.583370831435, 781.7537475695251, 977.9853302200472, 991.1377101921374, 1007.4257673126394, 1073.4686330266568, 30426.0, 30665.0, 31144.688553114178, 31195.68804311973, 31623.683763162535, 963.6001059560344, 1039.1319956918958, 1048.5491238767252, 1146.1060372909144, 1177.4348312809727, 15795.526134215992, 16032.0, 16061.357545698042, 16079.035257884565, 16277.372262773628, 659617.4229546243, 667104.0, 669161.0, 670520.0, 672581.2741872617, 55857.720711396185, 60456.0, 60546.09180862291, 60605.39394606086, 60655.0, 1257.0948916779903, 1375.9724805503834, 1427.871491565764, 1433.6344232220927, 1447.3631602095045, 24775.25674229776, 31477.68522314839, 31558.526622101144, 31676.841615792695, 32477.837610811795, 50892.74553627208, 51118.74440627922, 51335.0, 51410.0, 51438.97122057463, 524461.0, 529549.0, 529658.0, 530803.0, 533072.0, 23616.7564081412, 23733.406664832826, 26110.34724131912, 26190.95236190531, 26249.55627440509, 49030.264546031125, 49147.0, 49255.50744492509, 50099.0, 50297.24554131764, 37776.055598610255, 39690.40464393094, 40467.595324046386, 41176.794116029225, 41272.380914285706, 38417.03957401087, 38870.0, 39698.60301396949, 40346.9913252171, 40588.0, 37544.81227593844, 38848.41727374035, 39335.0, 39704.60295397009, 39750.403743944444, 33632.83183584066, 34540.827295864365, 34542.48186277158, 35684.64315356813, 35786.28427431489, 24742.876285619175, 25468.617970730083, 25761.484770304112, 25931.61102583425, 26567.46865062649], "value": 20226.498529330634, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.5775416025731, 381.9293430715318, 384.94716949144095, 394.04837317877343, 399.60638770810726, 19472.12375443105, 21659.025343859525, 21745.3476395708, 21879.453013674665, 21904.342869713895, 20239.39281821544, 20429.897850510763, 21156.57686846262, 21210.0, 21441.356759297207, 5614.27014488117, 5622.8313150605545, 6034.396560343975, 6054.727537260834, 6096.81709548712, 3844.2888065707793, 4066.3493840985448, 4066.369712694537, 5950.940490595103, 5961.791337303195, 5676.290463692038, 6000.249968753906, 6023.0, 6041.516678665718, 6056.848578785543, 5918.585699001072, 5990.041593345066, 6021.909671354933, 6025.0661147522205, 6045.153678485016, 3719.4420836874415, 3928.9803550982347, 5582.804601838957, 5770.826875193751, 5772.422757724236, 11021.283616564897, 12978.351082445934, 12985.740285194337, 13158.934205329007, 14494.637634059127, 1774.5120091974682, 2080.3238947342174, 2137.230596985084, 2186.7485239197536, 2211.0492488230057, 9538.809223815555, 9872.851907221397, 10095.091441770202, 10276.640317588923, 10392.220583456196, 1900.249401486419, 1904.3334832808407, 2045.2432599938118, 2055.465578949484, 2144.9356519304447, 117899.0, 123866.38066809608, 124471.37764311211, 125450.0, 126713.36643316725, 11414.828777568344, 11643.88356116445, 11684.18210725258, 11702.239354441934, 11836.034928299116, 14574.417023318949, 14631.122132672073, 14656.853431465763, 14689.706205875822, 14704.33830477631, 8412.705555305534, 8413.368997325226, 8444.0, 8472.983242010874, 8603.311735061305, 6910.965445172742, 8246.505209687439, 8252.83494330122, 8323.875141872877, 8342.29090527312, 3807.2575847709986, 4515.0, 4561.110583436264, 4602.838900638461, 4634.814607415733, 1575.4013474879473, 1785.580388608676, 1792.1181787258906, 1824.8813827101196, 1927.1809480970812, 792.8533221354068, 858.2790456016976, 924.8474001789764, 1011.9392836429838, 1042.7966546523508, 20228.0, 21058.78941210568, 21195.0, 21212.363629091153, 21272.680909786366, 860.3031544449017, 910.41733290694, 947.3557980573277, 968.1818863060703, 985.8669079674296, 10849.349039057683, 11083.980982441615, 11206.719832004264, 11213.831792523119, 11214.0, 594548.0, 594591.0, 596624.0, 600472.0, 611995.9400202971, 69137.0, 91202.0, 128827.0, 131882.34058829836, 134441.3277933604, 891.0421297105637, 959.4243453927587, 972.6255391674256, 999.1906555689915, 999.2605471950704, 14091.648617086115, 18424.723629145305, 19246.941418222134, 19725.309614163434, 19885.0, 48575.75712121417, 50191.74904125456, 51200.71998199926, 53035.0, 54043.18935215896, 64319.39201519999, 73444.7966561004, 76131.59528243328, 78414.0, 78735.0, 7027.402670773042, 7211.098612673416, 8263.462874913112, 8320.542370169456, 8554.871676924726, 7251.601161936144, 11213.383263920563, 11650.53397864076, 13171.419429668502, 13390.995675324584, 5853.326867410302, 10949.233553651167, 11381.658550243506, 11397.373144476802, 11606.24559403636, 11073.0, 11269.323840569592, 11383.943080284545, 11400.429978501208, 11572.36352000648, 8803.867941981003, 11522.711932201762, 11671.241369310968, 11812.291262524275, 11841.407929603658, 8201.917980820115, 8319.958400207961, 8322.16778322163, 8467.0, 8529.530875801891, 9582.233421326138, 10719.303245289344, 10803.209446824125, 11388.658340249805, 11545.249558778652], "value": 9200.71786496617, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.5102689572093, 1594.1949315595623, 1612.8387161283872, 1851.4445666300114, 1977.6044791041795, 7952.562609056499, 8685.869711954318, 8993.595288212038, 9002.234810041155, 9150.222231110363, 8840.33697472689, 8972.91027089729, 8977.506237156953, 8984.371094023421, 8991.0, 2082.458560774202, 2162.10272736814, 2170.815480684144, 2366.337425520857, 2401.711794584652, 1861.236892873922, 2186.49710566569, 2299.0458959531775, 2402.8077753779744, 2403.8197135214928, 2273.795358417742, 2296.8621882687094, 2301.470661747799, 2304.343262170282, 2304.8501847379866, 1942.2910637617167, 2033.5526184239443, 2307.0, 2384.2966324934187, 2392.892319845611, 2089.686547017944, 2319.3853628788384, 2389.904403823845, 2395.724491683461, 2412.3366074329524, 8663.263622592087, 8756.0, 8760.561971901443, 8762.649494020232, 8803.031666516677, 5240.423553409122, 5423.701696406696, 5425.0, 5429.375621803503, 5432.511074003359, 3191.824449655268, 3443.5351227584456, 3544.131687736495, 3626.474161246631, 3720.8883733488037, 1060.204846365226, 1089.3627228071587, 1107.4850194659523, 1116.6984914073237, 1117.5473933057044, 9951.20390368776, 11651.417429128509, 11919.463624136848, 11930.821037684442, 11937.522499100025, 4573.5426457354, 4719.882002949954, 4811.903761924812, 4816.975915120472, 4837.927431088537, 10176.542055607515, 10247.231457640706, 10249.795004100026, 10267.640632577824, 10301.124404425707, 5363.624546281801, 5367.194920761917, 5382.4886635769535, 5383.703896285663, 5383.865403364947, 4322.243607368698, 4363.80362883671, 4767.880802979953, 4808.2787581863, 4921.581665558468, 3788.905277368088, 3987.302222111177, 4015.5382131054716, 4114.773687447219, 4136.565660605654, 1104.3429159649995, 1261.243254047564, 1282.4229096906429, 1290.11627035481, 1303.1659737767836, 861.9138086191331, 866.3502373220085, 911.9772005699778, 951.9666811661558, 972.7324985629009, 5774.566907481955, 5915.733791979371, 5922.940770592325, 5933.199018132583, 5997.790077347359, 901.1754244866005, 949.3591825517748, 954.5942974235921, 973.4208146153028, 997.0677416615488, 3748.9062773430337, 3956.248312820555, 3975.900602484961, 4013.678905687538, 4041.6362527372676, 10557.736056598493, 10876.510557024953, 10972.451377431096, 10973.725656858483, 10974.506147223394, 10870.0, 10920.453977301104, 10964.671059868217, 10967.177461690406, 10988.395638239814, 6889.586624802528, 8238.588070596446, 8261.215184557457, 8384.622691978875, 8398.916010839814, 6010.789622363372, 6070.0, 6104.511639068953, 6130.846728831815, 6178.598391104564, 9987.850182247126, 10216.387016779017, 10237.129843963347, 10248.180145588185, 10251.077403033763, 8404.705835295736, 9814.411135331902, 10054.0, 10454.0, 10454.68635940923, 7040.964795176196, 7638.1216160140075, 7791.026121734783, 7804.102528209329, 7808.5314881107315, 7178.138623365229, 7785.922140778746, 7843.0, 8051.718189863327, 8052.87920681202, 7416.295451932057, 7533.8116547086765, 7556.168821429748, 7661.770146895602, 7677.846443071219, 7179.0, 7385.483016188815, 7398.741044063432, 7476.813079673052, 7557.924420755722, 7065.434765218873, 7152.284771522806, 7159.176694680178, 7164.570125792469, 7170.139583250043, 4882.267659851051, 4993.4756850530175, 5001.399832020035, 5028.748562571784, 5097.337346145126, 4919.631027672867, 4949.421428677519, 4974.825881094144, 5179.974100129475, 5186.299849520417], "value": 4596.441299335381, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.24663328212597, 311.2064236197695, 314.944884645187, 318.83579956322495, 322.29417575509615, 5092.694438333702, 5331.493508116732, 5371.301730774999, 5384.3807962084375, 5405.729713514318, 4282.828686852522, 4819.734914579697, 5046.293518907356, 5292.259083728281, 5319.175527793189, 3767.6059857852565, 3818.5035945327068, 3969.503812023497, 4020.336644453662, 4025.859094931678, 3562.8752993645226, 3800.866969656063, 3998.7800670963084, 4039.151778126597, 4056.6957478189106, 3967.7222594418404, 4004.819783109767, 4007.398890166484, 4011.3782363733667, 4031.6976226782963, 3540.990817616967, 3825.5600605930395, 3908.8045597720284, 3950.1902110067513, 4007.3788562772816, 3498.8250587470525, 3502.8423720932365, 3506.912857014334, 3507.9824600877087, 3518.36669399508, 4404.7357158570585, 5048.394192696863, 5060.544550990392, 5081.060003899291, 5087.465816089294, 4330.891727706801, 4384.027187765519, 4442.662860159766, 4595.310703394518, 4605.723656580616, 5014.924776128324, 5499.780008799643, 5509.586780991442, 5520.91718624221, 5521.723913804293, 3849.5957924418094, 3895.7078219133678, 3900.4929359183407, 3906.1211227473877, 3909.765414075136, 4911.729854857946, 5457.126859702468, 5789.928863160287, 5824.30108386992, 5887.411258874078, 3367.4275373186615, 3776.0, 3917.804109794556, 3945.835978386383, 3956.643902048829, 3360.277540328792, 3882.223555288897, 4004.3192657248337, 4012.719109662354, 4025.879223623296, 4392.38506609075, 4584.3352713856975, 4585.908281834411, 4599.505160822746, 4635.466921304093, 5354.732263386815, 5355.4376790436445, 5362.578916587107, 5610.915836262459, 5643.576731745054, 1132.8753837077916, 1136.3749937534324, 1342.778441557132, 1378.8624384882312, 1379.4894590423671, 700.425650966213, 800.6077022259051, 810.2626609785067, 836.4270474724833, 852.4331319672349, 4762.214234651313, 4893.804247830047, 4937.7777999990085, 5111.591072714174, 5115.7442127893455, 5130.56344223618, 5188.792448302026, 5312.184542192644, 5535.806246781343, 5643.717814109278, 4709.398804406518, 5066.670666406671, 5088.643794934319, 5113.488651134931, 5198.4801519847715, 2548.554003049459, 2635.9060989689065, 2880.6111174991106, 3023.591815104977, 3023.833689147074, 5472.890542189134, 5919.668074683162, 5923.318818335861, 5967.343592204854, 6037.78867739627, 5419.972900135474, 5489.423610520837, 5841.649501029951, 5955.434233747787, 5971.074483454984, 5124.461931497139, 5542.806001789918, 5972.850678733066, 5984.850378740479, 6042.546808989343, 5243.172484086562, 5381.730913454233, 5425.558498675533, 5637.661740295595, 5825.242718446663, 5624.6625202487985, 5625.718714064362, 5654.830355089354, 5710.486056254957, 5719.48524632785, 5091.66904151229, 5610.410906854885, 5887.61730487517, 5932.762689492544, 5972.177806204119, 3281.245313577843, 3695.1316440636965, 3813.504244448204, 3837.4819399380726, 3856.8842934712, 4752.524747525358, 5116.820911268088, 5702.60081794287, 5753.108268218432, 5823.301203855564, 4648.140094082605, 5042.319286896222, 5594.76915078682, 5601.271834661468, 5602.663840169603, 5366.946330536644, 5589.664620122806, 5595.356533998643, 5611.80358687444, 5656.2929633795775, 4611.7317737622425, 5307.840764777063, 5425.484578964992, 5434.673919564839, 5482.862928426821, 4754.787529180037, 4856.465788763304, 5447.618666693294, 5491.807786727445, 5654.6324488908085, 4686.437627484724, 4875.24433712775, 4894.2658601210105, 5579.218909352775, 5581.5255703265675], "value": 4046.245120856556, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.8333125046864, 852.7441767469758, 853.8078932240243, 862.9050804411513, 882.5807741322868, 14660.047096938692, 15377.69244615107, 15392.153431561259, 15411.922940385297, 15429.0, 13981.091229070129, 14046.157230566174, 14057.156570605744, 14078.718425631507, 14104.0, 687.9071325371074, 716.147784136878, 717.5909731453074, 718.0808565036762, 718.7664009197006, 712.3553184368155, 852.8038551133243, 885.8892638420198, 886.7694399456155, 891.2112780189547, 954.3796532254021, 956.6842941829179, 963.4797209506858, 964.4936408385619, 964.864918911353, 938.3900464697966, 952.0859974424561, 953.4660590069549, 953.4803532074966, 956.1251454918742, 539.6114797345905, 560.2744445942474, 711.0471967563439, 711.6263961420244, 711.8825393810015, 12242.0, 12313.815292770616, 12314.199577027464, 13556.051076424654, 14000.719985600232, 13622.523211687543, 14055.578332650037, 14154.716905661931, 14193.645158871008, 14361.994660373777, 11214.271072380268, 11648.359340236284, 11660.533578656843, 11870.52517899283, 11875.228110172897, 3769.5288088988877, 3793.886183414502, 3830.7701537907815, 3964.444977703124, 3974.642282194616, 83097.16902830955, 84609.57695211546, 85236.72144917831, 85433.14566854315, 86206.56896715538, 10540.0, 10607.045365917105, 10623.628173013907, 10771.084457821173, 11055.115590752881, 20576.691349629768, 20626.0, 20630.0, 20631.69052464214, 20638.0, 7822.413319001098, 7929.088154862149, 7955.801104972306, 7994.160613135653, 8098.392620553482, 4394.868153955386, 4448.110377924428, 4569.520200378913, 4581.931271030937, 4707.082118986765, 2994.3412449261127, 3078.7229149376217, 3084.0901933929545, 3087.7529797616135, 3090.6136732908385, 647.97732079377, 652.4095693397483, 655.0992385469979, 663.7643636509066, 674.3930462583714, 536.1689381458723, 612.6875293600207, 679.1578442730978, 697.2783169419627, 705.530822003362, 9156.771080723036, 9551.188149007274, 9578.760530986641, 9583.520823958774, 9690.612375505041, 679.5650783498564, 682.6962001909103, 682.9043933849265, 689.1282527602586, 692.6017539914468, 4202.3696445532805, 4355.542668019875, 4404.427424434803, 4433.667474939392, 4516.029053753393, 84777.0, 90520.0, 91811.54094229486, 91850.54074729717, 92587.53706231425, 73942.8908566372, 75706.62146689341, 77602.05994850061, 80277.19722802813, 81447.7782833258, 61999.0, 71092.0, 73197.26802732011, 73393.53212935712, 74306.6284668584, 8720.869186962327, 9045.321600880092, 9070.546472676471, 9112.043235460182, 9156.126767936128, 42999.0, 43412.0, 44014.779926101444, 45242.32136517889, 45488.862778430805, 59347.0, 60008.0, 61008.69495652493, 61083.0, 61678.69160654348, 10136.391816491034, 12143.75712485763, 12568.622941311774, 12846.550370736979, 13326.200427974352, 14236.217008064656, 14391.0, 14422.495212667507, 14480.782788257973, 15062.698746025237, 13425.865741342852, 13473.730525389241, 13493.797593035915, 13694.0, 13753.656158596115, 13444.663883402602, 13464.528741494001, 13593.932030339785, 13920.860791391957, 14048.929755351157, 13023.479060837839, 13129.803052954023, 13158.638891507075, 13253.403596838358, 13266.933665331611, 10274.640387586398, 10440.582376704846, 10441.582336706446, 10616.734581635521, 10622.044016038593, 10411.895881041093, 10707.325803372474, 10771.46142692878, 10796.298240614335, 10872.293300935415], "value": 7213.380370935393, "unit": "score"}], "name": "dromaeo_css", "value": 7224.7103008076965, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 114.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 124.0, 141.0, 202.0, 141.0, 121.0, 142.0, 116.0, 153.0, 119.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 184.0, 187.0, 184.0, 182.0, 185.0, 184.0, 183.0, 181.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 57.0, 89.0, 59.0, 57.0, 65.0, 62.0, 97.0, 57.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 94.0, 86.0, 106.0, 55.0, 114.0, 102.0, 98.0, 136.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 87.0, 85.0, 90.0, 129.0, 124.0, 84.0, 87.0, 84.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 92.0, 92.0, 93.0, 106.0, 149.0, 93.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 84.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 55.0, 54.0, 56.0, 54.0, 55.0, 53.0, 56.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 74.0, 76.0, 70.0, 70.0, 68.0, 71.0, 70.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 95.0, 107.0, 108.0, 102.0, 86.0, 85.0, 88.0, 122.0, 87.0], "value": 96.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 137.0, 136.0, 137.0, 146.0, 144.0, 143.0, 153.0, 148.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 165.0, 165.0, 165.0, 165.0, 165.0, 163.0, 157.0, 164.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 90.0, 83.0, 72.0, 65.0], "value": 71.1, "unit": "score"}], "name": "kraken", "value": 1482.6, "alertThreshold": 2.0}]} 10:27:22 INFO - Return code: 0 10:27:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:27:22 ERROR - # TBPL SUCCESS # 10:27:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:22 INFO - 2016-06-01 10:27:22.457 screenresolution[1948:17974] starting screenresolution argv=screenresolution get 10:27:22 INFO - 2016-06-01 10:27:22.459 screenresolution[1948:17974] Display 0: 1600x1200x32@60 10:27:22 INFO - 2016-06-01 10:27:22.465 screenresolution[1949:17977] starting screenresolution argv=screenresolution list 10:27:22 INFO - Available Modes on Display 0 10:27:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:22 INFO - Intel Iris: 10:27:22 INFO - Chipset Model: Intel Iris 10:27:22 INFO - Type: GPU 10:27:22 INFO - Bus: Built-In 10:27:22 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:22 INFO - Vendor: Intel (0x8086) 10:27:22 INFO - Device ID: 0x0a2e 10:27:22 INFO - Revision ID: 0x0009 10:27:22 INFO - Displays: 10:27:22 INFO - Display: 10:27:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:22 INFO - Display Serial Number: 1600x1200 60 10:27:22 INFO - Main Display: Yes 10:27:22 INFO - Mirror: Off 10:27:22 INFO - Online: Yes 10:27:22 INFO - Rotation: Supported 10:27:22 INFO - Return code: 0 10:27:22 INFO - Running post-action listener: _resource_record_post_action 10:27:22 INFO - ##### 10:27:22 INFO - ##### Finished run-tests step (success) 10:27:22 INFO - ##### 10:27:22 INFO - Running post-run listener: _resource_record_post_run 10:27:22 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1093632; Write bytes: 696130048; Read time: 10; Write time: 1899 10:27:22 INFO - TinderboxPrint: CPU usage
53.6% 10:27:22 INFO - TinderboxPrint: I/O read bytes / time
1,093,632 / 10 10:27:22 INFO - TinderboxPrint: I/O write bytes / time
696,130,048 / 1,899 10:27:22 INFO - TinderboxPrint: CPU idle
2,249.9 (46.4%) 10:27:22 INFO - TinderboxPrint: CPU system
968.7 (20.0%) 10:27:22 INFO - TinderboxPrint: CPU user
1,631.1 (33.6%) 10:27:22 INFO - TinderboxPrint: Swap in / out
400,756,736 / 0 10:27:22 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395276800; Write bytes: 390424576; Read time: 29614; Write time: 777 10:27:22 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1093632; Write bytes: 292348416; Read time: 10; Write time: 1084 10:27:23 INFO - Running post-run listener: _upload_blobber_files 10:27:23 INFO - Blob upload gear active. 10:27:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:27:23 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 10:27:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:27:23 INFO - (blobuploader) - INFO - Open directory for files ... 10:27:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:27:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:27:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:24 INFO - (blobuploader) - INFO - Done attempting. 10:27:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:27:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:27:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:25 INFO - (blobuploader) - INFO - Done attempting. 10:27:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:27:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:27:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:27 INFO - (blobuploader) - INFO - Done attempting. 10:27:27 INFO - (blobuploader) - INFO - Iteration through files over. 10:27:27 INFO - Return code: 0 10:27:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:27:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:27:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad9d754bb53324ae800b037347f18016539bbea30be26065468ab10abbde83396d16d7d11203f45ac76a7084dba9e647a5e15ad83502b8ea20f61bd1dc477aa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c32390a4f1a0adafff9b6f5396f934149bb0fd4e89b429e18baba59fb40e275d91f157660a002b272b0669c5dd9d90e6ed7df12b6e815f22eda82a8b597894aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98dab57b389c1b86450b209d50f06583c7020bb8ee2f12a20d33e63632d99ebb82f18c900819826b0d55e1f9aa793d2e4a5ed3ba2387fa2486d027c2069f2b48"} 10:27:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:27:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:27:27 INFO - Contents: 10:27:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad9d754bb53324ae800b037347f18016539bbea30be26065468ab10abbde83396d16d7d11203f45ac76a7084dba9e647a5e15ad83502b8ea20f61bd1dc477aa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c32390a4f1a0adafff9b6f5396f934149bb0fd4e89b429e18baba59fb40e275d91f157660a002b272b0669c5dd9d90e6ed7df12b6e815f22eda82a8b597894aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98dab57b389c1b86450b209d50f06583c7020bb8ee2f12a20d33e63632d99ebb82f18c900819826b0d55e1f9aa793d2e4a5ed3ba2387fa2486d027c2069f2b48"} 10:27:27 INFO - Running post-run listener: copy_logs_to_upload_dir 10:27:27 INFO - Copying logs to upload dir... 10:27:27 INFO - mkdir: /builds/slave/test/build/upload/logs 10:27:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.747246 ========= master_lag: 2.76 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-06-01 10:27:30.317927) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 10:27:30.321456) ========= 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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad9d754bb53324ae800b037347f18016539bbea30be26065468ab10abbde83396d16d7d11203f45ac76a7084dba9e647a5e15ad83502b8ea20f61bd1dc477aa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c32390a4f1a0adafff9b6f5396f934149bb0fd4e89b429e18baba59fb40e275d91f157660a002b272b0669c5dd9d90e6ed7df12b6e815f22eda82a8b597894aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98dab57b389c1b86450b209d50f06583c7020bb8ee2f12a20d33e63632d99ebb82f18c900819826b0d55e1f9aa793d2e4a5ed3ba2387fa2486d027c2069f2b48"} build_url:https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011004 build_url: 'https://queue.taskcluster.net/v1/task/I3zfWIXNQ4-LUd0qs1b5Dw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad9d754bb53324ae800b037347f18016539bbea30be26065468ab10abbde83396d16d7d11203f45ac76a7084dba9e647a5e15ad83502b8ea20f61bd1dc477aa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c32390a4f1a0adafff9b6f5396f934149bb0fd4e89b429e18baba59fb40e275d91f157660a002b272b0669c5dd9d90e6ed7df12b6e815f22eda82a8b597894aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98dab57b389c1b86450b209d50f06583c7020bb8ee2f12a20d33e63632d99ebb82f18c900819826b0d55e1f9aa793d2e4a5ed3ba2387fa2486d027c2069f2b48"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 10:27:30.356962) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:27:30.357322) ========= 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.lszXzQ0pfn/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.8rwrX4Hcqd/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005150 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:27:30.406422) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 10:27:30.406787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 10:27:30.410172) ========= ========= Total master_lag: 2.90 =========