builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0035 starttime: 1469442366.07 results: success (0) buildid: 20160704040257 builduid: 3be2057788644f939f1fd300407b5d07 revision: 4191df8e4b8e1e2dd0593132dac25915f987f797 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.066821) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.067281) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.067573) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.096440) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.096819) ========= 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.YgJOm4hMob/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.eg1475aEOT/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-25 03:26:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.7M=0.001s 2016-07-25 03:26:06 (14.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122278 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.242004) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.242402) ========= 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.YgJOm4hMob/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.eg1475aEOT/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.027653 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:06.294902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 03:26:06.295338) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4191df8e4b8e1e2dd0593132dac25915f987f797 --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 4191df8e4b8e1e2dd0593132dac25915f987f797 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YgJOm4hMob/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.eg1475aEOT/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-25 03:26:06,371 truncating revision to first 12 chars 2016-07-25 03:26:06,371 Setting DEBUG logging. 2016-07-25 03:26:06,371 attempt 1/10 2016-07-25 03:26:06,371 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4191df8e4b8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 03:26:07,632 unpacking tar archive at: mozilla-central-4191df8e4b8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.548511 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 03:26:07.865866) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:07.866210) ========= script_repo_revision: 4191df8e4b8e1e2dd0593132dac25915f987f797 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:07.866607) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:07.866879) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 03:26:07.884982) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-25 03:26:07.885424) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.YgJOm4hMob/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.eg1475aEOT/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:08 INFO - MultiFileLogger online at 20160725 03:26:08 in /builds/slave/test 03:26:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:26:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:26:08 INFO - {'append_to_log': False, 03:26:08 INFO - 'base_work_dir': '/builds/slave/test', 03:26:08 INFO - 'blob_upload_branch': 'Firefox', 03:26:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:26:08 INFO - 'branch': 'Firefox', 03:26:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:26:08 INFO - 'config_files': ('talos/mac_config.py',), 03:26:08 INFO - 'default_actions': ('clobber', 03:26:08 INFO - 'read-buildbot-config', 03:26:08 INFO - 'download-and-extract', 03:26:08 INFO - 'populate-webroot', 03:26:08 INFO - 'create-virtualenv', 03:26:08 INFO - 'install', 03:26:08 INFO - 'run-tests'), 03:26:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:26:08 INFO - 'download_minidump_stackwalk': True, 03:26:08 INFO - 'download_symbols': 'ondemand', 03:26:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:26:08 INFO - 'tooltool.py': '/tools/tooltool.py', 03:26:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:26:08 INFO - '/tools/misc-python/virtualenv.py')}, 03:26:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:26:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:26:08 INFO - 'installer_path': 'installer.exe', 03:26:08 INFO - 'log_level': 'info', 03:26:08 INFO - 'log_name': 'talos', 03:26:08 INFO - 'log_to_console': True, 03:26:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:26:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:26:08 INFO - 'opt_config_files': (), 03:26:08 INFO - 'pip_index': False, 03:26:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:26:08 INFO - 'cmd': ('bash', 03:26:08 INFO - '-c', 03:26:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:26:08 INFO - 'enabled': True, 03:26:08 INFO - 'halt_on_failure': False, 03:26:08 INFO - 'name': 'check_screen_resolution'},), 03:26:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:26:08 INFO - 'cmd': ('bash', 03:26:08 INFO - '-c', 03:26:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:26:08 INFO - 'enabled': True, 03:26:08 INFO - 'halt_on_failure': False, 03:26:08 INFO - 'name': 'check_screen_resolution'},), 03:26:08 INFO - 'run_cmd_checks_enabled': True, 03:26:08 INFO - 'sps_profile': False, 03:26:08 INFO - 'sps_profile_interval': 0, 03:26:08 INFO - 'suite': 'dromaeojs', 03:26:08 INFO - 'system_bits': '32', 03:26:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:26:08 INFO - 'title': 't-yosemite-r7-0035', 03:26:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:26:08 INFO - 'use_talos_json': True, 03:26:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:26:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:26:08 INFO - 'work_dir': 'build'} 03:26:08 INFO - ##### 03:26:08 INFO - ##### Running clobber step. 03:26:08 INFO - ##### 03:26:08 INFO - Running pre-action listener: _resource_record_pre_action 03:26:08 INFO - Running main action method: clobber 03:26:08 INFO - rmtree: /builds/slave/test/build 03:26:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:26:10 INFO - Running post-action listener: _resource_record_post_action 03:26:10 INFO - ##### 03:26:10 INFO - ##### Finished clobber step (success) 03:26:10 INFO - ##### 03:26:10 INFO - ##### 03:26:10 INFO - ##### Running read-buildbot-config step. 03:26:10 INFO - ##### 03:26:10 INFO - Running pre-action listener: _resource_record_pre_action 03:26:10 INFO - Running main action method: read_buildbot_config 03:26:10 INFO - Using buildbot properties: 03:26:10 INFO - { 03:26:10 INFO - "project": "", 03:26:10 INFO - "product": "firefox", 03:26:10 INFO - "script_repo_revision": "production", 03:26:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:26:10 INFO - "repository": "", 03:26:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:26:10 INFO - "buildid": "20160704040257", 03:26:10 INFO - "pgo_build": "False", 03:26:10 INFO - "basedir": "/builds/slave/test", 03:26:10 INFO - "buildnumber": 17, 03:26:10 INFO - "slavename": "t-yosemite-r7-0035", 03:26:10 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:26:10 INFO - "platform": "yosemite_r7", 03:26:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:26:10 INFO - "branch": "mozilla-central", 03:26:10 INFO - "repo_path": "mozilla-central", 03:26:10 INFO - "revision": "4191df8e4b8e1e2dd0593132dac25915f987f797", 03:26:10 INFO - "stage_platform": "macosx64", 03:26:10 INFO - "builduid": "3be2057788644f939f1fd300407b5d07", 03:26:10 INFO - "slavebuilddir": "test" 03:26:10 INFO - } 03:26:10 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg. 03:26:10 INFO - Running post-action listener: _resource_record_post_action 03:26:10 INFO - ##### 03:26:10 INFO - ##### Finished read-buildbot-config step (success) 03:26:10 INFO - ##### 03:26:10 INFO - ##### 03:26:10 INFO - ##### Running download-and-extract step. 03:26:10 INFO - ##### 03:26:10 INFO - Running pre-action listener: _resource_record_pre_action 03:26:10 INFO - Running main action method: download_and_extract 03:26:10 INFO - mkdir: /builds/slave/test/build/tests 03:26:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.test_packages.json 03:26:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:26:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:26:10 INFO - Downloaded 1199 bytes. 03:26:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:26:10 INFO - Using the following test package requirements: 03:26:10 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:26:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:26:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:26:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'jsshell-mac.zip'], 03:26:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:26:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:26:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:26:10 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:26:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:26:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:26:10 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:26:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:26:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip 03:26:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:26:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:26:10 INFO - Downloaded 18795151 bytes. 03:26:10 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'] 03:26:10 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 03:26:14 INFO - Return code: 0 03:26:14 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 03:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip 03:26:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:26:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:26:14 INFO - Downloaded 18795151 bytes. 03:26:14 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'] 03:26:14 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 03:26:18 INFO - Return code: 0 03:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.talos.tests.zip 03:26:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:26:18 INFO - Downloaded 14403333 bytes. 03:26:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:26:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:26:18 INFO - Return code: 0 03:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg 03:26:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:26:19 INFO - Downloaded 112415553 bytes. 03:26:19 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg 03:26:19 INFO - mkdir: /builds/slave/test/properties 03:26:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:26:19 INFO - Writing to file /builds/slave/test/properties/build_url 03:26:19 INFO - Contents: 03:26:19 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg 03:26:19 INFO - Running post-action listener: _resource_record_post_action 03:26:19 INFO - Running post-action listener: set_extra_try_arguments 03:26:19 INFO - ##### 03:26:19 INFO - ##### Finished download-and-extract step (success) 03:26:19 INFO - ##### 03:26:19 INFO - ##### 03:26:19 INFO - ##### Running populate-webroot step. 03:26:19 INFO - ##### 03:26:19 INFO - Running pre-action listener: _resource_record_pre_action 03:26:19 INFO - Running main action method: populate_webroot 03:26:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:26:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:26:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:26:19 INFO - '--tppagecycles', 03:26:19 INFO - '7'], 03:26:19 INFO - 'tests': ['tsvgm']}}, 03:26:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:26:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:26:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:26:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:26:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['damp', 'tps']}, 03:26:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'tests': ['damp', 'tps']}, 03:26:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['dromaeo_dom']}, 03:26:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:26:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['basic_compositor_video']}, 03:26:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:26:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['a11yr', 03:26:19 INFO - 'ts_paint', 03:26:19 INFO - 'tpaint', 03:26:19 INFO - 'sessionrestore', 03:26:19 INFO - 'sessionrestore_no_auto_restore', 03:26:19 INFO - 'tabpaint']}, 03:26:19 INFO - 'other-e10s': {'tests': ['a11yr', 03:26:19 INFO - 'ts_paint', 03:26:19 INFO - 'tpaint', 03:26:19 INFO - 'sessionrestore', 03:26:19 INFO - 'sessionrestore_no_auto_restore', 03:26:19 INFO - 'tabpaint']}, 03:26:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:26:19 INFO - 'ts_paint', 03:26:19 INFO - 'tpaint', 03:26:19 INFO - 'sessionrestore', 03:26:19 INFO - 'sessionrestore_no_auto_restore', 03:26:19 INFO - 'tabpaint']}, 03:26:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:26:19 INFO - 'ts_paint', 03:26:19 INFO - 'tpaint', 03:26:19 INFO - 'sessionrestore', 03:26:19 INFO - 'sessionrestore_no_auto_restore', 03:26:19 INFO - 'tabpaint']}, 03:26:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['a11yr', 03:26:19 INFO - 'ts_paint', 03:26:19 INFO - 'tpaint', 03:26:19 INFO - 'sessionrestore', 03:26:19 INFO - 'sessionrestore_no_auto_restore', 03:26:19 INFO - 'tabpaint']}, 03:26:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['a11yr', 03:26:19 INFO - 'ts_paint', 03:26:19 INFO - 'tpaint', 03:26:19 INFO - 'sessionrestore', 03:26:19 INFO - 'sessionrestore_no_auto_restore', 03:26:19 INFO - 'tabpaint']}, 03:26:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['tsvgx', 03:26:19 INFO - 'tsvgr_opacity', 03:26:19 INFO - 'tart', 03:26:19 INFO - 'tscrollx', 03:26:19 INFO - 'cart']}, 03:26:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:26:19 INFO - 'tsvgr_opacity', 03:26:19 INFO - 'tart', 03:26:19 INFO - 'tscrollx', 03:26:19 INFO - 'cart']}, 03:26:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'talos_options': ['--disable-e10s'], 03:26:19 INFO - 'tests': ['tp5o']}, 03:26:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'tests': ['tp5o']}, 03:26:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'talos_options': ['--disable-e10s', 03:26:19 INFO - '--xperf_path', 03:26:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:19 INFO - 'tests': ['tp5n']}, 03:26:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:19 INFO - 'talos_options': ['--xperf_path', 03:26:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:19 INFO - 'tests': ['tp5n']}}, 03:26:19 INFO - 'talos.zip': {'path': '', 03:26:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:26:19 INFO - Running post-action listener: _resource_record_post_action 03:26:19 INFO - ##### 03:26:19 INFO - ##### Finished populate-webroot step (success) 03:26:19 INFO - ##### 03:26:19 INFO - ##### 03:26:19 INFO - ##### Running create-virtualenv step. 03:26:19 INFO - ##### 03:26:19 INFO - Running pre-action listener: _resource_record_pre_action 03:26:19 INFO - Running main action method: create_virtualenv 03:26:19 INFO - Creating virtualenv /builds/slave/test/build/venv 03:26:19 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 03:26:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:26:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:26:20 INFO - Using real prefix '/tools/python27' 03:26:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:26:20 INFO - Installing distribute.............................................................................................................................................................................................done. 03:26:23 INFO - Installing pip.................done. 03:26:23 INFO - Return code: 0 03:26:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:23 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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:23 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 03:26:23 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 03:26:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:23 INFO - 'HOME': '/Users/cltbld', 03:26:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:23 INFO - 'LOGNAME': 'cltbld', 03:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:23 INFO - 'MOZ_NO_REMOTE': '1', 03:26:23 INFO - 'NO_EM_RESTART': '1', 03:26:23 INFO - 'PAGER': '/bin/cat', 03:26:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:23 INFO - 'PWD': '/builds/slave/test', 03:26:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:23 INFO - 'SHELL': '/bin/bash', 03:26:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:23 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:23 INFO - 'USER': 'cltbld', 03:26:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:23 INFO - 'XPC_FLAGS': '0x0', 03:26:23 INFO - 'XPC_SERVICE_NAME': '0', 03:26:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:23 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 03:26:23 INFO - Cleaning up... 03:26:23 INFO - Return code: 0 03:26:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:23 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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:23 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 03:26:23 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 03:26:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:23 INFO - 'HOME': '/Users/cltbld', 03:26:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:23 INFO - 'LOGNAME': 'cltbld', 03:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:23 INFO - 'MOZ_NO_REMOTE': '1', 03:26:23 INFO - 'NO_EM_RESTART': '1', 03:26:23 INFO - 'PAGER': '/bin/cat', 03:26:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:23 INFO - 'PWD': '/builds/slave/test', 03:26:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:23 INFO - 'SHELL': '/bin/bash', 03:26:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:23 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:23 INFO - 'USER': 'cltbld', 03:26:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:23 INFO - 'XPC_FLAGS': '0x0', 03:26:23 INFO - 'XPC_SERVICE_NAME': '0', 03:26:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:24 INFO - Downloading/unpacking psutil>=3.1.1 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:26:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:27 INFO - Installing collected packages: psutil 03:26:27 INFO - Running setup.py install for psutil 03:26:27 INFO - building 'psutil._psutil_osx' extension 03:26:27 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 03:26:27 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 03:26:27 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 03:26:27 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 03:26:28 INFO - building 'psutil._psutil_posix' extension 03:26:28 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 03:26:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:26:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:26:28 INFO - ^ 03:26:28 INFO - 1 warning generated. 03:26:28 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 03:26:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:28 INFO - Successfully installed psutil 03:26:28 INFO - Cleaning up... 03:26:28 INFO - Return code: 0 03:26:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:28 INFO - 'HOME': '/Users/cltbld', 03:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:28 INFO - 'LOGNAME': 'cltbld', 03:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:28 INFO - 'MOZ_NO_REMOTE': '1', 03:26:28 INFO - 'NO_EM_RESTART': '1', 03:26:28 INFO - 'PAGER': '/bin/cat', 03:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:28 INFO - 'PWD': '/builds/slave/test', 03:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:28 INFO - 'SHELL': '/bin/bash', 03:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:28 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:28 INFO - 'USER': 'cltbld', 03:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:28 INFO - 'XPC_FLAGS': '0x0', 03:26:28 INFO - 'XPC_SERVICE_NAME': '0', 03:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:26:32 INFO - Installing collected packages: mozsystemmonitor 03:26:32 INFO - Running setup.py install for mozsystemmonitor 03:26:32 INFO - Successfully installed mozsystemmonitor 03:26:32 INFO - Cleaning up... 03:26:32 INFO - Return code: 0 03:26:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:26:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:32 INFO - 'HOME': '/Users/cltbld', 03:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:32 INFO - 'LOGNAME': 'cltbld', 03:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:32 INFO - 'MOZ_NO_REMOTE': '1', 03:26:32 INFO - 'NO_EM_RESTART': '1', 03:26:32 INFO - 'PAGER': '/bin/cat', 03:26:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:32 INFO - 'PWD': '/builds/slave/test', 03:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:32 INFO - 'SHELL': '/bin/bash', 03:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:32 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:32 INFO - 'USER': 'cltbld', 03:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:32 INFO - 'XPC_FLAGS': '0x0', 03:26:32 INFO - 'XPC_SERVICE_NAME': '0', 03:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:32 INFO - Downloading/unpacking jsonschema==2.5.1 03:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:26:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:35 INFO - Downloading functools32-3.2.3-2.tar.gz 03:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:26:35 INFO - warning: no files found matching '*.txt' 03:26:35 INFO - no previously-included directories found matching 'build' 03:26:35 INFO - no previously-included directories found matching 'dist' 03:26:35 INFO - no previously-included directories found matching '.git*' 03:26:35 INFO - Installing collected packages: jsonschema, functools32 03:26:35 INFO - Running setup.py install for functools32 03:26:35 INFO - warning: no files found matching '*.txt' 03:26:35 INFO - no previously-included directories found matching 'build' 03:26:35 INFO - no previously-included directories found matching 'dist' 03:26:35 INFO - no previously-included directories found matching '.git*' 03:26:35 INFO - Successfully installed jsonschema functools32 03:26:35 INFO - Cleaning up... 03:26:35 INFO - Return code: 0 03:26:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:26:35 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')]} 03:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:35 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')]} 03:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:35 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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:35 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 03:26:35 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 03:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:36 INFO - 'HOME': '/Users/cltbld', 03:26:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:36 INFO - 'LOGNAME': 'cltbld', 03:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:36 INFO - 'MOZ_NO_REMOTE': '1', 03:26:36 INFO - 'NO_EM_RESTART': '1', 03:26:36 INFO - 'PAGER': '/bin/cat', 03:26:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:36 INFO - 'PWD': '/builds/slave/test', 03:26:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:36 INFO - 'SHELL': '/bin/bash', 03:26:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:36 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:36 INFO - 'USER': 'cltbld', 03:26:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:36 INFO - 'XPC_FLAGS': '0x0', 03:26:36 INFO - 'XPC_SERVICE_NAME': '0', 03:26:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:36 INFO - Downloading/unpacking blobuploader==1.2.4 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:38 INFO - Downloading blobuploader-1.2.4.tar.gz 03:26:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:26:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:26:38 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 03:26:38 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 03:26:38 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 03:26:38 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 03:26:38 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 03:26:38 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 03:26:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:26:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:39 INFO - Downloading docopt-0.6.1.tar.gz 03:26:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:26:40 INFO - Installing collected packages: blobuploader, requests, docopt 03:26:40 INFO - Running setup.py install for blobuploader 03:26:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:26:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:26:40 INFO - Running setup.py install for requests 03:26:40 INFO - Running setup.py install for docopt 03:26:40 INFO - Successfully installed blobuploader requests docopt 03:26:40 INFO - Cleaning up... 03:26:40 INFO - Return code: 0 03:26:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:40 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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:40 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 03:26:40 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 03:26:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:40 INFO - 'HOME': '/Users/cltbld', 03:26:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:40 INFO - 'LOGNAME': 'cltbld', 03:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:40 INFO - 'MOZ_NO_REMOTE': '1', 03:26:40 INFO - 'NO_EM_RESTART': '1', 03:26:40 INFO - 'PAGER': '/bin/cat', 03:26:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:40 INFO - 'PWD': '/builds/slave/test', 03:26:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:40 INFO - 'SHELL': '/bin/bash', 03:26:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:40 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:40 INFO - 'USER': 'cltbld', 03:26:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:40 INFO - 'XPC_FLAGS': '0x0', 03:26:40 INFO - 'XPC_SERVICE_NAME': '0', 03:26:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-j0l8HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-npHOd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-La9nqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-qsgEdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-M3k5JQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-7X5Cq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-_E2O8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:41 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-AU1wSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-PUxQ6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ftx183-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-SHkqHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-kUQhWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-nkqd3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-z4eN29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-b382_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-pLc89m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:42 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-KW9nOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:26:42 INFO - Running setup.py install for manifestparser 03:26:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:26:43 INFO - Running setup.py install for mozcrash 03:26:43 INFO - Running setup.py install for mozdebug 03:26:43 INFO - Running setup.py install for mozdevice 03:26:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:26:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:26:43 INFO - Running setup.py install for mozfile 03:26:43 INFO - Running setup.py install for mozhttpd 03:26:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:26:43 INFO - Running setup.py install for mozinfo 03:26:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Running setup.py install for mozInstall 03:26:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Running setup.py install for mozleak 03:26:44 INFO - Running setup.py install for mozlog 03:26:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Running setup.py install for moznetwork 03:26:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Running setup.py install for mozprocess 03:26:44 INFO - Running setup.py install for mozprofile 03:26:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:26:44 INFO - Running setup.py install for mozrunner 03:26:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:26:45 INFO - Running setup.py install for mozscreenshot 03:26:45 INFO - Running setup.py install for moztest 03:26:45 INFO - Running setup.py install for mozversion 03:26:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:26:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:26:45 INFO - Cleaning up... 03:26:45 INFO - Return code: 0 03:26:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:45 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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:45 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 03:26:45 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 03:26:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:45 INFO - 'HOME': '/Users/cltbld', 03:26:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:45 INFO - 'LOGNAME': 'cltbld', 03:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:45 INFO - 'MOZ_NO_REMOTE': '1', 03:26:45 INFO - 'NO_EM_RESTART': '1', 03:26:45 INFO - 'PAGER': '/bin/cat', 03:26:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:45 INFO - 'PWD': '/builds/slave/test', 03:26:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:45 INFO - 'SHELL': '/bin/bash', 03:26:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:45 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:45 INFO - 'USER': 'cltbld', 03:26:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:45 INFO - 'XPC_FLAGS': '0x0', 03:26:45 INFO - 'XPC_SERVICE_NAME': '0', 03:26:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:45 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-uW5Y3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:45 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)) 03:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:45 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-kKiBMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-RVFlhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Nr6v6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-O07oi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-kqfHD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-srOAFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Ri6vZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-bjUrDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-YWvnfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-jJN7FJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:46 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)) 03:26:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:46 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-bhiSyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:47 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-s0G0od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:47 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)) 03:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:47 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Bl5uuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:47 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-RZdWO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:47 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)) 03:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:47 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-DHvMvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:47 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)) 03:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:47 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Ld1MZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:47 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)) 03:26:47 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)) 03:26:47 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)) 03:26:47 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)) 03:26:47 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)) 03:26:47 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)) 03:26:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26:47 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 03:26:47 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 03:26:47 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 03:26:47 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 03:26:47 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 03:26:47 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 03:26:50 INFO - Downloading blessings-1.6.tar.gz 03:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:26:50 INFO - Installing collected packages: blessings 03:26:50 INFO - Running setup.py install for blessings 03:26:50 INFO - Successfully installed blessings 03:26:50 INFO - Cleaning up... 03:26:50 INFO - Return code: 0 03:26:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:26:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:50 INFO - Reading from file tmpfile_stdout 03:26:50 INFO - Current package versions: 03:26:50 INFO - blessings == 1.6 03:26:50 INFO - blobuploader == 1.2.4 03:26:50 INFO - docopt == 0.6.1 03:26:50 INFO - functools32 == 3.2.3-2 03:26:50 INFO - jsonschema == 2.5.1 03:26:50 INFO - manifestparser == 1.1 03:26:50 INFO - mozInstall == 1.12 03:26:50 INFO - mozcrash == 0.17 03:26:50 INFO - mozdebug == 0.1 03:26:50 INFO - mozdevice == 0.48 03:26:50 INFO - mozfile == 1.2 03:26:50 INFO - mozhttpd == 0.7 03:26:50 INFO - mozinfo == 0.9 03:26:50 INFO - mozleak == 0.1 03:26:50 INFO - mozlog == 3.2 03:26:50 INFO - moznetwork == 0.27 03:26:50 INFO - mozprocess == 0.22 03:26:50 INFO - mozprofile == 0.28 03:26:50 INFO - mozrunner == 6.11 03:26:50 INFO - mozscreenshot == 0.1 03:26:50 INFO - mozsystemmonitor == 0.3 03:26:50 INFO - moztest == 0.7 03:26:50 INFO - mozversion == 1.4 03:26:50 INFO - psutil == 3.1.1 03:26:50 INFO - requests == 1.2.3 03:26:50 INFO - wsgiref == 0.1.2 03:26:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:50 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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:50 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 03:26:50 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 03:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:50 INFO - 'HOME': '/Users/cltbld', 03:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:50 INFO - 'LOGNAME': 'cltbld', 03:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:50 INFO - 'MOZ_NO_REMOTE': '1', 03:26:50 INFO - 'NO_EM_RESTART': '1', 03:26:50 INFO - 'PAGER': '/bin/cat', 03:26:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:50 INFO - 'PWD': '/builds/slave/test', 03:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:50 INFO - 'SHELL': '/bin/bash', 03:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:50 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:50 INFO - 'USER': 'cltbld', 03:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:50 INFO - 'XPC_FLAGS': '0x0', 03:26:50 INFO - 'XPC_SERVICE_NAME': '0', 03:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 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)) 03:26:50 INFO - Cleaning up... 03:26:50 INFO - Return code: 0 03:26:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:50 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 0x10fcbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedd04753b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, '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.YgJOm4hMob/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.eg1475aEOT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 03:26:50 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 03:26:50 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 03:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:50 INFO - 'HOME': '/Users/cltbld', 03:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:50 INFO - 'LOGNAME': 'cltbld', 03:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:50 INFO - 'MOZ_NO_REMOTE': '1', 03:26:50 INFO - 'NO_EM_RESTART': '1', 03:26:50 INFO - 'PAGER': '/bin/cat', 03:26:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:50 INFO - 'PWD': '/builds/slave/test', 03:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:50 INFO - 'SHELL': '/bin/bash', 03:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:26:50 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:50 INFO - 'USER': 'cltbld', 03:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:50 INFO - 'XPC_FLAGS': '0x0', 03:26:50 INFO - 'XPC_SERVICE_NAME': '0', 03:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:26:51 INFO - Cleaning up... 03:26:51 INFO - Return code: 0 03:26:51 INFO - Running post-action listener: _resource_record_post_action 03:26:51 INFO - Running post-action listener: _start_resource_monitoring 03:26:51 INFO - Starting resource monitoring. 03:26:51 INFO - ##### 03:26:51 INFO - ##### Finished create-virtualenv step (success) 03:26:51 INFO - ##### 03:26:51 INFO - ##### 03:26:51 INFO - ##### Running install step. 03:26:51 INFO - ##### 03:26:51 INFO - Running pre-action listener: _resource_record_pre_action 03:26:51 INFO - Running main action method: install 03:26:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:51 INFO - Reading from file tmpfile_stdout 03:26:51 INFO - Detecting whether we're running mozinstall >=1.0... 03:26:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:26:51 INFO - Reading from file tmpfile_stdout 03:26:51 INFO - Output received: 03:26:51 INFO - Usage: mozinstall [options] installer 03:26:51 INFO - Options: 03:26:51 INFO - -h, --help show this help message and exit 03:26:51 INFO - -d DEST, --destination=DEST 03:26:51 INFO - Directory to install application into. [default: 03:26:51 INFO - "/builds/slave/test"] 03:26:51 INFO - --app=APP Application being installed. [default: firefox] 03:26:51 INFO - mkdir: /builds/slave/test/build/application 03:26:51 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'] 03:26:51 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 03:27:25 INFO - Reading from file tmpfile_stdout 03:27:25 INFO - Output received: 03:27:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:27:25 INFO - Running post-action listener: _resource_record_post_action 03:27:25 INFO - ##### 03:27:25 INFO - ##### Finished install step (success) 03:27:25 INFO - ##### 03:27:25 INFO - ##### 03:27:25 INFO - ##### Running run-tests step. 03:27:25 INFO - ##### 03:27:25 INFO - Running pre-action listener: _resource_record_pre_action 03:27:25 INFO - Running main action method: run_tests 03:27:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:27:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:27:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:27:25 INFO - 2016-07-25 03:27:25.905 screenresolution[1666:7778] starting screenresolution argv=screenresolution get 03:27:25 INFO - 2016-07-25 03:27:25.908 screenresolution[1666:7778] Display 0: 1600x1200x32@60 03:27:25 INFO - 2016-07-25 03:27:25.913 screenresolution[1667:7780] starting screenresolution argv=screenresolution list 03:27:25 INFO - Available Modes on Display 0 03:27:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:27:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:27:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:27:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:27:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:27:26 INFO - Intel Iris: 03:27:26 INFO - Chipset Model: Intel Iris 03:27:26 INFO - Type: GPU 03:27:26 INFO - Bus: Built-In 03:27:26 INFO - VRAM (Dynamic, Max): 1536 MB 03:27:26 INFO - Vendor: Intel (0x8086) 03:27:26 INFO - Device ID: 0x0a2e 03:27:26 INFO - Revision ID: 0x0009 03:27:26 INFO - Displays: 03:27:26 INFO - Display: 03:27:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:27:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:27:26 INFO - Display Serial Number: 1600x1200 60 03:27:26 INFO - Main Display: Yes 03:27:26 INFO - Mirror: Off 03:27:26 INFO - Online: Yes 03:27:26 INFO - Rotation: Supported 03:27:26 INFO - Return code: 0 03:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:27:26 INFO - Python 2.7.3 03:27:26 INFO - Return code: 0 03:27:26 INFO - grabbing minidump binary from tooltool 03:27:26 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')]} 03:27:26 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 0x10fc21d30>, '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 0x10f986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc19b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:27:26 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 03:27:26 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 03:27:26 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 03:27:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:27:26 INFO - Return code: 0 03:27:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:27:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:27:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:27:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:27:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:27:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YgJOm4hMob/Render', 03:27:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:27:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:27:26 INFO - 'HOME': '/Users/cltbld', 03:27:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:27:26 INFO - 'LOGNAME': 'cltbld', 03:27:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:27:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:26 INFO - 'MOZ_NO_REMOTE': '1', 03:27:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:27:26 INFO - 'NO_EM_RESTART': '1', 03:27:26 INFO - 'PAGER': '/bin/cat', 03:27:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:26 INFO - 'PWD': '/builds/slave/test', 03:27:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:27:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:27:26 INFO - 'SHELL': '/bin/bash', 03:27:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eg1475aEOT/Listeners', 03:27:26 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 03:27:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:27:26 INFO - 'USER': 'cltbld', 03:27:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:27:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:26 INFO - 'XPC_FLAGS': '0x0', 03:27:26 INFO - 'XPC_SERVICE_NAME': '0', 03:27:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:27:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:27:26 INFO - mozversion application_buildid: 20160704040257 03:27:26 INFO - mozversion application_changeset: 4191df8e4b8e1e2dd0593132dac25915f987f797 03:27:26 INFO - mozversion application_display_name: Nightly 03:27:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:27:26 INFO - mozversion application_name: Firefox 03:27:26 INFO - mozversion application_remotingname: firefox 03:27:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:27:26 INFO - mozversion application_vendor: Mozilla 03:27:26 INFO - mozversion application_version: 50.0a1 03:27:26 INFO - mozversion platform_buildid: 20160704040257 03:27:26 INFO - mozversion platform_changeset: 4191df8e4b8e1e2dd0593132dac25915f987f797 03:27:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:27:26 INFO - mozversion platform_version: 50.0a1 03:27:26 INFO - using testdate: 1469442446 03:27:26 INFO - actual date: 1469442446 03:27:26 INFO - starting webserver on 'localhost:49265' 03:27:26 INFO - SUITE-START | Running 2 tests 03:27:26 INFO - TEST-START | dromaeo_css 03:27:26 INFO - operating with platform_type : mac_ 03:27:26 INFO - Initialising browser for dromaeo_css test... 03:27:26 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpWtfl8K/profile http://localhost:49265/getInfo.html) 03:27:28 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 03:27:28 INFO - PROCESS | 1677 | colorDepth:24 03:27:28 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 03:27:28 INFO - PROCESS | 1677 | __metrics 03:27:28 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:27:28 INFO - PROCESS | 1677 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:27:29 INFO - TEST-INFO | 1677: exit 0 03:27:29 INFO - Browser initialized. 03:27:29 INFO - Running cycle 1/1 for dromaeo_css test... 03:27:29 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpWtfl8K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:27:41 INFO - PROCESS | 1680 | RSS: Main: 243810304 03:27:41 INFO - PROCESS | 1680 | 03:28:33 INFO - PROCESS | 1680 | 1469442513410 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] 03:30:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 03:30:45 INFO - PROCESS | 1680 | RSS: Main: 392384512 03:30:45 INFO - PROCESS | 1680 | 03:33:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 03:33:49 INFO - PROCESS | 1680 | RSS: Main: 383979520 03:33:49 INFO - PROCESS | 1680 | 03:36:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 03:36:52 INFO - PROCESS | 1680 | RSS: Main: 375713792 03:36:52 INFO - PROCESS | 1680 | 03:39:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 03:39:55 INFO - PROCESS | 1680 | RSS: Main: 376754176 03:39:55 INFO - PROCESS | 1680 | 03:42:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 03:42:59 INFO - PROCESS | 1680 | RSS: Main: 347090944 03:42:59 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 03:46:02 INFO - PROCESS | 1680 | RSS: Main: 426598400 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | __start_tp_report 03:46:02 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 03:46:02 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 03:46:02 INFO - PROCESS | 1680 | |i|pagename|runs| 03:46:02 INFO - PROCESS | 1680 | |0;dojo.html;2789.9442011159776;3337.282484265884;3338.3490219407195;3372.6290108088115;3418.3505134024535;5651.491365777079;5689.061304884689;5695.17419974104;5749.022666146755;5829.44620261075;5741.425857414267;5770.682612456313;5774.249347584819;5778.075507918734;5803.854903627412;5152.536271735553;5178.223266510016;5198.584113270948;5221.530062294392;5272.446393128724;5236.738163091849;5265.289185959904;5272.103742363788;5275.973620131894;5282.947170528303;5156.278121063054;5202.557782588466;5210.062188806013;5224.608154388436;5264.2893209416625;5246.711430871301;5250.711210883419;5267.86830329241;5280.551153151987;5320.6275560710765;5056.721880296583;5082.567981721558;5147.819826306099;5192.792288308463;5196.402413722432;5627.493525582676;5640.323161220639;5679.460451257165;5714.51426628737;5735.770569177228;6270.31026587075;6277.121203031534;6278.529110316745;6282.78010269643;6286.9056964145575;6117.388261173892;6204.441600255954;6286.277078136027;6341.3024567298025;6354.364563543609;5783.450572195635;5804.564657650735;5805.970970145207;5820.35976042635;5823.068309070529;6256.593321434183;6270.968645156836;6272.8118156455375;6273.99616061428;6280.623162610258;5997.160397544348;6021.976264035125;6052.515798736091;6069.393060693896;6120.975172448123;6169.259688837368;6199.5660303779205;6217.937820621826;6229.377062293825;6304.243490781136;6161.476274516716;6216.968915155395;6217.378262173746;6225.035119556476;6249.593776404519;6214.40963108504;6230.127782110508;6238.376162383725;6269.435750782452;6270.278917924497;4834.734089625034;4905.264210368473;4908.04293162837;4934.432540257916;4946.455889852114;4436.3123715824095;4459.197344478025;4476.955230447654;4478.373027776114;4488.551144885485;5775.422457754191;5775.682337471395;5840.707964601753;5853.326867410217;5920.881582368328;6249.437550620466;6300.338464461257;6333.841653958688;6344.302126766052;6348.174737284124;5827.94172058274;5840.474357307863;5842.182094506772;5849.210356601804;5889.440503152194;5356.8660783479945;5380.892382152335;5396.595255355864;5475.67145971243;5491.615586908958;5076.263941728503;6272.749090036347;6318.557700960889;6327.90508142369;6382.521310901701;6271.905921411274;6272.435480806749;6310.337414571588;6319.399657032574;6327.873442531031;6218.968905155626;6252.312245652884;6314.778982735582;6342.2389313280855;6347.904781428375;6163.568550201443;6200.720967556381;6244.68776561161;6260.874782504416;6269.247690277196;6304.905426418516;6319.96840015797;6326.367363263821;6347.809565713036;6351.079093531504;5993.580449368503;6023.819285421444;6079.270487541523;6185.938140618537;6196.845078873064;5925.022371308686;6084.056971169476;6113.476306051762;6129.540284478593;6157;6004.279486461478;6008.819735407945;6011.308699499614;6036.973714468463;6044.788432404845;5964.701764911651;5985.610935289193;6056.788012419544;6158.476529495043;6203.658798766111;5944.346121926498;5991.041433370639;6001.3698561652145;6001.819945401645;6056.06131049688 03:46:02 INFO - PROCESS | 1680 | |1;ext.html;514.0284861611555;523.6386893043799;528.3870709976426;529.8528685396118;546.1834557336782;27419.862900685494;30996.69003309966;42133.15733685325;42912.356314655306;43199;44653;45005.32492012622;45007.549924500745;45021;45048.3242751359;8235.794105147374;9918.00819918006;9923.255755818342;9929.553170107363;9940.850887236662;8733.912660873406;8953.23897468716;11891.821622675667;12114.697132571668;12133.272003679764;12020.69948251292;12097.697557561043;12104.697382565419;12119.151659383846;12125.818112728271;7425.517341372847;9016.413933094393;11988.340641264727;12062.69843253917;12089.758204835898;8802.735917922473;8839.558022098869;11594;11885.465154068088;11895.464704088337;42132;42353.364699529535;42490.78754606238;42539;42622;3202.439573074703;3351.2794749129052;3534.540509733718;3649.197176621139;3965.2466031453932;32361.352772944643;32925.6707432925;33934.49098263528;33991.66008339934;34036.48945265823;3286.917827054319;3347.09628400333;3352.346292472969;3397.2016576104597;3409.556757621518;366937.1653141717;368748;370466;370620.9999999973;371080;63990;64480.67759661265;64553.354466455676;64620;64641.35358646447;65590.67204664042;66015;66043;66050;66056.33943660598;16513.83486165147;16785;16786.244618992176;16800.227962386314;16872.746908796133;11657.59198428068;11909;12712.491500340067;12890.80663790044;12991.545295914597;16864.988100713996;17059.744103838453;17062.061586612854;17200.569985750455;17248.741268880978;6261.530385221126;6498.642574658439;7187.346910210576;7424.257574242641;7488.887666685004;953.3279038277998;1041.8489319048847;1065.1798115451213;1093.9835902461468;1130.6608017594688;30549;31408.214794630316;31470.89851855174;31491.84254078761;32251.838740806146;871.2855458524076;910.9569542873373;1089.8746644136027;1105.9447027648584;1126.7633796902578;16417.83582164163;16435.01389916609;16455.177241138135;16503.91748041252;16927.576810579834;521055;645966;648824;649308.2880441621;650138;62573.68713156405;62638.686806566584;62656;62756.37243627505;62797.37202628006;1289.3810970733912;1314.3953781260727;1441.3081720774296;1441.5242969819935;1459.88320934325;30777.615007324275;32941;32971.340573188885;33047.834760827005;33654.82208122704;52896.73551632217;53385.46614533805;53423;53520.92958140738;53578;521696;541194;541519;541628;541633;36180.45729314115;42741.78629106835;42916;43952.12095758003;44809.55190448054;50228.49771502238;50443.747781260856;50879.98240035253;51259.74370128125;51262.48737512577;41626.791866040476;41892.95267618334;42158.578414215466;42179.789101054295;43212.567874320855;41431.47832434277;41605.79197103995;42067.57932420637;42391;42822.35766463568;40033.99915002148;40373.19253614853;40786.79606601948;40793.796031020844;43142.78428607837;33930.66069339374;36080.819595901856;37057.25885482329;37062.814685926394;37068;26482.337941551614;26636.20091397261;26655.7334426661;26847.597286041117;27271.72728272692 03:46:02 INFO - PROCESS | 1680 | |2;jquery.html;384.6576546873283;392.34628996165264;394.48125714685193;395.6854300830841;416.1780483544997;14217.502387416442;20570.48573785656;21444.678329825067;21755.021024053935;21783;15813.525594232191;19294.614107717836;21190.894045529752;21323.893380533074;21628.2430114946;5828.358880523149;6030.668313242792;6079.422454866781;6094.481969032616;6096.116063170837;5790.623609465392;5981.760729570812;6022.3074346450285;6038.396160383971;6080.939190608104;5066.544011039005;5953.077273022688;5969.970150149265;5979.641221526722;5990.341062483123;4898.53463920927;5970.910436343458;5982.760689572412;5986.551008674367;5998.220231369894;5155.9484405155845;5170.844874653766;5181.689098654093;5184.755658641937;5188.573142385874;25189;25629.871850640626;26114.477710445684;26320.86839565809;26542.867285663448;2392.605220138675;2600.9726158167605;2635.6705411823523;2771.7366850149206;2818.85905704716;15139.621509462351;15505.302261398265;15692.68614627701;15845.92077039619;16329.02025878451;1823.2798044772242;1837.6232872261287;1878.2768634075842;1907.4563749331512;1908;116367.41816290864;120937.39531302375;121188;121192.78807211992;121872.39063804713;10261.692149235534;10482.10902073317;10713.982171693677;10765.677029689123;10898.128149748001;13405.530806421728;13498.257595832323;13506.93246533761;13512.391942362618;13517.391717372742;7477.962610186914;7511.737089201852;8327.958360208282;8529;8601.913980860236;8698.086700896314;8705.216530512158;8720.651173953098;8849;8868.911310886937;5878.5297176225795;6402.711877965503;6510.837229069217;6517.51118666102;6595.868082638321;1609.7907272054792;1696.041736418944;1750.5273576134373;1784.090114041822;1784.090114041822;800.4236949396425;937.840567103594;948.7390967483997;1033.6227277043824;1050.7338656918396;21109.155633774477;22140.889295553417;22145.114195432;22209;22290.21984230544;998.5356809083665;1002.1381611813835;1006.8137394582026;1017.1659239423601;1073.2540884085486;10302;11330;11380.203385762918;11842.171048026556;11847.170698051226;486191;488332;583378;601903;610452;125828.04633559797;143153.5684643161;148024;150630;155142.22428887783;428.72347335968124;447.4027173723116;450.40096671426926;452.8369786876722;453.091551439362;10737.785244295226;11087.611933582286;11162.386068766296;11254.887451125383;11339.319640821577;54880.451195487534;55747.442525574224;56030.719846400505;56451.43548564626;57297.71351143218;77111.61444192968;80188;80691;86705;88546.11453885378;6438.422586466346;7412.629368531444;7718.57547834852;7871.448998570045;8076.919230807616;11086;12812.167209131376;12835.165714228546;13174.011949104048;13175.934120329337;10955;11168.944155279496;11281.32312061279;11391.202615816814;11479.942600287279;9433.056694330511;9465.053494650756;11044.944775276072;11132.38771867555;11359.318440893572;11289.830652540382;11354.602588909678;11632;11651.242669226473;11662.591809286634;7928.643211055401;8186.713465028695;8294.29498492635;8403;8410.453320534145;10264.486775661038;11289.322640641587;11360.375179364884;11364.261323013978;11487.36819474937 03:46:02 INFO - PROCESS | 1680 | |3;mootools.html;1518.1725959352152;1561.7657351397293;1756.2360373237643;1901.087478010554;1917.5110346861547;8791.604377802998;9029.051949545285;9213.078692130792;9222.308326875485;9223.169914707674;7899.447038707292;8148.877766833488;8724.040355560883;8963.238124759388;8985.685501007467;2367.7332627044534;2401.735809061002;2402.8798560072014;2407.1334319645002;2412.2280870121567;2374.02664907388;2384.7376788553247;2406.831521793475;2412.4692567635093;2413.7706917842866;2100.2229175205193;2200.3838925101;2328.941776455585;2391.258709799959;2399.1243196233336;2367.183321753992;2373.2049763329305;2391.8804059797026;2402.903883844644;2407.5666380051584;2154.7737487563736;2212.159379435804;2366.538524987628;2387.8089752819733;2392.3899405651446;9074.18332350085;9131.0412406697;9149.542522873897;9161.908380916173;9177.036411176798;5010.874728131789;5094.032133894548;5347.224652425377;5505.366882808488;5526.834194974157;3578.499010138583;3720.832562534665;3732.8880133596035;3787.6780473659774;3792.2036372361836;932.4778124250441;948.3645957208618;1102.9889701102966;1110.9944450277696;1112.8942750438696;11973.46119424628;12192.146549741434;12216.816747748791;12224.877751222462;12261.141720079599;4733.479317275097;4816.20532612122;4833.782479788348;4919.877003074952;4939.777710002986;10383;10401.323913945569;10420.947895260626;10438.895611043945;10443.11233545142;5555.194496798023;5561.304836895388;5565.638233514808;5572.247746554244;5601.6358936668985;4955.11705551888;5072;5074.619403544675;5075.086484432837;5083.923741143886;3912.6087391261217;3954.406838974119;4010.2981978153707;4043.1913617276664;4050.7164498485413;1203.0796440722854;1241.6833707404553;1251.8497780266243;1277.444311724403;1292.6122163350958;638.9744410223632;929.3819609959437;954.5395544816442;959.3572306554626;973.3964941736169;5010.674306170087;5323.494268044529;6074.696265186723;6094.908576371358;6174;653.8394350028642;946.2335508238349;1019.5463018956641;1046.2310202001522;1086.8532748079067;3694.2796154749567;3983.2630963271886;4063.918721625551;4107.096438783463;4108;10585.311954722918;10914.236003479838;10923.453827308604;10934.507947142398;10957.780844383067;10047.899521004843;10654.467276636293;10937.83593246102;10955.014048735651;10977.780444391068;8318.62566184511;8331.916680833236;8549.914500854911;8558.272546833465;8571.314294856396;4809.807607695653;4934.481879402754;6153.84615384619;6207.720652570555;6441.355864413521;10140.898591013995;10271.84592231132;10514.053735163872;10522.158227341948;10534.42060686639;10237.795244095225;10542.420166890588;10624.52189651483;10648.574057037631;10648.574057037942;7717.768466946;7764.34003109742;7780.144184139627;7855.293023627901;7863.567503787347;8357.080721120794;8500.957495212484;8526.531040792815;8534;8544.57277136153;7631.618419078913;7747.031621047369;7752.844943100994;7888.684452621831;7891.092524359772;7548.886766698394;7560.130584982798;7585.089789225108;7625.1231108421025;7655.2344765523;6382.329855365306;7058.223595404604;7218.242084581043;7306.96346518264;7320.597367144645;4992.900141997067;5123.666961647481;5239.214117882349;5241.240020197126;5331.840044798662;5191.844244672666;5202.557782588522;5297.655652382583;5331.973340133274;5335.013022590833 03:46:02 INFO - PROCESS | 1680 | |4;prototype.html;304.17720067218175;307.2702331961591;313.21695760598504;316.14955768981423;317.5015226095032;4182.6235638792505;4788.401449818773;5265.447128051557;5331.600129990252;5337.813176538822;4126.422300877872;4344.478662560489;4844;5259.289995850559;5280.19825725478;3286.063471910494;3306.5536152619447;3757.6805971492454;3781.2815565042692;3829.5021647185827;3377.4596064629663;3790.374588192945;3970.8014599270064;4012.9766909438054;4017.6183262590007;3251.9349613007726;3975.522937247534;3979.263836190314;3982.522097348308;3989.740666856645;3220.7423406127573;3277.36091462165;3713;3873.7532231305777;3953.683705303569;3014.3820516794003;3177.3645270945904;3209.1816586770224;3487.860485580574;3532.9047995121596;4370.8907277318;4992.076465853793;5065.544101030925;5085.364329458817;5122.871928201787;3885.9028524286837;4028.234635419261;4413.933790993137;4590.977045114786;4610.884727881797;4817.253325734517;5448.482394172547;5485.286912701324;5494.313210848644;5514.779408823642;3887.33915234408;3892.708046896494;3893;3905.199434116028;3920.157166209279;5566.582506312043;5686.658800471943;5780.566457515703;5812.360640329561;5815.476607105338;3887.5918028607157;3913.92172156561;3925.1168487090176;3929.7052721045466;3946.881593552198;3980.5024371953505;3999.4800675911947;4007.0583412897945;4010.6991975601945;4020.4572382728543;4434.55654434554;4596.816127354935;4598.954010459853;4601.240795268744;4634.467036290802;4502.4371953505815;5265.947340526623;5469.343678758574;5507.504324610724;5513.173024046425;1113.6659002299439;1355.803408505781;1364.979525307121;1365.6244532753572;1367.3163418290856;629.6442509981846;829.7925518620345;839.2195258409738;840.4075127035426;852.2415050604967;4152.754173747924;4749.955009897817;5003.399592048904;5063.164577844688;5172.456892026358;5645.237892884408;5668.51817595509;5672.461116193955;5689.544836413077;5690.345610254791;4352.412424322676;4485.25993211123;4978.352814134139;5146.382434107931;5208.8958220835375;2732.5218086834725;2992.850357482117;2997.2656699108857;2997.28065264335;3006.977627606624;5844.1818145459665;5894.263217097863;5900.881982360329;5907.409259074144;5908.615939963888;5406.864828379322;5562.3881373048935;5808.564357673192;5820.330661973927;5874.706264686749;5073.416557095908;5428.0500912340185;5856.707164641751;5890.259685099907;5916.6924899289315;5055.87360315995;5200.323957885451;5670.943290567042;5693.572982026448;5704.3725190228215;5086.135356989246;5221.660592061504;5405.675659460445;5592.468715472024;5603.607747457639;4964.553190212754;5121.487851214849;5438.972805135949;5696.20253164549;5851.970740146442;2769.6814866290633;3319.7344212463427;3326.817025063645;3335.483000134983;3368.393689135979;4745.762711864462;4809.302651115639;5003.44962054181;5215.81744638951;5717.371089180104;5304.310439642822;5548.528375088002;5564.415736347625;5573.470520300565;5619.578531610227;5234.24103504982;5561.582881283839;5573.080441727069;5590.86022849432;5593.161025846156;4928.654994150375;5289.80979279659;5383.973080134574;5429.724014856584;5459.935312614002;3954.9406758899213;5261.605379596622;5273.367195936512;5513.5037846594;5560.499555040065;4872.610191184767;4992.6020714200085;5477.370102438271;5495.862603434946;5647.858803529944 03:46:02 INFO - PROCESS | 1680 | |5;yui.html;707.5012116457898;735.5843948169285;775.5997312571633;839.2907992746127;860.681547827304;15181;16247;16265;16298.266578003988;16306.429274975379;12054.638360849167;14899.478518251863;14931.328090235938;14931.626709332271;14933.328000239988;709.7267551992481;745.0872680965828;899.6851102114273;924.7780532672177;934.0426063285153;873.8863947686792;881.6561540999015;889.5374405309235;891.0332289465937;893.3344658229596;969.6751588217955;972.1687956796943;972.3048020665223;973.6543527047902;973.9172170365492;756.3608750605729;768.8539177556246;783.9886546355195;786.3316181245953;933.6125507914276;722.3390597603187;722.8445884134936;724.091265461842;724.3951300663952;724.873147199238;15639.843601563953;15654.452094176762;15656.21718914061;15670.843291567053;15750.2124893756;14619;14741.336639851232;14752.188629625367;14830.258487075604;14863.256837158098;13548.525801596898;13611.387487563083;13645.249511276876;13774.242416667079;14167.645808854759;4008.599140085997;4737.26572381278;4847.297141914438;4904.754762261872;4910.337104490919;86100;86214;86688.5665571668;90821;119442;11464.426778661034;11649.47577359021;11661.591844285409;11667.474963626657;12055.57805476804;23408.531829363317;23415;23448.944797484153;23460.413489662897;23892.7610723894;7887.250711182429;8076.4750291230885;8097.392695547857;8114.698040459478;8221.753347399588;4468.709533880287;4529.207388706963;4533.52397998212;4536.455625324982;4697.248440249544;3553.3959226931483;3669.614690457517;3676.6874815640435;3677.0439685681918;3677.1358730698266;568.943105689436;656.2158220925947;665.2748504130424;688.686647575362;743.7880204141794;597.5458651424862;648.7869964832641;662.3244290823333;702.304718328861;705.4004096518021;9505.809883802285;9782.168515676247;9798.755031124136;9837.311388202757;9933.65232216869;672.6065251827736;677.8949262864264;690.3062422265591;699.1714817940655;699.9335063168991;4594.690513203721;4666.346711460398;4723.197056500403;4744.525547445228;4810.39870016248;103654;104160.47919760353;129029;167790;168172;138968.30515847355;143150;145039.2748036253;146853.26573367065;147671;94486;95984.52007739917;107177.46411267894;107262;111330.88669113205;10026.498675066363;10032.749181270527;10044.296899216984;10132.240081993732;10168.796624067625;62210.37789622227;62428;63670.68164659147;65292.67353663391;65949.0107648376;97596.53605196069;99849.50225746474;100468.99531004597;101398;102105;11517.712057198638;11893.881061189277;11921.34432606215;12227.266364018187;13876.236806975714;13231.669208269484;13639.318034098455;14579.92710036443;15880.29805544287;16270.918645406697;14166;14387.799433716147;14435.35040923182;14754.409823607364;14891.851081489047;14421.423143074579;14429.062110962754;14841.554753356984;14875.925620372262;15104.093754374773;14275.714485710436;14308.284585770461;14328.570142895729;14388;14684.97205195626;11497;11508.827367589325;11560.30638161713;11645.216003192792;11741.530338786353;8922.776930576787;11639.867846914223;11676.766464670489;11820.23168494045;12025.75948481008 03:46:02 INFO - PROCESS | 1680 | __end_tp_report 03:46:02 INFO - PROCESS | 1680 | __start_cc_report 03:46:02 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1474 03:46:02 INFO - PROCESS | 1680 | __end_cc_report 03:46:02 INFO - PROCESS | 1680 | __startTimestamp1469443562785__endTimestamp 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | ------- Summary: start ------- 03:46:02 INFO - PROCESS | 1680 | Number of tests: 6 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | [#0] dojo.html Cycles:165 Average:5762.58 Median:5997.16 stddev:657.39 (11.0%) stddev-sans-first:616.66 03:46:02 INFO - PROCESS | 1680 | Values: 2789.9 3337.3 3338.3 3372.6 3418.4 5651.5 5689.1 5695.2 5749.0 5829.4 5741.4 5770.7 5774.2 5778.1 5803.9 5152.5 5178.2 5198.6 5221.5 5272.4 5236.7 5265.3 5272.1 5276.0 5282.9 5156.3 5202.6 5210.1 5224.6 5264.3 5246.7 5250.7 5267.9 5280.6 5320.6 5056.7 5082.6 5147.8 5192.8 5196.4 5627.5 5640.3 5679.5 5714.5 5735.8 6270.3 6277.1 6278.5 6282.8 6286.9 6117.4 6204.4 6286.3 6341.3 6354.4 5783.5 5804.6 5806.0 5820.4 5823.1 6256.6 6271.0 6272.8 6274.0 6280.6 5997.2 6022.0 6052.5 6069.4 6121.0 6169.3 6199.6 6217.9 6229.4 6304.2 6161.5 6217.0 6217.4 6225.0 6249.6 6214.4 6230.1 6238.4 6269.4 6270.3 4834.7 4905.3 4908.0 4934.4 4946.5 4436.3 4459.2 4477.0 4478.4 4488.6 5775.4 5775.7 5840.7 5853.3 5920.9 6249.4 6300.3 6333.8 6344.3 6348.2 5827.9 5840.5 5842.2 5849.2 5889.4 5356.9 5380.9 5396.6 5475.7 5491.6 5076.3 6272.7 6318.6 6327.9 6382.5 6271.9 6272.4 6310.3 6319.4 6327.9 6219.0 6252.3 6314.8 6342.2 6347.9 6163.6 6200.7 6244.7 6260.9 6269.2 6304.9 6320.0 6326.4 6347.8 6351.1 5993.6 6023.8 6079.3 6185.9 6196.8 5925.0 6084.1 6113.5 6129.5 6157.0 6004.3 6008.8 6011.3 6037.0 6044.8 5964.7 5985.6 6056.8 6158.5 6203.7 5944.3 5991.0 6001.4 6001.8 6056.1 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | [#1] ext.html Cycles:180 Average:67066.59 Median:40579.99 stddev:138979.24 (342.5%) stddev-sans-first:139279.29 03:46:02 INFO - PROCESS | 1680 | Values: 514.0 523.6 528.4 529.9 546.2 27419.9 30996.7 42133.2 42912.4 43199.0 44653.0 45005.3 45007.5 45021.0 45048.3 8235.8 9918.0 9923.3 9929.6 9940.9 8733.9 8953.2 11891.8 12114.7 12133.3 12020.7 12097.7 12104.7 12119.2 12125.8 7425.5 9016.4 11988.3 12062.7 12089.8 8802.7 8839.6 11594.0 11885.5 11895.5 42132.0 42353.4 42490.8 42539.0 42622.0 3202.4 3351.3 3534.5 3649.2 3965.2 32361.4 32925.7 33934.5 33991.7 34036.5 3286.9 3347.1 3352.3 3397.2 3409.6 366937.2 368748.0 370466.0 370621.0 371080.0 63990.0 64480.7 64553.4 64620.0 64641.4 65590.7 66015.0 66043.0 66050.0 66056.3 16513.8 16785.0 16786.2 16800.2 16872.7 11657.6 11909.0 12712.5 12890.8 12991.5 16865.0 17059.7 17062.1 17200.6 17248.7 6261.5 6498.6 7187.3 7424.3 7488.9 953.3 1041.8 1065.2 1094.0 1130.7 30549.0 31408.2 31470.9 31491.8 32251.8 871.3 911.0 1089.9 1105.9 1126.8 16417.8 16435.0 16455.2 16503.9 16927.6 521055.0 645966.0 648824.0 649308.3 650138.0 62573.7 62638.7 62656.0 62756.4 62797.4 1289.4 1314.4 1441.3 1441.5 1459.9 30777.6 32941.0 32971.3 33047.8 33654.8 52896.7 53385.5 53423.0 53520.9 53578.0 521696.0 541194.0 541519.0 541628.0 541633.0 36180.5 42741.8 42916.0 43952.1 44809.6 50228.5 50443.7 50880.0 51259.7 51262.5 41626.8 41893.0 42158.6 42179.8 43212.6 41431.5 41605.8 42067.6 42391.0 42822.4 40034.0 40373.2 40786.8 40793.8 43142.8 33930.7 36080.8 37057.3 37062.8 37068.0 26482.3 26636.2 26655.7 26847.6 27271.7 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | [#2] jquery.html Cycles:180 Average:34485.59 Median:25409.44 stddev:94037.99 (370.1%) stddev-sans-first:94266.93 03:46:02 INFO - PROCESS | 1680 | Values: 384.7 392.3 394.5 395.7 416.2 14217.5 20570.5 21444.7 21755.0 21783.0 15813.5 19294.6 21190.9 21323.9 21628.2 5828.4 6030.7 6079.4 6094.5 6096.1 5790.6 5981.8 6022.3 6038.4 6080.9 5066.5 5953.1 5970.0 5979.6 5990.3 4898.5 5970.9 5982.8 5986.6 5998.2 5155.9 5170.8 5181.7 5184.8 5188.6 25189.0 25629.9 26114.5 26320.9 26542.9 2392.6 2601.0 2635.7 2771.7 2818.9 15139.6 15505.3 15692.7 15845.9 16329.0 1823.3 1837.6 1878.3 1907.5 1908.0 116367.4 120937.4 121188.0 121192.8 121872.4 10261.7 10482.1 10714.0 10765.7 10898.1 13405.5 13498.3 13506.9 13512.4 13517.4 7478.0 7511.7 8328.0 8529.0 8601.9 8698.1 8705.2 8720.7 8849.0 8868.9 5878.5 6402.7 6510.8 6517.5 6595.9 1609.8 1696.0 1750.5 1784.1 1784.1 800.4 937.8 948.7 1033.6 1050.7 21109.2 22140.9 22145.1 22209.0 22290.2 998.5 1002.1 1006.8 1017.2 1073.3 10302.0 11330.0 11380.2 11842.2 11847.2 486191.0 488332.0 583378.0 601903.0 610452.0 125828.0 143153.6 148024.0 150630.0 155142.2 428.7 447.4 450.4 452.8 453.1 10737.8 11087.6 11162.4 11254.9 11339.3 54880.5 55747.4 56030.7 56451.4 57297.7 77111.6 80188.0 80691.0 86705.0 88546.1 6438.4 7412.6 7718.6 7871.4 8076.9 11086.0 12812.2 12835.2 13174.0 13175.9 10955.0 11168.9 11281.3 11391.2 11479.9 9433.1 9465.1 11044.9 11132.4 11359.3 11289.8 11354.6 11632.0 11651.2 11662.6 7928.6 8186.7 8294.3 8403.0 8410.5 10264.5 11289.3 11360.4 11364.3 11487.4 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | [#3] mootools.html Cycles:180 Average:5863.69 Median:4927.18 stddev:3315.12 (67.3%) stddev-sans-first:3308.33 03:46:02 INFO - PROCESS | 1680 | Values: 1518.2 1561.8 1756.2 1901.1 1917.5 8791.6 9029.1 9213.1 9222.3 9223.2 7899.4 8148.9 8724.0 8963.2 8985.7 2367.7 2401.7 2402.9 2407.1 2412.2 2374.0 2384.7 2406.8 2412.5 2413.8 2100.2 2200.4 2328.9 2391.3 2399.1 2367.2 2373.2 2391.9 2402.9 2407.6 2154.8 2212.2 2366.5 2387.8 2392.4 9074.2 9131.0 9149.5 9161.9 9177.0 5010.9 5094.0 5347.2 5505.4 5526.8 3578.5 3720.8 3732.9 3787.7 3792.2 932.5 948.4 1103.0 1111.0 1112.9 11973.5 12192.1 12216.8 12224.9 12261.1 4733.5 4816.2 4833.8 4919.9 4939.8 10383.0 10401.3 10420.9 10438.9 10443.1 5555.2 5561.3 5565.6 5572.2 5601.6 4955.1 5072.0 5074.6 5075.1 5083.9 3912.6 3954.4 4010.3 4043.2 4050.7 1203.1 1241.7 1251.8 1277.4 1292.6 639.0 929.4 954.5 959.4 973.4 5010.7 5323.5 6074.7 6094.9 6174.0 653.8 946.2 1019.5 1046.2 1086.9 3694.3 3983.3 4063.9 4107.1 4108.0 10585.3 10914.2 10923.5 10934.5 10957.8 10047.9 10654.5 10937.8 10955.0 10977.8 8318.6 8331.9 8549.9 8558.3 8571.3 4809.8 4934.5 6153.8 6207.7 6441.4 10140.9 10271.8 10514.1 10522.2 10534.4 10237.8 10542.4 10624.5 10648.6 10648.6 7717.8 7764.3 7780.1 7855.3 7863.6 8357.1 8501.0 8526.5 8534.0 8544.6 7631.6 7747.0 7752.8 7888.7 7891.1 7548.9 7560.1 7585.1 7625.1 7655.2 6382.3 7058.2 7218.2 7307.0 7320.6 4992.9 5123.7 5239.2 5241.2 5331.8 5191.8 5202.6 5297.7 5332.0 5335.0 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | [#4] prototype.html Cycles:180 Average:4420.02 Median:4998.00 stddev:1386.72 (27.7%) stddev-sans-first:1355.76 03:46:02 INFO - PROCESS | 1680 | Values: 304.2 307.3 313.2 316.1 317.5 4182.6 4788.4 5265.4 5331.6 5337.8 4126.4 4344.5 4844.0 5259.3 5280.2 3286.1 3306.6 3757.7 3781.3 3829.5 3377.5 3790.4 3970.8 4013.0 4017.6 3251.9 3975.5 3979.3 3982.5 3989.7 3220.7 3277.4 3713.0 3873.8 3953.7 3014.4 3177.4 3209.2 3487.9 3532.9 4370.9 4992.1 5065.5 5085.4 5122.9 3885.9 4028.2 4413.9 4591.0 4610.9 4817.3 5448.5 5485.3 5494.3 5514.8 3887.3 3892.7 3893.0 3905.2 3920.2 5566.6 5686.7 5780.6 5812.4 5815.5 3887.6 3913.9 3925.1 3929.7 3946.9 3980.5 3999.5 4007.1 4010.7 4020.5 4434.6 4596.8 4599.0 4601.2 4634.5 4502.4 5265.9 5469.3 5507.5 5513.2 1113.7 1355.8 1365.0 1365.6 1367.3 629.6 829.8 839.2 840.4 852.2 4152.8 4750.0 5003.4 5063.2 5172.5 5645.2 5668.5 5672.5 5689.5 5690.3 4352.4 4485.3 4978.4 5146.4 5208.9 2732.5 2992.9 2997.3 2997.3 3007.0 5844.2 5894.3 5900.9 5907.4 5908.6 5406.9 5562.4 5808.6 5820.3 5874.7 5073.4 5428.1 5856.7 5890.3 5916.7 5055.9 5200.3 5670.9 5693.6 5704.4 5086.1 5221.7 5405.7 5592.5 5603.6 4964.6 5121.5 5439.0 5696.2 5852.0 2769.7 3319.7 3326.8 3335.5 3368.4 4745.8 4809.3 5003.4 5215.8 5717.4 5304.3 5548.5 5564.4 5573.5 5619.6 5234.2 5561.6 5573.1 5590.9 5593.2 4928.7 5289.8 5384.0 5429.7 5459.9 3954.9 5261.6 5273.4 5513.5 5560.5 4872.6 4992.6 5477.4 5495.9 5647.9 03:46:02 INFO - PROCESS | 1680 | 03:46:02 INFO - PROCESS | 1680 | [#5] yui.html Cycles:180 Average:24890.03 Median:13723.08 stddev:39097.27 (284.9%) stddev-sans-first:39164.79 03:46:02 INFO - PROCESS | 1680 | Values: 707.5 735.6 775.6 839.3 860.7 15181.0 16247.0 16265.0 16298.3 16306.4 12054.6 14899.5 14931.3 14931.6 14933.3 709.7 745.1 899.7 924.8 934.0 873.9 881.7 889.5 891.0 893.3 969.7 972.2 972.3 973.7 973.9 756.4 768.9 784.0 786.3 933.6 722.3 722.8 724.1 724.4 724.9 15639.8 15654.5 15656.2 15670.8 15750.2 14619.0 14741.3 14752.2 14830.3 14863.3 13548.5 13611.4 13645.2 13774.2 14167.6 4008.6 4737.3 4847.3 4904.8 4910.3 86100.0 86214.0 86688.6 90821.0 119442.0 11464.4 11649.5 11661.6 11667.5 12055.6 23408.5 23415.0 23448.9 23460.4 23892.8 7887.3 8076.5 8097.4 8114.7 8221.8 4468.7 4529.2 4533.5 4536.5 4697.2 3553.4 3669.6 3676.7 3677.0 3677.1 568.9 656.2 665.3 688.7 743.8 597.5 648.8 662.3 702.3 705.4 9505.8 9782.2 9798.8 9837.3 9933.7 672.6 677.9 690.3 699.2 699.9 4594.7 4666.3 4723.2 4744.5 4810.4 103654.0 104160.5 129029.0 167790.0 168172.0 138968.3 143150.0 145039.3 146853.3 147671.0 94486.0 95984.5 107177.5 107262.0 111330.9 10026.5 10032.7 10044.3 10132.2 10168.8 62210.4 62428.0 63670.7 65292.7 65949.0 97596.5 99849.5 100469.0 101398.0 102105.0 11517.7 11893.9 11921.3 12227.3 13876.2 13231.7 13639.3 14579.9 15880.3 16270.9 14166.0 14387.8 14435.4 14754.4 14891.9 14421.4 14429.1 14841.6 14875.9 15104.1 14275.7 14308.3 14328.6 14388.0 14685.0 11497.0 11508.8 11560.3 11645.2 11741.5 8922.8 11639.9 11676.8 11820.2 12025.8 03:46:02 INFO - PROCESS | 1680 | -------- Summary: end -------- 03:46:02 INFO - PROCESS | 1680 | 03:46:03 INFO - TEST-INFO | 1680: exit 0 03:46:03 INFO - TEST-OK | dromaeo_css | took 1116912ms 03:46:03 INFO - TEST-START | kraken 03:46:03 INFO - operating with platform_type : mac_ 03:46:03 INFO - Initialising browser for kraken test... 03:46:03 INFO - TEST-INFO | started process 1823 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpExreH4/profile http://localhost:49265/getInfo.html) 03:46:05 INFO - PROCESS | 1823 | __metrics Screen width/height:1600/1200 03:46:05 INFO - PROCESS | 1823 | colorDepth:24 03:46:05 INFO - PROCESS | 1823 | Browser inner width/height: 1024/689 03:46:05 INFO - PROCESS | 1823 | __metrics 03:46:05 INFO - PROCESS | 1823 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:46:05 INFO - PROCESS | 1823 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:46:06 INFO - TEST-INFO | 1823: exit 0 03:46:06 INFO - Browser initialized. 03:46:06 INFO - Running cycle 1/1 for kraken test... 03:46:06 INFO - TEST-INFO | started process 1824 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpExreH4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:46:18 INFO - PROCESS | 1824 | RSS: Main: 244707328 03:46:18 INFO - PROCESS | 1824 | 03:46:20 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 03:46:21 INFO - PROCESS | 1824 | RSS: Main: 294113280 03:46:21 INFO - PROCESS | 1824 | 03:46:24 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 03:46:25 INFO - PROCESS | 1824 | RSS: Main: 304418816 03:46:25 INFO - PROCESS | 1824 | 03:46:28 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 03:46:29 INFO - PROCESS | 1824 | RSS: Main: 390799360 03:46:29 INFO - PROCESS | 1824 | 03:46:31 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 03:46:31 INFO - PROCESS | 1824 | RSS: Main: 293347328 03:46:31 INFO - PROCESS | 1824 | 03:46:34 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:46:34 INFO - PROCESS | 1824 | RSS: Main: 297459712 03:46:34 INFO - PROCESS | 1824 | 03:46:37 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 03:46:38 INFO - PROCESS | 1824 | RSS: Main: 336408576 03:46:38 INFO - PROCESS | 1824 | 03:46:41 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 03:46:41 INFO - PROCESS | 1824 | RSS: Main: 338894848 03:46:41 INFO - PROCESS | 1824 | 03:46:44 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 03:46:45 INFO - PROCESS | 1824 | RSS: Main: 337702912 03:46:45 INFO - PROCESS | 1824 | 03:46:46 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:46:47 INFO - PROCESS | 1824 | RSS: Main: 313700352 03:46:47 INFO - PROCESS | 1824 | 03:46:49 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:46:49 INFO - PROCESS | 1824 | RSS: Main: 320778240 03:46:49 INFO - PROCESS | 1824 | 03:46:52 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:46:52 INFO - PROCESS | 1824 | RSS: Main: 318005248 03:46:52 INFO - PROCESS | 1824 | 03:46:55 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:46:56 INFO - PROCESS | 1824 | RSS: Main: 327426048 03:46:56 INFO - PROCESS | 1824 | 03:46:58 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:46:59 INFO - PROCESS | 1824 | RSS: Main: 329011200 03:46:59 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 03:47:01 INFO - PROCESS | 1824 | RSS: Main: 372514816 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | __start_tp_report 03:47:01 INFO - PROCESS | 1824 | _x_x_mozilla_page_load 03:47:01 INFO - PROCESS | 1824 | _x_x_mozilla_page_load_details 03:47:01 INFO - PROCESS | 1824 | |i|pagename|runs| 03:47:01 INFO - PROCESS | 1824 | |0;ai-astar;102;113;117;160;165;111;112;110;111;112 03:47:01 INFO - PROCESS | 1824 | |1;audio-beat-detection;151;132;128;170;106;105;123;103;156;105 03:47:01 INFO - PROCESS | 1824 | |2;audio-dft;185;183;184;184;183;181;179;182;183;183 03:47:01 INFO - PROCESS | 1824 | |3;audio-fft;78;74;60;125;84;57;60;65;93;57 03:47:01 INFO - PROCESS | 1824 | |4;audio-oscillator;86;88;92;70;70;80;127;101;87;98 03:47:01 INFO - PROCESS | 1824 | |5;imaging-gaussian-blur;89;86;90;71;118;131;101;87;89;85 03:47:01 INFO - PROCESS | 1824 | |6;imaging-darkroom;93;93;94;93;93;94;93;94;93;93 03:47:01 INFO - PROCESS | 1824 | |7;imaging-desaturate;83;83;83;85;84;85;88;83;83;86 03:47:01 INFO - PROCESS | 1824 | |8;json-parse-financial;54;54;52;54;56;55;53;53;55;55 03:47:01 INFO - PROCESS | 1824 | |9;json-stringify-tinderbox;65;73;70;77;71;70;71;70;69;73 03:47:01 INFO - PROCESS | 1824 | |10;stanford-crypto-aes;85;81;72;80;96;103;102;101;100;96 03:47:01 INFO - PROCESS | 1824 | |11;stanford-crypto-ccm;136;136;137;139;142;144;136;135;174;155 03:47:01 INFO - PROCESS | 1824 | |12;stanford-crypto-pbkdf2;162;160;160;162;161;162;161;162;162;160 03:47:01 INFO - PROCESS | 1824 | |13;stanford-crypto-sha256-iterative;57;77;84;83;70;66;64;65;66;66 03:47:01 INFO - PROCESS | 1824 | __end_tp_report 03:47:01 INFO - PROCESS | 1824 | __start_cc_report 03:47:01 INFO - PROCESS | 1824 | _x_x_mozilla_cycle_collect,2045 03:47:01 INFO - PROCESS | 1824 | __end_cc_report 03:47:01 INFO - PROCESS | 1824 | __startTimestamp1469443621630__endTimestamp 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | ------- Summary: start ------- 03:47:01 INFO - PROCESS | 1824 | Number of tests: 14 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#0] ai-astar Cycles:10 Average:121.30 Median:112.50 stddev:22.06 (19.6%) stddev-sans-first:22.27 03:47:01 INFO - PROCESS | 1824 | Values: 102.0 113.0 117.0 160.0 165.0 111.0 112.0 110.0 111.0 112.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:130.00 stddev:24.23 (18.6%) stddev-sans-first:24.22 03:47:01 INFO - PROCESS | 1824 | Values: 151.0 132.0 128.0 170.0 106.0 105.0 123.0 103.0 156.0 105.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.70 (0.9%) stddev-sans-first:1.59 03:47:01 INFO - PROCESS | 1824 | Values: 185.0 183.0 184.0 184.0 183.0 181.0 179.0 182.0 183.0 183.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#3] audio-fft Cycles:10 Average:75.30 Median:69.50 stddev:21.38 (30.8%) stddev-sans-first:22.65 03:47:01 INFO - PROCESS | 1824 | Values: 78.0 74.0 60.0 125.0 84.0 57.0 60.0 65.0 93.0 57.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:86.50 stddev:16.62 (19.2%) stddev-sans-first:17.57 03:47:01 INFO - PROCESS | 1824 | Values: 86.0 88.0 92.0 70.0 70.0 80.0 127.0 101.0 87.0 98.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:86.50 stddev:17.57 (20.3%) stddev-sans-first:18.51 03:47:01 INFO - PROCESS | 1824 | Values: 89.0 86.0 90.0 71.0 118.0 131.0 101.0 87.0 89.0 85.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 03:47:01 INFO - PROCESS | 1824 | Values: 93.0 93.0 94.0 93.0 93.0 94.0 93.0 94.0 93.0 93.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.50 stddev:1.70 (2.0%) stddev-sans-first:1.74 03:47:01 INFO - PROCESS | 1824 | Values: 83.0 83.0 83.0 85.0 84.0 85.0 88.0 83.0 83.0 86.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:1.20 (2.2%) stddev-sans-first:1.27 03:47:01 INFO - PROCESS | 1824 | Values: 54.0 54.0 52.0 54.0 56.0 55.0 53.0 53.0 55.0 55.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.11 (4.4%) stddev-sans-first:2.46 03:47:01 INFO - PROCESS | 1824 | Values: 65.0 73.0 70.0 77.0 71.0 70.0 71.0 70.0 69.0 73.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:80.50 stddev:11.11 (13.8%) stddev-sans-first:11.52 03:47:01 INFO - PROCESS | 1824 | Values: 85.0 81.0 72.0 80.0 96.0 103.0 102.0 101.0 100.0 96.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:140.50 stddev:12.33 (8.8%) stddev-sans-first:12.78 03:47:01 INFO - PROCESS | 1824 | Values: 136.0 136.0 137.0 139.0 142.0 144.0 136.0 135.0 174.0 155.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.20 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 03:47:01 INFO - PROCESS | 1824 | Values: 162.0 160.0 160.0 162.0 161.0 162.0 161.0 162.0 162.0 160.0 03:47:01 INFO - PROCESS | 1824 | 03:47:01 INFO - PROCESS | 1824 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:68.00 stddev:8.77 (12.9%) stddev-sans-first:7.98 03:47:01 INFO - PROCESS | 1824 | Values: 57.0 77.0 84.0 83.0 70.0 66.0 64.0 65.0 66.0 66.0 03:47:01 INFO - PROCESS | 1824 | -------- Summary: end -------- 03:47:01 INFO - PROCESS | 1824 | 03:47:02 INFO - TEST-INFO | 1824: exit 0 03:47:02 INFO - TEST-OK | kraken | took 59034ms 03:47:02 INFO - SUITE-END | took 1175s 03:47:02 INFO - Completed test suite (00:19:36) 03:47:02 INFO - javascript benchmark 03:47:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2789.9442011159776, 3337.282484265884, 3338.3490219407195, 3372.6290108088115, 3418.3505134024535, 5651.491365777079, 5689.061304884689, 5695.17419974104, 5749.022666146755, 5829.44620261075, 5741.425857414267, 5770.682612456313, 5774.249347584819, 5778.075507918734, 5803.854903627412, 5152.536271735553, 5178.223266510016, 5198.584113270948, 5221.530062294392, 5272.446393128724, 5236.738163091849, 5265.289185959904, 5272.103742363788, 5275.973620131894, 5282.947170528303, 5156.278121063054, 5202.557782588466, 5210.062188806013, 5224.608154388436, 5264.2893209416625, 5246.711430871301, 5250.711210883419, 5267.86830329241, 5280.551153151987, 5320.6275560710765, 5056.721880296583, 5082.567981721558, 5147.819826306099, 5192.792288308463, 5196.402413722432, 5627.493525582676, 5640.323161220639, 5679.460451257165, 5714.51426628737, 5735.770569177228, 6270.31026587075, 6277.121203031534, 6278.529110316745, 6282.78010269643, 6286.9056964145575, 6117.388261173892, 6204.441600255954, 6286.277078136027, 6341.3024567298025, 6354.364563543609, 5783.450572195635, 5804.564657650735, 5805.970970145207, 5820.35976042635, 5823.068309070529, 6256.593321434183, 6270.968645156836, 6272.8118156455375, 6273.99616061428, 6280.623162610258, 5997.160397544348, 6021.976264035125, 6052.515798736091, 6069.393060693896, 6120.975172448123, 6169.259688837368, 6199.5660303779205, 6217.937820621826, 6229.377062293825, 6304.243490781136, 6161.476274516716, 6216.968915155395, 6217.378262173746, 6225.035119556476, 6249.593776404519, 6214.40963108504, 6230.127782110508, 6238.376162383725, 6269.435750782452, 6270.278917924497, 4834.734089625034, 4905.264210368473, 4908.04293162837, 4934.432540257916, 4946.455889852114, 4436.3123715824095, 4459.197344478025, 4476.955230447654, 4478.373027776114, 4488.551144885485, 5775.422457754191, 5775.682337471395, 5840.707964601753, 5853.326867410217, 5920.881582368328, 6249.437550620466, 6300.338464461257, 6333.841653958688, 6344.302126766052, 6348.174737284124, 5827.94172058274, 5840.474357307863, 5842.182094506772, 5849.210356601804, 5889.440503152194, 5356.8660783479945, 5380.892382152335, 5396.595255355864, 5475.67145971243, 5491.615586908958, 5076.263941728503, 6272.749090036347, 6318.557700960889, 6327.90508142369, 6382.521310901701, 6271.905921411274, 6272.435480806749, 6310.337414571588, 6319.399657032574, 6327.873442531031, 6218.968905155626, 6252.312245652884, 6314.778982735582, 6342.2389313280855, 6347.904781428375, 6163.568550201443, 6200.720967556381, 6244.68776561161, 6260.874782504416, 6269.247690277196, 6304.905426418516, 6319.96840015797, 6326.367363263821, 6347.809565713036, 6351.079093531504, 5993.580449368503, 6023.819285421444, 6079.270487541523, 6185.938140618537, 6196.845078873064, 5925.022371308686, 6084.056971169476, 6113.476306051762, 6129.540284478593, 6157.0, 6004.279486461478, 6008.819735407945, 6011.308699499614, 6036.973714468463, 6044.788432404845, 5964.701764911651, 5985.610935289193, 6056.788012419544, 6158.476529495043, 6203.658798766111, 5944.346121926498, 5991.041433370639, 6001.3698561652145, 6001.819945401645, 6056.06131049688], "value": 5718.377483501931, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.0284861611555, 523.6386893043799, 528.3870709976426, 529.8528685396118, 546.1834557336782, 27419.862900685494, 30996.69003309966, 42133.15733685325, 42912.356314655306, 43199.0, 44653.0, 45005.32492012622, 45007.549924500745, 45021.0, 45048.3242751359, 8235.794105147374, 9918.00819918006, 9923.255755818342, 9929.553170107363, 9940.850887236662, 8733.912660873406, 8953.23897468716, 11891.821622675667, 12114.697132571668, 12133.272003679764, 12020.69948251292, 12097.697557561043, 12104.697382565419, 12119.151659383846, 12125.818112728271, 7425.517341372847, 9016.413933094393, 11988.340641264727, 12062.69843253917, 12089.758204835898, 8802.735917922473, 8839.558022098869, 11594.0, 11885.465154068088, 11895.464704088337, 42132.0, 42353.364699529535, 42490.78754606238, 42539.0, 42622.0, 3202.439573074703, 3351.2794749129052, 3534.540509733718, 3649.197176621139, 3965.2466031453932, 32361.352772944643, 32925.6707432925, 33934.49098263528, 33991.66008339934, 34036.48945265823, 3286.917827054319, 3347.09628400333, 3352.346292472969, 3397.2016576104597, 3409.556757621518, 366937.1653141717, 368748.0, 370466.0, 370620.9999999973, 371080.0, 63990.0, 64480.67759661265, 64553.354466455676, 64620.0, 64641.35358646447, 65590.67204664042, 66015.0, 66043.0, 66050.0, 66056.33943660598, 16513.83486165147, 16785.0, 16786.244618992176, 16800.227962386314, 16872.746908796133, 11657.59198428068, 11909.0, 12712.491500340067, 12890.80663790044, 12991.545295914597, 16864.988100713996, 17059.744103838453, 17062.061586612854, 17200.569985750455, 17248.741268880978, 6261.530385221126, 6498.642574658439, 7187.346910210576, 7424.257574242641, 7488.887666685004, 953.3279038277998, 1041.8489319048847, 1065.1798115451213, 1093.9835902461468, 1130.6608017594688, 30549.0, 31408.214794630316, 31470.89851855174, 31491.84254078761, 32251.838740806146, 871.2855458524076, 910.9569542873373, 1089.8746644136027, 1105.9447027648584, 1126.7633796902578, 16417.83582164163, 16435.01389916609, 16455.177241138135, 16503.91748041252, 16927.576810579834, 521055.0, 645966.0, 648824.0, 649308.2880441621, 650138.0, 62573.68713156405, 62638.686806566584, 62656.0, 62756.37243627505, 62797.37202628006, 1289.3810970733912, 1314.3953781260727, 1441.3081720774296, 1441.5242969819935, 1459.88320934325, 30777.615007324275, 32941.0, 32971.340573188885, 33047.834760827005, 33654.82208122704, 52896.73551632217, 53385.46614533805, 53423.0, 53520.92958140738, 53578.0, 521696.0, 541194.0, 541519.0, 541628.0, 541633.0, 36180.45729314115, 42741.78629106835, 42916.0, 43952.12095758003, 44809.55190448054, 50228.49771502238, 50443.747781260856, 50879.98240035253, 51259.74370128125, 51262.48737512577, 41626.791866040476, 41892.95267618334, 42158.578414215466, 42179.789101054295, 43212.567874320855, 41431.47832434277, 41605.79197103995, 42067.57932420637, 42391.0, 42822.35766463568, 40033.99915002148, 40373.19253614853, 40786.79606601948, 40793.796031020844, 43142.78428607837, 33930.66069339374, 36080.819595901856, 37057.25885482329, 37062.814685926394, 37068.0, 26482.337941551614, 26636.20091397261, 26655.7334426661, 26847.597286041117, 27271.72728272692], "value": 20929.199616295937, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.6576546873283, 392.34628996165264, 394.48125714685193, 395.6854300830841, 416.1780483544997, 14217.502387416442, 20570.48573785656, 21444.678329825067, 21755.021024053935, 21783.0, 15813.525594232191, 19294.614107717836, 21190.894045529752, 21323.893380533074, 21628.2430114946, 5828.358880523149, 6030.668313242792, 6079.422454866781, 6094.481969032616, 6096.116063170837, 5790.623609465392, 5981.760729570812, 6022.3074346450285, 6038.396160383971, 6080.939190608104, 5066.544011039005, 5953.077273022688, 5969.970150149265, 5979.641221526722, 5990.341062483123, 4898.53463920927, 5970.910436343458, 5982.760689572412, 5986.551008674367, 5998.220231369894, 5155.9484405155845, 5170.844874653766, 5181.689098654093, 5184.755658641937, 5188.573142385874, 25189.0, 25629.871850640626, 26114.477710445684, 26320.86839565809, 26542.867285663448, 2392.605220138675, 2600.9726158167605, 2635.6705411823523, 2771.7366850149206, 2818.85905704716, 15139.621509462351, 15505.302261398265, 15692.68614627701, 15845.92077039619, 16329.02025878451, 1823.2798044772242, 1837.6232872261287, 1878.2768634075842, 1907.4563749331512, 1908.0, 116367.41816290864, 120937.39531302375, 121188.0, 121192.78807211992, 121872.39063804713, 10261.692149235534, 10482.10902073317, 10713.982171693677, 10765.677029689123, 10898.128149748001, 13405.530806421728, 13498.257595832323, 13506.93246533761, 13512.391942362618, 13517.391717372742, 7477.962610186914, 7511.737089201852, 8327.958360208282, 8529.0, 8601.913980860236, 8698.086700896314, 8705.216530512158, 8720.651173953098, 8849.0, 8868.911310886937, 5878.5297176225795, 6402.711877965503, 6510.837229069217, 6517.51118666102, 6595.868082638321, 1609.7907272054792, 1696.041736418944, 1750.5273576134373, 1784.090114041822, 1784.090114041822, 800.4236949396425, 937.840567103594, 948.7390967483997, 1033.6227277043824, 1050.7338656918396, 21109.155633774477, 22140.889295553417, 22145.114195432, 22209.0, 22290.21984230544, 998.5356809083665, 1002.1381611813835, 1006.8137394582026, 1017.1659239423601, 1073.2540884085486, 10302.0, 11330.0, 11380.203385762918, 11842.171048026556, 11847.170698051226, 486191.0, 488332.0, 583378.0, 601903.0, 610452.0, 125828.04633559797, 143153.5684643161, 148024.0, 150630.0, 155142.22428887783, 428.72347335968124, 447.4027173723116, 450.40096671426926, 452.8369786876722, 453.091551439362, 10737.785244295226, 11087.611933582286, 11162.386068766296, 11254.887451125383, 11339.319640821577, 54880.451195487534, 55747.442525574224, 56030.719846400505, 56451.43548564626, 57297.71351143218, 77111.61444192968, 80188.0, 80691.0, 86705.0, 88546.11453885378, 6438.422586466346, 7412.629368531444, 7718.57547834852, 7871.448998570045, 8076.919230807616, 11086.0, 12812.167209131376, 12835.165714228546, 13174.011949104048, 13175.934120329337, 10955.0, 11168.944155279496, 11281.32312061279, 11391.202615816814, 11479.942600287279, 9433.056694330511, 9465.053494650756, 11044.944775276072, 11132.38771867555, 11359.318440893572, 11289.830652540382, 11354.602588909678, 11632.0, 11651.242669226473, 11662.591809286634, 7928.643211055401, 8186.713465028695, 8294.29498492635, 8403.0, 8410.453320534145, 10264.486775661038, 11289.322640641587, 11360.375179364884, 11364.261323013978, 11487.36819474937], "value": 9471.457496731937, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1518.1725959352152, 1561.7657351397293, 1756.2360373237643, 1901.087478010554, 1917.5110346861547, 8791.604377802998, 9029.051949545285, 9213.078692130792, 9222.308326875485, 9223.169914707674, 7899.447038707292, 8148.877766833488, 8724.040355560883, 8963.238124759388, 8985.685501007467, 2367.7332627044534, 2401.735809061002, 2402.8798560072014, 2407.1334319645002, 2412.2280870121567, 2374.02664907388, 2384.7376788553247, 2406.831521793475, 2412.4692567635093, 2413.7706917842866, 2100.2229175205193, 2200.3838925101, 2328.941776455585, 2391.258709799959, 2399.1243196233336, 2367.183321753992, 2373.2049763329305, 2391.8804059797026, 2402.903883844644, 2407.5666380051584, 2154.7737487563736, 2212.159379435804, 2366.538524987628, 2387.8089752819733, 2392.3899405651446, 9074.18332350085, 9131.0412406697, 9149.542522873897, 9161.908380916173, 9177.036411176798, 5010.874728131789, 5094.032133894548, 5347.224652425377, 5505.366882808488, 5526.834194974157, 3578.499010138583, 3720.832562534665, 3732.8880133596035, 3787.6780473659774, 3792.2036372361836, 932.4778124250441, 948.3645957208618, 1102.9889701102966, 1110.9944450277696, 1112.8942750438696, 11973.46119424628, 12192.146549741434, 12216.816747748791, 12224.877751222462, 12261.141720079599, 4733.479317275097, 4816.20532612122, 4833.782479788348, 4919.877003074952, 4939.777710002986, 10383.0, 10401.323913945569, 10420.947895260626, 10438.895611043945, 10443.11233545142, 5555.194496798023, 5561.304836895388, 5565.638233514808, 5572.247746554244, 5601.6358936668985, 4955.11705551888, 5072.0, 5074.619403544675, 5075.086484432837, 5083.923741143886, 3912.6087391261217, 3954.406838974119, 4010.2981978153707, 4043.1913617276664, 4050.7164498485413, 1203.0796440722854, 1241.6833707404553, 1251.8497780266243, 1277.444311724403, 1292.6122163350958, 638.9744410223632, 929.3819609959437, 954.5395544816442, 959.3572306554626, 973.3964941736169, 5010.674306170087, 5323.494268044529, 6074.696265186723, 6094.908576371358, 6174.0, 653.8394350028642, 946.2335508238349, 1019.5463018956641, 1046.2310202001522, 1086.8532748079067, 3694.2796154749567, 3983.2630963271886, 4063.918721625551, 4107.096438783463, 4108.0, 10585.311954722918, 10914.236003479838, 10923.453827308604, 10934.507947142398, 10957.780844383067, 10047.899521004843, 10654.467276636293, 10937.83593246102, 10955.014048735651, 10977.780444391068, 8318.62566184511, 8331.916680833236, 8549.914500854911, 8558.272546833465, 8571.314294856396, 4809.807607695653, 4934.481879402754, 6153.84615384619, 6207.720652570555, 6441.355864413521, 10140.898591013995, 10271.84592231132, 10514.053735163872, 10522.158227341948, 10534.42060686639, 10237.795244095225, 10542.420166890588, 10624.52189651483, 10648.574057037631, 10648.574057037942, 7717.768466946, 7764.34003109742, 7780.144184139627, 7855.293023627901, 7863.567503787347, 8357.080721120794, 8500.957495212484, 8526.531040792815, 8534.0, 8544.57277136153, 7631.618419078913, 7747.031621047369, 7752.844943100994, 7888.684452621831, 7891.092524359772, 7548.886766698394, 7560.130584982798, 7585.089789225108, 7625.1231108421025, 7655.2344765523, 6382.329855365306, 7058.223595404604, 7218.242084581043, 7306.96346518264, 7320.597367144645, 4992.900141997067, 5123.666961647481, 5239.214117882349, 5241.240020197126, 5331.840044798662, 5191.844244672666, 5202.557782588522, 5297.655652382583, 5331.973340133274, 5335.013022590833], "value": 4688.016412220348, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.17720067218175, 307.2702331961591, 313.21695760598504, 316.14955768981423, 317.5015226095032, 4182.6235638792505, 4788.401449818773, 5265.447128051557, 5331.600129990252, 5337.813176538822, 4126.422300877872, 4344.478662560489, 4844.0, 5259.289995850559, 5280.19825725478, 3286.063471910494, 3306.5536152619447, 3757.6805971492454, 3781.2815565042692, 3829.5021647185827, 3377.4596064629663, 3790.374588192945, 3970.8014599270064, 4012.9766909438054, 4017.6183262590007, 3251.9349613007726, 3975.522937247534, 3979.263836190314, 3982.522097348308, 3989.740666856645, 3220.7423406127573, 3277.36091462165, 3713.0, 3873.7532231305777, 3953.683705303569, 3014.3820516794003, 3177.3645270945904, 3209.1816586770224, 3487.860485580574, 3532.9047995121596, 4370.8907277318, 4992.076465853793, 5065.544101030925, 5085.364329458817, 5122.871928201787, 3885.9028524286837, 4028.234635419261, 4413.933790993137, 4590.977045114786, 4610.884727881797, 4817.253325734517, 5448.482394172547, 5485.286912701324, 5494.313210848644, 5514.779408823642, 3887.33915234408, 3892.708046896494, 3893.0, 3905.199434116028, 3920.157166209279, 5566.582506312043, 5686.658800471943, 5780.566457515703, 5812.360640329561, 5815.476607105338, 3887.5918028607157, 3913.92172156561, 3925.1168487090176, 3929.7052721045466, 3946.881593552198, 3980.5024371953505, 3999.4800675911947, 4007.0583412897945, 4010.6991975601945, 4020.4572382728543, 4434.55654434554, 4596.816127354935, 4598.954010459853, 4601.240795268744, 4634.467036290802, 4502.4371953505815, 5265.947340526623, 5469.343678758574, 5507.504324610724, 5513.173024046425, 1113.6659002299439, 1355.803408505781, 1364.979525307121, 1365.6244532753572, 1367.3163418290856, 629.6442509981846, 829.7925518620345, 839.2195258409738, 840.4075127035426, 852.2415050604967, 4152.754173747924, 4749.955009897817, 5003.399592048904, 5063.164577844688, 5172.456892026358, 5645.237892884408, 5668.51817595509, 5672.461116193955, 5689.544836413077, 5690.345610254791, 4352.412424322676, 4485.25993211123, 4978.352814134139, 5146.382434107931, 5208.8958220835375, 2732.5218086834725, 2992.850357482117, 2997.2656699108857, 2997.28065264335, 3006.977627606624, 5844.1818145459665, 5894.263217097863, 5900.881982360329, 5907.409259074144, 5908.615939963888, 5406.864828379322, 5562.3881373048935, 5808.564357673192, 5820.330661973927, 5874.706264686749, 5073.416557095908, 5428.0500912340185, 5856.707164641751, 5890.259685099907, 5916.6924899289315, 5055.87360315995, 5200.323957885451, 5670.943290567042, 5693.572982026448, 5704.3725190228215, 5086.135356989246, 5221.660592061504, 5405.675659460445, 5592.468715472024, 5603.607747457639, 4964.553190212754, 5121.487851214849, 5438.972805135949, 5696.20253164549, 5851.970740146442, 2769.6814866290633, 3319.7344212463427, 3326.817025063645, 3335.483000134983, 3368.393689135979, 4745.762711864462, 4809.302651115639, 5003.44962054181, 5215.81744638951, 5717.371089180104, 5304.310439642822, 5548.528375088002, 5564.415736347625, 5573.470520300565, 5619.578531610227, 5234.24103504982, 5561.582881283839, 5573.080441727069, 5590.86022849432, 5593.161025846156, 4928.654994150375, 5289.80979279659, 5383.973080134574, 5429.724014856584, 5459.935312614002, 3954.9406758899213, 5261.605379596622, 5273.367195936512, 5513.5037846594, 5560.499555040065, 4872.610191184767, 4992.6020714200085, 5477.370102438271, 5495.862603434946, 5647.858803529944], "value": 3979.710301224648, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.5012116457898, 735.5843948169285, 775.5997312571633, 839.2907992746127, 860.681547827304, 15181.0, 16247.0, 16265.0, 16298.266578003988, 16306.429274975379, 12054.638360849167, 14899.478518251863, 14931.328090235938, 14931.626709332271, 14933.328000239988, 709.7267551992481, 745.0872680965828, 899.6851102114273, 924.7780532672177, 934.0426063285153, 873.8863947686792, 881.6561540999015, 889.5374405309235, 891.0332289465937, 893.3344658229596, 969.6751588217955, 972.1687956796943, 972.3048020665223, 973.6543527047902, 973.9172170365492, 756.3608750605729, 768.8539177556246, 783.9886546355195, 786.3316181245953, 933.6125507914276, 722.3390597603187, 722.8445884134936, 724.091265461842, 724.3951300663952, 724.873147199238, 15639.843601563953, 15654.452094176762, 15656.21718914061, 15670.843291567053, 15750.2124893756, 14619.0, 14741.336639851232, 14752.188629625367, 14830.258487075604, 14863.256837158098, 13548.525801596898, 13611.387487563083, 13645.249511276876, 13774.242416667079, 14167.645808854759, 4008.599140085997, 4737.26572381278, 4847.297141914438, 4904.754762261872, 4910.337104490919, 86100.0, 86214.0, 86688.5665571668, 90821.0, 119442.0, 11464.426778661034, 11649.47577359021, 11661.591844285409, 11667.474963626657, 12055.57805476804, 23408.531829363317, 23415.0, 23448.944797484153, 23460.413489662897, 23892.7610723894, 7887.250711182429, 8076.4750291230885, 8097.392695547857, 8114.698040459478, 8221.753347399588, 4468.709533880287, 4529.207388706963, 4533.52397998212, 4536.455625324982, 4697.248440249544, 3553.3959226931483, 3669.614690457517, 3676.6874815640435, 3677.0439685681918, 3677.1358730698266, 568.943105689436, 656.2158220925947, 665.2748504130424, 688.686647575362, 743.7880204141794, 597.5458651424862, 648.7869964832641, 662.3244290823333, 702.304718328861, 705.4004096518021, 9505.809883802285, 9782.168515676247, 9798.755031124136, 9837.311388202757, 9933.65232216869, 672.6065251827736, 677.8949262864264, 690.3062422265591, 699.1714817940655, 699.9335063168991, 4594.690513203721, 4666.346711460398, 4723.197056500403, 4744.525547445228, 4810.39870016248, 103654.0, 104160.47919760353, 129029.0, 167790.0, 168172.0, 138968.30515847355, 143150.0, 145039.2748036253, 146853.26573367065, 147671.0, 94486.0, 95984.52007739917, 107177.46411267894, 107262.0, 111330.88669113205, 10026.498675066363, 10032.749181270527, 10044.296899216984, 10132.240081993732, 10168.796624067625, 62210.37789622227, 62428.0, 63670.68164659147, 65292.67353663391, 65949.0107648376, 97596.53605196069, 99849.50225746474, 100468.99531004597, 101398.0, 102105.0, 11517.712057198638, 11893.881061189277, 11921.34432606215, 12227.266364018187, 13876.236806975714, 13231.669208269484, 13639.318034098455, 14579.92710036443, 15880.29805544287, 16270.918645406697, 14166.0, 14387.799433716147, 14435.35040923182, 14754.409823607364, 14891.851081489047, 14421.423143074579, 14429.062110962754, 14841.554753356984, 14875.925620372262, 15104.093754374773, 14275.714485710436, 14308.284585770461, 14328.570142895729, 14388.0, 14684.97205195626, 11497.0, 11508.827367589325, 11560.30638161713, 11645.216003192792, 11741.530338786353, 8922.776930576787, 11639.867846914223, 11676.766464670489, 11820.23168494045, 12025.75948481008], "value": 8106.506578790582, "unit": "score"}], "name": "dromaeo_css", "value": 7453.509490997054, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 117.0, 160.0, 165.0, 111.0, 112.0, 110.0, 111.0, 112.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 132.0, 128.0, 170.0, 106.0, 105.0, 123.0, 103.0, 156.0, 105.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 184.0, 183.0, 181.0, 179.0, 182.0, 183.0, 183.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 60.0, 125.0, 84.0, 57.0, 60.0, 65.0, 93.0, 57.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 92.0, 70.0, 70.0, 80.0, 127.0, 101.0, 87.0, 98.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 71.0, 118.0, 131.0, 101.0, 87.0, 89.0, 85.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 85.0, 84.0, 85.0, 88.0, 83.0, 83.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 52.0, 54.0, 56.0, 55.0, 53.0, 53.0, 55.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 70.0, 77.0, 71.0, 70.0, 71.0, 70.0, 69.0, 73.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 72.0, 80.0, 96.0, 103.0, 102.0, 101.0, 100.0, 96.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 137.0, 139.0, 142.0, 144.0, 136.0, 135.0, 174.0, 155.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 162.0, 161.0, 162.0, 161.0, 162.0, 162.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 77.0, 84.0, 83.0, 70.0, 66.0, 64.0, 65.0, 66.0, 66.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1460.4, "alertThreshold": 2.0}]} 03:47:02 INFO - Return code: 0 03:47:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:47:02 INFO - # TBPL SUCCESS # 03:47:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:47:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:47:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:47:02 INFO - 2016-07-25 03:47:02.931 screenresolution[1834:10183] starting screenresolution argv=screenresolution get 03:47:02 INFO - 2016-07-25 03:47:02.933 screenresolution[1834:10183] Display 0: 1600x1200x32@60 03:47:02 INFO - 2016-07-25 03:47:02.939 screenresolution[1835:10186] starting screenresolution argv=screenresolution list 03:47:02 INFO - Available Modes on Display 0 03:47:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:47:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:47:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:47:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:47:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:47:03 INFO - Intel Iris: 03:47:03 INFO - Chipset Model: Intel Iris 03:47:03 INFO - Type: GPU 03:47:03 INFO - Bus: Built-In 03:47:03 INFO - VRAM (Dynamic, Max): 1536 MB 03:47:03 INFO - Vendor: Intel (0x8086) 03:47:03 INFO - Device ID: 0x0a2e 03:47:03 INFO - Revision ID: 0x0009 03:47:03 INFO - Displays: 03:47:03 INFO - Display: 03:47:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:47:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:47:03 INFO - Display Serial Number: 1600x1200 60 03:47:03 INFO - Main Display: Yes 03:47:03 INFO - Mirror: Off 03:47:03 INFO - Online: Yes 03:47:03 INFO - Rotation: Supported 03:47:03 INFO - Return code: 0 03:47:03 INFO - Running post-action listener: _resource_record_post_action 03:47:03 INFO - ##### 03:47:03 INFO - ##### Finished run-tests step (success) 03:47:03 INFO - ##### 03:47:03 INFO - Running post-run listener: _resource_record_post_run 03:47:03 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38258176; Write bytes: 708232704; Read time: 383; Write time: 1888 03:47:03 INFO - TinderboxPrint: CPU usage
53.6% 03:47:03 INFO - TinderboxPrint: I/O read bytes / time
38,258,176 / 383 03:47:03 INFO - TinderboxPrint: I/O write bytes / time
708,232,704 / 1,888 03:47:03 INFO - TinderboxPrint: CPU idle
2,248.9 (46.4%) 03:47:03 INFO - TinderboxPrint: CPU system
968.6 (20.0%) 03:47:03 INFO - TinderboxPrint: CPU user
1,628.1 (33.6%) 03:47:03 INFO - TinderboxPrint: Swap in / out
444,694,528 / 0 03:47:03 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 399612416; Write bytes: 389359616; Read time: 29201; Write time: 743 03:47:03 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31227392; Write bytes: 296922624; Read time: 296; Write time: 1103 03:47:03 INFO - Running post-run listener: _upload_blobber_files 03:47:03 INFO - Blob upload gear active. 03:47:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:47:03 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 03:47:03 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'] 03:47:03 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 03:47:04 INFO - (blobuploader) - INFO - Open directory for files ... 03:47:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:47:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:47:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:04 INFO - (blobuploader) - INFO - Done attempting. 03:47:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:47:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:47:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:05 INFO - (blobuploader) - INFO - Done attempting. 03:47:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:47:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:47:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:08 INFO - (blobuploader) - INFO - Done attempting. 03:47:08 INFO - (blobuploader) - INFO - Iteration through files over. 03:47:08 INFO - Return code: 0 03:47:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:47:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:47:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91edbeecaa49a140d07178afed538493181157210f1241212b906cc133179f8fbc6b9f758b652da0f1fb12c272815a0953c59097996c189441ceb7fd25332378", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4b67cc4f5e320c75ef538e7b307cade4ad1fd0fed9f4147fc7ff1ddb546955de1ff9f11bc4cee8d16408625028778c1fbb27404af7eab335da866f7fcb6a1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dfa1eba67309b13d2b7f8a6ae8e91054b6bc3b4a458e2aab50465c659b4032f842f1e2f3f653a9c3e0fe8a8dc685fd4cc326d41602363fbbd41e86a40d36c52"} 03:47:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:47:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:47:08 INFO - Contents: 03:47:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91edbeecaa49a140d07178afed538493181157210f1241212b906cc133179f8fbc6b9f758b652da0f1fb12c272815a0953c59097996c189441ceb7fd25332378", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4b67cc4f5e320c75ef538e7b307cade4ad1fd0fed9f4147fc7ff1ddb546955de1ff9f11bc4cee8d16408625028778c1fbb27404af7eab335da866f7fcb6a1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dfa1eba67309b13d2b7f8a6ae8e91054b6bc3b4a458e2aab50465c659b4032f842f1e2f3f653a9c3e0fe8a8dc685fd4cc326d41602363fbbd41e86a40d36c52"} 03:47:08 INFO - Running post-run listener: copy_logs_to_upload_dir 03:47:08 INFO - Copying logs to upload dir... 03:47:08 INFO - mkdir: /builds/slave/test/build/upload/logs 03:47:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.887769 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-25 03:47:08.811021) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 03:47:08.814761) ========= 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.YgJOm4hMob/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.eg1475aEOT/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91edbeecaa49a140d07178afed538493181157210f1241212b906cc133179f8fbc6b9f758b652da0f1fb12c272815a0953c59097996c189441ceb7fd25332378", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4b67cc4f5e320c75ef538e7b307cade4ad1fd0fed9f4147fc7ff1ddb546955de1ff9f11bc4cee8d16408625028778c1fbb27404af7eab335da866f7fcb6a1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dfa1eba67309b13d2b7f8a6ae8e91054b6bc3b4a458e2aab50465c659b4032f842f1e2f3f653a9c3e0fe8a8dc685fd4cc326d41602363fbbd41e86a40d36c52"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011070 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91edbeecaa49a140d07178afed538493181157210f1241212b906cc133179f8fbc6b9f758b652da0f1fb12c272815a0953c59097996c189441ceb7fd25332378", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4b67cc4f5e320c75ef538e7b307cade4ad1fd0fed9f4147fc7ff1ddb546955de1ff9f11bc4cee8d16408625028778c1fbb27404af7eab335da866f7fcb6a1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dfa1eba67309b13d2b7f8a6ae8e91054b6bc3b4a458e2aab50465c659b4032f842f1e2f3f653a9c3e0fe8a8dc685fd4cc326d41602363fbbd41e86a40d36c52"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 03:47:08.846835) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:47:08.847158) ========= 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.YgJOm4hMob/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.eg1475aEOT/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.004913 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:47:08.896403) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 03:47:08.896819) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 03:47:08.897162) ========= ========= Total master_lag: 0.17 =========