builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0387 starttime: 1469255596.65 results: success (0) buildid: 20160702021526 builduid: af6c38806ca24e5894206bfe1be34d07 revision: 49a1d28776b4a2867bb8818dfab61c352cc02f49 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:16.646062) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:16.646487) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:16.646775) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:16.675243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:16.675516) ========= 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.2VArespZk0/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.OfSHMmu3fX/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-22 23:33:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 25.2M=0s 2016-07-22 23:33:17 (25.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.494555 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:17.193434) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:17.193759) ========= 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.2VArespZk0/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.OfSHMmu3fX/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.025415 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:17.242943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 23:33:17.243282) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 49a1d28776b4a2867bb8818dfab61c352cc02f49 --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 49a1d28776b4a2867bb8818dfab61c352cc02f49 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2VArespZk0/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.OfSHMmu3fX/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-22 23:33:17,324 truncating revision to first 12 chars 2016-07-22 23:33:17,325 Setting DEBUG logging. 2016-07-22 23:33:17,325 attempt 1/10 2016-07-22 23:33:17,325 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/49a1d28776b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 23:33:18,350 unpacking tar archive at: mozilla-central-49a1d28776b4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.296971 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 23:33:18.566131) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:18.566734) ========= script_repo_revision: 49a1d28776b4a2867bb8818dfab61c352cc02f49 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:18.567574) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:18.568169) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 23:33:18.591795) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-22 23:33:18.592517) ========= /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.2VArespZk0/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.OfSHMmu3fX/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:18 INFO - MultiFileLogger online at 20160722 23:33:18 in /builds/slave/test 23:33:18 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 23:33:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:33:18 INFO - {'append_to_log': False, 23:33:18 INFO - 'base_work_dir': '/builds/slave/test', 23:33:18 INFO - 'blob_upload_branch': 'Firefox', 23:33:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:33:18 INFO - 'branch': 'Firefox', 23:33:18 INFO - 'buildbot_json_path': 'buildprops.json', 23:33:18 INFO - 'config_files': ('talos/mac_config.py',), 23:33:18 INFO - 'default_actions': ('clobber', 23:33:18 INFO - 'read-buildbot-config', 23:33:18 INFO - 'download-and-extract', 23:33:18 INFO - 'populate-webroot', 23:33:18 INFO - 'create-virtualenv', 23:33:18 INFO - 'install', 23:33:18 INFO - 'run-tests'), 23:33:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:33:18 INFO - 'download_minidump_stackwalk': True, 23:33:18 INFO - 'download_symbols': 'ondemand', 23:33:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:33:18 INFO - 'tooltool.py': '/tools/tooltool.py', 23:33:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:33:18 INFO - '/tools/misc-python/virtualenv.py')}, 23:33:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:33:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:33:18 INFO - 'installer_path': 'installer.exe', 23:33:18 INFO - 'log_level': 'info', 23:33:18 INFO - 'log_name': 'talos', 23:33:18 INFO - 'log_to_console': True, 23:33:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:33:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:33:18 INFO - 'opt_config_files': (), 23:33:18 INFO - 'pip_index': False, 23:33:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:33:18 INFO - 'cmd': ('bash', 23:33:18 INFO - '-c', 23:33:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:33:18 INFO - 'enabled': True, 23:33:18 INFO - 'halt_on_failure': False, 23:33:18 INFO - 'name': 'check_screen_resolution'},), 23:33:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:33:18 INFO - 'cmd': ('bash', 23:33:18 INFO - '-c', 23:33:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:33:18 INFO - 'enabled': True, 23:33:18 INFO - 'halt_on_failure': False, 23:33:18 INFO - 'name': 'check_screen_resolution'},), 23:33:18 INFO - 'run_cmd_checks_enabled': True, 23:33:18 INFO - 'sps_profile': False, 23:33:18 INFO - 'sps_profile_interval': 0, 23:33:18 INFO - 'suite': 'dromaeojs', 23:33:18 INFO - 'system_bits': '32', 23:33:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:33:18 INFO - 'title': 't-yosemite-r7-0387', 23:33:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:33:18 INFO - 'use_talos_json': True, 23:33:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:33:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:33:18 INFO - 'work_dir': 'build'} 23:33:18 INFO - ##### 23:33:18 INFO - ##### Running clobber step. 23:33:18 INFO - ##### 23:33:18 INFO - Running pre-action listener: _resource_record_pre_action 23:33:18 INFO - Running main action method: clobber 23:33:18 INFO - rmtree: /builds/slave/test/build 23:33:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:33:20 INFO - Running post-action listener: _resource_record_post_action 23:33:20 INFO - ##### 23:33:20 INFO - ##### Finished clobber step (success) 23:33:20 INFO - ##### 23:33:20 INFO - ##### 23:33:20 INFO - ##### Running read-buildbot-config step. 23:33:20 INFO - ##### 23:33:20 INFO - Running pre-action listener: _resource_record_pre_action 23:33:20 INFO - Running main action method: read_buildbot_config 23:33:20 INFO - Using buildbot properties: 23:33:20 INFO - { 23:33:20 INFO - "project": "", 23:33:20 INFO - "product": "firefox", 23:33:20 INFO - "script_repo_revision": "production", 23:33:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 23:33:20 INFO - "repository": "", 23:33:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 23:33:20 INFO - "buildid": "20160702021526", 23:33:20 INFO - "pgo_build": "False", 23:33:20 INFO - "basedir": "/builds/slave/test", 23:33:20 INFO - "buildnumber": 41, 23:33:20 INFO - "slavename": "t-yosemite-r7-0387", 23:33:20 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 23:33:20 INFO - "platform": "yosemite_r7", 23:33:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:33:20 INFO - "branch": "mozilla-central", 23:33:20 INFO - "repo_path": "mozilla-central", 23:33:20 INFO - "revision": "49a1d28776b4a2867bb8818dfab61c352cc02f49", 23:33:20 INFO - "stage_platform": "macosx64", 23:33:20 INFO - "builduid": "af6c38806ca24e5894206bfe1be34d07", 23:33:20 INFO - "slavebuilddir": "test" 23:33:20 INFO - } 23:33:20 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg. 23:33:20 INFO - Running post-action listener: _resource_record_post_action 23:33:20 INFO - ##### 23:33:20 INFO - ##### Finished read-buildbot-config step (success) 23:33:20 INFO - ##### 23:33:20 INFO - ##### 23:33:20 INFO - ##### Running download-and-extract step. 23:33:20 INFO - ##### 23:33:20 INFO - Running pre-action listener: _resource_record_pre_action 23:33:20 INFO - Running main action method: download_and_extract 23:33:20 INFO - mkdir: /builds/slave/test/build/tests 23:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:20 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.test_packages.json 23:33:20 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:33:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/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 23:33:21 INFO - Downloaded 1199 bytes. 23:33:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:33:21 INFO - Using the following test package requirements: 23:33:21 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:33:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 23:33:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 23:33:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'jsshell-mac.zip'], 23:33:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 23:33:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:33:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 23:33:21 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 23:33:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 23:33:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:33:21 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 23:33:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.common.tests.zip 23:33:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:33:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/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 23:33:21 INFO - Downloaded 18789641 bytes. 23:33:21 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'] 23:33:21 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 23:33:24 INFO - Return code: 0 23:33:24 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 23:33:24 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')]} 23:33:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.common.tests.zip 23:33:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/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 23:33:25 INFO - Downloaded 18789641 bytes. 23:33:25 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'] 23:33:25 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 23:33:29 INFO - Return code: 0 23:33:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.talos.tests.zip 23:33:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:33:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/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 23:33:29 INFO - Downloaded 14405242 bytes. 23:33:29 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'] 23:33:29 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 23:33:29 INFO - Return code: 0 23:33:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg 23:33:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 23:33:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 23:33:33 INFO - Downloaded 112437120 bytes. 23:33:33 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg 23:33:33 INFO - mkdir: /builds/slave/test/properties 23:33:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:33:33 INFO - Writing to file /builds/slave/test/properties/build_url 23:33:33 INFO - Contents: 23:33:33 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg 23:33:33 INFO - Running post-action listener: _resource_record_post_action 23:33:33 INFO - Running post-action listener: set_extra_try_arguments 23:33:33 INFO - ##### 23:33:33 INFO - ##### Finished download-and-extract step (success) 23:33:33 INFO - ##### 23:33:33 INFO - ##### 23:33:33 INFO - ##### Running populate-webroot step. 23:33:33 INFO - ##### 23:33:33 INFO - Running pre-action listener: _resource_record_pre_action 23:33:33 INFO - Running main action method: populate_webroot 23:33:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:33:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:33:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:33:33 INFO - '--tppagecycles', 23:33:33 INFO - '7'], 23:33:33 INFO - 'tests': ['tsvgm']}}, 23:33:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:33:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:33:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:33:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:33:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:33:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:33:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['damp', 'tps']}, 23:33:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'tests': ['damp', 'tps']}, 23:33:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['dromaeo_dom']}, 23:33:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:33:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['basic_compositor_video']}, 23:33:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:33:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['a11yr', 23:33:33 INFO - 'ts_paint', 23:33:33 INFO - 'tpaint', 23:33:33 INFO - 'sessionrestore', 23:33:33 INFO - 'sessionrestore_no_auto_restore', 23:33:33 INFO - 'tabpaint']}, 23:33:33 INFO - 'other-e10s': {'tests': ['a11yr', 23:33:33 INFO - 'ts_paint', 23:33:33 INFO - 'tpaint', 23:33:33 INFO - 'sessionrestore', 23:33:33 INFO - 'sessionrestore_no_auto_restore', 23:33:33 INFO - 'tabpaint']}, 23:33:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:33:33 INFO - 'ts_paint', 23:33:33 INFO - 'tpaint', 23:33:33 INFO - 'sessionrestore', 23:33:33 INFO - 'sessionrestore_no_auto_restore', 23:33:33 INFO - 'tabpaint']}, 23:33:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:33:33 INFO - 'ts_paint', 23:33:33 INFO - 'tpaint', 23:33:33 INFO - 'sessionrestore', 23:33:33 INFO - 'sessionrestore_no_auto_restore', 23:33:33 INFO - 'tabpaint']}, 23:33:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['a11yr', 23:33:33 INFO - 'ts_paint', 23:33:33 INFO - 'tpaint', 23:33:33 INFO - 'sessionrestore', 23:33:33 INFO - 'sessionrestore_no_auto_restore', 23:33:33 INFO - 'tabpaint']}, 23:33:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['a11yr', 23:33:33 INFO - 'ts_paint', 23:33:33 INFO - 'tpaint', 23:33:33 INFO - 'sessionrestore', 23:33:33 INFO - 'sessionrestore_no_auto_restore', 23:33:33 INFO - 'tabpaint']}, 23:33:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['tsvgx', 23:33:33 INFO - 'tsvgr_opacity', 23:33:33 INFO - 'tart', 23:33:33 INFO - 'tscrollx', 23:33:33 INFO - 'cart']}, 23:33:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:33:33 INFO - 'tsvgr_opacity', 23:33:33 INFO - 'tart', 23:33:33 INFO - 'tscrollx', 23:33:33 INFO - 'cart']}, 23:33:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'talos_options': ['--disable-e10s'], 23:33:33 INFO - 'tests': ['tp5o']}, 23:33:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'tests': ['tp5o']}, 23:33:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'talos_options': ['--disable-e10s', 23:33:33 INFO - '--xperf_path', 23:33:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:33:33 INFO - 'tests': ['tp5n']}, 23:33:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:33 INFO - 'talos_options': ['--xperf_path', 23:33:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:33:33 INFO - 'tests': ['tp5n']}}, 23:33:33 INFO - 'talos.zip': {'path': '', 23:33:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:33:33 INFO - Running post-action listener: _resource_record_post_action 23:33:33 INFO - ##### 23:33:33 INFO - ##### Finished populate-webroot step (success) 23:33:33 INFO - ##### 23:33:33 INFO - ##### 23:33:33 INFO - ##### Running create-virtualenv step. 23:33:33 INFO - ##### 23:33:33 INFO - Running pre-action listener: _resource_record_pre_action 23:33:33 INFO - Running main action method: create_virtualenv 23:33:33 INFO - Creating virtualenv /builds/slave/test/build/venv 23:33:33 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 23:33:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:33:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:33:33 INFO - Using real prefix '/tools/python27' 23:33:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:33:34 INFO - Installing distribute.............................................................................................................................................................................................done. 23:33:37 INFO - Installing pip.................done. 23:33:37 INFO - Return code: 0 23:33:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:33:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:33:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:33:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:37 INFO - 'HOME': '/Users/cltbld', 23:33:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:37 INFO - 'LOGNAME': 'cltbld', 23:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:37 INFO - 'MOZ_NO_REMOTE': '1', 23:33:37 INFO - 'NO_EM_RESTART': '1', 23:33:37 INFO - 'PAGER': '/bin/cat', 23:33:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:37 INFO - 'PWD': '/builds/slave/test', 23:33:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:37 INFO - 'SHELL': '/bin/bash', 23:33:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:33:37 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:33:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:37 INFO - 'USER': 'cltbld', 23:33:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:37 INFO - 'XPC_FLAGS': '0x0', 23:33:37 INFO - 'XPC_SERVICE_NAME': '0', 23:33:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:37 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 23:33:37 INFO - Cleaning up... 23:33:37 INFO - Return code: 0 23:33:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:33:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:33:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:33:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:37 INFO - 'HOME': '/Users/cltbld', 23:33:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:37 INFO - 'LOGNAME': 'cltbld', 23:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:37 INFO - 'MOZ_NO_REMOTE': '1', 23:33:37 INFO - 'NO_EM_RESTART': '1', 23:33:37 INFO - 'PAGER': '/bin/cat', 23:33:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:37 INFO - 'PWD': '/builds/slave/test', 23:33:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:37 INFO - 'SHELL': '/bin/bash', 23:33:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:33:37 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:33:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:37 INFO - 'USER': 'cltbld', 23:33:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:37 INFO - 'XPC_FLAGS': '0x0', 23:33:37 INFO - 'XPC_SERVICE_NAME': '0', 23:33:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:37 INFO - Downloading/unpacking psutil>=3.1.1 23:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:33:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:33:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:33:41 INFO - Installing collected packages: psutil 23:33:41 INFO - Running setup.py install for psutil 23:33:41 INFO - building 'psutil._psutil_osx' extension 23:33:41 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 23:33:41 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 23:33:41 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 23:33:42 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 23:33:42 INFO - building 'psutil._psutil_posix' extension 23:33:42 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 23:33:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:33:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:33:42 INFO - ^ 23:33:42 INFO - 1 warning generated. 23:33:42 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 23:33:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:33:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:33:42 INFO - Successfully installed psutil 23:33:42 INFO - Cleaning up... 23:33:42 INFO - Return code: 0 23:33:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:33:42 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')]} 23:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:42 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')]} 23:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:42 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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:42 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 23:33:42 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 23:33:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:33:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:42 INFO - 'HOME': '/Users/cltbld', 23:33:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:42 INFO - 'LOGNAME': 'cltbld', 23:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:42 INFO - 'MOZ_NO_REMOTE': '1', 23:33:42 INFO - 'NO_EM_RESTART': '1', 23:33:42 INFO - 'PAGER': '/bin/cat', 23:33:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:42 INFO - 'PWD': '/builds/slave/test', 23:33:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:42 INFO - 'SHELL': '/bin/bash', 23:33:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:33:42 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:33:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:42 INFO - 'USER': 'cltbld', 23:33:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:42 INFO - 'XPC_FLAGS': '0x0', 23:33:42 INFO - 'XPC_SERVICE_NAME': '0', 23:33:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:33:42 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 23:33:42 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 23:33:42 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 23:33:42 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 23:33:42 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 23:33:42 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 23:33:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:33:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:33:45 INFO - Installing collected packages: mozsystemmonitor 23:33:45 INFO - Running setup.py install for mozsystemmonitor 23:33:45 INFO - Successfully installed mozsystemmonitor 23:33:45 INFO - Cleaning up... 23:33:45 INFO - Return code: 0 23:33:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:33: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')]} 23:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33: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')]} 23:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:33:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:33:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:45 INFO - 'HOME': '/Users/cltbld', 23:33:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:45 INFO - 'LOGNAME': 'cltbld', 23:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:45 INFO - 'MOZ_NO_REMOTE': '1', 23:33:45 INFO - 'NO_EM_RESTART': '1', 23:33:45 INFO - 'PAGER': '/bin/cat', 23:33:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:45 INFO - 'PWD': '/builds/slave/test', 23:33:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:45 INFO - 'SHELL': '/bin/bash', 23:33:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:33:45 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:33:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:45 INFO - 'USER': 'cltbld', 23:33:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:45 INFO - 'XPC_FLAGS': '0x0', 23:33:45 INFO - 'XPC_SERVICE_NAME': '0', 23:33:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:45 INFO - Downloading/unpacking jsonschema==2.5.1 23:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:33:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:33:48 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 23:33:48 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 23:33:48 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 23:33:48 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 23:33:48 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 23:33:48 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 23:33:48 INFO - Downloading functools32-3.2.3-2.tar.gz 23:33:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:33:48 INFO - warning: no files found matching '*.txt' 23:33:48 INFO - no previously-included directories found matching 'build' 23:33:48 INFO - no previously-included directories found matching 'dist' 23:33:48 INFO - no previously-included directories found matching '.git*' 23:33:48 INFO - Installing collected packages: jsonschema, functools32 23:33:48 INFO - Running setup.py install for functools32 23:33:48 INFO - warning: no files found matching '*.txt' 23:33:48 INFO - no previously-included directories found matching 'build' 23:33:48 INFO - no previously-included directories found matching 'dist' 23:33:48 INFO - no previously-included directories found matching '.git*' 23:33:48 INFO - Successfully installed jsonschema functools32 23:33:48 INFO - Cleaning up... 23:33:48 INFO - Return code: 0 23:33:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:33:48 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')]} 23:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:48 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')]} 23:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:48 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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:48 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 23:33:48 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 23:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:48 INFO - 'HOME': '/Users/cltbld', 23:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:48 INFO - 'LOGNAME': 'cltbld', 23:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:48 INFO - 'MOZ_NO_REMOTE': '1', 23:33:48 INFO - 'NO_EM_RESTART': '1', 23:33:48 INFO - 'PAGER': '/bin/cat', 23:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:48 INFO - 'PWD': '/builds/slave/test', 23:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:48 INFO - 'SHELL': '/bin/bash', 23:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:33:48 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:48 INFO - 'USER': 'cltbld', 23:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:48 INFO - 'XPC_FLAGS': '0x0', 23:33:48 INFO - 'XPC_SERVICE_NAME': '0', 23:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:49 INFO - Downloading/unpacking blobuploader==1.2.4 23:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:51 INFO - Downloading blobuploader-1.2.4.tar.gz 23:33:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:33:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:33:51 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 23:33:51 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 23:33:51 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 23:33:51 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 23:33:51 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 23:33:51 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 23:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:33:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:33:52 INFO - Downloading docopt-0.6.1.tar.gz 23:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:33:52 INFO - Installing collected packages: blobuploader, requests, docopt 23:33:52 INFO - Running setup.py install for blobuploader 23:33:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:33:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:33:53 INFO - Running setup.py install for requests 23:33:53 INFO - Running setup.py install for docopt 23:33:53 INFO - Successfully installed blobuploader requests docopt 23:33:53 INFO - Cleaning up... 23:33:53 INFO - Return code: 0 23:33:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:33:53 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')]} 23:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:53 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')]} 23:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:53 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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:53 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 23:33:53 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 23:33:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:33:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:53 INFO - 'HOME': '/Users/cltbld', 23:33:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:53 INFO - 'LOGNAME': 'cltbld', 23:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:53 INFO - 'MOZ_NO_REMOTE': '1', 23:33:53 INFO - 'NO_EM_RESTART': '1', 23:33:53 INFO - 'PAGER': '/bin/cat', 23:33:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:53 INFO - 'PWD': '/builds/slave/test', 23:33:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:53 INFO - 'SHELL': '/bin/bash', 23:33:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:33:53 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:33:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:53 INFO - 'USER': 'cltbld', 23:33:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:53 INFO - 'XPC_FLAGS': '0x0', 23:33:53 INFO - 'XPC_SERVICE_NAME': '0', 23:33:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-XO13bA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-52h4bI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-2GZqlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Xf8EZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-nM3GJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-AVvOwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-GdBcY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-oSA9ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:33:54 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-nTMgeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-VNNF3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-tPSA49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-GvTvDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-HG273J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-VckCeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-t5oRQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-SjuJJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:33:55 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-nekvxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:33:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:33:55 INFO - Running setup.py install for manifestparser 23:33:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:33:56 INFO - Running setup.py install for mozcrash 23:33:56 INFO - Running setup.py install for mozdebug 23:33:56 INFO - Running setup.py install for mozdevice 23:33:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:33:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:33:56 INFO - Running setup.py install for mozfile 23:33:56 INFO - Running setup.py install for mozhttpd 23:33:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:33:56 INFO - Running setup.py install for mozinfo 23:33:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:33:56 INFO - Running setup.py install for mozInstall 23:33:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Running setup.py install for mozleak 23:33:57 INFO - Running setup.py install for mozlog 23:33:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Running setup.py install for moznetwork 23:33:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Running setup.py install for mozprocess 23:33:57 INFO - Running setup.py install for mozprofile 23:33:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Running setup.py install for mozrunner 23:33:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:33:57 INFO - Running setup.py install for mozscreenshot 23:33:58 INFO - Running setup.py install for moztest 23:33:58 INFO - Running setup.py install for mozversion 23:33:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:33:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:33:58 INFO - Cleaning up... 23:33:58 INFO - Return code: 0 23:33:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:33:58 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')]} 23:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:58 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')]} 23:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:58 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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:33:58 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 23:33:58 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 23:33:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:33:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:58 INFO - 'HOME': '/Users/cltbld', 23:33:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:58 INFO - 'LOGNAME': 'cltbld', 23:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:58 INFO - 'MOZ_NO_REMOTE': '1', 23:33:58 INFO - 'NO_EM_RESTART': '1', 23:33:58 INFO - 'PAGER': '/bin/cat', 23:33:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:58 INFO - 'PWD': '/builds/slave/test', 23:33:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:58 INFO - 'SHELL': '/bin/bash', 23:33:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:33:58 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:33:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:58 INFO - 'USER': 'cltbld', 23:33:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:58 INFO - 'XPC_FLAGS': '0x0', 23:33:58 INFO - 'XPC_SERVICE_NAME': '0', 23:33:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:33:58 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-sIk5Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:33:58 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)) 23:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:33:58 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ADKAPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:33:58 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)) 23:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:33:58 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-bWZC8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ivzsgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-9ca3xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-vupCFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-IODAli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-k6qSEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-haFRCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-b16oN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-W49bba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-WzWN5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:33:59 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)) 23:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:33:59 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-1mF14t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:34:00 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)) 23:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:34:00 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-xX7Gzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:34:00 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)) 23:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:34:00 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-UnLYUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:34:00 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)) 23:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:34:00 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-S0EMud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:34:00 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)) 23:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:34:00 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-UL5D4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:34:00 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)) 23:34:00 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)) 23:34:00 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)) 23:34:00 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)) 23:34:00 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)) 23:34:00 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)) 23:34:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:34:00 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 23:34:00 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 23:34:00 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 23:34:00 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 23:34:00 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 23:34:00 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 23:34:02 INFO - Downloading blessings-1.6.tar.gz 23:34:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:34:03 INFO - Installing collected packages: blessings 23:34:03 INFO - Running setup.py install for blessings 23:34:03 INFO - Successfully installed blessings 23:34:03 INFO - Cleaning up... 23:34:03 INFO - Return code: 0 23:34:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:34:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:34:03 INFO - Reading from file tmpfile_stdout 23:34:03 INFO - Current package versions: 23:34:03 INFO - blessings == 1.6 23:34:03 INFO - blobuploader == 1.2.4 23:34:03 INFO - docopt == 0.6.1 23:34:03 INFO - functools32 == 3.2.3-2 23:34:03 INFO - jsonschema == 2.5.1 23:34:03 INFO - manifestparser == 1.1 23:34:03 INFO - mozInstall == 1.12 23:34:03 INFO - mozcrash == 0.17 23:34:03 INFO - mozdebug == 0.1 23:34:03 INFO - mozdevice == 0.48 23:34:03 INFO - mozfile == 1.2 23:34:03 INFO - mozhttpd == 0.7 23:34:03 INFO - mozinfo == 0.9 23:34:03 INFO - mozleak == 0.1 23:34:03 INFO - mozlog == 3.2 23:34:03 INFO - moznetwork == 0.27 23:34:03 INFO - mozprocess == 0.22 23:34:03 INFO - mozprofile == 0.28 23:34:03 INFO - mozrunner == 6.11 23:34:03 INFO - mozscreenshot == 0.1 23:34:03 INFO - mozsystemmonitor == 0.3 23:34:03 INFO - moztest == 0.7 23:34:03 INFO - mozversion == 1.4 23:34:03 INFO - psutil == 3.1.1 23:34:03 INFO - requests == 1.2.3 23:34:03 INFO - wsgiref == 0.1.2 23:34:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:34:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:34:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:03 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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:34:03 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 23:34:03 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 23:34:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:34:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:03 INFO - 'HOME': '/Users/cltbld', 23:34:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:03 INFO - 'LOGNAME': 'cltbld', 23:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:03 INFO - 'MOZ_NO_REMOTE': '1', 23:34:03 INFO - 'NO_EM_RESTART': '1', 23:34:03 INFO - 'PAGER': '/bin/cat', 23:34:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:03 INFO - 'PWD': '/builds/slave/test', 23:34:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:03 INFO - 'SHELL': '/bin/bash', 23:34:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:34:03 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:34:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:03 INFO - 'USER': 'cltbld', 23:34:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:03 INFO - 'XPC_FLAGS': '0x0', 23:34:03 INFO - 'XPC_SERVICE_NAME': '0', 23:34:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 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)) 23:34:03 INFO - Cleaning up... 23:34:03 INFO - Return code: 0 23:34:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:34:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:34:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:03 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 0x1068e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10697a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f225499f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, '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.2VArespZk0/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.OfSHMmu3fX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 23:34:03 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 23:34:03 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 23:34:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:34:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:03 INFO - 'HOME': '/Users/cltbld', 23:34:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:03 INFO - 'LOGNAME': 'cltbld', 23:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:03 INFO - 'MOZ_NO_REMOTE': '1', 23:34:03 INFO - 'NO_EM_RESTART': '1', 23:34:03 INFO - 'PAGER': '/bin/cat', 23:34:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:03 INFO - 'PWD': '/builds/slave/test', 23:34:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:03 INFO - 'SHELL': '/bin/bash', 23:34:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:34:03 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:34:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:03 INFO - 'USER': 'cltbld', 23:34:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:03 INFO - 'XPC_FLAGS': '0x0', 23:34:03 INFO - 'XPC_SERVICE_NAME': '0', 23:34:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:34:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:34:04 INFO - Cleaning up... 23:34:04 INFO - Return code: 0 23:34:04 INFO - Running post-action listener: _resource_record_post_action 23:34:04 INFO - Running post-action listener: _start_resource_monitoring 23:34:04 INFO - Starting resource monitoring. 23:34:04 INFO - ##### 23:34:04 INFO - ##### Finished create-virtualenv step (success) 23:34:04 INFO - ##### 23:34:04 INFO - ##### 23:34:04 INFO - ##### Running install step. 23:34:04 INFO - ##### 23:34:04 INFO - Running pre-action listener: _resource_record_pre_action 23:34:04 INFO - Running main action method: install 23:34:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:34:04 INFO - Reading from file tmpfile_stdout 23:34:04 INFO - Detecting whether we're running mozinstall >=1.0... 23:34:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:34:04 INFO - Reading from file tmpfile_stdout 23:34:04 INFO - Output received: 23:34:04 INFO - Usage: mozinstall [options] installer 23:34:04 INFO - Options: 23:34:04 INFO - -h, --help show this help message and exit 23:34:04 INFO - -d DEST, --destination=DEST 23:34:04 INFO - Directory to install application into. [default: 23:34:04 INFO - "/builds/slave/test"] 23:34:04 INFO - --app=APP Application being installed. [default: firefox] 23:34:04 INFO - mkdir: /builds/slave/test/build/application 23:34:04 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'] 23:34:04 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 23:34:38 INFO - Reading from file tmpfile_stdout 23:34:38 INFO - Output received: 23:34:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:34:38 INFO - Running post-action listener: _resource_record_post_action 23:34:38 INFO - ##### 23:34:38 INFO - ##### Finished install step (success) 23:34:38 INFO - ##### 23:34:38 INFO - ##### 23:34:38 INFO - ##### Running run-tests step. 23:34:38 INFO - ##### 23:34:38 INFO - Running pre-action listener: _resource_record_pre_action 23:34:38 INFO - Running main action method: run_tests 23:34:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:34:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:34:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:34:38 INFO - 2016-07-22 23:34:38.799 screenresolution[1652:8412] starting screenresolution argv=screenresolution get 23:34:38 INFO - 2016-07-22 23:34:38.802 screenresolution[1652:8412] Display 0: 1600x1200x32@60 23:34:38 INFO - 2016-07-22 23:34:38.808 screenresolution[1653:8414] starting screenresolution argv=screenresolution list 23:34:38 INFO - Available Modes on Display 0 23:34:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:34:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:34:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:34:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:34:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:34:39 INFO - Intel Iris: 23:34:39 INFO - Chipset Model: Intel Iris 23:34:39 INFO - Type: GPU 23:34:39 INFO - Bus: Built-In 23:34:39 INFO - VRAM (Dynamic, Max): 1536 MB 23:34:39 INFO - Vendor: Intel (0x8086) 23:34:39 INFO - Device ID: 0x0a2e 23:34:39 INFO - Revision ID: 0x0009 23:34:39 INFO - Displays: 23:34:39 INFO - Display: 23:34:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:34:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:34:39 INFO - Display Serial Number: 1600x1200 60 23:34:39 INFO - Main Display: Yes 23:34:39 INFO - Mirror: Off 23:34:39 INFO - Online: Yes 23:34:39 INFO - Rotation: Supported 23:34:39 INFO - Return code: 0 23:34:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:34:39 INFO - Python 2.7.3 23:34:39 INFO - Return code: 0 23:34:39 INFO - grabbing minidump binary from tooltool 23:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:39 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 0x106849d30>, '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 0x1065aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106841b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:34:39 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 23:34:39 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 23:34:39 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 23:34:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:34:39 INFO - Return code: 0 23:34:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:34:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:34:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:34:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:34:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:34:39 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/1467450926/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 23:34:39 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/1467450926/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0387 --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 23:34:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2VArespZk0/Render', 23:34:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:39 INFO - 'HOME': '/Users/cltbld', 23:34:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:39 INFO - 'LOGNAME': 'cltbld', 23:34:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:34:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:39 INFO - 'MOZ_NO_REMOTE': '1', 23:34:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:34:39 INFO - 'NO_EM_RESTART': '1', 23:34:39 INFO - 'PAGER': '/bin/cat', 23:34:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:39 INFO - 'PWD': '/builds/slave/test', 23:34:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:34:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:39 INFO - 'SHELL': '/bin/bash', 23:34:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OfSHMmu3fX/Listeners', 23:34:39 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 23:34:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:39 INFO - 'USER': 'cltbld', 23:34:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:39 INFO - 'XPC_FLAGS': '0x0', 23:34:39 INFO - 'XPC_SERVICE_NAME': '0', 23:34:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:39 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/1467450926/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 23:34:39 INFO - mozversion application_buildid: 20160702021526 23:34:39 INFO - mozversion application_changeset: 49a1d28776b4a2867bb8818dfab61c352cc02f49 23:34:39 INFO - mozversion application_display_name: Nightly 23:34:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:34:39 INFO - mozversion application_name: Firefox 23:34:39 INFO - mozversion application_remotingname: firefox 23:34:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:34:39 INFO - mozversion application_vendor: Mozilla 23:34:39 INFO - mozversion application_version: 50.0a1 23:34:39 INFO - mozversion platform_buildid: 20160702021526 23:34:39 INFO - mozversion platform_changeset: 49a1d28776b4a2867bb8818dfab61c352cc02f49 23:34:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:34:39 INFO - mozversion platform_version: 50.0a1 23:34:39 INFO - using testdate: 1469255679 23:34:39 INFO - actual date: 1469255679 23:34:39 INFO - starting webserver on 'localhost:49274' 23:34:39 INFO - SUITE-START | Running 2 tests 23:34:39 INFO - TEST-START | dromaeo_css 23:34:39 INFO - operating with platform_type : mac_ 23:34:39 INFO - Initialising browser for dromaeo_css test... 23:34:39 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpSghVT8/profile http://localhost:49274/getInfo.html) 23:34:42 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 23:34:42 INFO - PROCESS | 1663 | colorDepth:24 23:34:42 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 23:34:42 INFO - PROCESS | 1663 | __metrics 23:34:42 INFO - PROCESS | 1663 | 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" 23:34:42 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:34:42 INFO - TEST-INFO | 1663: exit 0 23:34:42 INFO - Browser initialized. 23:34:42 INFO - Running cycle 1/1 for dromaeo_css test... 23:34:42 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpSghVT8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:34:54 INFO - PROCESS | 1666 | RSS: Main: 244776960 23:34:54 INFO - PROCESS | 1666 | 23:35:46 INFO - PROCESS | 1666 | 1469255746247 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] 23:37:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 23:37:58 INFO - PROCESS | 1666 | RSS: Main: 367566848 23:37:58 INFO - PROCESS | 1666 | 23:41:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 23:41:01 INFO - PROCESS | 1666 | RSS: Main: 370896896 23:41:01 INFO - PROCESS | 1666 | 23:44:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 23:44:04 INFO - PROCESS | 1666 | RSS: Main: 374693888 23:44:04 INFO - PROCESS | 1666 | 23:47:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 23:47:07 INFO - PROCESS | 1666 | RSS: Main: 368652288 23:47:07 INFO - PROCESS | 1666 | 23:50:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 23:50:11 INFO - PROCESS | 1666 | RSS: Main: 336928768 23:50:11 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 23:53:14 INFO - PROCESS | 1666 | RSS: Main: 386076672 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | __start_tp_report 23:53:14 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 23:53:14 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 23:53:14 INFO - PROCESS | 1666 | |i|pagename|runs| 23:53:14 INFO - PROCESS | 1666 | |0;dojo.html;3300.8019518828864;3324.684155005275;3342.4317865962785;3344.347852168827;3386.3565922474727;5608.10270356743;5658.886822263557;5697.544196464278;5724.542036637069;5749.281339832521;5620.550355971523;5655;5731.254936858202;5746.051901436268;5782.017057100293;5049.141645920203;5063.291139240491;5118.6416950813455;5125.103106956286;5242.135047717122;5208.2968799212185;5233.267342572043;5258;5263.4210236874105;5299;5071.213961835884;5083.491650834942;5128.692278463304;5136.768845401966;5160.174372100446;5097.209932460438;5111.7699703513435;5113.821016264413;5161.870953226162;5219.895602087975;5111.923321150186;5119.974400128012;5135;5173.844784656429;5192.350956130484;5593.049181639131;5640.407757185519;5711.628744131618;5718.542516598662;5799.9130013049835;6167.290761562433;6169.290531588835;6227.688615569204;6235.220597425322;6238.594491358047;6259.718312675895;6326.240851097852;6331.810045698637;6334.841628959267;6335.619862808247;5631.521320687705;5682.977064128497;5720.313562372542;5802.709864506758;5822.446867547618;6296.212973378236;6351.809445716636;6354.52341074412;6359.236891573041;6401.295857455676;6088.6042407243385;6089.725962331706;6107.633541987495;6114.529105537134;6115.296740874767;6181.907271390933;6197;6254.21822272216;6275.560710750295;6287.7799277026;6009.519238460913;6086.573939824259;6107.450329470369;6155.138280640714;6162.537809664203;6245.156903818017;6251.093591429218;6254.437100660963;6255.873942690359;6264.749410023639;4836.419629644395;4879.5120487951635;4883.804647814119;4888.804447822118;4903.436104847917;4334.111507141029;4377.124575084996;4431.955680443219;4460.286354183315;4462.955370446319;5667.773289068474;5703.515201207861;5708.972384970745;5740.368559458456;5801.448862358069;6283.96858015707;6298.370162983665;6311.147995020613;6311.684415779193;6321;5513.9448605513435;5733.226014488074;5748.367679555246;5769.711514424262;5804.100364443518;5313.362396512366;5319.0957537218765;5366.597505187126;5389.245505629215;5390.865228369322;6284.37156284377;6308.27454842699;6315.8105256841445;6329.30377658448;6340.809775706736;6026.0659597762415;6302.369763023661;6306.65313407767;6306.873862522815;6316.431521163117;6276.780312689034;6334.493240540838;6338.334474880256;6342.112104305494;6353.555251132377;6002.7298771554515;6027.035674292092;6039.305479869871;6053.576249662481;6053.788117415869;6132.01899668756;6180.381961803783;6195.845103872259;6198;6204.162438070803;5940.732667029907;5967.791127310524;5971.343152253162;5974.820755377172;5977.880442391041;5834.212381328466;5939.109133629817;5948.524118070631;5972.134040564007;5981.07293369511;5856.3558008618165;5942.940570594239;5946.613470124428;5973.731182096729;5981.0729336952845;6076.72654730547;6088.802071683797;6103.671041328402;6108.873974645787;6112.41229444207;5709.314882214161;5779.7110144493445;5790.826275211751;5932.911006334994;5968.373320801254 23:53:14 INFO - PROCESS | 1666 | |1;ext.html;526.1870410216217;530.3688610553442;535.3950036458799;539.948704873037;540.732337492941;27181.45637087257;37573;41864.790676046614;43028.78485607571;43126.92182695434;36616.084597885;43870.561294387044;44162.55837441624;44415.555844441544;44430;8725.258353039999;9073.36486445949;9115.814944057263;9857.359271647356;9876.308658393915;8722.520261385622;11913;12140.089493287995;12149.939250303736;12340.197887137307;12094.758104837898;12150.635480935585;12153.453094610719;12154.93922530386;12180.69548261296;8706.998695150076;11791.29252244868;11982.520699172022;12000.69998250042;12066.577669781516;11868.169228154033;11968.461419236155;11971;11977.401129943468;11992.28046317215;41929.161416771494;42171.15657686829;42184.57815421868;42316.57683423157;42320;3441.0709108540555;3489.6859282664427;3493.8602455901732;3615;3818.293615681108;26009.739902600923;33223.50823688209;33302.16744581405;33358.166045848804;33439.498407523904;3296.604407471095;3317.419451595961;3324.7007769300635;3365.360581489509;3449.482227819751;484566;485930;487475;490842.54578727233;494417;34114.48828267578;34272.314553708784;60909;61162.388376116556;61217.38782612206;30912;36278.45582316267;36513.45229821555;36517.26965460676;36615.26769464596;14911.508849115002;14943.252837358088;15106.773398399033;15109.773353399707;15114.395424183129;11450.54197832094;11639.534418623329;11650.242734222249;11850.703732406586;12627.621171364874;7596.734114305973;7819.765407037798;7831.216878312123;7840.176781437981;8048.356131509465;3265.510173473998;3281.2945216778508;3285.720713739311;3292.489664102068;3300.85146168423;966.9419834809934;973.1922504321396;984.8473486609587;1009.9444530550743;1115.2973626615342;29022.570573373298;30576.69423305783;30884.58608620306;30907.69092309093;31139;990.3166814897697;1092.0990183098816;1111.0833562310963;1145.169751929861;1196.4376742930808;15742.189648190604;16231;16233.59416014609;16476.6704665906;16701.91649041747;708402;713546;714206;715021;717677;45669.543304567196;60101.699491502266;62271.6886415565;62338.68830655818;62519.6874015627;1218.5065048655308;1259.7669431155266;1299.382793173235;1380.9516666916609;1421.7014426970452;30497.847510763193;31808.522872156474;31915.28720058821;31926.52110218395;32790.50814237836;53717.92564148773;54342.184867227814;54352;54427;54460.45539544554;561949;576072;577810.3313801185;578184;578858;20705.171793128105;24323.148689795773;24820;27735.30661733473;28114.578281325386;49874.750626246634;50148.74628134326;50443;52504.474955249956;53838.46161538334;38651.61348386593;40858;41355.1728965425;41736.79131604444;42628.573714262464;36755;40129.39805902856;40608.1878362437;41122.794386027876;41919.580804191566;38971;39373;39579.80210098931;39615.603843962344;39927;28878.422431551673;30783.9225627102;35633.46549801703;35943.64056359403;36083.63916360803;22167.33497995063;22953.540929181658;25133.49199048063;25962;27720.861395692893 23:53:14 INFO - PROCESS | 1666 | |2;jquery.html;376.6403085053774;383.38466760848837;384.78067501524123;393.5316972802363;403.72143221177384;19523.804761952375;21213.575728485423;21617.783822161775;21914.671279930815;21975;19886.70169947452;20257;20579.794202057976;20869.58260834786;20927.162913483444;5041.949580504203;5629.521490673298;5891.941080589182;5901.911471327933;5906.409359064102;5810.738516766734;5819.709014549256;5930.940690593082;5936.436038576328;5939.227900372945;5713.542916566665;6068.15045893573;6069.696515174246;6113.388661133873;6116.81649550512;5855.09246066856;6051.2133422655;6100.084987251903;6102.176206212149;6111.327753947085;5830.912536311958;5858;5861.062230043215;5863.5016023638045;5864.912026319608;12696.936515317457;13055;13070.673233169151;13150.408231629599;13421.798673019912;2188.3763127508582;2194.297824696098;2310.145246258895;2321.6285394336824;2737.20621019906;9510.762230944281;9940.5029748513;9970.451625160686;10344.431056291902;10485.161187105014;1849.5191250275025;1902.8477721782224;1938.1665883670016;1957.5595491014408;2036.2465887621527;112987.43506282498;118466.40766796198;118837;119497;120071.39964300209;9235.861462078074;11287.43562821872;11495.712607184887;11592.6522204334;11663;14281.00032997701;14301.78547321791;14335.498257560936;14345;14353.569392918229;7751.534907905544;7869.134395216522;8298.54358010303;8497;8591.05498395176;8371.874421883676;8375.45559538628;8379.664813407517;8500.65997360111;8526.701565445304;4390.912181756347;4481.148581769454;4499.820007199741;4558.817647294071;4563.338315944236;1856.6379555986452;1907.551725344543;1909.5703466719876;1915.7796853361763;1924.6150769846088;863.9870401943975;873.9257163141077;879.4547380624055;916.4318122763929;954.0936110694868;20622.79377206239;21072.840993745493;21098.894505527373;21134.048967796487;21208;897.7935074932802;929.8047410043831;931.1340453378288;936.442816524167;1014.3558840136419;8711.649694297394;10772.94613526943;11057.723556911142;11098;11123.833142502868;587885;589993;603537;612372.9381353065;614002;122356;122487;124248.1362779559;124464;126738;923.5428462910902;929.6705710833573;947.0245646983618;949.6343907595625;975.8243516167016;15189.6202594936;18278.543036423664;18516.537086572942;18592.535186620444;18965.33621323247;42996.57003430051;54432.45567544274;56161.43838561726;57735.71132144312;57844;69121.61756764937;70202.64898675474;70426;70486.64756676389;71477;7940.6029698513685;8063.27430531255;8066.072401673882;8079.555624440713;8260.545669988209;11345.716357091138;12413.44139513706;12544.435500402322;12586.748265034465;12723.55467558631;10698.518566664363;10757.731056723644;10760.40817755031;10806.18953578469;10890.618828341287;10787.568497260021;10891.237613366988;10913.618023369145;10941.96051375118;11062.280951738112;11142.83285750698;11146.219764616724;11210.55157793679;11293.887061129284;11372.374519401512;7925.128235893932;8050.476719013245;8074.515529068275;8116.634751436081;8246;9215.723528294162;10821.080208182078;10889.292196007234;10987.890121098686;11031.779364412507 23:53:14 INFO - PROCESS | 1666 | |3;mootools.html;1366.4261010375642;1508.3966413434628;1555.7588573771066;1723.870709696773;1886.2549293029253;7721.03487064117;8498.532580708059;8726.825463490733;8727.607257673404;8882.378233523656;8520.91479085209;8602.2688071514;8665.39342246043;8678.088800675932;8678.91321086789;2025.8379329653592;2087.832973362127;2170.576737536181;2345.9296221113395;2383.916562920298;2162.5999190149796;2190.200576789476;2191.7150770399826;2197.6703494475796;2331.463763334434;2364.858108513486;2364.917227897024;2370.5969985102574;2374.394529395002;2376.5009348036933;2265.8187345012443;2292.071710957065;2295.5753185660706;2295.678604995302;2353.2469609724894;2088.0603728322317;2271.1710225767642;2342.5783358995373;2351.4356554426986;2353.176388264111;8613.698520551814;8695.956520217422;8698.869516957251;8722.30221582272;8726.86909696355;4856.490068542823;4930.975345123287;4932.8273510427325;4933.98853235086;4935.5804756595735;3317.668233176687;3384.4923261510726;3455.706264967481;3457.4122399192197;3458.2219000724886;1045.4354648489807;1061.9150467962545;1077.6713102503734;1081.6592773276393;1102.167863263231;9849.950750246275;9908.207343412581;9914.90085099147;9915.851262231072;9926.305158638897;4242.278812601865;4347.586979236968;4493.887652808706;4503.729776213438;4516.254817955;8539.573021349031;8586.184312490428;8599.78500537479;8606.78483037929;8610.61252243638;4924.162892308316;4933.975330123327;4934.3338649282605;4937.851864444073;4941;4468.352088947085;4553.33976573395;4557.065801510682;4561.270196768501;4573.291139873325;3646.726495512847;3777.99883030997;3782.2813665403487;3789.829457674411;3797.5632802227547;1247.6381849263794;1309.6987692830512;1316.8880645145077;1322.4247452358254;1326.3500884566492;806.2260230179094;902.2376092202079;903.8418276801533;929.9256059515222;951.3673407184145;5841.153032810304;5848.239728835224;5874.50066744323;5890.646561206341;6012.6392416455155;946.3612061858219;950.4154944708954;952.7475219066953;974.878140232471;987.9407235565889;3848.6728628066367;3967.0479085019388;3981.1838573092446;4036.9798151009645;4038.656714179269;10165;10576.995185457523;10637.202209834139;10663.306885052447;10675.83986240207;10594.046535811814;10598.31110977784;10613.044825965546;10618.840717389245;10665.466726663635;7740.26467485588;8004.679812807539;8075.273225409624;8181.304589109874;8201.95899020513;5982.670953097448;6012.819615411545;6055.424734650202;6079;6108.236470441195;9944.602215911282;10084.344517606332;10092.990700929848;10094.54574544162;10116.29185956976;9688.951555242178;9966.003399659976;10026.69919902404;10206.489675516046;10219.897801021894;7504.549727016397;7569.810754731176;7639.770806875803;7652.311291983749;7669.61651917413;7911.248431399008;8219.753407397788;8286.337093032664;8287.12985136552;8301.750947471586;7378.5941773202985;7414.814629634302;7422.888656670262;7428.9628551856895;7597.848043039219;7272.709091636275;7297.708091676273;7333.230010848784;7471.028766260351;7516.285952834472;6987.825304367431;7023.981522679285;7032.015517827406;7045.225025247116;7045.647717614202;4879.634027448026;4979.850604481871;5006.123928312603;5070.746462676925;5079;4332.761698106634;5026.798928042837;5088.465711100429;5099.490050995019;5158.252053452304 23:53:14 INFO - PROCESS | 1666 | |4;prototype.html;296.85454127477544;298.5596245537831;309.9008317338451;313.36856234687093;315.4416682472025;5374.892502149954;5391.245225668405;5405.945940540593;5414.103454651758;5418.83743487696;4142.627163555279;5093.236014597812;5222;5232.659877107985;5321.4412486688925;4026;4053.331200351946;4053.9797301013455;4072.5316588592245;4087.2847251730977;4054.0067683417606;4072.6131017553284;4076.449679293302;4091.034000349752;4092.0997380576373;3342.713055473648;3487.581490221177;3585.569731632208;3812.390017597185;4024.315866302721;3346.9832650836834;3712.3688972874675;3952.932708168807;4011.5787842276577;4036.3340048891896;2927.399883023975;3216.0512648768668;3418.384690755663;3533.4346504559403;3538.380783362901;4275.828966841323;4438.533953934822;4886.900447399342;5091.690401239224;5242.973785131088;4145.585441455861;4419.337099435078;4609.976950115261;4613.8154473821005;4664.976675116603;4526.125910047111;4585.885352866172;4837.443693975203;5585.413531579207;5603.973854118771;3263.298390845956;3429.8971030869116;3919.608039196086;3919.9608003920166;3931.528216613996;5546.611737178399;5600.647727954269;5675.631083979528;5889.204957330749;5915.231019967419;3404.14896275931;3921.490206273166;3954.9209015819524;3970.3250447424007;3979.900502487461;3333.516640087222;4021.4973128358956;4022.8189731462153;4023.0229630073536;4025.5370632377067;3873.670737987303;4448.510663826976;4628.560286772751;4640.883977900512;4642.953570464252;4461.598456138898;4483.910321793546;5603.3836278009385;5607.551395888319;5647.350554686263;1268.0616343905629;1290.329028904983;1296.6693493159185;1328.926909019994;1367.699106196635;790.0006491787893;926.1895841139003;927.7309580221795;933.2487347685178;934.5327336331834;4909.926351104737;5226.869328266747;5238.790448382022;5270.868228294324;5279.392869819943;5006.923511445057;5201.956855239727;5422.3764267109;5700.743466544015;5776.306843178845;4283.258714422772;4608.88477788058;5079.212722028092;5255.658382205144;5269.604779641542;2623.776978956772;2679.196241127654;2944.514155164417;2992.685767994373;3003.543281508467;4889.657723959289;5124.948750512447;5277;5310.707911064928;5967.313758917693;4748.507033807279;5755.366909639936;5841.561882858718;5855.477575830315;5902.3507414184405;5587.217789509472;5889.7042650616795;5894.823155305262;5902.439268269593;5914.526837852875;4740.644451666208;5370.838874833761;5542.0301447247375;5704.657720536781;5712.7714891403875;4679.017406344706;4921.818763496806;5108.821191258288;5618.4662457066515;5629.915551266816;5237.16205407133;5658.038133517394;5826.796062137805;5861.32594751609;5875;3275.5086736989642;3871.8838434845875;3921.3333733265986;3944.349182384989;3962.722609417313;4629.7916593754035;5399.379071406838;5547.722613869371;5647.322321321468;5651.2935883014625;4838.814490449862;5512.448755124616;5521.309836270466;5525.347833040083;5582.190582365453;4457.398251236042;4573.954260457353;5457.317835270591;5541.3904470509015;5545.500904918576;4306.633936115465;5089.821856235162;5315.6279060465395;5409.59195706718;5525.475079867406;4884.902301954012;4932.8766780830765;4976.8755781105765;5567.886422715392;5620.550355971431;5287.629865909504;5496.230527726041;5496.780128794803;5508.394076651485;5515.111846148536 23:53:14 INFO - PROCESS | 1666 | |5;yui.html;683.4327508168221;733.8018734941566;738.2064280898032;814.3322475570028;857.3826844671838;14884.851151488481;15721.37114515421;16048.438304659354;16084;16287.104209268486;12130.69673258169;14637.560873173796;14798.26008699566;14824.407023719039;14912.925435372836;686.097781417437;710.183289217399;844.2570537926616;846.3652260804397;879.1955360844819;863.9654413823439;870.0125357718971;870.3428911172056;874.2481465939287;875.4484674654958;918.8897332320131;919.8712180294764;920.2683866326253;920.7974245665977;922.2069020642242;706.7773651299875;749.7563291930145;753.8567672142275;755.0938873352;916.3860213656866;643.8519140597642;680.5610381304081;682.2597481732327;684.849333146712;689.348565605503;14837.406503739838;14884.03253788511;14888.851111488855;14900.180490073042;14900.925495372561;13700.588982330546;13770.380332884944;13823.170609763447;13843.377048032862;14140.787888181685;12637.2417654941;12809.871901280962;12836.101472896808;12906.935465322613;13303.73392532144;4536.954630453686;4583.289590113504;4613.723176609381;4619.630429565626;4787.497312782178;105556.9313060279;107530;108622.82754344947;108634;109118.45440772745;10950.3429794211;11042;11097.556097756;11115.777684446266;11550.711232219262;21735.239266625907;21742.89128554379;21773;21775.564488710137;21784.564308714052;7897.012873390826;7916.881246781303;8007.639656215484;8075.313598344089;8174.632141553644;3827.78947157909;4418.8453404130705;4485.058137791035;4496.572825581565;4514.435695538058;3373.527706121145;3466.30673865228;3472.6700963408434;3499.3176330615793;3503.6146023937345;564.9576281778883;635.1383587786248;652.4682383857141;657;671.1979184874028;522.5558275465823;559.1558038485413;630.1146888621494;641.7978336823885;641.9422251997249;7624.771256862303;8873.822523549492;8955.641774328953;9029.277657787361;9064.682736104205;591.1576004194093;613.2303958532013;621.6891554222889;633.2875515045574;634.30226750574;4263.104748002892;4379.671524635665;4380;4473.541187791983;4535.841245556389;127839.77281817433;130791.34604327107;132007;140251;148364.2581787084;124142.13786793205;124667;127093;127817;128187;91875.54062229647;102591.54788946916;109778;110262.76846778438;112938;9874.290045427246;9957;9966.302358834811;9992.650257241252;10023.54894029785;53677.463225367246;54497;55373.446265536826;59103.226903192975;59223.11165332437;88823.11176888148;95739.08521829663;96637;96972.06055878702;99484.50257748665;12974.675633109247;13095.738085238432;13123.212607243597;13134;13671.658208544866;14947.25263736787;15013.849861501245;15411.229438528253;15633.921830390775;15642.374305027672;13733.656658583215;14108.506202282872;14125.788113178105;14395.136291822524;14564.271786410849;13081.149725267827;13145.145565538593;14044.368003439666;14387.78418323705;14516.854831451972;13562.457501699822;13670.248136352198;13780.655483612589;14002.369893355028;14369.06601070927;11672.88327116718;11677.416129193676;11864.466099025392;11930.224535405518;12057.51769929228;11391.259568128045;11490.54037838477;11529.769404612029;11703.23928944616;11704.356260405759 23:53:14 INFO - PROCESS | 1666 | __end_tp_report 23:53:14 INFO - PROCESS | 1666 | __start_cc_report 23:53:14 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1182 23:53:14 INFO - PROCESS | 1666 | __end_cc_report 23:53:14 INFO - PROCESS | 1666 | __startTimestamp1469256794932__endTimestamp 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | ------- Summary: start ------- 23:53:14 INFO - PROCESS | 1666 | Number of tests: 6 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5721.04 Median:5940.73 stddev:646.90 (10.9%) stddev-sans-first:620.39 23:53:14 INFO - PROCESS | 1666 | Values: 3300.8 3324.7 3342.4 3344.3 3386.4 5608.1 5658.9 5697.5 5724.5 5749.3 5620.6 5655.0 5731.3 5746.1 5782.0 5049.1 5063.3 5118.6 5125.1 5242.1 5208.3 5233.3 5258.0 5263.4 5299.0 5071.2 5083.5 5128.7 5136.8 5160.2 5097.2 5111.8 5113.8 5161.9 5219.9 5111.9 5120.0 5135.0 5173.8 5192.4 5593.0 5640.4 5711.6 5718.5 5799.9 6167.3 6169.3 6227.7 6235.2 6238.6 6259.7 6326.2 6331.8 6334.8 6335.6 5631.5 5683.0 5720.3 5802.7 5822.4 6296.2 6351.8 6354.5 6359.2 6401.3 6088.6 6089.7 6107.6 6114.5 6115.3 6181.9 6197.0 6254.2 6275.6 6287.8 6009.5 6086.6 6107.5 6155.1 6162.5 6245.2 6251.1 6254.4 6255.9 6264.7 4836.4 4879.5 4883.8 4888.8 4903.4 4334.1 4377.1 4432.0 4460.3 4463.0 5667.8 5703.5 5709.0 5740.4 5801.4 6284.0 6298.4 6311.1 6311.7 6321.0 5513.9 5733.2 5748.4 5769.7 5804.1 5313.4 5319.1 5366.6 5389.2 5390.9 6284.4 6308.3 6315.8 6329.3 6340.8 6026.1 6302.4 6306.7 6306.9 6316.4 6276.8 6334.5 6338.3 6342.1 6353.6 6002.7 6027.0 6039.3 6053.6 6053.8 6132.0 6180.4 6195.8 6198.0 6204.2 5940.7 5967.8 5971.3 5974.8 5977.9 5834.2 5939.1 5948.5 5972.1 5981.1 5856.4 5942.9 5946.6 5973.7 5981.1 6076.7 6088.8 6103.7 6108.9 6112.4 5709.3 5779.7 5790.8 5932.9 5968.4 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:71385.76 Median:19849.32 stddev:160718.43 (809.7%) stddev-sans-first:161081.23 23:53:14 INFO - PROCESS | 1666 | Values: 526.2 530.4 535.4 539.9 540.7 27181.5 37573.0 41864.8 43028.8 43126.9 36616.1 43870.6 44162.6 44415.6 44430.0 8725.3 9073.4 9115.8 9857.4 9876.3 8722.5 11913.0 12140.1 12149.9 12340.2 12094.8 12150.6 12153.5 12154.9 12180.7 8707.0 11791.3 11982.5 12000.7 12066.6 11868.2 11968.5 11971.0 11977.4 11992.3 41929.2 42171.2 42184.6 42316.6 42320.0 3441.1 3489.7 3493.9 3615.0 3818.3 26009.7 33223.5 33302.2 33358.2 33439.5 3296.6 3317.4 3324.7 3365.4 3449.5 484566.0 485930.0 487475.0 490842.5 494417.0 34114.5 34272.3 60909.0 61162.4 61217.4 30912.0 36278.5 36513.5 36517.3 36615.3 14911.5 14943.3 15106.8 15109.8 15114.4 11450.5 11639.5 11650.2 11850.7 12627.6 7596.7 7819.8 7831.2 7840.2 8048.4 3265.5 3281.3 3285.7 3292.5 3300.9 966.9 973.2 984.8 1009.9 1115.3 29022.6 30576.7 30884.6 30907.7 31139.0 990.3 1092.1 1111.1 1145.2 1196.4 15742.2 16231.0 16233.6 16476.7 16701.9 708402.0 713546.0 714206.0 715021.0 717677.0 45669.5 60101.7 62271.7 62338.7 62519.7 1218.5 1259.8 1299.4 1381.0 1421.7 30497.8 31808.5 31915.3 31926.5 32790.5 53717.9 54342.2 54352.0 54427.0 54460.5 561949.0 576072.0 577810.3 578184.0 578858.0 20705.2 24323.1 24820.0 27735.3 28114.6 49874.8 50148.7 50443.0 52504.5 53838.5 38651.6 40858.0 41355.2 41736.8 42628.6 36755.0 40129.4 40608.2 41122.8 41919.6 38971.0 39373.0 39579.8 39615.6 39927.0 28878.4 30783.9 35633.5 35943.6 36083.6 22167.3 22953.5 25133.5 25962.0 27720.9 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34430.50 Median:380.01 stddev:100274.10 (26387.1%) stddev-sans-first:100522.80 23:53:14 INFO - PROCESS | 1666 | Values: 376.6 383.4 384.8 393.5 403.7 19523.8 21213.6 21617.8 21914.7 21975.0 19886.7 20257.0 20579.8 20869.6 20927.2 5041.9 5629.5 5891.9 5901.9 5906.4 5810.7 5819.7 5930.9 5936.4 5939.2 5713.5 6068.2 6069.7 6113.4 6116.8 5855.1 6051.2 6100.1 6102.2 6111.3 5830.9 5858.0 5861.1 5863.5 5864.9 12696.9 13055.0 13070.7 13150.4 13421.8 2188.4 2194.3 2310.1 2321.6 2737.2 9510.8 9940.5 9970.5 10344.4 10485.2 1849.5 1902.8 1938.2 1957.6 2036.2 112987.4 118466.4 118837.0 119497.0 120071.4 9235.9 11287.4 11495.7 11592.7 11663.0 14281.0 14301.8 14335.5 14345.0 14353.6 7751.5 7869.1 8298.5 8497.0 8591.1 8371.9 8375.5 8379.7 8500.7 8526.7 4390.9 4481.1 4499.8 4558.8 4563.3 1856.6 1907.6 1909.6 1915.8 1924.6 864.0 873.9 879.5 916.4 954.1 20622.8 21072.8 21098.9 21134.0 21208.0 897.8 929.8 931.1 936.4 1014.4 8711.6 10772.9 11057.7 11098.0 11123.8 587885.0 589993.0 603537.0 612372.9 614002.0 122356.0 122487.0 124248.1 124464.0 126738.0 923.5 929.7 947.0 949.6 975.8 15189.6 18278.5 18516.5 18592.5 18965.3 42996.6 54432.5 56161.4 57735.7 57844.0 69121.6 70202.6 70426.0 70486.6 71477.0 7940.6 8063.3 8066.1 8079.6 8260.5 11345.7 12413.4 12544.4 12586.7 12723.6 10698.5 10757.7 10760.4 10806.2 10890.6 10787.6 10891.2 10913.6 10942.0 11062.3 11142.8 11146.2 11210.6 11293.9 11372.4 7925.1 8050.5 8074.5 8116.6 8246.0 9215.7 10821.1 10889.3 10987.9 11031.8 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5565.07 Median:4934.96 stddev:3076.05 (62.3%) stddev-sans-first:3068.50 23:53:14 INFO - PROCESS | 1666 | Values: 1366.4 1508.4 1555.8 1723.9 1886.3 7721.0 8498.5 8726.8 8727.6 8882.4 8520.9 8602.3 8665.4 8678.1 8678.9 2025.8 2087.8 2170.6 2345.9 2383.9 2162.6 2190.2 2191.7 2197.7 2331.5 2364.9 2364.9 2370.6 2374.4 2376.5 2265.8 2292.1 2295.6 2295.7 2353.2 2088.1 2271.2 2342.6 2351.4 2353.2 8613.7 8696.0 8698.9 8722.3 8726.9 4856.5 4931.0 4932.8 4934.0 4935.6 3317.7 3384.5 3455.7 3457.4 3458.2 1045.4 1061.9 1077.7 1081.7 1102.2 9850.0 9908.2 9914.9 9915.9 9926.3 4242.3 4347.6 4493.9 4503.7 4516.3 8539.6 8586.2 8599.8 8606.8 8610.6 4924.2 4934.0 4934.3 4937.9 4941.0 4468.4 4553.3 4557.1 4561.3 4573.3 3646.7 3778.0 3782.3 3789.8 3797.6 1247.6 1309.7 1316.9 1322.4 1326.4 806.2 902.2 903.8 929.9 951.4 5841.2 5848.2 5874.5 5890.6 6012.6 946.4 950.4 952.7 974.9 987.9 3848.7 3967.0 3981.2 4037.0 4038.7 10165.0 10577.0 10637.2 10663.3 10675.8 10594.0 10598.3 10613.0 10618.8 10665.5 7740.3 8004.7 8075.3 8181.3 8202.0 5982.7 6012.8 6055.4 6079.0 6108.2 9944.6 10084.3 10093.0 10094.5 10116.3 9689.0 9966.0 10026.7 10206.5 10219.9 7504.5 7569.8 7639.8 7652.3 7669.6 7911.2 8219.8 8286.3 8287.1 8301.8 7378.6 7414.8 7422.9 7429.0 7597.8 7272.7 7297.7 7333.2 7471.0 7516.3 6987.8 7024.0 7032.0 7045.2 7045.6 4879.6 4979.9 5006.1 5070.7 5079.0 4332.8 5026.8 5088.5 5099.5 5158.3 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4439.00 Median:4899.79 stddev:1362.18 (27.8%) stddev-sans-first:1330.05 23:53:14 INFO - PROCESS | 1666 | Values: 296.9 298.6 309.9 313.4 315.4 5374.9 5391.2 5405.9 5414.1 5418.8 4142.6 5093.2 5222.0 5232.7 5321.4 4026.0 4053.3 4054.0 4072.5 4087.3 4054.0 4072.6 4076.4 4091.0 4092.1 3342.7 3487.6 3585.6 3812.4 4024.3 3347.0 3712.4 3952.9 4011.6 4036.3 2927.4 3216.1 3418.4 3533.4 3538.4 4275.8 4438.5 4886.9 5091.7 5243.0 4145.6 4419.3 4610.0 4613.8 4665.0 4526.1 4585.9 4837.4 5585.4 5604.0 3263.3 3429.9 3919.6 3920.0 3931.5 5546.6 5600.6 5675.6 5889.2 5915.2 3404.1 3921.5 3954.9 3970.3 3979.9 3333.5 4021.5 4022.8 4023.0 4025.5 3873.7 4448.5 4628.6 4640.9 4643.0 4461.6 4483.9 5603.4 5607.6 5647.4 1268.1 1290.3 1296.7 1328.9 1367.7 790.0 926.2 927.7 933.2 934.5 4909.9 5226.9 5238.8 5270.9 5279.4 5006.9 5202.0 5422.4 5700.7 5776.3 4283.3 4608.9 5079.2 5255.7 5269.6 2623.8 2679.2 2944.5 2992.7 3003.5 4889.7 5124.9 5277.0 5310.7 5967.3 4748.5 5755.4 5841.6 5855.5 5902.4 5587.2 5889.7 5894.8 5902.4 5914.5 4740.6 5370.8 5542.0 5704.7 5712.8 4679.0 4921.8 5108.8 5618.5 5629.9 5237.2 5658.0 5826.8 5861.3 5875.0 3275.5 3871.9 3921.3 3944.3 3962.7 4629.8 5399.4 5547.7 5647.3 5651.3 4838.8 5512.4 5521.3 5525.3 5582.2 4457.4 4574.0 5457.3 5541.4 5545.5 4306.6 5089.8 5315.6 5409.6 5525.5 4884.9 4932.9 4976.9 5567.9 5620.6 5287.6 5496.2 5496.8 5508.4 5515.1 23:53:14 INFO - PROCESS | 1666 | 23:53:14 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:24337.68 Median:3419.92 stddev:37939.96 (1109.4%) stddev-sans-first:38004.82 23:53:14 INFO - PROCESS | 1666 | Values: 683.4 733.8 738.2 814.3 857.4 14884.9 15721.4 16048.4 16084.0 16287.1 12130.7 14637.6 14798.3 14824.4 14912.9 686.1 710.2 844.3 846.4 879.2 864.0 870.0 870.3 874.2 875.4 918.9 919.9 920.3 920.8 922.2 706.8 749.8 753.9 755.1 916.4 643.9 680.6 682.3 684.8 689.3 14837.4 14884.0 14888.9 14900.2 14900.9 13700.6 13770.4 13823.2 13843.4 14140.8 12637.2 12809.9 12836.1 12906.9 13303.7 4537.0 4583.3 4613.7 4619.6 4787.5 105556.9 107530.0 108622.8 108634.0 109118.5 10950.3 11042.0 11097.6 11115.8 11550.7 21735.2 21742.9 21773.0 21775.6 21784.6 7897.0 7916.9 8007.6 8075.3 8174.6 3827.8 4418.8 4485.1 4496.6 4514.4 3373.5 3466.3 3472.7 3499.3 3503.6 565.0 635.1 652.5 657.0 671.2 522.6 559.2 630.1 641.8 641.9 7624.8 8873.8 8955.6 9029.3 9064.7 591.2 613.2 621.7 633.3 634.3 4263.1 4379.7 4380.0 4473.5 4535.8 127839.8 130791.3 132007.0 140251.0 148364.3 124142.1 124667.0 127093.0 127817.0 128187.0 91875.5 102591.5 109778.0 110262.8 112938.0 9874.3 9957.0 9966.3 9992.7 10023.5 53677.5 54497.0 55373.4 59103.2 59223.1 88823.1 95739.1 96637.0 96972.1 99484.5 12974.7 13095.7 13123.2 13134.0 13671.7 14947.3 15013.8 15411.2 15633.9 15642.4 13733.7 14108.5 14125.8 14395.1 14564.3 13081.1 13145.1 14044.4 14387.8 14516.9 13562.5 13670.2 13780.7 14002.4 14369.1 11672.9 11677.4 11864.5 11930.2 12057.5 11391.3 11490.5 11529.8 11703.2 11704.4 23:53:14 INFO - PROCESS | 1666 | -------- Summary: end -------- 23:53:14 INFO - PROCESS | 1666 | 23:53:15 INFO - TEST-INFO | 1666: exit 0 23:53:15 INFO - TEST-OK | dromaeo_css | took 1116148ms 23:53:15 INFO - TEST-START | kraken 23:53:15 INFO - operating with platform_type : mac_ 23:53:15 INFO - Initialising browser for kraken test... 23:53:15 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpbvX2U1/profile http://localhost:49274/getInfo.html) 23:53:17 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 23:53:17 INFO - PROCESS | 1672 | colorDepth:24 23:53:17 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 23:53:17 INFO - PROCESS | 1672 | __metrics 23:53:17 INFO - PROCESS | 1672 | 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" 23:53:17 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:53:18 INFO - TEST-INFO | 1672: exit 0 23:53:18 INFO - Browser initialized. 23:53:18 INFO - Running cycle 1/1 for kraken test... 23:53:18 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpbvX2U1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:53:30 INFO - PROCESS | 1673 | RSS: Main: 243752960 23:53:30 INFO - PROCESS | 1673 | 23:53:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 23:53:34 INFO - PROCESS | 1673 | RSS: Main: 294338560 23:53:34 INFO - PROCESS | 1673 | 23:53:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 23:53:37 INFO - PROCESS | 1673 | RSS: Main: 305258496 23:53:37 INFO - PROCESS | 1673 | 23:53:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 23:53:41 INFO - PROCESS | 1673 | RSS: Main: 390385664 23:53:41 INFO - PROCESS | 1673 | 23:53:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 23:53:44 INFO - PROCESS | 1673 | RSS: Main: 291848192 23:53:44 INFO - PROCESS | 1673 | 23:53:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:53:46 INFO - PROCESS | 1673 | RSS: Main: 295735296 23:53:46 INFO - PROCESS | 1673 | 23:53:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 23:53:50 INFO - PROCESS | 1673 | RSS: Main: 333402112 23:53:50 INFO - PROCESS | 1673 | 23:53:53 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 23:53:54 INFO - PROCESS | 1673 | RSS: Main: 337260544 23:53:54 INFO - PROCESS | 1673 | 23:53:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 23:53:57 INFO - PROCESS | 1673 | RSS: Main: 333934592 23:53:57 INFO - PROCESS | 1673 | 23:53:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:53:59 INFO - PROCESS | 1673 | RSS: Main: 314187776 23:53:59 INFO - PROCESS | 1673 | 23:54:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:54:02 INFO - PROCESS | 1673 | RSS: Main: 321200128 23:54:02 INFO - PROCESS | 1673 | 23:54:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:54:05 INFO - PROCESS | 1673 | RSS: Main: 325636096 23:54:05 INFO - PROCESS | 1673 | 23:54:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:54:08 INFO - PROCESS | 1673 | RSS: Main: 325042176 23:54:08 INFO - PROCESS | 1673 | 23:54:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:54:12 INFO - PROCESS | 1673 | RSS: Main: 322265088 23:54:12 INFO - PROCESS | 1673 | 23:54:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 23:54:14 INFO - PROCESS | 1673 | RSS: Main: 365543424 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | __start_tp_report 23:54:14 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 23:54:14 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 23:54:14 INFO - PROCESS | 1673 | |i|pagename|runs| 23:54:14 INFO - PROCESS | 1673 | |0;ai-astar;103;113;113;113;114;113;112;113;113;111 23:54:14 INFO - PROCESS | 1673 | |1;audio-beat-detection;140;110;107;156;108;109;126;129;181;107 23:54:14 INFO - PROCESS | 1673 | |2;audio-dft;181;181;184;185;182;184;180;183;182;184 23:54:14 INFO - PROCESS | 1673 | |3;audio-fft;83;74;57;109;58;75;86;94;83;57 23:54:14 INFO - PROCESS | 1673 | |4;audio-oscillator;81;86;94;92;102;98;103;69;68;97 23:54:14 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;118;132;87;122;137;131;91;86;89;86 23:54:14 INFO - PROCESS | 1673 | |6;imaging-darkroom;128;95;94;95;94;93;95;95;94;93 23:54:14 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;84;87;85;84;84;88;84;105;118 23:54:14 INFO - PROCESS | 1673 | |8;json-parse-financial;59;57;57;58;56;57;57;54;54;56 23:54:14 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;72;69;71;77;76;72;71;70;70 23:54:14 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;82;83;85;84;86;82;84;82;87;107 23:54:14 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;137;137;136;135;134;134;136;151;148;137 23:54:14 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;160;206;224;159;163;162;161;160;163;161 23:54:14 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;65;67;67;66;66;68;66;66;66;65 23:54:14 INFO - PROCESS | 1673 | __end_tp_report 23:54:14 INFO - PROCESS | 1673 | __start_cc_report 23:54:14 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2030 23:54:14 INFO - PROCESS | 1673 | __end_cc_report 23:54:14 INFO - PROCESS | 1673 | __startTimestamp1469256854049__endTimestamp 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | ------- Summary: start ------- 23:54:14 INFO - PROCESS | 1673 | Number of tests: 14 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 23:54:14 INFO - PROCESS | 1673 | Values: 103.0 113.0 113.0 113.0 114.0 113.0 112.0 113.0 113.0 111.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:127.50 stddev:25.18 (19.7%) stddev-sans-first:26.28 23:54:14 INFO - PROCESS | 1673 | Values: 140.0 110.0 107.0 156.0 108.0 109.0 126.0 129.0 181.0 107.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.50 stddev:1.65 (0.9%) stddev-sans-first:1.64 23:54:14 INFO - PROCESS | 1673 | Values: 181.0 181.0 184.0 185.0 182.0 184.0 180.0 183.0 182.0 184.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:77.60 Median:79.00 stddev:17.12 (21.7%) stddev-sans-first:18.04 23:54:14 INFO - PROCESS | 1673 | Values: 83.0 74.0 57.0 109.0 58.0 75.0 86.0 94.0 83.0 57.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:89.00 stddev:12.73 (14.3%) stddev-sans-first:13.17 23:54:14 INFO - PROCESS | 1673 | Values: 81.0 86.0 94.0 92.0 102.0 98.0 103.0 69.0 68.0 97.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:107.90 Median:86.00 stddev:21.86 (25.4%) stddev-sans-first:22.88 23:54:14 INFO - PROCESS | 1673 | Values: 118.0 132.0 87.0 122.0 137.0 131.0 91.0 86.0 89.0 86.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:97.60 Median:94.50 stddev:10.71 (11.3%) stddev-sans-first:0.83 23:54:14 INFO - PROCESS | 1673 | Values: 128.0 95.0 94.0 95.0 94.0 93.0 95.0 95.0 94.0 93.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:84.50 stddev:11.57 (13.7%) stddev-sans-first:12.16 23:54:14 INFO - PROCESS | 1673 | Values: 86.0 84.0 87.0 85.0 84.0 84.0 88.0 84.0 105.0 118.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:1.58 (2.8%) stddev-sans-first:1.39 23:54:14 INFO - PROCESS | 1673 | Values: 59.0 57.0 57.0 58.0 56.0 57.0 57.0 54.0 54.0 56.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:3.40 (4.8%) stddev-sans-first:2.74 23:54:14 INFO - PROCESS | 1673 | Values: 65.0 72.0 69.0 71.0 77.0 76.0 72.0 71.0 70.0 70.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:84.00 stddev:7.51 (8.9%) stddev-sans-first:7.81 23:54:14 INFO - PROCESS | 1673 | Values: 82.0 83.0 85.0 84.0 86.0 82.0 84.0 82.0 87.0 107.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:137.00 stddev:5.95 (4.3%) stddev-sans-first:6.28 23:54:14 INFO - PROCESS | 1673 | Values: 137.0 137.0 136.0 135.0 134.0 134.0 136.0 151.0 148.0 137.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:162.50 stddev:23.14 (14.2%) stddev-sans-first:24.14 23:54:14 INFO - PROCESS | 1673 | Values: 160.0 206.0 224.0 159.0 163.0 162.0 161.0 160.0 163.0 161.0 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.87 23:54:14 INFO - PROCESS | 1673 | Values: 65.0 67.0 67.0 66.0 66.0 68.0 66.0 66.0 66.0 65.0 23:54:14 INFO - PROCESS | 1673 | -------- Summary: end -------- 23:54:14 INFO - PROCESS | 1673 | 23:54:14 INFO - TEST-INFO | 1673: exit 0 23:54:14 INFO - TEST-OK | kraken | took 59322ms 23:54:14 INFO - SUITE-END | took 1175s 23:54:15 INFO - Completed test suite (00:19:35) 23:54:15 INFO - javascript benchmark 23:54:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.8019518828864, 3324.684155005275, 3342.4317865962785, 3344.347852168827, 3386.3565922474727, 5608.10270356743, 5658.886822263557, 5697.544196464278, 5724.542036637069, 5749.281339832521, 5620.550355971523, 5655.0, 5731.254936858202, 5746.051901436268, 5782.017057100293, 5049.141645920203, 5063.291139240491, 5118.6416950813455, 5125.103106956286, 5242.135047717122, 5208.2968799212185, 5233.267342572043, 5258.0, 5263.4210236874105, 5299.0, 5071.213961835884, 5083.491650834942, 5128.692278463304, 5136.768845401966, 5160.174372100446, 5097.209932460438, 5111.7699703513435, 5113.821016264413, 5161.870953226162, 5219.895602087975, 5111.923321150186, 5119.974400128012, 5135.0, 5173.844784656429, 5192.350956130484, 5593.049181639131, 5640.407757185519, 5711.628744131618, 5718.542516598662, 5799.9130013049835, 6167.290761562433, 6169.290531588835, 6227.688615569204, 6235.220597425322, 6238.594491358047, 6259.718312675895, 6326.240851097852, 6331.810045698637, 6334.841628959267, 6335.619862808247, 5631.521320687705, 5682.977064128497, 5720.313562372542, 5802.709864506758, 5822.446867547618, 6296.212973378236, 6351.809445716636, 6354.52341074412, 6359.236891573041, 6401.295857455676, 6088.6042407243385, 6089.725962331706, 6107.633541987495, 6114.529105537134, 6115.296740874767, 6181.907271390933, 6197.0, 6254.21822272216, 6275.560710750295, 6287.7799277026, 6009.519238460913, 6086.573939824259, 6107.450329470369, 6155.138280640714, 6162.537809664203, 6245.156903818017, 6251.093591429218, 6254.437100660963, 6255.873942690359, 6264.749410023639, 4836.419629644395, 4879.5120487951635, 4883.804647814119, 4888.804447822118, 4903.436104847917, 4334.111507141029, 4377.124575084996, 4431.955680443219, 4460.286354183315, 4462.955370446319, 5667.773289068474, 5703.515201207861, 5708.972384970745, 5740.368559458456, 5801.448862358069, 6283.96858015707, 6298.370162983665, 6311.147995020613, 6311.684415779193, 6321.0, 5513.9448605513435, 5733.226014488074, 5748.367679555246, 5769.711514424262, 5804.100364443518, 5313.362396512366, 5319.0957537218765, 5366.597505187126, 5389.245505629215, 5390.865228369322, 6284.37156284377, 6308.27454842699, 6315.8105256841445, 6329.30377658448, 6340.809775706736, 6026.0659597762415, 6302.369763023661, 6306.65313407767, 6306.873862522815, 6316.431521163117, 6276.780312689034, 6334.493240540838, 6338.334474880256, 6342.112104305494, 6353.555251132377, 6002.7298771554515, 6027.035674292092, 6039.305479869871, 6053.576249662481, 6053.788117415869, 6132.01899668756, 6180.381961803783, 6195.845103872259, 6198.0, 6204.162438070803, 5940.732667029907, 5967.791127310524, 5971.343152253162, 5974.820755377172, 5977.880442391041, 5834.212381328466, 5939.109133629817, 5948.524118070631, 5972.134040564007, 5981.07293369511, 5856.3558008618165, 5942.940570594239, 5946.613470124428, 5973.731182096729, 5981.0729336952845, 6076.72654730547, 6088.802071683797, 6103.671041328402, 6108.873974645787, 6112.41229444207, 5709.314882214161, 5779.7110144493445, 5790.826275211751, 5932.911006334994, 5968.373320801254], "value": 5678.144445346543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.1870410216217, 530.3688610553442, 535.3950036458799, 539.948704873037, 540.732337492941, 27181.45637087257, 37573.0, 41864.790676046614, 43028.78485607571, 43126.92182695434, 36616.084597885, 43870.561294387044, 44162.55837441624, 44415.555844441544, 44430.0, 8725.258353039999, 9073.36486445949, 9115.814944057263, 9857.359271647356, 9876.308658393915, 8722.520261385622, 11913.0, 12140.089493287995, 12149.939250303736, 12340.197887137307, 12094.758104837898, 12150.635480935585, 12153.453094610719, 12154.93922530386, 12180.69548261296, 8706.998695150076, 11791.29252244868, 11982.520699172022, 12000.69998250042, 12066.577669781516, 11868.169228154033, 11968.461419236155, 11971.0, 11977.401129943468, 11992.28046317215, 41929.161416771494, 42171.15657686829, 42184.57815421868, 42316.57683423157, 42320.0, 3441.0709108540555, 3489.6859282664427, 3493.8602455901732, 3615.0, 3818.293615681108, 26009.739902600923, 33223.50823688209, 33302.16744581405, 33358.166045848804, 33439.498407523904, 3296.604407471095, 3317.419451595961, 3324.7007769300635, 3365.360581489509, 3449.482227819751, 484566.0, 485930.0, 487475.0, 490842.54578727233, 494417.0, 34114.48828267578, 34272.314553708784, 60909.0, 61162.388376116556, 61217.38782612206, 30912.0, 36278.45582316267, 36513.45229821555, 36517.26965460676, 36615.26769464596, 14911.508849115002, 14943.252837358088, 15106.773398399033, 15109.773353399707, 15114.395424183129, 11450.54197832094, 11639.534418623329, 11650.242734222249, 11850.703732406586, 12627.621171364874, 7596.734114305973, 7819.765407037798, 7831.216878312123, 7840.176781437981, 8048.356131509465, 3265.510173473998, 3281.2945216778508, 3285.720713739311, 3292.489664102068, 3300.85146168423, 966.9419834809934, 973.1922504321396, 984.8473486609587, 1009.9444530550743, 1115.2973626615342, 29022.570573373298, 30576.69423305783, 30884.58608620306, 30907.69092309093, 31139.0, 990.3166814897697, 1092.0990183098816, 1111.0833562310963, 1145.169751929861, 1196.4376742930808, 15742.189648190604, 16231.0, 16233.59416014609, 16476.6704665906, 16701.91649041747, 708402.0, 713546.0, 714206.0, 715021.0, 717677.0, 45669.543304567196, 60101.699491502266, 62271.6886415565, 62338.68830655818, 62519.6874015627, 1218.5065048655308, 1259.7669431155266, 1299.382793173235, 1380.9516666916609, 1421.7014426970452, 30497.847510763193, 31808.522872156474, 31915.28720058821, 31926.52110218395, 32790.50814237836, 53717.92564148773, 54342.184867227814, 54352.0, 54427.0, 54460.45539544554, 561949.0, 576072.0, 577810.3313801185, 578184.0, 578858.0, 20705.171793128105, 24323.148689795773, 24820.0, 27735.30661733473, 28114.578281325386, 49874.750626246634, 50148.74628134326, 50443.0, 52504.474955249956, 53838.46161538334, 38651.61348386593, 40858.0, 41355.1728965425, 41736.79131604444, 42628.573714262464, 36755.0, 40129.39805902856, 40608.1878362437, 41122.794386027876, 41919.580804191566, 38971.0, 39373.0, 39579.80210098931, 39615.603843962344, 39927.0, 28878.422431551673, 30783.9225627102, 35633.46549801703, 35943.64056359403, 36083.63916360803, 22167.33497995063, 22953.540929181658, 25133.49199048063, 25962.0, 27720.861395692893], "value": 19351.286031602274, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.6403085053774, 383.38466760848837, 384.78067501524123, 393.5316972802363, 403.72143221177384, 19523.804761952375, 21213.575728485423, 21617.783822161775, 21914.671279930815, 21975.0, 19886.70169947452, 20257.0, 20579.794202057976, 20869.58260834786, 20927.162913483444, 5041.949580504203, 5629.521490673298, 5891.941080589182, 5901.911471327933, 5906.409359064102, 5810.738516766734, 5819.709014549256, 5930.940690593082, 5936.436038576328, 5939.227900372945, 5713.542916566665, 6068.15045893573, 6069.696515174246, 6113.388661133873, 6116.81649550512, 5855.09246066856, 6051.2133422655, 6100.084987251903, 6102.176206212149, 6111.327753947085, 5830.912536311958, 5858.0, 5861.062230043215, 5863.5016023638045, 5864.912026319608, 12696.936515317457, 13055.0, 13070.673233169151, 13150.408231629599, 13421.798673019912, 2188.3763127508582, 2194.297824696098, 2310.145246258895, 2321.6285394336824, 2737.20621019906, 9510.762230944281, 9940.5029748513, 9970.451625160686, 10344.431056291902, 10485.161187105014, 1849.5191250275025, 1902.8477721782224, 1938.1665883670016, 1957.5595491014408, 2036.2465887621527, 112987.43506282498, 118466.40766796198, 118837.0, 119497.0, 120071.39964300209, 9235.861462078074, 11287.43562821872, 11495.712607184887, 11592.6522204334, 11663.0, 14281.00032997701, 14301.78547321791, 14335.498257560936, 14345.0, 14353.569392918229, 7751.534907905544, 7869.134395216522, 8298.54358010303, 8497.0, 8591.05498395176, 8371.874421883676, 8375.45559538628, 8379.664813407517, 8500.65997360111, 8526.701565445304, 4390.912181756347, 4481.148581769454, 4499.820007199741, 4558.817647294071, 4563.338315944236, 1856.6379555986452, 1907.551725344543, 1909.5703466719876, 1915.7796853361763, 1924.6150769846088, 863.9870401943975, 873.9257163141077, 879.4547380624055, 916.4318122763929, 954.0936110694868, 20622.79377206239, 21072.840993745493, 21098.894505527373, 21134.048967796487, 21208.0, 897.7935074932802, 929.8047410043831, 931.1340453378288, 936.442816524167, 1014.3558840136419, 8711.649694297394, 10772.94613526943, 11057.723556911142, 11098.0, 11123.833142502868, 587885.0, 589993.0, 603537.0, 612372.9381353065, 614002.0, 122356.0, 122487.0, 124248.1362779559, 124464.0, 126738.0, 923.5428462910902, 929.6705710833573, 947.0245646983618, 949.6343907595625, 975.8243516167016, 15189.6202594936, 18278.543036423664, 18516.537086572942, 18592.535186620444, 18965.33621323247, 42996.57003430051, 54432.45567544274, 56161.43838561726, 57735.71132144312, 57844.0, 69121.61756764937, 70202.64898675474, 70426.0, 70486.64756676389, 71477.0, 7940.6029698513685, 8063.27430531255, 8066.072401673882, 8079.555624440713, 8260.545669988209, 11345.716357091138, 12413.44139513706, 12544.435500402322, 12586.748265034465, 12723.55467558631, 10698.518566664363, 10757.731056723644, 10760.40817755031, 10806.18953578469, 10890.618828341287, 10787.568497260021, 10891.237613366988, 10913.618023369145, 10941.96051375118, 11062.280951738112, 11142.83285750698, 11146.219764616724, 11210.55157793679, 11293.887061129284, 11372.374519401512, 7925.128235893932, 8050.476719013245, 8074.515529068275, 8116.634751436081, 8246.0, 9215.723528294162, 10821.080208182078, 10889.292196007234, 10987.890121098686, 11031.779364412507], "value": 9325.88552241781, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.4261010375642, 1508.3966413434628, 1555.7588573771066, 1723.870709696773, 1886.2549293029253, 7721.03487064117, 8498.532580708059, 8726.825463490733, 8727.607257673404, 8882.378233523656, 8520.91479085209, 8602.2688071514, 8665.39342246043, 8678.088800675932, 8678.91321086789, 2025.8379329653592, 2087.832973362127, 2170.576737536181, 2345.9296221113395, 2383.916562920298, 2162.5999190149796, 2190.200576789476, 2191.7150770399826, 2197.6703494475796, 2331.463763334434, 2364.858108513486, 2364.917227897024, 2370.5969985102574, 2374.394529395002, 2376.5009348036933, 2265.8187345012443, 2292.071710957065, 2295.5753185660706, 2295.678604995302, 2353.2469609724894, 2088.0603728322317, 2271.1710225767642, 2342.5783358995373, 2351.4356554426986, 2353.176388264111, 8613.698520551814, 8695.956520217422, 8698.869516957251, 8722.30221582272, 8726.86909696355, 4856.490068542823, 4930.975345123287, 4932.8273510427325, 4933.98853235086, 4935.5804756595735, 3317.668233176687, 3384.4923261510726, 3455.706264967481, 3457.4122399192197, 3458.2219000724886, 1045.4354648489807, 1061.9150467962545, 1077.6713102503734, 1081.6592773276393, 1102.167863263231, 9849.950750246275, 9908.207343412581, 9914.90085099147, 9915.851262231072, 9926.305158638897, 4242.278812601865, 4347.586979236968, 4493.887652808706, 4503.729776213438, 4516.254817955, 8539.573021349031, 8586.184312490428, 8599.78500537479, 8606.78483037929, 8610.61252243638, 4924.162892308316, 4933.975330123327, 4934.3338649282605, 4937.851864444073, 4941.0, 4468.352088947085, 4553.33976573395, 4557.065801510682, 4561.270196768501, 4573.291139873325, 3646.726495512847, 3777.99883030997, 3782.2813665403487, 3789.829457674411, 3797.5632802227547, 1247.6381849263794, 1309.6987692830512, 1316.8880645145077, 1322.4247452358254, 1326.3500884566492, 806.2260230179094, 902.2376092202079, 903.8418276801533, 929.9256059515222, 951.3673407184145, 5841.153032810304, 5848.239728835224, 5874.50066744323, 5890.646561206341, 6012.6392416455155, 946.3612061858219, 950.4154944708954, 952.7475219066953, 974.878140232471, 987.9407235565889, 3848.6728628066367, 3967.0479085019388, 3981.1838573092446, 4036.9798151009645, 4038.656714179269, 10165.0, 10576.995185457523, 10637.202209834139, 10663.306885052447, 10675.83986240207, 10594.046535811814, 10598.31110977784, 10613.044825965546, 10618.840717389245, 10665.466726663635, 7740.26467485588, 8004.679812807539, 8075.273225409624, 8181.304589109874, 8201.95899020513, 5982.670953097448, 6012.819615411545, 6055.424734650202, 6079.0, 6108.236470441195, 9944.602215911282, 10084.344517606332, 10092.990700929848, 10094.54574544162, 10116.29185956976, 9688.951555242178, 9966.003399659976, 10026.69919902404, 10206.489675516046, 10219.897801021894, 7504.549727016397, 7569.810754731176, 7639.770806875803, 7652.311291983749, 7669.61651917413, 7911.248431399008, 8219.753407397788, 8286.337093032664, 8287.12985136552, 8301.750947471586, 7378.5941773202985, 7414.814629634302, 7422.888656670262, 7428.9628551856895, 7597.848043039219, 7272.709091636275, 7297.708091676273, 7333.230010848784, 7471.028766260351, 7516.285952834472, 6987.825304367431, 7023.981522679285, 7032.015517827406, 7045.225025247116, 7045.647717614202, 4879.634027448026, 4979.850604481871, 5006.123928312603, 5070.746462676925, 5079.0, 4332.761698106634, 5026.798928042837, 5088.465711100429, 5099.490050995019, 5158.252053452304], "value": 4492.4362459278655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.85454127477544, 298.5596245537831, 309.9008317338451, 313.36856234687093, 315.4416682472025, 5374.892502149954, 5391.245225668405, 5405.945940540593, 5414.103454651758, 5418.83743487696, 4142.627163555279, 5093.236014597812, 5222.0, 5232.659877107985, 5321.4412486688925, 4026.0, 4053.331200351946, 4053.9797301013455, 4072.5316588592245, 4087.2847251730977, 4054.0067683417606, 4072.6131017553284, 4076.449679293302, 4091.034000349752, 4092.0997380576373, 3342.713055473648, 3487.581490221177, 3585.569731632208, 3812.390017597185, 4024.315866302721, 3346.9832650836834, 3712.3688972874675, 3952.932708168807, 4011.5787842276577, 4036.3340048891896, 2927.399883023975, 3216.0512648768668, 3418.384690755663, 3533.4346504559403, 3538.380783362901, 4275.828966841323, 4438.533953934822, 4886.900447399342, 5091.690401239224, 5242.973785131088, 4145.585441455861, 4419.337099435078, 4609.976950115261, 4613.8154473821005, 4664.976675116603, 4526.125910047111, 4585.885352866172, 4837.443693975203, 5585.413531579207, 5603.973854118771, 3263.298390845956, 3429.8971030869116, 3919.608039196086, 3919.9608003920166, 3931.528216613996, 5546.611737178399, 5600.647727954269, 5675.631083979528, 5889.204957330749, 5915.231019967419, 3404.14896275931, 3921.490206273166, 3954.9209015819524, 3970.3250447424007, 3979.900502487461, 3333.516640087222, 4021.4973128358956, 4022.8189731462153, 4023.0229630073536, 4025.5370632377067, 3873.670737987303, 4448.510663826976, 4628.560286772751, 4640.883977900512, 4642.953570464252, 4461.598456138898, 4483.910321793546, 5603.3836278009385, 5607.551395888319, 5647.350554686263, 1268.0616343905629, 1290.329028904983, 1296.6693493159185, 1328.926909019994, 1367.699106196635, 790.0006491787893, 926.1895841139003, 927.7309580221795, 933.2487347685178, 934.5327336331834, 4909.926351104737, 5226.869328266747, 5238.790448382022, 5270.868228294324, 5279.392869819943, 5006.923511445057, 5201.956855239727, 5422.3764267109, 5700.743466544015, 5776.306843178845, 4283.258714422772, 4608.88477788058, 5079.212722028092, 5255.658382205144, 5269.604779641542, 2623.776978956772, 2679.196241127654, 2944.514155164417, 2992.685767994373, 3003.543281508467, 4889.657723959289, 5124.948750512447, 5277.0, 5310.707911064928, 5967.313758917693, 4748.507033807279, 5755.366909639936, 5841.561882858718, 5855.477575830315, 5902.3507414184405, 5587.217789509472, 5889.7042650616795, 5894.823155305262, 5902.439268269593, 5914.526837852875, 4740.644451666208, 5370.838874833761, 5542.0301447247375, 5704.657720536781, 5712.7714891403875, 4679.017406344706, 4921.818763496806, 5108.821191258288, 5618.4662457066515, 5629.915551266816, 5237.16205407133, 5658.038133517394, 5826.796062137805, 5861.32594751609, 5875.0, 3275.5086736989642, 3871.8838434845875, 3921.3333733265986, 3944.349182384989, 3962.722609417313, 4629.7916593754035, 5399.379071406838, 5547.722613869371, 5647.322321321468, 5651.2935883014625, 4838.814490449862, 5512.448755124616, 5521.309836270466, 5525.347833040083, 5582.190582365453, 4457.398251236042, 4573.954260457353, 5457.317835270591, 5541.3904470509015, 5545.500904918576, 4306.633936115465, 5089.821856235162, 5315.6279060465395, 5409.59195706718, 5525.475079867406, 4884.902301954012, 4932.8766780830765, 4976.8755781105765, 5567.886422715392, 5620.550355971431, 5287.629865909504, 5496.230527726041, 5496.780128794803, 5508.394076651485, 5515.111846148536], "value": 4012.765060826671, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.4327508168221, 733.8018734941566, 738.2064280898032, 814.3322475570028, 857.3826844671838, 14884.851151488481, 15721.37114515421, 16048.438304659354, 16084.0, 16287.104209268486, 12130.69673258169, 14637.560873173796, 14798.26008699566, 14824.407023719039, 14912.925435372836, 686.097781417437, 710.183289217399, 844.2570537926616, 846.3652260804397, 879.1955360844819, 863.9654413823439, 870.0125357718971, 870.3428911172056, 874.2481465939287, 875.4484674654958, 918.8897332320131, 919.8712180294764, 920.2683866326253, 920.7974245665977, 922.2069020642242, 706.7773651299875, 749.7563291930145, 753.8567672142275, 755.0938873352, 916.3860213656866, 643.8519140597642, 680.5610381304081, 682.2597481732327, 684.849333146712, 689.348565605503, 14837.406503739838, 14884.03253788511, 14888.851111488855, 14900.180490073042, 14900.925495372561, 13700.588982330546, 13770.380332884944, 13823.170609763447, 13843.377048032862, 14140.787888181685, 12637.2417654941, 12809.871901280962, 12836.101472896808, 12906.935465322613, 13303.73392532144, 4536.954630453686, 4583.289590113504, 4613.723176609381, 4619.630429565626, 4787.497312782178, 105556.9313060279, 107530.0, 108622.82754344947, 108634.0, 109118.45440772745, 10950.3429794211, 11042.0, 11097.556097756, 11115.777684446266, 11550.711232219262, 21735.239266625907, 21742.89128554379, 21773.0, 21775.564488710137, 21784.564308714052, 7897.012873390826, 7916.881246781303, 8007.639656215484, 8075.313598344089, 8174.632141553644, 3827.78947157909, 4418.8453404130705, 4485.058137791035, 4496.572825581565, 4514.435695538058, 3373.527706121145, 3466.30673865228, 3472.6700963408434, 3499.3176330615793, 3503.6146023937345, 564.9576281778883, 635.1383587786248, 652.4682383857141, 657.0, 671.1979184874028, 522.5558275465823, 559.1558038485413, 630.1146888621494, 641.7978336823885, 641.9422251997249, 7624.771256862303, 8873.822523549492, 8955.641774328953, 9029.277657787361, 9064.682736104205, 591.1576004194093, 613.2303958532013, 621.6891554222889, 633.2875515045574, 634.30226750574, 4263.104748002892, 4379.671524635665, 4380.0, 4473.541187791983, 4535.841245556389, 127839.77281817433, 130791.34604327107, 132007.0, 140251.0, 148364.2581787084, 124142.13786793205, 124667.0, 127093.0, 127817.0, 128187.0, 91875.54062229647, 102591.54788946916, 109778.0, 110262.76846778438, 112938.0, 9874.290045427246, 9957.0, 9966.302358834811, 9992.650257241252, 10023.54894029785, 53677.463225367246, 54497.0, 55373.446265536826, 59103.226903192975, 59223.11165332437, 88823.11176888148, 95739.08521829663, 96637.0, 96972.06055878702, 99484.50257748665, 12974.675633109247, 13095.738085238432, 13123.212607243597, 13134.0, 13671.658208544866, 14947.25263736787, 15013.849861501245, 15411.229438528253, 15633.921830390775, 15642.374305027672, 13733.656658583215, 14108.506202282872, 14125.788113178105, 14395.136291822524, 14564.271786410849, 13081.149725267827, 13145.145565538593, 14044.368003439666, 14387.78418323705, 14516.854831451972, 13562.457501699822, 13670.248136352198, 13780.655483612589, 14002.369893355028, 14369.06601070927, 11672.88327116718, 11677.416129193676, 11864.466099025392, 11930.224535405518, 12057.51769929228, 11391.259568128045, 11490.54037838477, 11529.769404612029, 11703.23928944616, 11704.356260405759], "value": 7830.573277644548, "unit": "score"}], "name": "dromaeo_css", "value": 7245.393224799646, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 107.0, 156.0, 108.0, 109.0, 126.0, 129.0, 181.0, 107.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 181.0, 184.0, 185.0, 182.0, 184.0, 180.0, 183.0, 182.0, 184.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 74.0, 57.0, 109.0, 58.0, 75.0, 86.0, 94.0, 83.0, 57.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 94.0, 92.0, 102.0, 98.0, 103.0, 69.0, 68.0, 97.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 132.0, 87.0, 122.0, 137.0, 131.0, 91.0, 86.0, 89.0, 86.0], "value": 107.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 95.0, 94.0, 95.0, 94.0, 93.0, 95.0, 95.0, 94.0, 93.0], "value": 97.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 84.0, 84.0, 88.0, 84.0, 105.0, 118.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 57.0, 58.0, 56.0, 57.0, 57.0, 54.0, 54.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 69.0, 71.0, 77.0, 76.0, 72.0, 71.0, 70.0, 70.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 85.0, 84.0, 86.0, 82.0, 84.0, 82.0, 87.0, 107.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 136.0, 135.0, 134.0, 134.0, 136.0, 151.0, 148.0, 137.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 206.0, 224.0, 159.0, 163.0, 162.0, 161.0, 160.0, 163.0, 161.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 66.0, 66.0, 68.0, 66.0, 66.0, 66.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1474.9, "alertThreshold": 2.0}]} 23:54:15 INFO - Return code: 0 23:54:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:54:15 INFO - # TBPL SUCCESS # 23:54:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:54:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:54:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:54:15 INFO - 2016-07-22 23:54:15.377 screenresolution[1677:10489] starting screenresolution argv=screenresolution get 23:54:15 INFO - 2016-07-22 23:54:15.379 screenresolution[1677:10489] Display 0: 1600x1200x32@60 23:54:15 INFO - 2016-07-22 23:54:15.385 screenresolution[1678:10493] starting screenresolution argv=screenresolution list 23:54:15 INFO - Available Modes on Display 0 23:54:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:54:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:54:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:54:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:54:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:54:15 INFO - Intel Iris: 23:54:15 INFO - Chipset Model: Intel Iris 23:54:15 INFO - Type: GPU 23:54:15 INFO - Bus: Built-In 23:54:15 INFO - VRAM (Dynamic, Max): 1536 MB 23:54:15 INFO - Vendor: Intel (0x8086) 23:54:15 INFO - Device ID: 0x0a2e 23:54:15 INFO - Revision ID: 0x0009 23:54:15 INFO - Displays: 23:54:15 INFO - Display: 23:54:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:54:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:54:15 INFO - Display Serial Number: 1600x1200 60 23:54:15 INFO - Main Display: Yes 23:54:15 INFO - Mirror: Off 23:54:15 INFO - Online: Yes 23:54:15 INFO - Rotation: Supported 23:54:15 INFO - Return code: 0 23:54:15 INFO - Running post-action listener: _resource_record_post_action 23:54:15 INFO - ##### 23:54:15 INFO - ##### Finished run-tests step (success) 23:54:15 INFO - ##### 23:54:15 INFO - Running post-run listener: _resource_record_post_run 23:54:15 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31858176; Write bytes: 704295424; Read time: 213; Write time: 1915 23:54:15 INFO - TinderboxPrint: CPU usage
53.5% 23:54:15 INFO - TinderboxPrint: I/O read bytes / time
31,858,176 / 213 23:54:15 INFO - TinderboxPrint: I/O write bytes / time
704,295,424 / 1,915 23:54:15 INFO - TinderboxPrint: CPU idle
2,253.0 (46.5%) 23:54:15 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 23:54:15 INFO - TinderboxPrint: CPU user
1,622.5 (33.5%) 23:54:15 INFO - TinderboxPrint: Swap in / out
435,068,928 / 0 23:54:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396268032; Write bytes: 389969920; Read time: 29342; Write time: 797 23:54:15 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30096896; Write bytes: 294472192; Read time: 198; Write time: 1070 23:54:16 INFO - Running post-run listener: _upload_blobber_files 23:54:16 INFO - Blob upload gear active. 23:54:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:54:16 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 23:54:16 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'] 23:54:16 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 23:54:16 INFO - (blobuploader) - INFO - Open directory for files ... 23:54:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:54:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:54:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:54:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:54:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:54:17 INFO - (blobuploader) - INFO - Done attempting. 23:54:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:54:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:54:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:54:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:54:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:54:18 INFO - (blobuploader) - INFO - Done attempting. 23:54:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:54:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:54:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:54:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:54:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:54:22 INFO - (blobuploader) - INFO - Done attempting. 23:54:22 INFO - (blobuploader) - INFO - Iteration through files over. 23:54:22 INFO - Return code: 0 23:54:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:54:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:54:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8ce4ff427c634b791bd01b9ec5fa8e300c57387773436ec44e18086c0b4c9dabd8d9304eaea6cd18d908e6161ef2e0e8d509844c14366bb535eac2a64a04b16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/589926a04e77d9d8d8f4eb2e48167843cf0c6b9afde0ec7c1cc4a82cee18f547578e91174824bc5a538fbb792f71c518fa8ca0cc1bfeabcc904c6b6b7f480175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b473b6c04d3ce6514dcbcc9b2e89ab16ff3177d2cd59f8d2b7567638a520a8dd058dda4994deca7893537e09d916e30e6117b92aa138aba1b81806b3872f35f8"} 23:54:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:54:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:54:22 INFO - Contents: 23:54:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8ce4ff427c634b791bd01b9ec5fa8e300c57387773436ec44e18086c0b4c9dabd8d9304eaea6cd18d908e6161ef2e0e8d509844c14366bb535eac2a64a04b16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/589926a04e77d9d8d8f4eb2e48167843cf0c6b9afde0ec7c1cc4a82cee18f547578e91174824bc5a538fbb792f71c518fa8ca0cc1bfeabcc904c6b6b7f480175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b473b6c04d3ce6514dcbcc9b2e89ab16ff3177d2cd59f8d2b7567638a520a8dd058dda4994deca7893537e09d916e30e6117b92aa138aba1b81806b3872f35f8"} 23:54:22 INFO - Running post-run listener: copy_logs_to_upload_dir 23:54:22 INFO - Copying logs to upload dir... 23:54:22 INFO - mkdir: /builds/slave/test/build/upload/logs 23:54:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.060759 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-22 23:54:22.693320) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 23:54:22.697301) ========= 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.2VArespZk0/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.OfSHMmu3fX/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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/d8ce4ff427c634b791bd01b9ec5fa8e300c57387773436ec44e18086c0b4c9dabd8d9304eaea6cd18d908e6161ef2e0e8d509844c14366bb535eac2a64a04b16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/589926a04e77d9d8d8f4eb2e48167843cf0c6b9afde0ec7c1cc4a82cee18f547578e91174824bc5a538fbb792f71c518fa8ca0cc1bfeabcc904c6b6b7f480175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b473b6c04d3ce6514dcbcc9b2e89ab16ff3177d2cd59f8d2b7567638a520a8dd058dda4994deca7893537e09d916e30e6117b92aa138aba1b81806b3872f35f8"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011158 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450926/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8ce4ff427c634b791bd01b9ec5fa8e300c57387773436ec44e18086c0b4c9dabd8d9304eaea6cd18d908e6161ef2e0e8d509844c14366bb535eac2a64a04b16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/589926a04e77d9d8d8f4eb2e48167843cf0c6b9afde0ec7c1cc4a82cee18f547578e91174824bc5a538fbb792f71c518fa8ca0cc1bfeabcc904c6b6b7f480175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b473b6c04d3ce6514dcbcc9b2e89ab16ff3177d2cd59f8d2b7567638a520a8dd058dda4994deca7893537e09d916e30e6117b92aa138aba1b81806b3872f35f8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 23:54:22.736505) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 23:54:22.736847) ========= 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.2VArespZk0/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.OfSHMmu3fX/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.005073 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 23:54:22.785788) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 23:54:22.786094) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 23:54:22.786403) ========= ========= Total master_lag: 0.18 =========