builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0125 starttime: 1464648644.29 results: success (0) buildid: 20160530120549 builduid: 7997d47d310b490c9d491deb39fe4327 revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.293612) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.293982) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.294251) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.365773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.366069) ========= 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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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-30 15:50:44-- 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% 170M=0s 2016-05-30 15:50:44 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.598266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.986161) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.986451) ========= 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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.020408 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.037690) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 15:50:45.038001) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XarJTJ5n7i/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.sPKjgqrtkF/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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-30 15:50:45,108 truncating revision to first 12 chars 2016-05-30 15:50:45,108 Setting DEBUG logging. 2016-05-30 15:50:45,109 attempt 1/10 2016-05-30 15:50:45,109 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3c7a33fe0b64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 15:50:45,908 unpacking tar archive at: mozilla-aurora-3c7a33fe0b64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.056625 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 15:50:46.114411) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.114708) ========= script_repo_revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.115080) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.115359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.131872) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 42 secs) (at 2016-05-30 15:50:46.132232) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XarJTJ5n7i/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.sPKjgqrtkF/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:50:46 INFO - MultiFileLogger online at 20160530 15:50:46 in /builds/slave/test 15:50:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:50:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:46 INFO - {'append_to_log': False, 15:50:46 INFO - 'base_work_dir': '/builds/slave/test', 15:50:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:50:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:46 INFO - 'branch': 'Mozilla-Aurora', 15:50:46 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:46 INFO - 'config_files': ('talos/mac_config.py',), 15:50:46 INFO - 'default_actions': ('clobber', 15:50:46 INFO - 'read-buildbot-config', 15:50:46 INFO - 'download-and-extract', 15:50:46 INFO - 'populate-webroot', 15:50:46 INFO - 'create-virtualenv', 15:50:46 INFO - 'install', 15:50:46 INFO - 'run-tests'), 15:50:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:46 INFO - 'download_minidump_stackwalk': True, 15:50:46 INFO - 'download_symbols': 'ondemand', 15:50:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:46 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:46 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:46 INFO - 'installer_path': 'installer.exe', 15:50:46 INFO - 'log_level': 'info', 15:50:46 INFO - 'log_name': 'talos', 15:50:46 INFO - 'log_to_console': True, 15:50:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:50:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:50:46 INFO - 'opt_config_files': (), 15:50:46 INFO - 'pip_index': False, 15:50:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'run_cmd_checks_enabled': True, 15:50:46 INFO - 'sps_profile': False, 15:50:46 INFO - 'sps_profile_interval': 0, 15:50:46 INFO - 'suite': 'dromaeojs', 15:50:46 INFO - 'system_bits': '32', 15:50:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:46 INFO - 'title': 't-yosemite-r7-0125', 15:50:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:46 INFO - 'use_talos_json': True, 15:50:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:46 INFO - 'work_dir': 'build'} 15:50:46 INFO - ##### 15:50:46 INFO - ##### Running clobber step. 15:50:46 INFO - ##### 15:50:46 INFO - Running pre-action listener: _resource_record_pre_action 15:50:46 INFO - Running main action method: clobber 15:50:46 INFO - rmtree: /builds/slave/test/build 15:50:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:46 INFO - Running post-action listener: _resource_record_post_action 15:50:46 INFO - ##### 15:50:46 INFO - ##### Running read-buildbot-config step. 15:50:46 INFO - ##### 15:50:46 INFO - Running pre-action listener: _resource_record_pre_action 15:50:46 INFO - Running main action method: read_buildbot_config 15:50:46 INFO - Using buildbot properties: 15:50:46 INFO - { 15:50:46 INFO - "project": "", 15:50:46 INFO - "product": "firefox", 15:50:46 INFO - "script_repo_revision": "production", 15:50:46 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:50:46 INFO - "repository": "", 15:50:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:50:46 INFO - "buildid": "20160530120549", 15:50:46 INFO - "pgo_build": "False", 15:50:46 INFO - "basedir": "/builds/slave/test", 15:50:46 INFO - "buildnumber": 337, 15:50:46 INFO - "slavename": "t-yosemite-r7-0125", 15:50:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:50:46 INFO - "platform": "yosemite_r7", 15:50:46 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:50:46 INFO - "branch": "mozilla-aurora", 15:50:46 INFO - "repo_path": "releases/mozilla-aurora", 15:50:46 INFO - "revision": "3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4", 15:50:46 INFO - "stage_platform": "macosx64", 15:50:46 INFO - "builduid": "7997d47d310b490c9d491deb39fe4327", 15:50:46 INFO - "slavebuilddir": "test" 15:50:46 INFO - } 15:50:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:50:46 INFO - Running post-action listener: _resource_record_post_action 15:50:46 INFO - ##### 15:50:46 INFO - ##### Running download-and-extract step. 15:50:46 INFO - ##### 15:50:46 INFO - Running pre-action listener: _resource_record_pre_action 15:50:46 INFO - Running main action method: download_and_extract 15:50:46 INFO - mkdir: /builds/slave/test/build/tests 15:50: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')]} 15:50:46 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:50:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 15:50:56 INFO - Downloaded 1199 bytes. 15:50:56 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:56 INFO - Using the following test package requirements: 15:50:56 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:50:56 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:50:56 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:50:56 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'jsshell-mac.zip'], 15:50:56 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:50:56 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:50:56 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:50:56 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:50:56 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:50:56 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:56 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:50:56 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:50: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')]} 15:50:56 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:50:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:50:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:50:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:50:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:51:26 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 15:51:26 INFO - retry: attempt #1 caught exception: timed out 15:51:26 INFO - retry: Failed, sleeping 30 seconds before retrying 15:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #2 15:52:26 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 15:52:26 INFO - retry: attempt #2 caught exception: timed out 15:52:26 INFO - retry: Failed, sleeping 60 seconds before retrying 15:53:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #3 15:53:28 INFO - Downloaded 18463801 bytes. 15:53:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:53:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:53:31 INFO - Return code: 0 15:53:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:53:31 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')]} 15:53:31 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:53:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:53:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:53:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:53:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:53:32 INFO - Downloaded 18463801 bytes. 15:53:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:53:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:53:36 INFO - Return code: 0 15:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:36 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:53:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:53:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:53:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:53:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:53:37 INFO - Downloaded 10840145 bytes. 15:53:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:53:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:53:37 INFO - Return code: 0 15:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:37 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:53:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:53:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 15:53:40 INFO - Downloaded 114159654 bytes. 15:53:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:40 INFO - mkdir: /builds/slave/test/properties 15:53:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:53:40 INFO - Writing to file /builds/slave/test/properties/build_url 15:53:40 INFO - Contents: 15:53:40 INFO - build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:41 INFO - Running post-action listener: _resource_record_post_action 15:53:41 INFO - Running post-action listener: set_extra_try_arguments 15:53:41 INFO - ##### 15:53:41 INFO - ##### Running populate-webroot step. 15:53:41 INFO - ##### 15:53:41 INFO - Running pre-action listener: _resource_record_pre_action 15:53:41 INFO - Running main action method: populate_webroot 15:53:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:53:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:53:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:53:41 INFO - '--tppagecycles', 15:53:41 INFO - '7'], 15:53:41 INFO - 'tests': ['tsvgm']}}, 15:53:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:53:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:53:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:53:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:53:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:53:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:53:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['damp', 'tps']}, 15:53:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'tests': ['damp', 'tps']}, 15:53:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['dromaeo_dom']}, 15:53:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:53:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['a11yr', 15:53:41 INFO - 'ts_paint', 15:53:41 INFO - 'tpaint', 15:53:41 INFO - 'sessionrestore', 15:53:41 INFO - 'sessionrestore_no_auto_restore', 15:53:41 INFO - 'tabpaint']}, 15:53:41 INFO - 'other-e10s': {'tests': ['a11yr', 15:53:41 INFO - 'ts_paint', 15:53:41 INFO - 'tpaint', 15:53:41 INFO - 'sessionrestore', 15:53:41 INFO - 'sessionrestore_no_auto_restore', 15:53:41 INFO - 'tabpaint']}, 15:53:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:53:41 INFO - 'ts_paint', 15:53:41 INFO - 'tpaint', 15:53:41 INFO - 'sessionrestore', 15:53:41 INFO - 'sessionrestore_no_auto_restore', 15:53:41 INFO - 'tabpaint']}, 15:53:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:53:41 INFO - 'ts_paint', 15:53:41 INFO - 'tpaint', 15:53:41 INFO - 'sessionrestore', 15:53:41 INFO - 'sessionrestore_no_auto_restore', 15:53:41 INFO - 'tabpaint']}, 15:53:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['a11yr', 15:53:41 INFO - 'ts_paint', 15:53:41 INFO - 'tpaint', 15:53:41 INFO - 'sessionrestore', 15:53:41 INFO - 'sessionrestore_no_auto_restore', 15:53:41 INFO - 'tabpaint']}, 15:53:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['a11yr', 15:53:41 INFO - 'ts_paint', 15:53:41 INFO - 'tpaint', 15:53:41 INFO - 'sessionrestore', 15:53:41 INFO - 'sessionrestore_no_auto_restore', 15:53:41 INFO - 'tabpaint']}, 15:53:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['tsvgx', 15:53:41 INFO - 'tsvgr_opacity', 15:53:41 INFO - 'tart', 15:53:41 INFO - 'tscrollx', 15:53:41 INFO - 'cart']}, 15:53:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:53:41 INFO - 'tsvgr_opacity', 15:53:41 INFO - 'tart', 15:53:41 INFO - 'tscrollx', 15:53:41 INFO - 'cart']}, 15:53:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'talos_options': ['--disable-e10s'], 15:53:41 INFO - 'tests': ['tp5o']}, 15:53:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'tests': ['tp5o']}, 15:53:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'talos_options': ['--disable-e10s', 15:53:41 INFO - '--xperf_path', 15:53:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:53:41 INFO - 'tests': ['tp5n']}, 15:53:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:41 INFO - 'talos_options': ['--xperf_path', 15:53:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:53:41 INFO - 'tests': ['tp5n']}}, 15:53:41 INFO - 'talos.zip': {'path': '', 15:53:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:53:41 INFO - Running post-action listener: _resource_record_post_action 15:53:41 INFO - ##### 15:53:41 INFO - ##### Running create-virtualenv step. 15:53:41 INFO - ##### 15:53:41 INFO - Running pre-action listener: _resource_record_pre_action 15:53:41 INFO - Running main action method: create_virtualenv 15:53:41 INFO - Creating virtualenv /builds/slave/test/build/venv 15:53:41 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 15:53:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:53:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:53:41 INFO - Using real prefix '/tools/python27' 15:53:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:53:42 INFO - Installing distribute.............................................................................................................................................................................................done. 15:53:44 INFO - Installing pip.................done. 15:53:44 INFO - Return code: 0 15:53:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:53:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:44 INFO - 'HOME': '/Users/cltbld', 15:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:44 INFO - 'LOGNAME': 'cltbld', 15:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:44 INFO - 'MOZ_NO_REMOTE': '1', 15:53:44 INFO - 'NO_EM_RESTART': '1', 15:53:44 INFO - 'PAGER': '/bin/cat', 15:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:44 INFO - 'PWD': '/builds/slave/test', 15:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:44 INFO - 'SHELL': '/bin/bash', 15:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:53:44 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:44 INFO - 'USER': 'cltbld', 15:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:44 INFO - 'XPC_FLAGS': '0x0', 15:53:44 INFO - 'XPC_SERVICE_NAME': '0', 15:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:45 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 15:53:45 INFO - Cleaning up... 15:53:45 INFO - Return code: 0 15:53:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:45 INFO - 'HOME': '/Users/cltbld', 15:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:45 INFO - 'LOGNAME': 'cltbld', 15:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:45 INFO - 'MOZ_NO_REMOTE': '1', 15:53:45 INFO - 'NO_EM_RESTART': '1', 15:53:45 INFO - 'PAGER': '/bin/cat', 15:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:45 INFO - 'PWD': '/builds/slave/test', 15:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:45 INFO - 'SHELL': '/bin/bash', 15:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:53:45 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:45 INFO - 'USER': 'cltbld', 15:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:45 INFO - 'XPC_FLAGS': '0x0', 15:53:45 INFO - 'XPC_SERVICE_NAME': '0', 15:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:45 INFO - Downloading/unpacking psutil>=0.7.1 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:53:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:53:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:53:48 INFO - Installing collected packages: psutil 15:53:48 INFO - Running setup.py install for psutil 15:53:48 INFO - building 'psutil._psutil_osx' extension 15:53:48 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 15:53:48 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 15:53:48 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 15:53:49 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 15:53:49 INFO - building 'psutil._psutil_posix' extension 15:53:49 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 15:53:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:53:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:53:49 INFO - ^ 15:53:49 INFO - 1 warning generated. 15:53:49 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 15:53:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:53:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:53:49 INFO - Successfully installed psutil 15:53:49 INFO - Cleaning up... 15:53:49 INFO - Return code: 0 15:53:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:53:49 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')]} 15:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:49 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')]} 15:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:49 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:53:49 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.0.0'] in /builds/slave/test/build 15:53:49 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.0.0 15:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:49 INFO - 'HOME': '/Users/cltbld', 15:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:49 INFO - 'LOGNAME': 'cltbld', 15:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:49 INFO - 'MOZ_NO_REMOTE': '1', 15:53:49 INFO - 'NO_EM_RESTART': '1', 15:53:49 INFO - 'PAGER': '/bin/cat', 15:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:49 INFO - 'PWD': '/builds/slave/test', 15:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:49 INFO - 'SHELL': '/bin/bash', 15:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:53:49 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:49 INFO - 'USER': 'cltbld', 15:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:49 INFO - 'XPC_FLAGS': '0x0', 15:53:49 INFO - 'XPC_SERVICE_NAME': '0', 15:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:53:52 INFO - Installing collected packages: mozsystemmonitor 15:53:52 INFO - Running setup.py install for mozsystemmonitor 15:53:52 INFO - Successfully installed mozsystemmonitor 15:53:52 INFO - Cleaning up... 15:53:52 INFO - Return code: 0 15:53:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:52 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 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:53:52 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 15:53:52 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 15:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:52 INFO - 'HOME': '/Users/cltbld', 15:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:52 INFO - 'LOGNAME': 'cltbld', 15:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:52 INFO - 'MOZ_NO_REMOTE': '1', 15:53:52 INFO - 'NO_EM_RESTART': '1', 15:53:52 INFO - 'PAGER': '/bin/cat', 15:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:52 INFO - 'PWD': '/builds/slave/test', 15:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:52 INFO - 'SHELL': '/bin/bash', 15:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:53:52 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:52 INFO - 'USER': 'cltbld', 15:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:52 INFO - 'XPC_FLAGS': '0x0', 15:53:52 INFO - 'XPC_SERVICE_NAME': '0', 15:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:52 INFO - Downloading/unpacking blobuploader==1.2.4 15:53:52 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 15:53:52 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 15:53:52 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 15:53:52 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 15:53:52 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 15:53:52 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 15:53:55 INFO - Downloading blobuploader-1.2.4.tar.gz 15:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:53:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:53:55 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 15:53:55 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 15:53:55 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 15:53:55 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 15:53:55 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 15:53:55 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 15:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:53:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:53:55 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 15:53:55 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 15:53:55 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 15:53:55 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 15:53:55 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 15:53:55 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 15:53:56 INFO - Downloading docopt-0.6.1.tar.gz 15:53:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:53:56 INFO - Installing collected packages: blobuploader, requests, docopt 15:53:56 INFO - Running setup.py install for blobuploader 15:53:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:53:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:53:56 INFO - Running setup.py install for requests 15:53:56 INFO - Running setup.py install for docopt 15:53:57 INFO - Successfully installed blobuploader requests docopt 15:53:57 INFO - Cleaning up... 15:53:57 INFO - Return code: 0 15:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:57 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 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:53:57 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 15:53:57 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 15:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:57 INFO - 'HOME': '/Users/cltbld', 15:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:57 INFO - 'LOGNAME': 'cltbld', 15:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:57 INFO - 'MOZ_NO_REMOTE': '1', 15:53:57 INFO - 'NO_EM_RESTART': '1', 15:53:57 INFO - 'PAGER': '/bin/cat', 15:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:57 INFO - 'PWD': '/builds/slave/test', 15:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:57 INFO - 'SHELL': '/bin/bash', 15:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:53:57 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:57 INFO - 'USER': 'cltbld', 15:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:57 INFO - 'XPC_FLAGS': '0x0', 15:53:57 INFO - 'XPC_SERVICE_NAME': '0', 15:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:53:57 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-LydgHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:53:57 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-slsxrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:53:57 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-MNUxQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:53:57 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-jeMB8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:53:57 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-4aklmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:53:57 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-yiKcQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-vkB2OX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-H1aWwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-fSLU1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BHMh6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-RevDbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-0IcYiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-E19Fmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-_vldvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:58 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-_44Lph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:53:59 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-djeHXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:53:59 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-AoFoRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:53:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:53:59 INFO - Running setup.py install for manifestparser 15:53:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:53:59 INFO - Running setup.py install for mozcrash 15:53:59 INFO - Running setup.py install for mozdebug 15:53:59 INFO - Running setup.py install for mozdevice 15:53:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:53:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:53:59 INFO - Running setup.py install for mozfile 15:54:00 INFO - Running setup.py install for mozhttpd 15:54:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Running setup.py install for mozinfo 15:54:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Running setup.py install for mozInstall 15:54:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Running setup.py install for mozleak 15:54:00 INFO - Running setup.py install for mozlog 15:54:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Running setup.py install for moznetwork 15:54:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:54:00 INFO - Running setup.py install for mozprocess 15:54:01 INFO - Running setup.py install for mozprofile 15:54:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:54:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:54:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:54:01 INFO - Running setup.py install for mozrunner 15:54:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:54:01 INFO - Running setup.py install for mozscreenshot 15:54:01 INFO - Running setup.py install for moztest 15:54:01 INFO - Running setup.py install for mozversion 15:54:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:54:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:54:01 INFO - Cleaning up... 15:54:01 INFO - Return code: 0 15:54:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:01 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 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:54:01 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 15:54:01 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 15:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:01 INFO - 'HOME': '/Users/cltbld', 15:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:01 INFO - 'LOGNAME': 'cltbld', 15:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:01 INFO - 'MOZ_NO_REMOTE': '1', 15:54:01 INFO - 'NO_EM_RESTART': '1', 15:54:01 INFO - 'PAGER': '/bin/cat', 15:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:01 INFO - 'PWD': '/builds/slave/test', 15:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:01 INFO - 'SHELL': '/bin/bash', 15:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:54:01 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:01 INFO - 'USER': 'cltbld', 15:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:01 INFO - 'XPC_FLAGS': '0x0', 15:54:01 INFO - 'XPC_SERVICE_NAME': '0', 15:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-uddPNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-tmz02P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-o4qTlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-InvTro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ZSUkgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-HrlLtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-tyhK8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-6Dnzsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:02 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)) 15:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:02 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-y8LAif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:03 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-z90rdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-RwbtYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:03 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-yO2RI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:03 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-GZWfN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:03 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Pnkz3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:03 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Jig_vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:03 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-8biPb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:03 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)) 15:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:03 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-MtFwgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:03 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)) 15:54:03 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)) 15:54:03 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)) 15:54:03 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)) 15:54:03 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)) 15:54:03 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)) 15:54:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54:06 INFO - Downloading blessings-1.6.tar.gz 15:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:54:06 INFO - Installing collected packages: blessings 15:54:06 INFO - Running setup.py install for blessings 15:54:06 INFO - Successfully installed blessings 15:54:06 INFO - Cleaning up... 15:54:06 INFO - Return code: 0 15:54:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:06 INFO - Reading from file tmpfile_stdout 15:54:06 INFO - Current package versions: 15:54:06 INFO - blessings == 1.6 15:54:06 INFO - blobuploader == 1.2.4 15:54:06 INFO - docopt == 0.6.1 15:54:06 INFO - manifestparser == 1.1 15:54:06 INFO - mozInstall == 1.12 15:54:06 INFO - mozcrash == 0.17 15:54:06 INFO - mozdebug == 0.1 15:54:06 INFO - mozdevice == 0.48 15:54:06 INFO - mozfile == 1.2 15:54:06 INFO - mozhttpd == 0.7 15:54:06 INFO - mozinfo == 0.9 15:54:06 INFO - mozleak == 0.1 15:54:06 INFO - mozlog == 3.1 15:54:06 INFO - moznetwork == 0.27 15:54:06 INFO - mozprocess == 0.22 15:54:06 INFO - mozprofile == 0.28 15:54:06 INFO - mozrunner == 6.11 15:54:06 INFO - mozscreenshot == 0.1 15:54:06 INFO - mozsystemmonitor == 0.0 15:54:06 INFO - moztest == 0.7 15:54:06 INFO - mozversion == 1.4 15:54:06 INFO - psutil == 3.1.1 15:54:06 INFO - requests == 1.2.3 15:54:06 INFO - wsgiref == 0.1.2 15:54:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:06 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 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:54:06 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 15:54:06 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 15:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:06 INFO - 'HOME': '/Users/cltbld', 15:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:06 INFO - 'LOGNAME': 'cltbld', 15:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:06 INFO - 'MOZ_NO_REMOTE': '1', 15:54:06 INFO - 'NO_EM_RESTART': '1', 15:54:06 INFO - 'PAGER': '/bin/cat', 15:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:06 INFO - 'PWD': '/builds/slave/test', 15:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:06 INFO - 'SHELL': '/bin/bash', 15:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:54:06 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:06 INFO - 'USER': 'cltbld', 15:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:06 INFO - 'XPC_FLAGS': '0x0', 15:54:06 INFO - 'XPC_SERVICE_NAME': '0', 15:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 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)) 15:54:07 INFO - Cleaning up... 15:54:07 INFO - Return code: 0 15:54:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:07 INFO - 'HOME': '/Users/cltbld', 15:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:07 INFO - 'LOGNAME': 'cltbld', 15:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:07 INFO - 'MOZ_NO_REMOTE': '1', 15:54:07 INFO - 'NO_EM_RESTART': '1', 15:54:07 INFO - 'PAGER': '/bin/cat', 15:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:07 INFO - 'PWD': '/builds/slave/test', 15:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:07 INFO - 'SHELL': '/bin/bash', 15:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:54:07 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:07 INFO - 'USER': 'cltbld', 15:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:07 INFO - 'XPC_FLAGS': '0x0', 15:54:07 INFO - 'XPC_SERVICE_NAME': '0', 15:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:07 INFO - Downloading/unpacking jsonschema 15:54:07 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 15:54:07 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 15:54:07 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 15:54:07 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 15:54:07 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 15:54:07 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 15:54:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:09 INFO - Downloading/unpacking functools32 (from jsonschema) 15:54:09 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 15:54:09 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 15:54:09 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 15:54:09 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 15:54:09 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 15:54:09 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 15:54:10 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:10 INFO - warning: no files found matching '*.txt' 15:54:10 INFO - no previously-included directories found matching 'build' 15:54:10 INFO - no previously-included directories found matching 'dist' 15:54:10 INFO - no previously-included directories found matching '.git*' 15:54:10 INFO - Installing collected packages: jsonschema, functools32 15:54:10 INFO - Running setup.py install for functools32 15:54:10 INFO - warning: no files found matching '*.txt' 15:54:10 INFO - no previously-included directories found matching 'build' 15:54:10 INFO - no previously-included directories found matching 'dist' 15:54:10 INFO - no previously-included directories found matching '.git*' 15:54:10 INFO - Successfully installed jsonschema functools32 15:54:10 INFO - Cleaning up... 15:54:10 INFO - Return code: 0 15:54:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:54:10 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')]} 15:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:10 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')]} 15:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:10 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 0x1035efa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ff420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7a3588430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, '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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 15:54:10 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 15:54:10 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 15:54:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:54:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:10 INFO - 'HOME': '/Users/cltbld', 15:54:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:10 INFO - 'LOGNAME': 'cltbld', 15:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:10 INFO - 'MOZ_NO_REMOTE': '1', 15:54:10 INFO - 'NO_EM_RESTART': '1', 15:54:10 INFO - 'PAGER': '/bin/cat', 15:54:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:10 INFO - 'PWD': '/builds/slave/test', 15:54:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:10 INFO - 'SHELL': '/bin/bash', 15:54:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:54:10 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:54:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:10 INFO - 'USER': 'cltbld', 15:54:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:10 INFO - 'XPC_FLAGS': '0x0', 15:54:10 INFO - 'XPC_SERVICE_NAME': '0', 15:54:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:11 INFO - Downloading/unpacking flake8 15:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:54:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:54:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:54:14 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 15:54:14 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 15:54:14 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 15:54:14 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 15:54:14 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 15:54:14 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 15:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:54:14 INFO - no previously-included directories found matching 'docs/_build' 15:54:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:54:14 INFO - Running setup.py install for pep8 15:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:54:14 INFO - no previously-included directories found matching 'docs/_build' 15:54:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:54:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:54:15 INFO - Cleaning up... 15:54:15 INFO - Return code: 0 15:54:15 INFO - Running post-action listener: _resource_record_post_action 15:54:15 INFO - Running post-action listener: _start_resource_monitoring 15:54:15 INFO - Starting resource monitoring. 15:54:15 INFO - ##### 15:54:15 INFO - ##### Running install step. 15:54:15 INFO - ##### 15:54:15 INFO - Running pre-action listener: _resource_record_pre_action 15:54:15 INFO - Running main action method: install 15:54:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:15 INFO - Reading from file tmpfile_stdout 15:54:15 INFO - Detecting whether we're running mozinstall >=1.0... 15:54:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:54:15 INFO - Reading from file tmpfile_stdout 15:54:15 INFO - Output received: 15:54:15 INFO - Usage: mozinstall [options] installer 15:54:15 INFO - Options: 15:54:15 INFO - -h, --help show this help message and exit 15:54:15 INFO - -d DEST, --destination=DEST 15:54:15 INFO - Directory to install application into. [default: 15:54:15 INFO - "/builds/slave/test"] 15:54:15 INFO - --app=APP Application being installed. [default: firefox] 15:54:15 INFO - mkdir: /builds/slave/test/build/application 15:54:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:54:47 INFO - Reading from file tmpfile_stdout 15:54:47 INFO - Output received: 15:54:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:54:47 INFO - Running post-action listener: _resource_record_post_action 15:54:47 INFO - ##### 15:54:47 INFO - ##### Running run-tests step. 15:54:47 INFO - ##### 15:54:47 INFO - Running pre-action listener: _resource_record_pre_action 15:54:47 INFO - Running main action method: run_tests 15:54:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:54:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:54:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:54:47 INFO - 2016-05-30 15:54:47.154 screenresolution[2902:39222] starting screenresolution argv=screenresolution get 15:54:47 INFO - 2016-05-30 15:54:47.156 screenresolution[2902:39222] Display 0: 1600x1200x32@60 15:54:47 INFO - 2016-05-30 15:54:47.161 screenresolution[2903:39224] starting screenresolution argv=screenresolution list 15:54:47 INFO - Available Modes on Display 0 15:54:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:54:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:54:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:54:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:54:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:54:47 INFO - Intel Iris: 15:54:47 INFO - Chipset Model: Intel Iris 15:54:47 INFO - Type: GPU 15:54:47 INFO - Bus: Built-In 15:54:47 INFO - VRAM (Dynamic, Max): 1536 MB 15:54:47 INFO - Vendor: Intel (0x8086) 15:54:47 INFO - Device ID: 0x0a2e 15:54:47 INFO - Revision ID: 0x0009 15:54:47 INFO - Displays: 15:54:47 INFO - Display: 15:54:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:54:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:54:47 INFO - Display Serial Number: 1600x1200 60 15:54:47 INFO - Main Display: Yes 15:54:47 INFO - Mirror: Off 15:54:47 INFO - Online: Yes 15:54:47 INFO - Rotation: Supported 15:54:47 INFO - Return code: 0 15:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:54:47 INFO - Python 2.7.3 15:54:47 INFO - Return code: 0 15:54:47 INFO - grabbing minidump binary from tooltool 15:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:47 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348ee00>, '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 0x1032f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103489b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:54:47 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 15:54:47 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 15:54:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:54:47 INFO - Return code: 0 15:54:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:54:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:54:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:54:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:54:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:54:48 INFO - Return code: 0 15:54:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0125 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XarJTJ5n7i/Render', 15:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:48 INFO - 'HOME': '/Users/cltbld', 15:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:48 INFO - 'LOGNAME': 'cltbld', 15:54:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:54:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:48 INFO - 'MOZ_NO_REMOTE': '1', 15:54:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:54:48 INFO - 'NO_EM_RESTART': '1', 15:54:48 INFO - 'PAGER': '/bin/cat', 15:54:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:48 INFO - 'PWD': '/builds/slave/test', 15:54:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:48 INFO - 'SHELL': '/bin/bash', 15:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sPKjgqrtkF/Listeners', 15:54:48 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 15:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:48 INFO - 'USER': 'cltbld', 15:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:48 INFO - 'XPC_FLAGS': '0x0', 15:54:48 INFO - 'XPC_SERVICE_NAME': '0', 15:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:54:48 INFO - mozversion application_buildid: 20160530120549 15:54:48 INFO - mozversion application_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:54:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:54:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:54:48 INFO - mozversion application_name: Firefox 15:54:48 INFO - mozversion application_remotingname: firefox-dev 15:54:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:54:48 INFO - mozversion application_vendor: Mozilla 15:54:48 INFO - mozversion application_version: 48.0a2 15:54:48 INFO - mozversion platform_buildid: 20160530120549 15:54:48 INFO - mozversion platform_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:54:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:54:48 INFO - mozversion platform_version: 48.0a2 15:54:48 INFO - using testdate: 1464648888 15:54:48 INFO - actual date: 1464648888 15:54:48 INFO - starting webserver on 'localhost:55862' 15:54:48 INFO - SUITE-START | Running 2 tests 15:54:48 INFO - TEST-START | dromaeo_css 15:54:48 INFO - operating with platform_type : mac_ 15:54:48 INFO - Initialising browser for dromaeo_css test... 15:54:48 INFO - TEST-INFO | started process 2921 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpvZ34kB/profile http://localhost:55862/getInfo.html) 15:54:51 INFO - PROCESS | 2921 | __metrics Screen width/height:1600/1200 15:54:51 INFO - PROCESS | 2921 | colorDepth:24 15:54:51 INFO - PROCESS | 2921 | Browser inner width/height: 1024/689 15:54:51 INFO - PROCESS | 2921 | __metrics 15:54:51 INFO - PROCESS | 2921 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:54:51 ERROR - PROCESS | 2921 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:54:51 INFO - TEST-INFO | 2921: exit 0 15:54:51 INFO - Browser initialized. 15:54:51 INFO - Running cycle 1/1 for dromaeo_css test... 15:54:51 INFO - TEST-INFO | started process 2923 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpvZ34kB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:55:04 INFO - PROCESS | 2923 | RSS: Main: 211619840 15:55:04 INFO - PROCESS | 2923 | 15:55:55 INFO - PROCESS | 2923 | 1464648955684 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] 15:58:06 INFO - PROCESS | 2923 | Cycle 1(1): loaded http://localhost:55862/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55862/tests/dromaeo/cssquery-ext.html) 15:58:07 INFO - PROCESS | 2923 | RSS: Main: 386650112 15:58:07 INFO - PROCESS | 2923 | 16:01:11 INFO - PROCESS | 2923 | Cycle 1(1): loaded http://localhost:55862/tests/dromaeo/cssquery-ext.html (next: http://localhost:55862/tests/dromaeo/cssquery-jquery.html) 16:01:11 INFO - PROCESS | 2923 | RSS: Main: 356171776 16:01:11 INFO - PROCESS | 2923 | 16:04:13 INFO - PROCESS | 2923 | Cycle 1(1): loaded http://localhost:55862/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55862/tests/dromaeo/cssquery-mootools.html) 16:04:14 INFO - PROCESS | 2923 | RSS: Main: 359792640 16:04:14 INFO - PROCESS | 2923 | 16:07:16 INFO - PROCESS | 2923 | Cycle 1(1): loaded http://localhost:55862/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55862/tests/dromaeo/cssquery-prototype.html) 16:07:17 INFO - PROCESS | 2923 | RSS: Main: 358563840 16:07:17 INFO - PROCESS | 2923 | 16:10:20 INFO - PROCESS | 2923 | Cycle 1(1): loaded http://localhost:55862/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55862/tests/dromaeo/cssquery-yui.html) 16:10:21 INFO - PROCESS | 2923 | RSS: Main: 327454720 16:10:21 INFO - PROCESS | 2923 | 16:13:23 INFO - PROCESS | 2923 | Cycle 1(1): loaded http://localhost:55862/tests/dromaeo/cssquery-yui.html (next: http://localhost:55862/tests/dromaeo/cssquery-dojo.html) 16:13:24 INFO - PROCESS | 2923 | RSS: Main: 371621888 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | __start_tp_report 16:13:24 INFO - PROCESS | 2923 | _x_x_mozilla_page_load 16:13:24 INFO - PROCESS | 2923 | _x_x_mozilla_page_load_details 16:13:24 INFO - PROCESS | 2923 | |i|pagename|runs| 16:13:24 INFO - PROCESS | 2923 | |0;dojo.html;3173.2701478659906;3394.8472318745653;3409.727221822254;3429.0570093224346;3468.3583537045647;5653.858653533659;5667.4049224831415;5693.943060569393;5703.486686198241;5718.485336319735;5637.746301416436;5698.487136157745;5702.173184888189;5721.027425337692;5725.971370143154;5032.773525191357;5095.464976177504;5179.792808287664;5225.268462415265;5273.499017593342;4445.466544014723;5138.871528211787;5227.7908883644795;5240.764165612557;5244.527992480676;5164.8708782280555;5175.301334319856;5206.427292997767;5213.530782229599;5251.921221181686;5158.045761534109;5181.119209734355;5187.974060129675;5200.141976573861;5239.816606418757;5046.0664777016;5054.721990290533;5095.847124586231;5114.28400023997;5138.177891537336;5477.20580515827;5481.150421684605;5494.313210848644;5494.945050549523;5596.524295434893;6117.418845209698;6145.170401995718;6148.077788331741;6150.384961503813;6173.629582225081;6100.481459075984;6189.2572891252885;6220.284667263278;6232;6279.277883043463;5722.227499287626;5722.313322401338;5724.570657200727;5738.167965645;5768.192453056534;6250.124982502453;6255.96872015637;6259.123722678829;6265.561410701298;6286.8428289292215;5926.644401335933;6001.609895356788;6022.277326720821;6028.2766068072115;6047.909281360783;6236.376362363818;6251.249850018027;6270.592411493239;6273.560850740496;6273.7176827042895;6135.938640613626;6160.353162917919;6207.937920620736;6211;6227.688615569204;6055.7274922628585;6135.140800782589;6137.070562331796;6222.377762223832;6273.153124328249;4852.927206091911;4872;4899.6570240083565;4908.705477671351;4917.704937703749;4414.984553552701;4417.823287068545;4425.911481770347;4483.32750087483;4501.774911254424;5644.040513112781;5669.489745922887;5693;5697.886042279214;5726.942730572641;6118.908216376758;6208.565400422018;6239.906401403982;6263.342349053375;6263.906041409383;5627.184058311522;5641.774329026875;5710.65736055838;5711.057675483582;5715.685637289906;5352.759125839347;5363.570914326844;5364.463553644604;5364.678119312854;5375.946240537544;6194;6240.7503699851495;6269.216347956505;6300.684965751694;6310.463610593151;5475.616706830484;5892.793752218823;6161.753529858755;6164.167837341902;6167.876642467215;6271.717772700148;6308.08532762756;6319.589226700434;6333.2716737575765;6343.238811342484;6119.143319935123;6159.5072394209255;6166.920788861841;6169.444749972524;6189.412005859436;6112.205413296243;6197.411245931629;6199.814005579659;6282;6295.150154729053;5975.760969561169;6028.728707208098;6042.728077236447;6072.119542666201;6082;5959.821205363846;5965.254343207099;5996.640201587744;6020;6039;5747.5689323300085;5836.854078648068;5876.911846322394;5894.056950887837;5901.262342207224;4980.676256043345;5992.161097446448;5996.5502587307;6009.489193418608;6084.726187321493;5865.266841644794;5872.236609240771;5874.706264686834;5886.20536227621;5896.764129434946 16:13:24 INFO - PROCESS | 2923 | |1;ext.html;523.1968927695988;525.6872161064167;568.92603961485;573.4895942611076;585.0814221671972;30501.237469063286;37540.62459375409;38265.23469530608;38330.61669383305;38377.61622383775;41473.792631036806;41604.16791664172;41665.37501937466;41839;41926.58073419265;8444.366672499556;9405.858912116324;9623.037696230356;9624.374415662995;9630.518474076303;8557.786055348635;11487.885121148765;11688.181827272094;11717.589884354049;11720.589779357724;11407.315561066322;11482.942585287063;11488.885111148866;11498.540058397653;11510.482028308747;8574.742757717278;8705.129487051245;8714.128587141298;11378.146639002067;11454.770904581903;8509.191626795506;11399.14506412014;11630.476628551736;11642.592509262135;11653.883461165364;30939.071827844975;37107.257854842755;37523.62476375228;38067.619323806684;38095;3250.30118524516;3312.685294896981;3344.8160351180677;3488.2735588066544;3959.1883663848844;26617.46765064707;26654;27145;27413.86293068542;27912;2748.2991837081418;2770.9307267318277;2773.6394268745134;2798.9020384286655;2857.3142445812855;461039;463002;463176;463856;464083;35511;36227.275454490766;60334.79330413367;60682.69658651679;60714.392856071754;34796.65203347985;35000.12499687528;35086;35122.47316290258;35169.47245791315;15397.923010384877;15560.844391556166;15575.90968632185;15579.065256084672;15582.610434739223;11144.387058711687;12112.515499380102;12340.81488777669;13132.606021819362;13181.011424143227;8045.477043992122;8074.434789564792;8087.433879628487;8149.062857771313;8167.183281671904;3072.7080927311868;3257.055453918337;3329.217633856042;3372.37611041958;3373.4433818420175;1013.2400699475394;1016.2682868334787;1021.0453226233495;1051.2273478992934;1117.9944100279608;29368;29482;29958;30132.397352053275;30351.848240758656;1048.308116643012;1070.6680928912062;1124.8087825069758;1204.1270079192516;1209.8245754365744;14922.328495217742;14997.850021499646;15113.697726045417;15201.239938003277;15494.84505154934;616875.9156204191;621726;625043;625466;625932;57543;57611.71194144002;58337.124943125884;58392.708036459546;58474.7076264616;1356.19984209315;1372.3412761874556;1464.3849583175304;1482.7775833625042;1492.61938205759;24256.393590160387;26867.328316791456;28619.713802861705;29173.41653166967;29614.259643509085;47954.040919182116;57990.4200957985;59338.40661593329;59392.70303648627;59436.70281648564;579541;580693;588945;592530;595667;32889.50665739968;34403.65596344005;35120.12199695028;35161.29677406489;41844.95387615334;42435.363469548596;43460.348094779234;44279.11441771211;44738.3289250668;44750.55249447594;35615;35942.8202858984;36719.884614041635;37119.44320835244;37541.81229093837;34127.82936085304;36173;36699.26601468009;37234.25531489409;37240.813795930844;34611.82694086513;36242.27515449729;36990;37371.439428408055;37664;31307.686923130477;32563;32736.017919462454;32790.180245494055;32794.18014549655;18654.906725466284;20993.265235716677;24125.638115428636;24363.39091522726;24767.752322476543 16:13:24 INFO - PROCESS | 2923 | |2;jquery.html;402.65572935140455;404.9453323801286;409.82582402478977;411.45071329774765;421.06733585108975;13003.34983250836;18269.908650456728;19956.700649490253;20530.589388212244;20607.484812879666;19977.801331920058;20209.39371818844;20561;20907.37277881662;20932.848693321863;5721.8569535761735;5722.742476588543;5931.703414829263;6053.122297266894;6069.453749162574;3908.374660054392;5036.471170527097;5773.307203135609;5969.522438204933;6035.849103772419;6047.21386219793;6052.394760523956;6054.122152287916;6058.666773327465;6073.60521566099;5992.910106348409;6026.583752818085;6036.456718895299;6540.607563546155;6560.409563139293;5837.64974101551;5838.562107841929;5839.8540036499;5850.122481627747;5867.970660146672;23980.520389592286;24888.3777905552;25840.353991150183;25856.095036673814;25867.353316167057;2094.172801743318;2151.537419454809;2233.486298151418;2536.8985240590355;2710.4985577668194;9340;9672.468014259213;9688.75778105546;10096.242781791396;10428.79142417155;1746.6681330547156;1819.2160257202918;1822.5352535103596;1827.232562323827;1845.9169337379851;113403;115223;117344;117816.41091794572;117953.82046179514;10953.014228719454;11620.186586938833;11631.360275184947;11821;11840.822387664191;13405;13408.597742067754;13967.580972570839;15089.622759431102;15109.697806043818;7527.227563547393;8707.825843483095;8935.061818509093;8976;9087.273018158532;8952.68665596701;8962.238209752113;8983.371164018587;9040.547972601344;9099.499527525917;4581.266997280419;4696.95303046972;4703.764811759398;4742.5020372861045;4768.880777980578;1985.186073709801;2005.5186755178943;2040.6428874946826;2056.2186369179917;2060.3818854343635;901.4366021236727;937.6530683647094;958.7842735384482;983.233078199006;1015.2334987084704;19822.70265946012;20504.794952050586;20539.28112516055;20743.688844667344;21007.78992210089;950.334765664038;951.8048799995984;956.4596003258137;1018.7147598672384;1043.1654676258963;9178.541072946326;10740.946295268473;10966.958138976785;11010.39442830652;11029.83455248172;567129.1643541756;567267.1636641873;572581;578740;607255;68189.65905170541;68574.9713754294;68828.65585672039;120091.19863202059;122313.38843305726;1021.7598864266891;1038.4703801061512;1054.4253381906879;1089.809283375422;1089.8310761831929;17865;18452.446426607225;18734.625307494047;18820.905895470434;19016;36166.819165904;42050;50457.49542504528;51706.48293517167;53949;78526.6073669628;80041;81067;82907;83097.75353369584;8110.188981101843;8240.1347858474;8266.214709602578;8422.115677853737;8510.021347545111;9256.305777066613;12151.696207594527;12398.5660501882;12582;12588.87411125877;9633.710988670351;10850.782984340427;11211.60759373418;11261.774764504828;11415.543378265109;10616.787664246825;10889.78220435571;11060.557577696802;11230.213885028297;11336.263142895685;11078.667639970814;11294.60468883585;11506.654800356002;11601.941990290332;11618.360990145286;7340.5228660139055;8171.387145963958;8268.214519620626;8281.627326770189;8316.251537361666;8477.915220847712;9275.25797936177;10707.785844283226;11412.543498259976;11462.885371146182 16:13:24 INFO - PROCESS | 2923 | |3;mootools.html;1449.0581122270517;1590.3082159260723;1625.7073726729188;1862.0875770872276;1964.5186929202348;7904.051513818343;8099.352051835854;8100.513969161847;8108.310793582546;8530.274926631228;8581.48511089335;8690.608922598483;8690.826183476327;8715.95642021789;8722.084181160983;2380;2439.634054891763;2448;2450.8529488230674;2453.558359495299;2385.0698227691214;2424.9757502424927;2434.172381390327;2436.744141865105;2441.4628781668093;2389.5340408620327;2411.6141417373224;2426.1872272788637;2427.356750461129;2427.5630386530415;2401.4956859059616;2435.6468312094735;2447.9143229987044;2448.608222684371;2453.116877923946;2393.4614711689906;2433.0146290752095;2440.45089854783;2441.9755802442105;2453.153661986612;8810.955945220298;8834.86747698785;8845.248153906925;8845.911540884637;8867.290616750644;5296.92054619181;5316.574674026068;5447.972760136214;5464.808731694372;5470.042742520043;3424.109731469811;3443.2252743132844;3498.4227602445567;3504.5444092267844;3507.6316986716283;942.1708896171394;1042.9165666746642;1071.59815069349;1072.447689439939;1076.2466273608427;11849.17055806094;11942.104342174285;11980.70048248792;11982.161248712593;11992.940035299855;4464.866054018385;4584.4957054723955;4630.953690463052;4645.674802763841;4662.650301227422;9265.351425400157;9304.90695093054;9350.158485736316;9353.345265831462;9357.251419886392;5265.131253343156;5302.496262855023;5306.92039619406;5310;5317.042932272151;4271.9786401067795;4410.889727756832;4701.130290896195;4759.690620109682;4849.151398505247;3835.2329534092737;3844.7116466265143;3896.3765797472834;3932.2725295820364;3963.0290578808376;1192.362086283834;1197.4252358867793;1208.6434501821984;1212.7695737809963;1229.287013532149;605.42787066222;744.3933194446508;922.3820040572832;925.6806401791371;967.3542761230392;5726.828195154152;5771;5782.190493330937;5941;6024.246969128859;476.8903152274927;489.3784893185634;489.4224814718595;506.2027306991503;665.8181727433747;3754.81225938702;3920.2355540669864;3951.1702542466405;4014.6186112319283;4019.8593049243136;10304.742381440374;10701;10717.571297148183;10830.566777328977;10852.837207441895;10568.52441640128;10609.787804243872;10695.94652026735;10707.678769636923;10720.142388609049;7980.561069141137;8266.628001739937;8291.875621865676;8361.581920904053;8383;5836.328822185503;5894.056950887837;5961.821145365646;6044.24446944132;6065.4541091300225;9271.211946984313;9387.671431500137;9522.809543809224;9527.714168574954;9528.380655257386;9205.539723013957;9536.427814331162;9540.045995400404;9703.02969703024;9705.46619935882;7432.145303290191;7521.247875212435;7531.887021694789;7535.924640753741;7573.166951635426;8003.19928016215;8135.471194372347;8188.672453101809;8191.713290034821;8193.672253110048;7411.370033546993;7456.664450099867;7495.36289415384;7622.771316860503;7710.460267781202;7338.119425668954;7373.152087510005;7393.371563417169;7559.168491465824;7566.735164269224;7045.154581450259;7074.787756367317;7101;7127.429805615642;7140.285971402818;4782.617390608673;4841.176999909953;4941.2835138905375;5008.57427118699;5033.5721463676;4941.308216849716;4996.550310472075;5013.523715247045;5081.974590127025;5237.188235823453 16:13:24 INFO - PROCESS | 2923 | |4;prototype.html;305.8730626789882;325.7784706399649;331.50274588117816;332.3038234897888;336.8753561182363;6324.683765811714;6441;6444.935550644492;6469.70886310116;6495.675216239193;5399.433059528752;6408.711607977641;6423.004434312677;6440.903386449207;6444.935550644492;3821.407681809324;4550.317452382136;4662.626989840822;4680.882977925545;4776.952230477703;4710.197432527658;4778.044391121773;4794.88012799681;4795.688280261772;4835.2505361669;4023.9597604024025;4690.24956007039;4779.091972525209;4799.424069111711;4799.664023518355;3889.3388124018984;4540.545945405466;4643.210654188779;4745.311929770164;4778.856634300976;3356.345512625039;4100.34394496882;4110.815013324408;4117.258893399187;4118.629323360882;6179.19670442844;6203.751849925998;6235.563510554263;6241.812745617639;6247.843803904893;5599.664020158803;5612.045952188138;5618.0449323615085;5619.297587801525;5629.4652008059165;6027.035674292136;6908.240093589751;6911.619860907648;6917.342852429011;6918;3727.329080765461;4131.731437456586;4451.732896026217;4473.843415480443;4497.9325310120375;7093.503454758169;7280.890786638205;7281.1262648482525;7287.125544934589;7298.197198308182;4000.579939106352;4399.73601583906;4571.382863313476;4573.954260457419;4592.471865735416;4616.469106052847;4624.907501850012;4639.304104384302;4641.164590373765;4642.883927901762;4549.863504094882;5489.58828087895;5495.313085864267;5511.862203444946;5520.503154716095;6728.192616886005;6895.862082758417;6941.548799328028;6953.33943275388;6967.79096627102;1582.3512359932372;1583.8517075120676;1602.8958117722311;1604.7753314535742;1606.9919650401673;958.5399008475971;973.9464329461806;1002.979940401188;1006.7432804634772;1028.8662473878499;6357.777477788255;6469.353064693587;6484;6496.480281577463;6502.544821862424;6428.903566446507;7088.964555177191;7236.240194779578;7247.20280769115;7257.564546127249;5641.9435805641415;6108.908366374508;6615.239247486505;6618.841702702008;6630.83422914431;3774.3206222880144;3817.1983883384246;3823.770573765583;3824.9235015299537;3825.215830754689;6050.42520960508;6489.545731798823;6930.480213983971;7535.962320188364;7541.698332066656;7400.629968501661;7438.479306448497;7445.13871532117;7468.663910124057;7469.925300746923;7533.698652053966;7543.585102819397;7545.396368290633;7547.132079810783;7552.471327007167;6943.9652801735665;7214.350708436266;7224.674889629874;7228.63856807147;7230.457715671451;7102.360787529147;7114.181869085121;7131.714731410685;7135.643217838983;7151.713931442684;7480.364169045474;7541.321281084729;7547.05661792276;7548.207438218908;7554.886676699964;4556;4567.269236922077;4570.862874113782;4577.33628623841;4586.403767510203;6603.7688680895935;7190.029346038218;7200.351968322876;7219.639018049182;7224.530405523624;6739.19129704419;6995.440364770704;7001.369876710916;7016.614086225307;7027.929720702932;6931.549449285781;6994.300569942965;7004.509684322253;7004.894926576004;7016.438684905297;6823;6824.078749368771;6831.146106736658;6841.521093523405;6841.589504629738;5635.605507614591;6792.117024786746;6967.65161741921;6987;6988.4059854910865;6572.76995305162;6635.601863888182;6739.92161254197;6881.277465866012;6888.827779305357 16:13:24 INFO - PROCESS | 2923 | |5;yui.html;601.578894773658;602.7227475361334;738.462150087711;758.821676905927;766.4060353226246;12940.417681204344;15141.545753627408;15151;15164.924175379108;15169.317380717866;13643.454261829515;13717.1083879548;14948.850511494882;15430.382784688627;15490.070595764262;851.7146755836771;912.5847739278612;919.7332773495694;940.5955439161158;942.6135284533343;734.3280897978343;748.8315129095937;925.453982150531;943.4009404028423;944.032366824004;990.4453506036607;992.8312186928204;992.8560358747977;993.0169132558786;997.675755379501;769.7883082152397;983.3853841349156;985.8767654043245;986.2405947420447;986.4180133721103;655.5087176673106;690.7133539581068;732.3555271361221;734.5849594978873;736.0027163193848;14809.777853332209;14813.703725925421;14871.330790114363;14891.925540372336;14905.88205884563;12236;12243.69390765229;12377.381130943508;12713.809292860522;12721.173123746927;10007.49962501872;10127.392356458562;10141.44222067786;10246.129079028291;10375.481225938749;3271.9182020449443;3426.58880934287;3483.3207524532727;3561.786292822439;3566.4472006838723;81486.14798482084;81870;83701;84331;84341;9227.53862306882;9344.112309330603;9425.38734982224;9546.379485333431;9785.902140978498;21189.89405052965;21296.893515532323;21443.892780535996;21447.463813404476;21514.56970860574;7298.671559779823;7344.265573442613;7529.962350188323;7582.052243469567;7633.160352361236;4135.793210339471;4140.834366625301;4196.706230563831;4222.092250166229;4357.324614684729;3508.4737289406794;3601.7658852174527;3649.7445178837756;3659.5608526976935;3674.026383008505;656.671664167916;657.6251536624078;660.5046215338496;672.4217173231017;676.1277951442584;543.6086018066985;577.4427677291434;578.2627150383211;584.6433675457968;585.2245774349004;7598.544087354777;7886.842263154705;7945.920540794634;7962.800929976797;8233.50598964064;510.9310243117206;516.1638146203175;521.2077641984239;527.6385675812081;592.2626330218905;3130.405223007667;3644.4168932970597;3772.5472943246987;3793.525809273841;3946.6053394660303;80882;84268.31463370698;87537.5623121893;88867.55566222128;91576.08423915676;72032;75879.62060189663;77003.61498192586;77344.61327693326;79876.6006169977;60293.69853150706;63987.680061601255;64005.07984760465;64509.70980580456;64964.675176623816;7384.4830861839155;7543.981562488993;7609.695612175451;7617.314441700273;7729.381649468141;46283.305750414445;49171;49196;49673;50326.99346013132;66667;66895.66552167208;67006;67407.98888016782;68183;9766.85349719769;9895.455749933823;10054.849177262493;10103.848442273225;10701.732456688645;11477.253978491372;11793.351365674971;11922.463489142836;12040.277583345027;12044.819327709916;8828.514431706319;11268.211225214156;11629.592964246549;11646;11832.17174797756;8626.482411055356;10865;11670.066394688574;11699.415029248674;12182.02543796512;10906.072983796466;11046.558137674725;11321.716957075809;11329.830052549054;11492.965633092726;9472.810543789223;9698.6120555177;9737.123658870736;9847.409155450408;9958.15355694774;9107.134822191882;9658.179054780141;9738.367006144577;9794.608215671293;9851.16265117473 16:13:24 INFO - PROCESS | 2923 | __end_tp_report 16:13:24 INFO - PROCESS | 2923 | __start_cc_report 16:13:24 INFO - PROCESS | 2923 | _x_x_mozilla_cycle_collect,1575 16:13:24 INFO - PROCESS | 2923 | __end_cc_report 16:13:24 INFO - PROCESS | 2923 | __startTimestamp1464650004142__endTimestamp 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | ------- Summary: start ------- 16:13:24 INFO - PROCESS | 2923 | Number of tests: 6 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | [#0] dojo.html Cycles:165 Average:5689.17 Median:5876.91 stddev:633.81 (10.8%) stddev-sans-first:604.25 16:13:24 INFO - PROCESS | 2923 | Values: 3173.3 3394.8 3409.7 3429.1 3468.4 5653.9 5667.4 5693.9 5703.5 5718.5 5637.7 5698.5 5702.2 5721.0 5726.0 5032.8 5095.5 5179.8 5225.3 5273.5 4445.5 5138.9 5227.8 5240.8 5244.5 5164.9 5175.3 5206.4 5213.5 5251.9 5158.0 5181.1 5188.0 5200.1 5239.8 5046.1 5054.7 5095.8 5114.3 5138.2 5477.2 5481.2 5494.3 5494.9 5596.5 6117.4 6145.2 6148.1 6150.4 6173.6 6100.5 6189.3 6220.3 6232.0 6279.3 5722.2 5722.3 5724.6 5738.2 5768.2 6250.1 6256.0 6259.1 6265.6 6286.8 5926.6 6001.6 6022.3 6028.3 6047.9 6236.4 6251.2 6270.6 6273.6 6273.7 6135.9 6160.4 6207.9 6211.0 6227.7 6055.7 6135.1 6137.1 6222.4 6273.2 4852.9 4872.0 4899.7 4908.7 4917.7 4415.0 4417.8 4425.9 4483.3 4501.8 5644.0 5669.5 5693.0 5697.9 5726.9 6118.9 6208.6 6239.9 6263.3 6263.9 5627.2 5641.8 5710.7 5711.1 5715.7 5352.8 5363.6 5364.5 5364.7 5375.9 6194.0 6240.8 6269.2 6300.7 6310.5 5475.6 5892.8 6161.8 6164.2 6167.9 6271.7 6308.1 6319.6 6333.3 6343.2 6119.1 6159.5 6166.9 6169.4 6189.4 6112.2 6197.4 6199.8 6282.0 6295.2 5975.8 6028.7 6042.7 6072.1 6082.0 5959.8 5965.3 5996.6 6020.0 6039.0 5747.6 5836.9 5876.9 5894.1 5901.3 4980.7 5992.2 5996.6 6009.5 6084.7 5865.3 5872.2 5874.7 5886.2 5896.8 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | [#1] ext.html Cycles:180 Average:67646.28 Median:19244.20 stddev:150459.67 (781.8%) stddev-sans-first:150797.34 16:13:24 INFO - PROCESS | 2923 | Values: 523.2 525.7 568.9 573.5 585.1 30501.2 37540.6 38265.2 38330.6 38377.6 41473.8 41604.2 41665.4 41839.0 41926.6 8444.4 9405.9 9623.0 9624.4 9630.5 8557.8 11487.9 11688.2 11717.6 11720.6 11407.3 11482.9 11488.9 11498.5 11510.5 8574.7 8705.1 8714.1 11378.1 11454.8 8509.2 11399.1 11630.5 11642.6 11653.9 30939.1 37107.3 37523.6 38067.6 38095.0 3250.3 3312.7 3344.8 3488.3 3959.2 26617.5 26654.0 27145.0 27413.9 27912.0 2748.3 2770.9 2773.6 2798.9 2857.3 461039.0 463002.0 463176.0 463856.0 464083.0 35511.0 36227.3 60334.8 60682.7 60714.4 34796.7 35000.1 35086.0 35122.5 35169.5 15397.9 15560.8 15575.9 15579.1 15582.6 11144.4 12112.5 12340.8 13132.6 13181.0 8045.5 8074.4 8087.4 8149.1 8167.2 3072.7 3257.1 3329.2 3372.4 3373.4 1013.2 1016.3 1021.0 1051.2 1118.0 29368.0 29482.0 29958.0 30132.4 30351.8 1048.3 1070.7 1124.8 1204.1 1209.8 14922.3 14997.9 15113.7 15201.2 15494.8 616875.9 621726.0 625043.0 625466.0 625932.0 57543.0 57611.7 58337.1 58392.7 58474.7 1356.2 1372.3 1464.4 1482.8 1492.6 24256.4 26867.3 28619.7 29173.4 29614.3 47954.0 57990.4 59338.4 59392.7 59436.7 579541.0 580693.0 588945.0 592530.0 595667.0 32889.5 34403.7 35120.1 35161.3 41845.0 42435.4 43460.3 44279.1 44738.3 44750.6 35615.0 35942.8 36719.9 37119.4 37541.8 34127.8 36173.0 36699.3 37234.3 37240.8 34611.8 36242.3 36990.0 37371.4 37664.0 31307.7 32563.0 32736.0 32790.2 32794.2 18654.9 20993.3 24125.6 24363.4 24767.8 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | [#2] jquery.html Cycles:180 Average:33200.47 Median:14288.93 stddev:96041.45 (672.1%) stddev-sans-first:96279.30 16:13:24 INFO - PROCESS | 2923 | Values: 402.7 404.9 409.8 411.5 421.1 13003.3 18269.9 19956.7 20530.6 20607.5 19977.8 20209.4 20561.0 20907.4 20932.8 5721.9 5722.7 5931.7 6053.1 6069.5 3908.4 5036.5 5773.3 5969.5 6035.8 6047.2 6052.4 6054.1 6058.7 6073.6 5992.9 6026.6 6036.5 6540.6 6560.4 5837.6 5838.6 5839.9 5850.1 5868.0 23980.5 24888.4 25840.4 25856.1 25867.4 2094.2 2151.5 2233.5 2536.9 2710.5 9340.0 9672.5 9688.8 10096.2 10428.8 1746.7 1819.2 1822.5 1827.2 1845.9 113403.0 115223.0 117344.0 117816.4 117953.8 10953.0 11620.2 11631.4 11821.0 11840.8 13405.0 13408.6 13967.6 15089.6 15109.7 7527.2 8707.8 8935.1 8976.0 9087.3 8952.7 8962.2 8983.4 9040.5 9099.5 4581.3 4697.0 4703.8 4742.5 4768.9 1985.2 2005.5 2040.6 2056.2 2060.4 901.4 937.7 958.8 983.2 1015.2 19822.7 20504.8 20539.3 20743.7 21007.8 950.3 951.8 956.5 1018.7 1043.2 9178.5 10740.9 10967.0 11010.4 11029.8 567129.2 567267.2 572581.0 578740.0 607255.0 68189.7 68575.0 68828.7 120091.2 122313.4 1021.8 1038.5 1054.4 1089.8 1089.8 17865.0 18452.4 18734.6 18820.9 19016.0 36166.8 42050.0 50457.5 51706.5 53949.0 78526.6 80041.0 81067.0 82907.0 83097.8 8110.2 8240.1 8266.2 8422.1 8510.0 9256.3 12151.7 12398.6 12582.0 12588.9 9633.7 10850.8 11211.6 11261.8 11415.5 10616.8 10889.8 11060.6 11230.2 11336.3 11078.7 11294.6 11506.7 11601.9 11618.4 7340.5 8171.4 8268.2 8281.6 8316.3 8477.9 9275.3 10707.8 11412.5 11462.9 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | [#3] mootools.html Cycles:180 Average:5658.56 Median:2794.09 stddev:3156.18 (113.0%) stddev-sans-first:3149.18 16:13:24 INFO - PROCESS | 2923 | Values: 1449.1 1590.3 1625.7 1862.1 1964.5 7904.1 8099.4 8100.5 8108.3 8530.3 8581.5 8690.6 8690.8 8716.0 8722.1 2380.0 2439.6 2448.0 2450.9 2453.6 2385.1 2425.0 2434.2 2436.7 2441.5 2389.5 2411.6 2426.2 2427.4 2427.6 2401.5 2435.6 2447.9 2448.6 2453.1 2393.5 2433.0 2440.5 2442.0 2453.2 8811.0 8834.9 8845.2 8845.9 8867.3 5296.9 5316.6 5448.0 5464.8 5470.0 3424.1 3443.2 3498.4 3504.5 3507.6 942.2 1042.9 1071.6 1072.4 1076.2 11849.2 11942.1 11980.7 11982.2 11992.9 4464.9 4584.5 4631.0 4645.7 4662.7 9265.4 9304.9 9350.2 9353.3 9357.3 5265.1 5302.5 5306.9 5310.0 5317.0 4272.0 4410.9 4701.1 4759.7 4849.2 3835.2 3844.7 3896.4 3932.3 3963.0 1192.4 1197.4 1208.6 1212.8 1229.3 605.4 744.4 922.4 925.7 967.4 5726.8 5771.0 5782.2 5941.0 6024.2 476.9 489.4 489.4 506.2 665.8 3754.8 3920.2 3951.2 4014.6 4019.9 10304.7 10701.0 10717.6 10830.6 10852.8 10568.5 10609.8 10695.9 10707.7 10720.1 7980.6 8266.6 8291.9 8361.6 8383.0 5836.3 5894.1 5961.8 6044.2 6065.5 9271.2 9387.7 9522.8 9527.7 9528.4 9205.5 9536.4 9540.0 9703.0 9705.5 7432.1 7521.2 7531.9 7535.9 7573.2 8003.2 8135.5 8188.7 8191.7 8193.7 7411.4 7456.7 7495.4 7622.8 7710.5 7338.1 7373.2 7393.4 7559.2 7566.7 7045.2 7074.8 7101.0 7127.4 7140.3 4782.6 4841.2 4941.3 5008.6 5033.6 4941.3 4996.6 5013.5 5082.0 5237.2 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | [#4] prototype.html Cycles:180 Average:5626.10 Median:6442.97 stddev:1831.58 (28.4%) stddev-sans-first:1792.66 16:13:24 INFO - PROCESS | 2923 | Values: 305.9 325.8 331.5 332.3 336.9 6324.7 6441.0 6444.9 6469.7 6495.7 5399.4 6408.7 6423.0 6440.9 6444.9 3821.4 4550.3 4662.6 4680.9 4777.0 4710.2 4778.0 4794.9 4795.7 4835.3 4024.0 4690.2 4779.1 4799.4 4799.7 3889.3 4540.5 4643.2 4745.3 4778.9 3356.3 4100.3 4110.8 4117.3 4118.6 6179.2 6203.8 6235.6 6241.8 6247.8 5599.7 5612.0 5618.0 5619.3 5629.5 6027.0 6908.2 6911.6 6917.3 6918.0 3727.3 4131.7 4451.7 4473.8 4497.9 7093.5 7280.9 7281.1 7287.1 7298.2 4000.6 4399.7 4571.4 4574.0 4592.5 4616.5 4624.9 4639.3 4641.2 4642.9 4549.9 5489.6 5495.3 5511.9 5520.5 6728.2 6895.9 6941.5 6953.3 6967.8 1582.4 1583.9 1602.9 1604.8 1607.0 958.5 973.9 1003.0 1006.7 1028.9 6357.8 6469.4 6484.0 6496.5 6502.5 6428.9 7089.0 7236.2 7247.2 7257.6 5641.9 6108.9 6615.2 6618.8 6630.8 3774.3 3817.2 3823.8 3824.9 3825.2 6050.4 6489.5 6930.5 7536.0 7541.7 7400.6 7438.5 7445.1 7468.7 7469.9 7533.7 7543.6 7545.4 7547.1 7552.5 6944.0 7214.4 7224.7 7228.6 7230.5 7102.4 7114.2 7131.7 7135.6 7151.7 7480.4 7541.3 7547.1 7548.2 7554.9 4556.0 4567.3 4570.9 4577.3 4586.4 6603.8 7190.0 7200.4 7219.6 7224.5 6739.2 6995.4 7001.4 7016.6 7027.9 6931.5 6994.3 7004.5 7004.9 7016.4 6823.0 6824.1 6831.1 6841.5 6841.6 5635.6 6792.1 6967.7 6987.0 6988.4 6572.8 6635.6 6739.9 6881.3 6888.8 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - PROCESS | 2923 | [#5] yui.html Cycles:180 Average:17861.91 Median:64257.39 stddev:24931.18 (38.8%) stddev-sans-first:24967.43 16:13:24 INFO - PROCESS | 2923 | Values: 601.6 602.7 738.5 758.8 766.4 12940.4 15141.5 15151.0 15164.9 15169.3 13643.5 13717.1 14948.9 15430.4 15490.1 851.7 912.6 919.7 940.6 942.6 734.3 748.8 925.5 943.4 944.0 990.4 992.8 992.9 993.0 997.7 769.8 983.4 985.9 986.2 986.4 655.5 690.7 732.4 734.6 736.0 14809.8 14813.7 14871.3 14891.9 14905.9 12236.0 12243.7 12377.4 12713.8 12721.2 10007.5 10127.4 10141.4 10246.1 10375.5 3271.9 3426.6 3483.3 3561.8 3566.4 81486.1 81870.0 83701.0 84331.0 84341.0 9227.5 9344.1 9425.4 9546.4 9785.9 21189.9 21296.9 21443.9 21447.5 21514.6 7298.7 7344.3 7530.0 7582.1 7633.2 4135.8 4140.8 4196.7 4222.1 4357.3 3508.5 3601.8 3649.7 3659.6 3674.0 656.7 657.6 660.5 672.4 676.1 543.6 577.4 578.3 584.6 585.2 7598.5 7886.8 7945.9 7962.8 8233.5 510.9 516.2 521.2 527.6 592.3 3130.4 3644.4 3772.5 3793.5 3946.6 80882.0 84268.3 87537.6 88867.6 91576.1 72032.0 75879.6 77003.6 77344.6 79876.6 60293.7 63987.7 64005.1 64509.7 64964.7 7384.5 7544.0 7609.7 7617.3 7729.4 46283.3 49171.0 49196.0 49673.0 50327.0 66667.0 66895.7 67006.0 67408.0 68183.0 9766.9 9895.5 10054.8 10103.8 10701.7 11477.3 11793.4 11922.5 12040.3 12044.8 8828.5 11268.2 11629.6 11646.0 11832.2 8626.5 10865.0 11670.1 11699.4 12182.0 10906.1 11046.6 11321.7 11329.8 11493.0 9472.8 9698.6 9737.1 9847.4 9958.2 9107.1 9658.2 9738.4 9794.6 9851.2 16:13:24 INFO - PROCESS | 2923 | -------- Summary: end -------- 16:13:24 INFO - PROCESS | 2923 | 16:13:24 INFO - TEST-INFO | 2923: exit 0 16:13:24 INFO - TEST-OK | dromaeo_css | took 1115930ms 16:13:24 INFO - TEST-START | kraken 16:13:24 INFO - operating with platform_type : mac_ 16:13:24 INFO - Initialising browser for kraken test... 16:13:24 INFO - TEST-INFO | started process 2927 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpE9Qtzb/profile http://localhost:55862/getInfo.html) 16:13:27 INFO - PROCESS | 2927 | __metrics Screen width/height:1600/1200 16:13:27 INFO - PROCESS | 2927 | colorDepth:24 16:13:27 INFO - PROCESS | 2927 | Browser inner width/height: 1024/689 16:13:27 INFO - PROCESS | 2927 | __metrics 16:13:27 INFO - PROCESS | 2927 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:13:27 ERROR - PROCESS | 2927 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:13:27 INFO - TEST-INFO | 2927: exit 0 16:13:27 INFO - Browser initialized. 16:13:27 INFO - Running cycle 1/1 for kraken test... 16:13:27 INFO - TEST-INFO | started process 2929 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpE9Qtzb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:39 INFO - PROCESS | 2929 | RSS: Main: 211181568 16:13:39 INFO - PROCESS | 2929 | 16:13:42 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55862/tests/kraken/driver.html?testName=audio-beat-detection) 16:13:43 INFO - PROCESS | 2929 | RSS: Main: 272646144 16:13:43 INFO - PROCESS | 2929 | 16:13:46 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55862/tests/kraken/driver.html?testName=audio-dft) 16:13:46 INFO - PROCESS | 2929 | RSS: Main: 286257152 16:13:46 INFO - PROCESS | 2929 | 16:13:49 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55862/tests/kraken/driver.html?testName=audio-fft) 16:13:50 INFO - PROCESS | 2929 | RSS: Main: 372801536 16:13:50 INFO - PROCESS | 2929 | 16:13:52 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55862/tests/kraken/driver.html?testName=audio-oscillator) 16:13:53 INFO - PROCESS | 2929 | RSS: Main: 278564864 16:13:53 INFO - PROCESS | 2929 | 16:13:55 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55862/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:13:56 INFO - PROCESS | 2929 | RSS: Main: 280399872 16:13:56 INFO - PROCESS | 2929 | 16:13:58 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55862/tests/kraken/driver.html?testName=imaging-darkroom) 16:13:59 INFO - PROCESS | 2929 | RSS: Main: 319336448 16:13:59 INFO - PROCESS | 2929 | 16:14:02 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55862/tests/kraken/driver.html?testName=imaging-desaturate) 16:14:03 INFO - PROCESS | 2929 | RSS: Main: 319070208 16:14:03 INFO - PROCESS | 2929 | 16:14:05 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55862/tests/kraken/driver.html?testName=json-parse-financial) 16:14:06 INFO - PROCESS | 2929 | RSS: Main: 317980672 16:14:06 INFO - PROCESS | 2929 | 16:14:08 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55862/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:14:09 INFO - PROCESS | 2929 | RSS: Main: 309407744 16:14:09 INFO - PROCESS | 2929 | 16:14:10 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:14:11 INFO - PROCESS | 2929 | RSS: Main: 316264448 16:14:11 INFO - PROCESS | 2929 | 16:14:13 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:14:14 INFO - PROCESS | 2929 | RSS: Main: 319143936 16:14:14 INFO - PROCESS | 2929 | 16:14:17 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:14:17 INFO - PROCESS | 2929 | RSS: Main: 323936256 16:14:17 INFO - PROCESS | 2929 | 16:14:20 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:14:21 INFO - PROCESS | 2929 | RSS: Main: 321949696 16:14:21 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:55862/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55862/tests/kraken/driver.html?testName=ai-astar) 16:14:23 INFO - PROCESS | 2929 | RSS: Main: 386764800 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | __start_tp_report 16:14:23 INFO - PROCESS | 2929 | _x_x_mozilla_page_load 16:14:23 INFO - PROCESS | 2929 | _x_x_mozilla_page_load_details 16:14:23 INFO - PROCESS | 2929 | |i|pagename|runs| 16:14:23 INFO - PROCESS | 2929 | |0;ai-astar;115;115;123;165;163;115;115;114;113;136 16:14:23 INFO - PROCESS | 2929 | |1;audio-beat-detection;159;125;158;159;120;142;118;151;157;121 16:14:23 INFO - PROCESS | 2929 | |2;audio-dft;185;186;189;186;184;186;184;186;188;188 16:14:23 INFO - PROCESS | 2929 | |3;audio-fft;71;80;72;155;73;82;50;62;145;84 16:14:23 INFO - PROCESS | 2929 | |4;audio-oscillator;95;92;144;77;78;86;125;80;104;164 16:14:23 INFO - PROCESS | 2929 | |5;imaging-gaussian-blur;86;84;86;84;86;83;87;83;87;130 16:14:23 INFO - PROCESS | 2929 | |6;imaging-darkroom;93;93;94;108;150;94;94;94;96;94 16:14:23 INFO - PROCESS | 2929 | |7;imaging-desaturate;99;132;139;84;85;87;84;85;86;87 16:14:23 INFO - PROCESS | 2929 | |8;json-parse-financial;59;64;61;61;65;53;57;100;86;93 16:14:23 INFO - PROCESS | 2929 | |9;json-stringify-tinderbox;65;65;66;76;76;69;67;69;67;69 16:14:23 INFO - PROCESS | 2929 | |10;stanford-crypto-aes;81;82;80;81;80;80;81;81;79;87 16:14:23 INFO - PROCESS | 2929 | |11;stanford-crypto-ccm;141;147;150;135;129;129;129;129;150;145 16:14:23 INFO - PROCESS | 2929 | |12;stanford-crypto-pbkdf2;166;165;166;164;165;172;224;225;166;163 16:14:23 INFO - PROCESS | 2929 | |13;stanford-crypto-sha256-iterative;66;68;68;69;68;69;70;68;69;65 16:14:23 INFO - PROCESS | 2929 | __end_tp_report 16:14:23 INFO - PROCESS | 2929 | __start_cc_report 16:14:23 INFO - PROCESS | 2929 | _x_x_mozilla_cycle_collect,2024 16:14:23 INFO - PROCESS | 2929 | __end_cc_report 16:14:23 INFO - PROCESS | 2929 | __startTimestamp1464650063311__endTimestamp 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | ------- Summary: start ------- 16:14:23 INFO - PROCESS | 2929 | Number of tests: 14 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#0] ai-astar Cycles:10 Average:127.40 Median:119.00 stddev:20.48 (17.2%) stddev-sans-first:21.23 16:14:23 INFO - PROCESS | 2929 | Values: 115.0 115.0 123.0 165.0 163.0 115.0 115.0 114.0 113.0 136.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:154.00 stddev:18.01 (11.7%) stddev-sans-first:17.89 16:14:23 INFO - PROCESS | 2929 | Values: 159.0 125.0 158.0 159.0 120.0 142.0 118.0 151.0 157.0 121.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#2] audio-dft Cycles:10 Average:186.20 Median:186.00 stddev:1.69 (0.9%) stddev-sans-first:1.73 16:14:23 INFO - PROCESS | 2929 | Values: 185.0 186.0 189.0 186.0 184.0 186.0 184.0 186.0 188.0 188.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#3] audio-fft Cycles:10 Average:87.40 Median:72.50 stddev:34.55 (47.6%) stddev-sans-first:36.13 16:14:23 INFO - PROCESS | 2929 | Values: 71.0 80.0 72.0 155.0 73.0 82.0 50.0 62.0 145.0 84.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:79.00 stddev:30.13 (38.1%) stddev-sans-first:31.76 16:14:23 INFO - PROCESS | 2929 | Values: 95.0 92.0 144.0 77.0 78.0 86.0 125.0 80.0 104.0 164.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:86.00 stddev:14.28 (16.6%) stddev-sans-first:15.08 16:14:23 INFO - PROCESS | 2929 | Values: 86.0 84.0 86.0 84.0 86.0 83.0 87.0 83.0 87.0 130.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:94.00 stddev:17.79 (18.9%) stddev-sans-first:18.63 16:14:23 INFO - PROCESS | 2929 | Values: 93.0 93.0 94.0 108.0 150.0 94.0 94.0 94.0 96.0 94.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:85.50 stddev:20.92 (24.5%) stddev-sans-first:22.18 16:14:23 INFO - PROCESS | 2929 | Values: 99.0 132.0 139.0 84.0 85.0 87.0 84.0 85.0 86.0 87.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#8] json-parse-financial Cycles:10 Average:69.90 Median:62.50 stddev:16.62 (26.6%) stddev-sans-first:17.16 16:14:23 INFO - PROCESS | 2929 | Values: 59.0 64.0 61.0 61.0 65.0 53.0 57.0 100.0 86.0 93.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:4.04 (5.9%) stddev-sans-first:4.03 16:14:23 INFO - PROCESS | 2929 | Values: 65.0 65.0 66.0 76.0 76.0 69.0 67.0 69.0 67.0 69.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:2.20 (2.7%) stddev-sans-first:2.33 16:14:23 INFO - PROCESS | 2929 | Values: 81.0 82.0 80.0 81.0 80.0 80.0 81.0 81.0 79.0 87.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:143.00 stddev:9.18 (6.4%) stddev-sans-first:9.69 16:14:23 INFO - PROCESS | 2929 | Values: 141.0 147.0 150.0 135.0 129.0 129.0 129.0 129.0 150.0 145.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:166.00 stddev:24.83 (15.0%) stddev-sans-first:25.98 16:14:23 INFO - PROCESS | 2929 | Values: 166.0 165.0 166.0 164.0 165.0 172.0 224.0 225.0 166.0 163.0 16:14:23 INFO - PROCESS | 2929 | 16:14:23 INFO - PROCESS | 2929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.50 stddev:1.49 (2.2%) stddev-sans-first:1.39 16:14:23 INFO - PROCESS | 2929 | Values: 66.0 68.0 68.0 69.0 68.0 69.0 70.0 68.0 69.0 65.0 16:14:23 INFO - PROCESS | 2929 | -------- Summary: end -------- 16:14:23 INFO - PROCESS | 2929 | 16:14:24 INFO - TEST-INFO | 2929: exit 0 16:14:24 INFO - TEST-OK | kraken | took 59547ms 16:14:24 INFO - SUITE-END | took 1175s 16:14:24 INFO - Completed test suite (00:19:35) 16:14:24 INFO - javascript benchmark 16:14:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.2701478659906, 3394.8472318745653, 3409.727221822254, 3429.0570093224346, 3468.3583537045647, 5653.858653533659, 5667.4049224831415, 5693.943060569393, 5703.486686198241, 5718.485336319735, 5637.746301416436, 5698.487136157745, 5702.173184888189, 5721.027425337692, 5725.971370143154, 5032.773525191357, 5095.464976177504, 5179.792808287664, 5225.268462415265, 5273.499017593342, 4445.466544014723, 5138.871528211787, 5227.7908883644795, 5240.764165612557, 5244.527992480676, 5164.8708782280555, 5175.301334319856, 5206.427292997767, 5213.530782229599, 5251.921221181686, 5158.045761534109, 5181.119209734355, 5187.974060129675, 5200.141976573861, 5239.816606418757, 5046.0664777016, 5054.721990290533, 5095.847124586231, 5114.28400023997, 5138.177891537336, 5477.20580515827, 5481.150421684605, 5494.313210848644, 5494.945050549523, 5596.524295434893, 6117.418845209698, 6145.170401995718, 6148.077788331741, 6150.384961503813, 6173.629582225081, 6100.481459075984, 6189.2572891252885, 6220.284667263278, 6232.0, 6279.277883043463, 5722.227499287626, 5722.313322401338, 5724.570657200727, 5738.167965645, 5768.192453056534, 6250.124982502453, 6255.96872015637, 6259.123722678829, 6265.561410701298, 6286.8428289292215, 5926.644401335933, 6001.609895356788, 6022.277326720821, 6028.2766068072115, 6047.909281360783, 6236.376362363818, 6251.249850018027, 6270.592411493239, 6273.560850740496, 6273.7176827042895, 6135.938640613626, 6160.353162917919, 6207.937920620736, 6211.0, 6227.688615569204, 6055.7274922628585, 6135.140800782589, 6137.070562331796, 6222.377762223832, 6273.153124328249, 4852.927206091911, 4872.0, 4899.6570240083565, 4908.705477671351, 4917.704937703749, 4414.984553552701, 4417.823287068545, 4425.911481770347, 4483.32750087483, 4501.774911254424, 5644.040513112781, 5669.489745922887, 5693.0, 5697.886042279214, 5726.942730572641, 6118.908216376758, 6208.565400422018, 6239.906401403982, 6263.342349053375, 6263.906041409383, 5627.184058311522, 5641.774329026875, 5710.65736055838, 5711.057675483582, 5715.685637289906, 5352.759125839347, 5363.570914326844, 5364.463553644604, 5364.678119312854, 5375.946240537544, 6194.0, 6240.7503699851495, 6269.216347956505, 6300.684965751694, 6310.463610593151, 5475.616706830484, 5892.793752218823, 6161.753529858755, 6164.167837341902, 6167.876642467215, 6271.717772700148, 6308.08532762756, 6319.589226700434, 6333.2716737575765, 6343.238811342484, 6119.143319935123, 6159.5072394209255, 6166.920788861841, 6169.444749972524, 6189.412005859436, 6112.205413296243, 6197.411245931629, 6199.814005579659, 6282.0, 6295.150154729053, 5975.760969561169, 6028.728707208098, 6042.728077236447, 6072.119542666201, 6082.0, 5959.821205363846, 5965.254343207099, 5996.640201587744, 6020.0, 6039.0, 5747.5689323300085, 5836.854078648068, 5876.911846322394, 5894.056950887837, 5901.262342207224, 4980.676256043345, 5992.161097446448, 5996.5502587307, 6009.489193418608, 6084.726187321493, 5865.266841644794, 5872.236609240771, 5874.706264686834, 5886.20536227621, 5896.764129434946], "value": 5649.000351305763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.1968927695988, 525.6872161064167, 568.92603961485, 573.4895942611076, 585.0814221671972, 30501.237469063286, 37540.62459375409, 38265.23469530608, 38330.61669383305, 38377.61622383775, 41473.792631036806, 41604.16791664172, 41665.37501937466, 41839.0, 41926.58073419265, 8444.366672499556, 9405.858912116324, 9623.037696230356, 9624.374415662995, 9630.518474076303, 8557.786055348635, 11487.885121148765, 11688.181827272094, 11717.589884354049, 11720.589779357724, 11407.315561066322, 11482.942585287063, 11488.885111148866, 11498.540058397653, 11510.482028308747, 8574.742757717278, 8705.129487051245, 8714.128587141298, 11378.146639002067, 11454.770904581903, 8509.191626795506, 11399.14506412014, 11630.476628551736, 11642.592509262135, 11653.883461165364, 30939.071827844975, 37107.257854842755, 37523.62476375228, 38067.619323806684, 38095.0, 3250.30118524516, 3312.685294896981, 3344.8160351180677, 3488.2735588066544, 3959.1883663848844, 26617.46765064707, 26654.0, 27145.0, 27413.86293068542, 27912.0, 2748.2991837081418, 2770.9307267318277, 2773.6394268745134, 2798.9020384286655, 2857.3142445812855, 461039.0, 463002.0, 463176.0, 463856.0, 464083.0, 35511.0, 36227.275454490766, 60334.79330413367, 60682.69658651679, 60714.392856071754, 34796.65203347985, 35000.12499687528, 35086.0, 35122.47316290258, 35169.47245791315, 15397.923010384877, 15560.844391556166, 15575.90968632185, 15579.065256084672, 15582.610434739223, 11144.387058711687, 12112.515499380102, 12340.81488777669, 13132.606021819362, 13181.011424143227, 8045.477043992122, 8074.434789564792, 8087.433879628487, 8149.062857771313, 8167.183281671904, 3072.7080927311868, 3257.055453918337, 3329.217633856042, 3372.37611041958, 3373.4433818420175, 1013.2400699475394, 1016.2682868334787, 1021.0453226233495, 1051.2273478992934, 1117.9944100279608, 29368.0, 29482.0, 29958.0, 30132.397352053275, 30351.848240758656, 1048.308116643012, 1070.6680928912062, 1124.8087825069758, 1204.1270079192516, 1209.8245754365744, 14922.328495217742, 14997.850021499646, 15113.697726045417, 15201.239938003277, 15494.84505154934, 616875.9156204191, 621726.0, 625043.0, 625466.0, 625932.0, 57543.0, 57611.71194144002, 58337.124943125884, 58392.708036459546, 58474.7076264616, 1356.19984209315, 1372.3412761874556, 1464.3849583175304, 1482.7775833625042, 1492.61938205759, 24256.393590160387, 26867.328316791456, 28619.713802861705, 29173.41653166967, 29614.259643509085, 47954.040919182116, 57990.4200957985, 59338.40661593329, 59392.70303648627, 59436.70281648564, 579541.0, 580693.0, 588945.0, 592530.0, 595667.0, 32889.50665739968, 34403.65596344005, 35120.12199695028, 35161.29677406489, 41844.95387615334, 42435.363469548596, 43460.348094779234, 44279.11441771211, 44738.3289250668, 44750.55249447594, 35615.0, 35942.8202858984, 36719.884614041635, 37119.44320835244, 37541.81229093837, 34127.82936085304, 36173.0, 36699.26601468009, 37234.25531489409, 37240.813795930844, 34611.82694086513, 36242.27515449729, 36990.0, 37371.439428408055, 37664.0, 31307.686923130477, 32563.0, 32736.017919462454, 32790.180245494055, 32794.18014549655, 18654.906725466284, 20993.265235716677, 24125.638115428636, 24363.39091522726, 24767.752322476543], "value": 18710.479826347146, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.65572935140455, 404.9453323801286, 409.82582402478977, 411.45071329774765, 421.06733585108975, 13003.34983250836, 18269.908650456728, 19956.700649490253, 20530.589388212244, 20607.484812879666, 19977.801331920058, 20209.39371818844, 20561.0, 20907.37277881662, 20932.848693321863, 5721.8569535761735, 5722.742476588543, 5931.703414829263, 6053.122297266894, 6069.453749162574, 3908.374660054392, 5036.471170527097, 5773.307203135609, 5969.522438204933, 6035.849103772419, 6047.21386219793, 6052.394760523956, 6054.122152287916, 6058.666773327465, 6073.60521566099, 5992.910106348409, 6026.583752818085, 6036.456718895299, 6540.607563546155, 6560.409563139293, 5837.64974101551, 5838.562107841929, 5839.8540036499, 5850.122481627747, 5867.970660146672, 23980.520389592286, 24888.3777905552, 25840.353991150183, 25856.095036673814, 25867.353316167057, 2094.172801743318, 2151.537419454809, 2233.486298151418, 2536.8985240590355, 2710.4985577668194, 9340.0, 9672.468014259213, 9688.75778105546, 10096.242781791396, 10428.79142417155, 1746.6681330547156, 1819.2160257202918, 1822.5352535103596, 1827.232562323827, 1845.9169337379851, 113403.0, 115223.0, 117344.0, 117816.41091794572, 117953.82046179514, 10953.014228719454, 11620.186586938833, 11631.360275184947, 11821.0, 11840.822387664191, 13405.0, 13408.597742067754, 13967.580972570839, 15089.622759431102, 15109.697806043818, 7527.227563547393, 8707.825843483095, 8935.061818509093, 8976.0, 9087.273018158532, 8952.68665596701, 8962.238209752113, 8983.371164018587, 9040.547972601344, 9099.499527525917, 4581.266997280419, 4696.95303046972, 4703.764811759398, 4742.5020372861045, 4768.880777980578, 1985.186073709801, 2005.5186755178943, 2040.6428874946826, 2056.2186369179917, 2060.3818854343635, 901.4366021236727, 937.6530683647094, 958.7842735384482, 983.233078199006, 1015.2334987084704, 19822.70265946012, 20504.794952050586, 20539.28112516055, 20743.688844667344, 21007.78992210089, 950.334765664038, 951.8048799995984, 956.4596003258137, 1018.7147598672384, 1043.1654676258963, 9178.541072946326, 10740.946295268473, 10966.958138976785, 11010.39442830652, 11029.83455248172, 567129.1643541756, 567267.1636641873, 572581.0, 578740.0, 607255.0, 68189.65905170541, 68574.9713754294, 68828.65585672039, 120091.19863202059, 122313.38843305726, 1021.7598864266891, 1038.4703801061512, 1054.4253381906879, 1089.809283375422, 1089.8310761831929, 17865.0, 18452.446426607225, 18734.625307494047, 18820.905895470434, 19016.0, 36166.819165904, 42050.0, 50457.49542504528, 51706.48293517167, 53949.0, 78526.6073669628, 80041.0, 81067.0, 82907.0, 83097.75353369584, 8110.188981101843, 8240.1347858474, 8266.214709602578, 8422.115677853737, 8510.021347545111, 9256.305777066613, 12151.696207594527, 12398.5660501882, 12582.0, 12588.87411125877, 9633.710988670351, 10850.782984340427, 11211.60759373418, 11261.774764504828, 11415.543378265109, 10616.787664246825, 10889.78220435571, 11060.557577696802, 11230.213885028297, 11336.263142895685, 11078.667639970814, 11294.60468883585, 11506.654800356002, 11601.941990290332, 11618.360990145286, 7340.5228660139055, 8171.387145963958, 8268.214519620626, 8281.627326770189, 8316.251537361666, 8477.915220847712, 9275.25797936177, 10707.785844283226, 11412.543498259976, 11462.885371146182], "value": 9477.034922676423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.0581122270517, 1590.3082159260723, 1625.7073726729188, 1862.0875770872276, 1964.5186929202348, 7904.051513818343, 8099.352051835854, 8100.513969161847, 8108.310793582546, 8530.274926631228, 8581.48511089335, 8690.608922598483, 8690.826183476327, 8715.95642021789, 8722.084181160983, 2380.0, 2439.634054891763, 2448.0, 2450.8529488230674, 2453.558359495299, 2385.0698227691214, 2424.9757502424927, 2434.172381390327, 2436.744141865105, 2441.4628781668093, 2389.5340408620327, 2411.6141417373224, 2426.1872272788637, 2427.356750461129, 2427.5630386530415, 2401.4956859059616, 2435.6468312094735, 2447.9143229987044, 2448.608222684371, 2453.116877923946, 2393.4614711689906, 2433.0146290752095, 2440.45089854783, 2441.9755802442105, 2453.153661986612, 8810.955945220298, 8834.86747698785, 8845.248153906925, 8845.911540884637, 8867.290616750644, 5296.92054619181, 5316.574674026068, 5447.972760136214, 5464.808731694372, 5470.042742520043, 3424.109731469811, 3443.2252743132844, 3498.4227602445567, 3504.5444092267844, 3507.6316986716283, 942.1708896171394, 1042.9165666746642, 1071.59815069349, 1072.447689439939, 1076.2466273608427, 11849.17055806094, 11942.104342174285, 11980.70048248792, 11982.161248712593, 11992.940035299855, 4464.866054018385, 4584.4957054723955, 4630.953690463052, 4645.674802763841, 4662.650301227422, 9265.351425400157, 9304.90695093054, 9350.158485736316, 9353.345265831462, 9357.251419886392, 5265.131253343156, 5302.496262855023, 5306.92039619406, 5310.0, 5317.042932272151, 4271.9786401067795, 4410.889727756832, 4701.130290896195, 4759.690620109682, 4849.151398505247, 3835.2329534092737, 3844.7116466265143, 3896.3765797472834, 3932.2725295820364, 3963.0290578808376, 1192.362086283834, 1197.4252358867793, 1208.6434501821984, 1212.7695737809963, 1229.287013532149, 605.42787066222, 744.3933194446508, 922.3820040572832, 925.6806401791371, 967.3542761230392, 5726.828195154152, 5771.0, 5782.190493330937, 5941.0, 6024.246969128859, 476.8903152274927, 489.3784893185634, 489.4224814718595, 506.2027306991503, 665.8181727433747, 3754.81225938702, 3920.2355540669864, 3951.1702542466405, 4014.6186112319283, 4019.8593049243136, 10304.742381440374, 10701.0, 10717.571297148183, 10830.566777328977, 10852.837207441895, 10568.52441640128, 10609.787804243872, 10695.94652026735, 10707.678769636923, 10720.142388609049, 7980.561069141137, 8266.628001739937, 8291.875621865676, 8361.581920904053, 8383.0, 5836.328822185503, 5894.056950887837, 5961.821145365646, 6044.24446944132, 6065.4541091300225, 9271.211946984313, 9387.671431500137, 9522.809543809224, 9527.714168574954, 9528.380655257386, 9205.539723013957, 9536.427814331162, 9540.045995400404, 9703.02969703024, 9705.46619935882, 7432.145303290191, 7521.247875212435, 7531.887021694789, 7535.924640753741, 7573.166951635426, 8003.19928016215, 8135.471194372347, 8188.672453101809, 8191.713290034821, 8193.672253110048, 7411.370033546993, 7456.664450099867, 7495.36289415384, 7622.771316860503, 7710.460267781202, 7338.119425668954, 7373.152087510005, 7393.371563417169, 7559.168491465824, 7566.735164269224, 7045.154581450259, 7074.787756367317, 7101.0, 7127.429805615642, 7140.285971402818, 4782.617390608673, 4841.176999909953, 4941.2835138905375, 5008.57427118699, 5033.5721463676, 4941.308216849716, 4996.550310472075, 5013.523715247045, 5081.974590127025, 5237.188235823453], "value": 4496.6543633256015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.8730626789882, 325.7784706399649, 331.50274588117816, 332.3038234897888, 336.8753561182363, 6324.683765811714, 6441.0, 6444.935550644492, 6469.70886310116, 6495.675216239193, 5399.433059528752, 6408.711607977641, 6423.004434312677, 6440.903386449207, 6444.935550644492, 3821.407681809324, 4550.317452382136, 4662.626989840822, 4680.882977925545, 4776.952230477703, 4710.197432527658, 4778.044391121773, 4794.88012799681, 4795.688280261772, 4835.2505361669, 4023.9597604024025, 4690.24956007039, 4779.091972525209, 4799.424069111711, 4799.664023518355, 3889.3388124018984, 4540.545945405466, 4643.210654188779, 4745.311929770164, 4778.856634300976, 3356.345512625039, 4100.34394496882, 4110.815013324408, 4117.258893399187, 4118.629323360882, 6179.19670442844, 6203.751849925998, 6235.563510554263, 6241.812745617639, 6247.843803904893, 5599.664020158803, 5612.045952188138, 5618.0449323615085, 5619.297587801525, 5629.4652008059165, 6027.035674292136, 6908.240093589751, 6911.619860907648, 6917.342852429011, 6918.0, 3727.329080765461, 4131.731437456586, 4451.732896026217, 4473.843415480443, 4497.9325310120375, 7093.503454758169, 7280.890786638205, 7281.1262648482525, 7287.125544934589, 7298.197198308182, 4000.579939106352, 4399.73601583906, 4571.382863313476, 4573.954260457419, 4592.471865735416, 4616.469106052847, 4624.907501850012, 4639.304104384302, 4641.164590373765, 4642.883927901762, 4549.863504094882, 5489.58828087895, 5495.313085864267, 5511.862203444946, 5520.503154716095, 6728.192616886005, 6895.862082758417, 6941.548799328028, 6953.33943275388, 6967.79096627102, 1582.3512359932372, 1583.8517075120676, 1602.8958117722311, 1604.7753314535742, 1606.9919650401673, 958.5399008475971, 973.9464329461806, 1002.979940401188, 1006.7432804634772, 1028.8662473878499, 6357.777477788255, 6469.353064693587, 6484.0, 6496.480281577463, 6502.544821862424, 6428.903566446507, 7088.964555177191, 7236.240194779578, 7247.20280769115, 7257.564546127249, 5641.9435805641415, 6108.908366374508, 6615.239247486505, 6618.841702702008, 6630.83422914431, 3774.3206222880144, 3817.1983883384246, 3823.770573765583, 3824.9235015299537, 3825.215830754689, 6050.42520960508, 6489.545731798823, 6930.480213983971, 7535.962320188364, 7541.698332066656, 7400.629968501661, 7438.479306448497, 7445.13871532117, 7468.663910124057, 7469.925300746923, 7533.698652053966, 7543.585102819397, 7545.396368290633, 7547.132079810783, 7552.471327007167, 6943.9652801735665, 7214.350708436266, 7224.674889629874, 7228.63856807147, 7230.457715671451, 7102.360787529147, 7114.181869085121, 7131.714731410685, 7135.643217838983, 7151.713931442684, 7480.364169045474, 7541.321281084729, 7547.05661792276, 7548.207438218908, 7554.886676699964, 4556.0, 4567.269236922077, 4570.862874113782, 4577.33628623841, 4586.403767510203, 6603.7688680895935, 7190.029346038218, 7200.351968322876, 7219.639018049182, 7224.530405523624, 6739.19129704419, 6995.440364770704, 7001.369876710916, 7016.614086225307, 7027.929720702932, 6931.549449285781, 6994.300569942965, 7004.509684322253, 7004.894926576004, 7016.438684905297, 6823.0, 6824.078749368771, 6831.146106736658, 6841.521093523405, 6841.589504629738, 5635.605507614591, 6792.117024786746, 6967.65161741921, 6987.0, 6988.4059854910865, 6572.76995305162, 6635.601863888182, 6739.92161254197, 6881.277465866012, 6888.827779305357], "value": 5013.817470254232, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.578894773658, 602.7227475361334, 738.462150087711, 758.821676905927, 766.4060353226246, 12940.417681204344, 15141.545753627408, 15151.0, 15164.924175379108, 15169.317380717866, 13643.454261829515, 13717.1083879548, 14948.850511494882, 15430.382784688627, 15490.070595764262, 851.7146755836771, 912.5847739278612, 919.7332773495694, 940.5955439161158, 942.6135284533343, 734.3280897978343, 748.8315129095937, 925.453982150531, 943.4009404028423, 944.032366824004, 990.4453506036607, 992.8312186928204, 992.8560358747977, 993.0169132558786, 997.675755379501, 769.7883082152397, 983.3853841349156, 985.8767654043245, 986.2405947420447, 986.4180133721103, 655.5087176673106, 690.7133539581068, 732.3555271361221, 734.5849594978873, 736.0027163193848, 14809.777853332209, 14813.703725925421, 14871.330790114363, 14891.925540372336, 14905.88205884563, 12236.0, 12243.69390765229, 12377.381130943508, 12713.809292860522, 12721.173123746927, 10007.49962501872, 10127.392356458562, 10141.44222067786, 10246.129079028291, 10375.481225938749, 3271.9182020449443, 3426.58880934287, 3483.3207524532727, 3561.786292822439, 3566.4472006838723, 81486.14798482084, 81870.0, 83701.0, 84331.0, 84341.0, 9227.53862306882, 9344.112309330603, 9425.38734982224, 9546.379485333431, 9785.902140978498, 21189.89405052965, 21296.893515532323, 21443.892780535996, 21447.463813404476, 21514.56970860574, 7298.671559779823, 7344.265573442613, 7529.962350188323, 7582.052243469567, 7633.160352361236, 4135.793210339471, 4140.834366625301, 4196.706230563831, 4222.092250166229, 4357.324614684729, 3508.4737289406794, 3601.7658852174527, 3649.7445178837756, 3659.5608526976935, 3674.026383008505, 656.671664167916, 657.6251536624078, 660.5046215338496, 672.4217173231017, 676.1277951442584, 543.6086018066985, 577.4427677291434, 578.2627150383211, 584.6433675457968, 585.2245774349004, 7598.544087354777, 7886.842263154705, 7945.920540794634, 7962.800929976797, 8233.50598964064, 510.9310243117206, 516.1638146203175, 521.2077641984239, 527.6385675812081, 592.2626330218905, 3130.405223007667, 3644.4168932970597, 3772.5472943246987, 3793.525809273841, 3946.6053394660303, 80882.0, 84268.31463370698, 87537.5623121893, 88867.55566222128, 91576.08423915676, 72032.0, 75879.62060189663, 77003.61498192586, 77344.61327693326, 79876.6006169977, 60293.69853150706, 63987.680061601255, 64005.07984760465, 64509.70980580456, 64964.675176623816, 7384.4830861839155, 7543.981562488993, 7609.695612175451, 7617.314441700273, 7729.381649468141, 46283.305750414445, 49171.0, 49196.0, 49673.0, 50326.99346013132, 66667.0, 66895.66552167208, 67006.0, 67407.98888016782, 68183.0, 9766.85349719769, 9895.455749933823, 10054.849177262493, 10103.848442273225, 10701.732456688645, 11477.253978491372, 11793.351365674971, 11922.463489142836, 12040.277583345027, 12044.819327709916, 8828.514431706319, 11268.211225214156, 11629.592964246549, 11646.0, 11832.17174797756, 8626.482411055356, 10865.0, 11670.066394688574, 11699.415029248674, 12182.02543796512, 10906.072983796466, 11046.558137674725, 11321.716957075809, 11329.830052549054, 11492.965633092726, 9472.810543789223, 9698.6120555177, 9737.123658870736, 9847.409155450408, 9958.15355694774, 9107.134822191882, 9658.179054780141, 9738.367006144577, 9794.608215671293, 9851.16265117473], "value": 6799.924969984414, "unit": "score"}], "name": "dromaeo_css", "value": 7317.925914932321, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 123.0, 165.0, 163.0, 115.0, 115.0, 114.0, 113.0, 136.0], "value": 127.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 125.0, 158.0, 159.0, 120.0, 142.0, 118.0, 151.0, 157.0, 121.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 189.0, 186.0, 184.0, 186.0, 184.0, 186.0, 188.0, 188.0], "value": 186.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 72.0, 155.0, 73.0, 82.0, 50.0, 62.0, 145.0, 84.0], "value": 87.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 144.0, 77.0, 78.0, 86.0, 125.0, 80.0, 104.0, 164.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 83.0, 87.0, 83.0, 87.0, 130.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 108.0, 150.0, 94.0, 94.0, 94.0, 96.0, 94.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 132.0, 139.0, 84.0, 85.0, 87.0, 84.0, 85.0, 86.0, 87.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 61.0, 61.0, 65.0, 53.0, 57.0, 100.0, 86.0, 93.0], "value": 69.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 76.0, 76.0, 69.0, 67.0, 69.0, 67.0, 69.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 81.0, 80.0, 80.0, 81.0, 81.0, 79.0, 87.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 147.0, 150.0, 135.0, 129.0, 129.0, 129.0, 129.0, 150.0, 145.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 166.0, 164.0, 165.0, 172.0, 224.0, 225.0, 166.0, 163.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 69.0, 68.0, 69.0, 70.0, 68.0, 69.0, 65.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1537.9, "alertThreshold": 2.0}]} 16:14:24 INFO - Return code: 0 16:14:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:14:24 ERROR - # TBPL SUCCESS # 16:14:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:14:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:14:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:14:24 INFO - 2016-05-30 16:14:24.611 screenresolution[2933:41112] starting screenresolution argv=screenresolution get 16:14:24 INFO - 2016-05-30 16:14:24.614 screenresolution[2933:41112] Display 0: 1600x1200x32@60 16:14:24 INFO - 2016-05-30 16:14:24.620 screenresolution[2934:41115] starting screenresolution argv=screenresolution list 16:14:24 INFO - Available Modes on Display 0 16:14:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:14:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:14:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:14:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:14:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:14:24 INFO - Intel Iris: 16:14:24 INFO - Chipset Model: Intel Iris 16:14:24 INFO - Type: GPU 16:14:24 INFO - Bus: Built-In 16:14:24 INFO - VRAM (Dynamic, Max): 1536 MB 16:14:24 INFO - Vendor: Intel (0x8086) 16:14:24 INFO - Device ID: 0x0a2e 16:14:24 INFO - Revision ID: 0x0009 16:14:24 INFO - Displays: 16:14:24 INFO - Display: 16:14:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:14:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:14:24 INFO - Display Serial Number: 1600x1200 60 16:14:24 INFO - Main Display: Yes 16:14:24 INFO - Mirror: Off 16:14:24 INFO - Online: Yes 16:14:24 INFO - Rotation: Supported 16:14:24 INFO - Return code: 0 16:14:24 INFO - Running post-action listener: _resource_record_post_action 16:14:24 INFO - Running post-run listener: _resource_record_post_run 16:14:25 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 925696; Write bytes: 654248960; Read time: 9; Write time: 1888 16:14:25 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 342718976; Write bytes: 338007040; Read time: 26416; Write time: 709 16:14:25 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 925696; Write bytes: 296904704; Read time: 9; Write time: 1124 16:14:25 INFO - Running post-run listener: _upload_blobber_files 16:14:25 INFO - Blob upload gear active. 16:14:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:14:25 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 16:14:25 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:14:25 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:14:26 INFO - (blobuploader) - INFO - Open directory for files ... 16:14:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:14:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:14:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:27 INFO - (blobuploader) - INFO - Done attempting. 16:14:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:14:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:14:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:28 INFO - (blobuploader) - INFO - Done attempting. 16:14:28 INFO - (blobuploader) - INFO - Iteration through files over. 16:14:28 INFO - Return code: 0 16:14:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:14:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:14:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e9b224eedc9e986b956fd7dd287a74bee727a6931ae7e121ebdb23a57471584001e7f4603b27034e642707b1eceda2761ba8d756b175d5e28ff8b3a3c930d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5540efbda8f6369bb006234c6511fbb620f0f09c56dfb79e5057a3a407ca90dd3afb5a493847822a19e139911ddb239ac90d1f4e4688123313aec0cf2a492e81"} 16:14:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:14:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:14:28 INFO - Contents: 16:14:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e9b224eedc9e986b956fd7dd287a74bee727a6931ae7e121ebdb23a57471584001e7f4603b27034e642707b1eceda2761ba8d756b175d5e28ff8b3a3c930d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5540efbda8f6369bb006234c6511fbb620f0f09c56dfb79e5057a3a407ca90dd3afb5a493847822a19e139911ddb239ac90d1f4e4688123313aec0cf2a492e81"} 16:14:28 INFO - Running post-run listener: copy_logs_to_upload_dir 16:14:28 INFO - Copying logs to upload dir... 16:14:28 INFO - mkdir: /builds/slave/test/build/upload/logs 16:14:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1422.414964 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 42 secs) (at 2016-05-30 16:14:28.586547) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:14:28.591736) ========= 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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e9b224eedc9e986b956fd7dd287a74bee727a6931ae7e121ebdb23a57471584001e7f4603b27034e642707b1eceda2761ba8d756b175d5e28ff8b3a3c930d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5540efbda8f6369bb006234c6511fbb620f0f09c56dfb79e5057a3a407ca90dd3afb5a493847822a19e139911ddb239ac90d1f4e4688123313aec0cf2a492e81"} build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e9b224eedc9e986b956fd7dd287a74bee727a6931ae7e121ebdb23a57471584001e7f4603b27034e642707b1eceda2761ba8d756b175d5e28ff8b3a3c930d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5540efbda8f6369bb006234c6511fbb620f0f09c56dfb79e5057a3a407ca90dd3afb5a493847822a19e139911ddb239ac90d1f4e4688123313aec0cf2a492e81"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:14:28.622378) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:14:28.622694) ========= 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.XarJTJ5n7i/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.sPKjgqrtkF/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.005227 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:14:28.671037) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:14:28.671352) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:14:28.671685) ========= ========= Total master_lag: 0.17 =========