builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0055 starttime: 1463808189.43 results: success (0) buildid: 20160520212034 builduid: 37f845c2f7054708a3bac5462d464393 revision: f1f2644d34440fe38c098e323bf570e09197e51a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:09.426544) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:09.426992) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:09.427340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:09.459809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:09.460249) ========= 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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-20 22:23:09-- 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% 29.1M=0s 2016-05-20 22:23:09 (29.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.497917 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:09.976287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:09.976590) ========= 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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.020373 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:10.025597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 22:23:10.025945) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f1f2644d34440fe38c098e323bf570e09197e51a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f1f2644d34440fe38c098e323bf570e09197e51a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CxTvtrbkXc/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.AfCZJHxJGS/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-20 22:23:09,793 truncating revision to first 12 chars 2016-05-20 22:23:09,793 Setting DEBUG logging. 2016-05-20 22:23:09,793 attempt 1/10 2016-05-20 22:23:09,793 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f1f2644d3444?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 22:23:10,859 unpacking tar archive at: mozilla-central-f1f2644d3444/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314840 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 22:23:11.362538) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:11.362836) ========= script_repo_revision: f1f2644d34440fe38c098e323bf570e09197e51a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:11.363284) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:11.363570) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 22:23:11.386308) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-20 22:23:11.386603) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CxTvtrbkXc/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.AfCZJHxJGS/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:11 INFO - MultiFileLogger online at 20160520 22:23:11 in /builds/slave/test 22:23:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:23:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:23:11 INFO - {'append_to_log': False, 22:23:11 INFO - 'base_work_dir': '/builds/slave/test', 22:23:11 INFO - 'blob_upload_branch': 'Firefox', 22:23:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:23:11 INFO - 'branch': 'Firefox', 22:23:11 INFO - 'buildbot_json_path': 'buildprops.json', 22:23:11 INFO - 'config_files': ('talos/mac_config.py',), 22:23:11 INFO - 'default_actions': ('clobber', 22:23:11 INFO - 'read-buildbot-config', 22:23:11 INFO - 'download-and-extract', 22:23:11 INFO - 'populate-webroot', 22:23:11 INFO - 'create-virtualenv', 22:23:11 INFO - 'install', 22:23:11 INFO - 'run-tests'), 22:23:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:23:11 INFO - 'download_minidump_stackwalk': True, 22:23:11 INFO - 'download_symbols': 'ondemand', 22:23:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:23:11 INFO - 'tooltool.py': '/tools/tooltool.py', 22:23:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:23:11 INFO - '/tools/misc-python/virtualenv.py')}, 22:23:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:23:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:23:11 INFO - 'installer_path': 'installer.exe', 22:23:11 INFO - 'log_level': 'info', 22:23:11 INFO - 'log_name': 'talos', 22:23:11 INFO - 'log_to_console': True, 22:23:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:23:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:23:11 INFO - 'opt_config_files': (), 22:23:11 INFO - 'pip_index': False, 22:23:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:11 INFO - 'cmd': ('bash', 22:23:11 INFO - '-c', 22:23:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:11 INFO - 'enabled': True, 22:23:11 INFO - 'halt_on_failure': False, 22:23:11 INFO - 'name': 'check_screen_resolution'},), 22:23:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:11 INFO - 'cmd': ('bash', 22:23:11 INFO - '-c', 22:23:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:11 INFO - 'enabled': True, 22:23:11 INFO - 'halt_on_failure': False, 22:23:11 INFO - 'name': 'check_screen_resolution'},), 22:23:11 INFO - 'run_cmd_checks_enabled': True, 22:23:11 INFO - 'sps_profile': False, 22:23:11 INFO - 'sps_profile_interval': 0, 22:23:11 INFO - 'suite': 'dromaeojs-e10s', 22:23:11 INFO - 'system_bits': '32', 22:23:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:23:11 INFO - 'title': 't-yosemite-r7-0055', 22:23:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:23:11 INFO - 'use_talos_json': True, 22:23:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:23:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:23:11 INFO - 'work_dir': 'build'} 22:23:11 INFO - ##### 22:23:11 INFO - ##### Running clobber step. 22:23:11 INFO - ##### 22:23:11 INFO - Running pre-action listener: _resource_record_pre_action 22:23:11 INFO - Running main action method: clobber 22:23:11 INFO - rmtree: /builds/slave/test/build 22:23:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:23:11 INFO - Running post-action listener: _resource_record_post_action 22:23:11 INFO - ##### 22:23:11 INFO - ##### Finished clobber step (success) 22:23:11 INFO - ##### 22:23:11 INFO - ##### 22:23:11 INFO - ##### Running read-buildbot-config step. 22:23:11 INFO - ##### 22:23:11 INFO - Running pre-action listener: _resource_record_pre_action 22:23:11 INFO - Running main action method: read_buildbot_config 22:23:11 INFO - Using buildbot properties: 22:23:11 INFO - { 22:23:11 INFO - "project": "", 22:23:11 INFO - "product": "firefox", 22:23:11 INFO - "script_repo_revision": "production", 22:23:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:23:11 INFO - "repository": "", 22:23:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:23:11 INFO - "buildid": "20160520212034", 22:23:11 INFO - "pgo_build": "False", 22:23:11 INFO - "basedir": "/builds/slave/test", 22:23:11 INFO - "buildnumber": 3, 22:23:11 INFO - "slavename": "t-yosemite-r7-0055", 22:23:11 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:23:11 INFO - "platform": "yosemite_r7", 22:23:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:23:11 INFO - "branch": "mozilla-central", 22:23:11 INFO - "repo_path": "mozilla-central", 22:23:11 INFO - "revision": "f1f2644d34440fe38c098e323bf570e09197e51a", 22:23:11 INFO - "stage_platform": "macosx64", 22:23:11 INFO - "builduid": "37f845c2f7054708a3bac5462d464393", 22:23:11 INFO - "slavebuilddir": "test" 22:23:11 INFO - } 22:23:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 22:23:11 INFO - Running post-action listener: _resource_record_post_action 22:23:11 INFO - ##### 22:23:11 INFO - ##### Finished read-buildbot-config step (success) 22:23:11 INFO - ##### 22:23:11 INFO - ##### 22:23:11 INFO - ##### Running download-and-extract step. 22:23:11 INFO - ##### 22:23:11 INFO - Running pre-action listener: _resource_record_pre_action 22:23:11 INFO - Running main action method: download_and_extract 22:23:11 INFO - mkdir: /builds/slave/test/build/tests 22:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:11 INFO - https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:23:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:23:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:23:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:13 INFO - Downloaded 1199 bytes. 22:23:13 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:23:13 INFO - Using the following test package requirements: 22:23:13 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:23:13 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 22:23:13 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 22:23:13 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'jsshell-mac.zip'], 22:23:13 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 22:23:13 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:23:13 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 22:23:13 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 22:23:13 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 22:23:13 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:23:13 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 22:23:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:23:13 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')]} 22:23:13 INFO - https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:23:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:23:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:23:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:14 INFO - Downloaded 18630457 bytes. 22:23:14 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'] 22:23:14 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 22:23:18 INFO - Return code: 0 22:23:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:18 INFO - https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:23:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:23:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:23:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:19 INFO - Downloaded 18630457 bytes. 22:23:19 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'] 22:23:19 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 22:23:23 INFO - Return code: 0 22:23: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')]} 22:23:23 INFO - https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:23:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:23:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:23:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:24 INFO - Downloaded 10840359 bytes. 22:23:24 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'] 22:23:24 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 22:23:25 INFO - Return code: 0 22:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:25 INFO - https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:23:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:23:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:23:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 22:23:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RxSGKEWoR4ugOHvrsQykVA/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 22:23:29 INFO - Downloaded 110183040 bytes. 22:23:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:23:29 INFO - mkdir: /builds/slave/test/properties 22:23:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:29 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:29 INFO - Contents: 22:23:29 INFO - build_url:https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:23:31 INFO - Running post-action listener: _resource_record_post_action 22:23:31 INFO - Running post-action listener: set_extra_try_arguments 22:23:31 INFO - ##### 22:23:31 INFO - ##### Finished download-and-extract step (success) 22:23:31 INFO - ##### 22:23:31 INFO - ##### 22:23:31 INFO - ##### Running populate-webroot step. 22:23:31 INFO - ##### 22:23:31 INFO - Running pre-action listener: _resource_record_pre_action 22:23:31 INFO - Running main action method: populate_webroot 22:23:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:31 INFO - '--tppagecycles', 22:23:31 INFO - '7'], 22:23:31 INFO - 'tests': ['tsvgm']}}, 22:23:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['damp', 'tps']}, 22:23:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'tests': ['damp', 'tps']}, 22:23:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['dromaeo_dom']}, 22:23:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['a11yr', 22:23:31 INFO - 'ts_paint', 22:23:31 INFO - 'tpaint', 22:23:31 INFO - 'sessionrestore', 22:23:31 INFO - 'sessionrestore_no_auto_restore', 22:23:31 INFO - 'tabpaint']}, 22:23:31 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:31 INFO - 'ts_paint', 22:23:31 INFO - 'tpaint', 22:23:31 INFO - 'sessionrestore', 22:23:31 INFO - 'sessionrestore_no_auto_restore', 22:23:31 INFO - 'tabpaint']}, 22:23:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:31 INFO - 'ts_paint', 22:23:31 INFO - 'tpaint', 22:23:31 INFO - 'sessionrestore', 22:23:31 INFO - 'sessionrestore_no_auto_restore', 22:23:31 INFO - 'tabpaint']}, 22:23:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:31 INFO - 'ts_paint', 22:23:31 INFO - 'tpaint', 22:23:31 INFO - 'sessionrestore', 22:23:31 INFO - 'sessionrestore_no_auto_restore', 22:23:31 INFO - 'tabpaint']}, 22:23:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['a11yr', 22:23:31 INFO - 'ts_paint', 22:23:31 INFO - 'tpaint', 22:23:31 INFO - 'sessionrestore', 22:23:31 INFO - 'sessionrestore_no_auto_restore', 22:23:31 INFO - 'tabpaint']}, 22:23:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['a11yr', 22:23:31 INFO - 'ts_paint', 22:23:31 INFO - 'tpaint', 22:23:31 INFO - 'sessionrestore', 22:23:31 INFO - 'sessionrestore_no_auto_restore', 22:23:31 INFO - 'tabpaint']}, 22:23:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['tsvgx', 22:23:31 INFO - 'tsvgr_opacity', 22:23:31 INFO - 'tart', 22:23:31 INFO - 'tscrollx', 22:23:31 INFO - 'cart']}, 22:23:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:31 INFO - 'tsvgr_opacity', 22:23:31 INFO - 'tart', 22:23:31 INFO - 'tscrollx', 22:23:31 INFO - 'cart']}, 22:23:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'talos_options': ['--disable-e10s'], 22:23:31 INFO - 'tests': ['tp5o']}, 22:23:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'tests': ['tp5o']}, 22:23:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'talos_options': ['--disable-e10s', 22:23:31 INFO - '--xperf_path', 22:23:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:31 INFO - 'tests': ['tp5n']}, 22:23:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:31 INFO - 'talos_options': ['--xperf_path', 22:23:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:31 INFO - 'tests': ['tp5n']}}, 22:23:31 INFO - 'talos.zip': {'path': '', 22:23:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:31 INFO - Running post-action listener: _resource_record_post_action 22:23:31 INFO - ##### 22:23:31 INFO - ##### Finished populate-webroot step (success) 22:23:31 INFO - ##### 22:23:31 INFO - ##### 22:23:31 INFO - ##### Running create-virtualenv step. 22:23:31 INFO - ##### 22:23:31 INFO - Running pre-action listener: _resource_record_pre_action 22:23:31 INFO - Running main action method: create_virtualenv 22:23:31 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:31 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 22:23:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:31 INFO - Using real prefix '/tools/python27' 22:23:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:31 INFO - Installing distribute.............................................................................................................................................................................................done. 22:23:34 INFO - Installing pip.................done. 22:23:34 INFO - Return code: 0 22:23:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:34 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 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:34 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 22:23:34 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 22:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:34 INFO - 'HOME': '/Users/cltbld', 22:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:34 INFO - 'LOGNAME': 'cltbld', 22:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:34 INFO - 'MOZ_NO_REMOTE': '1', 22:23:34 INFO - 'NO_EM_RESTART': '1', 22:23:34 INFO - 'PAGER': '/bin/cat', 22:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:34 INFO - 'PWD': '/builds/slave/test', 22:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:34 INFO - 'SHELL': '/bin/bash', 22:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:34 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:34 INFO - 'USER': 'cltbld', 22:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:34 INFO - 'XPC_FLAGS': '0x0', 22:23:34 INFO - 'XPC_SERVICE_NAME': '0', 22:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:34 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 22:23:34 INFO - Cleaning up... 22:23:34 INFO - Return code: 0 22:23:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:34 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 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:34 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 22:23:34 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 22:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:34 INFO - 'HOME': '/Users/cltbld', 22:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:34 INFO - 'LOGNAME': 'cltbld', 22:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:34 INFO - 'MOZ_NO_REMOTE': '1', 22:23:34 INFO - 'NO_EM_RESTART': '1', 22:23:34 INFO - 'PAGER': '/bin/cat', 22:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:34 INFO - 'PWD': '/builds/slave/test', 22:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:34 INFO - 'SHELL': '/bin/bash', 22:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:34 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:34 INFO - 'USER': 'cltbld', 22:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:34 INFO - 'XPC_FLAGS': '0x0', 22:23:34 INFO - 'XPC_SERVICE_NAME': '0', 22:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:35 INFO - Downloading/unpacking psutil>=3.1.1 22:23:35 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 22:23:35 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 22:23:35 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 22:23:35 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 22:23:35 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 22:23:35 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 22:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:23:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:38 INFO - Installing collected packages: psutil 22:23:38 INFO - Running setup.py install for psutil 22:23:38 INFO - building 'psutil._psutil_osx' extension 22:23:38 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 22:23:38 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 22:23:38 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 22:23:38 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 22:23:38 INFO - building 'psutil._psutil_posix' extension 22:23:38 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 22:23:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:23:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:23:38 INFO - ^ 22:23:38 INFO - 1 warning generated. 22:23:38 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 22:23:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:38 INFO - Successfully installed psutil 22:23:38 INFO - Cleaning up... 22:23:39 INFO - Return code: 0 22:23:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:23: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 mozsystemmonitor==0.3 22:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:39 INFO - 'HOME': '/Users/cltbld', 22:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:39 INFO - 'LOGNAME': 'cltbld', 22:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:39 INFO - 'MOZ_NO_REMOTE': '1', 22:23:39 INFO - 'NO_EM_RESTART': '1', 22:23:39 INFO - 'PAGER': '/bin/cat', 22:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:39 INFO - 'PWD': '/builds/slave/test', 22:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:39 INFO - 'SHELL': '/bin/bash', 22:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:39 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:39 INFO - 'USER': 'cltbld', 22:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:39 INFO - 'XPC_FLAGS': '0x0', 22:23:39 INFO - 'XPC_SERVICE_NAME': '0', 22:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:23:39 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 22:23:39 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 22:23:39 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 22:23:39 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 22:23:39 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 22:23:39 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 22:23:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:23:41 INFO - Installing collected packages: mozsystemmonitor 22:23:41 INFO - Running setup.py install for mozsystemmonitor 22:23:41 INFO - Successfully installed mozsystemmonitor 22:23:41 INFO - Cleaning up... 22:23:42 INFO - Return code: 0 22:23:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:23:42 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')]} 22:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:42 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')]} 22:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:42 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 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:42 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 22:23:42 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 22:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:42 INFO - 'HOME': '/Users/cltbld', 22:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:42 INFO - 'LOGNAME': 'cltbld', 22:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:42 INFO - 'MOZ_NO_REMOTE': '1', 22:23:42 INFO - 'NO_EM_RESTART': '1', 22:23:42 INFO - 'PAGER': '/bin/cat', 22:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:42 INFO - 'PWD': '/builds/slave/test', 22:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:42 INFO - 'SHELL': '/bin/bash', 22:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:42 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:42 INFO - 'USER': 'cltbld', 22:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:42 INFO - 'XPC_FLAGS': '0x0', 22:23:42 INFO - 'XPC_SERVICE_NAME': '0', 22:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:42 INFO - Downloading/unpacking blobuploader==1.2.4 22:23:42 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 22:23:42 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 22:23:42 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 22:23:42 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 22:23:42 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 22:23:42 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 22:23:44 INFO - Downloading blobuploader-1.2.4.tar.gz 22:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:23:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:23:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:45 INFO - Downloading docopt-0.6.1.tar.gz 22:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:23:45 INFO - Installing collected packages: blobuploader, requests, docopt 22:23:45 INFO - Running setup.py install for blobuploader 22:23:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:23:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:23:46 INFO - Running setup.py install for requests 22:23:46 INFO - Running setup.py install for docopt 22:23:46 INFO - Successfully installed blobuploader requests docopt 22:23:46 INFO - Cleaning up... 22:23:46 INFO - Return code: 0 22:23:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:46 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 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:46 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 22:23:46 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 22:23:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:46 INFO - 'HOME': '/Users/cltbld', 22:23:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:46 INFO - 'LOGNAME': 'cltbld', 22:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:46 INFO - 'MOZ_NO_REMOTE': '1', 22:23:46 INFO - 'NO_EM_RESTART': '1', 22:23:46 INFO - 'PAGER': '/bin/cat', 22:23:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:46 INFO - 'PWD': '/builds/slave/test', 22:23:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:46 INFO - 'SHELL': '/bin/bash', 22:23:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:46 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:46 INFO - 'USER': 'cltbld', 22:23:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:46 INFO - 'XPC_FLAGS': '0x0', 22:23:46 INFO - 'XPC_SERVICE_NAME': '0', 22:23:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-8RLEAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-HBOPnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-4n01qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-huPEQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-6V_gYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-gzFle5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-7uBHnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Bs8xGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:47 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-DWkRFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-F2X8rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-olPtov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-MlVsVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-hmngXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-1tGtqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-k4TKUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Hkbald-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:48 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-GumO6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:23:48 INFO - Running setup.py install for manifestparser 22:23:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:23:48 INFO - Running setup.py install for mozcrash 22:23:49 INFO - Running setup.py install for mozdebug 22:23:49 INFO - Running setup.py install for mozdevice 22:23:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:23:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:23:49 INFO - Running setup.py install for mozfile 22:23:49 INFO - Running setup.py install for mozhttpd 22:23:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:23:49 INFO - Running setup.py install for mozinfo 22:23:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:23:49 INFO - Running setup.py install for mozInstall 22:23:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:23:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:23:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:23:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for mozleak 22:23:50 INFO - Running setup.py install for mozlog 22:23:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for moznetwork 22:23:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for mozprocess 22:23:50 INFO - Running setup.py install for mozprofile 22:23:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for mozrunner 22:23:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for mozscreenshot 22:23:51 INFO - Running setup.py install for moztest 22:23:51 INFO - Running setup.py install for mozversion 22:23:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:23:51 INFO - Cleaning up... 22:23:51 INFO - Return code: 0 22:23:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:51 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 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:51 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 22:23:51 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 22:23:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:51 INFO - 'HOME': '/Users/cltbld', 22:23:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:51 INFO - 'LOGNAME': 'cltbld', 22:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:51 INFO - 'MOZ_NO_REMOTE': '1', 22:23:51 INFO - 'NO_EM_RESTART': '1', 22:23:51 INFO - 'PAGER': '/bin/cat', 22:23:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:51 INFO - 'PWD': '/builds/slave/test', 22:23:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:51 INFO - 'SHELL': '/bin/bash', 22:23:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:51 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:51 INFO - 'USER': 'cltbld', 22:23:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:51 INFO - 'XPC_FLAGS': '0x0', 22:23:51 INFO - 'XPC_SERVICE_NAME': '0', 22:23:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Kw7Dkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:51 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)) 22:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-zHgwu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:51 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)) 22:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ekbSMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:51 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)) 22:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-n8bBVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-RfwB7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-TaGjKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Qu3FDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-N5q6li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-IXSHnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-p5w7lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-PK3K_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-WDevnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:52 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)) 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:52 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-vqr76H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:53 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-wJKMRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:53 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-KvXWuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:53 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-_Vg61x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:53 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-IjSNRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:53 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)) 22:23:53 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)) 22:23:53 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)) 22:23:53 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)) 22:23:53 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)) 22:23:53 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)) 22:23:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:23:53 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 22:23:53 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 22:23:53 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 22:23:53 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 22:23:53 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 22:23:53 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 22:23:55 INFO - Downloading blessings-1.6.tar.gz 22:23:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:23:55 INFO - Installing collected packages: blessings 22:23:55 INFO - Running setup.py install for blessings 22:23:56 INFO - Successfully installed blessings 22:23:56 INFO - Cleaning up... 22:23:56 INFO - Return code: 0 22:23:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:23:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:56 INFO - Reading from file tmpfile_stdout 22:23:56 INFO - Current package versions: 22:23:56 INFO - blessings == 1.6 22:23:56 INFO - blobuploader == 1.2.4 22:23:56 INFO - docopt == 0.6.1 22:23:56 INFO - manifestparser == 1.1 22:23:56 INFO - mozInstall == 1.12 22:23:56 INFO - mozcrash == 0.17 22:23:56 INFO - mozdebug == 0.1 22:23:56 INFO - mozdevice == 0.48 22:23:56 INFO - mozfile == 1.2 22:23:56 INFO - mozhttpd == 0.7 22:23:56 INFO - mozinfo == 0.9 22:23:56 INFO - mozleak == 0.1 22:23:56 INFO - mozlog == 3.2 22:23:56 INFO - moznetwork == 0.27 22:23:56 INFO - mozprocess == 0.22 22:23:56 INFO - mozprofile == 0.28 22:23:56 INFO - mozrunner == 6.11 22:23:56 INFO - mozscreenshot == 0.1 22:23:56 INFO - mozsystemmonitor == 0.3 22:23:56 INFO - moztest == 0.7 22:23:56 INFO - mozversion == 1.4 22:23:56 INFO - psutil == 3.1.1 22:23:56 INFO - requests == 1.2.3 22:23:56 INFO - wsgiref == 0.1.2 22:23:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:56 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 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:56 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 22:23:56 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 22:23:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:56 INFO - 'HOME': '/Users/cltbld', 22:23:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:56 INFO - 'LOGNAME': 'cltbld', 22:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:56 INFO - 'MOZ_NO_REMOTE': '1', 22:23:56 INFO - 'NO_EM_RESTART': '1', 22:23:56 INFO - 'PAGER': '/bin/cat', 22:23:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:56 INFO - 'PWD': '/builds/slave/test', 22:23:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:56 INFO - 'SHELL': '/bin/bash', 22:23:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:56 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:56 INFO - 'USER': 'cltbld', 22:23:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:56 INFO - 'XPC_FLAGS': '0x0', 22:23:56 INFO - 'XPC_SERVICE_NAME': '0', 22:23:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 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)) 22:23:56 INFO - Cleaning up... 22:23:56 INFO - Return code: 0 22:23:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:56 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 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:23:56 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 22:23:56 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 22:23:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:23:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:56 INFO - 'HOME': '/Users/cltbld', 22:23:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:56 INFO - 'LOGNAME': 'cltbld', 22:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:56 INFO - 'MOZ_NO_REMOTE': '1', 22:23:56 INFO - 'NO_EM_RESTART': '1', 22:23:56 INFO - 'PAGER': '/bin/cat', 22:23:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:56 INFO - 'PWD': '/builds/slave/test', 22:23:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:56 INFO - 'SHELL': '/bin/bash', 22:23:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:23:56 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:23:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:56 INFO - 'USER': 'cltbld', 22:23:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:56 INFO - 'XPC_FLAGS': '0x0', 22:23:56 INFO - 'XPC_SERVICE_NAME': '0', 22:23:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:57 INFO - Downloading/unpacking jsonschema 22:23:57 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 22:23:57 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 22:23:57 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 22:23:57 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 22:23:57 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 22:23:57 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 22:23:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:23:59 INFO - Downloading/unpacking functools32 (from jsonschema) 22:23:59 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 22:23:59 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 22:23:59 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 22:23:59 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 22:23:59 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 22:23:59 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 22:23:59 INFO - Downloading functools32-3.2.3-2.tar.gz 22:23:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:23:59 INFO - warning: no files found matching '*.txt' 22:23:59 INFO - no previously-included directories found matching 'build' 22:23:59 INFO - no previously-included directories found matching 'dist' 22:23:59 INFO - no previously-included directories found matching '.git*' 22:23:59 INFO - Installing collected packages: jsonschema, functools32 22:24:00 INFO - Running setup.py install for functools32 22:24:00 INFO - warning: no files found matching '*.txt' 22:24:00 INFO - no previously-included directories found matching 'build' 22:24:00 INFO - no previously-included directories found matching 'dist' 22:24:00 INFO - no previously-included directories found matching '.git*' 22:24:00 INFO - Successfully installed jsonschema functools32 22:24:00 INFO - Cleaning up... 22:24:00 INFO - Return code: 0 22:24:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c3476290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, '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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 22:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:00 INFO - 'HOME': '/Users/cltbld', 22:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:00 INFO - 'LOGNAME': 'cltbld', 22:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:00 INFO - 'MOZ_NO_REMOTE': '1', 22:24:00 INFO - 'NO_EM_RESTART': '1', 22:24:00 INFO - 'PAGER': '/bin/cat', 22:24:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:00 INFO - 'PWD': '/builds/slave/test', 22:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:00 INFO - 'SHELL': '/bin/bash', 22:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:24:00 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:00 INFO - 'USER': 'cltbld', 22:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:00 INFO - 'XPC_FLAGS': '0x0', 22:24:00 INFO - 'XPC_SERVICE_NAME': '0', 22:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:00 INFO - Downloading/unpacking flake8 22:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:24:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:24:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:24:04 INFO - no previously-included directories found matching 'docs/_build' 22:24:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:24:04 INFO - Running setup.py install for pep8 22:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:24:04 INFO - no previously-included directories found matching 'docs/_build' 22:24:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:24:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:24:04 INFO - Cleaning up... 22:24:04 INFO - Return code: 0 22:24:04 INFO - Running post-action listener: _resource_record_post_action 22:24:04 INFO - Running post-action listener: _start_resource_monitoring 22:24:04 INFO - Starting resource monitoring. 22:24:04 INFO - ##### 22:24:04 INFO - ##### Finished create-virtualenv step (success) 22:24:04 INFO - ##### 22:24:04 INFO - ##### 22:24:04 INFO - ##### Running install step. 22:24:04 INFO - ##### 22:24:04 INFO - Running pre-action listener: _resource_record_pre_action 22:24:04 INFO - Running main action method: install 22:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:04 INFO - Reading from file tmpfile_stdout 22:24:04 INFO - Detecting whether we're running mozinstall >=1.0... 22:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:24:05 INFO - Reading from file tmpfile_stdout 22:24:05 INFO - Output received: 22:24:05 INFO - Usage: mozinstall [options] installer 22:24:05 INFO - Options: 22:24:05 INFO - -h, --help show this help message and exit 22:24:05 INFO - -d DEST, --destination=DEST 22:24:05 INFO - Directory to install application into. [default: 22:24:05 INFO - "/builds/slave/test"] 22:24:05 INFO - --app=APP Application being installed. [default: firefox] 22:24:05 INFO - mkdir: /builds/slave/test/build/application 22:24:05 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'] 22:24:05 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 22:24:39 INFO - Reading from file tmpfile_stdout 22:24:39 INFO - Output received: 22:24:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:24:39 INFO - Running post-action listener: _resource_record_post_action 22:24:39 INFO - ##### 22:24:39 INFO - ##### Finished install step (success) 22:24:39 INFO - ##### 22:24:39 INFO - ##### 22:24:39 INFO - ##### Running run-tests step. 22:24:39 INFO - ##### 22:24:39 INFO - Running pre-action listener: _resource_record_pre_action 22:24:39 INFO - Running main action method: run_tests 22:24:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:24:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:24:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:24:39 INFO - 2016-05-20 22:24:39.182 screenresolution[1915:10070] starting screenresolution argv=screenresolution get 22:24:39 INFO - 2016-05-20 22:24:39.185 screenresolution[1915:10070] Display 0: 1600x1200x32@60 22:24:39 INFO - 2016-05-20 22:24:39.191 screenresolution[1916:10073] starting screenresolution argv=screenresolution list 22:24:39 INFO - Available Modes on Display 0 22:24:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:24:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:24:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:24:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:24:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:24:39 INFO - Intel Iris: 22:24:39 INFO - Chipset Model: Intel Iris 22:24:39 INFO - Type: GPU 22:24:39 INFO - Bus: Built-In 22:24:39 INFO - VRAM (Dynamic, Max): 1536 MB 22:24:39 INFO - Vendor: Intel (0x8086) 22:24:39 INFO - Device ID: 0x0a2e 22:24:39 INFO - Revision ID: 0x0009 22:24:39 INFO - Displays: 22:24:39 INFO - Display: 22:24:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:24:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:24:39 INFO - Display Serial Number: 1600x1200 60 22:24:39 INFO - Main Display: Yes 22:24:39 INFO - Mirror: Off 22:24:39 INFO - Online: Yes 22:24:39 INFO - Rotation: Supported 22:24:39 INFO - Return code: 0 22:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:24:39 INFO - Python 2.7.3 22:24:39 INFO - Return code: 0 22:24:39 INFO - grabbing minidump binary from tooltool 22:24: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')]} 22:24:39 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 0x104afbd30>, '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 0x10491fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104af2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:24:39 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 22:24:39 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 22:24:39 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 22:24:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:24:39 INFO - Return code: 0 22:24:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:24:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:24:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:24:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:24:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:24:40 INFO - Return code: 0 22:24:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:24:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:24:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CxTvtrbkXc/Render', 22:24:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:40 INFO - 'HOME': '/Users/cltbld', 22:24:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:40 INFO - 'LOGNAME': 'cltbld', 22:24:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:24:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:40 INFO - 'MOZ_NO_REMOTE': '1', 22:24:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:24:40 INFO - 'NO_EM_RESTART': '1', 22:24:40 INFO - 'PAGER': '/bin/cat', 22:24:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:40 INFO - 'PWD': '/builds/slave/test', 22:24:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:24:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:40 INFO - 'SHELL': '/bin/bash', 22:24:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AfCZJHxJGS/Listeners', 22:24:40 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 22:24:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:40 INFO - 'USER': 'cltbld', 22:24:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:40 INFO - 'XPC_FLAGS': '0x0', 22:24:40 INFO - 'XPC_SERVICE_NAME': '0', 22:24:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:24:40 INFO - mozversion application_buildid: 20160520212034 22:24:40 INFO - mozversion application_changeset: f1f2644d34440fe38c098e323bf570e09197e51a 22:24:40 INFO - mozversion application_display_name: Nightly 22:24:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:24:40 INFO - mozversion application_name: Firefox 22:24:40 INFO - mozversion application_remotingname: firefox 22:24:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:24:40 INFO - mozversion application_vendor: Mozilla 22:24:40 INFO - mozversion application_version: 49.0a1 22:24:40 INFO - mozversion platform_buildid: 20160520212034 22:24:40 INFO - mozversion platform_changeset: f1f2644d34440fe38c098e323bf570e09197e51a 22:24:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:24:40 INFO - mozversion platform_version: 49.0a1 22:24:40 INFO - using testdate: 1463808280 22:24:40 INFO - actual date: 1463808280 22:24:40 INFO - starting webserver on 'localhost:49469' 22:24:40 INFO - SUITE-START | Running 2 tests 22:24:40 INFO - TEST-START | dromaeo_css 22:24:40 INFO - operating with platform_type : mac_ 22:24:40 INFO - Initialising browser for dromaeo_css test... 22:24:40 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpH7lx8h/profile http://localhost:49469/getInfo.html) 22:24:43 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 22:24:43 INFO - PROCESS | 1934 | colorDepth:24 22:24:43 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 22:24:43 INFO - PROCESS | 1934 | __metrics 22:24:43 ERROR - PROCESS | 1934 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 22:24:44 INFO - TEST-INFO | 1934: exit 0 22:24:44 INFO - Browser initialized. 22:24:44 INFO - Running cycle 1/1 for dromaeo_css test... 22:24:44 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpH7lx8h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:56 INFO - PROCESS | 1937 | RSS: Main: 249647104 22:24:56 INFO - PROCESS | 1937 | 22:25:45 INFO - PROCESS | 1937 | 1463808345972 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] 22:27:57 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49469/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49469/tests/dromaeo/cssquery-ext.html) 22:27:58 INFO - PROCESS | 1937 | RSS: Main: 260734976 22:27:58 INFO - PROCESS | 1937 | 22:31:00 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49469/tests/dromaeo/cssquery-ext.html (next: http://localhost:49469/tests/dromaeo/cssquery-jquery.html) 22:31:01 INFO - PROCESS | 1937 | RSS: Main: 273432576 22:31:01 INFO - PROCESS | 1937 | 22:34:03 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49469/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49469/tests/dromaeo/cssquery-mootools.html) 22:34:04 INFO - PROCESS | 1937 | RSS: Main: 275075072 22:34:04 INFO - PROCESS | 1937 | 22:37:05 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49469/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49469/tests/dromaeo/cssquery-prototype.html) 22:37:06 INFO - PROCESS | 1937 | RSS: Main: 279494656 22:37:06 INFO - PROCESS | 1937 | 22:40:09 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49469/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49469/tests/dromaeo/cssquery-yui.html) 22:40:09 INFO - PROCESS | 1937 | RSS: Main: 278528000 22:40:09 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49469/tests/dromaeo/cssquery-yui.html (next: http://localhost:49469/tests/dromaeo/cssquery-dojo.html) 22:43:12 INFO - PROCESS | 1937 | RSS: Main: 280379392 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | __start_tp_report 22:43:12 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 22:43:12 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 22:43:12 INFO - PROCESS | 1937 | |i|pagename|runs| 22:43:12 INFO - PROCESS | 1937 | |0;dojo.html;3508.5263489428926;3518.592562974808;3534.522839416678;3551.9289614207714;3561.9109522261942;4711.316859055435;5851.063829787229;5858.443447872451;5945.197398351222;6003.609765365253;5765.567582431324;5869.941300587004;5904.498117659993;5957.225560677106;5988.431099045594;5227.2159176123605;5265.999460102568;5300.284461597693;5376.542993845528;5429.375621803503;5329.173978033392;5393.056215162351;5446.863828404282;5460.044492213847;5495.725213739317;4394.868153955386;4496.5278645742055;5244.265802787594;5268.631195816294;5346.438623944488;4424.269995450747;5304.681719096867;5314.202869569595;5376.650517716375;5452.672839629595;5290.603204759658;5328.333958255218;5352.545033672142;5352.785888564453;5367.785288588452;4825.613950883921;5669.574781891333;5835.766569337221;5853.004989151855;5853.912191317134;6179.227596550431;6261.7182226799905;6277.372262773732;6279.937200627981;6289.591176573554;6192.783252586183;6205.286392064925;6279.597794356156;6306.148669929593;6317.557770956035;5911.379305172938;5977.103434484862;5977.581569290108;5984.1622172895395;6035.969820150915;6289.233154904859;6337.493000559944;6348.365163483615;6365;6365.49076073913;6168.475679567287;6172.135900973867;6183.227096612924;6233.688315584202;6234.657093859882;6305.558610897193;6308.779192728232;6321.683915804191;6326.2724786649205;6328.177336946167;6111.561351782648;6135.44703110649;6213.285472170758;6242.438180563771;6248.9062664060075;6112.26032301701;6186.19579454677;6196;6259.843503912439;6280.811575652647;5102.923456148161;5103.8724031898755;5104.336436263262;5114.33513643224;5150.742462876841;4517.277235642281;4620.838270660511;4626.167289887852;4626.699264547794;4667.7199368037345;5733.054046082433;5967.343592204854;5998.970005150034;6024.849378765566;6061.666608336495;6224.4558443042315;6315.021171718391;6373.649449280334;6482.222133344029;6554.14796076507;4793.424789025339;6018.578699490994;6022.57841951068;6048.395160483917;6051.878962420815;4761.785719642625;5529.612927095065;5571.721413929287;5613.943860561424;5633.577481688808;6570.737170513222;6622.1722284714415;6627.23786764509;6628.900566491603;6648.66756662165;6218.347073557392;6347.238331400077;6347.365263473615;6363.459105976044;6389.456896163692;6436.324185960407;6468.3855033771715;6502.902456463063;6536.934630653633;6602.900956485752;6235.189425374672;6267.091271765477;6268.561200715906;6285.120083188449;6285.182926219745;6181.629102253879;6217.4715149212825;6322.652254126067;6340.61956282606;6341.112244285896;6141.025769127022;6180.103884936749;6181.320054794059;6226.097215903759;6270.372962703693;5246.921296180484;6183.721732521957;6216.875662486635;6221.813345599639;6254.186955695731;5303.204519322133;6065.120557519223;6132.570720049732;6194.318624951342;6199.659018754011;6187.752489900534;6191.938080619136;6215.751369945332;6255.46828519581;6256.937430625636;6200.100985357008;6208.503319734501;6223.066540019034;6228.5951413158;6246.875062498815 22:43:12 INFO - PROCESS | 1937 | |1;ext.html;488.4602514221785;507.3176577503258;531.8590573498024;535.1143856916801;558.3690429814309;35469.2906141877;37643;38763.61236387635;38800.02999925003;38908;39853.202935941335;40362.79818600903;40640.39039414404;40671.98320042001;40807.79596102023;8451.450655707391;9317.20803731682;9593.376430532027;9599.280053995979;9605.27960402969;9414.952925235364;9548.952255238713;9566.091221333962;9573.13841754242;9605.231581473465;9537.570809313564;9571.617135314578;9588.664396746115;9592.280578956572;9594.808103837953;9500.809983800355;9551.808963820684;9557.99641037688;9563.139317461428;9566.712998610017;9436.811263774754;9556.187724043395;9567.712968610953;9609.327347085708;9641.758956026086;36976.890693279245;37060;37120.44319335212;37120.814395927846;37505;2147.903344349508;2861.5850701648146;3046.299351149248;3373.9662603374145;3658.2317713280245;27233.591496127574;28196.436071278666;28405;28552.71447285542;28573.714182861822;2355.364051706047;2470.3330100872845;2475.344033831036;2482.329770961848;2547.0957809977563;422527.8873605643;427107;429755;430588;431141;32925.50611740826;33098.3380332392;33170.66829331724;33269.50095748566;33317.83341083279;33970.660293397246;34476.655233447345;34651.30697386038;34791.82604086964;34816;14724;15104.622384440478;15242.847571524364;15243.23783810805;15258.542243732705;12713.364331783374;12904.290264035568;13042.152260103063;13052.412641431158;13066.673333166747;7510.1363343215135;7715.112762032438;7770.184130666312;7777.105632852181;7792.2986931176465;3231.095293317828;3275.8853440129483;3297.027376923765;3303.6035675718585;3324.268660894648;867.6355930509201;987.7876256604865;1010.4644538394663;1063.3832377221192;1137.6018393562188;28285.57571636427;28418.573721394194;28492.28769280743;29005.41989160205;29022.419551609273;1038.8337865941412;1052.7262911642874;1120.7086157599083;1150.5915400033034;1158.3455347728507;14621;14746.778798318033;14867;14999.700006000037;15167.089974601558;626210;627191;628546;633287;633832;48190.75904620525;49180.75409622929;57076;57307;57686.423135768106;1268.7462507498353;1391.08883681189;1449.4419648435216;1453.0990785712925;1454.1929229277641;27148.864255678596;27914.302142446602;28102.437951240452;28410.715892840806;28619.998300059397;46321.305180423;46635;46781.532184679076;47449;47629.523704763895;497779;505777.4711126568;506365;507919;509541;25145.622815657414;25367.731613418888;25665.615015774405;25819.709014548822;25974;44189.558104418546;44353.77823110864;44946.550534494236;45234.32148517841;45365;37097.25805483821;37384.81307593445;37385.626143739304;37583.81208093942;38050;34354.484682730275;36158.09604759902;36731.78128623707;37361;37413.43879841859;30494.76452781447;35085.29829403449;36099;36864.89405317845;38321.42517862179;29603.555946660388;31447.52828707617;32375.51436728404;32422.83788581042;32572;23377.41556461102;24383;24441.633375499736;24777;24850.254492365257 22:43:12 INFO - PROCESS | 1937 | |2;jquery.html;361.4523996145839;381.87347325390107;384.1816929939229;401.92765302245584;409.2183928696191;18631.06844657767;19556.511087222792;21176.152953881825;21196.682049769246;21259.787402125974;10238.283320167591;11787.17489775716;11799.646010619696;11827.645170644873;17624.20691068902;5347.491988261128;5508.7521061552325;5533.446655334455;5543.833684989457;5564.276644036269;3920.3727403615426;5834.56240781941;6131.110988906606;6146.84632884177;6211.782587609412;6075.2054055134695;6197.814065578017;6202.100695399165;6223.533235007392;6612.140421745166;6716.1604799400075;6723.798286051402;6727.697253623599;6732.528722989368;6738.52830301879;5940.376260492672;6679.833004174886;7265.818354541126;7342.118945726494;7360.2271761465345;26894;27890.3027424314;29278.41443171146;29354;29458.558121628193;2136.6794980752857;2153.493928926701;2165.9241926532495;2198;2203.06369792839;16211;16272.918635406866;16437.178141092896;16667.91666041674;16742.74885876713;1640.2290923266046;1924.5573518090778;1947.57153426246;1954.7263383126374;1962.3916585858287;134837;136313;137607.31196343954;138651.99999999898;139387;18479.81520184808;21165.788342116382;21928.122875085137;22025;22241.110355585595;35142.82428587841;35665;35775.4633680495;35790.64209357925;35856.462153067725;10974.012338889379;11189;11248.550057997589;11296;11336.03643690279;9190.713300137986;9223.584938677775;9258.583363748647;9260.861087083698;9366.62533498666;9935.105840474393;9974.900250997542;9987.350822196535;10086.29395942277;10148.391096534231;3712.925741485155;3870.9225815484097;3911.647951684362;4105.035316700573;4133.689973252018;905.2667339455063;952.3999880075509;977.2279898879948;994.7264502261936;1011.5094179323022;19840.900795496218;20328.186872525228;20495.385138445872;20695.8965205173;21055.68416473754;919.3288899103558;935.1116439382614;989.5200827598609;1010.0656892374524;1029.6653587584065;9154.81690366189;10378.948105259577;10584.941505849354;10701.136465681418;10740.140788736882;611298;625112.874435634;636132.8193359004;636621;670279;116692.41653791847;128950.71049289574;129476;131674.34162829124;136854;941.4445477168366;954.2652157838426;975.3513913247752;989.5992123190119;992.9751756206008;17433.56416089608;18279.54301142482;18415.723764143815;18452.63094738071;18491.260349586402;54546;55214;55563.44436555744;55633.443665564446;55722.72138639281;62464.68767656133;79061.20938790539;79295.62113136615;82346.17653823385;83973.58013209894;7595.278448547379;7790.0651921767485;8004;8148.511089334659;8324.0427350853;11267.26762760418;12435.564755233523;12575.371231438574;12807.93596032014;12816.166949148275;10750.156468870184;10759.031687148194;10764.892351076389;10788.244822862323;10907.400092994962;10607.628732994308;10631.8936810634;10736.604241448562;10964.945175274392;11005.1746119043;5642.915356269741;6717.966410167918;10509.527071281964;10903.400312982862;11459.31244125355;8037.718679846177;8051.718189863561;8052.919470805451;8132.83734325322;8239.588020599065;8053.073896501977;9988.300818942604;10881.401522916316;11208;11221.990020898158 22:43:12 INFO - PROCESS | 1937 | |3;mootools.html;1526.3436722209449;1561.4534912780528;1758.9736153957692;1866.9906650466753;1873.9599522265155;7980.9201907980905;8545.61544730487;8592.613332400042;8972.91027089729;8983.28133749301;8529.829403411928;8599.140085991397;8746.256568191695;8751.824963500727;8760.474371537712;2322.4890524084676;2345.6716059751648;2357.8585284882874;2358.6108292131776;2362.621980483123;2375.70303712036;2386.9880650596724;2393.9521209575796;2405.939851503718;2407.843490173142;2393.664886915833;2396.604560247557;2409.915652952147;2415.9758402416014;2417.6373543968457;2371.34787933318;2383.833131680783;2384.5469360821476;2388.3670827230712;2396.3769419950936;2339.6724458575814;2349.917752878658;2363.8108951283857;2365.7161140663056;2378.452955820154;7551.77344679655;8710.825783484295;8800.515971621558;8813;8858.512781796999;4805.351277577517;5354.089804733205;5435.42927992559;5448.972755136199;5449.346078470569;3429.657034296575;3440.088376580208;3520.0847779577493;3614.3855544557487;3635.4001589737663;911.9316051296179;1027.126942099217;1045.796069766396;1046.3042077018774;1046.9267151299412;11375.488103035383;11446.084313255004;11474.770504589946;11560;11611.071114310835;4533.252013417815;4541.114482675846;4576.748278844629;4581.404417425713;4606.286025665961;9246.768830779285;9265.861012084824;9269.860952085724;9282.582283797245;9292.72121836346;5191.2472691459525;5264.473552644705;5272.20916862466;5274.525292723597;5303.973480132575;4471.5528447155675;4803.639727020487;4900.950990490121;4962.851114466572;4975.228839529878;3755.4742336072973;3819.503464549646;3849.3071247175776;3855.749376290532;3936.075022369741;1108.5731993182683;1153.4982282707049;1175.5474142455216;1183.8461000069935;1189.1735244005506;814.2346194577078;912.1425859691868;917.1149840404042;950.7575568230058;951.7953639967302;5624.437556244343;5701.515371193412;5877.0596704527925;5963.254593175853;6071.483923866433;432.27809558038155;432.8549935771489;434.96520278377653;440.9162259170747;452.53389009320455;3914.0410599402758;3970.1464185199743;3977.7016723745833;4051.2707712611423;4071.470708807836;10298.330608510423;10610.628627997983;10768.730781730363;10776.245662803529;10789.892101078889;10639.468026598794;10707.625233116803;10722.946385268024;10745.892541074645;10756;7828.45200835936;8182;8188.426810123353;8188.50868947865;8238.958805206055;6006.438326035175;6009.278886533644;6095.085737139288;6138.416850399205;6164.876702466016;9837.311388203043;9987.950060249652;10046.598136074475;10102.39385636864;10103.797924041331;9847.261455390731;10144.594216231268;10165.949170254102;10303.175745940456;10312.690619281433;6922.757703480354;7279.890801637874;7459.2540745927145;7530.246975302426;7702.075750909927;7089.397401220954;7302.488825782144;8234.629441675259;8243.83512329762;8248.13394593559;7299.671514781954;7381.1511676157925;7403.962980185064;7503.474756767192;7550.697972081055;6444.967775161094;6476.676166191766;7279.162896266997;7368.668409921459;7391.556506609406;6906.689198986158;7004.509684322253;7018.789436316919;7049.823754406181;7082.893756593759;3999.8800035998966;4480.081583275355;4485.775711214492;4531.637469002406;4597.965457771975;4525.909481810279;4900.460949295503;5050.510099520577;5105.7191854448365;5238.37139543242 22:43:12 INFO - PROCESS | 1937 | |4;prototype.html;308.72677680252986;310.5590062111801;311.3026819923371;314.81583273784827;321.2098238333699;5556.444355564441;5585.4360778981845;5605.523530499912;5626.352969408515;5638.731285460769;4852.053849499349;4896;5065.620078494109;5094.363204599425;5510.6969116698565;3779.1874746929407;3817.6373244541724;3912.3349030664785;4065.5324637666604;4157.646600039;3775.6413140751583;3901.941470877939;4005.2790497710403;4108.096218831853;4160.459140311756;3682.8526858925743;3708.7589306695113;4016.8192431340512;4132.173565286939;4158.334666453368;3626.9455958160647;3838.846446142151;3863.652271295597;4007.6994225433064;4153.937690934638;3470.0457374222037;3595.993121925865;3607.0080727799805;3626.7098632109632;3628.546431696038;4687.789049492747;5285.885645741734;5290.232916227164;5294.49702278283;5312.415634280226;4107.671386289119;4173.373993900909;4231.355402873204;4760.976195119037;4816.759162041884;5812.534997200214;5850.795222167247;5858.627255961343;5875.559333050039;5900.616459930134;3526.9118272043147;3675.852965881388;3909.0032041829436;3926.6466018058227;3953.683705303569;5129.846104616868;5738.397468265855;5992.221011268507;6117.66352850593;6161.9216637088775;3438.707709844691;3552.3605750965075;3942.7831469269468;3951.920961580752;3984.2828290907914;3716.386796178654;3738.7569807962395;4007.6593489553716;4048.716589838742;4076.6331030207425;4153.979230103831;4450.933236001462;4676.485586585472;4707.552782485658;4733.455652599926;4913;4950.851474455772;5534.335879694462;5712.714364281769;5914.9112763308585;1224.8407706998032;1309.967250818737;1368.6988862450246;1381.2886363522794;1384.819973403451;795.383577727266;831.0235473318947;840.7855996720797;841.6296829395046;849.613425891218;4854.538818812207;4935.580475659537;5350.91973620396;5479.013777520005;5514.80698175562;5640.407757185519;5849.239598852122;6107.541934354941;6112;6127.2647282325515;4520.728756274634;5107.438181799999;5380.569554435636;5470.343558772893;5531.308586426697;2819.4220184862056;3189.888353907602;3219.5492631031675;3224.048905572815;3230.547723318737;5289;6290.370962903673;6304.968475157595;6347.904781428282;6362.141110949962;6218.533609979179;6224.844378890473;6268.623882567061;6272.968635156795;6274.2784579773;5281.9207711884355;6295.779647712399;6320.399562041599;6329.461995730322;6349.714262858182;5520.309961254843;5651.57613179005;5655;6051.304100028553;6084.787032453843;5200.297959775382;5383.87735113641;6003.219581454383;6014.346054834956;6018.488428483628;5675.432456754292;5909.133232381128;6032.707571122945;6088.208532890696;6168.915542228886;3327.683870032343;3741.5136032315627;3923;3938.232044751246;3945.881623551298;4962.900741985212;5540.066552747433;6051.727672254671;6059.424744483665;6080.726367313393;4942.75286235694;5086.21163719624;5529.640573362719;5622.915656265078;5807.274090738658;5201.609879258995;5617.286727547974;5787.5369970401425;5867.501262392744;5867.618604790675;4905.681130726492;5070.847874563769;5733.627314224562;5733.627314224729;5753.942460575341;4868.853934381974;5537.529310008695;5798.768049277982;5841.591088623755;5929.703514824328;4810.206315957827;4932.975335123301;5569.777208911598;5583.352910891215;5720.427957204246 22:43:12 INFO - PROCESS | 1937 | |5;yui.html;647.3073811022206;656.9113169722086;694.3958755882383;720.8233982674245;732.282363283982;14731.263436828169;15153.54539363821;15461.6134596635;15492.845071549282;15496.612584685388;12312.692182695437;13974.231417272045;14198.78701819471;14311.141331520115;14342.71314573708;685.239384283445;732.9853402931965;844.320322140675;858.9441686290403;859.1194026123211;831.9584020798966;853.4580541356253;853.7822855171953;853.8463076646201;856.5503110866814;905.597009330848;906.8503696890006;906.9863952040724;907.2016625369665;907.5326207003399;889.9110088991114;895.2882458445551;897.5826240798027;898.0346127912487;906.125588806798;648.8669822686338;662.8011596521025;663.8838203314401;664.6610228783306;666.5534092158266;14988.550343489713;14994.100353978687;14999.1750453725;15012.249387530688;15013.624659383493;11962.700932476671;12127.696807579881;12691.555795547203;12933.159344642569;13094.934525327313;9801.16690081344;9985.600575976952;9998.100170984573;10200.0819926207;10350.792984140351;3361.8151001694896;3551.129973156593;3564.572251329831;3591.28174365128;3666.724995625312;83113.58443207806;84018.57990710069;84368.57815710944;84760.15239847646;85196.14803851944;9056.818863622691;9176.128267814547;9260.351775375659;9269.768255793524;9407;19739.703904441445;19980.90009549943;20278;20315.8984205081;20547.589048218953;7192.928070719226;7255.201927787939;7267.818304542428;7292.8906066409045;7403.481756277117;3545.2377738786286;3970.2258059678084;3984.2230765001127;4006.1587066715733;4010.6189911958318;3385.23832137767;3421.5209870618132;3469.930601387958;3470.4273794824076;3472;558.8407303918364;627.6485168305763;629.0752593687272;633.762339122829;657.1686816177539;495.28678702668043;519.605100123901;537.6290359651828;566.7308028686424;584.1588113117098;7700.614969251515;7792.571408572469;7827.25641064098;8050.959745201354;8145.789769086738;495.45339919600445;504.6164914664808;516.246280430568;549.3545084525666;571.7284289962234;2558.7952963762855;2957.3937342844665;3004.879804807827;3038.14931819091;3087.799293045945;85446.14553854382;90181;91714.54142729244;91797.54101229452;92407;79984.60007699924;80481.59759201166;82446.17553824505;82803;82842;65638.6872262552;66121.66939165273;66777.33222667809;69670;71519.28480715126;7019.824504387431;7958.0848202455145;8089.595520223847;8095.87856182146;8171.142030086755;36437.72467963715;43227.78386108049;44919.77540112279;44981;46235.76882115568;53035;57343.13985290308;59029.40970590411;61598.4600384994;62774.37225627685;10610.8408373876;11133.220674552704;11174.944125279322;11276.661700149009;11363.715907102389;10501.684949451528;11586.71033224176;11673.708157296136;11871.287722736663;12294.139410241198;11565.59520416815;11618.88381116178;11634.185607007428;11659.475323610628;11778.882211177779;11082.05802506796;11518.251313664585;11751.353675547927;11774.470148843151;11971.76056478883;11008.449577521253;11091.50088246047;11136.38749868765;11190.88809111931;11480.425978700865;9281.303902207226;9313.022132675971;9321.394109382869;9426.670066547638;9658.082482164184;8709.948230026695;9321.627134914528;9389.577469014048;9440.527973601429;9614.95192524033 22:43:12 INFO - PROCESS | 1937 | __end_tp_report 22:43:12 INFO - PROCESS | 1937 | __start_cc_report 22:43:12 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,416 22:43:12 INFO - PROCESS | 1937 | __end_cc_report 22:43:12 INFO - PROCESS | 1937 | __startTimestamp1463809392878__endTimestamp 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | ------- Summary: start ------- 22:43:12 INFO - PROCESS | 1937 | Number of tests: 6 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | [#0] dojo.html Cycles:165 Average:5836.74 Median:6141.03 stddev:668.29 (10.9%) stddev-sans-first:644.90 22:43:12 INFO - PROCESS | 1937 | Values: 3508.5 3518.6 3534.5 3551.9 3561.9 4711.3 5851.1 5858.4 5945.2 6003.6 5765.6 5869.9 5904.5 5957.2 5988.4 5227.2 5266.0 5300.3 5376.5 5429.4 5329.2 5393.1 5446.9 5460.0 5495.7 4394.9 4496.5 5244.3 5268.6 5346.4 4424.3 5304.7 5314.2 5376.7 5452.7 5290.6 5328.3 5352.5 5352.8 5367.8 4825.6 5669.6 5835.8 5853.0 5853.9 6179.2 6261.7 6277.4 6279.9 6289.6 6192.8 6205.3 6279.6 6306.1 6317.6 5911.4 5977.1 5977.6 5984.2 6036.0 6289.2 6337.5 6348.4 6365.0 6365.5 6168.5 6172.1 6183.2 6233.7 6234.7 6305.6 6308.8 6321.7 6326.3 6328.2 6111.6 6135.4 6213.3 6242.4 6248.9 6112.3 6186.2 6196.0 6259.8 6280.8 5102.9 5103.9 5104.3 5114.3 5150.7 4517.3 4620.8 4626.2 4626.7 4667.7 5733.1 5967.3 5999.0 6024.8 6061.7 6224.5 6315.0 6373.6 6482.2 6554.1 4793.4 6018.6 6022.6 6048.4 6051.9 4761.8 5529.6 5571.7 5613.9 5633.6 6570.7 6622.2 6627.2 6628.9 6648.7 6218.3 6347.2 6347.4 6363.5 6389.5 6436.3 6468.4 6502.9 6536.9 6602.9 6235.2 6267.1 6268.6 6285.1 6285.2 6181.6 6217.5 6322.7 6340.6 6341.1 6141.0 6180.1 6181.3 6226.1 6270.4 5246.9 6183.7 6216.9 6221.8 6254.2 5303.2 6065.1 6132.6 6194.3 6199.7 6187.8 6191.9 6215.8 6255.5 6256.9 6200.1 6208.5 6223.1 6228.6 6246.9 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | [#1] ext.html Cycles:180 Average:63312.24 Median:36920.89 stddev:141320.03 (382.8%) stddev-sans-first:141637.75 22:43:12 INFO - PROCESS | 1937 | Values: 488.5 507.3 531.9 535.1 558.4 35469.3 37643.0 38763.6 38800.0 38908.0 39853.2 40362.8 40640.4 40672.0 40807.8 8451.5 9317.2 9593.4 9599.3 9605.3 9415.0 9549.0 9566.1 9573.1 9605.2 9537.6 9571.6 9588.7 9592.3 9594.8 9500.8 9551.8 9558.0 9563.1 9566.7 9436.8 9556.2 9567.7 9609.3 9641.8 36976.9 37060.0 37120.4 37120.8 37505.0 2147.9 2861.6 3046.3 3374.0 3658.2 27233.6 28196.4 28405.0 28552.7 28573.7 2355.4 2470.3 2475.3 2482.3 2547.1 422527.9 427107.0 429755.0 430588.0 431141.0 32925.5 33098.3 33170.7 33269.5 33317.8 33970.7 34476.7 34651.3 34791.8 34816.0 14724.0 15104.6 15242.8 15243.2 15258.5 12713.4 12904.3 13042.2 13052.4 13066.7 7510.1 7715.1 7770.2 7777.1 7792.3 3231.1 3275.9 3297.0 3303.6 3324.3 867.6 987.8 1010.5 1063.4 1137.6 28285.6 28418.6 28492.3 29005.4 29022.4 1038.8 1052.7 1120.7 1150.6 1158.3 14621.0 14746.8 14867.0 14999.7 15167.1 626210.0 627191.0 628546.0 633287.0 633832.0 48190.8 49180.8 57076.0 57307.0 57686.4 1268.7 1391.1 1449.4 1453.1 1454.2 27148.9 27914.3 28102.4 28410.7 28620.0 46321.3 46635.0 46781.5 47449.0 47629.5 497779.0 505777.5 506365.0 507919.0 509541.0 25145.6 25367.7 25665.6 25819.7 25974.0 44189.6 44353.8 44946.6 45234.3 45365.0 37097.3 37384.8 37385.6 37583.8 38050.0 34354.5 36158.1 36731.8 37361.0 37413.4 30494.8 35085.3 36099.0 36864.9 38321.4 29603.6 31447.5 32375.5 32422.8 32572.0 23377.4 24383.0 24441.6 24777.0 24850.3 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | [#2] jquery.html Cycles:180 Average:37801.15 Median:35403.91 stddev:106150.44 (299.8%) stddev-sans-first:106410.99 22:43:12 INFO - PROCESS | 1937 | Values: 361.5 381.9 384.2 401.9 409.2 18631.1 19556.5 21176.2 21196.7 21259.8 10238.3 11787.2 11799.6 11827.6 17624.2 5347.5 5508.8 5533.4 5543.8 5564.3 3920.4 5834.6 6131.1 6146.8 6211.8 6075.2 6197.8 6202.1 6223.5 6612.1 6716.2 6723.8 6727.7 6732.5 6738.5 5940.4 6679.8 7265.8 7342.1 7360.2 26894.0 27890.3 29278.4 29354.0 29458.6 2136.7 2153.5 2165.9 2198.0 2203.1 16211.0 16272.9 16437.2 16667.9 16742.7 1640.2 1924.6 1947.6 1954.7 1962.4 134837.0 136313.0 137607.3 138652.0 139387.0 18479.8 21165.8 21928.1 22025.0 22241.1 35142.8 35665.0 35775.5 35790.6 35856.5 10974.0 11189.0 11248.6 11296.0 11336.0 9190.7 9223.6 9258.6 9260.9 9366.6 9935.1 9974.9 9987.4 10086.3 10148.4 3712.9 3870.9 3911.6 4105.0 4133.7 905.3 952.4 977.2 994.7 1011.5 19840.9 20328.2 20495.4 20695.9 21055.7 919.3 935.1 989.5 1010.1 1029.7 9154.8 10378.9 10584.9 10701.1 10740.1 611298.0 625112.9 636132.8 636621.0 670279.0 116692.4 128950.7 129476.0 131674.3 136854.0 941.4 954.3 975.4 989.6 993.0 17433.6 18279.5 18415.7 18452.6 18491.3 54546.0 55214.0 55563.4 55633.4 55722.7 62464.7 79061.2 79295.6 82346.2 83973.6 7595.3 7790.1 8004.0 8148.5 8324.0 11267.3 12435.6 12575.4 12807.9 12816.2 10750.2 10759.0 10764.9 10788.2 10907.4 10607.6 10631.9 10736.6 10964.9 11005.2 5642.9 6718.0 10509.5 10903.4 11459.3 8037.7 8051.7 8052.9 8132.8 8239.6 8053.1 9988.3 10881.4 11208.0 11222.0 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | [#3] mootools.html Cycles:180 Average:5629.68 Median:4589.68 stddev:3173.81 (69.2%) stddev-sans-first:3167.74 22:43:12 INFO - PROCESS | 1937 | Values: 1526.3 1561.5 1759.0 1867.0 1874.0 7980.9 8545.6 8592.6 8972.9 8983.3 8529.8 8599.1 8746.3 8751.8 8760.5 2322.5 2345.7 2357.9 2358.6 2362.6 2375.7 2387.0 2394.0 2405.9 2407.8 2393.7 2396.6 2409.9 2416.0 2417.6 2371.3 2383.8 2384.5 2388.4 2396.4 2339.7 2349.9 2363.8 2365.7 2378.5 7551.8 8710.8 8800.5 8813.0 8858.5 4805.4 5354.1 5435.4 5449.0 5449.3 3429.7 3440.1 3520.1 3614.4 3635.4 911.9 1027.1 1045.8 1046.3 1046.9 11375.5 11446.1 11474.8 11560.0 11611.1 4533.3 4541.1 4576.7 4581.4 4606.3 9246.8 9265.9 9269.9 9282.6 9292.7 5191.2 5264.5 5272.2 5274.5 5304.0 4471.6 4803.6 4901.0 4962.9 4975.2 3755.5 3819.5 3849.3 3855.7 3936.1 1108.6 1153.5 1175.5 1183.8 1189.2 814.2 912.1 917.1 950.8 951.8 5624.4 5701.5 5877.1 5963.3 6071.5 432.3 432.9 435.0 440.9 452.5 3914.0 3970.1 3977.7 4051.3 4071.5 10298.3 10610.6 10768.7 10776.2 10789.9 10639.5 10707.6 10722.9 10745.9 10756.0 7828.5 8182.0 8188.4 8188.5 8239.0 6006.4 6009.3 6095.1 6138.4 6164.9 9837.3 9988.0 10046.6 10102.4 10103.8 9847.3 10144.6 10165.9 10303.2 10312.7 6922.8 7279.9 7459.3 7530.2 7702.1 7089.4 7302.5 8234.6 8243.8 8248.1 7299.7 7381.2 7404.0 7503.5 7550.7 6445.0 6476.7 7279.2 7368.7 7391.6 6906.7 7004.5 7018.8 7049.8 7082.9 3999.9 4480.1 4485.8 4531.6 4598.0 4525.9 4900.5 5050.5 5105.7 5238.4 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | [#4] prototype.html Cycles:180 Average:4658.18 Median:5200.95 stddev:1477.36 (28.4%) stddev-sans-first:1444.98 22:43:12 INFO - PROCESS | 1937 | Values: 308.7 310.6 311.3 314.8 321.2 5556.4 5585.4 5605.5 5626.4 5638.7 4852.1 4896.0 5065.6 5094.4 5510.7 3779.2 3817.6 3912.3 4065.5 4157.6 3775.6 3901.9 4005.3 4108.1 4160.5 3682.9 3708.8 4016.8 4132.2 4158.3 3626.9 3838.8 3863.7 4007.7 4153.9 3470.0 3596.0 3607.0 3626.7 3628.5 4687.8 5285.9 5290.2 5294.5 5312.4 4107.7 4173.4 4231.4 4761.0 4816.8 5812.5 5850.8 5858.6 5875.6 5900.6 3526.9 3675.9 3909.0 3926.6 3953.7 5129.8 5738.4 5992.2 6117.7 6161.9 3438.7 3552.4 3942.8 3951.9 3984.3 3716.4 3738.8 4007.7 4048.7 4076.6 4154.0 4450.9 4676.5 4707.6 4733.5 4913.0 4950.9 5534.3 5712.7 5914.9 1224.8 1310.0 1368.7 1381.3 1384.8 795.4 831.0 840.8 841.6 849.6 4854.5 4935.6 5350.9 5479.0 5514.8 5640.4 5849.2 6107.5 6112.0 6127.3 4520.7 5107.4 5380.6 5470.3 5531.3 2819.4 3189.9 3219.5 3224.0 3230.5 5289.0 6290.4 6305.0 6347.9 6362.1 6218.5 6224.8 6268.6 6273.0 6274.3 5281.9 6295.8 6320.4 6329.5 6349.7 5520.3 5651.6 5655.0 6051.3 6084.8 5200.3 5383.9 6003.2 6014.3 6018.5 5675.4 5909.1 6032.7 6088.2 6168.9 3327.7 3741.5 3923.0 3938.2 3945.9 4962.9 5540.1 6051.7 6059.4 6080.7 4942.8 5086.2 5529.6 5622.9 5807.3 5201.6 5617.3 5787.5 5867.5 5867.6 4905.7 5070.8 5733.6 5733.6 5753.9 4868.9 5537.5 5798.8 5841.6 5929.7 4810.2 4933.0 5569.8 5583.4 5720.4 22:43:12 INFO - PROCESS | 1937 | 22:43:12 INFO - PROCESS | 1937 | [#5] yui.html Cycles:180 Average:17819.98 Median:60313.93 stddev:25311.70 (42.0%) stddev-sans-first:25349.86 22:43:12 INFO - PROCESS | 1937 | Values: 647.3 656.9 694.4 720.8 732.3 14731.3 15153.5 15461.6 15492.8 15496.6 12312.7 13974.2 14198.8 14311.1 14342.7 685.2 733.0 844.3 858.9 859.1 832.0 853.5 853.8 853.8 856.6 905.6 906.9 907.0 907.2 907.5 889.9 895.3 897.6 898.0 906.1 648.9 662.8 663.9 664.7 666.6 14988.6 14994.1 14999.2 15012.2 15013.6 11962.7 12127.7 12691.6 12933.2 13094.9 9801.2 9985.6 9998.1 10200.1 10350.8 3361.8 3551.1 3564.6 3591.3 3666.7 83113.6 84018.6 84368.6 84760.2 85196.1 9056.8 9176.1 9260.4 9269.8 9407.0 19739.7 19980.9 20278.0 20315.9 20547.6 7192.9 7255.2 7267.8 7292.9 7403.5 3545.2 3970.2 3984.2 4006.2 4010.6 3385.2 3421.5 3469.9 3470.4 3472.0 558.8 627.6 629.1 633.8 657.2 495.3 519.6 537.6 566.7 584.2 7700.6 7792.6 7827.3 8051.0 8145.8 495.5 504.6 516.2 549.4 571.7 2558.8 2957.4 3004.9 3038.1 3087.8 85446.1 90181.0 91714.5 91797.5 92407.0 79984.6 80481.6 82446.2 82803.0 82842.0 65638.7 66121.7 66777.3 69670.0 71519.3 7019.8 7958.1 8089.6 8095.9 8171.1 36437.7 43227.8 44919.8 44981.0 46235.8 53035.0 57343.1 59029.4 61598.5 62774.4 10610.8 11133.2 11174.9 11276.7 11363.7 10501.7 11586.7 11673.7 11871.3 12294.1 11565.6 11618.9 11634.2 11659.5 11778.9 11082.1 11518.3 11751.4 11774.5 11971.8 11008.4 11091.5 11136.4 11190.9 11480.4 9281.3 9313.0 9321.4 9426.7 9658.1 8709.9 9321.6 9389.6 9440.5 9615.0 22:43:12 INFO - PROCESS | 1937 | -------- Summary: end -------- 22:43:12 INFO - PROCESS | 1937 | 22:43:13 INFO - TEST-INFO | 1937: exit 0 22:43:13 INFO - TEST-OK | dromaeo_css | took 1112603ms 22:43:13 INFO - TEST-START | kraken 22:43:13 INFO - operating with platform_type : mac_ 22:43:13 INFO - Initialising browser for kraken test... 22:43:13 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpp6AcpE/profile http://localhost:49469/getInfo.html) 22:43:16 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 22:43:16 INFO - PROCESS | 1946 | colorDepth:24 22:43:16 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 22:43:16 INFO - PROCESS | 1946 | __metrics 22:43:16 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 22:43:17 INFO - TEST-INFO | 1946: exit 0 22:43:17 INFO - Browser initialized. 22:43:17 INFO - Running cycle 1/1 for kraken test... 22:43:17 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpp6AcpE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:43:29 INFO - PROCESS | 1949 | RSS: Main: 249974784 22:43:29 INFO - PROCESS | 1949 | 22:43:31 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49469/tests/kraken/driver.html?testName=audio-beat-detection) 22:43:32 INFO - PROCESS | 1949 | RSS: Main: 261586944 22:43:32 INFO - PROCESS | 1949 | 22:43:35 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49469/tests/kraken/driver.html?testName=audio-dft) 22:43:36 INFO - PROCESS | 1949 | RSS: Main: 266346496 22:43:36 INFO - PROCESS | 1949 | 22:43:39 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49469/tests/kraken/driver.html?testName=audio-fft) 22:43:40 INFO - PROCESS | 1949 | RSS: Main: 267198464 22:43:40 INFO - PROCESS | 1949 | 22:43:42 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49469/tests/kraken/driver.html?testName=audio-oscillator) 22:43:43 INFO - PROCESS | 1949 | RSS: Main: 267857920 22:43:43 INFO - PROCESS | 1949 | 22:43:45 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49469/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:43:45 INFO - PROCESS | 1949 | RSS: Main: 268410880 22:43:45 INFO - PROCESS | 1949 | 22:43:48 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49469/tests/kraken/driver.html?testName=imaging-darkroom) 22:43:49 INFO - PROCESS | 1949 | RSS: Main: 270315520 22:43:49 INFO - PROCESS | 1949 | 22:43:53 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49469/tests/kraken/driver.html?testName=imaging-desaturate) 22:43:53 INFO - PROCESS | 1949 | RSS: Main: 271323136 22:43:53 INFO - PROCESS | 1949 | 22:43:56 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49469/tests/kraken/driver.html?testName=json-parse-financial) 22:43:57 INFO - PROCESS | 1949 | RSS: Main: 270860288 22:43:57 INFO - PROCESS | 1949 | 22:43:59 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49469/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:43:59 INFO - PROCESS | 1949 | RSS: Main: 270155776 22:43:59 INFO - PROCESS | 1949 | 22:44:01 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:44:02 INFO - PROCESS | 1949 | RSS: Main: 270659584 22:44:02 INFO - PROCESS | 1949 | 22:44:04 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:44:05 INFO - PROCESS | 1949 | RSS: Main: 270479360 22:44:05 INFO - PROCESS | 1949 | 22:44:08 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:44:09 INFO - PROCESS | 1949 | RSS: Main: 271089664 22:44:09 INFO - PROCESS | 1949 | 22:44:11 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:44:12 INFO - PROCESS | 1949 | RSS: Main: 270487552 22:44:12 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49469/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49469/tests/kraken/driver.html?testName=ai-astar) 22:44:14 INFO - PROCESS | 1949 | RSS: Main: 273432576 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | __start_tp_report 22:44:14 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 22:44:14 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 22:44:14 INFO - PROCESS | 1949 | |i|pagename|runs| 22:44:14 INFO - PROCESS | 1949 | |0;ai-astar;115;124;120;96;163;167;137;116;116;115 22:44:14 INFO - PROCESS | 1949 | |1;audio-beat-detection;161;120;146;199;118;142;150;119;163;121 22:44:14 INFO - PROCESS | 1949 | |2;audio-dft;196;197;221;272;243;196;198;199;197;195 22:44:14 INFO - PROCESS | 1949 | |3;audio-fft;75;83;75;192;70;77;77;92;154;60 22:44:14 INFO - PROCESS | 1949 | |4;audio-oscillator;78;80;141;78;82;85;122;79;81;119 22:44:14 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;101;101;91;89;97;84;123;114;92;98 22:44:14 INFO - PROCESS | 1949 | |6;imaging-darkroom;124;139;142;98;110;142;101;139;101;99 22:44:14 INFO - PROCESS | 1949 | |7;imaging-desaturate;92;91;90;90;90;93;94;130;92;92 22:44:14 INFO - PROCESS | 1949 | |8;json-parse-financial;57;60;66;79;82;69;83;129;60;60 22:44:14 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;70;68;76;69;85;74;73;68;66;68 22:44:14 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;84;83;88;88;85;88;86;85;85;86 22:44:14 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;154;153;162;142;153;153;134;136;259;154 22:44:14 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;209;221;169;165;168;164;167;168;169;165 22:44:14 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;68;67;67;65;67;65;68;66;80;78 22:44:14 INFO - PROCESS | 1949 | __end_tp_report 22:44:14 INFO - PROCESS | 1949 | __start_cc_report 22:44:14 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1091 22:44:14 INFO - PROCESS | 1949 | __end_cc_report 22:44:14 INFO - PROCESS | 1949 | __startTimestamp1463809454603__endTimestamp 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | ------- Summary: start ------- 22:44:14 INFO - PROCESS | 1949 | Number of tests: 14 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:126.90 Median:130.50 stddev:22.47 (17.2%) stddev-sans-first:23.42 22:44:14 INFO - PROCESS | 1949 | Values: 115.0 124.0 120.0 96.0 163.0 167.0 137.0 116.0 116.0 115.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:143.90 Median:148.00 stddev:26.09 (17.6%) stddev-sans-first:26.93 22:44:14 INFO - PROCESS | 1949 | Values: 161.0 120.0 146.0 199.0 118.0 142.0 150.0 119.0 163.0 121.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:211.40 Median:198.50 stddev:26.36 (13.3%) stddev-sans-first:27.37 22:44:14 INFO - PROCESS | 1949 | Values: 196.0 197.0 221.0 272.0 243.0 196.0 198.0 199.0 197.0 195.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:95.50 Median:76.00 stddev:42.61 (56.1%) stddev-sans-first:44.54 22:44:14 INFO - PROCESS | 1949 | Values: 75.0 83.0 75.0 192.0 70.0 77.0 77.0 92.0 154.0 60.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:79.50 stddev:23.43 (29.5%) stddev-sans-first:24.08 22:44:14 INFO - PROCESS | 1949 | Values: 78.0 80.0 141.0 78.0 82.0 85.0 122.0 79.0 81.0 119.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:90.00 stddev:11.79 (13.1%) stddev-sans-first:12.49 22:44:14 INFO - PROCESS | 1949 | Values: 101.0 101.0 91.0 89.0 97.0 84.0 123.0 114.0 92.0 98.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:119.50 Median:140.50 stddev:19.58 (13.9%) stddev-sans-first:20.70 22:44:14 INFO - PROCESS | 1949 | Values: 124.0 139.0 142.0 98.0 110.0 142.0 101.0 139.0 101.0 99.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:92.00 stddev:12.23 (13.3%) stddev-sans-first:12.91 22:44:14 INFO - PROCESS | 1949 | Values: 92.0 91.0 90.0 90.0 90.0 93.0 94.0 130.0 92.0 92.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:74.50 Median:67.50 stddev:21.50 (31.8%) stddev-sans-first:21.85 22:44:14 INFO - PROCESS | 1949 | Values: 57.0 60.0 66.0 79.0 82.0 69.0 83.0 129.0 60.0 60.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:5.64 (7.9%) stddev-sans-first:5.95 22:44:14 INFO - PROCESS | 1949 | Values: 70.0 68.0 76.0 69.0 85.0 74.0 73.0 68.0 66.0 68.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:1.75 (2.0%) stddev-sans-first:1.73 22:44:14 INFO - PROCESS | 1949 | Values: 84.0 83.0 88.0 88.0 85.0 88.0 86.0 85.0 85.0 86.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:160.00 Median:153.50 stddev:35.90 (23.4%) stddev-sans-first:38.01 22:44:14 INFO - PROCESS | 1949 | Values: 154.0 153.0 162.0 142.0 153.0 153.0 134.0 136.0 259.0 154.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:168.50 stddev:20.56 (12.2%) stddev-sans-first:18.13 22:44:14 INFO - PROCESS | 1949 | Values: 209.0 221.0 169.0 165.0 168.0 164.0 167.0 168.0 169.0 165.0 22:44:14 INFO - PROCESS | 1949 | 22:44:14 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:67.50 stddev:5.34 (7.9%) stddev-sans-first:5.65 22:44:14 INFO - PROCESS | 1949 | Values: 68.0 67.0 67.0 65.0 67.0 65.0 68.0 66.0 80.0 78.0 22:44:14 INFO - PROCESS | 1949 | -------- Summary: end -------- 22:44:14 INFO - PROCESS | 1949 | 22:44:15 INFO - TEST-INFO | 1949: exit 0 22:44:15 INFO - TEST-OK | kraken | took 62124ms 22:44:15 INFO - SUITE-END | took 1174s 22:44:15 INFO - Completed test suite (00:19:34) 22:44:15 INFO - javascript benchmark 22:44:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7152.393212382793, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3508.5263489428926, 3518.592562974808, 3534.522839416678, 3551.9289614207714, 3561.9109522261942, 4711.316859055435, 5851.063829787229, 5858.443447872451, 5945.197398351222, 6003.609765365253, 5765.567582431324, 5869.941300587004, 5904.498117659993, 5957.225560677106, 5988.431099045594, 5227.2159176123605, 5265.999460102568, 5300.284461597693, 5376.542993845528, 5429.375621803503, 5329.173978033392, 5393.056215162351, 5446.863828404282, 5460.044492213847, 5495.725213739317, 4394.868153955386, 4496.5278645742055, 5244.265802787594, 5268.631195816294, 5346.438623944488, 4424.269995450747, 5304.681719096867, 5314.202869569595, 5376.650517716375, 5452.672839629595, 5290.603204759658, 5328.333958255218, 5352.545033672142, 5352.785888564453, 5367.785288588452, 4825.613950883921, 5669.574781891333, 5835.766569337221, 5853.004989151855, 5853.912191317134, 6179.227596550431, 6261.7182226799905, 6277.372262773732, 6279.937200627981, 6289.591176573554, 6192.783252586183, 6205.286392064925, 6279.597794356156, 6306.148669929593, 6317.557770956035, 5911.379305172938, 5977.103434484862, 5977.581569290108, 5984.1622172895395, 6035.969820150915, 6289.233154904859, 6337.493000559944, 6348.365163483615, 6365.0, 6365.49076073913, 6168.475679567287, 6172.135900973867, 6183.227096612924, 6233.688315584202, 6234.657093859882, 6305.558610897193, 6308.779192728232, 6321.683915804191, 6326.2724786649205, 6328.177336946167, 6111.561351782648, 6135.44703110649, 6213.285472170758, 6242.438180563771, 6248.9062664060075, 6112.26032301701, 6186.19579454677, 6196.0, 6259.843503912439, 6280.811575652647, 5102.923456148161, 5103.8724031898755, 5104.336436263262, 5114.33513643224, 5150.742462876841, 4517.277235642281, 4620.838270660511, 4626.167289887852, 4626.699264547794, 4667.7199368037345, 5733.054046082433, 5967.343592204854, 5998.970005150034, 6024.849378765566, 6061.666608336495, 6224.4558443042315, 6315.021171718391, 6373.649449280334, 6482.222133344029, 6554.14796076507, 4793.424789025339, 6018.578699490994, 6022.57841951068, 6048.395160483917, 6051.878962420815, 4761.785719642625, 5529.612927095065, 5571.721413929287, 5613.943860561424, 5633.577481688808, 6570.737170513222, 6622.1722284714415, 6627.23786764509, 6628.900566491603, 6648.66756662165, 6218.347073557392, 6347.238331400077, 6347.365263473615, 6363.459105976044, 6389.456896163692, 6436.324185960407, 6468.3855033771715, 6502.902456463063, 6536.934630653633, 6602.900956485752, 6235.189425374672, 6267.091271765477, 6268.561200715906, 6285.120083188449, 6285.182926219745, 6181.629102253879, 6217.4715149212825, 6322.652254126067, 6340.61956282606, 6341.112244285896, 6141.025769127022, 6180.103884936749, 6181.320054794059, 6226.097215903759, 6270.372962703693, 5246.921296180484, 6183.721732521957, 6216.875662486635, 6221.813345599639, 6254.186955695731, 5303.204519322133, 6065.120557519223, 6132.570720049732, 6194.318624951342, 6199.659018754011, 6187.752489900534, 6191.938080619136, 6215.751369945332, 6255.46828519581, 6256.937430625636, 6200.100985357008, 6208.503319734501, 6223.066540019034, 6228.5951413158, 6246.875062498815], "value": 5796.638186290069, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.4602514221785, 507.3176577503258, 531.8590573498024, 535.1143856916801, 558.3690429814309, 35469.2906141877, 37643.0, 38763.61236387635, 38800.02999925003, 38908.0, 39853.202935941335, 40362.79818600903, 40640.39039414404, 40671.98320042001, 40807.79596102023, 8451.450655707391, 9317.20803731682, 9593.376430532027, 9599.280053995979, 9605.27960402969, 9414.952925235364, 9548.952255238713, 9566.091221333962, 9573.13841754242, 9605.231581473465, 9537.570809313564, 9571.617135314578, 9588.664396746115, 9592.280578956572, 9594.808103837953, 9500.809983800355, 9551.808963820684, 9557.99641037688, 9563.139317461428, 9566.712998610017, 9436.811263774754, 9556.187724043395, 9567.712968610953, 9609.327347085708, 9641.758956026086, 36976.890693279245, 37060.0, 37120.44319335212, 37120.814395927846, 37505.0, 2147.903344349508, 2861.5850701648146, 3046.299351149248, 3373.9662603374145, 3658.2317713280245, 27233.591496127574, 28196.436071278666, 28405.0, 28552.71447285542, 28573.714182861822, 2355.364051706047, 2470.3330100872845, 2475.344033831036, 2482.329770961848, 2547.0957809977563, 422527.8873605643, 427107.0, 429755.0, 430588.0, 431141.0, 32925.50611740826, 33098.3380332392, 33170.66829331724, 33269.50095748566, 33317.83341083279, 33970.660293397246, 34476.655233447345, 34651.30697386038, 34791.82604086964, 34816.0, 14724.0, 15104.622384440478, 15242.847571524364, 15243.23783810805, 15258.542243732705, 12713.364331783374, 12904.290264035568, 13042.152260103063, 13052.412641431158, 13066.673333166747, 7510.1363343215135, 7715.112762032438, 7770.184130666312, 7777.105632852181, 7792.2986931176465, 3231.095293317828, 3275.8853440129483, 3297.027376923765, 3303.6035675718585, 3324.268660894648, 867.6355930509201, 987.7876256604865, 1010.4644538394663, 1063.3832377221192, 1137.6018393562188, 28285.57571636427, 28418.573721394194, 28492.28769280743, 29005.41989160205, 29022.419551609273, 1038.8337865941412, 1052.7262911642874, 1120.7086157599083, 1150.5915400033034, 1158.3455347728507, 14621.0, 14746.778798318033, 14867.0, 14999.700006000037, 15167.089974601558, 626210.0, 627191.0, 628546.0, 633287.0, 633832.0, 48190.75904620525, 49180.75409622929, 57076.0, 57307.0, 57686.423135768106, 1268.7462507498353, 1391.08883681189, 1449.4419648435216, 1453.0990785712925, 1454.1929229277641, 27148.864255678596, 27914.302142446602, 28102.437951240452, 28410.715892840806, 28619.998300059397, 46321.305180423, 46635.0, 46781.532184679076, 47449.0, 47629.523704763895, 497779.0, 505777.4711126568, 506365.0, 507919.0, 509541.0, 25145.622815657414, 25367.731613418888, 25665.615015774405, 25819.709014548822, 25974.0, 44189.558104418546, 44353.77823110864, 44946.550534494236, 45234.32148517841, 45365.0, 37097.25805483821, 37384.81307593445, 37385.626143739304, 37583.81208093942, 38050.0, 34354.484682730275, 36158.09604759902, 36731.78128623707, 37361.0, 37413.43879841859, 30494.76452781447, 35085.29829403449, 36099.0, 36864.89405317845, 38321.42517862179, 29603.555946660388, 31447.52828707617, 32375.51436728404, 32422.83788581042, 32572.0, 23377.41556461102, 24383.0, 24441.633375499736, 24777.0, 24850.254492365257], "value": 17654.5988188963, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.4523996145839, 381.87347325390107, 384.1816929939229, 401.92765302245584, 409.2183928696191, 18631.06844657767, 19556.511087222792, 21176.152953881825, 21196.682049769246, 21259.787402125974, 10238.283320167591, 11787.17489775716, 11799.646010619696, 11827.645170644873, 17624.20691068902, 5347.491988261128, 5508.7521061552325, 5533.446655334455, 5543.833684989457, 5564.276644036269, 3920.3727403615426, 5834.56240781941, 6131.110988906606, 6146.84632884177, 6211.782587609412, 6075.2054055134695, 6197.814065578017, 6202.100695399165, 6223.533235007392, 6612.140421745166, 6716.1604799400075, 6723.798286051402, 6727.697253623599, 6732.528722989368, 6738.52830301879, 5940.376260492672, 6679.833004174886, 7265.818354541126, 7342.118945726494, 7360.2271761465345, 26894.0, 27890.3027424314, 29278.41443171146, 29354.0, 29458.558121628193, 2136.6794980752857, 2153.493928926701, 2165.9241926532495, 2198.0, 2203.06369792839, 16211.0, 16272.918635406866, 16437.178141092896, 16667.91666041674, 16742.74885876713, 1640.2290923266046, 1924.5573518090778, 1947.57153426246, 1954.7263383126374, 1962.3916585858287, 134837.0, 136313.0, 137607.31196343954, 138651.99999999898, 139387.0, 18479.81520184808, 21165.788342116382, 21928.122875085137, 22025.0, 22241.110355585595, 35142.82428587841, 35665.0, 35775.4633680495, 35790.64209357925, 35856.462153067725, 10974.012338889379, 11189.0, 11248.550057997589, 11296.0, 11336.03643690279, 9190.713300137986, 9223.584938677775, 9258.583363748647, 9260.861087083698, 9366.62533498666, 9935.105840474393, 9974.900250997542, 9987.350822196535, 10086.29395942277, 10148.391096534231, 3712.925741485155, 3870.9225815484097, 3911.647951684362, 4105.035316700573, 4133.689973252018, 905.2667339455063, 952.3999880075509, 977.2279898879948, 994.7264502261936, 1011.5094179323022, 19840.900795496218, 20328.186872525228, 20495.385138445872, 20695.8965205173, 21055.68416473754, 919.3288899103558, 935.1116439382614, 989.5200827598609, 1010.0656892374524, 1029.6653587584065, 9154.81690366189, 10378.948105259577, 10584.941505849354, 10701.136465681418, 10740.140788736882, 611298.0, 625112.874435634, 636132.8193359004, 636621.0, 670279.0, 116692.41653791847, 128950.71049289574, 129476.0, 131674.34162829124, 136854.0, 941.4445477168366, 954.2652157838426, 975.3513913247752, 989.5992123190119, 992.9751756206008, 17433.56416089608, 18279.54301142482, 18415.723764143815, 18452.63094738071, 18491.260349586402, 54546.0, 55214.0, 55563.44436555744, 55633.443665564446, 55722.72138639281, 62464.68767656133, 79061.20938790539, 79295.62113136615, 82346.17653823385, 83973.58013209894, 7595.278448547379, 7790.0651921767485, 8004.0, 8148.511089334659, 8324.0427350853, 11267.26762760418, 12435.564755233523, 12575.371231438574, 12807.93596032014, 12816.166949148275, 10750.156468870184, 10759.031687148194, 10764.892351076389, 10788.244822862323, 10907.400092994962, 10607.628732994308, 10631.8936810634, 10736.604241448562, 10964.945175274392, 11005.1746119043, 5642.915356269741, 6717.966410167918, 10509.527071281964, 10903.400312982862, 11459.31244125355, 8037.718679846177, 8051.718189863561, 8052.919470805451, 8132.83734325322, 8239.588020599065, 8053.073896501977, 9988.300818942604, 10881.401522916316, 11208.0, 11221.990020898158], "value": 10594.728769647123, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1526.3436722209449, 1561.4534912780528, 1758.9736153957692, 1866.9906650466753, 1873.9599522265155, 7980.9201907980905, 8545.61544730487, 8592.613332400042, 8972.91027089729, 8983.28133749301, 8529.829403411928, 8599.140085991397, 8746.256568191695, 8751.824963500727, 8760.474371537712, 2322.4890524084676, 2345.6716059751648, 2357.8585284882874, 2358.6108292131776, 2362.621980483123, 2375.70303712036, 2386.9880650596724, 2393.9521209575796, 2405.939851503718, 2407.843490173142, 2393.664886915833, 2396.604560247557, 2409.915652952147, 2415.9758402416014, 2417.6373543968457, 2371.34787933318, 2383.833131680783, 2384.5469360821476, 2388.3670827230712, 2396.3769419950936, 2339.6724458575814, 2349.917752878658, 2363.8108951283857, 2365.7161140663056, 2378.452955820154, 7551.77344679655, 8710.825783484295, 8800.515971621558, 8813.0, 8858.512781796999, 4805.351277577517, 5354.089804733205, 5435.42927992559, 5448.972755136199, 5449.346078470569, 3429.657034296575, 3440.088376580208, 3520.0847779577493, 3614.3855544557487, 3635.4001589737663, 911.9316051296179, 1027.126942099217, 1045.796069766396, 1046.3042077018774, 1046.9267151299412, 11375.488103035383, 11446.084313255004, 11474.770504589946, 11560.0, 11611.071114310835, 4533.252013417815, 4541.114482675846, 4576.748278844629, 4581.404417425713, 4606.286025665961, 9246.768830779285, 9265.861012084824, 9269.860952085724, 9282.582283797245, 9292.72121836346, 5191.2472691459525, 5264.473552644705, 5272.20916862466, 5274.525292723597, 5303.973480132575, 4471.5528447155675, 4803.639727020487, 4900.950990490121, 4962.851114466572, 4975.228839529878, 3755.4742336072973, 3819.503464549646, 3849.3071247175776, 3855.749376290532, 3936.075022369741, 1108.5731993182683, 1153.4982282707049, 1175.5474142455216, 1183.8461000069935, 1189.1735244005506, 814.2346194577078, 912.1425859691868, 917.1149840404042, 950.7575568230058, 951.7953639967302, 5624.437556244343, 5701.515371193412, 5877.0596704527925, 5963.254593175853, 6071.483923866433, 432.27809558038155, 432.8549935771489, 434.96520278377653, 440.9162259170747, 452.53389009320455, 3914.0410599402758, 3970.1464185199743, 3977.7016723745833, 4051.2707712611423, 4071.470708807836, 10298.330608510423, 10610.628627997983, 10768.730781730363, 10776.245662803529, 10789.892101078889, 10639.468026598794, 10707.625233116803, 10722.946385268024, 10745.892541074645, 10756.0, 7828.45200835936, 8182.0, 8188.426810123353, 8188.50868947865, 8238.958805206055, 6006.438326035175, 6009.278886533644, 6095.085737139288, 6138.416850399205, 6164.876702466016, 9837.311388203043, 9987.950060249652, 10046.598136074475, 10102.39385636864, 10103.797924041331, 9847.261455390731, 10144.594216231268, 10165.949170254102, 10303.175745940456, 10312.690619281433, 6922.757703480354, 7279.890801637874, 7459.2540745927145, 7530.246975302426, 7702.075750909927, 7089.397401220954, 7302.488825782144, 8234.629441675259, 8243.83512329762, 8248.13394593559, 7299.671514781954, 7381.1511676157925, 7403.962980185064, 7503.474756767192, 7550.697972081055, 6444.967775161094, 6476.676166191766, 7279.162896266997, 7368.668409921459, 7391.556506609406, 6906.689198986158, 7004.509684322253, 7018.789436316919, 7049.823754406181, 7082.893756593759, 3999.8800035998966, 4480.081583275355, 4485.775711214492, 4531.637469002406, 4597.965457771975, 4525.909481810279, 4900.460949295503, 5050.510099520577, 5105.7191854448365, 5238.37139543242], "value": 4448.585746008066, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.72677680252986, 310.5590062111801, 311.3026819923371, 314.81583273784827, 321.2098238333699, 5556.444355564441, 5585.4360778981845, 5605.523530499912, 5626.352969408515, 5638.731285460769, 4852.053849499349, 4896.0, 5065.620078494109, 5094.363204599425, 5510.6969116698565, 3779.1874746929407, 3817.6373244541724, 3912.3349030664785, 4065.5324637666604, 4157.646600039, 3775.6413140751583, 3901.941470877939, 4005.2790497710403, 4108.096218831853, 4160.459140311756, 3682.8526858925743, 3708.7589306695113, 4016.8192431340512, 4132.173565286939, 4158.334666453368, 3626.9455958160647, 3838.846446142151, 3863.652271295597, 4007.6994225433064, 4153.937690934638, 3470.0457374222037, 3595.993121925865, 3607.0080727799805, 3626.7098632109632, 3628.546431696038, 4687.789049492747, 5285.885645741734, 5290.232916227164, 5294.49702278283, 5312.415634280226, 4107.671386289119, 4173.373993900909, 4231.355402873204, 4760.976195119037, 4816.759162041884, 5812.534997200214, 5850.795222167247, 5858.627255961343, 5875.559333050039, 5900.616459930134, 3526.9118272043147, 3675.852965881388, 3909.0032041829436, 3926.6466018058227, 3953.683705303569, 5129.846104616868, 5738.397468265855, 5992.221011268507, 6117.66352850593, 6161.9216637088775, 3438.707709844691, 3552.3605750965075, 3942.7831469269468, 3951.920961580752, 3984.2828290907914, 3716.386796178654, 3738.7569807962395, 4007.6593489553716, 4048.716589838742, 4076.6331030207425, 4153.979230103831, 4450.933236001462, 4676.485586585472, 4707.552782485658, 4733.455652599926, 4913.0, 4950.851474455772, 5534.335879694462, 5712.714364281769, 5914.9112763308585, 1224.8407706998032, 1309.967250818737, 1368.6988862450246, 1381.2886363522794, 1384.819973403451, 795.383577727266, 831.0235473318947, 840.7855996720797, 841.6296829395046, 849.613425891218, 4854.538818812207, 4935.580475659537, 5350.91973620396, 5479.013777520005, 5514.80698175562, 5640.407757185519, 5849.239598852122, 6107.541934354941, 6112.0, 6127.2647282325515, 4520.728756274634, 5107.438181799999, 5380.569554435636, 5470.343558772893, 5531.308586426697, 2819.4220184862056, 3189.888353907602, 3219.5492631031675, 3224.048905572815, 3230.547723318737, 5289.0, 6290.370962903673, 6304.968475157595, 6347.904781428282, 6362.141110949962, 6218.533609979179, 6224.844378890473, 6268.623882567061, 6272.968635156795, 6274.2784579773, 5281.9207711884355, 6295.779647712399, 6320.399562041599, 6329.461995730322, 6349.714262858182, 5520.309961254843, 5651.57613179005, 5655.0, 6051.304100028553, 6084.787032453843, 5200.297959775382, 5383.87735113641, 6003.219581454383, 6014.346054834956, 6018.488428483628, 5675.432456754292, 5909.133232381128, 6032.707571122945, 6088.208532890696, 6168.915542228886, 3327.683870032343, 3741.5136032315627, 3923.0, 3938.232044751246, 3945.881623551298, 4962.900741985212, 5540.066552747433, 6051.727672254671, 6059.424744483665, 6080.726367313393, 4942.75286235694, 5086.21163719624, 5529.640573362719, 5622.915656265078, 5807.274090738658, 5201.609879258995, 5617.286727547974, 5787.5369970401425, 5867.501262392744, 5867.618604790675, 4905.681130726492, 5070.847874563769, 5733.627314224562, 5733.627314224729, 5753.942460575341, 4868.853934381974, 5537.529310008695, 5798.768049277982, 5841.591088623755, 5929.703514824328, 4810.206315957827, 4932.975335123301, 5569.777208911598, 5583.352910891215, 5720.427957204246], "value": 4182.347244513443, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.3073811022206, 656.9113169722086, 694.3958755882383, 720.8233982674245, 732.282363283982, 14731.263436828169, 15153.54539363821, 15461.6134596635, 15492.845071549282, 15496.612584685388, 12312.692182695437, 13974.231417272045, 14198.78701819471, 14311.141331520115, 14342.71314573708, 685.239384283445, 732.9853402931965, 844.320322140675, 858.9441686290403, 859.1194026123211, 831.9584020798966, 853.4580541356253, 853.7822855171953, 853.8463076646201, 856.5503110866814, 905.597009330848, 906.8503696890006, 906.9863952040724, 907.2016625369665, 907.5326207003399, 889.9110088991114, 895.2882458445551, 897.5826240798027, 898.0346127912487, 906.125588806798, 648.8669822686338, 662.8011596521025, 663.8838203314401, 664.6610228783306, 666.5534092158266, 14988.550343489713, 14994.100353978687, 14999.1750453725, 15012.249387530688, 15013.624659383493, 11962.700932476671, 12127.696807579881, 12691.555795547203, 12933.159344642569, 13094.934525327313, 9801.16690081344, 9985.600575976952, 9998.100170984573, 10200.0819926207, 10350.792984140351, 3361.8151001694896, 3551.129973156593, 3564.572251329831, 3591.28174365128, 3666.724995625312, 83113.58443207806, 84018.57990710069, 84368.57815710944, 84760.15239847646, 85196.14803851944, 9056.818863622691, 9176.128267814547, 9260.351775375659, 9269.768255793524, 9407.0, 19739.703904441445, 19980.90009549943, 20278.0, 20315.8984205081, 20547.589048218953, 7192.928070719226, 7255.201927787939, 7267.818304542428, 7292.8906066409045, 7403.481756277117, 3545.2377738786286, 3970.2258059678084, 3984.2230765001127, 4006.1587066715733, 4010.6189911958318, 3385.23832137767, 3421.5209870618132, 3469.930601387958, 3470.4273794824076, 3472.0, 558.8407303918364, 627.6485168305763, 629.0752593687272, 633.762339122829, 657.1686816177539, 495.28678702668043, 519.605100123901, 537.6290359651828, 566.7308028686424, 584.1588113117098, 7700.614969251515, 7792.571408572469, 7827.25641064098, 8050.959745201354, 8145.789769086738, 495.45339919600445, 504.6164914664808, 516.246280430568, 549.3545084525666, 571.7284289962234, 2558.7952963762855, 2957.3937342844665, 3004.879804807827, 3038.14931819091, 3087.799293045945, 85446.14553854382, 90181.0, 91714.54142729244, 91797.54101229452, 92407.0, 79984.60007699924, 80481.59759201166, 82446.17553824505, 82803.0, 82842.0, 65638.6872262552, 66121.66939165273, 66777.33222667809, 69670.0, 71519.28480715126, 7019.824504387431, 7958.0848202455145, 8089.595520223847, 8095.87856182146, 8171.142030086755, 36437.72467963715, 43227.78386108049, 44919.77540112279, 44981.0, 46235.76882115568, 53035.0, 57343.13985290308, 59029.40970590411, 61598.4600384994, 62774.37225627685, 10610.8408373876, 11133.220674552704, 11174.944125279322, 11276.661700149009, 11363.715907102389, 10501.684949451528, 11586.71033224176, 11673.708157296136, 11871.287722736663, 12294.139410241198, 11565.59520416815, 11618.88381116178, 11634.185607007428, 11659.475323610628, 11778.882211177779, 11082.05802506796, 11518.251313664585, 11751.353675547927, 11774.470148843151, 11971.76056478883, 11008.449577521253, 11091.50088246047, 11136.38749868765, 11190.88809111931, 11480.425978700865, 9281.303902207226, 9313.022132675971, 9321.394109382869, 9426.670066547638, 9658.082482164184, 8709.948230026695, 9321.627134914528, 9389.577469014048, 9440.527973601429, 9614.95192524033], "value": 6635.891237709131, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1623.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 124.0, 120.0, 96.0, 163.0, 167.0, 137.0, 116.0, 116.0, 115.0], "value": 126.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 120.0, 146.0, 199.0, 118.0, 142.0, 150.0, 119.0, 163.0, 121.0], "value": 143.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 197.0, 221.0, 272.0, 243.0, 196.0, 198.0, 199.0, 197.0, 195.0], "value": 211.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 75.0, 192.0, 70.0, 77.0, 77.0, 92.0, 154.0, 60.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 141.0, 78.0, 82.0, 85.0, 122.0, 79.0, 81.0, 119.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 91.0, 89.0, 97.0, 84.0, 123.0, 114.0, 92.0, 98.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 139.0, 142.0, 98.0, 110.0, 142.0, 101.0, 139.0, 101.0, 99.0], "value": 119.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 90.0, 90.0, 90.0, 93.0, 94.0, 130.0, 92.0, 92.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 66.0, 79.0, 82.0, 69.0, 83.0, 129.0, 60.0, 60.0], "value": 74.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 76.0, 69.0, 85.0, 74.0, 73.0, 68.0, 66.0, 68.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 88.0, 88.0, 85.0, 88.0, 86.0, 85.0, 85.0, 86.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 162.0, 142.0, 153.0, 153.0, 134.0, 136.0, 259.0, 154.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 221.0, 169.0, 165.0, 168.0, 164.0, 167.0, 168.0, 169.0, 165.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 65.0, 67.0, 65.0, 68.0, 66.0, 80.0, 78.0], "value": 69.1, "unit": "score"}]}]} 22:44:15 INFO - Return code: 0 22:44:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:44:15 ERROR - # TBPL SUCCESS # 22:44:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:15 INFO - 2016-05-20 22:44:15.889 screenresolution[1954:12611] starting screenresolution argv=screenresolution get 22:44:15 INFO - 2016-05-20 22:44:15.892 screenresolution[1954:12611] Display 0: 1600x1200x32@60 22:44:15 INFO - 2016-05-20 22:44:15.898 screenresolution[1955:12614] starting screenresolution argv=screenresolution list 22:44:15 INFO - Available Modes on Display 0 22:44:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:16 INFO - Intel Iris: 22:44:16 INFO - Chipset Model: Intel Iris 22:44:16 INFO - Type: GPU 22:44:16 INFO - Bus: Built-In 22:44:16 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:16 INFO - Vendor: Intel (0x8086) 22:44:16 INFO - Device ID: 0x0a2e 22:44:16 INFO - Revision ID: 0x0009 22:44:16 INFO - Displays: 22:44:16 INFO - Display: 22:44:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:16 INFO - Display Serial Number: 1600x1200 60 22:44:16 INFO - Main Display: Yes 22:44:16 INFO - Mirror: Off 22:44:16 INFO - Online: Yes 22:44:16 INFO - Rotation: Supported 22:44:16 INFO - Return code: 0 22:44:16 INFO - Running post-action listener: _resource_record_post_action 22:44:16 INFO - ##### 22:44:16 INFO - ##### Finished run-tests step (success) 22:44:16 INFO - ##### 22:44:16 INFO - Running post-run listener: _resource_record_post_run 22:44:16 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1957888; Write bytes: 698792448; Read time: 24; Write time: 2151 22:44:16 INFO - TinderboxPrint: CPU usage
54.6% 22:44:16 INFO - TinderboxPrint: I/O read bytes / time
1,957,888 / 24 22:44:16 INFO - TinderboxPrint: I/O write bytes / time
698,792,448 / 2,151 22:44:16 INFO - TinderboxPrint: CPU idle
2,197.4 (45.4%) 22:44:16 INFO - TinderboxPrint: CPU system
978.1 (20.2%) 22:44:16 INFO - TinderboxPrint: CPU user
1,666.2 (34.4%) 22:44:16 INFO - TinderboxPrint: Swap in / out
399,163,392 / 0 22:44:16 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 387877376; Write bytes: 382232576; Read time: 29426; Write time: 793 22:44:16 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1097728; Write bytes: 299143680; Read time: 19; Write time: 1307 22:44:16 INFO - Running post-run listener: _upload_blobber_files 22:44:16 INFO - Blob upload gear active. 22:44:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:44:16 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 22:44:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:44:17 INFO - (blobuploader) - INFO - Open directory for files ... 22:44:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:44:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:44:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:18 INFO - (blobuploader) - INFO - Done attempting. 22:44:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:44:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:44:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:19 INFO - (blobuploader) - INFO - Done attempting. 22:44:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:44:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:44:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:21 INFO - (blobuploader) - INFO - Done attempting. 22:44:21 INFO - (blobuploader) - INFO - Iteration through files over. 22:44:21 INFO - Return code: 0 22:44:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:44:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:44:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c13c41e1ed6c497991bfb799ffab9c5cd4b9e5ff1753c455cb3c919a7c9924622dfbd0414d7b1856b99020939157daadebf2fe973cbd026224f898b412bd4b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb3bdad3b808b746f05f1b038c81053dd44ac303a532001ba832d588247f6260c0afd3a3932c49371aebf48230f2d33d356aa2727d1e3131aeb28d504b6e72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99502fddaa54159d88065dc459e81e1bd12558ce5c580759b54de682c64194d1dd161afa941e1b82fbcdfcd69125076d4e4447292f0f214e162fa351c08d42f1"} 22:44:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:44:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:44:21 INFO - Contents: 22:44:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c13c41e1ed6c497991bfb799ffab9c5cd4b9e5ff1753c455cb3c919a7c9924622dfbd0414d7b1856b99020939157daadebf2fe973cbd026224f898b412bd4b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb3bdad3b808b746f05f1b038c81053dd44ac303a532001ba832d588247f6260c0afd3a3932c49371aebf48230f2d33d356aa2727d1e3131aeb28d504b6e72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99502fddaa54159d88065dc459e81e1bd12558ce5c580759b54de682c64194d1dd161afa941e1b82fbcdfcd69125076d4e4447292f0f214e162fa351c08d42f1"} 22:44:21 INFO - Running post-run listener: copy_logs_to_upload_dir 22:44:21 INFO - Copying logs to upload dir... 22:44:21 INFO - mkdir: /builds/slave/test/build/upload/logs 22:44:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.357615 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-20 22:44:21.786104) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 22:44:21.789683) ========= 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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c13c41e1ed6c497991bfb799ffab9c5cd4b9e5ff1753c455cb3c919a7c9924622dfbd0414d7b1856b99020939157daadebf2fe973cbd026224f898b412bd4b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb3bdad3b808b746f05f1b038c81053dd44ac303a532001ba832d588247f6260c0afd3a3932c49371aebf48230f2d33d356aa2727d1e3131aeb28d504b6e72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99502fddaa54159d88065dc459e81e1bd12558ce5c580759b54de682c64194d1dd161afa941e1b82fbcdfcd69125076d4e4447292f0f214e162fa351c08d42f1"} build_url:https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010997 build_url: 'https://queue.taskcluster.net/v1/task/RxSGKEWoR4ugOHvrsQykVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c13c41e1ed6c497991bfb799ffab9c5cd4b9e5ff1753c455cb3c919a7c9924622dfbd0414d7b1856b99020939157daadebf2fe973cbd026224f898b412bd4b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb3bdad3b808b746f05f1b038c81053dd44ac303a532001ba832d588247f6260c0afd3a3932c49371aebf48230f2d33d356aa2727d1e3131aeb28d504b6e72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99502fddaa54159d88065dc459e81e1bd12558ce5c580759b54de682c64194d1dd161afa941e1b82fbcdfcd69125076d4e4447292f0f214e162fa351c08d42f1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 22:44:21.820890) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:44:21.821386) ========= 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.CxTvtrbkXc/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.AfCZJHxJGS/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.005149 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:44:21.871990) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 22:44:21.872456) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 22:44:21.872946) ========= ========= Total master_lag: 0.18 =========