builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0054 starttime: 1471819187.32 results: success (0) buildid: 20160721030203 builduid: c12711786ada4111b5ccdb7baf1ca68e revision: d224fc999cb6accb208af0a105f14433375e2e77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.315688) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.316387) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.316727) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.355067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.355445) ========= 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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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-08-21 15:39:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.3M=0.001s 2016-08-21 15:39:47 (15.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.533891 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.923663) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.923990) ========= 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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.028375 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:47.976147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-21 15:39:47.976956) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d224fc999cb6accb208af0a105f14433375e2e77 --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 d224fc999cb6accb208af0a105f14433375e2e77 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eZS3xejk4g/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.0Z1RtJkpUY/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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-08-21 15:39:48,051 truncating revision to first 12 chars 2016-08-21 15:39:48,051 Setting DEBUG logging. 2016-08-21 15:39:48,052 attempt 1/10 2016-08-21 15:39:48,052 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d224fc999cb6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-21 15:39:49,426 unpacking tar archive at: mozilla-central-d224fc999cb6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.632669 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-21 15:39:49.634357) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:49.634744) ========= script_repo_revision: d224fc999cb6accb208af0a105f14433375e2e77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:49.635242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:49.635575) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 15:39:49.654419) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-21 15:39:49.654778) ========= /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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:39:49 INFO - MultiFileLogger online at 20160821 15:39:49 in /builds/slave/test 15:39:49 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 15:39:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:49 INFO - {'append_to_log': False, 15:39:49 INFO - 'base_work_dir': '/builds/slave/test', 15:39:49 INFO - 'blob_upload_branch': 'Firefox', 15:39:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:49 INFO - 'branch': 'Firefox', 15:39:49 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:49 INFO - 'config_files': ('talos/mac_config.py',), 15:39:49 INFO - 'default_actions': ('clobber', 15:39:49 INFO - 'read-buildbot-config', 15:39:49 INFO - 'download-and-extract', 15:39:49 INFO - 'populate-webroot', 15:39:49 INFO - 'create-virtualenv', 15:39:49 INFO - 'install', 15:39:49 INFO - 'run-tests'), 15:39:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:49 INFO - 'download_minidump_stackwalk': True, 15:39:49 INFO - 'download_symbols': 'ondemand', 15:39:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:49 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:49 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:49 INFO - 'installer_path': 'installer.exe', 15:39:49 INFO - 'log_level': 'info', 15:39:49 INFO - 'log_name': 'talos', 15:39:49 INFO - 'log_to_console': True, 15:39:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:39:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:39:49 INFO - 'opt_config_files': (), 15:39:49 INFO - 'pip_index': False, 15:39:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:49 INFO - 'cmd': ('bash', 15:39:49 INFO - '-c', 15:39:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:49 INFO - 'enabled': True, 15:39:49 INFO - 'halt_on_failure': False, 15:39:49 INFO - 'name': 'check_screen_resolution'},), 15:39:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:49 INFO - 'cmd': ('bash', 15:39:49 INFO - '-c', 15:39:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:49 INFO - 'enabled': True, 15:39:49 INFO - 'halt_on_failure': False, 15:39:49 INFO - 'name': 'check_screen_resolution'},), 15:39:49 INFO - 'run_cmd_checks_enabled': True, 15:39:49 INFO - 'sps_profile': False, 15:39:49 INFO - 'sps_profile_interval': 0, 15:39:49 INFO - 'suite': 'dromaeojs-e10s', 15:39:49 INFO - 'system_bits': '32', 15:39:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:49 INFO - 'title': 't-yosemite-r7-0054', 15:39:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:49 INFO - 'use_talos_json': True, 15:39:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:49 INFO - 'work_dir': 'build'} 15:39:49 INFO - [mozharness: 2016-08-21 22:39:49.843660Z] Running clobber step. 15:39:49 INFO - Running pre-action listener: _resource_record_pre_action 15:39:49 INFO - Running main action method: clobber 15:39:49 INFO - rmtree: /builds/slave/test/build 15:39:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:51 INFO - Running post-action listener: _resource_record_post_action 15:39:51 INFO - [mozharness: 2016-08-21 22:39:51.900751Z] Finished clobber step (success) 15:39:51 INFO - [mozharness: 2016-08-21 22:39:51.900932Z] Running read-buildbot-config step. 15:39:51 INFO - Running pre-action listener: _resource_record_pre_action 15:39:51 INFO - Running main action method: read_buildbot_config 15:39:51 INFO - Using buildbot properties: 15:39:51 INFO - { 15:39:51 INFO - "project": "", 15:39:51 INFO - "product": "firefox", 15:39:51 INFO - "script_repo_revision": "production", 15:39:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:39:51 INFO - "repository": "", 15:39:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:39:51 INFO - "buildid": "20160721030203", 15:39:51 INFO - "stage_platform": "macosx64", 15:39:51 INFO - "basedir": "/builds/slave/test", 15:39:51 INFO - "nightly_build": "True", 15:39:51 INFO - "buildnumber": 34, 15:39:51 INFO - "slavename": "t-yosemite-r7-0054", 15:39:51 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:39:51 INFO - "platform": "yosemite_r7", 15:39:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:39:51 INFO - "branch": "mozilla-central", 15:39:51 INFO - "repo_path": "mozilla-central", 15:39:51 INFO - "revision": "d224fc999cb6accb208af0a105f14433375e2e77", 15:39:51 INFO - "pgo_build": "False", 15:39:51 INFO - "builduid": "c12711786ada4111b5ccdb7baf1ca68e", 15:39:51 INFO - "slavebuilddir": "test" 15:39:51 INFO - } 15:39:51 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg. 15:39:51 INFO - Running post-action listener: _resource_record_post_action 15:39:51 INFO - [mozharness: 2016-08-21 22:39:51.904473Z] Finished read-buildbot-config step (success) 15:39:51 INFO - [mozharness: 2016-08-21 22:39:51.904614Z] Running download-and-extract step. 15:39:51 INFO - Running pre-action listener: _resource_record_pre_action 15:39:51 INFO - Running main action method: download_and_extract 15:39:51 INFO - mkdir: /builds/slave/test/build/tests 15:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:51 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.test_packages.json 15:39:51 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:39:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/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 15:39:52 INFO - Downloaded 1199 bytes. 15:39:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:39:52 INFO - Using the following test package requirements: 15:39:52 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:39:52 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 15:39:52 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 15:39:52 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'jsshell-mac.zip'], 15:39:52 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 15:39:52 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:39:52 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 15:39:52 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 15:39:52 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 15:39:52 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:52 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 15:39:52 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:52 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:52 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/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 15:39:52 INFO - Downloaded 18843014 bytes. 15:39:52 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'] 15:39:52 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 15:39:55 INFO - Return code: 0 15:39:55 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 15:39: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')]} 15:39:55 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:55 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/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 15:39:56 INFO - Downloaded 18843014 bytes. 15:39:56 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'] 15:39:56 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 15:39:59 INFO - Return code: 0 15:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:59 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.talos.tests.zip 15:39:59 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:39:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/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 15:40:02 INFO - Downloaded 14403317 bytes. 15:40:02 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'] 15:40:02 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 15:40:03 INFO - Return code: 0 15:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:03 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg 15:40:03 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 15:40:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 15:40:07 INFO - Downloaded 113051677 bytes. 15:40:07 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg 15:40:07 INFO - mkdir: /builds/slave/test/properties 15:40:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:40:07 INFO - Writing to file /builds/slave/test/properties/build_url 15:40:07 INFO - Contents: 15:40:07 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg 15:40:08 INFO - Running post-action listener: _resource_record_post_action 15:40:08 INFO - Running post-action listener: set_extra_try_arguments 15:40:08 INFO - [mozharness: 2016-08-21 22:40:08.259449Z] Finished download-and-extract step (success) 15:40:08 INFO - [mozharness: 2016-08-21 22:40:08.259572Z] Running populate-webroot step. 15:40:08 INFO - Running pre-action listener: _resource_record_pre_action 15:40:08 INFO - Running main action method: populate_webroot 15:40:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:40:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:40:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:40:08 INFO - '--tppagecycles', 15:40:08 INFO - '7'], 15:40:08 INFO - 'tests': ['tsvgm']}}, 15:40:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:40:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:40:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:40:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:40:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['damp', 'tps']}, 15:40:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'tests': ['damp', 'tps']}, 15:40:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['dromaeo_dom']}, 15:40:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:40:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['basic_compositor_video']}, 15:40:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:40:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['a11yr', 15:40:08 INFO - 'ts_paint', 15:40:08 INFO - 'tpaint', 15:40:08 INFO - 'sessionrestore', 15:40:08 INFO - 'sessionrestore_no_auto_restore', 15:40:08 INFO - 'tabpaint']}, 15:40:08 INFO - 'other-e10s': {'tests': ['a11yr', 15:40:08 INFO - 'ts_paint', 15:40:08 INFO - 'tpaint', 15:40:08 INFO - 'sessionrestore', 15:40:08 INFO - 'sessionrestore_no_auto_restore', 15:40:08 INFO - 'tabpaint']}, 15:40:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:40:08 INFO - 'ts_paint', 15:40:08 INFO - 'tpaint', 15:40:08 INFO - 'sessionrestore', 15:40:08 INFO - 'sessionrestore_no_auto_restore', 15:40:08 INFO - 'tabpaint']}, 15:40:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:40:08 INFO - 'ts_paint', 15:40:08 INFO - 'tpaint', 15:40:08 INFO - 'sessionrestore', 15:40:08 INFO - 'sessionrestore_no_auto_restore', 15:40:08 INFO - 'tabpaint']}, 15:40:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['a11yr', 15:40:08 INFO - 'ts_paint', 15:40:08 INFO - 'tpaint', 15:40:08 INFO - 'sessionrestore', 15:40:08 INFO - 'sessionrestore_no_auto_restore', 15:40:08 INFO - 'tabpaint']}, 15:40:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['a11yr', 15:40:08 INFO - 'ts_paint', 15:40:08 INFO - 'tpaint', 15:40:08 INFO - 'sessionrestore', 15:40:08 INFO - 'sessionrestore_no_auto_restore', 15:40:08 INFO - 'tabpaint']}, 15:40:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['tsvgx', 15:40:08 INFO - 'tsvgr_opacity', 15:40:08 INFO - 'tart', 15:40:08 INFO - 'tscrollx', 15:40:08 INFO - 'cart']}, 15:40:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:40:08 INFO - 'tsvgr_opacity', 15:40:08 INFO - 'tart', 15:40:08 INFO - 'tscrollx', 15:40:08 INFO - 'cart']}, 15:40:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'talos_options': ['--disable-e10s'], 15:40:08 INFO - 'tests': ['tp5o']}, 15:40:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'tests': ['tp5o']}, 15:40:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'talos_options': ['--disable-e10s', 15:40:08 INFO - '--xperf_path', 15:40:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:08 INFO - 'tests': ['tp5n']}, 15:40:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:08 INFO - 'talos_options': ['--xperf_path', 15:40:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:08 INFO - 'tests': ['tp5n']}}, 15:40:08 INFO - 'talos.zip': {'path': '', 15:40:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:40:08 INFO - Running post-action listener: _resource_record_post_action 15:40:08 INFO - [mozharness: 2016-08-21 22:40:08.278306Z] Finished populate-webroot step (success) 15:40:08 INFO - [mozharness: 2016-08-21 22:40:08.278419Z] Running create-virtualenv step. 15:40:08 INFO - Running pre-action listener: _resource_record_pre_action 15:40:08 INFO - Running main action method: create_virtualenv 15:40:08 INFO - Creating virtualenv /builds/slave/test/build/venv 15:40:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:40:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:40:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:40:08 INFO - Using real prefix '/tools/python27' 15:40:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:40:09 INFO - Installing distribute.............................................................................................................................................................................................done. 15:40:11 INFO - Installing pip.................done. 15:40:11 INFO - Return code: 0 15:40:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:11 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:40:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:11 INFO - 'HOME': '/Users/cltbld', 15:40:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:11 INFO - 'LOGNAME': 'cltbld', 15:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:12 INFO - 'MOZ_NO_REMOTE': '1', 15:40:12 INFO - 'NO_EM_RESTART': '1', 15:40:12 INFO - 'PAGER': '/bin/cat', 15:40:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:12 INFO - 'PWD': '/builds/slave/test', 15:40:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:12 INFO - 'SHELL': '/bin/bash', 15:40:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:12 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:12 INFO - 'USER': 'cltbld', 15:40:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:12 INFO - 'XPC_FLAGS': '0x0', 15:40:12 INFO - 'XPC_SERVICE_NAME': '0', 15:40:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:40:12 INFO - Cleaning up... 15:40:12 INFO - Return code: 0 15:40:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:12 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:12 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 15:40:12 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 15:40:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:12 INFO - 'HOME': '/Users/cltbld', 15:40:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:12 INFO - 'LOGNAME': 'cltbld', 15:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:12 INFO - 'MOZ_NO_REMOTE': '1', 15:40:12 INFO - 'NO_EM_RESTART': '1', 15:40:12 INFO - 'PAGER': '/bin/cat', 15:40:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:12 INFO - 'PWD': '/builds/slave/test', 15:40:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:12 INFO - 'SHELL': '/bin/bash', 15:40:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:12 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:12 INFO - 'USER': 'cltbld', 15:40:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:12 INFO - 'XPC_FLAGS': '0x0', 15:40:12 INFO - 'XPC_SERVICE_NAME': '0', 15:40:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:12 INFO - Downloading/unpacking psutil>=3.1.1 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:40:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:16 INFO - Installing collected packages: psutil 15:40:16 INFO - Running setup.py install for psutil 15:40:16 INFO - building 'psutil._psutil_osx' extension 15:40:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:40:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:40:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:40:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:40:17 INFO - building 'psutil._psutil_posix' extension 15:40:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:40:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:40:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:40:17 INFO - ^ 15:40:17 INFO - 1 warning generated. 15:40:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:40:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:17 INFO - Successfully installed psutil 15:40:17 INFO - Cleaning up... 15:40:17 INFO - Return code: 0 15:40:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:17 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 15:40:17 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 15:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:17 INFO - 'HOME': '/Users/cltbld', 15:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:17 INFO - 'LOGNAME': 'cltbld', 15:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:17 INFO - 'MOZ_NO_REMOTE': '1', 15:40:17 INFO - 'NO_EM_RESTART': '1', 15:40:17 INFO - 'PAGER': '/bin/cat', 15:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:17 INFO - 'PWD': '/builds/slave/test', 15:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:17 INFO - 'SHELL': '/bin/bash', 15:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:17 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:17 INFO - 'USER': 'cltbld', 15:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:17 INFO - 'XPC_FLAGS': '0x0', 15:40:17 INFO - 'XPC_SERVICE_NAME': '0', 15:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:21 INFO - Installing collected packages: mozsystemmonitor 15:40:21 INFO - Running setup.py install for mozsystemmonitor 15:40:21 INFO - Successfully installed mozsystemmonitor 15:40:21 INFO - Cleaning up... 15:40:21 INFO - Return code: 0 15:40:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:21 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:21 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 15:40:21 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 15:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:21 INFO - 'HOME': '/Users/cltbld', 15:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:21 INFO - 'LOGNAME': 'cltbld', 15:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:21 INFO - 'MOZ_NO_REMOTE': '1', 15:40:21 INFO - 'NO_EM_RESTART': '1', 15:40:21 INFO - 'PAGER': '/bin/cat', 15:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:21 INFO - 'PWD': '/builds/slave/test', 15:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:21 INFO - 'SHELL': '/bin/bash', 15:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:21 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:21 INFO - 'USER': 'cltbld', 15:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:21 INFO - 'XPC_FLAGS': '0x0', 15:40:21 INFO - 'XPC_SERVICE_NAME': '0', 15:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:21 INFO - Downloading/unpacking jsonschema==2.5.1 15:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:24 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:24 INFO - warning: no files found matching '*.txt' 15:40:24 INFO - no previously-included directories found matching 'build' 15:40:24 INFO - no previously-included directories found matching 'dist' 15:40:24 INFO - no previously-included directories found matching '.git*' 15:40:24 INFO - Installing collected packages: jsonschema, functools32 15:40:24 INFO - Running setup.py install for functools32 15:40:24 INFO - warning: no files found matching '*.txt' 15:40:24 INFO - no previously-included directories found matching 'build' 15:40:24 INFO - no previously-included directories found matching 'dist' 15:40:24 INFO - no previously-included directories found matching '.git*' 15:40:24 INFO - Successfully installed jsonschema functools32 15:40:24 INFO - Cleaning up... 15:40:25 INFO - Return code: 0 15:40:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:25 INFO - 'HOME': '/Users/cltbld', 15:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:25 INFO - 'LOGNAME': 'cltbld', 15:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:25 INFO - 'MOZ_NO_REMOTE': '1', 15:40:25 INFO - 'NO_EM_RESTART': '1', 15:40:25 INFO - 'PAGER': '/bin/cat', 15:40:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:25 INFO - 'PWD': '/builds/slave/test', 15:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:25 INFO - 'SHELL': '/bin/bash', 15:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:25 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:25 INFO - 'USER': 'cltbld', 15:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:25 INFO - 'XPC_FLAGS': '0x0', 15:40:25 INFO - 'XPC_SERVICE_NAME': '0', 15:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:25 INFO - Downloading/unpacking blobuploader==1.2.4 15:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:27 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:28 INFO - Downloading docopt-0.6.1.tar.gz 15:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:29 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:29 INFO - Running setup.py install for blobuploader 15:40:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:29 INFO - Running setup.py install for requests 15:40:29 INFO - Running setup.py install for docopt 15:40:29 INFO - Successfully installed blobuploader requests docopt 15:40:29 INFO - Cleaning up... 15:40:29 INFO - Return code: 0 15:40:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:29 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:29 INFO - 'HOME': '/Users/cltbld', 15:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:29 INFO - 'LOGNAME': 'cltbld', 15:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:29 INFO - 'MOZ_NO_REMOTE': '1', 15:40:29 INFO - 'NO_EM_RESTART': '1', 15:40:29 INFO - 'PAGER': '/bin/cat', 15:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:29 INFO - 'PWD': '/builds/slave/test', 15:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:29 INFO - 'SHELL': '/bin/bash', 15:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:29 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:29 INFO - 'USER': 'cltbld', 15:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:29 INFO - 'XPC_FLAGS': '0x0', 15:40:29 INFO - 'XPC_SERVICE_NAME': '0', 15:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-TQZLvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-7n_IGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-MwvR7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-lCfPik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-QhX9Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-a5rSML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-BlBkbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-F5M2qc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-gwHXD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-yKav2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-bYVE_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-DCNw0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-95eQmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-4ioYn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-tYl92g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-L70q42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-izel6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:31 INFO - Running setup.py install for manifestparser 15:40:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:32 INFO - Running setup.py install for mozcrash 15:40:32 INFO - Running setup.py install for mozdebug 15:40:32 INFO - Running setup.py install for mozdevice 15:40:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:40:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:32 INFO - Running setup.py install for mozfile 15:40:32 INFO - Running setup.py install for mozhttpd 15:40:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:32 INFO - Running setup.py install for mozinfo 15:40:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:32 INFO - Running setup.py install for mozInstall 15:40:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Running setup.py install for mozleak 15:40:33 INFO - Running setup.py install for mozlog 15:40:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Running setup.py install for moznetwork 15:40:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Running setup.py install for mozprocess 15:40:33 INFO - Running setup.py install for mozprofile 15:40:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:33 INFO - Running setup.py install for mozrunner 15:40:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:34 INFO - Running setup.py install for mozscreenshot 15:40:34 INFO - Running setup.py install for moztest 15:40:34 INFO - Running setup.py install for mozversion 15:40:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:34 INFO - Cleaning up... 15:40:34 INFO - Return code: 0 15:40:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:34 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:34 INFO - 'HOME': '/Users/cltbld', 15:40:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:34 INFO - 'LOGNAME': 'cltbld', 15:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:34 INFO - 'MOZ_NO_REMOTE': '1', 15:40:34 INFO - 'NO_EM_RESTART': '1', 15:40:34 INFO - 'PAGER': '/bin/cat', 15:40:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:34 INFO - 'PWD': '/builds/slave/test', 15:40:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:34 INFO - 'SHELL': '/bin/bash', 15:40:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:34 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:34 INFO - 'USER': 'cltbld', 15:40:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:34 INFO - 'XPC_FLAGS': '0x0', 15:40:34 INFO - 'XPC_SERVICE_NAME': '0', 15:40:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-MRQOa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-NSR7pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-XEnN1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-IPcCaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-hcqckU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-eahH8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-nvODS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-HTmFQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-LOvIyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-CjdvsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-EliD6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:35 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-oVO379-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:36 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-fZjgdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:36 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-yZMRZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:36 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-HL7CBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:36 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-xKP1nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:36 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-xPOaXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:38 INFO - Downloading blessings-1.6.tar.gz 15:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:39 INFO - Installing collected packages: blessings 15:40:39 INFO - Running setup.py install for blessings 15:40:39 INFO - Successfully installed blessings 15:40:39 INFO - Cleaning up... 15:40:39 INFO - Return code: 0 15:40:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:39 INFO - Reading from file tmpfile_stdout 15:40:39 INFO - Current package versions: 15:40:39 INFO - blessings == 1.6 15:40:39 INFO - blobuploader == 1.2.4 15:40:39 INFO - docopt == 0.6.1 15:40:39 INFO - functools32 == 3.2.3-2 15:40:39 INFO - jsonschema == 2.5.1 15:40:39 INFO - manifestparser == 1.1 15:40:39 INFO - mozInstall == 1.12 15:40:39 INFO - mozcrash == 0.17 15:40:39 INFO - mozdebug == 0.1 15:40:39 INFO - mozdevice == 0.48 15:40:39 INFO - mozfile == 1.2 15:40:39 INFO - mozhttpd == 0.7 15:40:39 INFO - mozinfo == 0.9 15:40:39 INFO - mozleak == 0.1 15:40:39 INFO - mozlog == 3.3 15:40:39 INFO - moznetwork == 0.27 15:40:39 INFO - mozprocess == 0.23 15:40:39 INFO - mozprofile == 0.28 15:40:39 INFO - mozrunner == 6.12 15:40:39 INFO - mozscreenshot == 0.1 15:40:39 INFO - mozsystemmonitor == 0.3 15:40:39 INFO - moztest == 0.7 15:40:39 INFO - mozversion == 1.4 15:40:39 INFO - psutil == 3.1.1 15:40:39 INFO - requests == 1.2.3 15:40:39 INFO - wsgiref == 0.1.2 15:40:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:39 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:39 INFO - 'HOME': '/Users/cltbld', 15:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:39 INFO - 'LOGNAME': 'cltbld', 15:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:39 INFO - 'MOZ_NO_REMOTE': '1', 15:40:39 INFO - 'NO_EM_RESTART': '1', 15:40:39 INFO - 'PAGER': '/bin/cat', 15:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:39 INFO - 'PWD': '/builds/slave/test', 15:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:39 INFO - 'SHELL': '/bin/bash', 15:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:39 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:39 INFO - 'USER': 'cltbld', 15:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:39 INFO - 'XPC_FLAGS': '0x0', 15:40:39 INFO - 'XPC_SERVICE_NAME': '0', 15:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:40:39 INFO - Cleaning up... 15:40:39 INFO - Return code: 0 15:40:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebb2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91eb46a8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, '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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:39 INFO - 'HOME': '/Users/cltbld', 15:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:39 INFO - 'LOGNAME': 'cltbld', 15:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:39 INFO - 'MOZ_NO_REMOTE': '1', 15:40:39 INFO - 'NO_EM_RESTART': '1', 15:40:39 INFO - 'PAGER': '/bin/cat', 15:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:39 INFO - 'PWD': '/builds/slave/test', 15:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:39 INFO - 'SHELL': '/bin/bash', 15:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:40:39 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:39 INFO - 'USER': 'cltbld', 15:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:39 INFO - 'XPC_FLAGS': '0x0', 15:40:39 INFO - 'XPC_SERVICE_NAME': '0', 15:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:40 INFO - Cleaning up... 15:40:40 INFO - Return code: 0 15:40:40 INFO - Running post-action listener: _resource_record_post_action 15:40:40 INFO - Running post-action listener: _start_resource_monitoring 15:40:40 INFO - Starting resource monitoring. 15:40:40 INFO - [mozharness: 2016-08-21 22:40:40.167639Z] Finished create-virtualenv step (success) 15:40:40 INFO - [mozharness: 2016-08-21 22:40:40.168587Z] Running install step. 15:40:40 INFO - Running pre-action listener: _resource_record_pre_action 15:40:40 INFO - Running main action method: install 15:40:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:40 INFO - Reading from file tmpfile_stdout 15:40:40 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:40 INFO - Reading from file tmpfile_stdout 15:40:40 INFO - Output received: 15:40:40 INFO - Usage: mozinstall [options] installer 15:40:40 INFO - Options: 15:40:40 INFO - -h, --help show this help message and exit 15:40:40 INFO - -d DEST, --destination=DEST 15:40:40 INFO - Directory to install application into. [default: 15:40:40 INFO - "/builds/slave/test"] 15:40:40 INFO - --app=APP Application being installed. [default: firefox] 15:40:40 INFO - mkdir: /builds/slave/test/build/application 15:40:40 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'] 15:40:40 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 15:41:15 INFO - Reading from file tmpfile_stdout 15:41:15 INFO - Output received: 15:41:15 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 15:41:15 INFO - Running post-action listener: _resource_record_post_action 15:41:15 INFO - [mozharness: 2016-08-21 22:41:15.143857Z] Finished install step (success) 15:41:15 INFO - [mozharness: 2016-08-21 22:41:15.144018Z] Running run-tests step. 15:41:15 INFO - Running pre-action listener: _resource_record_pre_action 15:41:15 INFO - Running main action method: run_tests 15:41:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:41:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:41:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:41:15 INFO - 2016-08-21 15:41:15.158 screenresolution[1645:8021] starting screenresolution argv=screenresolution get 15:41:15 INFO - 2016-08-21 15:41:15.160 screenresolution[1645:8021] Display 0: 1600x1200x32@60 15:41:15 INFO - 2016-08-21 15:41:15.166 screenresolution[1646:8023] starting screenresolution argv=screenresolution list 15:41:15 INFO - Available Modes on Display 0 15:41:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:41:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:41:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:41:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:41:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:41:15 INFO - Intel Iris: 15:41:15 INFO - Chipset Model: Intel Iris 15:41:15 INFO - Type: GPU 15:41:15 INFO - Bus: Built-In 15:41:15 INFO - VRAM (Dynamic, Max): 1536 MB 15:41:15 INFO - Vendor: Intel (0x8086) 15:41:15 INFO - Device ID: 0x0a2e 15:41:15 INFO - Revision ID: 0x0009 15:41:15 INFO - Displays: 15:41:15 INFO - Display: 15:41:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:41:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:41:15 INFO - Display Serial Number: 1600x1200 60 15:41:15 INFO - Main Display: Yes 15:41:15 INFO - Mirror: Off 15:41:15 INFO - Online: Yes 15:41:15 INFO - Rotation: Supported 15:41:15 INFO - Return code: 0 15:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:41:15 INFO - Python 2.7.3 15:41:15 INFO - Return code: 0 15:41:15 INFO - grabbing minidump binary from tooltool 15:41:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:15 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 0x10ea81d30>, '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 0x10e7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea79b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:41:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:41:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:41:15 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 15:41:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:41:15 INFO - Return code: 0 15:41:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:41:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:41:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:41:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:41:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:41:15 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--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 15:41:15 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0054 --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 15:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZS3xejk4g/Render', 15:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:15 INFO - 'HOME': '/Users/cltbld', 15:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:15 INFO - 'LOGNAME': 'cltbld', 15:41:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:41:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:15 INFO - 'MOZ_NO_REMOTE': '1', 15:41:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:41:15 INFO - 'NO_EM_RESTART': '1', 15:41:15 INFO - 'PAGER': '/bin/cat', 15:41:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:15 INFO - 'PWD': '/builds/slave/test', 15:41:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:15 INFO - 'SHELL': '/bin/bash', 15:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Z1RtJkpUY/Listeners', 15:41:15 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 15:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:15 INFO - 'USER': 'cltbld', 15:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:15 INFO - 'XPC_FLAGS': '0x0', 15:41:15 INFO - 'XPC_SERVICE_NAME': '0', 15:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:15 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--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 15:41:15 INFO - mozversion application_buildid: 20160721030203 15:41:15 INFO - mozversion application_changeset: d224fc999cb6accb208af0a105f14433375e2e77 15:41:15 INFO - mozversion application_display_name: Nightly 15:41:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:41:15 INFO - mozversion application_name: Firefox 15:41:15 INFO - mozversion application_remotingname: firefox 15:41:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:41:15 INFO - mozversion application_vendor: Mozilla 15:41:15 INFO - mozversion application_version: 50.0a1 15:41:15 INFO - mozversion platform_buildid: 20160721030203 15:41:15 INFO - mozversion platform_changeset: d224fc999cb6accb208af0a105f14433375e2e77 15:41:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:41:15 INFO - mozversion platform_version: 50.0a1 15:41:15 INFO - using testdate: 1471819275 15:41:15 INFO - actual date: 1471819275 15:41:15 INFO - starting webserver on 'localhost:49267' 15:41:15 INFO - SUITE-START | Running 2 tests 15:41:15 INFO - TEST-START | dromaeo_css 15:41:15 INFO - operating with platform_type : mac_ 15:41:15 INFO - Initialising browser for dromaeo_css test... 15:41:15 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmphl4z8J/profile http://localhost:49267/getInfo.html) 15:41:19 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 15:41:19 INFO - PROCESS | 1656 | colorDepth:24 15:41:19 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 15:41:19 INFO - PROCESS | 1656 | __metrics 15:41:19 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 15:41:19 INFO - TEST-INFO | 1656: exit 0 15:41:19 INFO - Browser initialized. 15:41:19 INFO - Running cycle 1/1 for dromaeo_css test... 15:41:19 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmphl4z8J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:31 INFO - PROCESS | 1661 | RSS: Main: 255135744 15:41:31 INFO - PROCESS | 1661 | 15:42:21 INFO - PROCESS | 1661 | 1471819341386 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:44:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 15:44:33 INFO - PROCESS | 1661 | RSS: Main: 267300864 15:44:33 INFO - PROCESS | 1661 | 15:47:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 15:47:36 INFO - PROCESS | 1661 | RSS: Main: 276054016 15:47:36 INFO - PROCESS | 1661 | 15:50:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 15:50:39 INFO - PROCESS | 1661 | RSS: Main: 280293376 15:50:39 INFO - PROCESS | 1661 | 15:53:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 15:53:41 INFO - PROCESS | 1661 | RSS: Main: 279171072 15:53:41 INFO - PROCESS | 1661 | 15:56:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 15:56:45 INFO - PROCESS | 1661 | RSS: Main: 282865664 15:56:45 INFO - PROCESS | 1661 | 15:59:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 15:59:48 INFO - PROCESS | 1661 | RSS: Main: 283009024 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | __start_tp_report 15:59:48 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 15:59:48 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 15:59:48 INFO - PROCESS | 1661 | |i|pagename|runs| 15:59:48 INFO - PROCESS | 1661 | |0;dojo.html;3023.213964369264;3383.729301655868;3406.9659303406956;3431.8798842040524;3440.690337869591;5738.7417566209515;5754.597178197518;5762.798302059428;5849.473547380735;5889.8822023559505;5763.337216220136;5778.422157784219;5812.302523697152;5870.501007414364;5989.9101513477335;5246.134387826023;5270.789168433258;5335.413104558495;5391.218273350361;5416.891662166754;5249.527542521172;5302.708351040691;5480.232767412565;5494.423085576017;5500.257465242181;5228.529432351069;5239.214117882329;5245.632805703603;5362.705051222181;5411.431799661038;3740.4763333133383;5295.549878260353;5301.310829592128;5305.602079844027;5374.677519348813;5258.894822103574;5300.734963251822;5325.707086110262;5336.386315573681;5349.037173308841;4531.456225252958;5754.798582049608;5788.5369170466265;5926.8814623707285;5951.6726580038085;5548.91676624851;6235.688215589203;6241.594296370767;6304.148939893102;6307.779227727007;6357.427831495142;6382.042693595998;6389.393007664265;6401.423871851555;6423.8394040148905;5270.49930256625;5845.210896528956;5866.1494083357675;5982.880342393128;6025.397460253984;4807.543283388072;6054.152418661391;6419.679016049179;6443.48452123829;6446.580972236789;6245.718942647592;6275.811725648238;6293.087502312231;6294.496440284766;6312.0531920211415;5828.358880523139;5933.9406605934255;6146.846328841725;6241.812745617639;6315;6109.725062372204;6117.370705100174;6136.978641397139;6220.973539366045;6261.154744109578;5332.9466705333225;6200.782972595937;6250.15622890913;6267.9373206268265;6311.652859092702;4982;4991.950080499221;5015.072211640858;5094.5669618082875;5098.566621837112;4466.1067786442845;4497.707649002804;4535.455745310584;4542.136993971135;4569.543045695404;5937.168796368512;5946.8810623787285;6024.1566180734735;6025.6083354581815;6033.396660333931;6213.440790328802;6215.192025036717;6333.714982825784;6339.2392912850755;6345.270293916259;5953.613015154001;6023.849403764941;6035.577509574287;6066.969665151734;6076.8784624308155;5536.474034966672;5558.916616250759;5563.888722225533;5573.108302671634;5588.938101760653;6232.314445411092;6328.335524769833;6486.05952136947;6488.059231411419;6513.609183448913;6263.78076767311;6300.905486417799;6332.715027823851;6340.619562826245;6343.492520598434;6419.646919419477;6478.384553467413;6478.740850366121;6479.546431749733;6480.092787009728;6123.632582045091;6141.946315431972;6283.8114856554375;6294.968525157345;6328.588641738456;6160.16837726919;6196.845078872884;6267.435930766071;6288.245410550763;6318.336574659595;6216.968915155395;6221.564490485623;6241.812745617639;6251.937480625317;6264.937350626436;4981.427135879276;5144.742762861767;6141.539384546266;6201.906971395523;6240.469560087444;5982.282126144891;6074.544409169418;6084.239470066242;6138.815835524762;6141.087130295657;5115.974420127875;6191.071339298961;6196.783112591038;6202.038684003986;6207.099970504342;5048.974755126201;5855.853603659943;6029.969850150721;6051.515878729779;6057.606255593372 15:59:48 INFO - PROCESS | 1661 | |1;ext.html;468.6719296492455;479.23083451061046;527.1197100841597;547.0372145024756;561.1442550111083;38890.611093889056;41469.37795933063;41474.37788433172;41504.37743433851;41685;42234.788826055825;42528;42826.57173428265;42905.14189716204;43004.13991720164;9644.951775241114;9775.902240977606;9821.36161149523;9841.507924603777;9869.111779939803;9703.369280996712;9793.314467987244;9795.608175672964;9817.90182098177;9831.262655300845;9763.414195148278;9813.558389872438;9829.557669904836;9850.802983940317;9858;9741.123298903098;9771.560279787356;9773.657921972732;9786.706398808046;9802.019798020141;9666.75833104178;9713.61145554177;9733.124018838338;9739.805203895883;9746.902530974741;40834.59165408338;40907.181856363;41002.79498602488;41206.38190427146;41371.37942930858;2868.9856550717113;3104.8137111773367;3361.781484203519;3429.16625359372;3545.1314427965312;24751.62872556913;30406.84796576003;30746.692533074605;31515.369692606248;31665.52501712476;3142.120206342205;3156.4949608062598;3165.2878102427;3169.5245713143213;3275.4922986937063;321514;449382;456493;457325;458253.70873145753;33491.16272093169;33693.15767105793;33693.83153084219;33710.15724606856;33865.15337116592;34485.310293793984;34948.30103397918;35001;35012.29975400529;35740;16759.581010474834;17090;17182.054986975836;17269.481915542554;17271.13644317779;12817.807732884014;13043.934780326037;13170.736585268241;13191.274479903697;13193.340332983313;18778.061096945097;19074.904625476785;19217.51956201078;19334.903325483563;19475.868682479384;8068.3948703847445;8169.30560902318;8189.467684600482;8222.25887221313;8260.669573217125;924.824283386168;990.8910019897805;1060.6287799270194;1108.0194028284864;1131.6209069961656;31115.377692446025;31150.844245778626;31158;31846.2038449036;31887;965.5799727118725;972.9708108756748;1079.2445288298222;1080.2654195147375;1084.8264277715527;14029.835951826257;15814.68370632581;16196.190190490664;16199.514014579581;16422;674922;690260;693338;695356;701156;59474.81050378968;59557;59645;60405.39594604086;60425.39574604286;1369.1784929042494;1389.6386939395627;1389.7428975639539;1417.5109587192403;1423.992880035593;25033.74966250314;25470.61794073053;29118.417631647673;30752.538711919788;31621.20946976344;51388.97222055613;51434.74282628563;51611.967760645326;51920.22119668283;52022;592779;600816;602730;603512;604864;27038.05366812152;27126.864365678044;27312.317192070357;27612.723872761017;27636;46963.29555056609;47079;47200.52799471961;48412;51096.489035109174;31611.20971975719;39509;41159;41465.02287849412;42023.7898810504;40513.79743101384;41234.9691257721;41798.791006044776;41942.790286048374;42249.78875105605;33698.83150584231;39111;39167.804160979016;39864.402033970095;40188.39717404183;32918.177045574055;35034.94895153149;36100.27799444049;36400.08999774921;36420.08949776277;25599.616005759555;26030.86984565065;26510.125395116436;26953.86523067372;27145.45709085847 15:59:48 INFO - PROCESS | 1661 | |2;jquery.html;342.753217683268;373.2255569692888;390.20008981587745;397.0470869912212;405.5133839392728;18918.716219256705;20042.89978550109;20315;20323.796762032376;20410.795892041075;18011.459656210336;18071.819281807177;18619.06904654765;20355.694664580005;20832.58334833306;5258.763355648985;5815.505682017023;5939.079442686369;6015.969920150393;6035.486983606377;5972.910406343908;6010.12853136295;6019.969900150493;6020.217371741667;6025.3372129065765;5312.67183204199;5534.335879694442;5830;5831.067029275317;5942.078977758454;5840.211571437844;5889.293284805808;5899.085641725539;5925.111233315016;5944.256967879015;5733.369329373765;5849.356570777211;5851.4441128093185;5854.970725146347;5861.589688721791;10643.46782660864;12286.877131228663;12561.618221995574;13092.672683182902;13473.730525389536;1811.619559892411;2192.3094225318973;2299.7585253548473;2515.2328539795353;2903.3041186292053;10284.151587436245;10306.33008854422;10575.471226438724;13273.535426260034;14167.574972750834;1417.340936464549;1816.4187460012802;1816.8553811098923;1855.1651756709534;1888.9622207555772;120379.19431208538;120437.39781301125;121896;123218.38390807988;125060.37469812683;9158.81682366349;11397.544098235978;11653.41732913351;11756.85363261055;11866.821997670042;14687.26563671812;14705.044172128777;14724.116553006854;14744.55766327012;14756.631084222765;7424.517406368676;8451.535165565952;8641.876556047673;8712.041675415827;8825.293976481866;8631.697890573925;8632.050474447806;8669.306455483546;8690.565471726515;8775.780605484786;4447.933281000787;4539.977300113478;4543.318502224693;4612.146752850734;4625.4911959684405;1913.7320775091498;1942.8348590369694;1962.2445358537066;1965.9115339809744;1969.743933288692;796.8167321516083;906.9365144439957;917.8393781088283;930.7114794413753;970.146271281268;20897.164113435596;21648.675269870964;21742.45643858916;21754.564908701737;21777.891110544664;870.8737233101165;946.6402766948653;969.6412327438893;991.9683529129625;1048.826943554305;10938;11422.885771142348;11449.942750286194;11451.427428628534;11506.424678766029;507789;525120;528862;529728;547655.5234447605;57237;115306;123499;133985;146294;981.5582987655583;1006.7432804634772;1019.7501612104934;1024.6004058417222;1028.830243009895;17058.061806600756;18806.717899231247;19026.52433689169;19081.618367632847;19275.51811204731;51563.71090722762;54502;55356;55786;55897;66228;82133;86810.56594716986;87137;87484.5625771867;7063.39961103312;7852.097008843828;7894.802629934297;7937.404694647809;8279.047909490486;10423.895761042293;11058.557657693924;13020;13426.530071447452;13583.79624305656;11189.496472658588;11342.60300889465;11476.770464590494;11651.766964660828;11673.883261167279;10861.511231994737;11128.721781955515;11225.8263732214;11312.321260724053;11579.594714184963;11522.193446459003;11575.942120289345;11672.416379180837;11701.88298117042;11726.94136529312;6433.646149461824;7239.312265334785;7461.477696561188;7646.426518011061;7703.422243331885;9701.611935522782;11358.716032098933;11360.772784544428;11462.025727813229;11544.480498377425 15:59:48 INFO - PROCESS | 1661 | |3;mootools.html;1563.1402728499322;1585.6194513316805;1822.6719190545705;1832.9816701832985;1951.1219951022044;7926.128125906146;8481.57592120393;8626.611802468888;9083.227925626321;9109.31680123991;8616.741497755062;8770.210681038705;8798.956005219981;8809.986851512078;8834.95582522088;2338.7193536775612;2365.526894621074;2370.063824789207;2372.0511795281855;2373.5846226910307;2332.090484710964;2345.9178928737497;2361.397843549901;2372.620380739082;2377.7622237776172;2345.296411076679;2356.4226764442737;2363.420961864346;2371.762823717623;2374.608189648706;2055.342290467051;2300.5513924784673;2304.6543018547186;2339.30990357844;2341.0284731836355;2303.9539209215723;2319.373769082355;2325.395397196724;2326.93019209424;2339.368370539945;8835.823283534357;8836.513991730451;8905.821883562357;8914;8924.330675199386;5167.974160129175;5180.145276029489;5260.158374660036;5329.546988505982;5390.5418039466695;3501.964980350215;3541.3625547401716;3577.141486043331;3651.1054791575966;3675.22820207754;1025.6410256410272;1071.9088877445427;1074.1138560687464;1091.2633972068902;1103.6688993301977;11372.37451940143;11384.715382115513;11438.19932604718;11527.250728702607;11529.769404611861;4519.841805536857;4596.747178905192;4719.858404247878;4742.66801323904;4758.619310455155;9799.804003919882;9926.602935882484;9951.900480995242;9955.352902061342;9961.900380996098;5329.7068661224;5337;5338.626296159309;5341.332333458317;5351.491608297206;4330.480342358937;4735.881602959885;4873.610111191097;4883.706977581356;4915.926261106087;3913.5499417567344;3966.7619942803526;4035.556088830226;4072.3484242521054;4101.343784994447;1183.8871460826795;1292.4959265886312;1303.2245813740808;1306.3794697518604;1306.764782339188;855.2986551028222;898.7887846356101;898.9460632362079;931.3806318797923;945.2815859946353;5614.859628509238;5646.4353564644025;5833.679147646835;5896.321922978912;5994.7602095916545;420.10307992436367;436.6921320469062;436.892961224502;442.2658387077436;444.4155934945536;3791.981040094837;3828.1769419574366;3927.8821635350987;3934.15415685629;3939.6848252140335;10087.49562521871;10542.998415150549;10607.628732994308;10630.415327156927;10990.725231869108;10587.788244235073;10597.364158150536;10616.893831061745;10642.574297028032;10767.676969690921;7955.24425179607;7972.60136993148;8269.173082691683;8273.793155171168;8294.875576866352;5590.888182236414;5637.351704554028;5706.514946229451;5742.2535070442245;5803.506701930214;9735.513224338612;9954.900450995397;9970.252231082843;9985.500724963867;10048.799024019332;9406.153446189875;9678.177354924628;10110.292279540361;10123.443210623487;10208.846867296848;6940.27127151662;7326.157491888501;7493.100827900469;7578.848423031398;7682.0397450318715;8456.40805143634;8536;8540.14598540141;8541.402101852811;8574.82850343002;7364.96317518409;7435.739749108971;7595.696172153273;7659.540427574363;7661.15727270011;7408.333250007525;7544.471886968079;7546.622668866425;7565.735199268;7642.121156067124;6982.406495447741;6999.230084690782;7094.716211351488;7149.106361704787;7164.140303163654;4643.2802915548145;4819.204831202812;4923.753812309442;4949.529794669501;5000.12497812874;4782.497837726988;4866.197077482176;4903;4917.508249175054;4936.136176169228 15:59:48 INFO - PROCESS | 1661 | |4;prototype.html;273.2826330881436;304.61618360865305;318.72589572967246;320.0989215358765;322.50173481970353;4060.1932140197623;5092.981403719253;5393.514583687467;5703.629264097831;5849.4735473807405;5492.368377636573;5531.751071201796;5560.360558535759;5616.297962754656;5621;3648.799316037617;4245.639120674747;4291.163223171483;4294.4202532658;4318.67609929255;3771.283456143324;3937.310970580151;4191.266528357525;4287.941787941774;4318.179545886272;3431.4852772084137;4218.553239121317;4237.131388065439;4242.257604919142;4254.319308910575;3883.834849545139;4030.186416112761;4230.9153816923645;4278.614924656765;4311.28863737483;3728.3475391806323;3729.869454569077;3730.9626903730887;3743.5320584926885;3744;5040.412270134896;5236.842894713165;5277.6305658603915;5395.895600715724;5498.45015498451;4034.6973976951554;4901.43633482147;4908.828191013297;4911.336969509106;4956.6282528810125;5081.78037271056;5591.74837132326;5870.823875283749;5939.881202375928;5949.375315591844;3550.6743083280494;3860.455817672925;3894.591067937854;4107.260693075245;4120.814563344657;6158.784442544535;6271.466925311354;6282.151909492206;6314.778982735628;6323.857873093131;3660.926781464356;3678.521792167001;4158.9376159357635;4160.875173744793;4239.194553034913;3467.427874400746;3832.904177395532;3966.940495892564;4233.491980962304;4257.680673949466;4595.85103724069;4626.791794369262;4971.552560269593;4993.251012348104;5002.124203423716;5290.365156181283;6032.426919442646;6036.064410016366;6089.5737298388685;6098.451139397475;1389.5761792653136;1441.1137150652273;1459.7226526959844;1465.7874608181753;1466.538040517255;878.7653347047409;882.7861690176009;888.5868071346855;903.2322525852973;916.4821875640242;3923.803809809498;4568.360429539867;4634.841289677581;4662.5079974408545;4667.7199368037345;4467.597916187558;4503.459584849839;4867.878303042452;5117.773768410753;5209.609279304067;3849.4610754494392;4171.749695018248;4185.895352616209;4420.480326214091;4712.787924543335;2283.2579411691268;2366.917112940636;2442.7557244275786;2656.738049426507;2703.4998525272495;4422.204003279377;5193.454687257859;5355.277037599952;5434.315362237719;5464.207689884945;4897.1185186666025;4922.90154196914;4948;5047.192449208109;5320.308359913187;4896;5197.97401013;5268.682829292677;5316.431652662473;5391.894661594442;4289.721168124062;4652.278896771006;5035.475167382773;5063.797448102035;5239.43718271579;5069.847904562869;5077.517635824591;5088.6946783193125;5110.744462776921;5391.514763671288;5612.7193640317;6305.274893387319;6307.842303942438;6411.647359395091;6456.418922297016;3126.2653276480464;3979.0900367824183;4025.2150830587752;4028.5165780106577;4084.959150408458;6102.298235702951;6120.663363514871;6151.26184857802;6170.938290617216;6211.627302361872;4859.586935110555;5945.562319449005;5965.657727011388;5972.313183983897;6009.849753756191;5041.823536176216;5719.685417302088;5734.627249228787;5854.9121763174435;5979.641221526722;5586.553075753849;5630.930123276565;5844.707764611668;5853.736581853742;5869.911951320819;4596.678232523691;5177.637565370388;5880.235569375954;5921.378255283305;5961.642301461926;5794.420557944172;5799.739011744566;5834.329052159056;5846.444587764156;5870.5010074144175 15:59:48 INFO - PROCESS | 1661 | |5;yui.html;698.3575110897975;751.166205511882;800.2277801921144;833.1418638802036;833.9374546908981;15276.236188190602;15750.13374264415;15860.762088568667;15912.52262432129;15951.68096638068;13986.510472133477;14063.92968035161;14076.859231407683;14109.082909610894;14136.646583835409;704.031956060417;870.3820287595814;883.0463099852176;897.4211633496424;901.8241442918634;885.6147575804546;902.1024081039375;902.3548163063408;906.1934877958622;906.9954650226773;911.4667919267204;939.4128669581512;961.6538046303325;963.5519483440199;968.3415277611222;935.2751617496426;948.9715308540755;952.7141857442739;956.1346980982188;959.889612694542;674.8110529051874;675.9729610815562;713.357977819961;714.7462650758957;716.1549371741343;14986;15202.619934501616;15308.387664493406;15314.234288285541;15356.616084597863;14884.70230595382;14982;15003;15017.699646007128;15034.99999999989;11968.222065565711;12287.201331913486;12564.434600443003;12568.74862502754;12696.936515317457;3804.988118772333;3828.2917660232947;3923.3526468132723;3940.5862384449792;4023.054582173187;92014;98648.50675746576;104072.47963760208;106833;107071.46464267629;10907.345559266469;11398.259113157617;11478.540858365572;11539.884601154048;11950.282983021047;22752.976116074817;23416.414589635056;23704.64443033356;23751.406214844767;23765.88117059438;8231.917680823235;8257.21556452136;8278.379121565908;8288.917110828814;8354.498730076215;4494.752788596593;4588.38056862326;4589.816407343736;4608.976955115203;4609.032103258353;3422.144463884029;3497.1082373994977;3530.8411121499594;3531.3643544161782;3531.5762108546623;656.0978654350268;658.9275179730225;661.7187695229508;690.182134171009;711.9430445564342;614.5667304550282;673.4948788408693;703.4899697719216;720.2149656874033;727.6070921702327;9140.54297285133;9319.673811416704;9389.859152112724;9419.24646028316;10502.737431564272;618.5948203926436;645.8288553533223;666.6566718140161;701.5650296816007;713.1121753416926;4497.370368148462;4628.143793398232;4735.195016847144;4760.679107063334;4768.451628062817;97574;100522.99477005283;127189;157211.6418253727;164282;91564;99945;126492;133174.67777255693;134018;112295;115079;117874;118198.22702659467;119476;7506.9249307506225;9155.313351498531;9262.770271951485;9289.396189247676;9292.117248861347;61451.692741536004;63487.68256158874;63530.931677018634;65224;65710.34289657042;92328;97530;98406;100041;102141;11901.702457438634;12011;12058.457369418222;12145.271283723005;12406.193597416139;13368.598942031755;13389.732205356033;13529.323533823073;13568.59294221175;14157.292135393394;12399.628011159679;12689.8096528554;12714.364281785689;12736.426860791473;13147.276899770222;12647.304398257817;12733.681657958625;12817.807732884201;12897.48410063587;12910.67723306925;12271.961968901513;12274.631761047183;12446.004319654587;12529.18560293578;12730.745385092432;9015.594298256463;10692.663417072867;10743.623973160902;11022.724431889266;11488.885111149117;10059.74850628711;11061.502232399722;11543.413315040318;11675.416229188337;11774.646760597196 15:59:48 INFO - PROCESS | 1661 | __end_tp_report 15:59:48 INFO - PROCESS | 1661 | __start_cc_report 15:59:48 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,450 15:59:48 INFO - PROCESS | 1661 | __end_cc_report 15:59:48 INFO - PROCESS | 1661 | __startTimestamp1471820388052__endTimestamp 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | ------- Summary: start ------- 15:59:48 INFO - PROCESS | 1661 | Number of tests: 6 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5783.67 Median:6029.97 stddev:680.55 (11.3%) stddev-sans-first:647.27 15:59:48 INFO - PROCESS | 1661 | Values: 3023.2 3383.7 3407.0 3431.9 3440.7 5738.7 5754.6 5762.8 5849.5 5889.9 5763.3 5778.4 5812.3 5870.5 5989.9 5246.1 5270.8 5335.4 5391.2 5416.9 5249.5 5302.7 5480.2 5494.4 5500.3 5228.5 5239.2 5245.6 5362.7 5411.4 3740.5 5295.5 5301.3 5305.6 5374.7 5258.9 5300.7 5325.7 5336.4 5349.0 4531.5 5754.8 5788.5 5926.9 5951.7 5548.9 6235.7 6241.6 6304.1 6307.8 6357.4 6382.0 6389.4 6401.4 6423.8 5270.5 5845.2 5866.1 5982.9 6025.4 4807.5 6054.2 6419.7 6443.5 6446.6 6245.7 6275.8 6293.1 6294.5 6312.1 5828.4 5933.9 6146.8 6241.8 6315.0 6109.7 6117.4 6137.0 6221.0 6261.2 5332.9 6200.8 6250.2 6267.9 6311.7 4982.0 4992.0 5015.1 5094.6 5098.6 4466.1 4497.7 4535.5 4542.1 4569.5 5937.2 5946.9 6024.2 6025.6 6033.4 6213.4 6215.2 6333.7 6339.2 6345.3 5953.6 6023.8 6035.6 6067.0 6076.9 5536.5 5558.9 5563.9 5573.1 5588.9 6232.3 6328.3 6486.1 6488.1 6513.6 6263.8 6300.9 6332.7 6340.6 6343.5 6419.6 6478.4 6478.7 6479.5 6480.1 6123.6 6141.9 6283.8 6295.0 6328.6 6160.2 6196.8 6267.4 6288.2 6318.3 6217.0 6221.6 6241.8 6251.9 6264.9 4981.4 5144.7 6141.5 6201.9 6240.5 5982.3 6074.5 6084.2 6138.8 6141.1 5116.0 6191.1 6196.8 6202.0 6207.1 5049.0 5855.9 6030.0 6051.5 6057.6 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:69564.22 Median:40674.19 stddev:156695.26 (385.2%) stddev-sans-first:157048.95 15:59:48 INFO - PROCESS | 1661 | Values: 468.7 479.2 527.1 547.0 561.1 38890.6 41469.4 41474.4 41504.4 41685.0 42234.8 42528.0 42826.6 42905.1 43004.1 9645.0 9775.9 9821.4 9841.5 9869.1 9703.4 9793.3 9795.6 9817.9 9831.3 9763.4 9813.6 9829.6 9850.8 9858.0 9741.1 9771.6 9773.7 9786.7 9802.0 9666.8 9713.6 9733.1 9739.8 9746.9 40834.6 40907.2 41002.8 41206.4 41371.4 2869.0 3104.8 3361.8 3429.2 3545.1 24751.6 30406.8 30746.7 31515.4 31665.5 3142.1 3156.5 3165.3 3169.5 3275.5 321514.0 449382.0 456493.0 457325.0 458253.7 33491.2 33693.2 33693.8 33710.2 33865.2 34485.3 34948.3 35001.0 35012.3 35740.0 16759.6 17090.0 17182.1 17269.5 17271.1 12817.8 13043.9 13170.7 13191.3 13193.3 18778.1 19074.9 19217.5 19334.9 19475.9 8068.4 8169.3 8189.5 8222.3 8260.7 924.8 990.9 1060.6 1108.0 1131.6 31115.4 31150.8 31158.0 31846.2 31887.0 965.6 973.0 1079.2 1080.3 1084.8 14029.8 15814.7 16196.2 16199.5 16422.0 674922.0 690260.0 693338.0 695356.0 701156.0 59474.8 59557.0 59645.0 60405.4 60425.4 1369.2 1389.6 1389.7 1417.5 1424.0 25033.7 25470.6 29118.4 30752.5 31621.2 51389.0 51434.7 51612.0 51920.2 52022.0 592779.0 600816.0 602730.0 603512.0 604864.0 27038.1 27126.9 27312.3 27612.7 27636.0 46963.3 47079.0 47200.5 48412.0 51096.5 31611.2 39509.0 41159.0 41465.0 42023.8 40513.8 41235.0 41798.8 41942.8 42249.8 33698.8 39111.0 39167.8 39864.4 40188.4 32918.2 35034.9 36100.3 36400.1 36420.1 25599.6 26030.9 26510.1 26953.9 27145.5 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:32657.46 Median:21748.51 stddev:88878.80 (408.7%) stddev-sans-first:89095.01 15:59:48 INFO - PROCESS | 1661 | Values: 342.8 373.2 390.2 397.0 405.5 18918.7 20042.9 20315.0 20323.8 20410.8 18011.5 18071.8 18619.1 20355.7 20832.6 5258.8 5815.5 5939.1 6016.0 6035.5 5972.9 6010.1 6020.0 6020.2 6025.3 5312.7 5534.3 5830.0 5831.1 5942.1 5840.2 5889.3 5899.1 5925.1 5944.3 5733.4 5849.4 5851.4 5855.0 5861.6 10643.5 12286.9 12561.6 13092.7 13473.7 1811.6 2192.3 2299.8 2515.2 2903.3 10284.2 10306.3 10575.5 13273.5 14167.6 1417.3 1816.4 1816.9 1855.2 1889.0 120379.2 120437.4 121896.0 123218.4 125060.4 9158.8 11397.5 11653.4 11756.9 11866.8 14687.3 14705.0 14724.1 14744.6 14756.6 7424.5 8451.5 8641.9 8712.0 8825.3 8631.7 8632.1 8669.3 8690.6 8775.8 4447.9 4540.0 4543.3 4612.1 4625.5 1913.7 1942.8 1962.2 1965.9 1969.7 796.8 906.9 917.8 930.7 970.1 20897.2 21648.7 21742.5 21754.6 21777.9 870.9 946.6 969.6 992.0 1048.8 10938.0 11422.9 11449.9 11451.4 11506.4 507789.0 525120.0 528862.0 529728.0 547655.5 57237.0 115306.0 123499.0 133985.0 146294.0 981.6 1006.7 1019.8 1024.6 1028.8 17058.1 18806.7 19026.5 19081.6 19275.5 51563.7 54502.0 55356.0 55786.0 55897.0 66228.0 82133.0 86810.6 87137.0 87484.6 7063.4 7852.1 7894.8 7937.4 8279.0 10423.9 11058.6 13020.0 13426.5 13583.8 11189.5 11342.6 11476.8 11651.8 11673.9 10861.5 11128.7 11225.8 11312.3 11579.6 11522.2 11575.9 11672.4 11701.9 11726.9 6433.6 7239.3 7461.5 7646.4 7703.4 9701.6 11358.7 11360.8 11462.0 11544.5 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5690.20 Median:4878.66 stddev:3203.08 (65.7%) stddev-sans-first:3197.05 15:59:48 INFO - PROCESS | 1661 | Values: 1563.1 1585.6 1822.7 1833.0 1951.1 7926.1 8481.6 8626.6 9083.2 9109.3 8616.7 8770.2 8799.0 8810.0 8835.0 2338.7 2365.5 2370.1 2372.1 2373.6 2332.1 2345.9 2361.4 2372.6 2377.8 2345.3 2356.4 2363.4 2371.8 2374.6 2055.3 2300.6 2304.7 2339.3 2341.0 2304.0 2319.4 2325.4 2326.9 2339.4 8835.8 8836.5 8905.8 8914.0 8924.3 5168.0 5180.1 5260.2 5329.5 5390.5 3502.0 3541.4 3577.1 3651.1 3675.2 1025.6 1071.9 1074.1 1091.3 1103.7 11372.4 11384.7 11438.2 11527.3 11529.8 4519.8 4596.7 4719.9 4742.7 4758.6 9799.8 9926.6 9951.9 9955.4 9961.9 5329.7 5337.0 5338.6 5341.3 5351.5 4330.5 4735.9 4873.6 4883.7 4915.9 3913.5 3966.8 4035.6 4072.3 4101.3 1183.9 1292.5 1303.2 1306.4 1306.8 855.3 898.8 898.9 931.4 945.3 5614.9 5646.4 5833.7 5896.3 5994.8 420.1 436.7 436.9 442.3 444.4 3792.0 3828.2 3927.9 3934.2 3939.7 10087.5 10543.0 10607.6 10630.4 10990.7 10587.8 10597.4 10616.9 10642.6 10767.7 7955.2 7972.6 8269.2 8273.8 8294.9 5590.9 5637.4 5706.5 5742.3 5803.5 9735.5 9954.9 9970.3 9985.5 10048.8 9406.2 9678.2 10110.3 10123.4 10208.8 6940.3 7326.2 7493.1 7578.8 7682.0 8456.4 8536.0 8540.1 8541.4 8574.8 7365.0 7435.7 7595.7 7659.5 7661.2 7408.3 7544.5 7546.6 7565.7 7642.1 6982.4 6999.2 7094.7 7149.1 7164.1 4643.3 4819.2 4923.8 4949.5 5000.1 4782.5 4866.2 4903.0 4917.5 4936.1 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4568.26 Median:4982.40 stddev:1426.85 (28.6%) stddev-sans-first:1393.96 15:59:48 INFO - PROCESS | 1661 | Values: 273.3 304.6 318.7 320.1 322.5 4060.2 5093.0 5393.5 5703.6 5849.5 5492.4 5531.8 5560.4 5616.3 5621.0 3648.8 4245.6 4291.2 4294.4 4318.7 3771.3 3937.3 4191.3 4287.9 4318.2 3431.5 4218.6 4237.1 4242.3 4254.3 3883.8 4030.2 4230.9 4278.6 4311.3 3728.3 3729.9 3731.0 3743.5 3744.0 5040.4 5236.8 5277.6 5395.9 5498.5 4034.7 4901.4 4908.8 4911.3 4956.6 5081.8 5591.7 5870.8 5939.9 5949.4 3550.7 3860.5 3894.6 4107.3 4120.8 6158.8 6271.5 6282.2 6314.8 6323.9 3660.9 3678.5 4158.9 4160.9 4239.2 3467.4 3832.9 3966.9 4233.5 4257.7 4595.9 4626.8 4971.6 4993.3 5002.1 5290.4 6032.4 6036.1 6089.6 6098.5 1389.6 1441.1 1459.7 1465.8 1466.5 878.8 882.8 888.6 903.2 916.5 3923.8 4568.4 4634.8 4662.5 4667.7 4467.6 4503.5 4867.9 5117.8 5209.6 3849.5 4171.7 4185.9 4420.5 4712.8 2283.3 2366.9 2442.8 2656.7 2703.5 4422.2 5193.5 5355.3 5434.3 5464.2 4897.1 4922.9 4948.0 5047.2 5320.3 4896.0 5198.0 5268.7 5316.4 5391.9 4289.7 4652.3 5035.5 5063.8 5239.4 5069.8 5077.5 5088.7 5110.7 5391.5 5612.7 6305.3 6307.8 6411.6 6456.4 3126.3 3979.1 4025.2 4028.5 4085.0 6102.3 6120.7 6151.3 6170.9 6211.6 4859.6 5945.6 5965.7 5972.3 6009.8 5041.8 5719.7 5734.6 5854.9 5979.6 5586.6 5630.9 5844.7 5853.7 5869.9 4596.7 5177.6 5880.2 5921.4 5961.6 5794.4 5799.7 5834.3 5846.4 5870.5 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:24170.83 Median:3531.47 stddev:37849.39 (1071.8%) stddev-sans-first:37914.54 15:59:48 INFO - PROCESS | 1661 | Values: 698.4 751.2 800.2 833.1 833.9 15276.2 15750.1 15860.8 15912.5 15951.7 13986.5 14063.9 14076.9 14109.1 14136.6 704.0 870.4 883.0 897.4 901.8 885.6 902.1 902.4 906.2 907.0 911.5 939.4 961.7 963.6 968.3 935.3 949.0 952.7 956.1 959.9 674.8 676.0 713.4 714.7 716.2 14986.0 15202.6 15308.4 15314.2 15356.6 14884.7 14982.0 15003.0 15017.7 15035.0 11968.2 12287.2 12564.4 12568.7 12696.9 3805.0 3828.3 3923.4 3940.6 4023.1 92014.0 98648.5 104072.5 106833.0 107071.5 10907.3 11398.3 11478.5 11539.9 11950.3 22753.0 23416.4 23704.6 23751.4 23765.9 8231.9 8257.2 8278.4 8288.9 8354.5 4494.8 4588.4 4589.8 4609.0 4609.0 3422.1 3497.1 3530.8 3531.4 3531.6 656.1 658.9 661.7 690.2 711.9 614.6 673.5 703.5 720.2 727.6 9140.5 9319.7 9389.9 9419.2 10502.7 618.6 645.8 666.7 701.6 713.1 4497.4 4628.1 4735.2 4760.7 4768.5 97574.0 100523.0 127189.0 157211.6 164282.0 91564.0 99945.0 126492.0 133174.7 134018.0 112295.0 115079.0 117874.0 118198.2 119476.0 7506.9 9155.3 9262.8 9289.4 9292.1 61451.7 63487.7 63530.9 65224.0 65710.3 92328.0 97530.0 98406.0 100041.0 102141.0 11901.7 12011.0 12058.5 12145.3 12406.2 13368.6 13389.7 13529.3 13568.6 14157.3 12399.6 12689.8 12714.4 12736.4 13147.3 12647.3 12733.7 12817.8 12897.5 12910.7 12272.0 12274.6 12446.0 12529.2 12730.7 9015.6 10692.7 10743.6 11022.7 11488.9 10059.7 11061.5 11543.4 11675.4 11774.6 15:59:48 INFO - PROCESS | 1661 | -------- Summary: end -------- 15:59:48 INFO - PROCESS | 1661 | 15:59:48 INFO - TEST-INFO | 1661: exit 0 15:59:48 INFO - TEST-OK | dromaeo_css | took 1112934ms 15:59:48 INFO - TEST-START | kraken 15:59:48 INFO - operating with platform_type : mac_ 15:59:48 INFO - Initialising browser for kraken test... 15:59:48 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpdXNUM7/profile http://localhost:49267/getInfo.html) 15:59:51 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 15:59:51 INFO - PROCESS | 1669 | colorDepth:24 15:59:51 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 15:59:51 INFO - PROCESS | 1669 | __metrics 15:59:51 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 15:59:52 INFO - TEST-INFO | 1669: exit 0 15:59:52 INFO - Browser initialized. 15:59:52 INFO - Running cycle 1/1 for kraken test... 15:59:52 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpdXNUM7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:04 INFO - PROCESS | 1672 | RSS: Main: 254996480 16:00:04 INFO - PROCESS | 1672 | 16:00:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 16:00:08 INFO - PROCESS | 1672 | RSS: Main: 268546048 16:00:08 INFO - PROCESS | 1672 | 16:00:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 16:00:11 INFO - PROCESS | 1672 | RSS: Main: 272064512 16:00:11 INFO - PROCESS | 1672 | 16:00:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 16:00:15 INFO - PROCESS | 1672 | RSS: Main: 272281600 16:00:15 INFO - PROCESS | 1672 | 16:00:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 16:00:17 INFO - PROCESS | 1672 | RSS: Main: 273195008 16:00:17 INFO - PROCESS | 1672 | 16:00:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:00:20 INFO - PROCESS | 1672 | RSS: Main: 273838080 16:00:20 INFO - PROCESS | 1672 | 16:00:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 16:00:24 INFO - PROCESS | 1672 | RSS: Main: 274595840 16:00:24 INFO - PROCESS | 1672 | 16:00:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 16:00:27 INFO - PROCESS | 1672 | RSS: Main: 274837504 16:00:27 INFO - PROCESS | 1672 | 16:00:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 16:00:31 INFO - PROCESS | 1672 | RSS: Main: 275038208 16:00:31 INFO - PROCESS | 1672 | 16:00:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:00:33 INFO - PROCESS | 1672 | RSS: Main: 274538496 16:00:33 INFO - PROCESS | 1672 | 16:00:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:36 INFO - PROCESS | 1672 | RSS: Main: 274259968 16:00:36 INFO - PROCESS | 1672 | 16:00:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:39 INFO - PROCESS | 1672 | RSS: Main: 274628608 16:00:39 INFO - PROCESS | 1672 | 16:00:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:42 INFO - PROCESS | 1672 | RSS: Main: 274726912 16:00:42 INFO - PROCESS | 1672 | 16:00:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:46 INFO - PROCESS | 1672 | RSS: Main: 275001344 16:00:46 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 16:00:48 INFO - PROCESS | 1672 | RSS: Main: 275656704 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | __start_tp_report 16:00:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 16:00:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 16:00:48 INFO - PROCESS | 1672 | |i|pagename|runs| 16:00:48 INFO - PROCESS | 1672 | |0;ai-astar;172;175;105;148;104;154;146;137;116;116 16:00:48 INFO - PROCESS | 1672 | |1;audio-beat-detection;140;111;131;154;108;128;125;113;159;108 16:00:48 INFO - PROCESS | 1672 | |2;audio-dft;194;193;199;225;197;194;196;197;229;198 16:00:48 INFO - PROCESS | 1672 | |3;audio-fft;69;81;74;137;58;59;60;68;135;59 16:00:48 INFO - PROCESS | 1672 | |4;audio-oscillator;68;70;117;72;72;74;115;70;73;115 16:00:48 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;136;86;81;132;123;85;91;131;126;85 16:00:48 INFO - PROCESS | 1672 | |6;imaging-darkroom;94;95;96;95;152;95;95;96;117;122 16:00:48 INFO - PROCESS | 1672 | |7;imaging-desaturate;96;96;92;126;90;114;147;87;145;144 16:00:48 INFO - PROCESS | 1672 | |8;json-parse-financial;55;56;57;71;71;54;53;55;54;58 16:00:48 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;67;73;68;78;79;76;74;76;73;99 16:00:48 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;83;84;90;84;79;97;97;106;75;94 16:00:48 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;145;163;162;139;136;137;140;141;215;157 16:00:48 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;205;237;167;169;164;169;172;236;169;166 16:00:48 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;66;69;67;69;70;68;67;67;65;69 16:00:48 INFO - PROCESS | 1672 | __end_tp_report 16:00:48 INFO - PROCESS | 1672 | __start_cc_report 16:00:48 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1132 16:00:48 INFO - PROCESS | 1672 | __end_cc_report 16:00:48 INFO - PROCESS | 1672 | __startTimestamp1471820448255__endTimestamp 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | ------- Summary: start ------- 16:00:48 INFO - PROCESS | 1672 | Number of tests: 14 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:137.30 Median:147.00 stddev:26.15 (17.8%) stddev-sans-first:24.54 16:00:48 INFO - PROCESS | 1672 | Values: 172.0 175.0 105.0 148.0 104.0 154.0 146.0 137.0 116.0 116.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:129.50 stddev:18.60 (14.4%) stddev-sans-first:19.18 16:00:48 INFO - PROCESS | 1672 | Values: 140.0 111.0 131.0 154.0 108.0 128.0 125.0 113.0 159.0 108.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:202.20 Median:197.50 stddev:13.24 (6.7%) stddev-sans-first:13.71 16:00:48 INFO - PROCESS | 1672 | Values: 194.0 193.0 199.0 225.0 197.0 194.0 196.0 197.0 229.0 198.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:80.00 Median:64.00 stddev:30.44 (47.6%) stddev-sans-first:32.03 16:00:48 INFO - PROCESS | 1672 | Values: 69.0 81.0 74.0 137.0 58.0 59.0 60.0 68.0 135.0 59.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:71.00 stddev:21.51 (30.3%) stddev-sans-first:21.96 16:00:48 INFO - PROCESS | 1672 | Values: 68.0 70.0 117.0 72.0 72.0 74.0 115.0 70.0 73.0 115.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:107.60 Median:83.00 stddev:23.56 (28.4%) stddev-sans-first:22.64 16:00:48 INFO - PROCESS | 1672 | Values: 136.0 86.0 81.0 132.0 123.0 85.0 91.0 131.0 126.0 85.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:105.70 Median:95.00 stddev:19.21 (20.2%) stddev-sans-first:19.90 16:00:48 INFO - PROCESS | 1672 | Values: 94.0 95.0 96.0 95.0 152.0 95.0 95.0 96.0 117.0 122.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:113.70 Median:88.50 stddev:24.79 (28.0%) stddev-sans-first:25.45 16:00:48 INFO - PROCESS | 1672 | Values: 96.0 96.0 92.0 126.0 90.0 114.0 147.0 87.0 145.0 144.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:56.50 stddev:6.80 (12.0%) stddev-sans-first:7.10 16:00:48 INFO - PROCESS | 1672 | Values: 55.0 56.0 57.0 71.0 71.0 54.0 53.0 55.0 54.0 58.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:76.00 stddev:8.87 (11.7%) stddev-sans-first:8.75 16:00:48 INFO - PROCESS | 1672 | Values: 67.0 73.0 68.0 78.0 79.0 76.0 74.0 76.0 73.0 99.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:87.00 stddev:9.57 (11.0%) stddev-sans-first:9.91 16:00:48 INFO - PROCESS | 1672 | Values: 83.0 84.0 90.0 84.0 79.0 97.0 97.0 106.0 75.0 94.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:151.00 stddev:23.94 (15.9%) stddev-sans-first:25.19 16:00:48 INFO - PROCESS | 1672 | Values: 145.0 163.0 162.0 139.0 136.0 137.0 140.0 141.0 215.0 157.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:170.50 stddev:29.38 (17.2%) stddev-sans-first:30.29 16:00:48 INFO - PROCESS | 1672 | Values: 205.0 237.0 167.0 169.0 164.0 169.0 172.0 236.0 169.0 166.0 16:00:48 INFO - PROCESS | 1672 | 16:00:48 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.50 stddev:1.57 (2.3%) stddev-sans-first:1.54 16:00:48 INFO - PROCESS | 1672 | Values: 66.0 69.0 67.0 69.0 70.0 68.0 67.0 67.0 65.0 69.0 16:00:48 INFO - PROCESS | 1672 | -------- Summary: end -------- 16:00:48 INFO - PROCESS | 1672 | 16:00:49 INFO - TEST-INFO | 1672: exit 0 16:00:49 INFO - TEST-OK | kraken | took 60389ms 16:00:49 INFO - SUITE-END | took 1173s 16:00:49 INFO - Completed test suite (00:19:33) 16:00:49 INFO - javascript benchmark 16:00:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7326.878997134589, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3023.213964369264, 3383.729301655868, 3406.9659303406956, 3431.8798842040524, 3440.690337869591, 5738.7417566209515, 5754.597178197518, 5762.798302059428, 5849.473547380735, 5889.8822023559505, 5763.337216220136, 5778.422157784219, 5812.302523697152, 5870.501007414364, 5989.9101513477335, 5246.134387826023, 5270.789168433258, 5335.413104558495, 5391.218273350361, 5416.891662166754, 5249.527542521172, 5302.708351040691, 5480.232767412565, 5494.423085576017, 5500.257465242181, 5228.529432351069, 5239.214117882329, 5245.632805703603, 5362.705051222181, 5411.431799661038, 3740.4763333133383, 5295.549878260353, 5301.310829592128, 5305.602079844027, 5374.677519348813, 5258.894822103574, 5300.734963251822, 5325.707086110262, 5336.386315573681, 5349.037173308841, 4531.456225252958, 5754.798582049608, 5788.5369170466265, 5926.8814623707285, 5951.6726580038085, 5548.91676624851, 6235.688215589203, 6241.594296370767, 6304.148939893102, 6307.779227727007, 6357.427831495142, 6382.042693595998, 6389.393007664265, 6401.423871851555, 6423.8394040148905, 5270.49930256625, 5845.210896528956, 5866.1494083357675, 5982.880342393128, 6025.397460253984, 4807.543283388072, 6054.152418661391, 6419.679016049179, 6443.48452123829, 6446.580972236789, 6245.718942647592, 6275.811725648238, 6293.087502312231, 6294.496440284766, 6312.0531920211415, 5828.358880523139, 5933.9406605934255, 6146.846328841725, 6241.812745617639, 6315.0, 6109.725062372204, 6117.370705100174, 6136.978641397139, 6220.973539366045, 6261.154744109578, 5332.9466705333225, 6200.782972595937, 6250.15622890913, 6267.9373206268265, 6311.652859092702, 4982.0, 4991.950080499221, 5015.072211640858, 5094.5669618082875, 5098.566621837112, 4466.1067786442845, 4497.707649002804, 4535.455745310584, 4542.136993971135, 4569.543045695404, 5937.168796368512, 5946.8810623787285, 6024.1566180734735, 6025.6083354581815, 6033.396660333931, 6213.440790328802, 6215.192025036717, 6333.714982825784, 6339.2392912850755, 6345.270293916259, 5953.613015154001, 6023.849403764941, 6035.577509574287, 6066.969665151734, 6076.8784624308155, 5536.474034966672, 5558.916616250759, 5563.888722225533, 5573.108302671634, 5588.938101760653, 6232.314445411092, 6328.335524769833, 6486.05952136947, 6488.059231411419, 6513.609183448913, 6263.78076767311, 6300.905486417799, 6332.715027823851, 6340.619562826245, 6343.492520598434, 6419.646919419477, 6478.384553467413, 6478.740850366121, 6479.546431749733, 6480.092787009728, 6123.632582045091, 6141.946315431972, 6283.8114856554375, 6294.968525157345, 6328.588641738456, 6160.16837726919, 6196.845078872884, 6267.435930766071, 6288.245410550763, 6318.336574659595, 6216.968915155395, 6221.564490485623, 6241.812745617639, 6251.937480625317, 6264.937350626436, 4981.427135879276, 5144.742762861767, 6141.539384546266, 6201.906971395523, 6240.469560087444, 5982.282126144891, 6074.544409169418, 6084.239470066242, 6138.815835524762, 6141.087130295657, 5115.974420127875, 6191.071339298961, 6196.783112591038, 6202.038684003986, 6207.099970504342, 5048.974755126201, 5855.853603659943, 6029.969850150721, 6051.515878729779, 6057.606255593372], "value": 5742.469350313155, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.6719296492455, 479.23083451061046, 527.1197100841597, 547.0372145024756, 561.1442550111083, 38890.611093889056, 41469.37795933063, 41474.37788433172, 41504.37743433851, 41685.0, 42234.788826055825, 42528.0, 42826.57173428265, 42905.14189716204, 43004.13991720164, 9644.951775241114, 9775.902240977606, 9821.36161149523, 9841.507924603777, 9869.111779939803, 9703.369280996712, 9793.314467987244, 9795.608175672964, 9817.90182098177, 9831.262655300845, 9763.414195148278, 9813.558389872438, 9829.557669904836, 9850.802983940317, 9858.0, 9741.123298903098, 9771.560279787356, 9773.657921972732, 9786.706398808046, 9802.019798020141, 9666.75833104178, 9713.61145554177, 9733.124018838338, 9739.805203895883, 9746.902530974741, 40834.59165408338, 40907.181856363, 41002.79498602488, 41206.38190427146, 41371.37942930858, 2868.9856550717113, 3104.8137111773367, 3361.781484203519, 3429.16625359372, 3545.1314427965312, 24751.62872556913, 30406.84796576003, 30746.692533074605, 31515.369692606248, 31665.52501712476, 3142.120206342205, 3156.4949608062598, 3165.2878102427, 3169.5245713143213, 3275.4922986937063, 321514.0, 449382.0, 456493.0, 457325.0, 458253.70873145753, 33491.16272093169, 33693.15767105793, 33693.83153084219, 33710.15724606856, 33865.15337116592, 34485.310293793984, 34948.30103397918, 35001.0, 35012.29975400529, 35740.0, 16759.581010474834, 17090.0, 17182.054986975836, 17269.481915542554, 17271.13644317779, 12817.807732884014, 13043.934780326037, 13170.736585268241, 13191.274479903697, 13193.340332983313, 18778.061096945097, 19074.904625476785, 19217.51956201078, 19334.903325483563, 19475.868682479384, 8068.3948703847445, 8169.30560902318, 8189.467684600482, 8222.25887221313, 8260.669573217125, 924.824283386168, 990.8910019897805, 1060.6287799270194, 1108.0194028284864, 1131.6209069961656, 31115.377692446025, 31150.844245778626, 31158.0, 31846.2038449036, 31887.0, 965.5799727118725, 972.9708108756748, 1079.2445288298222, 1080.2654195147375, 1084.8264277715527, 14029.835951826257, 15814.68370632581, 16196.190190490664, 16199.514014579581, 16422.0, 674922.0, 690260.0, 693338.0, 695356.0, 701156.0, 59474.81050378968, 59557.0, 59645.0, 60405.39594604086, 60425.39574604286, 1369.1784929042494, 1389.6386939395627, 1389.7428975639539, 1417.5109587192403, 1423.992880035593, 25033.74966250314, 25470.61794073053, 29118.417631647673, 30752.538711919788, 31621.20946976344, 51388.97222055613, 51434.74282628563, 51611.967760645326, 51920.22119668283, 52022.0, 592779.0, 600816.0, 602730.0, 603512.0, 604864.0, 27038.05366812152, 27126.864365678044, 27312.317192070357, 27612.723872761017, 27636.0, 46963.29555056609, 47079.0, 47200.52799471961, 48412.0, 51096.489035109174, 31611.20971975719, 39509.0, 41159.0, 41465.02287849412, 42023.7898810504, 40513.79743101384, 41234.9691257721, 41798.791006044776, 41942.790286048374, 42249.78875105605, 33698.83150584231, 39111.0, 39167.804160979016, 39864.402033970095, 40188.39717404183, 32918.177045574055, 35034.94895153149, 36100.27799444049, 36400.08999774921, 36420.08949776277, 25599.616005759555, 26030.86984565065, 26510.125395116436, 26953.86523067372, 27145.45709085847], "value": 19681.62795901212, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.753217683268, 373.2255569692888, 390.20008981587745, 397.0470869912212, 405.5133839392728, 18918.716219256705, 20042.89978550109, 20315.0, 20323.796762032376, 20410.795892041075, 18011.459656210336, 18071.819281807177, 18619.06904654765, 20355.694664580005, 20832.58334833306, 5258.763355648985, 5815.505682017023, 5939.079442686369, 6015.969920150393, 6035.486983606377, 5972.910406343908, 6010.12853136295, 6019.969900150493, 6020.217371741667, 6025.3372129065765, 5312.67183204199, 5534.335879694442, 5830.0, 5831.067029275317, 5942.078977758454, 5840.211571437844, 5889.293284805808, 5899.085641725539, 5925.111233315016, 5944.256967879015, 5733.369329373765, 5849.356570777211, 5851.4441128093185, 5854.970725146347, 5861.589688721791, 10643.46782660864, 12286.877131228663, 12561.618221995574, 13092.672683182902, 13473.730525389536, 1811.619559892411, 2192.3094225318973, 2299.7585253548473, 2515.2328539795353, 2903.3041186292053, 10284.151587436245, 10306.33008854422, 10575.471226438724, 13273.535426260034, 14167.574972750834, 1417.340936464549, 1816.4187460012802, 1816.8553811098923, 1855.1651756709534, 1888.9622207555772, 120379.19431208538, 120437.39781301125, 121896.0, 123218.38390807988, 125060.37469812683, 9158.81682366349, 11397.544098235978, 11653.41732913351, 11756.85363261055, 11866.821997670042, 14687.26563671812, 14705.044172128777, 14724.116553006854, 14744.55766327012, 14756.631084222765, 7424.517406368676, 8451.535165565952, 8641.876556047673, 8712.041675415827, 8825.293976481866, 8631.697890573925, 8632.050474447806, 8669.306455483546, 8690.565471726515, 8775.780605484786, 4447.933281000787, 4539.977300113478, 4543.318502224693, 4612.146752850734, 4625.4911959684405, 1913.7320775091498, 1942.8348590369694, 1962.2445358537066, 1965.9115339809744, 1969.743933288692, 796.8167321516083, 906.9365144439957, 917.8393781088283, 930.7114794413753, 970.146271281268, 20897.164113435596, 21648.675269870964, 21742.45643858916, 21754.564908701737, 21777.891110544664, 870.8737233101165, 946.6402766948653, 969.6412327438893, 991.9683529129625, 1048.826943554305, 10938.0, 11422.885771142348, 11449.942750286194, 11451.427428628534, 11506.424678766029, 507789.0, 525120.0, 528862.0, 529728.0, 547655.5234447605, 57237.0, 115306.0, 123499.0, 133985.0, 146294.0, 981.5582987655583, 1006.7432804634772, 1019.7501612104934, 1024.6004058417222, 1028.830243009895, 17058.061806600756, 18806.717899231247, 19026.52433689169, 19081.618367632847, 19275.51811204731, 51563.71090722762, 54502.0, 55356.0, 55786.0, 55897.0, 66228.0, 82133.0, 86810.56594716986, 87137.0, 87484.5625771867, 7063.39961103312, 7852.097008843828, 7894.802629934297, 7937.404694647809, 8279.047909490486, 10423.895761042293, 11058.557657693924, 13020.0, 13426.530071447452, 13583.79624305656, 11189.496472658588, 11342.60300889465, 11476.770464590494, 11651.766964660828, 11673.883261167279, 10861.511231994737, 11128.721781955515, 11225.8263732214, 11312.321260724053, 11579.594714184963, 11522.193446459003, 11575.942120289345, 11672.416379180837, 11701.88298117042, 11726.94136529312, 6433.646149461824, 7239.312265334785, 7461.477696561188, 7646.426518011061, 7703.422243331885, 9701.611935522782, 11358.716032098933, 11360.772784544428, 11462.025727813229, 11544.480498377425], "value": 9382.317054868843, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1563.1402728499322, 1585.6194513316805, 1822.6719190545705, 1832.9816701832985, 1951.1219951022044, 7926.128125906146, 8481.57592120393, 8626.611802468888, 9083.227925626321, 9109.31680123991, 8616.741497755062, 8770.210681038705, 8798.956005219981, 8809.986851512078, 8834.95582522088, 2338.7193536775612, 2365.526894621074, 2370.063824789207, 2372.0511795281855, 2373.5846226910307, 2332.090484710964, 2345.9178928737497, 2361.397843549901, 2372.620380739082, 2377.7622237776172, 2345.296411076679, 2356.4226764442737, 2363.420961864346, 2371.762823717623, 2374.608189648706, 2055.342290467051, 2300.5513924784673, 2304.6543018547186, 2339.30990357844, 2341.0284731836355, 2303.9539209215723, 2319.373769082355, 2325.395397196724, 2326.93019209424, 2339.368370539945, 8835.823283534357, 8836.513991730451, 8905.821883562357, 8914.0, 8924.330675199386, 5167.974160129175, 5180.145276029489, 5260.158374660036, 5329.546988505982, 5390.5418039466695, 3501.964980350215, 3541.3625547401716, 3577.141486043331, 3651.1054791575966, 3675.22820207754, 1025.6410256410272, 1071.9088877445427, 1074.1138560687464, 1091.2633972068902, 1103.6688993301977, 11372.37451940143, 11384.715382115513, 11438.19932604718, 11527.250728702607, 11529.769404611861, 4519.841805536857, 4596.747178905192, 4719.858404247878, 4742.66801323904, 4758.619310455155, 9799.804003919882, 9926.602935882484, 9951.900480995242, 9955.352902061342, 9961.900380996098, 5329.7068661224, 5337.0, 5338.626296159309, 5341.332333458317, 5351.491608297206, 4330.480342358937, 4735.881602959885, 4873.610111191097, 4883.706977581356, 4915.926261106087, 3913.5499417567344, 3966.7619942803526, 4035.556088830226, 4072.3484242521054, 4101.343784994447, 1183.8871460826795, 1292.4959265886312, 1303.2245813740808, 1306.3794697518604, 1306.764782339188, 855.2986551028222, 898.7887846356101, 898.9460632362079, 931.3806318797923, 945.2815859946353, 5614.859628509238, 5646.4353564644025, 5833.679147646835, 5896.321922978912, 5994.7602095916545, 420.10307992436367, 436.6921320469062, 436.892961224502, 442.2658387077436, 444.4155934945536, 3791.981040094837, 3828.1769419574366, 3927.8821635350987, 3934.15415685629, 3939.6848252140335, 10087.49562521871, 10542.998415150549, 10607.628732994308, 10630.415327156927, 10990.725231869108, 10587.788244235073, 10597.364158150536, 10616.893831061745, 10642.574297028032, 10767.676969690921, 7955.24425179607, 7972.60136993148, 8269.173082691683, 8273.793155171168, 8294.875576866352, 5590.888182236414, 5637.351704554028, 5706.514946229451, 5742.2535070442245, 5803.506701930214, 9735.513224338612, 9954.900450995397, 9970.252231082843, 9985.500724963867, 10048.799024019332, 9406.153446189875, 9678.177354924628, 10110.292279540361, 10123.443210623487, 10208.846867296848, 6940.27127151662, 7326.157491888501, 7493.100827900469, 7578.848423031398, 7682.0397450318715, 8456.40805143634, 8536.0, 8540.14598540141, 8541.402101852811, 8574.82850343002, 7364.96317518409, 7435.739749108971, 7595.696172153273, 7659.540427574363, 7661.15727270011, 7408.333250007525, 7544.471886968079, 7546.622668866425, 7565.735199268, 7642.121156067124, 6982.406495447741, 6999.230084690782, 7094.716211351488, 7149.106361704787, 7164.140303163654, 4643.2802915548145, 4819.204831202812, 4923.753812309442, 4949.529794669501, 5000.12497812874, 4782.497837726988, 4866.197077482176, 4903.0, 4917.508249175054, 4936.136176169228], "value": 4496.175308541429, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.2826330881436, 304.61618360865305, 318.72589572967246, 320.0989215358765, 322.50173481970353, 4060.1932140197623, 5092.981403719253, 5393.514583687467, 5703.629264097831, 5849.4735473807405, 5492.368377636573, 5531.751071201796, 5560.360558535759, 5616.297962754656, 5621.0, 3648.799316037617, 4245.639120674747, 4291.163223171483, 4294.4202532658, 4318.67609929255, 3771.283456143324, 3937.310970580151, 4191.266528357525, 4287.941787941774, 4318.179545886272, 3431.4852772084137, 4218.553239121317, 4237.131388065439, 4242.257604919142, 4254.319308910575, 3883.834849545139, 4030.186416112761, 4230.9153816923645, 4278.614924656765, 4311.28863737483, 3728.3475391806323, 3729.869454569077, 3730.9626903730887, 3743.5320584926885, 3744.0, 5040.412270134896, 5236.842894713165, 5277.6305658603915, 5395.895600715724, 5498.45015498451, 4034.6973976951554, 4901.43633482147, 4908.828191013297, 4911.336969509106, 4956.6282528810125, 5081.78037271056, 5591.74837132326, 5870.823875283749, 5939.881202375928, 5949.375315591844, 3550.6743083280494, 3860.455817672925, 3894.591067937854, 4107.260693075245, 4120.814563344657, 6158.784442544535, 6271.466925311354, 6282.151909492206, 6314.778982735628, 6323.857873093131, 3660.926781464356, 3678.521792167001, 4158.9376159357635, 4160.875173744793, 4239.194553034913, 3467.427874400746, 3832.904177395532, 3966.940495892564, 4233.491980962304, 4257.680673949466, 4595.85103724069, 4626.791794369262, 4971.552560269593, 4993.251012348104, 5002.124203423716, 5290.365156181283, 6032.426919442646, 6036.064410016366, 6089.5737298388685, 6098.451139397475, 1389.5761792653136, 1441.1137150652273, 1459.7226526959844, 1465.7874608181753, 1466.538040517255, 878.7653347047409, 882.7861690176009, 888.5868071346855, 903.2322525852973, 916.4821875640242, 3923.803809809498, 4568.360429539867, 4634.841289677581, 4662.5079974408545, 4667.7199368037345, 4467.597916187558, 4503.459584849839, 4867.878303042452, 5117.773768410753, 5209.609279304067, 3849.4610754494392, 4171.749695018248, 4185.895352616209, 4420.480326214091, 4712.787924543335, 2283.2579411691268, 2366.917112940636, 2442.7557244275786, 2656.738049426507, 2703.4998525272495, 4422.204003279377, 5193.454687257859, 5355.277037599952, 5434.315362237719, 5464.207689884945, 4897.1185186666025, 4922.90154196914, 4948.0, 5047.192449208109, 5320.308359913187, 4896.0, 5197.97401013, 5268.682829292677, 5316.431652662473, 5391.894661594442, 4289.721168124062, 4652.278896771006, 5035.475167382773, 5063.797448102035, 5239.43718271579, 5069.847904562869, 5077.517635824591, 5088.6946783193125, 5110.744462776921, 5391.514763671288, 5612.7193640317, 6305.274893387319, 6307.842303942438, 6411.647359395091, 6456.418922297016, 3126.2653276480464, 3979.0900367824183, 4025.2150830587752, 4028.5165780106577, 4084.959150408458, 6102.298235702951, 6120.663363514871, 6151.26184857802, 6170.938290617216, 6211.627302361872, 4859.586935110555, 5945.562319449005, 5965.657727011388, 5972.313183983897, 6009.849753756191, 5041.823536176216, 5719.685417302088, 5734.627249228787, 5854.9121763174435, 5979.641221526722, 5586.553075753849, 5630.930123276565, 5844.707764611668, 5853.736581853742, 5869.911951320819, 4596.678232523691, 5177.637565370388, 5880.235569375954, 5921.378255283305, 5961.642301461926, 5794.420557944172, 5799.739011744566, 5834.329052159056, 5846.444587764156, 5870.5010074144175], "value": 4119.306405177158, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3575110897975, 751.166205511882, 800.2277801921144, 833.1418638802036, 833.9374546908981, 15276.236188190602, 15750.13374264415, 15860.762088568667, 15912.52262432129, 15951.68096638068, 13986.510472133477, 14063.92968035161, 14076.859231407683, 14109.082909610894, 14136.646583835409, 704.031956060417, 870.3820287595814, 883.0463099852176, 897.4211633496424, 901.8241442918634, 885.6147575804546, 902.1024081039375, 902.3548163063408, 906.1934877958622, 906.9954650226773, 911.4667919267204, 939.4128669581512, 961.6538046303325, 963.5519483440199, 968.3415277611222, 935.2751617496426, 948.9715308540755, 952.7141857442739, 956.1346980982188, 959.889612694542, 674.8110529051874, 675.9729610815562, 713.357977819961, 714.7462650758957, 716.1549371741343, 14986.0, 15202.619934501616, 15308.387664493406, 15314.234288285541, 15356.616084597863, 14884.70230595382, 14982.0, 15003.0, 15017.699646007128, 15034.99999999989, 11968.222065565711, 12287.201331913486, 12564.434600443003, 12568.74862502754, 12696.936515317457, 3804.988118772333, 3828.2917660232947, 3923.3526468132723, 3940.5862384449792, 4023.054582173187, 92014.0, 98648.50675746576, 104072.47963760208, 106833.0, 107071.46464267629, 10907.345559266469, 11398.259113157617, 11478.540858365572, 11539.884601154048, 11950.282983021047, 22752.976116074817, 23416.414589635056, 23704.64443033356, 23751.406214844767, 23765.88117059438, 8231.917680823235, 8257.21556452136, 8278.379121565908, 8288.917110828814, 8354.498730076215, 4494.752788596593, 4588.38056862326, 4589.816407343736, 4608.976955115203, 4609.032103258353, 3422.144463884029, 3497.1082373994977, 3530.8411121499594, 3531.3643544161782, 3531.5762108546623, 656.0978654350268, 658.9275179730225, 661.7187695229508, 690.182134171009, 711.9430445564342, 614.5667304550282, 673.4948788408693, 703.4899697719216, 720.2149656874033, 727.6070921702327, 9140.54297285133, 9319.673811416704, 9389.859152112724, 9419.24646028316, 10502.737431564272, 618.5948203926436, 645.8288553533223, 666.6566718140161, 701.5650296816007, 713.1121753416926, 4497.370368148462, 4628.143793398232, 4735.195016847144, 4760.679107063334, 4768.451628062817, 97574.0, 100522.99477005283, 127189.0, 157211.6418253727, 164282.0, 91564.0, 99945.0, 126492.0, 133174.67777255693, 134018.0, 112295.0, 115079.0, 117874.0, 118198.22702659467, 119476.0, 7506.9249307506225, 9155.313351498531, 9262.770271951485, 9289.396189247676, 9292.117248861347, 61451.692741536004, 63487.68256158874, 63530.931677018634, 65224.0, 65710.34289657042, 92328.0, 97530.0, 98406.0, 100041.0, 102141.0, 11901.702457438634, 12011.0, 12058.457369418222, 12145.271283723005, 12406.193597416139, 13368.598942031755, 13389.732205356033, 13529.323533823073, 13568.59294221175, 14157.292135393394, 12399.628011159679, 12689.8096528554, 12714.364281785689, 12736.426860791473, 13147.276899770222, 12647.304398257817, 12733.681657958625, 12817.807732884201, 12897.48410063587, 12910.67723306925, 12271.961968901513, 12274.631761047183, 12446.004319654587, 12529.18560293578, 12730.745385092432, 9015.594298256463, 10692.663417072867, 10743.623973160902, 11022.724431889266, 11488.885111149117, 10059.74850628711, 11061.502232399722, 11543.413315040318, 11675.416229188337, 11774.646760597196], "value": 7876.467549748129, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.0000000000005, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 105.0, 148.0, 104.0, 154.0, 146.0, 137.0, 116.0, 116.0], "value": 137.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 131.0, 154.0, 108.0, 128.0, 125.0, 113.0, 159.0, 108.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 193.0, 199.0, 225.0, 197.0, 194.0, 196.0, 197.0, 229.0, 198.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 74.0, 137.0, 58.0, 59.0, 60.0, 68.0, 135.0, 59.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 117.0, 72.0, 72.0, 74.0, 115.0, 70.0, 73.0, 115.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 86.0, 81.0, 132.0, 123.0, 85.0, 91.0, 131.0, 126.0, 85.0], "value": 107.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 152.0, 95.0, 95.0, 96.0, 117.0, 122.0], "value": 105.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 92.0, 126.0, 90.0, 114.0, 147.0, 87.0, 145.0, 144.0], "value": 113.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 71.0, 71.0, 54.0, 53.0, 55.0, 54.0, 58.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 68.0, 78.0, 79.0, 76.0, 74.0, 76.0, 73.0, 99.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 90.0, 84.0, 79.0, 97.0, 97.0, 106.0, 75.0, 94.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 163.0, 162.0, 139.0, 136.0, 137.0, 140.0, 141.0, 215.0, 157.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 237.0, 167.0, 169.0, 164.0, 169.0, 172.0, 236.0, 169.0, 166.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 69.0, 70.0, 68.0, 67.0, 67.0, 65.0, 69.0], "value": 67.7, "unit": "score"}]}]} 16:00:49 INFO - Return code: 0 16:00:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:49 ERROR - # TBPL SUCCESS # 16:00:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:49 INFO - 2016-08-21 16:00:49.590 screenresolution[1677:10861] starting screenresolution argv=screenresolution get 16:00:49 INFO - 2016-08-21 16:00:49.593 screenresolution[1677:10861] Display 0: 1600x1200x32@60 16:00:49 INFO - 2016-08-21 16:00:49.599 screenresolution[1678:10864] starting screenresolution argv=screenresolution list 16:00:49 INFO - Available Modes on Display 0 16:00:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:49 INFO - Intel Iris: 16:00:49 INFO - Chipset Model: Intel Iris 16:00:49 INFO - Type: GPU 16:00:49 INFO - Bus: Built-In 16:00:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:49 INFO - Vendor: Intel (0x8086) 16:00:49 INFO - Device ID: 0x0a2e 16:00:49 INFO - Revision ID: 0x0009 16:00:49 INFO - Displays: 16:00:49 INFO - Display: 16:00:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:49 INFO - Display Serial Number: 1600x1200 60 16:00:49 INFO - Main Display: Yes 16:00:49 INFO - Mirror: Off 16:00:49 INFO - Online: Yes 16:00:49 INFO - Rotation: Supported 16:00:49 INFO - Return code: 0 16:00:49 INFO - Running post-action listener: _resource_record_post_action 16:00:49 INFO - [mozharness: 2016-08-21 23:00:49.832640Z] Finished run-tests step (success) 16:00:49 INFO - Running post-run listener: _resource_record_post_run 16:00:49 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 33569792; Write bytes: 711543296; Read time: 315; Write time: 1924 16:00:49 INFO - TinderboxPrint: CPU usage
54.4% 16:00:49 INFO - TinderboxPrint: I/O read bytes / time
33,569,792 / 315 16:00:49 INFO - TinderboxPrint: I/O write bytes / time
711,543,296 / 1,924 16:00:49 INFO - TinderboxPrint: CPU idle
2,205.6 (45.6%) 16:00:49 INFO - TinderboxPrint: CPU system
980.2 (20.3%) 16:00:49 INFO - TinderboxPrint: CPU user
1,651.8 (34.1%) 16:00:49 INFO - TinderboxPrint: Swap in / out
437,989,376 / 0 16:00:49 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387004928; Write bytes: 382424576; Read time: 29454; Write time: 765 16:00:49 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 32644096; Write bytes: 299664384; Read time: 301; Write time: 1108 16:00:50 INFO - Running post-run listener: _upload_blobber_files 16:00:50 INFO - Blob upload gear active. 16:00:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:00:50 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'] 16:00:50 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 16:00:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:00:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:00:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:51 INFO - (blobuploader) - INFO - Done attempting. 16:00:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:00:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:00:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:52 INFO - (blobuploader) - INFO - Done attempting. 16:00:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:54 INFO - (blobuploader) - INFO - Done attempting. 16:00:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:54 INFO - Return code: 0 16:00:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a976d9658c6ce44152aa0dcbdeea90bcf0b5d5aa19ff02022da43c31cb579b76978af69fce6d483b02290efa73e266b5ca3a8447404e9e2ee394e0c2553e9c0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22abd9faa6ec521e8b68c1c5d9f3dc2d58ad061d3c24c5f79790631fc7c9db9f42688183ef55b17f863e89b06a36af0bcfb9249444499509793870448c277e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be827a641890700b69532f2917ffe5367bd3f4b31d3f10e83f207a29fa4db4453dc9f5621026d18daa9b0020458bf1120e04ad1c9e5c35f4d48ba8e56c863af9"} 16:00:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:54 INFO - Contents: 16:00:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a976d9658c6ce44152aa0dcbdeea90bcf0b5d5aa19ff02022da43c31cb579b76978af69fce6d483b02290efa73e266b5ca3a8447404e9e2ee394e0c2553e9c0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22abd9faa6ec521e8b68c1c5d9f3dc2d58ad061d3c24c5f79790631fc7c9db9f42688183ef55b17f863e89b06a36af0bcfb9249444499509793870448c277e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be827a641890700b69532f2917ffe5367bd3f4b31d3f10e83f207a29fa4db4453dc9f5621026d18daa9b0020458bf1120e04ad1c9e5c35f4d48ba8e56c863af9"} 16:00:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:54 INFO - Copying logs to upload dir... 16:00:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.226304 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-21 16:00:54.921035) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 16:00:54.925334) ========= 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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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/a976d9658c6ce44152aa0dcbdeea90bcf0b5d5aa19ff02022da43c31cb579b76978af69fce6d483b02290efa73e266b5ca3a8447404e9e2ee394e0c2553e9c0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22abd9faa6ec521e8b68c1c5d9f3dc2d58ad061d3c24c5f79790631fc7c9db9f42688183ef55b17f863e89b06a36af0bcfb9249444499509793870448c277e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be827a641890700b69532f2917ffe5367bd3f4b31d3f10e83f207a29fa4db4453dc9f5621026d18daa9b0020458bf1120e04ad1c9e5c35f4d48ba8e56c863af9"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011030 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469095323/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a976d9658c6ce44152aa0dcbdeea90bcf0b5d5aa19ff02022da43c31cb579b76978af69fce6d483b02290efa73e266b5ca3a8447404e9e2ee394e0c2553e9c0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22abd9faa6ec521e8b68c1c5d9f3dc2d58ad061d3c24c5f79790631fc7c9db9f42688183ef55b17f863e89b06a36af0bcfb9249444499509793870448c277e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be827a641890700b69532f2917ffe5367bd3f4b31d3f10e83f207a29fa4db4453dc9f5621026d18daa9b0020458bf1120e04ad1c9e5c35f4d48ba8e56c863af9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 16:00:54.958459) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:00:54.958828) ========= 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.eZS3xejk4g/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.0Z1RtJkpUY/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.005083 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:00:55.007878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 16:00:55.008231) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 16:00:55.008612) ========= ========= Total master_lag: 0.19 =========