builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0258 starttime: 1474121881.5 results: success (0) buildid: 20160917071757 builduid: 3d78af38861a4f60a0dd8acca3202180 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.500906) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.501424) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.501737) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.575230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.575656) ========= 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.1RaeyGyaF8/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.eletWKFMYn/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-09-17 07:18:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-09-17 07:18:02 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.540232 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.143830) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.144161) ========= 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.1RaeyGyaF8/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.eletWKFMYn/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.026140 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.191805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:02.192126) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --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 b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1RaeyGyaF8/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.eletWKFMYn/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-09-17 07:18:02,277 truncating revision to first 12 chars 2016-09-17 07:18:02,277 Setting DEBUG logging. 2016-09-17 07:18:02,277 attempt 1/10 2016-09-17 07:18:02,277 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9499912b323?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 07:18:03,844 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=1.826644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:04.041225) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.041556) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.041961) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.042242) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.060972) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-09-17 07:18:04.061306) ========= /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.1RaeyGyaF8/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.eletWKFMYn/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:04 INFO - MultiFileLogger online at 20160917 07:18:04 in /builds/slave/test 07:18:04 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 07:18:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:04 INFO - {'append_to_log': False, 07:18:04 INFO - 'base_work_dir': '/builds/slave/test', 07:18:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:04 INFO - 'branch': 'Mozilla-Aurora', 07:18:04 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:04 INFO - 'config_files': ('talos/mac_config.py',), 07:18:04 INFO - 'default_actions': ('clobber', 07:18:04 INFO - 'read-buildbot-config', 07:18:04 INFO - 'download-and-extract', 07:18:04 INFO - 'populate-webroot', 07:18:04 INFO - 'create-virtualenv', 07:18:04 INFO - 'install', 07:18:04 INFO - 'run-tests'), 07:18:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:04 INFO - 'download_minidump_stackwalk': True, 07:18:04 INFO - 'download_symbols': 'ondemand', 07:18:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:04 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:04 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:04 INFO - 'installer_path': 'installer.exe', 07:18:04 INFO - 'log_level': 'info', 07:18:04 INFO - 'log_name': 'talos', 07:18:04 INFO - 'log_to_console': True, 07:18:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:04 INFO - 'opt_config_files': (), 07:18:04 INFO - 'pip_index': False, 07:18:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'run_cmd_checks_enabled': True, 07:18:04 INFO - 'sps_profile': False, 07:18:04 INFO - 'sps_profile_interval': 0, 07:18:04 INFO - 'suite': 'dromaeojs', 07:18:04 INFO - 'system_bits': '32', 07:18:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:04 INFO - 'title': 't-yosemite-r7-0258', 07:18:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:04 INFO - 'use_talos_json': True, 07:18:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:04 INFO - 'work_dir': 'build'} 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.252346Z] Running clobber step. 07:18:04 INFO - Running pre-action listener: _resource_record_pre_action 07:18:04 INFO - Running main action method: clobber 07:18:04 INFO - rmtree: /builds/slave/test/build 07:18:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:06 INFO - Running post-action listener: _resource_record_post_action 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.340017Z] Finished clobber step (success) 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.340150Z] Running read-buildbot-config step. 07:18:06 INFO - Running pre-action listener: _resource_record_pre_action 07:18:06 INFO - Running main action method: read_buildbot_config 07:18:06 INFO - Using buildbot properties: 07:18:06 INFO - { 07:18:06 INFO - "project": "", 07:18:06 INFO - "product": "firefox", 07:18:06 INFO - "script_repo_revision": "production", 07:18:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:18:06 INFO - "repository": "", 07:18:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:18:06 INFO - "buildid": "20160917071757", 07:18:06 INFO - "stage_platform": "macosx64", 07:18:06 INFO - "basedir": "/builds/slave/test", 07:18:06 INFO - "buildnumber": 41, 07:18:06 INFO - "platform": "yosemite_r7", 07:18:06 INFO - "mozci_request": { 07:18:06 INFO - "priority": -1, 07:18:06 INFO - "type": "trigger_all_talos_jobs", 07:18:06 INFO - "times": 6 07:18:06 INFO - }, 07:18:06 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:18:06 INFO - "slavebuilddir": "test", 07:18:06 INFO - "branch": "mozilla-aurora", 07:18:06 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:06 INFO - "slavename": "t-yosemite-r7-0258", 07:18:06 INFO - "builduid": "3d78af38861a4f60a0dd8acca3202180", 07:18:06 INFO - "repo_path": "releases/mozilla-aurora" 07:18:06 INFO - } 07:18:06 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg. 07:18:06 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json. 07:18:06 INFO - Running post-action listener: _resource_record_post_action 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.344077Z] Finished read-buildbot-config step (success) 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.344195Z] Running download-and-extract step. 07:18:06 INFO - Running pre-action listener: _resource_record_pre_action 07:18:06 INFO - Running main action method: download_and_extract 07:18:06 INFO - mkdir: /builds/slave/test/build/tests 07:18: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')]} 07:18:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json 07:18:06 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json'}, attempt #1 07:18:06 INFO - Downloaded 1199 bytes. 07:18:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:06 INFO - Using the following test package requirements: 07:18:06 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:06 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:06 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:06 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'jsshell-mac.zip'], 07:18:06 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:06 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:06 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:06 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:06 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:06 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:06 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:18: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')]} 07:18:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:06 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:06 INFO - Downloaded 16309409 bytes. 07:18:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:09 INFO - Return code: 0 07:18:09 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip', u'firefox-50.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:18:09 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')]} 07:18:09 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:09 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:10 INFO - Downloaded 16309409 bytes. 07:18:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:13 INFO - Return code: 0 07:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:13 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip 07:18:13 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip 07:18:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:18:13 INFO - Downloaded 14413740 bytes. 07:18:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:18:14 INFO - Return code: 0 07:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:14 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:14 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg 07:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg'}, attempt #1 07:18:16 INFO - Downloaded 115349660 bytes. 07:18:16 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:16 INFO - mkdir: /builds/slave/test/properties 07:18:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:16 INFO - Contents: 07:18:16 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:16 INFO - Running post-action listener: _resource_record_post_action 07:18:16 INFO - Running post-action listener: set_extra_try_arguments 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.640777Z] Finished download-and-extract step (success) 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.640901Z] Running populate-webroot step. 07:18:16 INFO - Running pre-action listener: _resource_record_pre_action 07:18:16 INFO - Running main action method: populate_webroot 07:18:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:16 INFO - '--tppagecycles', 07:18:16 INFO - '7'], 07:18:16 INFO - 'tests': ['tsvgm']}}, 07:18:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['damp', 'tps']}, 07:18:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'tests': ['damp', 'tps']}, 07:18:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['dromaeo_dom']}, 07:18:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['basic_compositor_video']}, 07:18:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tsvgx', 07:18:16 INFO - 'tsvgr_opacity', 07:18:16 INFO - 'tart', 07:18:16 INFO - 'tscrollx', 07:18:16 INFO - 'cart']}, 07:18:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:16 INFO - 'tsvgr_opacity', 07:18:16 INFO - 'tart', 07:18:16 INFO - 'tscrollx', 07:18:16 INFO - 'cart']}, 07:18:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tp5o']}, 07:18:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'tests': ['tp5o']}, 07:18:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s', 07:18:16 INFO - '--xperf_path', 07:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:16 INFO - 'tests': ['tp5n']}, 07:18:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--xperf_path', 07:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:16 INFO - 'tests': ['tp5n']}}, 07:18:16 INFO - 'talos.zip': {'path': '', 07:18:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:16 INFO - Running post-action listener: _resource_record_post_action 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.659758Z] Finished populate-webroot step (success) 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.659909Z] Running create-virtualenv step. 07:18:16 INFO - Running pre-action listener: _resource_record_pre_action 07:18:16 INFO - Running main action method: create_virtualenv 07:18:16 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:16 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 07:18:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:16 INFO - Using real prefix '/tools/python27' 07:18:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:17 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:20 INFO - Installing pip.................done. 07:18:20 INFO - Return code: 0 07:18:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:20 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')]} 07:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 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')]} 07:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 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 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:20 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 07:18:20 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 07:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:20 INFO - 'HOME': '/Users/cltbld', 07:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:20 INFO - 'LOGNAME': 'cltbld', 07:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:20 INFO - 'MOZ_NO_REMOTE': '1', 07:18:20 INFO - 'NO_EM_RESTART': '1', 07:18:20 INFO - 'PAGER': '/bin/cat', 07:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:20 INFO - 'PWD': '/builds/slave/test', 07:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:20 INFO - 'SHELL': '/bin/bash', 07:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:20 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:20 INFO - 'USER': 'cltbld', 07:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:20 INFO - 'XPC_FLAGS': '0x0', 07:18:20 INFO - 'XPC_SERVICE_NAME': '0', 07:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:20 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 07:18:20 INFO - Cleaning up... 07:18:20 INFO - Return code: 0 07:18:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:20 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')]} 07:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 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')]} 07:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:20 INFO - 'HOME': '/Users/cltbld', 07:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:20 INFO - 'LOGNAME': 'cltbld', 07:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:20 INFO - 'MOZ_NO_REMOTE': '1', 07:18:20 INFO - 'NO_EM_RESTART': '1', 07:18:20 INFO - 'PAGER': '/bin/cat', 07:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:20 INFO - 'PWD': '/builds/slave/test', 07:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:20 INFO - 'SHELL': '/bin/bash', 07:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:20 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:20 INFO - 'USER': 'cltbld', 07:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:20 INFO - 'XPC_FLAGS': '0x0', 07:18:20 INFO - 'XPC_SERVICE_NAME': '0', 07:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:20 INFO - Downloading/unpacking psutil>=3.1.1 07:18:20 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 07:18:20 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 07:18:20 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 07:18:20 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 07:18:20 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 07:18:20 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 07:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:24 INFO - Installing collected packages: psutil 07:18:24 INFO - Running setup.py install for psutil 07:18:24 INFO - building 'psutil._psutil_osx' extension 07:18:24 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 07:18:24 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 07:18:24 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 07:18:24 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 07:18:25 INFO - building 'psutil._psutil_posix' extension 07:18:25 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 07:18:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:25 INFO - ^ 07:18:25 INFO - 1 warning generated. 07:18:25 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 07:18:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:25 INFO - Successfully installed psutil 07:18:25 INFO - Cleaning up... 07:18:25 INFO - Return code: 0 07:18:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:25 INFO - 'HOME': '/Users/cltbld', 07:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:25 INFO - 'LOGNAME': 'cltbld', 07:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:25 INFO - 'MOZ_NO_REMOTE': '1', 07:18:25 INFO - 'NO_EM_RESTART': '1', 07:18:25 INFO - 'PAGER': '/bin/cat', 07:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:25 INFO - 'PWD': '/builds/slave/test', 07:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:25 INFO - 'SHELL': '/bin/bash', 07:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:25 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:25 INFO - 'USER': 'cltbld', 07:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:25 INFO - 'XPC_FLAGS': '0x0', 07:18:25 INFO - 'XPC_SERVICE_NAME': '0', 07:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:18:28 INFO - Installing collected packages: mozsystemmonitor 07:18:28 INFO - Running setup.py install for mozsystemmonitor 07:18:28 INFO - Successfully installed mozsystemmonitor 07:18:28 INFO - Cleaning up... 07:18:28 INFO - Return code: 0 07:18:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:18:28 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')]} 07:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 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')]} 07:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:28 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==2.5.1'] in /builds/slave/test/build 07:18:28 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==2.5.1 07:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:28 INFO - 'HOME': '/Users/cltbld', 07:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:28 INFO - 'LOGNAME': 'cltbld', 07:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:28 INFO - 'MOZ_NO_REMOTE': '1', 07:18:28 INFO - 'NO_EM_RESTART': '1', 07:18:28 INFO - 'PAGER': '/bin/cat', 07:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:28 INFO - 'PWD': '/builds/slave/test', 07:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:28 INFO - 'SHELL': '/bin/bash', 07:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:28 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:28 INFO - 'USER': 'cltbld', 07:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:28 INFO - 'XPC_FLAGS': '0x0', 07:18:28 INFO - 'XPC_SERVICE_NAME': '0', 07:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:28 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:28 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 07:18:28 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 07:18:28 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 07:18:28 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 07:18:28 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 07:18:28 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 07:18:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:31 INFO - warning: no files found matching '*.txt' 07:18:31 INFO - no previously-included directories found matching 'build' 07:18:31 INFO - no previously-included directories found matching 'dist' 07:18:31 INFO - no previously-included directories found matching '.git*' 07:18:31 INFO - Installing collected packages: jsonschema, functools32 07:18:31 INFO - Running setup.py install for functools32 07:18:31 INFO - warning: no files found matching '*.txt' 07:18:31 INFO - no previously-included directories found matching 'build' 07:18:31 INFO - no previously-included directories found matching 'dist' 07:18:31 INFO - no previously-included directories found matching '.git*' 07:18:31 INFO - Successfully installed jsonschema functools32 07:18:31 INFO - Cleaning up... 07:18:31 INFO - Return code: 0 07:18:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:31 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:31 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:18:31 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 functools32==3.2.3-2 07:18:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:31 INFO - 'HOME': '/Users/cltbld', 07:18:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:31 INFO - 'LOGNAME': 'cltbld', 07:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:31 INFO - 'MOZ_NO_REMOTE': '1', 07:18:31 INFO - 'NO_EM_RESTART': '1', 07:18:31 INFO - 'PAGER': '/bin/cat', 07:18:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:31 INFO - 'PWD': '/builds/slave/test', 07:18:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:31 INFO - 'SHELL': '/bin/bash', 07:18:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:31 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:31 INFO - 'USER': 'cltbld', 07:18:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:31 INFO - 'XPC_FLAGS': '0x0', 07:18:31 INFO - 'XPC_SERVICE_NAME': '0', 07:18:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:32 INFO - Cleaning up... 07:18:32 INFO - Return code: 0 07:18:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:32 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')]} 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:32 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')]} 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:32 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 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:32 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 07:18:32 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 07:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:32 INFO - 'HOME': '/Users/cltbld', 07:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:32 INFO - 'LOGNAME': 'cltbld', 07:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:32 INFO - 'MOZ_NO_REMOTE': '1', 07:18:32 INFO - 'NO_EM_RESTART': '1', 07:18:32 INFO - 'PAGER': '/bin/cat', 07:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:32 INFO - 'PWD': '/builds/slave/test', 07:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:32 INFO - 'SHELL': '/bin/bash', 07:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:32 INFO - 'USER': 'cltbld', 07:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:32 INFO - 'XPC_FLAGS': '0x0', 07:18:32 INFO - 'XPC_SERVICE_NAME': '0', 07:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:32 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:32 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 07:18:32 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 07:18:32 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 07:18:32 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 07:18:32 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 07:18:32 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 07:18:34 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:34 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 07:18:34 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 07:18:34 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 07:18:34 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 07:18:34 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 07:18:34 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 07:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - Downloading docopt-0.6.1.tar.gz 07:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:36 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:36 INFO - Running setup.py install for blobuploader 07:18:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:36 INFO - Running setup.py install for requests 07:18:36 INFO - Running setup.py install for docopt 07:18:36 INFO - Successfully installed blobuploader requests docopt 07:18:36 INFO - Cleaning up... 07:18:37 INFO - Return code: 0 07:18:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 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 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:37 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 07:18:37 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 07:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:37 INFO - 'HOME': '/Users/cltbld', 07:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:37 INFO - 'LOGNAME': 'cltbld', 07:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:37 INFO - 'MOZ_NO_REMOTE': '1', 07:18:37 INFO - 'NO_EM_RESTART': '1', 07:18:37 INFO - 'PAGER': '/bin/cat', 07:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:37 INFO - 'PWD': '/builds/slave/test', 07:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:37 INFO - 'SHELL': '/bin/bash', 07:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:37 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:37 INFO - 'USER': 'cltbld', 07:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:37 INFO - 'XPC_FLAGS': '0x0', 07:18:37 INFO - 'XPC_SERVICE_NAME': '0', 07:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-z8Ygn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-2KRK3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-U_aorC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-mQXvcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-eNcbjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-JEm1bN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-MNCUrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-RSowhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6wYtNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-2iyRCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-igTeg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Zeaqfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-1fZZnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6QoXVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-GQG9wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-_HiNOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-hoOe2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:39 INFO - Running setup.py install for manifestparser 07:18:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:39 INFO - Running setup.py install for mozcrash 07:18:39 INFO - Running setup.py install for mozdebug 07:18:39 INFO - Running setup.py install for mozdevice 07:18:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:39 INFO - Running setup.py install for mozfile 07:18:39 INFO - Running setup.py install for mozhttpd 07:18:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:39 INFO - Running setup.py install for mozinfo 07:18:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozInstall 07:18:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozleak 07:18:40 INFO - Running setup.py install for mozlog 07:18:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for moznetwork 07:18:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozprocess 07:18:40 INFO - Running setup.py install for mozprofile 07:18:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozrunner 07:18:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozscreenshot 07:18:41 INFO - Running setup.py install for moztest 07:18:41 INFO - Running setup.py install for mozversion 07:18:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:41 INFO - Cleaning up... 07:18:41 INFO - Return code: 0 07:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:41 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')]} 07:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 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')]} 07:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 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 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:41 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 07:18:41 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 07:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:41 INFO - 'HOME': '/Users/cltbld', 07:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:41 INFO - 'LOGNAME': 'cltbld', 07:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:41 INFO - 'MOZ_NO_REMOTE': '1', 07:18:41 INFO - 'NO_EM_RESTART': '1', 07:18:41 INFO - 'PAGER': '/bin/cat', 07:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:41 INFO - 'PWD': '/builds/slave/test', 07:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:41 INFO - 'SHELL': '/bin/bash', 07:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:41 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:41 INFO - 'USER': 'cltbld', 07:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:41 INFO - 'XPC_FLAGS': '0x0', 07:18:41 INFO - 'XPC_SERVICE_NAME': '0', 07:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ycKKOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:41 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)) 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-OfNuDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-epxFDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-bZgy8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6evECK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-FeNyRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ujcs90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-c0pxyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-oXW7E1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Zrm7wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-YswV6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Lg9h3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Or0IFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-AspAwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-NcI9xZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-YdC7g1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-svbbaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:46 INFO - Downloading blessings-1.6.tar.gz 07:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:46 INFO - Installing collected packages: blessings 07:18:46 INFO - Running setup.py install for blessings 07:18:46 INFO - Successfully installed blessings 07:18:46 INFO - Cleaning up... 07:18:46 INFO - Return code: 0 07:18:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:46 INFO - Reading from file tmpfile_stdout 07:18:46 INFO - Current package versions: 07:18:46 INFO - blessings == 1.6 07:18:46 INFO - blobuploader == 1.2.4 07:18:46 INFO - docopt == 0.6.1 07:18:46 INFO - functools32 == 3.2.3-2 07:18:46 INFO - jsonschema == 2.5.1 07:18:46 INFO - manifestparser == 1.1 07:18:46 INFO - mozInstall == 1.12 07:18:46 INFO - mozcrash == 0.17 07:18:46 INFO - mozdebug == 0.1 07:18:46 INFO - mozdevice == 0.48 07:18:46 INFO - mozfile == 1.2 07:18:46 INFO - mozhttpd == 0.7 07:18:46 INFO - mozinfo == 0.9 07:18:46 INFO - mozleak == 0.1 07:18:46 INFO - mozlog == 3.3 07:18:46 INFO - moznetwork == 0.27 07:18:46 INFO - mozprocess == 0.23 07:18:46 INFO - mozprofile == 0.28 07:18:46 INFO - mozrunner == 6.12 07:18:46 INFO - mozscreenshot == 0.1 07:18:46 INFO - mozsystemmonitor == 0.3 07:18:46 INFO - moztest == 0.7 07:18:46 INFO - mozversion == 1.4 07:18:46 INFO - psutil == 3.1.1 07:18:46 INFO - requests == 1.2.3 07:18:46 INFO - wsgiref == 0.1.2 07:18:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:46 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 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:46 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 07:18:46 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 07:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:46 INFO - 'HOME': '/Users/cltbld', 07:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:46 INFO - 'LOGNAME': 'cltbld', 07:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:46 INFO - 'MOZ_NO_REMOTE': '1', 07:18:46 INFO - 'NO_EM_RESTART': '1', 07:18:46 INFO - 'PAGER': '/bin/cat', 07:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:46 INFO - 'PWD': '/builds/slave/test', 07:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:46 INFO - 'SHELL': '/bin/bash', 07:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:46 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:46 INFO - 'USER': 'cltbld', 07:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:46 INFO - 'XPC_FLAGS': '0x0', 07:18:46 INFO - 'XPC_SERVICE_NAME': '0', 07:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 07:18:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bb4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f816b482340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, '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.1RaeyGyaF8/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.eletWKFMYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 07:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:47 INFO - 'HOME': '/Users/cltbld', 07:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:47 INFO - 'LOGNAME': 'cltbld', 07:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:47 INFO - 'MOZ_NO_REMOTE': '1', 07:18:47 INFO - 'NO_EM_RESTART': '1', 07:18:47 INFO - 'PAGER': '/bin/cat', 07:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:47 INFO - 'PWD': '/builds/slave/test', 07:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:47 INFO - 'SHELL': '/bin/bash', 07:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:18:47 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:47 INFO - 'USER': 'cltbld', 07:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:47 INFO - 'XPC_FLAGS': '0x0', 07:18:47 INFO - 'XPC_SERVICE_NAME': '0', 07:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 07:18:47 INFO - Running post-action listener: _resource_record_post_action 07:18:47 INFO - Running post-action listener: _start_resource_monitoring 07:18:47 INFO - Starting resource monitoring. 07:18:47 INFO - [mozharness: 2016-09-17 14:18:47.359701Z] Finished create-virtualenv step (success) 07:18:47 INFO - [mozharness: 2016-09-17 14:18:47.360631Z] Running install step. 07:18:47 INFO - Running pre-action listener: _resource_record_pre_action 07:18:47 INFO - Running main action method: install 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:47 INFO - Reading from file tmpfile_stdout 07:18:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:47 INFO - Reading from file tmpfile_stdout 07:18:47 INFO - Output received: 07:18:47 INFO - Usage: mozinstall [options] installer 07:18:47 INFO - Options: 07:18:47 INFO - -h, --help show this help message and exit 07:18:47 INFO - -d DEST, --destination=DEST 07:18:47 INFO - Directory to install application into. [default: 07:18:47 INFO - "/builds/slave/test"] 07:18:47 INFO - --app=APP Application being installed. [default: firefox] 07:18:47 INFO - mkdir: /builds/slave/test/build/application 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:19:19 INFO - Reading from file tmpfile_stdout 07:19:19 INFO - Output received: 07:19:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:19 INFO - Running post-action listener: _resource_record_post_action 07:19:19 INFO - [mozharness: 2016-09-17 14:19:19.880467Z] Finished install step (success) 07:19:19 INFO - [mozharness: 2016-09-17 14:19:19.880594Z] Running run-tests step. 07:19:19 INFO - Running pre-action listener: _resource_record_pre_action 07:19:19 INFO - Running main action method: run_tests 07:19:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:19 INFO - 2016-09-17 07:19:19.894 screenresolution[3212:22106] starting screenresolution argv=screenresolution get 07:19:19 INFO - 2016-09-17 07:19:19.897 screenresolution[3212:22106] Display 0: 1600x1200x32@60 07:19:19 INFO - 2016-09-17 07:19:19.903 screenresolution[3213:22108] starting screenresolution argv=screenresolution list 07:19:19 INFO - Available Modes on Display 0 07:19:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:20 INFO - Intel Iris: 07:19:20 INFO - Chipset Model: Intel Iris 07:19:20 INFO - Type: GPU 07:19:20 INFO - Bus: Built-In 07:19:20 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:20 INFO - Vendor: Intel (0x8086) 07:19:20 INFO - Device ID: 0x0a2e 07:19:20 INFO - Revision ID: 0x0009 07:19:20 INFO - Displays: 07:19:20 INFO - Display: 07:19:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:20 INFO - Display Serial Number: 1600x1200 60 07:19:20 INFO - Main Display: Yes 07:19:20 INFO - Mirror: Off 07:19:20 INFO - Online: Yes 07:19:20 INFO - Rotation: Supported 07:19:20 INFO - Return code: 0 07:19:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:20 INFO - Python 2.7.3 07:19:20 INFO - Return code: 0 07:19:20 INFO - grabbing minidump binary from tooltool 07:19:20 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')]} 07:19:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a83d30>, '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 0x1057e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a7bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:20 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 07:19:20 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 07:19:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:19:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:20 INFO - Return code: 0 07:19:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:20 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 07:19:20 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 07:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RaeyGyaF8/Render', 07:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:20 INFO - 'HOME': '/Users/cltbld', 07:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:20 INFO - 'LOGNAME': 'cltbld', 07:19:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:20 INFO - 'MOZ_NO_REMOTE': '1', 07:19:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:20 INFO - 'NO_EM_RESTART': '1', 07:19:20 INFO - 'PAGER': '/bin/cat', 07:19:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:20 INFO - 'PWD': '/builds/slave/test', 07:19:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:20 INFO - 'SHELL': '/bin/bash', 07:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eletWKFMYn/Listeners', 07:19:20 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 07:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:20 INFO - 'USER': 'cltbld', 07:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:20 INFO - 'XPC_FLAGS': '0x0', 07:19:20 INFO - 'XPC_SERVICE_NAME': '0', 07:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:20 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 07:19:20 INFO - mozversion application_buildid: 20160916162818 07:19:20 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:20 INFO - mozversion application_name: Firefox 07:19:20 INFO - mozversion application_remotingname: firefox-dev 07:19:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:20 INFO - mozversion application_vendor: Mozilla 07:19:20 INFO - mozversion application_version: 50.0a2 07:19:20 INFO - mozversion platform_buildid: 20160916162818 07:19:20 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:20 INFO - mozversion platform_version: 50.0a2 07:19:20 INFO - using testdate: 1474121960 07:19:20 INFO - actual date: 1474121960 07:19:20 INFO - starting webserver on 'localhost:49276' 07:19:20 INFO - SUITE-START | Running 2 tests 07:19:20 INFO - TEST-START | dromaeo_css 07:19:20 INFO - operating with platform_type : mac_ 07:19:20 INFO - Initialising browser for dromaeo_css test... 07:19:20 INFO - TEST-INFO | started process 3223 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpy3nE3S/profile http://localhost:49276/getInfo.html) 07:19:22 INFO - PROCESS | 3223 | __metrics Screen width/height:1600/1200 07:19:22 INFO - PROCESS | 3223 | colorDepth:24 07:19:22 INFO - PROCESS | 3223 | Browser inner width/height: 1024/689 07:19:22 INFO - PROCESS | 3223 | __metrics 07:19:23 INFO - PROCESS | 3223 | 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" 07:19:23 INFO - PROCESS | 3223 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:23 INFO - TEST-INFO | 3223: exit 0 07:19:23 INFO - Browser initialized. 07:19:23 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:23 INFO - TEST-INFO | started process 3226 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpy3nE3S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:35 INFO - PROCESS | 3226 | RSS: Main: 215621632 07:19:35 INFO - PROCESS | 3226 | 07:20:27 INFO - PROCESS | 3226 | 1474122027386 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] 07:22:37 INFO - PROCESS | 3226 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 07:22:39 INFO - PROCESS | 3226 | RSS: Main: 331919360 07:22:39 INFO - PROCESS | 3226 | 07:25:42 INFO - PROCESS | 3226 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 07:25:42 INFO - PROCESS | 3226 | RSS: Main: 361467904 07:25:42 INFO - PROCESS | 3226 | 07:28:45 INFO - PROCESS | 3226 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 07:28:45 INFO - PROCESS | 3226 | RSS: Main: 339550208 07:28:45 INFO - PROCESS | 3226 | 07:31:48 INFO - PROCESS | 3226 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 07:31:48 INFO - PROCESS | 3226 | RSS: Main: 338911232 07:31:48 INFO - PROCESS | 3226 | 07:34:52 INFO - PROCESS | 3226 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 07:34:52 INFO - PROCESS | 3226 | RSS: Main: 319901696 07:34:52 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 07:37:55 INFO - PROCESS | 3226 | RSS: Main: 368672768 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | __start_tp_report 07:37:55 INFO - PROCESS | 3226 | _x_x_mozilla_page_load 07:37:55 INFO - PROCESS | 3226 | _x_x_mozilla_page_load_details 07:37:55 INFO - PROCESS | 3226 | |i|pagename|runs| 07:37:55 INFO - PROCESS | 3226 | |0;dojo.html;3337.833108344583;3342.398368293707;3369.275605744766;3388.3392738415987;3409.607895092065;5801.709914504274;5848.970755146229;5868.73590688419;5879.11813228016;5934.67359295238;5764.25064741583;5770.105633626795;5868.2664666916635;5869.471747542721;5900.085486749561;5197.818076367328;5254.632175747699;5267.367915850094;5284.973575132119;5328.094223981932;5312.840614781563;5366.114591092465;5367.490088441592;5367.892642147155;5371.221172929922;5259.421463638997;5293.57651387888;5298.337707786527;5304.443033481487;5332.120200166987;5358.839234822962;5372.086745253317;5375.086235340002;5376.4085950545405;5396.001739678172;5124.897502049976;5226.608004399685;5257.342832145981;5277.076511610453;5300.734963251861;5795.1886735857015;5795.536357091422;5816.331121920991;5832.825015249549;5850.64896106235;6264.843378915563;6272.5922815016875;6277.937220627827;6281.717322720443;6287.528435367366;6221.5333849961435;6281.403266689657;6299.748010079591;6299.779507717208;6323.462505686976;5852.473277405054;5893.764249430061;5907.084401917709;5927.555433342516;5937.406259374071;6420.903686444707;6429.807105786834;6430.228372595225;6431.839204019843;6453.741850326029;6268.028455589391;6279.403456671609;6285.968570157212;6297.874042519124;6302;6179.412955769285;6217.502599792097;6262.091996660459;6262.906056409066;6290.716917738621;6222;6245.718942647592;6283.434490895841;6308.085327627468;6309.621422714652;6235;6301.40136687014;6315.210598675166;6316.4315211630255;6321.1782468278825;5095.133827249377;5095.439501654815;5099.107656160157;5106.106431374495;5113.258577506315;3761.8307176177136;4488.035072459437;4502.369668246448;4507.459104907432;4522.638188944877;5904.1734157218025;5910.4385083417865;5913.408659134052;5919.704014799243;5942.10868369748;6258.311585725566;6268.905966410507;6271.184745983084;6272.874542509125;6277.309495955533;5721.427857214246;5801.158831969341;5816.243888294494;5844.12338149281;5898.675572843535;5519.806806761824;5568.805091821848;5577.191307260425;5583.302087239095;5611.270534830446;6461.063145843732;6462;6466.64433456155;6468.611883287018;6476.870462590722;6215.96892015537;6218.626882387071;6249.625022498664;6260.217472815898;6269.12232287471;6357.7139028742895;6375.904361434489;6380.425761681471;6396.776112836028;6403.263624683221;6157.538184636081;6254.562180647311;6270.341614130451;6283.088952102011;6287.025511045796;6227.688615569294;6235.033569796688;6263.029230469285;6266.655333956676;6289.937100628935;6253.874922501615;6272.341404152498;6280.214973128359;6293.874122517433;6297.2128483939505;6212.378762123752;6223.284322302993;6243.750249990131;6265.248170219603;6268.811935641938;5929.644221346733;5974.492168165581;6052.152698622284;6157.84605384869;6171;6211.751529938751;6246.531510136667;6272.5922815016875;6273.9372606273355;6275.497960163268;6093.9390606093375;6144.078388241799;6153.630782153085;6155.876882462415;6162.938370616236 07:37:55 INFO - PROCESS | 3226 | |1;ext.html;468.93083769006665;492.2517772985065;533.3652952985947;535.7623888816834;543.9881819815141;32063.19842003948;44073.77963110188;44613.33080003803;44677.77661111698;47029;46644.76677616616;46819.29771053437;46852.29721554171;46879.765601171945;47115.29327060088;9778.90221097787;9963.302568820185;9969.10278074973;9973.451460169725;9980.850287245697;8908.376413651047;9083.772905677379;9088.363814532986;9278;9281.675141370053;12094.576689815858;12319.445624946899;12351.38243087842;12588.181768185039;12753.553625623104;12236.51053957845;12344.38278086092;12352.011839052899;12355.814662780065;12358.62924112278;8900.777480563038;11776.470058847286;12117.151799374047;12210.633680989584;12216.633500994983;42116.94707632303;44561.77719111383;44608;44639;44641.55358446406;3566.8038257895805;3627.165751877083;3644.5444319460066;3646.252518233782;3692.316921369555;32767;33742.493862592084;34120;34157.316853662785;34775.1306217344;3249.3988612106837;3255.3489302139665;3285.1130194847497;3286.556314897482;3309.3712194683176;549064;550347;553573.3213339316;554036;554413.9999999959;56277.43722562804;66055.66972165108;66804.99792503116;66822.66588667025;72039.91940120903;72179;72462;72488.63755681188;72675;72679.6366018177;17700.026498542447;17882.195301211475;17912.462626121236;17938.461846144637;17960.820391796176;13485.325733713471;13572.660683482794;13599.980001499927;13785.172889626454;13829.51596694111;16332.75500867488;16814.411495597597;17175.570610734583;19056.904715476612;19138.058424782317;4901.281175349961;6626.105475760807;6834;6881.346272104142;6935.757248496278;907.7186072317603;1004.8141093897652;1045.1012129568564;1099.142668718401;1140.7276339786642;31817.363652726817;32008.839955800537;32306.83846580752;32411.632398722977;32732.345353092805;1008.4703537618336;1114.3202646385698;1116.5087361560886;1207.251504067484;1210.2315029956042;17052.317907283817;17736.024518651597;17777.20002599886;18289.81116227441;18426.35507757222;709503;714282;715924;716280;717303;51533;55314;63736.68131659312;64557;64776;1434.300354080418;1455.2505459688268;1463.9853601464274;1464.1507925403027;1488.7692407676611;30767.69232307738;33834.49248261327;33888;34204.726613060244;34208.486872697424;56826.14760778509;57532.42467575438;57563.424365757484;57628.42371576231;57666.42333576611;638308.8084559548;924499;958672;958683;958940.205298969;46639.53360466352;47256.7637161812;47425;49081.50918490769;50848.728781779275;53646;54093;54349.72825135849;54449.72775136099;56021.87956240771;34858.65141348656;41166.382504263056;43024.78487607542;43513;45230.773846130556;42832.78583607062;43588.910277243325;43828.780856095516;45226.54773452223;45855.54144458513;41226.175476490906;42589;43726.12547748964;43733.78133109441;43813.56186438095;30933.381332373676;32437.18907027343;35122;37074.44388334231;37269.068273293386;27633.17100486908;27809.860950695118;28021.25162778972;28178.154655360373;28180.85909570439 07:37:55 INFO - PROCESS | 3226 | |2;jquery.html;354.3409258778673;373.23300617231587;380.2281368821292;389.15553249448703;405.5559162716824;15146.77279840802;16284;20409.387718368434;21056.157753689873;21222.57554848902;16706.99758014517;20956.056977436016;21199.68200476994;21837.454063648416;22701.65947510789;5854.121881717734;5889;6116.388361163892;6144.2012538369945;6154.907676384858;3863.2466668999555;5205.37535495741;6140.723667434954;6186.56694031418;6193.225846769154;5877.265341832271;6118.449339559439;6161.075838624198;6222.315545290015;6242.18851549298;6220.533459990474;6229.595076320024;6232.532560057991;6650.268470468244;6738.52830301879;5445.210444485567;5580.525655319303;5937.079752638348;5946.583739138262;5951.851203719898;13428.127171733806;13447;13651.658708532266;13801.654958626013;13864.96012799034;2136.8076873081495;2277.9635265953953;2304.3893368257427;2391.7847393734646;2812.395335002984;10171.89828101717;10196.74508137295;10267.897321026769;10652.680419587346;10706.732331691692;1510.3505492638162;1822.5352535103464;1848.7319338695813;1861.283405888729;1905.3426567834076;121499.97260642379;127232;127354.72645273614;128492;132302.3384883079;11488.827667584992;11795;11806;11823.812938177141;11824.940875295568;15022.624434389229;15038.32327545263;15064.246787660573;15066.322015509328;15081.396744130112;8794.076621954731;8978.955105224431;8986.86519702205;8997.235235005099;9064.501452420049;8929.464232146092;8966.462012279284;9010.053944335881;9126.634934602542;9161.175494205554;4642.721436713808;4649.907001860011;4658.767061646904;4712.31671407644;4771.618270538349;1811.7825860896512;1936.9709454358197;1987.522994481314;1988.393539970323;1988.532694816717;781.4529829119639;781.8749000159947;784.4783219159309;790.3400660448475;1056.2870062707796;20716.792832071787;22153.66769498459;22355.552888942457;22488.100475981104;22490.875456227124;895.2927187521915;944.4286206844897;964.3394274921701;970.7039352997404;993.9950300248453;9619.038096190325;10863.782724345469;11718.531258749725;11766.94116529429;11778.411079445994;513502;619579;626064.8696756578;630832;637860;135588;136307.63692363148;138570;144047;147157;999.7900440907491;1001.1740314240781;1007.1539906478447;1019.1337363241186;1021.2851004296873;17592.680548959135;17882.28470861203;18615.162317695464;19722.013899305264;19790.307339243056;39736;49614.75192624014;57422.13866791918;57525.260858044094;59215.40784592099;80513;86881.56559217164;89130;89923.10076899147;90972.54513727389;5541.224228607917;6752.020956961312;7224.241454647186;8143.267105960492;8547.188017138362;10680.198985075995;11853.703657408634;12633.81049284243;12793.360331983178;12943.611691649265;9009.61245270017;11252.493637786156;11280.153988451064;11354.091672666333;11531.135164862837;10839.187060970302;10953.123750099814;10960.397178155277;11045.834312485158;11309.66071017838;9705.320627556004;11127.276727012719;11196.04833589154;11325.377104259345;11384.48769805344;6530.47756179514;6916;8099.392545558989;8248.793780155544;8392.20274073962;10228.948855255676;10912.890871091506;11218.831717524406;11287.927646873533;11292.15308851823 07:37:55 INFO - PROCESS | 3226 | |3;mootools.html;1561.078963411587;1601.4715144002478;1804.5127815489818;1920.1743250402321;1960.500072481518;8569.4429862059;9216.49309287989;9216.585253663585;9220.170184683377;9231.815363692722;8035.6383962721675;8313.293370063551;8722.345824063188;8903;9059.320550958688;2400.5558971590267;2431.3070774829175;2434.1115492845074;2439.316991242446;2441.9389515262174;2395.7125144982538;2418.0207016158483;2443.1937460637946;2446.3150317911013;2446.449548851512;2412.384841865322;2426.211481268595;2433.4159801647565;2433.610622300432;2436.062116085302;2394.293683363412;2404.158544509425;2412.2522018174245;2416.0818888822223;2423.4426082001237;2396.053558844264;2408.241403957765;2415.2995631266913;2415.867127307997;2416.347586151729;9208.401453905475;9215.400998935047;9350.719478415658;9353.625854965792;9357.251419886392;5256.894862102775;5445.074337362658;5456.236126942231;5458.918116228259;5462.672239665632;3572.1962558424407;3698.778073315583;3712.1833196696684;3780.7164462665137;3790.9620903790883;1107.2249425402251;1107.5846557540922;1115.0800589513683;1132.3828513460185;1137.624583887315;12025.939870300592;12062.577809776616;12146.635600931897;12154.817677734842;12158.331291778948;4468.151051300242;4546.318052292117;4718.150732868048;4734.337192793012;4744.624059022914;9467.479288639192;9478.668246611336;9481.956984731674;9485.525723713787;9496.240300775922;5383.676979381171;5385.084535628953;5386.757595908115;5387.111126664056;5395.001924643953;4499.775011249424;4661.207594708908;4880.902381952341;5021.196608542616;5057.140286151363;3776.4335349697767;3845.442410850467;3901.51231096113;3913.021744563859;3959.5248570171766;1161.0827446317483;1172.0974849366112;1178.3754610056683;1181.3620644851856;1182.8639706433871;833.7540425574471;854.3250831842898;919.8942121655962;957.7653474898694;964.4020707161465;6076.906156891802;6077.969610151921;6250.281217659936;6300.684965751786;6374.713137908712;949.3971328206638;981.5092453773113;1007.2797949466167;1038.8441733739849;1040.0483557544912;3906.43356713275;4022.5374081980362;4086.734362266443;4258.42511260982;4265.296226122717;10928.508217130247;11185.552577896793;11306.830397544043;11308.886911130783;11313.208075434804;11245.381504017194;11259.493322800494;11284.435778211056;11285.661430157108;11319.094472442184;8009.919900801034;8365.07984121746;8395.580220988926;8554.443961142506;8560.61477233526;5484.725763711878;6070.5734152475015;6176.139912554653;6196.597221180609;6235.282942461675;10376.221783366127;10552.525136369035;10561;10571;10571.312864663772;10217.284790064625;10380.117689996436;10395.304574850788;10491.737706557396;10703.411312377893;7219.205887352282;7409.518381305198;7423.8144046399275;7706;7931.920680793118;8216.424850260664;8324.292435143081;8389;8645.740627781177;8690.087540808125;7682.692692292246;7779.883301750591;7819.569923654026;7830.295273425191;7945.046594408708;7558.13081495613;7665.616719163908;7665.616719164131;7679.19368466303;7704.460687751804;7062.8940565890525;7168.319009694071;7175.8564828704175;7175.892361614476;7186.856262874818;4872.561469467765;4912.410510738734;4983.426905905722;5018.422881368543;5098.668586541863;4992.550670439678;5000.424866167145;5116.974415128049;5178.378594568676;5188.922166167435 07:37:55 INFO - PROCESS | 3226 | |4;prototype.html;251.74196448640146;298.74009611637877;305.56457048705596;309.96162854437637;315.5424634280294;4918.163912134936;5250.816221432251;5283.973580132104;5498.285222921019;5590.3320727192795;5201.971231219827;5367.812126575561;5384.496209262692;5397.298351214336;5506.531944784688;3621.519259307376;3645.362061639216;3927.3127202739406;4001.4197941298494;4163.458750362449;3454.758166928316;4134.514052253308;4146;4166.9791651041705;4195.517515485728;3813.6758375537975;4068.206699693561;4081.3877918312196;4083.2445997490413;4099.938500922489;3372.6964573188407;4076.8776936691947;4078.8164532596106;4104.671626269891;4166.145940082276;2766.39139389334;3299.6902168482156;3545.804980726059;3574.8927532173816;3606.6753992140834;4442.089371678798;5139.331886854722;5351.652142610717;5414.593905457067;5419.756110975016;3748.4189950557707;3946;3974.5230572331216;4669.875206780906;4700.341952126705;5179.989901969117;5271.65734227274;5752.36723960364;5807.680577568232;5866.6480011199465;3422.503736958152;3629.89110326688;3827.851644506637;3860.4595356650093;3899.7660140391667;5884.470037790163;5970.761169553212;6023.307319658208;6023.759049638009;6031.60794548353;3448.896353167013;3525.8413371398346;3932.9213415731524;3954.861579844749;3968.920621587552;3403.4724617684296;3508.9473158052483;3881.049142959993;3934.468846705658;4012.9197416052098;4456.264716321771;4582.358469814229;4680.391549098664;4693.249080147229;4711.505291944365;5752.223449834302;5769.365369809401;5784.82645520635;5789.623674461147;5797.420257974253;1209.818527220924;1284.3257289922865;1361.4418088583832;1363.604554679132;1375.5048182654236;735.4043224987777;757.443279189795;839.8194388206565;859.1494420523761;882.4087861133056;3803.7337386382687;4151.8962025949595;4346.826126954949;4391.8462853799965;4446.666500012512;4258.74447533149;4440.866773996785;4501.369808226851;4813.205821039559;4942.8764280893265;4088.509378874554;4453.265211240174;4456;4500.977495112569;4543.931841022388;2229.5652347792347;2594.637815147063;2929.956050659242;3107.8912238071557;3146.433641944472;4865.659403841697;6022.969885150546;6142;6176;6225.817094751983;4910.901781964412;5779;6012.1963410977405;6073.995581458108;6121.84695382619;5615.427680249455;5646.407127251661;6113.663748493876;6157.322694503601;6207.503399728102;4878.756062196805;5905.675187864536;5937.287525496968;5977.625146216482;6010.2186715726675;4734.55021772931;5891.646501209942;5902.2327097477055;5924.585279030426;5939.91090133657;4647.535246475461;5127.333616574553;5954.434328738763;6176.505879529716;6220.315765265907;3972.4248176641254;4006.09862780872;4013.6387725104883;4014.374178457729;4028.2950483665827;5601.467860553241;5753.475329037892;5933.317668468181;5943.227380440688;5950.375210602824;5281.257185128975;5613.01772189857;5678.3186017678145;5785;5791.305043394819;5756.222909907109;5756.424357564378;5764.685305949012;5782;5823.321272214828;4253.829846806093;4751.572358487752;5636.718164091697;5652;5658.971705141448;5766.509846663081;5768.134779783066;5779.306483221872;5779.797707080232;5789.713063339873;4769.117713223065;5676.801311954062;5680.659160450221;5699.686517241592;5727.8854422912145 07:37:55 INFO - PROCESS | 3226 | |5;yui.html;718.9682805174573;723.3453724379436;732.4762794601862;752.9924700752991;809.9109097999219;15076.170810605427;16527.752083718722;16541.338346466142;16543;16576.751348729762;15002.324895379707;15177.165255910948;15189.544313670607;15203.847961520381;15225.84774152258;697.3758486154901;876.9517676527788;906.2297047509597;908.8727578139066;912.4069354919322;908.7228395339419;912.3886995712855;912.4160537256162;912.803747194353;914.2046419614911;744.5160645580389;972.5574863437155;973.3235401396032;976.3848775271568;978.9265805064613;935.7379933618564;953.1897886796237;961.7499450143014;964.84080126779;965.9082387173207;718.7699936020475;719.3741444942881;720.5136532840364;720.8378114924152;721.3904250907974;15501.92249038759;15511.3019914103;15549.689006219925;15566;15575.065496070272;14479;14620.342084606218;14628.122312661275;14665.633359165999;14691.045082069739;12773.808392874114;12792.872071279262;13332.600021999355;13366.599002029954;13415.396307166104;4565.132624801277;4624.560666736688;4627.699199552018;4655.953440465586;4766.904661906742;113883;123876.14185787221;126810;128067.35966320202;128784;11398.316101033799;11614.593489227836;11647.12646551512;11726.70683232926;11825.52697892092;23484.412889677555;23560.175393861475;23778.40553986164;23822.285331440085;23830.761692382854;7716.459847810712;8212.17878212186;8225.71210007647;8255.504669719836;8349.707760228484;4375.409319741858;4433.46798384196;4571.634269258451;4630.050839577879;4696.2955556666775;3025.636923569142;3550.751447398709;3579.2483578448296;3587.461880717913;3594.155373487228;634.5653227539151;646.599108552691;649.4057936987698;659.8815008560443;661.6691654172913;566.8979583674895;635.1520719838975;640.6732566391174;640.8333833203308;642.2646070249538;8240.21717936795;8857.955710221408;9297.488638124969;9392.67125650599;9565.856512152322;528.6907159311769;534.1720333483086;643.4177069751883;649.2630863969334;658.3219284137347;4258.084511829971;4572.108438854458;4585.220512512881;4597.034622729264;4715;142654;165281;169937;170699;173754;75357;77274.61362693262;89779;112954.30568541479;145274;71382.64308678423;90480;110135;111880;113771;9529.952350238205;10057.446840423554;10184.898151018395;10206.795864082826;10245.180385568987;46463.07073858436;51250.718732032;51609;59427.70286148541;60428.69785651044;92519.07480925105;92907;96364.0363596355;97513;97744;13377.327834020747;13494;13532.323383830571;13644.317784110954;13816.585502434944;14508.927455362656;14551.854481455473;15522.922385388001;15562.066276023475;15760.448384306954;13710.794338085136;13734.587962361145;14104.576862694135;14646.707065858836;15158;13920.51278205258;14109.929450353093;14206.21865797391;14288.42846286137;14325.7851132231;13791;13823.723525529635;13867.375968081662;13880.791788122984;13897.374618142007;11405.429728513374;11527.538898443969;11581.478833452684;11675.532978680758;12100.576479823163;11418.714532136431;11596.5361385547;11722.824157637813;11729.765404691687;11864.288142711464 07:37:55 INFO - PROCESS | 3226 | __end_tp_report 07:37:55 INFO - PROCESS | 3226 | __start_cc_report 07:37:55 INFO - PROCESS | 3226 | _x_x_mozilla_cycle_collect,1351 07:37:55 INFO - PROCESS | 3226 | __end_cc_report 07:37:55 INFO - PROCESS | 3226 | __startTimestamp1474123075716__endTimestamp 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | ------- Summary: start ------- 07:37:55 INFO - PROCESS | 3226 | Number of tests: 6 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | [#0] dojo.html Cycles:165 Average:5849.52 Median:6157.54 stddev:654.15 (10.6%) stddev-sans-first:625.78 07:37:55 INFO - PROCESS | 3226 | Values: 3337.8 3342.4 3369.3 3388.3 3409.6 5801.7 5849.0 5868.7 5879.1 5934.7 5764.3 5770.1 5868.3 5869.5 5900.1 5197.8 5254.6 5267.4 5285.0 5328.1 5312.8 5366.1 5367.5 5367.9 5371.2 5259.4 5293.6 5298.3 5304.4 5332.1 5358.8 5372.1 5375.1 5376.4 5396.0 5124.9 5226.6 5257.3 5277.1 5300.7 5795.2 5795.5 5816.3 5832.8 5850.6 6264.8 6272.6 6277.9 6281.7 6287.5 6221.5 6281.4 6299.7 6299.8 6323.5 5852.5 5893.8 5907.1 5927.6 5937.4 6420.9 6429.8 6430.2 6431.8 6453.7 6268.0 6279.4 6286.0 6297.9 6302.0 6179.4 6217.5 6262.1 6262.9 6290.7 6222.0 6245.7 6283.4 6308.1 6309.6 6235.0 6301.4 6315.2 6316.4 6321.2 5095.1 5095.4 5099.1 5106.1 5113.3 3761.8 4488.0 4502.4 4507.5 4522.6 5904.2 5910.4 5913.4 5919.7 5942.1 6258.3 6268.9 6271.2 6272.9 6277.3 5721.4 5801.2 5816.2 5844.1 5898.7 5519.8 5568.8 5577.2 5583.3 5611.3 6461.1 6462.0 6466.6 6468.6 6476.9 6216.0 6218.6 6249.6 6260.2 6269.1 6357.7 6375.9 6380.4 6396.8 6403.3 6157.5 6254.6 6270.3 6283.1 6287.0 6227.7 6235.0 6263.0 6266.7 6289.9 6253.9 6272.3 6280.2 6293.9 6297.2 6212.4 6223.3 6243.8 6265.2 6268.8 5929.6 5974.5 6052.2 6157.8 6171.0 6211.8 6246.5 6272.6 6273.9 6275.5 6093.9 6144.1 6153.6 6155.9 6162.9 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | [#1] ext.html Cycles:180 Average:85480.20 Median:43268.89 stddev:197551.74 (456.6%) stddev-sans-first:198002.81 07:37:55 INFO - PROCESS | 3226 | Values: 468.9 492.3 533.4 535.8 544.0 32063.2 44073.8 44613.3 44677.8 47029.0 46644.8 46819.3 46852.3 46879.8 47115.3 9778.9 9963.3 9969.1 9973.5 9980.9 8908.4 9083.8 9088.4 9278.0 9281.7 12094.6 12319.4 12351.4 12588.2 12753.6 12236.5 12344.4 12352.0 12355.8 12358.6 8900.8 11776.5 12117.2 12210.6 12216.6 42116.9 44561.8 44608.0 44639.0 44641.6 3566.8 3627.2 3644.5 3646.3 3692.3 32767.0 33742.5 34120.0 34157.3 34775.1 3249.4 3255.3 3285.1 3286.6 3309.4 549064.0 550347.0 553573.3 554036.0 554414.0 56277.4 66055.7 66805.0 66822.7 72039.9 72179.0 72462.0 72488.6 72675.0 72679.6 17700.0 17882.2 17912.5 17938.5 17960.8 13485.3 13572.7 13600.0 13785.2 13829.5 16332.8 16814.4 17175.6 19056.9 19138.1 4901.3 6626.1 6834.0 6881.3 6935.8 907.7 1004.8 1045.1 1099.1 1140.7 31817.4 32008.8 32306.8 32411.6 32732.3 1008.5 1114.3 1116.5 1207.3 1210.2 17052.3 17736.0 17777.2 18289.8 18426.4 709503.0 714282.0 715924.0 716280.0 717303.0 51533.0 55314.0 63736.7 64557.0 64776.0 1434.3 1455.3 1464.0 1464.2 1488.8 30767.7 33834.5 33888.0 34204.7 34208.5 56826.1 57532.4 57563.4 57628.4 57666.4 638308.8 924499.0 958672.0 958683.0 958940.2 46639.5 47256.8 47425.0 49081.5 50848.7 53646.0 54093.0 54349.7 54449.7 56021.9 34858.7 41166.4 43024.8 43513.0 45230.8 42832.8 43588.9 43828.8 45226.5 45855.5 41226.2 42589.0 43726.1 43733.8 43813.6 30933.4 32437.2 35122.0 37074.4 37269.1 27633.2 27809.9 28021.3 28178.2 28180.9 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | [#2] jquery.html Cycles:180 Average:35790.91 Median:22489.49 stddev:102037.54 (453.7%) stddev-sans-first:102289.09 07:37:55 INFO - PROCESS | 3226 | Values: 354.3 373.2 380.2 389.2 405.6 15146.8 16284.0 20409.4 21056.2 21222.6 16707.0 20956.1 21199.7 21837.5 22701.7 5854.1 5889.0 6116.4 6144.2 6154.9 3863.2 5205.4 6140.7 6186.6 6193.2 5877.3 6118.4 6161.1 6222.3 6242.2 6220.5 6229.6 6232.5 6650.3 6738.5 5445.2 5580.5 5937.1 5946.6 5951.9 13428.1 13447.0 13651.7 13801.7 13865.0 2136.8 2278.0 2304.4 2391.8 2812.4 10171.9 10196.7 10267.9 10652.7 10706.7 1510.4 1822.5 1848.7 1861.3 1905.3 121500.0 127232.0 127354.7 128492.0 132302.3 11488.8 11795.0 11806.0 11823.8 11824.9 15022.6 15038.3 15064.2 15066.3 15081.4 8794.1 8979.0 8986.9 8997.2 9064.5 8929.5 8966.5 9010.1 9126.6 9161.2 4642.7 4649.9 4658.8 4712.3 4771.6 1811.8 1937.0 1987.5 1988.4 1988.5 781.5 781.9 784.5 790.3 1056.3 20716.8 22153.7 22355.6 22488.1 22490.9 895.3 944.4 964.3 970.7 994.0 9619.0 10863.8 11718.5 11766.9 11778.4 513502.0 619579.0 626064.9 630832.0 637860.0 135588.0 136307.6 138570.0 144047.0 147157.0 999.8 1001.2 1007.2 1019.1 1021.3 17592.7 17882.3 18615.2 19722.0 19790.3 39736.0 49614.8 57422.1 57525.3 59215.4 80513.0 86881.6 89130.0 89923.1 90972.5 5541.2 6752.0 7224.2 8143.3 8547.2 10680.2 11853.7 12633.8 12793.4 12943.6 9009.6 11252.5 11280.2 11354.1 11531.1 10839.2 10953.1 10960.4 11045.8 11309.7 9705.3 11127.3 11196.0 11325.4 11384.5 6530.5 6916.0 8099.4 8248.8 8392.2 10228.9 10912.9 11218.8 11287.9 11292.2 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | [#3] mootools.html Cycles:180 Average:5872.16 Median:4876.73 stddev:3310.72 (67.9%) stddev-sans-first:3304.16 07:37:55 INFO - PROCESS | 3226 | Values: 1561.1 1601.5 1804.5 1920.2 1960.5 8569.4 9216.5 9216.6 9220.2 9231.8 8035.6 8313.3 8722.3 8903.0 9059.3 2400.6 2431.3 2434.1 2439.3 2441.9 2395.7 2418.0 2443.2 2446.3 2446.4 2412.4 2426.2 2433.4 2433.6 2436.1 2394.3 2404.2 2412.3 2416.1 2423.4 2396.1 2408.2 2415.3 2415.9 2416.3 9208.4 9215.4 9350.7 9353.6 9357.3 5256.9 5445.1 5456.2 5458.9 5462.7 3572.2 3698.8 3712.2 3780.7 3791.0 1107.2 1107.6 1115.1 1132.4 1137.6 12025.9 12062.6 12146.6 12154.8 12158.3 4468.2 4546.3 4718.2 4734.3 4744.6 9467.5 9478.7 9482.0 9485.5 9496.2 5383.7 5385.1 5386.8 5387.1 5395.0 4499.8 4661.2 4880.9 5021.2 5057.1 3776.4 3845.4 3901.5 3913.0 3959.5 1161.1 1172.1 1178.4 1181.4 1182.9 833.8 854.3 919.9 957.8 964.4 6076.9 6078.0 6250.3 6300.7 6374.7 949.4 981.5 1007.3 1038.8 1040.0 3906.4 4022.5 4086.7 4258.4 4265.3 10928.5 11185.6 11306.8 11308.9 11313.2 11245.4 11259.5 11284.4 11285.7 11319.1 8009.9 8365.1 8395.6 8554.4 8560.6 5484.7 6070.6 6176.1 6196.6 6235.3 10376.2 10552.5 10561.0 10571.0 10571.3 10217.3 10380.1 10395.3 10491.7 10703.4 7219.2 7409.5 7423.8 7706.0 7931.9 8216.4 8324.3 8389.0 8645.7 8690.1 7682.7 7779.9 7819.6 7830.3 7945.0 7558.1 7665.6 7665.6 7679.2 7704.5 7062.9 7168.3 7175.9 7175.9 7186.9 4872.6 4912.4 4983.4 5018.4 5098.7 4992.6 5000.4 5117.0 5178.4 5188.9 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | [#4] prototype.html Cycles:180 Average:4537.12 Median:4872.21 stddev:1460.52 (30.0%) stddev-sans-first:1428.76 07:37:55 INFO - PROCESS | 3226 | Values: 251.7 298.7 305.6 310.0 315.5 4918.2 5250.8 5284.0 5498.3 5590.3 5202.0 5367.8 5384.5 5397.3 5506.5 3621.5 3645.4 3927.3 4001.4 4163.5 3454.8 4134.5 4146.0 4167.0 4195.5 3813.7 4068.2 4081.4 4083.2 4099.9 3372.7 4076.9 4078.8 4104.7 4166.1 2766.4 3299.7 3545.8 3574.9 3606.7 4442.1 5139.3 5351.7 5414.6 5419.8 3748.4 3946.0 3974.5 4669.9 4700.3 5180.0 5271.7 5752.4 5807.7 5866.6 3422.5 3629.9 3827.9 3860.5 3899.8 5884.5 5970.8 6023.3 6023.8 6031.6 3448.9 3525.8 3932.9 3954.9 3968.9 3403.5 3508.9 3881.0 3934.5 4012.9 4456.3 4582.4 4680.4 4693.2 4711.5 5752.2 5769.4 5784.8 5789.6 5797.4 1209.8 1284.3 1361.4 1363.6 1375.5 735.4 757.4 839.8 859.1 882.4 3803.7 4151.9 4346.8 4391.8 4446.7 4258.7 4440.9 4501.4 4813.2 4942.9 4088.5 4453.3 4456.0 4501.0 4543.9 2229.6 2594.6 2930.0 3107.9 3146.4 4865.7 6023.0 6142.0 6176.0 6225.8 4910.9 5779.0 6012.2 6074.0 6121.8 5615.4 5646.4 6113.7 6157.3 6207.5 4878.8 5905.7 5937.3 5977.6 6010.2 4734.6 5891.6 5902.2 5924.6 5939.9 4647.5 5127.3 5954.4 6176.5 6220.3 3972.4 4006.1 4013.6 4014.4 4028.3 5601.5 5753.5 5933.3 5943.2 5950.4 5281.3 5613.0 5678.3 5785.0 5791.3 5756.2 5756.4 5764.7 5782.0 5823.3 4253.8 4751.6 5636.7 5652.0 5659.0 5766.5 5768.1 5779.3 5779.8 5789.7 4769.1 5676.8 5680.7 5699.7 5727.9 07:37:55 INFO - PROCESS | 3226 | 07:37:55 INFO - PROCESS | 3226 | [#5] yui.html Cycles:180 Average:24820.61 Median:3565.00 stddev:39842.74 (1117.6%) stddev-sans-first:39913.41 07:37:55 INFO - PROCESS | 3226 | Values: 719.0 723.3 732.5 753.0 809.9 15076.2 16527.8 16541.3 16543.0 16576.8 15002.3 15177.2 15189.5 15203.8 15225.8 697.4 877.0 906.2 908.9 912.4 908.7 912.4 912.4 912.8 914.2 744.5 972.6 973.3 976.4 978.9 935.7 953.2 961.7 964.8 965.9 718.8 719.4 720.5 720.8 721.4 15501.9 15511.3 15549.7 15566.0 15575.1 14479.0 14620.3 14628.1 14665.6 14691.0 12773.8 12792.9 13332.6 13366.6 13415.4 4565.1 4624.6 4627.7 4656.0 4766.9 113883.0 123876.1 126810.0 128067.4 128784.0 11398.3 11614.6 11647.1 11726.7 11825.5 23484.4 23560.2 23778.4 23822.3 23830.8 7716.5 8212.2 8225.7 8255.5 8349.7 4375.4 4433.5 4571.6 4630.1 4696.3 3025.6 3550.8 3579.2 3587.5 3594.2 634.6 646.6 649.4 659.9 661.7 566.9 635.2 640.7 640.8 642.3 8240.2 8858.0 9297.5 9392.7 9565.9 528.7 534.2 643.4 649.3 658.3 4258.1 4572.1 4585.2 4597.0 4715.0 142654.0 165281.0 169937.0 170699.0 173754.0 75357.0 77274.6 89779.0 112954.3 145274.0 71382.6 90480.0 110135.0 111880.0 113771.0 9530.0 10057.4 10184.9 10206.8 10245.2 46463.1 51250.7 51609.0 59427.7 60428.7 92519.1 92907.0 96364.0 97513.0 97744.0 13377.3 13494.0 13532.3 13644.3 13816.6 14508.9 14551.9 15522.9 15562.1 15760.4 13710.8 13734.6 14104.6 14646.7 15158.0 13920.5 14109.9 14206.2 14288.4 14325.8 13791.0 13823.7 13867.4 13880.8 13897.4 11405.4 11527.5 11581.5 11675.5 12100.6 11418.7 11596.5 11722.8 11729.8 11864.3 07:37:55 INFO - PROCESS | 3226 | -------- Summary: end -------- 07:37:55 INFO - PROCESS | 3226 | 07:37:56 INFO - TEST-INFO | 3226: exit 0 07:37:56 INFO - TEST-OK | dromaeo_css | took 1115852ms 07:37:56 INFO - TEST-START | kraken 07:37:56 INFO - operating with platform_type : mac_ 07:37:56 INFO - Initialising browser for kraken test... 07:37:56 INFO - TEST-INFO | started process 3343 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmplxFFO9/profile http://localhost:49276/getInfo.html) 07:37:58 INFO - PROCESS | 3343 | __metrics Screen width/height:1600/1200 07:37:58 INFO - PROCESS | 3343 | colorDepth:24 07:37:58 INFO - PROCESS | 3343 | Browser inner width/height: 1024/689 07:37:58 INFO - PROCESS | 3343 | __metrics 07:37:58 INFO - PROCESS | 3343 | 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" 07:37:58 INFO - PROCESS | 3343 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:37:59 INFO - TEST-INFO | 3343: exit 0 07:37:59 INFO - Browser initialized. 07:37:59 INFO - Running cycle 1/1 for kraken test... 07:37:59 INFO - TEST-INFO | started process 3345 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmplxFFO9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:11 INFO - PROCESS | 3345 | RSS: Main: 215826432 07:38:11 INFO - PROCESS | 3345 | 07:38:13 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:14 INFO - PROCESS | 3345 | RSS: Main: 279429120 07:38:14 INFO - PROCESS | 3345 | 07:38:17 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 07:38:17 INFO - PROCESS | 3345 | RSS: Main: 282087424 07:38:17 INFO - PROCESS | 3345 | 07:38:21 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 07:38:21 INFO - PROCESS | 3345 | RSS: Main: 379113472 07:38:21 INFO - PROCESS | 3345 | 07:38:23 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 07:38:24 INFO - PROCESS | 3345 | RSS: Main: 285216768 07:38:24 INFO - PROCESS | 3345 | 07:38:26 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:27 INFO - PROCESS | 3345 | RSS: Main: 285704192 07:38:27 INFO - PROCESS | 3345 | 07:38:29 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:30 INFO - PROCESS | 3345 | RSS: Main: 321892352 07:38:30 INFO - PROCESS | 3345 | 07:38:33 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:33 INFO - PROCESS | 3345 | RSS: Main: 325259264 07:38:33 INFO - PROCESS | 3345 | 07:38:36 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 07:38:37 INFO - PROCESS | 3345 | RSS: Main: 322781184 07:38:37 INFO - PROCESS | 3345 | 07:38:38 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:39 INFO - PROCESS | 3345 | RSS: Main: 298577920 07:38:39 INFO - PROCESS | 3345 | 07:38:41 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:41 INFO - PROCESS | 3345 | RSS: Main: 303558656 07:38:41 INFO - PROCESS | 3345 | 07:38:44 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:44 INFO - PROCESS | 3345 | RSS: Main: 305844224 07:38:44 INFO - PROCESS | 3345 | 07:38:47 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:48 INFO - PROCESS | 3345 | RSS: Main: 316100608 07:38:48 INFO - PROCESS | 3345 | 07:38:51 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:51 INFO - PROCESS | 3345 | RSS: Main: 317456384 07:38:51 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 07:38:53 INFO - PROCESS | 3345 | RSS: Main: 361025536 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | __start_tp_report 07:38:53 INFO - PROCESS | 3345 | _x_x_mozilla_page_load 07:38:53 INFO - PROCESS | 3345 | _x_x_mozilla_page_load_details 07:38:53 INFO - PROCESS | 3345 | |i|pagename|runs| 07:38:53 INFO - PROCESS | 3345 | |0;ai-astar;112;113;126;113;112;113;112;114;114;114 07:38:53 INFO - PROCESS | 3345 | |1;audio-beat-detection;148;112;158;192;106;132;151;96;180;135 07:38:53 INFO - PROCESS | 3345 | |2;audio-dft;184;183;183;184;183;184;184;185;185;184 07:38:53 INFO - PROCESS | 3345 | |3;audio-fft;73;80;76;160;58;57;58;60;139;58 07:38:53 INFO - PROCESS | 3345 | |4;audio-oscillator;83;85;134;70;73;76;121;69;69;118 07:38:53 INFO - PROCESS | 3345 | |5;imaging-gaussian-blur;87;84;86;86;87;83;86;84;86;84 07:38:53 INFO - PROCESS | 3345 | |6;imaging-darkroom;93;93;92;93;93;92;93;94;92;93 07:38:53 INFO - PROCESS | 3345 | |7;imaging-desaturate;87;89;85;84;87;83;86;83;85;84 07:38:53 INFO - PROCESS | 3345 | |8;json-parse-financial;58;57;57;58;56;57;58;57;59;56 07:38:53 INFO - PROCESS | 3345 | |9;json-stringify-tinderbox;67;67;74;78;71;71;68;69;70;70 07:38:53 INFO - PROCESS | 3345 | |10;stanford-crypto-aes;83;80;82;81;79;78;82;80;87;88 07:38:53 INFO - PROCESS | 3345 | |11;stanford-crypto-ccm;152;134;149;144;138;138;137;160;180;136 07:38:53 INFO - PROCESS | 3345 | |12;stanford-crypto-pbkdf2;158;154;163;169;220;165;164;160;165;160 07:38:53 INFO - PROCESS | 3345 | |13;stanford-crypto-sha256-iterative;66;65;67;66;65;67;64;66;67;66 07:38:53 INFO - PROCESS | 3345 | __end_tp_report 07:38:53 INFO - PROCESS | 3345 | __start_cc_report 07:38:53 INFO - PROCESS | 3345 | _x_x_mozilla_cycle_collect,2125 07:38:53 INFO - PROCESS | 3345 | __end_cc_report 07:38:53 INFO - PROCESS | 3345 | __startTimestamp1474123133720__endTimestamp 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | ------- Summary: start ------- 07:38:53 INFO - PROCESS | 3345 | Number of tests: 14 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#0] ai-astar Cycles:10 Average:114.30 Median:113.50 stddev:4.19 (3.7%) stddev-sans-first:4.36 07:38:53 INFO - PROCESS | 3345 | Values: 112.0 113.0 126.0 113.0 112.0 113.0 112.0 114.0 114.0 114.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:154.50 stddev:31.21 (20.2%) stddev-sans-first:33.00 07:38:53 INFO - PROCESS | 3345 | Values: 148.0 112.0 158.0 192.0 106.0 132.0 151.0 96.0 180.0 135.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:0.74 (0.4%) stddev-sans-first:0.78 07:38:53 INFO - PROCESS | 3345 | Values: 184.0 183.0 183.0 184.0 183.0 184.0 184.0 185.0 185.0 184.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#3] audio-fft Cycles:10 Average:81.90 Median:59.00 stddev:36.95 (62.6%) stddev-sans-first:39.06 07:38:53 INFO - PROCESS | 3345 | Values: 73.0 80.0 76.0 160.0 58.0 57.0 58.0 60.0 139.0 58.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:71.50 stddev:24.77 (34.6%) stddev-sans-first:26.15 07:38:53 INFO - PROCESS | 3345 | Values: 83.0 85.0 134.0 70.0 73.0 76.0 121.0 69.0 69.0 118.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.42 (1.6%) stddev-sans-first:1.36 07:38:53 INFO - PROCESS | 3345 | Values: 87.0 84.0 86.0 86.0 87.0 83.0 86.0 84.0 86.0 84.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 07:38:53 INFO - PROCESS | 3345 | Values: 93.0 93.0 92.0 93.0 93.0 92.0 93.0 94.0 92.0 93.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.95 (2.3%) stddev-sans-first:1.96 07:38:53 INFO - PROCESS | 3345 | Values: 87.0 89.0 85.0 84.0 87.0 83.0 86.0 83.0 85.0 84.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.50 stddev:0.95 (1.6%) stddev-sans-first:0.97 07:38:53 INFO - PROCESS | 3345 | Values: 58.0 57.0 57.0 58.0 56.0 57.0 58.0 57.0 59.0 56.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:3.37 (4.8%) stddev-sans-first:3.33 07:38:53 INFO - PROCESS | 3345 | Values: 67.0 67.0 74.0 78.0 71.0 71.0 68.0 69.0 70.0 70.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:3.27 (4.0%) stddev-sans-first:3.44 07:38:53 INFO - PROCESS | 3345 | Values: 83.0 80.0 82.0 81.0 79.0 78.0 82.0 80.0 87.0 88.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:146.50 stddev:14.33 (9.8%) stddev-sans-first:15.07 07:38:53 INFO - PROCESS | 3345 | Values: 152.0 134.0 149.0 144.0 138.0 138.0 137.0 160.0 180.0 136.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:164.50 stddev:18.82 (11.4%) stddev-sans-first:19.62 07:38:53 INFO - PROCESS | 3345 | Values: 158.0 154.0 163.0 169.0 220.0 165.0 164.0 160.0 165.0 160.0 07:38:53 INFO - PROCESS | 3345 | 07:38:53 INFO - PROCESS | 3345 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 07:38:53 INFO - PROCESS | 3345 | Values: 66.0 65.0 67.0 66.0 65.0 67.0 64.0 66.0 67.0 66.0 07:38:53 INFO - PROCESS | 3345 | -------- Summary: end -------- 07:38:53 INFO - PROCESS | 3345 | 07:38:54 INFO - TEST-INFO | 3345: exit 0 07:38:54 INFO - TEST-OK | kraken | took 58233ms 07:38:54 INFO - SUITE-END | took 1174s 07:38:54 INFO - Completed test suite (00:19:34) 07:38:54 INFO - javascript benchmark 07:38:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.833108344583, 3342.398368293707, 3369.275605744766, 3388.3392738415987, 3409.607895092065, 5801.709914504274, 5848.970755146229, 5868.73590688419, 5879.11813228016, 5934.67359295238, 5764.25064741583, 5770.105633626795, 5868.2664666916635, 5869.471747542721, 5900.085486749561, 5197.818076367328, 5254.632175747699, 5267.367915850094, 5284.973575132119, 5328.094223981932, 5312.840614781563, 5366.114591092465, 5367.490088441592, 5367.892642147155, 5371.221172929922, 5259.421463638997, 5293.57651387888, 5298.337707786527, 5304.443033481487, 5332.120200166987, 5358.839234822962, 5372.086745253317, 5375.086235340002, 5376.4085950545405, 5396.001739678172, 5124.897502049976, 5226.608004399685, 5257.342832145981, 5277.076511610453, 5300.734963251861, 5795.1886735857015, 5795.536357091422, 5816.331121920991, 5832.825015249549, 5850.64896106235, 6264.843378915563, 6272.5922815016875, 6277.937220627827, 6281.717322720443, 6287.528435367366, 6221.5333849961435, 6281.403266689657, 6299.748010079591, 6299.779507717208, 6323.462505686976, 5852.473277405054, 5893.764249430061, 5907.084401917709, 5927.555433342516, 5937.406259374071, 6420.903686444707, 6429.807105786834, 6430.228372595225, 6431.839204019843, 6453.741850326029, 6268.028455589391, 6279.403456671609, 6285.968570157212, 6297.874042519124, 6302.0, 6179.412955769285, 6217.502599792097, 6262.091996660459, 6262.906056409066, 6290.716917738621, 6222.0, 6245.718942647592, 6283.434490895841, 6308.085327627468, 6309.621422714652, 6235.0, 6301.40136687014, 6315.210598675166, 6316.4315211630255, 6321.1782468278825, 5095.133827249377, 5095.439501654815, 5099.107656160157, 5106.106431374495, 5113.258577506315, 3761.8307176177136, 4488.035072459437, 4502.369668246448, 4507.459104907432, 4522.638188944877, 5904.1734157218025, 5910.4385083417865, 5913.408659134052, 5919.704014799243, 5942.10868369748, 6258.311585725566, 6268.905966410507, 6271.184745983084, 6272.874542509125, 6277.309495955533, 5721.427857214246, 5801.158831969341, 5816.243888294494, 5844.12338149281, 5898.675572843535, 5519.806806761824, 5568.805091821848, 5577.191307260425, 5583.302087239095, 5611.270534830446, 6461.063145843732, 6462.0, 6466.64433456155, 6468.611883287018, 6476.870462590722, 6215.96892015537, 6218.626882387071, 6249.625022498664, 6260.217472815898, 6269.12232287471, 6357.7139028742895, 6375.904361434489, 6380.425761681471, 6396.776112836028, 6403.263624683221, 6157.538184636081, 6254.562180647311, 6270.341614130451, 6283.088952102011, 6287.025511045796, 6227.688615569294, 6235.033569796688, 6263.029230469285, 6266.655333956676, 6289.937100628935, 6253.874922501615, 6272.341404152498, 6280.214973128359, 6293.874122517433, 6297.2128483939505, 6212.378762123752, 6223.284322302993, 6243.750249990131, 6265.248170219603, 6268.811935641938, 5929.644221346733, 5974.492168165581, 6052.152698622284, 6157.84605384869, 6171.0, 6211.751529938751, 6246.531510136667, 6272.5922815016875, 6273.9372606273355, 6275.497960163268, 6093.9390606093375, 6144.078388241799, 6153.630782153085, 6155.876882462415, 6162.938370616236], "value": 5805.7860348289, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.93083769006665, 492.2517772985065, 533.3652952985947, 535.7623888816834, 543.9881819815141, 32063.19842003948, 44073.77963110188, 44613.33080003803, 44677.77661111698, 47029.0, 46644.76677616616, 46819.29771053437, 46852.29721554171, 46879.765601171945, 47115.29327060088, 9778.90221097787, 9963.302568820185, 9969.10278074973, 9973.451460169725, 9980.850287245697, 8908.376413651047, 9083.772905677379, 9088.363814532986, 9278.0, 9281.675141370053, 12094.576689815858, 12319.445624946899, 12351.38243087842, 12588.181768185039, 12753.553625623104, 12236.51053957845, 12344.38278086092, 12352.011839052899, 12355.814662780065, 12358.62924112278, 8900.777480563038, 11776.470058847286, 12117.151799374047, 12210.633680989584, 12216.633500994983, 42116.94707632303, 44561.77719111383, 44608.0, 44639.0, 44641.55358446406, 3566.8038257895805, 3627.165751877083, 3644.5444319460066, 3646.252518233782, 3692.316921369555, 32767.0, 33742.493862592084, 34120.0, 34157.316853662785, 34775.1306217344, 3249.3988612106837, 3255.3489302139665, 3285.1130194847497, 3286.556314897482, 3309.3712194683176, 549064.0, 550347.0, 553573.3213339316, 554036.0, 554413.9999999959, 56277.43722562804, 66055.66972165108, 66804.99792503116, 66822.66588667025, 72039.91940120903, 72179.0, 72462.0, 72488.63755681188, 72675.0, 72679.6366018177, 17700.026498542447, 17882.195301211475, 17912.462626121236, 17938.461846144637, 17960.820391796176, 13485.325733713471, 13572.660683482794, 13599.980001499927, 13785.172889626454, 13829.51596694111, 16332.75500867488, 16814.411495597597, 17175.570610734583, 19056.904715476612, 19138.058424782317, 4901.281175349961, 6626.105475760807, 6834.0, 6881.346272104142, 6935.757248496278, 907.7186072317603, 1004.8141093897652, 1045.1012129568564, 1099.142668718401, 1140.7276339786642, 31817.363652726817, 32008.839955800537, 32306.83846580752, 32411.632398722977, 32732.345353092805, 1008.4703537618336, 1114.3202646385698, 1116.5087361560886, 1207.251504067484, 1210.2315029956042, 17052.317907283817, 17736.024518651597, 17777.20002599886, 18289.81116227441, 18426.35507757222, 709503.0, 714282.0, 715924.0, 716280.0, 717303.0, 51533.0, 55314.0, 63736.68131659312, 64557.0, 64776.0, 1434.300354080418, 1455.2505459688268, 1463.9853601464274, 1464.1507925403027, 1488.7692407676611, 30767.69232307738, 33834.49248261327, 33888.0, 34204.726613060244, 34208.486872697424, 56826.14760778509, 57532.42467575438, 57563.424365757484, 57628.42371576231, 57666.42333576611, 638308.8084559548, 924499.0, 958672.0, 958683.0, 958940.205298969, 46639.53360466352, 47256.7637161812, 47425.0, 49081.50918490769, 50848.728781779275, 53646.0, 54093.0, 54349.72825135849, 54449.72775136099, 56021.87956240771, 34858.65141348656, 41166.382504263056, 43024.78487607542, 43513.0, 45230.773846130556, 42832.78583607062, 43588.910277243325, 43828.780856095516, 45226.54773452223, 45855.54144458513, 41226.175476490906, 42589.0, 43726.12547748964, 43733.78133109441, 43813.56186438095, 30933.381332373676, 32437.18907027343, 35122.0, 37074.44388334231, 37269.068273293386, 27633.17100486908, 27809.860950695118, 28021.25162778972, 28178.154655360373, 28180.85909570439], "value": 22231.75603742537, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.3409258778673, 373.23300617231587, 380.2281368821292, 389.15553249448703, 405.5559162716824, 15146.77279840802, 16284.0, 20409.387718368434, 21056.157753689873, 21222.57554848902, 16706.99758014517, 20956.056977436016, 21199.68200476994, 21837.454063648416, 22701.65947510789, 5854.121881717734, 5889.0, 6116.388361163892, 6144.2012538369945, 6154.907676384858, 3863.2466668999555, 5205.37535495741, 6140.723667434954, 6186.56694031418, 6193.225846769154, 5877.265341832271, 6118.449339559439, 6161.075838624198, 6222.315545290015, 6242.18851549298, 6220.533459990474, 6229.595076320024, 6232.532560057991, 6650.268470468244, 6738.52830301879, 5445.210444485567, 5580.525655319303, 5937.079752638348, 5946.583739138262, 5951.851203719898, 13428.127171733806, 13447.0, 13651.658708532266, 13801.654958626013, 13864.96012799034, 2136.8076873081495, 2277.9635265953953, 2304.3893368257427, 2391.7847393734646, 2812.395335002984, 10171.89828101717, 10196.74508137295, 10267.897321026769, 10652.680419587346, 10706.732331691692, 1510.3505492638162, 1822.5352535103464, 1848.7319338695813, 1861.283405888729, 1905.3426567834076, 121499.97260642379, 127232.0, 127354.72645273614, 128492.0, 132302.3384883079, 11488.827667584992, 11795.0, 11806.0, 11823.812938177141, 11824.940875295568, 15022.624434389229, 15038.32327545263, 15064.246787660573, 15066.322015509328, 15081.396744130112, 8794.076621954731, 8978.955105224431, 8986.86519702205, 8997.235235005099, 9064.501452420049, 8929.464232146092, 8966.462012279284, 9010.053944335881, 9126.634934602542, 9161.175494205554, 4642.721436713808, 4649.907001860011, 4658.767061646904, 4712.31671407644, 4771.618270538349, 1811.7825860896512, 1936.9709454358197, 1987.522994481314, 1988.393539970323, 1988.532694816717, 781.4529829119639, 781.8749000159947, 784.4783219159309, 790.3400660448475, 1056.2870062707796, 20716.792832071787, 22153.66769498459, 22355.552888942457, 22488.100475981104, 22490.875456227124, 895.2927187521915, 944.4286206844897, 964.3394274921701, 970.7039352997404, 993.9950300248453, 9619.038096190325, 10863.782724345469, 11718.531258749725, 11766.94116529429, 11778.411079445994, 513502.0, 619579.0, 626064.8696756578, 630832.0, 637860.0, 135588.0, 136307.63692363148, 138570.0, 144047.0, 147157.0, 999.7900440907491, 1001.1740314240781, 1007.1539906478447, 1019.1337363241186, 1021.2851004296873, 17592.680548959135, 17882.28470861203, 18615.162317695464, 19722.013899305264, 19790.307339243056, 39736.0, 49614.75192624014, 57422.13866791918, 57525.260858044094, 59215.40784592099, 80513.0, 86881.56559217164, 89130.0, 89923.10076899147, 90972.54513727389, 5541.224228607917, 6752.020956961312, 7224.241454647186, 8143.267105960492, 8547.188017138362, 10680.198985075995, 11853.703657408634, 12633.81049284243, 12793.360331983178, 12943.611691649265, 9009.61245270017, 11252.493637786156, 11280.153988451064, 11354.091672666333, 11531.135164862837, 10839.187060970302, 10953.123750099814, 10960.397178155277, 11045.834312485158, 11309.66071017838, 9705.320627556004, 11127.276727012719, 11196.04833589154, 11325.377104259345, 11384.48769805344, 6530.47756179514, 6916.0, 8099.392545558989, 8248.793780155544, 8392.20274073962, 10228.948855255676, 10912.890871091506, 11218.831717524406, 11287.927646873533, 11292.15308851823], "value": 9504.037746913895, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.078963411587, 1601.4715144002478, 1804.5127815489818, 1920.1743250402321, 1960.500072481518, 8569.4429862059, 9216.49309287989, 9216.585253663585, 9220.170184683377, 9231.815363692722, 8035.6383962721675, 8313.293370063551, 8722.345824063188, 8903.0, 9059.320550958688, 2400.5558971590267, 2431.3070774829175, 2434.1115492845074, 2439.316991242446, 2441.9389515262174, 2395.7125144982538, 2418.0207016158483, 2443.1937460637946, 2446.3150317911013, 2446.449548851512, 2412.384841865322, 2426.211481268595, 2433.4159801647565, 2433.610622300432, 2436.062116085302, 2394.293683363412, 2404.158544509425, 2412.2522018174245, 2416.0818888822223, 2423.4426082001237, 2396.053558844264, 2408.241403957765, 2415.2995631266913, 2415.867127307997, 2416.347586151729, 9208.401453905475, 9215.400998935047, 9350.719478415658, 9353.625854965792, 9357.251419886392, 5256.894862102775, 5445.074337362658, 5456.236126942231, 5458.918116228259, 5462.672239665632, 3572.1962558424407, 3698.778073315583, 3712.1833196696684, 3780.7164462665137, 3790.9620903790883, 1107.2249425402251, 1107.5846557540922, 1115.0800589513683, 1132.3828513460185, 1137.624583887315, 12025.939870300592, 12062.577809776616, 12146.635600931897, 12154.817677734842, 12158.331291778948, 4468.151051300242, 4546.318052292117, 4718.150732868048, 4734.337192793012, 4744.624059022914, 9467.479288639192, 9478.668246611336, 9481.956984731674, 9485.525723713787, 9496.240300775922, 5383.676979381171, 5385.084535628953, 5386.757595908115, 5387.111126664056, 5395.001924643953, 4499.775011249424, 4661.207594708908, 4880.902381952341, 5021.196608542616, 5057.140286151363, 3776.4335349697767, 3845.442410850467, 3901.51231096113, 3913.021744563859, 3959.5248570171766, 1161.0827446317483, 1172.0974849366112, 1178.3754610056683, 1181.3620644851856, 1182.8639706433871, 833.7540425574471, 854.3250831842898, 919.8942121655962, 957.7653474898694, 964.4020707161465, 6076.906156891802, 6077.969610151921, 6250.281217659936, 6300.684965751786, 6374.713137908712, 949.3971328206638, 981.5092453773113, 1007.2797949466167, 1038.8441733739849, 1040.0483557544912, 3906.43356713275, 4022.5374081980362, 4086.734362266443, 4258.42511260982, 4265.296226122717, 10928.508217130247, 11185.552577896793, 11306.830397544043, 11308.886911130783, 11313.208075434804, 11245.381504017194, 11259.493322800494, 11284.435778211056, 11285.661430157108, 11319.094472442184, 8009.919900801034, 8365.07984121746, 8395.580220988926, 8554.443961142506, 8560.61477233526, 5484.725763711878, 6070.5734152475015, 6176.139912554653, 6196.597221180609, 6235.282942461675, 10376.221783366127, 10552.525136369035, 10561.0, 10571.0, 10571.312864663772, 10217.284790064625, 10380.117689996436, 10395.304574850788, 10491.737706557396, 10703.411312377893, 7219.205887352282, 7409.518381305198, 7423.8144046399275, 7706.0, 7931.920680793118, 8216.424850260664, 8324.292435143081, 8389.0, 8645.740627781177, 8690.087540808125, 7682.692692292246, 7779.883301750591, 7819.569923654026, 7830.295273425191, 7945.046594408708, 7558.13081495613, 7665.616719163908, 7665.616719164131, 7679.19368466303, 7704.460687751804, 7062.8940565890525, 7168.319009694071, 7175.8564828704175, 7175.892361614476, 7186.856262874818, 4872.561469467765, 4912.410510738734, 4983.426905905722, 5018.422881368543, 5098.668586541863, 4992.550670439678, 5000.424866167145, 5116.974415128049, 5178.378594568676, 5188.922166167435], "value": 4712.139545005396, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.74196448640146, 298.74009611637877, 305.56457048705596, 309.96162854437637, 315.5424634280294, 4918.163912134936, 5250.816221432251, 5283.973580132104, 5498.285222921019, 5590.3320727192795, 5201.971231219827, 5367.812126575561, 5384.496209262692, 5397.298351214336, 5506.531944784688, 3621.519259307376, 3645.362061639216, 3927.3127202739406, 4001.4197941298494, 4163.458750362449, 3454.758166928316, 4134.514052253308, 4146.0, 4166.9791651041705, 4195.517515485728, 3813.6758375537975, 4068.206699693561, 4081.3877918312196, 4083.2445997490413, 4099.938500922489, 3372.6964573188407, 4076.8776936691947, 4078.8164532596106, 4104.671626269891, 4166.145940082276, 2766.39139389334, 3299.6902168482156, 3545.804980726059, 3574.8927532173816, 3606.6753992140834, 4442.089371678798, 5139.331886854722, 5351.652142610717, 5414.593905457067, 5419.756110975016, 3748.4189950557707, 3946.0, 3974.5230572331216, 4669.875206780906, 4700.341952126705, 5179.989901969117, 5271.65734227274, 5752.36723960364, 5807.680577568232, 5866.6480011199465, 3422.503736958152, 3629.89110326688, 3827.851644506637, 3860.4595356650093, 3899.7660140391667, 5884.470037790163, 5970.761169553212, 6023.307319658208, 6023.759049638009, 6031.60794548353, 3448.896353167013, 3525.8413371398346, 3932.9213415731524, 3954.861579844749, 3968.920621587552, 3403.4724617684296, 3508.9473158052483, 3881.049142959993, 3934.468846705658, 4012.9197416052098, 4456.264716321771, 4582.358469814229, 4680.391549098664, 4693.249080147229, 4711.505291944365, 5752.223449834302, 5769.365369809401, 5784.82645520635, 5789.623674461147, 5797.420257974253, 1209.818527220924, 1284.3257289922865, 1361.4418088583832, 1363.604554679132, 1375.5048182654236, 735.4043224987777, 757.443279189795, 839.8194388206565, 859.1494420523761, 882.4087861133056, 3803.7337386382687, 4151.8962025949595, 4346.826126954949, 4391.8462853799965, 4446.666500012512, 4258.74447533149, 4440.866773996785, 4501.369808226851, 4813.205821039559, 4942.8764280893265, 4088.509378874554, 4453.265211240174, 4456.0, 4500.977495112569, 4543.931841022388, 2229.5652347792347, 2594.637815147063, 2929.956050659242, 3107.8912238071557, 3146.433641944472, 4865.659403841697, 6022.969885150546, 6142.0, 6176.0, 6225.817094751983, 4910.901781964412, 5779.0, 6012.1963410977405, 6073.995581458108, 6121.84695382619, 5615.427680249455, 5646.407127251661, 6113.663748493876, 6157.322694503601, 6207.503399728102, 4878.756062196805, 5905.675187864536, 5937.287525496968, 5977.625146216482, 6010.2186715726675, 4734.55021772931, 5891.646501209942, 5902.2327097477055, 5924.585279030426, 5939.91090133657, 4647.535246475461, 5127.333616574553, 5954.434328738763, 6176.505879529716, 6220.315765265907, 3972.4248176641254, 4006.09862780872, 4013.6387725104883, 4014.374178457729, 4028.2950483665827, 5601.467860553241, 5753.475329037892, 5933.317668468181, 5943.227380440688, 5950.375210602824, 5281.257185128975, 5613.01772189857, 5678.3186017678145, 5785.0, 5791.305043394819, 5756.222909907109, 5756.424357564378, 5764.685305949012, 5782.0, 5823.321272214828, 4253.829846806093, 4751.572358487752, 5636.718164091697, 5652.0, 5658.971705141448, 5766.509846663081, 5768.134779783066, 5779.306483221872, 5779.797707080232, 5789.713063339873, 4769.117713223065, 5676.801311954062, 5680.659160450221, 5699.686517241592, 5727.8854422912145], "value": 4067.871664142776, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.9682805174573, 723.3453724379436, 732.4762794601862, 752.9924700752991, 809.9109097999219, 15076.170810605427, 16527.752083718722, 16541.338346466142, 16543.0, 16576.751348729762, 15002.324895379707, 15177.165255910948, 15189.544313670607, 15203.847961520381, 15225.84774152258, 697.3758486154901, 876.9517676527788, 906.2297047509597, 908.8727578139066, 912.4069354919322, 908.7228395339419, 912.3886995712855, 912.4160537256162, 912.803747194353, 914.2046419614911, 744.5160645580389, 972.5574863437155, 973.3235401396032, 976.3848775271568, 978.9265805064613, 935.7379933618564, 953.1897886796237, 961.7499450143014, 964.84080126779, 965.9082387173207, 718.7699936020475, 719.3741444942881, 720.5136532840364, 720.8378114924152, 721.3904250907974, 15501.92249038759, 15511.3019914103, 15549.689006219925, 15566.0, 15575.065496070272, 14479.0, 14620.342084606218, 14628.122312661275, 14665.633359165999, 14691.045082069739, 12773.808392874114, 12792.872071279262, 13332.600021999355, 13366.599002029954, 13415.396307166104, 4565.132624801277, 4624.560666736688, 4627.699199552018, 4655.953440465586, 4766.904661906742, 113883.0, 123876.14185787221, 126810.0, 128067.35966320202, 128784.0, 11398.316101033799, 11614.593489227836, 11647.12646551512, 11726.70683232926, 11825.52697892092, 23484.412889677555, 23560.175393861475, 23778.40553986164, 23822.285331440085, 23830.761692382854, 7716.459847810712, 8212.17878212186, 8225.71210007647, 8255.504669719836, 8349.707760228484, 4375.409319741858, 4433.46798384196, 4571.634269258451, 4630.050839577879, 4696.2955556666775, 3025.636923569142, 3550.751447398709, 3579.2483578448296, 3587.461880717913, 3594.155373487228, 634.5653227539151, 646.599108552691, 649.4057936987698, 659.8815008560443, 661.6691654172913, 566.8979583674895, 635.1520719838975, 640.6732566391174, 640.8333833203308, 642.2646070249538, 8240.21717936795, 8857.955710221408, 9297.488638124969, 9392.67125650599, 9565.856512152322, 528.6907159311769, 534.1720333483086, 643.4177069751883, 649.2630863969334, 658.3219284137347, 4258.084511829971, 4572.108438854458, 4585.220512512881, 4597.034622729264, 4715.0, 142654.0, 165281.0, 169937.0, 170699.0, 173754.0, 75357.0, 77274.61362693262, 89779.0, 112954.30568541479, 145274.0, 71382.64308678423, 90480.0, 110135.0, 111880.0, 113771.0, 9529.952350238205, 10057.446840423554, 10184.898151018395, 10206.795864082826, 10245.180385568987, 46463.07073858436, 51250.718732032, 51609.0, 59427.70286148541, 60428.69785651044, 92519.07480925105, 92907.0, 96364.0363596355, 97513.0, 97744.0, 13377.327834020747, 13494.0, 13532.323383830571, 13644.317784110954, 13816.585502434944, 14508.927455362656, 14551.854481455473, 15522.922385388001, 15562.066276023475, 15760.448384306954, 13710.794338085136, 13734.587962361145, 14104.576862694135, 14646.707065858836, 15158.0, 13920.51278205258, 14109.929450353093, 14206.21865797391, 14288.42846286137, 14325.7851132231, 13791.0, 13823.723525529635, 13867.375968081662, 13880.791788122984, 13897.374618142007, 11405.429728513374, 11527.538898443969, 11581.478833452684, 11675.532978680758, 12100.576479823163, 11418.714532136431, 11596.5361385547, 11722.824157637813, 11729.765404691687, 11864.288142711464], "value": 8036.609777248712, "unit": "score"}], "name": "dromaeo_css", "value": 7575.448869042696, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 126.0, 113.0, 112.0, 113.0, 112.0, 114.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 158.0, 192.0, 106.0, 132.0, 151.0, 96.0, 180.0, 135.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 184.0, 183.0, 184.0, 184.0, 185.0, 185.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 80.0, 76.0, 160.0, 58.0, 57.0, 58.0, 60.0, 139.0, 58.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 134.0, 70.0, 73.0, 76.0, 121.0, 69.0, 69.0, 118.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 86.0, 87.0, 83.0, 86.0, 84.0, 86.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 94.0, 92.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 85.0, 84.0, 87.0, 83.0, 86.0, 83.0, 85.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 58.0, 56.0, 57.0, 58.0, 57.0, 59.0, 56.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 74.0, 78.0, 71.0, 71.0, 68.0, 69.0, 70.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 82.0, 81.0, 79.0, 78.0, 82.0, 80.0, 87.0, 88.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 134.0, 149.0, 144.0, 138.0, 138.0, 137.0, 160.0, 180.0, 136.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 163.0, 169.0, 220.0, 165.0, 164.0, 160.0, 165.0, 160.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 66.0, 65.0, 67.0, 64.0, 66.0, 67.0, 66.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1464.6, "alertThreshold": 2.0}]} 07:38:54 INFO - Return code: 0 07:38:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:55 INFO - # TBPL SUCCESS # 07:38:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:55 INFO - 2016-09-17 07:38:55.035 screenresolution[3354:24344] starting screenresolution argv=screenresolution get 07:38:55 INFO - 2016-09-17 07:38:55.037 screenresolution[3354:24344] Display 0: 1600x1200x32@60 07:38:55 INFO - 2016-09-17 07:38:55.043 screenresolution[3355:24347] starting screenresolution argv=screenresolution list 07:38:55 INFO - Available Modes on Display 0 07:38:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:55 INFO - Intel Iris: 07:38:55 INFO - Chipset Model: Intel Iris 07:38:55 INFO - Type: GPU 07:38:55 INFO - Bus: Built-In 07:38:55 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:55 INFO - Vendor: Intel (0x8086) 07:38:55 INFO - Device ID: 0x0a2e 07:38:55 INFO - Revision ID: 0x0009 07:38:55 INFO - Displays: 07:38:55 INFO - Display: 07:38:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:55 INFO - Display Serial Number: 1600x1200 60 07:38:55 INFO - Main Display: Yes 07:38:55 INFO - Mirror: Off 07:38:55 INFO - Online: Yes 07:38:55 INFO - Rotation: Supported 07:38:55 INFO - Return code: 0 07:38:55 INFO - Running post-action listener: _resource_record_post_action 07:38:55 INFO - [mozharness: 2016-09-17 14:38:55.279051Z] Finished run-tests step (success) 07:38:55 INFO - Running post-run listener: _resource_record_post_run 07:38:55 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 31841280; Write bytes: 667683328; Read time: 191; Write time: 1902 07:38:55 INFO - TinderboxPrint: CPU usage
53.3% 07:38:55 INFO - TinderboxPrint: I/O read bytes / time
31,841,280 / 191 07:38:55 INFO - TinderboxPrint: I/O write bytes / time
667,683,328 / 1,902 07:38:55 INFO - TinderboxPrint: CPU idle
2,255.2 (46.7%) 07:38:55 INFO - TinderboxPrint: CPU system
964.4 (20.0%) 07:38:55 INFO - TinderboxPrint: CPU user
1,609.9 (33.3%) 07:38:55 INFO - TinderboxPrint: Swap in / out
392,204,288 / 0 07:38:55 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 359809536; Write bytes: 357393920; Read time: 27517; Write time: 781 07:38:55 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 29871104; Write bytes: 299467776; Read time: 173; Write time: 1086 07:38:55 INFO - Running post-run listener: _upload_blobber_files 07:38:55 INFO - Blob upload gear active. 07:38:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:55 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 07:38:55 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'] 07:38:55 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 07:38:56 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:38:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:38:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:56 INFO - (blobuploader) - INFO - Done attempting. 07:38:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:38:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:58 INFO - (blobuploader) - INFO - Done attempting. 07:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:00 INFO - (blobuploader) - INFO - Done attempting. 07:39:00 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:00 INFO - Return code: 0 07:39:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39bfa01acceb0679e20d62a959543c256cf902257ad849512d68bb728b8510a28d75424d2e3c7f2025c27d8eaff3958df029555cf20660c4d14a23ec7fc27739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/650757562d9ac1283fe39089ff51d638267486c7d10074f2114985c1310b3287a89412c4b1eb73ccd5f46b25e53b0701029d85b38442dbd2e21b9df12bd925d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae4b371a1e94870f746b57e0b06d95588ac4177c22959e684f403d9bd0f0b7df3b5c435f5f8fc97a0432550efb6d913e2cd98cb3e0f2c638e97e41612a893122"} 07:39:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:00 INFO - Contents: 07:39:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39bfa01acceb0679e20d62a959543c256cf902257ad849512d68bb728b8510a28d75424d2e3c7f2025c27d8eaff3958df029555cf20660c4d14a23ec7fc27739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/650757562d9ac1283fe39089ff51d638267486c7d10074f2114985c1310b3287a89412c4b1eb73ccd5f46b25e53b0701029d85b38442dbd2e21b9df12bd925d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae4b371a1e94870f746b57e0b06d95588ac4177c22959e684f403d9bd0f0b7df3b5c435f5f8fc97a0432550efb6d913e2cd98cb3e0f2c638e97e41612a893122"} 07:39:00 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:00 INFO - Copying logs to upload dir... 07:39:00 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.143159 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-09-17 07:39:00.244708) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:00.248700) ========= 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.1RaeyGyaF8/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.eletWKFMYn/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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/39bfa01acceb0679e20d62a959543c256cf902257ad849512d68bb728b8510a28d75424d2e3c7f2025c27d8eaff3958df029555cf20660c4d14a23ec7fc27739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/650757562d9ac1283fe39089ff51d638267486c7d10074f2114985c1310b3287a89412c4b1eb73ccd5f46b25e53b0701029d85b38442dbd2e21b9df12bd925d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae4b371a1e94870f746b57e0b06d95588ac4177c22959e684f403d9bd0f0b7df3b5c435f5f8fc97a0432550efb6d913e2cd98cb3e0f2c638e97e41612a893122"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010543 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39bfa01acceb0679e20d62a959543c256cf902257ad849512d68bb728b8510a28d75424d2e3c7f2025c27d8eaff3958df029555cf20660c4d14a23ec7fc27739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/650757562d9ac1283fe39089ff51d638267486c7d10074f2114985c1310b3287a89412c4b1eb73ccd5f46b25e53b0701029d85b38442dbd2e21b9df12bd925d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae4b371a1e94870f746b57e0b06d95588ac4177c22959e684f403d9bd0f0b7df3b5c435f5f8fc97a0432550efb6d913e2cd98cb3e0f2c638e97e41612a893122"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:00.280805) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:00.281144) ========= 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.1RaeyGyaF8/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.eletWKFMYn/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005106 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:00.330175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:00.330505) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:00.330873) ========= ========= Total master_lag: 0.18 =========