builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1469625785.35 results: success (0) buildid: 20160704150257 builduid: 288e0872bed146679b0a067544a79c15 revision: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.348237) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.348767) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.349057) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.478580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.478852) ========= 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.tQr8uQxcjF/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.BqSehrtWes/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-07-27 06:23:05-- 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% 16.0M=0.001s 2016-07-27 06:23:05 (16.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173394 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.677076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.677416) ========= 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.tQr8uQxcjF/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.BqSehrtWes/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.027204 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.726336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 06:23:05.726697) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tQr8uQxcjF/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.BqSehrtWes/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-07-27 06:23:05,805 truncating revision to first 12 chars 2016-07-27 06:23:05,805 Setting DEBUG logging. 2016-07-27 06:23:05,805 attempt 1/10 2016-07-27 06:23:05,805 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c9a70b64f2fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 06:23:06,590 unpacking tar archive at: mozilla-central-c9a70b64f2fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.051535 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 06:23:06.800168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.800592) ========= script_repo_revision: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.800995) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.801275) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.821431) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-27 06:23:06.821723) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tQr8uQxcjF/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.BqSehrtWes/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:07 INFO - MultiFileLogger online at 20160727 06:23:07 in /builds/slave/test 06:23:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:23:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:23:07 INFO - {'append_to_log': False, 06:23:07 INFO - 'base_work_dir': '/builds/slave/test', 06:23:07 INFO - 'blob_upload_branch': 'Firefox', 06:23:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:23:07 INFO - 'branch': 'Firefox', 06:23:07 INFO - 'buildbot_json_path': 'buildprops.json', 06:23:07 INFO - 'config_files': ('talos/mac_config.py',), 06:23:07 INFO - 'default_actions': ('clobber', 06:23:07 INFO - 'read-buildbot-config', 06:23:07 INFO - 'download-and-extract', 06:23:07 INFO - 'populate-webroot', 06:23:07 INFO - 'create-virtualenv', 06:23:07 INFO - 'install', 06:23:07 INFO - 'run-tests'), 06:23:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:23:07 INFO - 'download_minidump_stackwalk': True, 06:23:07 INFO - 'download_symbols': 'ondemand', 06:23:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:23:07 INFO - 'tooltool.py': '/tools/tooltool.py', 06:23:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:23:07 INFO - '/tools/misc-python/virtualenv.py')}, 06:23:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:23:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:23:07 INFO - 'installer_path': 'installer.exe', 06:23:07 INFO - 'log_level': 'info', 06:23:07 INFO - 'log_name': 'talos', 06:23:07 INFO - 'log_to_console': True, 06:23:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:23:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:23:07 INFO - 'opt_config_files': (), 06:23:07 INFO - 'pip_index': False, 06:23:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:23:07 INFO - 'cmd': ('bash', 06:23:07 INFO - '-c', 06:23:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:23:07 INFO - 'enabled': True, 06:23:07 INFO - 'halt_on_failure': False, 06:23:07 INFO - 'name': 'check_screen_resolution'},), 06:23:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:23:07 INFO - 'cmd': ('bash', 06:23:07 INFO - '-c', 06:23:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:23:07 INFO - 'enabled': True, 06:23:07 INFO - 'halt_on_failure': False, 06:23:07 INFO - 'name': 'check_screen_resolution'},), 06:23:07 INFO - 'run_cmd_checks_enabled': True, 06:23:07 INFO - 'sps_profile': False, 06:23:07 INFO - 'sps_profile_interval': 0, 06:23:07 INFO - 'suite': 'dromaeojs-e10s', 06:23:07 INFO - 'system_bits': '32', 06:23:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:23:07 INFO - 'title': 't-yosemite-r7-0082', 06:23:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:23:07 INFO - 'use_talos_json': True, 06:23:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:23:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:23:07 INFO - 'work_dir': 'build'} 06:23:07 INFO - ##### 06:23:07 INFO - ##### Running clobber step. 06:23:07 INFO - ##### 06:23:07 INFO - Running pre-action listener: _resource_record_pre_action 06:23:07 INFO - Running main action method: clobber 06:23:07 INFO - rmtree: /builds/slave/test/build 06:23:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:23:09 INFO - Running post-action listener: _resource_record_post_action 06:23:09 INFO - ##### 06:23:09 INFO - ##### Finished clobber step (success) 06:23:09 INFO - ##### 06:23:09 INFO - ##### 06:23:09 INFO - ##### Running read-buildbot-config step. 06:23:09 INFO - ##### 06:23:09 INFO - Running pre-action listener: _resource_record_pre_action 06:23:09 INFO - Running main action method: read_buildbot_config 06:23:09 INFO - Using buildbot properties: 06:23:09 INFO - { 06:23:09 INFO - "project": "", 06:23:09 INFO - "product": "firefox", 06:23:09 INFO - "script_repo_revision": "production", 06:23:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:23:09 INFO - "repository": "", 06:23:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:23:09 INFO - "buildid": "20160704150257", 06:23:09 INFO - "pgo_build": "False", 06:23:09 INFO - "basedir": "/builds/slave/test", 06:23:09 INFO - "buildnumber": 77, 06:23:09 INFO - "slavename": "t-yosemite-r7-0082", 06:23:09 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:23:09 INFO - "platform": "yosemite_r7", 06:23:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:23:09 INFO - "branch": "mozilla-central", 06:23:09 INFO - "repo_path": "mozilla-central", 06:23:09 INFO - "revision": "c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5", 06:23:09 INFO - "stage_platform": "macosx64", 06:23:09 INFO - "builduid": "288e0872bed146679b0a067544a79c15", 06:23:09 INFO - "slavebuilddir": "test" 06:23:09 INFO - } 06:23:09 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg. 06:23:09 INFO - Running post-action listener: _resource_record_post_action 06:23:09 INFO - ##### 06:23:09 INFO - ##### Finished read-buildbot-config step (success) 06:23:09 INFO - ##### 06:23:09 INFO - ##### 06:23:09 INFO - ##### Running download-and-extract step. 06:23:09 INFO - ##### 06:23:09 INFO - Running pre-action listener: _resource_record_pre_action 06:23:09 INFO - Running main action method: download_and_extract 06:23:09 INFO - mkdir: /builds/slave/test/build/tests 06:23: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')]} 06:23:09 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.test_packages.json 06:23:09 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:23:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 06:23:09 INFO - Downloaded 1199 bytes. 06:23:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:23:09 INFO - Using the following test package requirements: 06:23:09 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:23:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 06:23:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 06:23:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'jsshell-mac.zip'], 06:23:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 06:23:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:23:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 06:23:09 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 06:23:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 06:23:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:09 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 06:23:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:23: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')]} 06:23:09 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:09 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:23:09 INFO - Downloaded 18800192 bytes. 06:23:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:23:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:23:12 INFO - Return code: 0 06:23:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:23:13 INFO - Downloaded 18800192 bytes. 06:23:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:23:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:23:16 INFO - Return code: 0 06:23:16 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')]} 06:23:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.talos.tests.zip 06:23:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:23:18 INFO - Downloaded 14403333 bytes. 06:23:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:23:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:23:19 INFO - Return code: 0 06:23:19 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')]} 06:23:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg 06:23:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 06:23:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 06:23:25 INFO - Downloaded 112425084 bytes. 06:23:25 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg 06:23:25 INFO - mkdir: /builds/slave/test/properties 06:23:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:23:25 INFO - Writing to file /builds/slave/test/properties/build_url 06:23:25 INFO - Contents: 06:23:25 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg 06:23:25 INFO - Running post-action listener: _resource_record_post_action 06:23:25 INFO - Running post-action listener: set_extra_try_arguments 06:23:25 INFO - ##### 06:23:25 INFO - ##### Finished download-and-extract step (success) 06:23:25 INFO - ##### 06:23:25 INFO - ##### 06:23:25 INFO - ##### Running populate-webroot step. 06:23:25 INFO - ##### 06:23:25 INFO - Running pre-action listener: _resource_record_pre_action 06:23:25 INFO - Running main action method: populate_webroot 06:23:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:23:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:23:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:23:25 INFO - '--tppagecycles', 06:23:25 INFO - '7'], 06:23:25 INFO - 'tests': ['tsvgm']}}, 06:23:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:23:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:23:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:23:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:23:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:23:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:23:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['damp', 'tps']}, 06:23:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'tests': ['damp', 'tps']}, 06:23:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['dromaeo_dom']}, 06:23:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:23:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['basic_compositor_video']}, 06:23:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:23:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other-e10s': {'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tsvgx', 06:23:25 INFO - 'tsvgr_opacity', 06:23:25 INFO - 'tart', 06:23:25 INFO - 'tscrollx', 06:23:25 INFO - 'cart']}, 06:23:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:23:25 INFO - 'tsvgr_opacity', 06:23:25 INFO - 'tart', 06:23:25 INFO - 'tscrollx', 06:23:25 INFO - 'cart']}, 06:23:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tp5o']}, 06:23:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'tests': ['tp5o']}, 06:23:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s', 06:23:25 INFO - '--xperf_path', 06:23:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:23:25 INFO - 'tests': ['tp5n']}, 06:23:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--xperf_path', 06:23:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:23:25 INFO - 'tests': ['tp5n']}}, 06:23:25 INFO - 'talos.zip': {'path': '', 06:23:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:23:25 INFO - Running post-action listener: _resource_record_post_action 06:23:25 INFO - ##### 06:23:25 INFO - ##### Finished populate-webroot step (success) 06:23:25 INFO - ##### 06:23:25 INFO - ##### 06:23:25 INFO - ##### Running create-virtualenv step. 06:23:25 INFO - ##### 06:23:25 INFO - Running pre-action listener: _resource_record_pre_action 06:23:25 INFO - Running main action method: create_virtualenv 06:23:25 INFO - Creating virtualenv /builds/slave/test/build/venv 06:23:25 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 06:23:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:23:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:23:26 INFO - Using real prefix '/tools/python27' 06:23:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:23:26 INFO - Installing distribute.............................................................................................................................................................................................done. 06:23:29 INFO - Installing pip.................done. 06:23:29 INFO - Return code: 0 06:23:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:29 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 06:23:29 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 06:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:29 INFO - 'HOME': '/Users/cltbld', 06:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:29 INFO - 'LOGNAME': 'cltbld', 06:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:29 INFO - 'MOZ_NO_REMOTE': '1', 06:23:29 INFO - 'NO_EM_RESTART': '1', 06:23:29 INFO - 'PAGER': '/bin/cat', 06:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:29 INFO - 'PWD': '/builds/slave/test', 06:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:29 INFO - 'SHELL': '/bin/bash', 06:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:29 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:29 INFO - 'USER': 'cltbld', 06:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:29 INFO - 'XPC_FLAGS': '0x0', 06:23:29 INFO - 'XPC_SERVICE_NAME': '0', 06:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:29 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 06:23:29 INFO - Cleaning up... 06:23:29 INFO - Return code: 0 06:23:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:29 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 06:23:29 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 06:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:29 INFO - 'HOME': '/Users/cltbld', 06:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:29 INFO - 'LOGNAME': 'cltbld', 06:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:29 INFO - 'MOZ_NO_REMOTE': '1', 06:23:29 INFO - 'NO_EM_RESTART': '1', 06:23:29 INFO - 'PAGER': '/bin/cat', 06:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:29 INFO - 'PWD': '/builds/slave/test', 06:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:29 INFO - 'SHELL': '/bin/bash', 06:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:29 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:29 INFO - 'USER': 'cltbld', 06:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:29 INFO - 'XPC_FLAGS': '0x0', 06:23:29 INFO - 'XPC_SERVICE_NAME': '0', 06:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:30 INFO - Downloading/unpacking psutil>=3.1.1 06:23:30 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 06:23:30 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 06:23:30 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 06:23:30 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 06:23:30 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 06:23:30 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 06:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:23:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:23:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:23:32 INFO - Installing collected packages: psutil 06:23:32 INFO - Running setup.py install for psutil 06:23:32 INFO - building 'psutil._psutil_osx' extension 06:23:32 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 06:23:33 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 06:23:33 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 06:23:33 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 06:23:33 INFO - building 'psutil._psutil_posix' extension 06:23:33 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 06:23:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:23:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:23:33 INFO - ^ 06:23:33 INFO - 1 warning generated. 06:23:33 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 06:23:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:23:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:23:33 INFO - Successfully installed psutil 06:23:33 INFO - Cleaning up... 06:23:34 INFO - Return code: 0 06:23:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:23:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:34 INFO - 'HOME': '/Users/cltbld', 06:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:34 INFO - 'LOGNAME': 'cltbld', 06:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:34 INFO - 'MOZ_NO_REMOTE': '1', 06:23:34 INFO - 'NO_EM_RESTART': '1', 06:23:34 INFO - 'PAGER': '/bin/cat', 06:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:34 INFO - 'PWD': '/builds/slave/test', 06:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:34 INFO - 'SHELL': '/bin/bash', 06:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:34 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:34 INFO - 'USER': 'cltbld', 06:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:34 INFO - 'XPC_FLAGS': '0x0', 06:23:34 INFO - 'XPC_SERVICE_NAME': '0', 06:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:23:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:23:36 INFO - Installing collected packages: mozsystemmonitor 06:23:36 INFO - Running setup.py install for mozsystemmonitor 06:23:37 INFO - Successfully installed mozsystemmonitor 06:23:37 INFO - Cleaning up... 06:23:37 INFO - Return code: 0 06:23:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:23: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')]} 06:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23: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')]} 06:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:37 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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:37 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 06:23:37 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 06:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:37 INFO - 'HOME': '/Users/cltbld', 06:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:37 INFO - 'LOGNAME': 'cltbld', 06:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:37 INFO - 'MOZ_NO_REMOTE': '1', 06:23:37 INFO - 'NO_EM_RESTART': '1', 06:23:37 INFO - 'PAGER': '/bin/cat', 06:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:37 INFO - 'PWD': '/builds/slave/test', 06:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:37 INFO - 'SHELL': '/bin/bash', 06:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:37 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:37 INFO - 'USER': 'cltbld', 06:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:37 INFO - 'XPC_FLAGS': '0x0', 06:23:37 INFO - 'XPC_SERVICE_NAME': '0', 06:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:37 INFO - Downloading/unpacking jsonschema==2.5.1 06:23:37 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 06:23:37 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 06:23:37 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 06:23:37 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 06:23:37 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 06:23:37 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 06:23:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:23:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:40 INFO - Downloading functools32-3.2.3-2.tar.gz 06:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:23:40 INFO - warning: no files found matching '*.txt' 06:23:40 INFO - no previously-included directories found matching 'build' 06:23:40 INFO - no previously-included directories found matching 'dist' 06:23:40 INFO - no previously-included directories found matching '.git*' 06:23:40 INFO - Installing collected packages: jsonschema, functools32 06:23:40 INFO - Running setup.py install for functools32 06:23:40 INFO - warning: no files found matching '*.txt' 06:23:40 INFO - no previously-included directories found matching 'build' 06:23:40 INFO - no previously-included directories found matching 'dist' 06:23:40 INFO - no previously-included directories found matching '.git*' 06:23:40 INFO - Successfully installed jsonschema functools32 06:23:40 INFO - Cleaning up... 06:23:40 INFO - Return code: 0 06:23:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:23:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:40 INFO - 'HOME': '/Users/cltbld', 06:23:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:40 INFO - 'LOGNAME': 'cltbld', 06:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:40 INFO - 'MOZ_NO_REMOTE': '1', 06:23:40 INFO - 'NO_EM_RESTART': '1', 06:23:40 INFO - 'PAGER': '/bin/cat', 06:23:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:40 INFO - 'PWD': '/builds/slave/test', 06:23:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:40 INFO - 'SHELL': '/bin/bash', 06:23:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:40 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:40 INFO - 'USER': 'cltbld', 06:23:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:40 INFO - 'XPC_FLAGS': '0x0', 06:23:40 INFO - 'XPC_SERVICE_NAME': '0', 06:23:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:40 INFO - Downloading/unpacking blobuploader==1.2.4 06:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:43 INFO - Downloading blobuploader-1.2.4.tar.gz 06:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:23:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:23:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23:44 INFO - Downloading docopt-0.6.1.tar.gz 06:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:23:44 INFO - Installing collected packages: blobuploader, requests, docopt 06:23:44 INFO - Running setup.py install for blobuploader 06:23:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:23:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:23:44 INFO - Running setup.py install for requests 06:23:45 INFO - Running setup.py install for docopt 06:23:45 INFO - Successfully installed blobuploader requests docopt 06:23:45 INFO - Cleaning up... 06:23:45 INFO - Return code: 0 06:23:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:45 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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:45 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 06:23:45 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 06:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:45 INFO - 'HOME': '/Users/cltbld', 06:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:45 INFO - 'LOGNAME': 'cltbld', 06:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:45 INFO - 'MOZ_NO_REMOTE': '1', 06:23:45 INFO - 'NO_EM_RESTART': '1', 06:23:45 INFO - 'PAGER': '/bin/cat', 06:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:45 INFO - 'PWD': '/builds/slave/test', 06:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:45 INFO - 'SHELL': '/bin/bash', 06:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:45 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:45 INFO - 'USER': 'cltbld', 06:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:45 INFO - 'XPC_FLAGS': '0x0', 06:23:45 INFO - 'XPC_SERVICE_NAME': '0', 06:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:23:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-23N3ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:23:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ddnsW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:23:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Yyib6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:23:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-fxhilR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-byK0kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-GYLwi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-XvUh4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-PnICzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-EB9G6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-VGVF_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-cfNcOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-nTbbEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:23:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-XgIQjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:23:47 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-LsXUSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:47 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-uCqsH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:23:47 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-3yDMEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:23:47 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ceNAbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:23:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:23:47 INFO - Running setup.py install for manifestparser 06:23:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:23:47 INFO - Running setup.py install for mozcrash 06:23:47 INFO - Running setup.py install for mozdebug 06:23:47 INFO - Running setup.py install for mozdevice 06:23:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Running setup.py install for mozfile 06:23:48 INFO - Running setup.py install for mozhttpd 06:23:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Running setup.py install for mozinfo 06:23:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Running setup.py install for mozInstall 06:23:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Running setup.py install for mozleak 06:23:48 INFO - Running setup.py install for mozlog 06:23:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Running setup.py install for moznetwork 06:23:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for mozprocess 06:23:49 INFO - Running setup.py install for mozprofile 06:23:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for mozrunner 06:23:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for mozscreenshot 06:23:49 INFO - Running setup.py install for moztest 06:23:49 INFO - Running setup.py install for mozversion 06:23:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:23:49 INFO - Cleaning up... 06:23:50 INFO - Return code: 0 06:23:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:50 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')]} 06:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:50 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')]} 06:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:50 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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:50 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 06:23:50 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 06:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:50 INFO - 'HOME': '/Users/cltbld', 06:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:50 INFO - 'LOGNAME': 'cltbld', 06:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:50 INFO - 'MOZ_NO_REMOTE': '1', 06:23:50 INFO - 'NO_EM_RESTART': '1', 06:23:50 INFO - 'PAGER': '/bin/cat', 06:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:50 INFO - 'PWD': '/builds/slave/test', 06:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:50 INFO - 'SHELL': '/bin/bash', 06:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:50 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:50 INFO - 'USER': 'cltbld', 06:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:50 INFO - 'XPC_FLAGS': '0x0', 06:23:50 INFO - 'XPC_SERVICE_NAME': '0', 06:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:23:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dVVsyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:23:50 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)) 06:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:23:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-FNiKiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:23:50 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)) 06:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:23:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-FggUDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:23:50 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)) 06:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:23:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7w1PLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:23:50 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)) 06:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:23:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-354tqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:23:50 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)) 06:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:23:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-zOIqhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:23:50 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)) 06:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:23:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-d3PfKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-8ekVqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-rrSXy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-m51vbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-FxYRIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-95G3k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-gEYYcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-DD99MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-n6N8AE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-bWrHQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:23:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-HI3yos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:54 INFO - Downloading blessings-1.6.tar.gz 06:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:23:54 INFO - Installing collected packages: blessings 06:23:54 INFO - Running setup.py install for blessings 06:23:54 INFO - Successfully installed blessings 06:23:54 INFO - Cleaning up... 06:23:54 INFO - Return code: 0 06:23:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:23:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:23:55 INFO - Reading from file tmpfile_stdout 06:23:55 INFO - Current package versions: 06:23:55 INFO - blessings == 1.6 06:23:55 INFO - blobuploader == 1.2.4 06:23:55 INFO - docopt == 0.6.1 06:23:55 INFO - functools32 == 3.2.3-2 06:23:55 INFO - jsonschema == 2.5.1 06:23:55 INFO - manifestparser == 1.1 06:23:55 INFO - mozInstall == 1.12 06:23:55 INFO - mozcrash == 0.17 06:23:55 INFO - mozdebug == 0.1 06:23:55 INFO - mozdevice == 0.48 06:23:55 INFO - mozfile == 1.2 06:23:55 INFO - mozhttpd == 0.7 06:23:55 INFO - mozinfo == 0.9 06:23:55 INFO - mozleak == 0.1 06:23:55 INFO - mozlog == 3.2 06:23:55 INFO - moznetwork == 0.27 06:23:55 INFO - mozprocess == 0.22 06:23:55 INFO - mozprofile == 0.28 06:23:55 INFO - mozrunner == 6.11 06:23:55 INFO - mozscreenshot == 0.1 06:23:55 INFO - mozsystemmonitor == 0.3 06:23:55 INFO - moztest == 0.7 06:23:55 INFO - mozversion == 1.4 06:23:55 INFO - psutil == 3.1.1 06:23:55 INFO - requests == 1.2.3 06:23:55 INFO - wsgiref == 0.1.2 06:23:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:55 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')]} 06:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:55 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')]} 06:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:55 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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:55 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 06:23:55 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 06:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:55 INFO - 'HOME': '/Users/cltbld', 06:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:55 INFO - 'LOGNAME': 'cltbld', 06:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:55 INFO - 'MOZ_NO_REMOTE': '1', 06:23:55 INFO - 'NO_EM_RESTART': '1', 06:23:55 INFO - 'PAGER': '/bin/cat', 06:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:55 INFO - 'PWD': '/builds/slave/test', 06:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:55 INFO - 'SHELL': '/bin/bash', 06:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:55 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:55 INFO - 'USER': 'cltbld', 06:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:55 INFO - 'XPC_FLAGS': '0x0', 06:23:55 INFO - 'XPC_SERVICE_NAME': '0', 06:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 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)) 06:23:55 INFO - Cleaning up... 06:23:55 INFO - Return code: 0 06:23:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:23:55 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')]} 06:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:55 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')]} 06:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:55 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 0x10a857a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff449c70050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, '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.tQr8uQxcjF/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.BqSehrtWes/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 06:23:55 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 06:23:55 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 06:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:55 INFO - 'HOME': '/Users/cltbld', 06:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:55 INFO - 'LOGNAME': 'cltbld', 06:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:55 INFO - 'MOZ_NO_REMOTE': '1', 06:23:55 INFO - 'NO_EM_RESTART': '1', 06:23:55 INFO - 'PAGER': '/bin/cat', 06:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:55 INFO - 'PWD': '/builds/slave/test', 06:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:55 INFO - 'SHELL': '/bin/bash', 06:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:23:55 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:55 INFO - 'USER': 'cltbld', 06:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:55 INFO - 'XPC_FLAGS': '0x0', 06:23:55 INFO - 'XPC_SERVICE_NAME': '0', 06:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:23:55 INFO - Cleaning up... 06:23:55 INFO - Return code: 0 06:23:55 INFO - Running post-action listener: _resource_record_post_action 06:23:55 INFO - Running post-action listener: _start_resource_monitoring 06:23:55 INFO - Starting resource monitoring. 06:23:55 INFO - ##### 06:23:55 INFO - ##### Finished create-virtualenv step (success) 06:23:55 INFO - ##### 06:23:55 INFO - ##### 06:23:55 INFO - ##### Running install step. 06:23:55 INFO - ##### 06:23:55 INFO - Running pre-action listener: _resource_record_pre_action 06:23:55 INFO - Running main action method: install 06:23:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:23:56 INFO - Reading from file tmpfile_stdout 06:23:56 INFO - Detecting whether we're running mozinstall >=1.0... 06:23:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:23:56 INFO - Reading from file tmpfile_stdout 06:23:56 INFO - Output received: 06:23:56 INFO - Usage: mozinstall [options] installer 06:23:56 INFO - Options: 06:23:56 INFO - -h, --help show this help message and exit 06:23:56 INFO - -d DEST, --destination=DEST 06:23:56 INFO - Directory to install application into. [default: 06:23:56 INFO - "/builds/slave/test"] 06:23:56 INFO - --app=APP Application being installed. [default: firefox] 06:23:56 INFO - mkdir: /builds/slave/test/build/application 06:23:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:24:30 INFO - Reading from file tmpfile_stdout 06:24:30 INFO - Output received: 06:24:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:24:30 INFO - Running post-action listener: _resource_record_post_action 06:24:30 INFO - ##### 06:24:30 INFO - ##### Finished install step (success) 06:24:30 INFO - ##### 06:24:30 INFO - ##### 06:24:30 INFO - ##### Running run-tests step. 06:24:30 INFO - ##### 06:24:30 INFO - Running pre-action listener: _resource_record_pre_action 06:24:30 INFO - Running main action method: run_tests 06:24:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:24:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:24:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:24:30 INFO - 2016-07-27 06:24:30.965 screenresolution[1651:8250] starting screenresolution argv=screenresolution get 06:24:30 INFO - 2016-07-27 06:24:30.968 screenresolution[1651:8250] Display 0: 1600x1200x32@60 06:24:30 INFO - 2016-07-27 06:24:30.973 screenresolution[1652:8252] starting screenresolution argv=screenresolution list 06:24:30 INFO - Available Modes on Display 0 06:24:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:24:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:24:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:24:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:24:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:24:31 INFO - Intel Iris: 06:24:31 INFO - Chipset Model: Intel Iris 06:24:31 INFO - Type: GPU 06:24:31 INFO - Bus: Built-In 06:24:31 INFO - VRAM (Dynamic, Max): 1536 MB 06:24:31 INFO - Vendor: Intel (0x8086) 06:24:31 INFO - Device ID: 0x0a2e 06:24:31 INFO - Revision ID: 0x0009 06:24:31 INFO - Displays: 06:24:31 INFO - Display: 06:24:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:24:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:24:31 INFO - Display Serial Number: 1600x1200 60 06:24:31 INFO - Main Display: Yes 06:24:31 INFO - Mirror: Off 06:24:31 INFO - Online: Yes 06:24:31 INFO - Rotation: Supported 06:24:31 INFO - Return code: 0 06:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:24:31 INFO - Python 2.7.3 06:24:31 INFO - Return code: 0 06:24:31 INFO - grabbing minidump binary from tooltool 06:24: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')]} 06:24:31 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 0x10a7bbd30>, '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 0x10a520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7b3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:24:31 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 06:24:31 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 06:24:31 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 06:24:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:24:31 INFO - Return code: 0 06:24:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:24:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:24:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:24:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:24:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tQr8uQxcjF/Render', 06:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:31 INFO - 'HOME': '/Users/cltbld', 06:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:31 INFO - 'LOGNAME': 'cltbld', 06:24:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:24:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:31 INFO - 'MOZ_NO_REMOTE': '1', 06:24:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:24:31 INFO - 'NO_EM_RESTART': '1', 06:24:31 INFO - 'PAGER': '/bin/cat', 06:24:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:31 INFO - 'PWD': '/builds/slave/test', 06:24:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:31 INFO - 'SHELL': '/bin/bash', 06:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqSehrtWes/Listeners', 06:24:31 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 06:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:31 INFO - 'USER': 'cltbld', 06:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:31 INFO - 'XPC_FLAGS': '0x0', 06:24:31 INFO - 'XPC_SERVICE_NAME': '0', 06:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:24:31 INFO - mozversion application_buildid: 20160704150257 06:24:31 INFO - mozversion application_changeset: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 06:24:31 INFO - mozversion application_display_name: Nightly 06:24:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:24:31 INFO - mozversion application_name: Firefox 06:24:31 INFO - mozversion application_remotingname: firefox 06:24:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:24:31 INFO - mozversion application_vendor: Mozilla 06:24:31 INFO - mozversion application_version: 50.0a1 06:24:31 INFO - mozversion platform_buildid: 20160704150257 06:24:31 INFO - mozversion platform_changeset: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 06:24:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:24:31 INFO - mozversion platform_version: 50.0a1 06:24:31 INFO - using testdate: 1469625871 06:24:31 INFO - actual date: 1469625871 06:24:31 INFO - starting webserver on 'localhost:49265' 06:24:31 INFO - SUITE-START | Running 2 tests 06:24:31 INFO - TEST-START | dromaeo_css 06:24:31 INFO - operating with platform_type : mac_ 06:24:31 INFO - Initialising browser for dromaeo_css test... 06:24:31 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpKMKvdT/profile http://localhost:49265/getInfo.html) 06:24:34 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 06:24:34 INFO - PROCESS | 1662 | colorDepth:24 06:24:34 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 06:24:34 INFO - PROCESS | 1662 | __metrics 06:24:34 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 06:24:35 INFO - TEST-INFO | 1662: exit 0 06:24:35 INFO - Browser initialized. 06:24:35 INFO - Running cycle 1/1 for dromaeo_css test... 06:24:35 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpKMKvdT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:24:47 INFO - PROCESS | 1666 | RSS: Main: 255721472 06:24:47 INFO - PROCESS | 1666 | 06:25:37 INFO - PROCESS | 1666 | 1469625937189 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] 06:27:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 06:27:49 INFO - PROCESS | 1666 | RSS: Main: 268075008 06:27:49 INFO - PROCESS | 1666 | 06:30:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 06:30:52 INFO - PROCESS | 1666 | RSS: Main: 276746240 06:30:52 INFO - PROCESS | 1666 | 06:33:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 06:33:54 INFO - PROCESS | 1666 | RSS: Main: 281333760 06:33:54 INFO - PROCESS | 1666 | 06:36:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 06:36:57 INFO - PROCESS | 1666 | RSS: Main: 280715264 06:36:57 INFO - PROCESS | 1666 | 06:40:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 06:40:00 INFO - PROCESS | 1666 | RSS: Main: 283844608 06:40:00 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 06:43:03 INFO - PROCESS | 1666 | RSS: Main: 284000256 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | __start_tp_report 06:43:03 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 06:43:03 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 06:43:03 INFO - PROCESS | 1666 | |i|pagename|runs| 06:43:03 INFO - PROCESS | 1666 | |0;dojo.html;3375.2911888503413;3431.245126072264;3431.6053653829786;3439.1574064354236;3443.122003889008;5813.418658134185;5875.706214689264;5891.587588868781;5925.555583331251;5940.316863560692;5766.509846663028;5861.325947516037;5894.528437724983;5908.202392676987;5963.165156878041;5199.532042116219;5243.79024839006;5317.654352467097;5325.920111198335;5357.598180136505;5263.763130659129;5333.2000199970125;5367.1680889462;5398.595105367093;5431.646942948715;5153.304303918961;5224.111900976843;5269.104252277122;5290.1800220965815;5298.788048478056;5281.155015197588;5293.179557168645;5295.391030031557;5299.33758280215;5312.043832110218;5185.481451854822;5259.76331065103;5267.815626453094;5290.312259406291;5341.278927344836;4657.6273898088075;5198.428172900978;5826.242588463515;5866.296044474648;5987.491063259628;6283.874322513524;6321.810345689591;6338.176037115146;6341.619502829845;6353.745850165988;6236.064590311489;6254.655993920333;6276.466500347476;6278.77856654673;6313.179286692746;5893.970530147321;5944.583879128462;5957.4638282553915;5959.523238140939;6014.1580178775;5373.247745315659;6112.297085835097;6404.103425520431;6429.6142231466265;6434.646094464756;5034.546890779848;6155.815325540151;6170.784022559189;6285.528585356116;6332.936670633327;6247.656378899204;6307.873842523124;6315.810525684236;6332.461740751995;6335.588186767845;6106.764041036412;6270.717817698214;6274.215723034621;6328.968355158287;6376.585521941059;6195.504359651217;6212.47193988516;6301.4328710416285;6328.588641738273;6343.524235682342;5013.724245166551;5020.071286811952;5024.62315326352;5036.32009678696;5044.646874718808;4480.596746292849;4487.910241795146;4498.370228168059;4538.364628951949;4552.2488789350045;4893.314935908976;5032.32063671407;5847.44449277318;5900.468957793819;6010.398960103955;6272.090546870678;6301.117843501914;6309.968450157811;6321.55749097568;6376.202974628171;5914;6044.667543285162;6046.425589569072;6077.6353418795015;6078.848028799315;5434.375046869661;5502.477264659851;5531.889362212733;5541.445855414427;5562.582806289463;6451.580647257913;6476.6114033158165;6486.935130648633;6513.088167656532;6520.673966301761;6136.7028951139955;6304.18045654062;6306.936930630635;6312.368763123651;6315.621062736251;6352.460040896575;6390.520710946605;6422.2614399344675;6422.903656445251;6497.155369801895;6196.132541444292;6219.471344935731;6227.097070924781;6234.719437625408;6237.282712488122;6009.639421634716;6226.501879849692;6280.623162610258;6311.053341998737;6322.114903913363;6087.878242435215;6130.061839217998;6167.907481387693;6185.690715464299;6256.343083976117;6216.2540495139065;6218.657973811303;6225.501959843292;6243.906341404973;6246.562740608114;5058.6206034548295;5992.280926288873;6069.302030266399;6099.146119543356;6172.938270617236;6144.95535758931;6167.84580385494;6222.81331560054;6233.189685340877;6246.6876656167915;5999.250093738283;6085.452309292008;6109.75560977556;6116.3272040076445;6146.508279337731 06:43:03 INFO - PROCESS | 1666 | |1;ext.html;496.43654452196745;515.724087613127;531.1978911843117;556.8273835111114;605.062153661824;30873.919412820556;38207.42688859666;39621.009474763145;39677.80161099198;39847.601523984755;32282.67717322826;33014;41245;41462.37806432906;41464.37803432951;8270.33837293018;9463.716088517322;9748.463834489101;9749;9762.609495620167;9585.712428627152;9658.517074146299;9729.270304727173;9731.318807683465;9761.316707830454;9651.179649729782;9670.613175472972;9689.806203875918;9760.511974401286;9782.364146330501;9681.612735490608;9729.659461918869;9735.659251926185;9736.318457707963;9765;9591.280653950982;9607.423554586747;9623.615055397775;9720.07659272368;9744.02559744027;39608.009799754946;39826.60173398287;39906.00234994119;39964.20071598552;40057.19885602272;2806.9438611227865;3357.7649564530493;3376.324735052999;3525.94711079334;3569.964300357015;31409;31414.685853141404;31764.20589485258;32044.519332210035;32195.83902080498;3150.5589217509337;3198.2803869129493;3205.5512228288057;3211.6467188609236;3337.2324365396094;448647;453256;454065;456593;459354;34564.61741530361;35303.823480882435;35328.47007294893;35417.82291088528;35449;31943.520847187312;35528.93413197608;35969;36021.27957440836;36043.81978090093;14942.850571494364;14947.252637368305;15062.322195501229;15081.944263901421;15089.868259880554;12756.936215318863;12810.615681529569;12852.164609300366;12888.48446062147;12908.225506469642;7762.146163921964;7787.727429540052;7807.531548107132;7842.764717058498;7856.84286314282;3270;3284.408806414868;3290.572225610655;3291.835408229627;3325.7505687073567;809.8137428391385;998.3211416236884;1004.372267332917;1103.1560855945208;1130.2540323386525;31045.844770776002;31134.377312453624;31352.686473135433;31477.685223147935;31499;939.6382392778829;1083.7236504691252;1157.9594714184964;1164.7321116143335;1268.3658170914543;14502.927485362507;16416.835831641532;16457;16545.50363489097;16617.750733739005;659698;674394;675167;676016;676450;56625;61821.69089154525;61829;61948.38051619426;62469.37530624636;1351.1015174908773;1355.749186400519;1385.3350391811987;1405.2130806748253;1418.2979425184599;27205.31986700269;30540;30749;31130.066098017094;31399.84300078485;45282.547174527834;53072.73463632657;54041.729791350794;54226.72886635699;54772.72613636906;552960;554798;562541;562775;563611;27668.23317668217;28057.438851222454;28062.579061314504;28117.578236326062;28118.015869444473;50113.24830127478;50348;50475.49524504708;51317;51480.48519514757;34440.48339274963;41175;41214;41792.58207417887;42316;37383.504659813305;40526.999258658616;40905.18189636131;41071.38392924168;41891.952701182716;39013.414798778605;39590.802045990735;39916.800415997735;40176.598234017285;41059.384109237784;32615.836920815244;35849.64150358568;35874.64125358713;35929;36151;22352.664710029687;24513.877430612734;25730.09944651928;25749.3562660935;25982.87008564945 06:43:03 INFO - PROCESS | 1666 | |2;jquery.html;377.4281962826318;384.0877914951989;385;394.40051122294096;402.2919661395944;19927.402177934666;21337.893310533465;21660.8916955415;22492.88753556232;22531.887340563295;18698.53253668659;18848.5287867803;19530.70703939442;21495.355139345844;21840.890795546;5454.836354909359;6025.186599809035;6241.71912263947;6251.812445626639;6272.059191121345;3596.2268112355837;6683.799486015428;7025.051618031552;7026.367626913577;7099.964500177492;6896.310368963089;6987.481177037732;6994.8950765738555;7007.544509606884;7041.119860017498;6750.62871542065;6783.762568310135;6914.654267286615;6956.478264130211;6967.6167810770385;5849.473547380756;5893.204417403638;5920.792772252994;5934.050551911673;5934.5845790794665;23919;26914.461710765674;27242.31894202641;27351;27397.041103561478;2307.849989750661;2440.414300567869;2544.1731437282774;2679.5310820606314;2828.9391478195676;15511.99172053813;15973.361065557363;16270.186490675418;16321.83678163215;16383.83616163835;1847.4919397165752;1884.2086323743924;1901.1482775836246;1907.1703808843197;1928.7203355513373;116277;121368.17947730792;121460;121836;121947;10737.624183153708;11202.831957520644;11227.27022743519;11243.83134252987;11332.43337833105;13712.588622341345;13765.242911639956;13767.31163441844;13769.31153442324;13783.51757688476;7434.182239953709;7545.6981720730555;7570.5836179010685;7571.318581327707;7648.885266721004;7137.1435427747965;7150.356467917913;7175.533590316717;7202.53183543068;7223.566586004857;5928.851778705567;6730.607270601497;6731.1922569292;6773.458123350219;6894.793156205322;1790.6239689665317;1907.4468404162637;1911.4838993471685;1933.5069557262807;1954.8631595788443;797.7686470923368;951.6050838901916;1004.3070281505737;1063.6818687430582;1127.3067063755732;21939.561208775736;22342.441438964157;22798.65802012971;22882.427939301317;22996.4250893729;952.8808898887639;1029.248648486609;1046.4192373232777;1085.8751243606928;1111.6942840718705;9304.25565954722;10388.37669739803;11367.374794386387;12062.276263424048;12355.135140540257;588428;588855;594789;597770;636754;65235.69528609496;101091.4945425283;108294;108648;109038;908.886389201363;1038.522279751323;1062.654637242899;1104.8066588347008;1120.4621781544904;16434.342626294816;17762.644747105245;19930.800691992896;20260.696089558372;20371.694424583347;33141.84578002422;43130.78434607807;51097.48902510927;52470.73764631152;53281.93436131333;70132.29867701257;70145.29854701388;70216.64891675509;71512.28487715057;73733.26266737263;6364.363563643598;6461.224653041665;6892.965535172292;8012.799680008046;8239.176082391712;13085.214887106804;13887.236202008986;13918.51285205013;14050.648733781329;14140.78788818189;11815.409229538316;11842;11944.223625464318;11954.760904781682;12041.638750837488;9185.816283674423;9530.523473826142;11375.715607109889;11547.4803633835;11628.18602697803;11972.940135299268;12066.457009434422;12148.453319600463;12172.087093467848;12212.87787122153;7577.734779282699;7664.69341226367;7865.84268314619;7866.331361834308;7875.84248315042;11678.532858685558;11876.406179690808;11894.167408281337;11914.344711040976;12040.518379265082 06:43:03 INFO - PROCESS | 1666 | |3;mootools.html;1553;1561.313022270201;1580.1941010084856;1906.2375049980014;1909.95225119372;7870.370370370371;8290.668373265062;8574.399792014554;9036.322275829323;9046.004939456663;8409.285210757078;8482.575871206445;8548.358873084513;8586.22723954844;8710.085441028696;2316.3166865774597;2356.705411823522;2357.57563638545;2358.0449917783326;2377.084822343393;2341.5785158671433;2345.95308093838;2376.239603326936;2383.511380167062;2388.414838364603;2362.456634973957;2363.8108951283857;2373.6914201153913;2383.392234980078;2389.0443822471066;2304.3893368257427;2340.32130682102;2350.330155905567;2360.6104992676273;2366.538524987628;1973.595412940358;2341.4731685370825;2345.3784747042046;2350.6239001759636;2352;8629.30965522763;8657.783555411102;8693.608787604573;8712.956435217846;8724.563771811385;5046.26829109777;5108.667936584147;5174.120399532088;5180.989707007135;5235.502627250406;3504.702100321476;3569.839357228931;3629.94555081674;3644.5079914211506;3644.599094099647;920.2362039507193;1078.2721662877605;1110.4947249001684;1125.983110253347;1127.216584473791;9171.403858749227;11289.604863829809;11291.717707057389;11293.887061129366;11295.378754168518;4566.086782643484;4594.931076033862;4775.355327030807;4786.329913812069;4788.497207793202;9981.201503879673;9994.300398972002;10005.249606279558;10038.698839034842;10069.446180459998;5264.184051472028;5353.250544923714;5354.759035843396;5357.919631205535;5391.29913111293;4352.564743525622;4764.142454358249;4882.487338829443;4910.508949105061;4914.33656456381;3953.466282051886;3971.8808435746973;4048.919021619551;4069.04377471294;4092.0588264699286;1289.8000809874484;1312.6324629103867;1314.309987256698;1321.662975941129;1324.6158614002024;858.6261980830621;930.9805762689844;949.3876449689911;950.3917492804611;950.7955789505289;5982.880342393128;5986.132010858489;6056.454919057306;6072.05883088122;6176.69116544171;440.17247574560264;444.8443044934247;445.45654301751813;445.5544455544456;454.3502791008889;4040.878773636796;4061.654759345489;4078.408630748525;4109.4246805447265;4146.274401979642;10273.74315642115;10559.260851740459;10570.471476426148;10572.89427105719;10660.62687805923;10534.631287904887;10617.84073238902;10627.309224900357;10644.988726071011;10679.359238445562;7836.451448398558;8120.187981201832;8125;8204.425690201748;8216.507009579444;6045.6070355426755;6046.606970546901;6099;6146.477549408351;6200.627962322275;9762.219022478042;9905.851412228967;9919.851202232117;9950.502474876372;9958.053984871425;9701.951490242503;9987.051230133126;10086.991300869855;10147.18822494184;10216.846747298945;6799.626020568916;6995.615241161785;7354.77935661931;7573.507721998052;7639;8377.078521362768;8401.915980840113;8408.285295749929;8470.237678608955;8471.872921906299;7467.253274672489;7628;7673.040869891263;7690.461667683432;7748.225177482207;7339;7451.478396512409;7467.327940485382;7568.772936811904;7576.772696819105;7097.148342198969;7150.427965762622;7152.713891444284;7176.174739904978;7209.243029482039;4949.307097006494;5044.738815296176;5092.414372347227;5130;5156.7421628917655;5108.438071812024;5145.408278048072;5164.8192313268855;5272.815451459181;5282.20766884955 06:43:03 INFO - PROCESS | 1666 | |4;prototype.html;298.7818892208688;312.84826858973395;316.939781441526;317.32093320294575;319.5430534335899;5216.035033518801;5252.553532949699;5842.620229685068;5893;5943.662675897921;5446.945530544694;5783.306003279612;5793.826185214451;5809.912851307234;5841.136664419311;3578.2306804037125;3967.1867267210296;4234.5765423457715;4298.247806633843;4323.265044942367;3564.2515071834946;4267.679924005712;4332.913341733163;4340.631046361063;4350.912271932017;3945.763254204757;3949.413134396975;4269;4271.231178387889;4281.850135245267;3251.024692592213;3601.549806274216;4211.368294755781;4278.080212754273;4296.914061718748;3080.6303243610687;3423.2126610879636;3702.8148592570265;3719.1074142205675;3742.3450896093345;5614.79956424838;5634.605577609569;5654.982103221419;5666.4217780909585;5681.318241810968;4210.873673789792;5016.648834581581;5016.799328026875;5043.856361046541;5064.949350506521;5025.844055867135;6017.69911504423;6067.180930574405;6073.51411887048;6103.901297766401;4110.212057754886;4110.602395185621;4115.629593336614;4127.690423218271;4150.522689890641;5286.81496147633;5972.910406343908;6394.793796140324;6476.740930362827;6484.319146489598;4041.8383264669674;4048.7570745755347;4051.6556092731857;4067.7355971862326;4078.9592104079175;4094.8362065517044;4103.379165229718;4123.649489793401;4128.772917489567;4136.234796562646;4674.878029273018;4781.306710526954;4850.981293928244;4858.82994095205;4874.073925954128;5378.731063446812;5613.214150018919;5990.6405615662325;5995.670238136944;6062.242219722535;1310.1811367895066;1312.0422091872986;1421.8080559124592;1425.672095418039;1431.8138641976684;790.952542847431;830.2818062376001;860.4536119564121;873.3144481581928;918.7657147427363;5528.834134975958;5590.161475778666;5681.545476361881;5703.857403564944;5724.77100915967;5240.266362709223;5309.628326017219;6073.301570319381;6169.629822210682;6240.625562466266;4823.662343635982;5456.699881506555;5656.688882111441;5673.687947162863;5710.942890571124;2752.6696796384563;3128.6859519002073;3160.8577614006963;3289.3914625794305;3304.7851889627095;5581.71620527281;5706.717313074803;6176.722047507873;6473.935260647333;6546.0835483032415;5282.471752824764;6075.939240607626;6391.6258004529245;6393.6163830170335;6417.679116044179;6169.352218017044;6420.913203208883;6434.324395938546;6466.838329041811;6489.448396886223;5463.508284254436;5817.476427121579;6085.421884920926;6228.657423841732;6236.251649802053;5732.398098199629;5926.843646027984;6097.634141951497;6167.013277875519;6203.627782333075;6024.518038556993;6402.35976402356;6434.806955791334;6451.451626611791;6454.774082907075;3715.442683597482;3903.2974064668633;4011.876674531136;4025.8389664413094;4067.4305597217003;6221.751129954932;6224.844378890564;6254.937450625435;6270.641903876762;6286.717097730704;5107.872303192301;5617.691026993554;5763.193152958505;5928.347881733092;6011.579189456696;5076.746162691777;5449.373322068013;6011.248593925759;6025.896663218358;6027.022165370834;4658;4749.477557468606;5595.720213989365;5926.436988486087;5946.651072826123;5110.386753589593;5374.4625537445945;5618.971905140612;6110.480609148272;6122.448979591858;5022.522860328263;5172.017316709813;5986.820395388146;5998.910016349845;6052.273727152769 06:43:03 INFO - PROCESS | 1666 | |5;yui.html;652.4682383857156;727.8507905879292;793.6666600027992;803.4135081390589;827.5407149032285;14396.776274016722;15193.848061519382;15554.300056497457;15807.130607816565;15840.762388564166;12362.876371236285;14433.639159021004;14447.494337698183;14454.927725361358;14518.637034074154;714.4213187318263;801.4029547986729;883.8188171424874;926.5182105305271;929.2287401456774;729.5513259345491;923.3213588012806;926.8702381666573;928.109015345266;930.2279108340061;929.5584597316288;940.9106134917206;979.6913972098802;983.2969426859794;985.5367977050811;923.9307051971061;959.6785076999214;972.2756546372962;976.5166242709834;981.8527220916848;697.8360085379983;711.9857602847914;721.5526373648319;734.5555938657089;735.5219107580062;12788.296643684595;13514.986376021838;14660.56018319452;14781.556553303311;14884.925575372054;10541.73645658857;14740.189289589069;14750.926245368812;14792.482263120846;14806.037607555581;11818.704532386673;11849.881501184964;11925.34410607425;11931.343776092312;12068.456919438646;3832.386818109117;3893.6301051400073;3955.64399204073;3959.8614048508166;4071.89820254496;91995.08004919931;96138;96506;107888.4605576975;114215.42892285486;10536.209784266211;11072.723181920355;11500.137489688139;11770.117241206772;12067.818982715266;21418.78581214168;21710.674339884914;21979.120835166734;22059;22078;7924.84150317002;7940.5598045096;7964.920350796534;8119.309858661962;8180.6318715657935;4441.111777644484;4499.325101234841;4525.773711314487;4540.432445944257;4615.053913947632;3455.3434847378917;3536.1513236823494;3575.910602234965;3578.624244454347;3587.802670853076;660.0758937487556;660.1352228580574;671.1342368344878;682.0757873082001;687.814290141659;605.6910975402576;624.643952946816;630.6027202862167;639.2201514152727;646.8124243969196;8604.259810608666;8662.350323725746;8792.677089980814;8899.510526920953;8978.910210897939;573.3349314794901;591.3642833953431;614.966176860277;626.2078470734524;639.6641763074423;4112.876613701594;4295.935560966588;4296.505901821268;4325.440775885329;4436.6672499562665;139578;141429.29285353507;145044;145073;149710;112989.4350528242;120720;121158;126822.36588817181;137546;72968.90546641698;95947;107971.92028079776;115356;118196.40901795436;8763.211310982042;9243.260539156718;9635.951820240854;9661.985491523288;9664.178544823768;45418;59441.10838337515;60954.4761380969;60980.39019609747;61333.69333153305;90634;93181.53409232911;93275.53362233145;93691;93915.06084939337;13935.512257070954;13939.16365018102;13992.720145596828;14126;14473.8938229318;13134.60596182116;13795.379207935466;14094.436222550983;14567.854321456649;14656.413743450568;13275.345857432787;13676.863231367559;13765.38055787472;13892.861071389158;13904;10298.433586152834;13069.803952940523;13475.326233688473;13841.930790345983;13898.51355202563;10688.89311106879;13198.670033248862;13234.404451799885;13520.053596248168;13564.79652805189;10704.63128602682;10808.513616887416;10825.188110891557;10967.287126336763;11436.085113191091;11223.494942727433;11440.427978601203;11441.14191435629;11581.419779392103;11599.536018559163 06:43:03 INFO - PROCESS | 1666 | __end_tp_report 06:43:03 INFO - PROCESS | 1666 | __start_cc_report 06:43:03 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,439 06:43:03 INFO - PROCESS | 1666 | __end_cc_report 06:43:03 INFO - PROCESS | 1666 | __startTimestamp1469626983863__endTimestamp 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | ------- Summary: start ------- 06:43:03 INFO - PROCESS | 1666 | Number of tests: 6 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5813.89 Median:6085.45 stddev:660.26 (10.8%) stddev-sans-first:633.97 06:43:03 INFO - PROCESS | 1666 | Values: 3375.3 3431.2 3431.6 3439.2 3443.1 5813.4 5875.7 5891.6 5925.6 5940.3 5766.5 5861.3 5894.5 5908.2 5963.2 5199.5 5243.8 5317.7 5325.9 5357.6 5263.8 5333.2 5367.2 5398.6 5431.6 5153.3 5224.1 5269.1 5290.2 5298.8 5281.2 5293.2 5295.4 5299.3 5312.0 5185.5 5259.8 5267.8 5290.3 5341.3 4657.6 5198.4 5826.2 5866.3 5987.5 6283.9 6321.8 6338.2 6341.6 6353.7 6236.1 6254.7 6276.5 6278.8 6313.2 5894.0 5944.6 5957.5 5959.5 6014.2 5373.2 6112.3 6404.1 6429.6 6434.6 5034.5 6155.8 6170.8 6285.5 6332.9 6247.7 6307.9 6315.8 6332.5 6335.6 6106.8 6270.7 6274.2 6329.0 6376.6 6195.5 6212.5 6301.4 6328.6 6343.5 5013.7 5020.1 5024.6 5036.3 5044.6 4480.6 4487.9 4498.4 4538.4 4552.2 4893.3 5032.3 5847.4 5900.5 6010.4 6272.1 6301.1 6310.0 6321.6 6376.2 5914.0 6044.7 6046.4 6077.6 6078.8 5434.4 5502.5 5531.9 5541.4 5562.6 6451.6 6476.6 6486.9 6513.1 6520.7 6136.7 6304.2 6306.9 6312.4 6315.6 6352.5 6390.5 6422.3 6422.9 6497.2 6196.1 6219.5 6227.1 6234.7 6237.3 6009.6 6226.5 6280.6 6311.1 6322.1 6087.9 6130.1 6167.9 6185.7 6256.3 6216.3 6218.7 6225.5 6243.9 6246.6 5058.6 5992.3 6069.3 6099.1 6172.9 6145.0 6167.8 6222.8 6233.2 6246.7 5999.3 6085.5 6109.8 6116.3 6146.5 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:68098.65 Median:39614.51 stddev:152456.84 (384.9%) stddev-sans-first:152800.03 06:43:03 INFO - PROCESS | 1666 | Values: 496.4 515.7 531.2 556.8 605.1 30873.9 38207.4 39621.0 39677.8 39847.6 32282.7 33014.0 41245.0 41462.4 41464.4 8270.3 9463.7 9748.5 9749.0 9762.6 9585.7 9658.5 9729.3 9731.3 9761.3 9651.2 9670.6 9689.8 9760.5 9782.4 9681.6 9729.7 9735.7 9736.3 9765.0 9591.3 9607.4 9623.6 9720.1 9744.0 39608.0 39826.6 39906.0 39964.2 40057.2 2806.9 3357.8 3376.3 3525.9 3570.0 31409.0 31414.7 31764.2 32044.5 32195.8 3150.6 3198.3 3205.6 3211.6 3337.2 448647.0 453256.0 454065.0 456593.0 459354.0 34564.6 35303.8 35328.5 35417.8 35449.0 31943.5 35528.9 35969.0 36021.3 36043.8 14942.9 14947.3 15062.3 15081.9 15089.9 12756.9 12810.6 12852.2 12888.5 12908.2 7762.1 7787.7 7807.5 7842.8 7856.8 3270.0 3284.4 3290.6 3291.8 3325.8 809.8 998.3 1004.4 1103.2 1130.3 31045.8 31134.4 31352.7 31477.7 31499.0 939.6 1083.7 1158.0 1164.7 1268.4 14502.9 16416.8 16457.0 16545.5 16617.8 659698.0 674394.0 675167.0 676016.0 676450.0 56625.0 61821.7 61829.0 61948.4 62469.4 1351.1 1355.7 1385.3 1405.2 1418.3 27205.3 30540.0 30749.0 31130.1 31399.8 45282.5 53072.7 54041.7 54226.7 54772.7 552960.0 554798.0 562541.0 562775.0 563611.0 27668.2 28057.4 28062.6 28117.6 28118.0 50113.2 50348.0 50475.5 51317.0 51480.5 34440.5 41175.0 41214.0 41792.6 42316.0 37383.5 40527.0 40905.2 41071.4 41892.0 39013.4 39590.8 39916.8 40176.6 41059.4 32615.8 35849.6 35874.6 35929.0 36151.0 22352.7 24513.9 25730.1 25749.4 25982.9 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34406.30 Median:22939.43 stddev:99648.80 (434.4%) stddev-sans-first:99895.59 06:43:03 INFO - PROCESS | 1666 | Values: 377.4 384.1 385.0 394.4 402.3 19927.4 21337.9 21660.9 22492.9 22531.9 18698.5 18848.5 19530.7 21495.4 21840.9 5454.8 6025.2 6241.7 6251.8 6272.1 3596.2 6683.8 7025.1 7026.4 7100.0 6896.3 6987.5 6994.9 7007.5 7041.1 6750.6 6783.8 6914.7 6956.5 6967.6 5849.5 5893.2 5920.8 5934.1 5934.6 23919.0 26914.5 27242.3 27351.0 27397.0 2307.8 2440.4 2544.2 2679.5 2828.9 15512.0 15973.4 16270.2 16321.8 16383.8 1847.5 1884.2 1901.1 1907.2 1928.7 116277.0 121368.2 121460.0 121836.0 121947.0 10737.6 11202.8 11227.3 11243.8 11332.4 13712.6 13765.2 13767.3 13769.3 13783.5 7434.2 7545.7 7570.6 7571.3 7648.9 7137.1 7150.4 7175.5 7202.5 7223.6 5928.9 6730.6 6731.2 6773.5 6894.8 1790.6 1907.4 1911.5 1933.5 1954.9 797.8 951.6 1004.3 1063.7 1127.3 21939.6 22342.4 22798.7 22882.4 22996.4 952.9 1029.2 1046.4 1085.9 1111.7 9304.3 10388.4 11367.4 12062.3 12355.1 588428.0 588855.0 594789.0 597770.0 636754.0 65235.7 101091.5 108294.0 108648.0 109038.0 908.9 1038.5 1062.7 1104.8 1120.5 16434.3 17762.6 19930.8 20260.7 20371.7 33141.8 43130.8 51097.5 52470.7 53281.9 70132.3 70145.3 70216.6 71512.3 73733.3 6364.4 6461.2 6893.0 8012.8 8239.2 13085.2 13887.2 13918.5 14050.6 14140.8 11815.4 11842.0 11944.2 11954.8 12041.6 9185.8 9530.5 11375.7 11547.5 11628.2 11972.9 12066.5 12148.5 12172.1 12212.9 7577.7 7664.7 7865.8 7866.3 7875.8 11678.5 11876.4 11894.2 11914.3 12040.5 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5697.58 Median:5045.50 stddev:3157.47 (62.6%) stddev-sans-first:3150.96 06:43:03 INFO - PROCESS | 1666 | Values: 1553.0 1561.3 1580.2 1906.2 1910.0 7870.4 8290.7 8574.4 9036.3 9046.0 8409.3 8482.6 8548.4 8586.2 8710.1 2316.3 2356.7 2357.6 2358.0 2377.1 2341.6 2346.0 2376.2 2383.5 2388.4 2362.5 2363.8 2373.7 2383.4 2389.0 2304.4 2340.3 2350.3 2360.6 2366.5 1973.6 2341.5 2345.4 2350.6 2352.0 8629.3 8657.8 8693.6 8713.0 8724.6 5046.3 5108.7 5174.1 5181.0 5235.5 3504.7 3569.8 3629.9 3644.5 3644.6 920.2 1078.3 1110.5 1126.0 1127.2 9171.4 11289.6 11291.7 11293.9 11295.4 4566.1 4594.9 4775.4 4786.3 4788.5 9981.2 9994.3 10005.2 10038.7 10069.4 5264.2 5353.3 5354.8 5357.9 5391.3 4352.6 4764.1 4882.5 4910.5 4914.3 3953.5 3971.9 4048.9 4069.0 4092.1 1289.8 1312.6 1314.3 1321.7 1324.6 858.6 931.0 949.4 950.4 950.8 5982.9 5986.1 6056.5 6072.1 6176.7 440.2 444.8 445.5 445.6 454.4 4040.9 4061.7 4078.4 4109.4 4146.3 10273.7 10559.3 10570.5 10572.9 10660.6 10534.6 10617.8 10627.3 10645.0 10679.4 7836.5 8120.2 8125.0 8204.4 8216.5 6045.6 6046.6 6099.0 6146.5 6200.6 9762.2 9905.9 9919.9 9950.5 9958.1 9702.0 9987.1 10087.0 10147.2 10216.8 6799.6 6995.6 7354.8 7573.5 7639.0 8377.1 8401.9 8408.3 8470.2 8471.9 7467.3 7628.0 7673.0 7690.5 7748.2 7339.0 7451.5 7467.3 7568.8 7576.8 7097.1 7150.4 7152.7 7176.2 7209.2 4949.3 5044.7 5092.4 5130.0 5156.7 5108.4 5145.4 5164.8 5272.8 5282.2 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4846.38 Median:5496.17 stddev:1532.52 (27.9%) stddev-sans-first:1498.33 06:43:03 INFO - PROCESS | 1666 | Values: 298.8 312.8 316.9 317.3 319.5 5216.0 5252.6 5842.6 5893.0 5943.7 5446.9 5783.3 5793.8 5809.9 5841.1 3578.2 3967.2 4234.6 4298.2 4323.3 3564.3 4267.7 4332.9 4340.6 4350.9 3945.8 3949.4 4269.0 4271.2 4281.9 3251.0 3601.5 4211.4 4278.1 4296.9 3080.6 3423.2 3702.8 3719.1 3742.3 5614.8 5634.6 5655.0 5666.4 5681.3 4210.9 5016.6 5016.8 5043.9 5064.9 5025.8 6017.7 6067.2 6073.5 6103.9 4110.2 4110.6 4115.6 4127.7 4150.5 5286.8 5972.9 6394.8 6476.7 6484.3 4041.8 4048.8 4051.7 4067.7 4079.0 4094.8 4103.4 4123.6 4128.8 4136.2 4674.9 4781.3 4851.0 4858.8 4874.1 5378.7 5613.2 5990.6 5995.7 6062.2 1310.2 1312.0 1421.8 1425.7 1431.8 791.0 830.3 860.5 873.3 918.8 5528.8 5590.2 5681.5 5703.9 5724.8 5240.3 5309.6 6073.3 6169.6 6240.6 4823.7 5456.7 5656.7 5673.7 5710.9 2752.7 3128.7 3160.9 3289.4 3304.8 5581.7 5706.7 6176.7 6473.9 6546.1 5282.5 6075.9 6391.6 6393.6 6417.7 6169.4 6420.9 6434.3 6466.8 6489.4 5463.5 5817.5 6085.4 6228.7 6236.3 5732.4 5926.8 6097.6 6167.0 6203.6 6024.5 6402.4 6434.8 6451.5 6454.8 3715.4 3903.3 4011.9 4025.8 4067.4 6221.8 6224.8 6254.9 6270.6 6286.7 5107.9 5617.7 5763.2 5928.3 6011.6 5076.7 5449.4 6011.2 6025.9 6027.0 4658.0 4749.5 5595.7 5926.4 5946.7 5110.4 5374.5 5619.0 6110.5 6122.4 5022.5 5172.0 5986.8 5998.9 6052.3 06:43:03 INFO - PROCESS | 1666 | 06:43:03 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:24005.81 Median:3863.01 stddev:37942.80 (982.2%) stddev-sans-first:38008.72 06:43:03 INFO - PROCESS | 1666 | Values: 652.5 727.9 793.7 803.4 827.5 14396.8 15193.8 15554.3 15807.1 15840.8 12362.9 14433.6 14447.5 14454.9 14518.6 714.4 801.4 883.8 926.5 929.2 729.6 923.3 926.9 928.1 930.2 929.6 940.9 979.7 983.3 985.5 923.9 959.7 972.3 976.5 981.9 697.8 712.0 721.6 734.6 735.5 12788.3 13515.0 14660.6 14781.6 14884.9 10541.7 14740.2 14750.9 14792.5 14806.0 11818.7 11849.9 11925.3 11931.3 12068.5 3832.4 3893.6 3955.6 3959.9 4071.9 91995.1 96138.0 96506.0 107888.5 114215.4 10536.2 11072.7 11500.1 11770.1 12067.8 21418.8 21710.7 21979.1 22059.0 22078.0 7924.8 7940.6 7964.9 8119.3 8180.6 4441.1 4499.3 4525.8 4540.4 4615.1 3455.3 3536.2 3575.9 3578.6 3587.8 660.1 660.1 671.1 682.1 687.8 605.7 624.6 630.6 639.2 646.8 8604.3 8662.4 8792.7 8899.5 8978.9 573.3 591.4 615.0 626.2 639.7 4112.9 4295.9 4296.5 4325.4 4436.7 139578.0 141429.3 145044.0 145073.0 149710.0 112989.4 120720.0 121158.0 126822.4 137546.0 72968.9 95947.0 107971.9 115356.0 118196.4 8763.2 9243.3 9636.0 9662.0 9664.2 45418.0 59441.1 60954.5 60980.4 61333.7 90634.0 93181.5 93275.5 93691.0 93915.1 13935.5 13939.2 13992.7 14126.0 14473.9 13134.6 13795.4 14094.4 14567.9 14656.4 13275.3 13676.9 13765.4 13892.9 13904.0 10298.4 13069.8 13475.3 13841.9 13898.5 10688.9 13198.7 13234.4 13520.1 13564.8 10704.6 10808.5 10825.2 10967.3 11436.1 11223.5 11440.4 11441.1 11581.4 11599.5 06:43:03 INFO - PROCESS | 1666 | -------- Summary: end -------- 06:43:03 INFO - PROCESS | 1666 | 06:43:04 INFO - TEST-INFO | 1666: exit 0 06:43:04 INFO - TEST-OK | dromaeo_css | took 1112971ms 06:43:04 INFO - TEST-START | kraken 06:43:04 INFO - operating with platform_type : mac_ 06:43:04 INFO - Initialising browser for kraken test... 06:43:04 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmp3_U2yl/profile http://localhost:49265/getInfo.html) 06:43:07 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 06:43:07 INFO - PROCESS | 1675 | colorDepth:24 06:43:07 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 06:43:07 INFO - PROCESS | 1675 | __metrics 06:43:07 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 06:43:08 INFO - TEST-INFO | 1675: exit 0 06:43:08 INFO - Browser initialized. 06:43:08 INFO - Running cycle 1/1 for kraken test... 06:43:08 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmp3_U2yl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:43:20 INFO - PROCESS | 1677 | RSS: Main: 255889408 06:43:20 INFO - PROCESS | 1677 | 06:43:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 06:43:24 INFO - PROCESS | 1677 | RSS: Main: 268525568 06:43:24 INFO - PROCESS | 1677 | 06:43:26 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 06:43:27 INFO - PROCESS | 1677 | RSS: Main: 272691200 06:43:27 INFO - PROCESS | 1677 | 06:43:30 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 06:43:31 INFO - PROCESS | 1677 | RSS: Main: 273018880 06:43:31 INFO - PROCESS | 1677 | 06:43:33 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 06:43:33 INFO - PROCESS | 1677 | RSS: Main: 273969152 06:43:33 INFO - PROCESS | 1677 | 06:43:35 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:43:36 INFO - PROCESS | 1677 | RSS: Main: 274575360 06:43:36 INFO - PROCESS | 1677 | 06:43:39 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 06:43:39 INFO - PROCESS | 1677 | RSS: Main: 275046400 06:43:39 INFO - PROCESS | 1677 | 06:43:42 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 06:43:43 INFO - PROCESS | 1677 | RSS: Main: 275210240 06:43:43 INFO - PROCESS | 1677 | 06:43:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 06:43:47 INFO - PROCESS | 1677 | RSS: Main: 275521536 06:43:47 INFO - PROCESS | 1677 | 06:43:48 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:43:49 INFO - PROCESS | 1677 | RSS: Main: 275058688 06:43:49 INFO - PROCESS | 1677 | 06:43:51 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:43:51 INFO - PROCESS | 1677 | RSS: Main: 275062784 06:43:51 INFO - PROCESS | 1677 | 06:43:54 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:43:54 INFO - PROCESS | 1677 | RSS: Main: 275374080 06:43:54 INFO - PROCESS | 1677 | 06:43:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:43:58 INFO - PROCESS | 1677 | RSS: Main: 275443712 06:43:58 INFO - PROCESS | 1677 | 06:44:01 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:44:01 INFO - PROCESS | 1677 | RSS: Main: 275308544 06:44:01 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 06:44:03 INFO - PROCESS | 1677 | RSS: Main: 276205568 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | __start_tp_report 06:44:03 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 06:44:03 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 06:44:03 INFO - PROCESS | 1677 | |i|pagename|runs| 06:44:03 INFO - PROCESS | 1677 | |0;ai-astar;121;167;153;116;116;114;107;118;116;118 06:44:03 INFO - PROCESS | 1677 | |1;audio-beat-detection;142;111;137;150;108;130;129;108;153;109 06:44:03 INFO - PROCESS | 1677 | |2;audio-dft;194;199;212;247;197;195;198;190;196;200 06:44:03 INFO - PROCESS | 1677 | |3;audio-fft;70;74;71;108;64;82;82;84;140;85 06:44:03 INFO - PROCESS | 1677 | |4;audio-oscillator;69;94;150;84;73;79;115;70;77;114 06:44:03 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;91;89;90;88;90;87;91;88;91;87 06:44:03 INFO - PROCESS | 1677 | |6;imaging-darkroom;150;120;95;98;108;96;105;129;95;95 06:44:03 INFO - PROCESS | 1677 | |7;imaging-desaturate;90;94;91;99;93;92;116;142;100;140 06:44:03 INFO - PROCESS | 1677 | |8;json-parse-financial;58;56;58;57;55;57;58;56;57;57 06:44:03 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;65;71;60;95;112;107;77;71;69;64 06:44:03 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;87;86;106;103;77;84;86;84;86;94 06:44:03 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;140;138;126;137;136;139;171;151;190;158 06:44:03 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;167;167;164;166;165;165;166;164;168;226 06:44:03 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;67;65;67;78;86;89;86;93;82;67 06:44:03 INFO - PROCESS | 1677 | __end_tp_report 06:44:03 INFO - PROCESS | 1677 | __start_cc_report 06:44:03 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1116 06:44:03 INFO - PROCESS | 1677 | __end_cc_report 06:44:03 INFO - PROCESS | 1677 | __startTimestamp1469627043923__endTimestamp 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | ------- Summary: start ------- 06:44:03 INFO - PROCESS | 1677 | Number of tests: 14 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:124.60 Median:118.00 stddev:19.29 (16.3%) stddev-sans-first:20.41 06:44:03 INFO - PROCESS | 1677 | Values: 121.0 167.0 153.0 116.0 116.0 114.0 107.0 118.0 116.0 118.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:133.50 stddev:17.76 (13.3%) stddev-sans-first:18.07 06:44:03 INFO - PROCESS | 1677 | Values: 142.0 111.0 137.0 150.0 108.0 130.0 129.0 108.0 153.0 109.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:202.80 Median:198.50 stddev:16.55 (8.3%) stddev-sans-first:17.25 06:44:03 INFO - PROCESS | 1677 | Values: 194.0 199.0 212.0 247.0 197.0 195.0 198.0 190.0 196.0 200.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:86.00 Median:78.00 stddev:22.47 (28.8%) stddev-sans-first:23.08 06:44:03 INFO - PROCESS | 1677 | Values: 70.0 74.0 71.0 108.0 64.0 82.0 82.0 84.0 140.0 85.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:75.00 stddev:26.27 (35.0%) stddev-sans-first:26.45 06:44:03 INFO - PROCESS | 1677 | Values: 69.0 94.0 150.0 84.0 73.0 79.0 115.0 70.0 77.0 114.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:90.00 stddev:1.62 (1.8%) stddev-sans-first:1.58 06:44:03 INFO - PROCESS | 1677 | Values: 91.0 89.0 90.0 88.0 90.0 87.0 91.0 88.0 91.0 87.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:109.10 Median:95.00 stddev:18.55 (19.5%) stddev-sans-first:12.44 06:44:03 INFO - PROCESS | 1677 | Values: 150.0 120.0 95.0 98.0 108.0 96.0 105.0 129.0 95.0 95.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:105.70 Median:91.50 stddev:20.07 (21.9%) stddev-sans-first:20.47 06:44:03 INFO - PROCESS | 1677 | Values: 90.0 94.0 91.0 99.0 93.0 92.0 116.0 142.0 100.0 140.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.99 (1.7%) stddev-sans-first:0.97 06:44:03 INFO - PROCESS | 1677 | Values: 58.0 56.0 58.0 57.0 55.0 57.0 58.0 56.0 57.0 57.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:79.10 Median:70.00 stddev:18.69 (26.7%) stddev-sans-first:19.11 06:44:03 INFO - PROCESS | 1677 | Values: 65.0 71.0 60.0 95.0 112.0 107.0 77.0 71.0 69.0 64.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:86.00 stddev:9.03 (10.5%) stddev-sans-first:9.54 06:44:03 INFO - PROCESS | 1677 | Values: 87.0 86.0 106.0 103.0 77.0 84.0 86.0 84.0 86.0 94.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:145.50 stddev:19.41 (13.3%) stddev-sans-first:20.34 06:44:03 INFO - PROCESS | 1677 | Values: 140.0 138.0 126.0 137.0 136.0 139.0 171.0 151.0 190.0 158.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:166.50 stddev:19.09 (11.5%) stddev-sans-first:20.17 06:44:03 INFO - PROCESS | 1677 | Values: 167.0 167.0 164.0 166.0 165.0 165.0 166.0 164.0 168.0 226.0 06:44:03 INFO - PROCESS | 1677 | 06:44:03 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.00 Median:84.00 stddev:10.66 (12.7%) stddev-sans-first:10.53 06:44:03 INFO - PROCESS | 1677 | Values: 67.0 65.0 67.0 78.0 86.0 89.0 86.0 93.0 82.0 67.0 06:44:03 INFO - PROCESS | 1677 | -------- Summary: end -------- 06:44:03 INFO - PROCESS | 1677 | 06:44:04 INFO - TEST-INFO | 1677: exit 0 06:44:04 INFO - TEST-OK | kraken | took 60176ms 06:44:04 INFO - SUITE-END | took 1173s 06:44:05 INFO - Completed test suite (00:19:33) 06:44:05 INFO - javascript benchmark 06:44:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7392.536323783565, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3375.2911888503413, 3431.245126072264, 3431.6053653829786, 3439.1574064354236, 3443.122003889008, 5813.418658134185, 5875.706214689264, 5891.587588868781, 5925.555583331251, 5940.316863560692, 5766.509846663028, 5861.325947516037, 5894.528437724983, 5908.202392676987, 5963.165156878041, 5199.532042116219, 5243.79024839006, 5317.654352467097, 5325.920111198335, 5357.598180136505, 5263.763130659129, 5333.2000199970125, 5367.1680889462, 5398.595105367093, 5431.646942948715, 5153.304303918961, 5224.111900976843, 5269.104252277122, 5290.1800220965815, 5298.788048478056, 5281.155015197588, 5293.179557168645, 5295.391030031557, 5299.33758280215, 5312.043832110218, 5185.481451854822, 5259.76331065103, 5267.815626453094, 5290.312259406291, 5341.278927344836, 4657.6273898088075, 5198.428172900978, 5826.242588463515, 5866.296044474648, 5987.491063259628, 6283.874322513524, 6321.810345689591, 6338.176037115146, 6341.619502829845, 6353.745850165988, 6236.064590311489, 6254.655993920333, 6276.466500347476, 6278.77856654673, 6313.179286692746, 5893.970530147321, 5944.583879128462, 5957.4638282553915, 5959.523238140939, 6014.1580178775, 5373.247745315659, 6112.297085835097, 6404.103425520431, 6429.6142231466265, 6434.646094464756, 5034.546890779848, 6155.815325540151, 6170.784022559189, 6285.528585356116, 6332.936670633327, 6247.656378899204, 6307.873842523124, 6315.810525684236, 6332.461740751995, 6335.588186767845, 6106.764041036412, 6270.717817698214, 6274.215723034621, 6328.968355158287, 6376.585521941059, 6195.504359651217, 6212.47193988516, 6301.4328710416285, 6328.588641738273, 6343.524235682342, 5013.724245166551, 5020.071286811952, 5024.62315326352, 5036.32009678696, 5044.646874718808, 4480.596746292849, 4487.910241795146, 4498.370228168059, 4538.364628951949, 4552.2488789350045, 4893.314935908976, 5032.32063671407, 5847.44449277318, 5900.468957793819, 6010.398960103955, 6272.090546870678, 6301.117843501914, 6309.968450157811, 6321.55749097568, 6376.202974628171, 5914.0, 6044.667543285162, 6046.425589569072, 6077.6353418795015, 6078.848028799315, 5434.375046869661, 5502.477264659851, 5531.889362212733, 5541.445855414427, 5562.582806289463, 6451.580647257913, 6476.6114033158165, 6486.935130648633, 6513.088167656532, 6520.673966301761, 6136.7028951139955, 6304.18045654062, 6306.936930630635, 6312.368763123651, 6315.621062736251, 6352.460040896575, 6390.520710946605, 6422.2614399344675, 6422.903656445251, 6497.155369801895, 6196.132541444292, 6219.471344935731, 6227.097070924781, 6234.719437625408, 6237.282712488122, 6009.639421634716, 6226.501879849692, 6280.623162610258, 6311.053341998737, 6322.114903913363, 6087.878242435215, 6130.061839217998, 6167.907481387693, 6185.690715464299, 6256.343083976117, 6216.2540495139065, 6218.657973811303, 6225.501959843292, 6243.906341404973, 6246.562740608114, 5058.6206034548295, 5992.280926288873, 6069.302030266399, 6099.146119543356, 6172.938270617236, 6144.95535758931, 6167.84580385494, 6222.81331560054, 6233.189685340877, 6246.6876656167915, 5999.250093738283, 6085.452309292008, 6109.75560977556, 6116.3272040076445, 6146.508279337731], "value": 5772.997194132411, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.43654452196745, 515.724087613127, 531.1978911843117, 556.8273835111114, 605.062153661824, 30873.919412820556, 38207.42688859666, 39621.009474763145, 39677.80161099198, 39847.601523984755, 32282.67717322826, 33014.0, 41245.0, 41462.37806432906, 41464.37803432951, 8270.33837293018, 9463.716088517322, 9748.463834489101, 9749.0, 9762.609495620167, 9585.712428627152, 9658.517074146299, 9729.270304727173, 9731.318807683465, 9761.316707830454, 9651.179649729782, 9670.613175472972, 9689.806203875918, 9760.511974401286, 9782.364146330501, 9681.612735490608, 9729.659461918869, 9735.659251926185, 9736.318457707963, 9765.0, 9591.280653950982, 9607.423554586747, 9623.615055397775, 9720.07659272368, 9744.02559744027, 39608.009799754946, 39826.60173398287, 39906.00234994119, 39964.20071598552, 40057.19885602272, 2806.9438611227865, 3357.7649564530493, 3376.324735052999, 3525.94711079334, 3569.964300357015, 31409.0, 31414.685853141404, 31764.20589485258, 32044.519332210035, 32195.83902080498, 3150.5589217509337, 3198.2803869129493, 3205.5512228288057, 3211.6467188609236, 3337.2324365396094, 448647.0, 453256.0, 454065.0, 456593.0, 459354.0, 34564.61741530361, 35303.823480882435, 35328.47007294893, 35417.82291088528, 35449.0, 31943.520847187312, 35528.93413197608, 35969.0, 36021.27957440836, 36043.81978090093, 14942.850571494364, 14947.252637368305, 15062.322195501229, 15081.944263901421, 15089.868259880554, 12756.936215318863, 12810.615681529569, 12852.164609300366, 12888.48446062147, 12908.225506469642, 7762.146163921964, 7787.727429540052, 7807.531548107132, 7842.764717058498, 7856.84286314282, 3270.0, 3284.408806414868, 3290.572225610655, 3291.835408229627, 3325.7505687073567, 809.8137428391385, 998.3211416236884, 1004.372267332917, 1103.1560855945208, 1130.2540323386525, 31045.844770776002, 31134.377312453624, 31352.686473135433, 31477.685223147935, 31499.0, 939.6382392778829, 1083.7236504691252, 1157.9594714184964, 1164.7321116143335, 1268.3658170914543, 14502.927485362507, 16416.835831641532, 16457.0, 16545.50363489097, 16617.750733739005, 659698.0, 674394.0, 675167.0, 676016.0, 676450.0, 56625.0, 61821.69089154525, 61829.0, 61948.38051619426, 62469.37530624636, 1351.1015174908773, 1355.749186400519, 1385.3350391811987, 1405.2130806748253, 1418.2979425184599, 27205.31986700269, 30540.0, 30749.0, 31130.066098017094, 31399.84300078485, 45282.547174527834, 53072.73463632657, 54041.729791350794, 54226.72886635699, 54772.72613636906, 552960.0, 554798.0, 562541.0, 562775.0, 563611.0, 27668.23317668217, 28057.438851222454, 28062.579061314504, 28117.578236326062, 28118.015869444473, 50113.24830127478, 50348.0, 50475.49524504708, 51317.0, 51480.48519514757, 34440.48339274963, 41175.0, 41214.0, 41792.58207417887, 42316.0, 37383.504659813305, 40526.999258658616, 40905.18189636131, 41071.38392924168, 41891.952701182716, 39013.414798778605, 39590.802045990735, 39916.800415997735, 40176.598234017285, 41059.384109237784, 32615.836920815244, 35849.64150358568, 35874.64125358713, 35929.0, 36151.0, 22352.664710029687, 24513.877430612734, 25730.09944651928, 25749.3562660935, 25982.87008564945], "value": 18657.714655936466, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.4281962826318, 384.0877914951989, 385.0, 394.40051122294096, 402.2919661395944, 19927.402177934666, 21337.893310533465, 21660.8916955415, 22492.88753556232, 22531.887340563295, 18698.53253668659, 18848.5287867803, 19530.70703939442, 21495.355139345844, 21840.890795546, 5454.836354909359, 6025.186599809035, 6241.71912263947, 6251.812445626639, 6272.059191121345, 3596.2268112355837, 6683.799486015428, 7025.051618031552, 7026.367626913577, 7099.964500177492, 6896.310368963089, 6987.481177037732, 6994.8950765738555, 7007.544509606884, 7041.119860017498, 6750.62871542065, 6783.762568310135, 6914.654267286615, 6956.478264130211, 6967.6167810770385, 5849.473547380756, 5893.204417403638, 5920.792772252994, 5934.050551911673, 5934.5845790794665, 23919.0, 26914.461710765674, 27242.31894202641, 27351.0, 27397.041103561478, 2307.849989750661, 2440.414300567869, 2544.1731437282774, 2679.5310820606314, 2828.9391478195676, 15511.99172053813, 15973.361065557363, 16270.186490675418, 16321.83678163215, 16383.83616163835, 1847.4919397165752, 1884.2086323743924, 1901.1482775836246, 1907.1703808843197, 1928.7203355513373, 116277.0, 121368.17947730792, 121460.0, 121836.0, 121947.0, 10737.624183153708, 11202.831957520644, 11227.27022743519, 11243.83134252987, 11332.43337833105, 13712.588622341345, 13765.242911639956, 13767.31163441844, 13769.31153442324, 13783.51757688476, 7434.182239953709, 7545.6981720730555, 7570.5836179010685, 7571.318581327707, 7648.885266721004, 7137.1435427747965, 7150.356467917913, 7175.533590316717, 7202.53183543068, 7223.566586004857, 5928.851778705567, 6730.607270601497, 6731.1922569292, 6773.458123350219, 6894.793156205322, 1790.6239689665317, 1907.4468404162637, 1911.4838993471685, 1933.5069557262807, 1954.8631595788443, 797.7686470923368, 951.6050838901916, 1004.3070281505737, 1063.6818687430582, 1127.3067063755732, 21939.561208775736, 22342.441438964157, 22798.65802012971, 22882.427939301317, 22996.4250893729, 952.8808898887639, 1029.248648486609, 1046.4192373232777, 1085.8751243606928, 1111.6942840718705, 9304.25565954722, 10388.37669739803, 11367.374794386387, 12062.276263424048, 12355.135140540257, 588428.0, 588855.0, 594789.0, 597770.0, 636754.0, 65235.69528609496, 101091.4945425283, 108294.0, 108648.0, 109038.0, 908.886389201363, 1038.522279751323, 1062.654637242899, 1104.8066588347008, 1120.4621781544904, 16434.342626294816, 17762.644747105245, 19930.800691992896, 20260.696089558372, 20371.694424583347, 33141.84578002422, 43130.78434607807, 51097.48902510927, 52470.73764631152, 53281.93436131333, 70132.29867701257, 70145.29854701388, 70216.64891675509, 71512.28487715057, 73733.26266737263, 6364.363563643598, 6461.224653041665, 6892.965535172292, 8012.799680008046, 8239.176082391712, 13085.214887106804, 13887.236202008986, 13918.51285205013, 14050.648733781329, 14140.78788818189, 11815.409229538316, 11842.0, 11944.223625464318, 11954.760904781682, 12041.638750837488, 9185.816283674423, 9530.523473826142, 11375.715607109889, 11547.4803633835, 11628.18602697803, 11972.940135299268, 12066.457009434422, 12148.453319600463, 12172.087093467848, 12212.87787122153, 7577.734779282699, 7664.69341226367, 7865.84268314619, 7866.331361834308, 7875.84248315042, 11678.532858685558, 11876.406179690808, 11894.167408281337, 11914.344711040976, 12040.518379265082], "value": 9893.585587670721, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1553.0, 1561.313022270201, 1580.1941010084856, 1906.2375049980014, 1909.95225119372, 7870.370370370371, 8290.668373265062, 8574.399792014554, 9036.322275829323, 9046.004939456663, 8409.285210757078, 8482.575871206445, 8548.358873084513, 8586.22723954844, 8710.085441028696, 2316.3166865774597, 2356.705411823522, 2357.57563638545, 2358.0449917783326, 2377.084822343393, 2341.5785158671433, 2345.95308093838, 2376.239603326936, 2383.511380167062, 2388.414838364603, 2362.456634973957, 2363.8108951283857, 2373.6914201153913, 2383.392234980078, 2389.0443822471066, 2304.3893368257427, 2340.32130682102, 2350.330155905567, 2360.6104992676273, 2366.538524987628, 1973.595412940358, 2341.4731685370825, 2345.3784747042046, 2350.6239001759636, 2352.0, 8629.30965522763, 8657.783555411102, 8693.608787604573, 8712.956435217846, 8724.563771811385, 5046.26829109777, 5108.667936584147, 5174.120399532088, 5180.989707007135, 5235.502627250406, 3504.702100321476, 3569.839357228931, 3629.94555081674, 3644.5079914211506, 3644.599094099647, 920.2362039507193, 1078.2721662877605, 1110.4947249001684, 1125.983110253347, 1127.216584473791, 9171.403858749227, 11289.604863829809, 11291.717707057389, 11293.887061129366, 11295.378754168518, 4566.086782643484, 4594.931076033862, 4775.355327030807, 4786.329913812069, 4788.497207793202, 9981.201503879673, 9994.300398972002, 10005.249606279558, 10038.698839034842, 10069.446180459998, 5264.184051472028, 5353.250544923714, 5354.759035843396, 5357.919631205535, 5391.29913111293, 4352.564743525622, 4764.142454358249, 4882.487338829443, 4910.508949105061, 4914.33656456381, 3953.466282051886, 3971.8808435746973, 4048.919021619551, 4069.04377471294, 4092.0588264699286, 1289.8000809874484, 1312.6324629103867, 1314.309987256698, 1321.662975941129, 1324.6158614002024, 858.6261980830621, 930.9805762689844, 949.3876449689911, 950.3917492804611, 950.7955789505289, 5982.880342393128, 5986.132010858489, 6056.454919057306, 6072.05883088122, 6176.69116544171, 440.17247574560264, 444.8443044934247, 445.45654301751813, 445.5544455544456, 454.3502791008889, 4040.878773636796, 4061.654759345489, 4078.408630748525, 4109.4246805447265, 4146.274401979642, 10273.74315642115, 10559.260851740459, 10570.471476426148, 10572.89427105719, 10660.62687805923, 10534.631287904887, 10617.84073238902, 10627.309224900357, 10644.988726071011, 10679.359238445562, 7836.451448398558, 8120.187981201832, 8125.0, 8204.425690201748, 8216.507009579444, 6045.6070355426755, 6046.606970546901, 6099.0, 6146.477549408351, 6200.627962322275, 9762.219022478042, 9905.851412228967, 9919.851202232117, 9950.502474876372, 9958.053984871425, 9701.951490242503, 9987.051230133126, 10086.991300869855, 10147.18822494184, 10216.846747298945, 6799.626020568916, 6995.615241161785, 7354.77935661931, 7573.507721998052, 7639.0, 8377.078521362768, 8401.915980840113, 8408.285295749929, 8470.237678608955, 8471.872921906299, 7467.253274672489, 7628.0, 7673.040869891263, 7690.461667683432, 7748.225177482207, 7339.0, 7451.478396512409, 7467.327940485382, 7568.772936811904, 7576.772696819105, 7097.148342198969, 7150.427965762622, 7152.713891444284, 7176.174739904978, 7209.243029482039, 4949.307097006494, 5044.738815296176, 5092.414372347227, 5130.0, 5156.7421628917655, 5108.438071812024, 5145.408278048072, 5164.8192313268855, 5272.815451459181, 5282.20766884955], "value": 4521.6537731657645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.7818892208688, 312.84826858973395, 316.939781441526, 317.32093320294575, 319.5430534335899, 5216.035033518801, 5252.553532949699, 5842.620229685068, 5893.0, 5943.662675897921, 5446.945530544694, 5783.306003279612, 5793.826185214451, 5809.912851307234, 5841.136664419311, 3578.2306804037125, 3967.1867267210296, 4234.5765423457715, 4298.247806633843, 4323.265044942367, 3564.2515071834946, 4267.679924005712, 4332.913341733163, 4340.631046361063, 4350.912271932017, 3945.763254204757, 3949.413134396975, 4269.0, 4271.231178387889, 4281.850135245267, 3251.024692592213, 3601.549806274216, 4211.368294755781, 4278.080212754273, 4296.914061718748, 3080.6303243610687, 3423.2126610879636, 3702.8148592570265, 3719.1074142205675, 3742.3450896093345, 5614.79956424838, 5634.605577609569, 5654.982103221419, 5666.4217780909585, 5681.318241810968, 4210.873673789792, 5016.648834581581, 5016.799328026875, 5043.856361046541, 5064.949350506521, 5025.844055867135, 6017.69911504423, 6067.180930574405, 6073.51411887048, 6103.901297766401, 4110.212057754886, 4110.602395185621, 4115.629593336614, 4127.690423218271, 4150.522689890641, 5286.81496147633, 5972.910406343908, 6394.793796140324, 6476.740930362827, 6484.319146489598, 4041.8383264669674, 4048.7570745755347, 4051.6556092731857, 4067.7355971862326, 4078.9592104079175, 4094.8362065517044, 4103.379165229718, 4123.649489793401, 4128.772917489567, 4136.234796562646, 4674.878029273018, 4781.306710526954, 4850.981293928244, 4858.82994095205, 4874.073925954128, 5378.731063446812, 5613.214150018919, 5990.6405615662325, 5995.670238136944, 6062.242219722535, 1310.1811367895066, 1312.0422091872986, 1421.8080559124592, 1425.672095418039, 1431.8138641976684, 790.952542847431, 830.2818062376001, 860.4536119564121, 873.3144481581928, 918.7657147427363, 5528.834134975958, 5590.161475778666, 5681.545476361881, 5703.857403564944, 5724.77100915967, 5240.266362709223, 5309.628326017219, 6073.301570319381, 6169.629822210682, 6240.625562466266, 4823.662343635982, 5456.699881506555, 5656.688882111441, 5673.687947162863, 5710.942890571124, 2752.6696796384563, 3128.6859519002073, 3160.8577614006963, 3289.3914625794305, 3304.7851889627095, 5581.71620527281, 5706.717313074803, 6176.722047507873, 6473.935260647333, 6546.0835483032415, 5282.471752824764, 6075.939240607626, 6391.6258004529245, 6393.6163830170335, 6417.679116044179, 6169.352218017044, 6420.913203208883, 6434.324395938546, 6466.838329041811, 6489.448396886223, 5463.508284254436, 5817.476427121579, 6085.421884920926, 6228.657423841732, 6236.251649802053, 5732.398098199629, 5926.843646027984, 6097.634141951497, 6167.013277875519, 6203.627782333075, 6024.518038556993, 6402.35976402356, 6434.806955791334, 6451.451626611791, 6454.774082907075, 3715.442683597482, 3903.2974064668633, 4011.876674531136, 4025.8389664413094, 4067.4305597217003, 6221.751129954932, 6224.844378890564, 6254.937450625435, 6270.641903876762, 6286.717097730704, 5107.872303192301, 5617.691026993554, 5763.193152958505, 5928.347881733092, 6011.579189456696, 5076.746162691777, 5449.373322068013, 6011.248593925759, 6025.896663218358, 6027.022165370834, 4658.0, 4749.477557468606, 5595.720213989365, 5926.436988486087, 5946.651072826123, 5110.386753589593, 5374.4625537445945, 5618.971905140612, 6110.480609148272, 6122.448979591858, 5022.522860328263, 5172.017316709813, 5986.820395388146, 5998.910016349845, 6052.273727152769], "value": 4345.953038812344, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.4682383857156, 727.8507905879292, 793.6666600027992, 803.4135081390589, 827.5407149032285, 14396.776274016722, 15193.848061519382, 15554.300056497457, 15807.130607816565, 15840.762388564166, 12362.876371236285, 14433.639159021004, 14447.494337698183, 14454.927725361358, 14518.637034074154, 714.4213187318263, 801.4029547986729, 883.8188171424874, 926.5182105305271, 929.2287401456774, 729.5513259345491, 923.3213588012806, 926.8702381666573, 928.109015345266, 930.2279108340061, 929.5584597316288, 940.9106134917206, 979.6913972098802, 983.2969426859794, 985.5367977050811, 923.9307051971061, 959.6785076999214, 972.2756546372962, 976.5166242709834, 981.8527220916848, 697.8360085379983, 711.9857602847914, 721.5526373648319, 734.5555938657089, 735.5219107580062, 12788.296643684595, 13514.986376021838, 14660.56018319452, 14781.556553303311, 14884.925575372054, 10541.73645658857, 14740.189289589069, 14750.926245368812, 14792.482263120846, 14806.037607555581, 11818.704532386673, 11849.881501184964, 11925.34410607425, 11931.343776092312, 12068.456919438646, 3832.386818109117, 3893.6301051400073, 3955.64399204073, 3959.8614048508166, 4071.89820254496, 91995.08004919931, 96138.0, 96506.0, 107888.4605576975, 114215.42892285486, 10536.209784266211, 11072.723181920355, 11500.137489688139, 11770.117241206772, 12067.818982715266, 21418.78581214168, 21710.674339884914, 21979.120835166734, 22059.0, 22078.0, 7924.84150317002, 7940.5598045096, 7964.920350796534, 8119.309858661962, 8180.6318715657935, 4441.111777644484, 4499.325101234841, 4525.773711314487, 4540.432445944257, 4615.053913947632, 3455.3434847378917, 3536.1513236823494, 3575.910602234965, 3578.624244454347, 3587.802670853076, 660.0758937487556, 660.1352228580574, 671.1342368344878, 682.0757873082001, 687.814290141659, 605.6910975402576, 624.643952946816, 630.6027202862167, 639.2201514152727, 646.8124243969196, 8604.259810608666, 8662.350323725746, 8792.677089980814, 8899.510526920953, 8978.910210897939, 573.3349314794901, 591.3642833953431, 614.966176860277, 626.2078470734524, 639.6641763074423, 4112.876613701594, 4295.935560966588, 4296.505901821268, 4325.440775885329, 4436.6672499562665, 139578.0, 141429.29285353507, 145044.0, 145073.0, 149710.0, 112989.4350528242, 120720.0, 121158.0, 126822.36588817181, 137546.0, 72968.90546641698, 95947.0, 107971.92028079776, 115356.0, 118196.40901795436, 8763.211310982042, 9243.260539156718, 9635.951820240854, 9661.985491523288, 9664.178544823768, 45418.0, 59441.10838337515, 60954.4761380969, 60980.39019609747, 61333.69333153305, 90634.0, 93181.53409232911, 93275.53362233145, 93691.0, 93915.06084939337, 13935.512257070954, 13939.16365018102, 13992.720145596828, 14126.0, 14473.8938229318, 13134.60596182116, 13795.379207935466, 14094.436222550983, 14567.854321456649, 14656.413743450568, 13275.345857432787, 13676.863231367559, 13765.38055787472, 13892.861071389158, 13904.0, 10298.433586152834, 13069.803952940523, 13475.326233688473, 13841.930790345983, 13898.51355202563, 10688.89311106879, 13198.670033248862, 13234.404451799885, 13520.053596248168, 13564.79652805189, 10704.63128602682, 10808.513616887416, 10825.188110891557, 10967.287126336763, 11436.085113191091, 11223.494942727433, 11440.427978601203, 11441.14191435629, 11581.419779392103, 11599.536018559163], "value": 7793.33510288097, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 167.0, 153.0, 116.0, 116.0, 114.0, 107.0, 118.0, 116.0, 118.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 137.0, 150.0, 108.0, 130.0, 129.0, 108.0, 153.0, 109.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 212.0, 247.0, 197.0, 195.0, 198.0, 190.0, 196.0, 200.0], "value": 202.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 71.0, 108.0, 64.0, 82.0, 82.0, 84.0, 140.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 94.0, 150.0, 84.0, 73.0, 79.0, 115.0, 70.0, 77.0, 114.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 90.0, 88.0, 90.0, 87.0, 91.0, 88.0, 91.0, 87.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 95.0, 98.0, 108.0, 96.0, 105.0, 129.0, 95.0, 95.0], "value": 109.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 91.0, 99.0, 93.0, 92.0, 116.0, 142.0, 100.0, 140.0], "value": 105.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 57.0, 55.0, 57.0, 58.0, 56.0, 57.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 60.0, 95.0, 112.0, 107.0, 77.0, 71.0, 69.0, 64.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 106.0, 103.0, 77.0, 84.0, 86.0, 84.0, 86.0, 94.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 126.0, 137.0, 136.0, 139.0, 171.0, 151.0, 190.0, 158.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 164.0, 166.0, 165.0, 165.0, 166.0, 164.0, 168.0, 226.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 78.0, 86.0, 89.0, 86.0, 93.0, 82.0, 67.0], "value": 78.0, "unit": "score"}]}]} 06:44:05 INFO - Return code: 0 06:44:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:44:05 ERROR - # TBPL SUCCESS # 06:44:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:44:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:44:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:44:05 INFO - 2016-07-27 06:44:05.232 screenresolution[1682:11119] starting screenresolution argv=screenresolution get 06:44:05 INFO - 2016-07-27 06:44:05.235 screenresolution[1682:11119] Display 0: 1600x1200x32@60 06:44:05 INFO - 2016-07-27 06:44:05.241 screenresolution[1683:11122] starting screenresolution argv=screenresolution list 06:44:05 INFO - Available Modes on Display 0 06:44:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:44:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:44:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:44:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:44:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:44:05 INFO - Intel Iris: 06:44:05 INFO - Chipset Model: Intel Iris 06:44:05 INFO - Type: GPU 06:44:05 INFO - Bus: Built-In 06:44:05 INFO - VRAM (Dynamic, Max): 1536 MB 06:44:05 INFO - Vendor: Intel (0x8086) 06:44:05 INFO - Device ID: 0x0a2e 06:44:05 INFO - Revision ID: 0x0009 06:44:05 INFO - Displays: 06:44:05 INFO - Display: 06:44:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:44:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:44:05 INFO - Display Serial Number: 1600x1200 60 06:44:05 INFO - Main Display: Yes 06:44:05 INFO - Mirror: Off 06:44:05 INFO - Online: Yes 06:44:05 INFO - Rotation: Supported 06:44:05 INFO - Return code: 0 06:44:05 INFO - Running post-action listener: _resource_record_post_action 06:44:05 INFO - ##### 06:44:05 INFO - ##### Finished run-tests step (success) 06:44:05 INFO - ##### 06:44:05 INFO - Running post-run listener: _resource_record_post_run 06:44:05 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 31550976; Write bytes: 709711360; Read time: 321; Write time: 1891 06:44:05 INFO - TinderboxPrint: CPU usage
54.3% 06:44:05 INFO - TinderboxPrint: I/O read bytes / time
31,550,976 / 321 06:44:05 INFO - TinderboxPrint: I/O write bytes / time
709,711,360 / 1,891 06:44:05 INFO - TinderboxPrint: CPU idle
2,208.8 (45.7%) 06:44:05 INFO - TinderboxPrint: CPU system
978.7 (20.2%) 06:44:05 INFO - TinderboxPrint: CPU user
1,650.2 (34.1%) 06:44:05 INFO - TinderboxPrint: Swap in / out
434,925,568 / 0 06:44:05 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 406960640; Write bytes: 402900992; Read time: 30989; Write time: 769 06:44:05 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 29314560; Write bytes: 297020928; Read time: 294; Write time: 1090 06:44:05 INFO - Running post-run listener: _upload_blobber_files 06:44:05 INFO - Blob upload gear active. 06:44:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:44:05 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 06:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:44:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:44:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:44:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:44:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:07 INFO - (blobuploader) - INFO - Done attempting. 06:44:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:44:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:08 INFO - (blobuploader) - INFO - Done attempting. 06:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:44:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:10 INFO - (blobuploader) - INFO - Done attempting. 06:44:10 INFO - (blobuploader) - INFO - Iteration through files over. 06:44:10 INFO - Return code: 0 06:44:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:44:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:44:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb816f4c3cd7c179cb1ebaa059ba9b4f4ca1d4bd8c8283530179b5bb1ea1f2b07998257434c9ea83f2313e2e95e3cd5983e2e3c0ac72d790a633987794c2619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37afed357edc2daa1bc34d514a82a4c94c8f9ce26a41ed3f4a0126728fe583c62fbdc234f3bcd6880e6907f81a42583a5d80822361884a8452086732d9b08bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e556f840cc583e4a2f6b2cb4e4192f6df97a3ab38f67e3e682602f1e37a7c01a0d07219f4b5c3919ed2c099dad533b2ad76599819ee719047ec862fe124b45d"} 06:44:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:44:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:44:10 INFO - Contents: 06:44:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb816f4c3cd7c179cb1ebaa059ba9b4f4ca1d4bd8c8283530179b5bb1ea1f2b07998257434c9ea83f2313e2e95e3cd5983e2e3c0ac72d790a633987794c2619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37afed357edc2daa1bc34d514a82a4c94c8f9ce26a41ed3f4a0126728fe583c62fbdc234f3bcd6880e6907f81a42583a5d80822361884a8452086732d9b08bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e556f840cc583e4a2f6b2cb4e4192f6df97a3ab38f67e3e682602f1e37a7c01a0d07219f4b5c3919ed2c099dad533b2ad76599819ee719047ec862fe124b45d"} 06:44:10 INFO - Running post-run listener: copy_logs_to_upload_dir 06:44:10 INFO - Copying logs to upload dir... 06:44:10 INFO - mkdir: /builds/slave/test/build/upload/logs 06:44:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.078597 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-27 06:44:10.952846) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:10.955929) ========= 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.tQr8uQxcjF/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.BqSehrtWes/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb816f4c3cd7c179cb1ebaa059ba9b4f4ca1d4bd8c8283530179b5bb1ea1f2b07998257434c9ea83f2313e2e95e3cd5983e2e3c0ac72d790a633987794c2619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37afed357edc2daa1bc34d514a82a4c94c8f9ce26a41ed3f4a0126728fe583c62fbdc234f3bcd6880e6907f81a42583a5d80822361884a8452086732d9b08bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e556f840cc583e4a2f6b2cb4e4192f6df97a3ab38f67e3e682602f1e37a7c01a0d07219f4b5c3919ed2c099dad533b2ad76599819ee719047ec862fe124b45d"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011059 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb816f4c3cd7c179cb1ebaa059ba9b4f4ca1d4bd8c8283530179b5bb1ea1f2b07998257434c9ea83f2313e2e95e3cd5983e2e3c0ac72d790a633987794c2619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37afed357edc2daa1bc34d514a82a4c94c8f9ce26a41ed3f4a0126728fe583c62fbdc234f3bcd6880e6907f81a42583a5d80822361884a8452086732d9b08bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e556f840cc583e4a2f6b2cb4e4192f6df97a3ab38f67e3e682602f1e37a7c01a0d07219f4b5c3919ed2c099dad533b2ad76599819ee719047ec862fe124b45d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:10.989089) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:10.989415) ========= 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.tQr8uQxcjF/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.BqSehrtWes/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005204 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:11.038312) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 06:44:11.040832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 06:44:11.041172) ========= ========= Total master_lag: 0.19 =========