builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0265 starttime: 1490009736.64 results: success (0) buildid: 20170320030209 builduid: 011dc9fd643d45f5989c80ac3ffb6b80 revision: 1b9293be51637f841275541d8991314ca56561a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:36.641778) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:36.642190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:36.642460) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:36.668107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 04:35:36.668364) ========= 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.cdEJq2er9w/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.44Mq868gYD/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 --2017-03-20 04:35:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2017-03-20 04:35:37 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.266599 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 04:35:37.958764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:37.959085) ========= 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.cdEJq2er9w/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.44Mq868gYD/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.038166 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:38.018837) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 04:35:38.019183) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1b9293be51637f841275541d8991314ca56561a5 --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 1b9293be51637f841275541d8991314ca56561a5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cdEJq2er9w/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.44Mq868gYD/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 2017-03-20 04:35:38,126 truncating revision to first 12 chars 2017-03-20 04:35:38,126 Setting DEBUG logging. 2017-03-20 04:35:38,126 attempt 1/10 2017-03-20 04:35:38,126 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1b9293be5163?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 04:35:38,856 unpacking tar archive at: mozilla-central-1b9293be5163/testing/mozharness/ program finished with exit code 0 elapsedTime=1.011212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 04:35:39.053569) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:39.053895) ========= script_repo_revision: 1b9293be51637f841275541d8991314ca56561a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:39.054304) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:39.054562) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:39.073903) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 35 secs) (at 2017-03-20 04:35:39.074201) ========= /tools/buildbot/bin/python -u 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', '-u', '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.cdEJq2er9w/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.44Mq868gYD/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:35:39 INFO - MultiFileLogger online at 20170320 04:35:39 in /builds/slave/test 04:35:39 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 04:35:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:35:39 INFO - {'append_to_log': False, 04:35:39 INFO - 'base_work_dir': '/builds/slave/test', 04:35:39 INFO - 'blob_upload_branch': 'Firefox', 04:35:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:35:39 INFO - 'branch': 'Firefox', 04:35:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:35:39 INFO - 'config_files': ('talos/mac_config.py',), 04:35:39 INFO - 'default_actions': ('clobber', 04:35:39 INFO - 'read-buildbot-config', 04:35:39 INFO - 'download-and-extract', 04:35:39 INFO - 'populate-webroot', 04:35:39 INFO - 'create-virtualenv', 04:35:39 INFO - 'install', 04:35:39 INFO - 'run-tests'), 04:35:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:35:39 INFO - 'download_minidump_stackwalk': True, 04:35:39 INFO - 'download_symbols': 'ondemand', 04:35:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:35:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:35:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:35:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:35:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:35:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:35:39 INFO - 'gecko_profile': False, 04:35:39 INFO - 'gecko_profile_interval': 0, 04:35:39 INFO - 'installer_path': 'installer.exe', 04:35:39 INFO - 'log_level': 'info', 04:35:39 INFO - 'log_name': 'talos', 04:35:39 INFO - 'log_to_console': True, 04:35:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:35:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:35:39 INFO - 'opt_config_files': (), 04:35:39 INFO - 'pip_index': False, 04:35:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:35:39 INFO - 'cmd': ('bash', 04:35:39 INFO - '-c', 04:35:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:35:39 INFO - 'enabled': True, 04:35:39 INFO - 'halt_on_failure': False, 04:35:39 INFO - 'name': 'check_screen_resolution'},), 04:35:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:35:39 INFO - 'cmd': ('bash', 04:35:39 INFO - '-c', 04:35:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:35:39 INFO - 'enabled': True, 04:35:39 INFO - 'halt_on_failure': False, 04:35:39 INFO - 'name': 'check_screen_resolution'},), 04:35:39 INFO - 'run_cmd_checks_enabled': True, 04:35:39 INFO - 'suite': 'dromaeojs', 04:35:39 INFO - 'system_bits': '32', 04:35:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:35:39 INFO - 'title': 't-yosemite-r7-0265', 04:35:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:35:39 INFO - 'use_talos_json': True, 04:35:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:35:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:35:39 INFO - 'work_dir': 'build'} 04:35:39 INFO - [mozharness: 2017-03-20 11:35:39.288995Z] Running clobber step. 04:35:39 INFO - Running pre-action listener: _resource_record_pre_action 04:35:39 INFO - Running main action method: clobber 04:35:39 INFO - rmtree: /builds/slave/test/build 04:35:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:35:41 INFO - Running post-action listener: _resource_record_post_action 04:35:41 INFO - [mozharness: 2017-03-20 11:35:41.717906Z] Finished clobber step (success) 04:35:41 INFO - [mozharness: 2017-03-20 11:35:41.718031Z] Running read-buildbot-config step. 04:35:41 INFO - Running pre-action listener: _resource_record_pre_action 04:35:41 INFO - Running main action method: read_buildbot_config 04:35:41 INFO - Using buildbot properties: 04:35:41 INFO - { 04:35:41 INFO - "project": "", 04:35:41 INFO - "product": "firefox", 04:35:41 INFO - "script_repo_revision": "production", 04:35:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:35:41 INFO - "repository": "", 04:35:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:35:41 INFO - "buildid": "20170320030209", 04:35:41 INFO - "stage_platform": "macosx64", 04:35:41 INFO - "basedir": "/builds/slave/test", 04:35:41 INFO - "nightly_build": "True", 04:35:41 INFO - "buildnumber": 0, 04:35:41 INFO - "slavename": "t-yosemite-r7-0265", 04:35:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:35:41 INFO - "platform": "yosemite_r7", 04:35:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:35:41 INFO - "branch": "mozilla-central", 04:35:41 INFO - "repo_path": "mozilla-central", 04:35:41 INFO - "revision": "1b9293be51637f841275541d8991314ca56561a5", 04:35:41 INFO - "pgo_build": "False", 04:35:41 INFO - "builduid": "011dc9fd643d45f5989c80ac3ffb6b80", 04:35:41 INFO - "slavebuilddir": "test" 04:35:41 INFO - } 04:35:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:35:41 INFO - Running post-action listener: _resource_record_post_action 04:35:41 INFO - [mozharness: 2017-03-20 11:35:41.721415Z] Finished read-buildbot-config step (success) 04:35:41 INFO - [mozharness: 2017-03-20 11:35:41.721517Z] Running download-and-extract step. 04:35:41 INFO - Running pre-action listener: _resource_record_pre_action 04:35:41 INFO - Running main action method: download_and_extract 04:35:41 INFO - mkdir: /builds/slave/test/build/tests 04:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:35:41 INFO - trying https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:35:41 INFO - Downloading https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:35:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:35:43 INFO - Downloaded 1325 bytes. 04:35:43 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:35:43 INFO - Using the following test package requirements: 04:35:43 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:35:43 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:35:43 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:35:43 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:35:43 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'jsshell-mac.zip'], 04:35:43 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:35:43 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:35:43 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:35:43 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:35:43 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:35:43 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:43 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:35:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:35:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:35:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:35:43 INFO - Fetch https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:36:09 INFO - Expected file size: 52473169 04:36:09 INFO - Obtained file size: 52473169 04:36:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:36:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:36:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:36:25 INFO - Fetch https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:36:28 INFO - Expected file size: 52473169 04:36:28 INFO - Obtained file size: 52473169 04:36:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:36:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:36:43 INFO - Fetch https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:36:46 INFO - Expected file size: 14404171 04:36:46 INFO - Obtained file size: 14404171 04:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:47 INFO - trying https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:36:47 INFO - Downloading https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:36:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:37:20 INFO - Downloaded 75260959 bytes. 04:37:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:37:20 INFO - mkdir: /builds/slave/test/properties 04:37:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:37:20 INFO - Writing to file /builds/slave/test/properties/build_url 04:37:20 INFO - Contents: 04:37:20 INFO - build_url:https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:37:21 INFO - Running post-action listener: _resource_record_post_action 04:37:21 INFO - Running post-action listener: set_extra_try_arguments 04:37:21 INFO - [mozharness: 2017-03-20 11:37:21.557080Z] Finished download-and-extract step (success) 04:37:21 INFO - [mozharness: 2017-03-20 11:37:21.557196Z] Running populate-webroot step. 04:37:21 INFO - Running pre-action listener: _resource_record_pre_action 04:37:21 INFO - Running main action method: populate_webroot 04:37:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:37:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:37:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:37:21 INFO - '--tppagecycles', 04:37:21 INFO - '7'], 04:37:21 INFO - 'tests': ['tsvgm']}}, 04:37:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:37:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:37:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:37:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:37:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:37:21 INFO - 'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:37:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:37:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:37:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:37:21 INFO - 'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['damp', 'tps']}, 04:37:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:37:21 INFO - 'tests': ['damp', 'tps']}, 04:37:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['dromaeo_dom']}, 04:37:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:37:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['basic_compositor_video']}, 04:37:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:37:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['a11yr', 04:37:21 INFO - 'ts_paint', 04:37:21 INFO - 'tpaint', 04:37:21 INFO - 'sessionrestore', 04:37:21 INFO - 'sessionrestore_no_auto_restore', 04:37:21 INFO - 'tabpaint']}, 04:37:21 INFO - 'other-e10s': {'tests': ['a11yr', 04:37:21 INFO - 'ts_paint', 04:37:21 INFO - 'tpaint', 04:37:21 INFO - 'sessionrestore', 04:37:21 INFO - 'sessionrestore_no_auto_restore', 04:37:21 INFO - 'tabpaint']}, 04:37:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:37:21 INFO - 'ts_paint', 04:37:21 INFO - 'tpaint', 04:37:21 INFO - 'sessionrestore', 04:37:21 INFO - 'sessionrestore_no_auto_restore', 04:37:21 INFO - 'tabpaint']}, 04:37:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:37:21 INFO - 'ts_paint', 04:37:21 INFO - 'tpaint', 04:37:21 INFO - 'sessionrestore', 04:37:21 INFO - 'sessionrestore_no_auto_restore', 04:37:21 INFO - 'tabpaint']}, 04:37:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['a11yr', 04:37:21 INFO - 'ts_paint', 04:37:21 INFO - 'tpaint', 04:37:21 INFO - 'sessionrestore', 04:37:21 INFO - 'sessionrestore_no_auto_restore', 04:37:21 INFO - 'tabpaint']}, 04:37:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['a11yr', 04:37:21 INFO - 'ts_paint', 04:37:21 INFO - 'tpaint', 04:37:21 INFO - 'sessionrestore', 04:37:21 INFO - 'sessionrestore_no_auto_restore', 04:37:21 INFO - 'tabpaint']}, 04:37:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['tsvgx', 04:37:21 INFO - 'tsvgr_opacity', 04:37:21 INFO - 'tart', 04:37:21 INFO - 'tscrollx', 04:37:21 INFO - 'cart', 04:37:21 INFO - 'tsvg_static']}, 04:37:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:37:21 INFO - 'tsvgr_opacity', 04:37:21 INFO - 'tart', 04:37:21 INFO - 'tscrollx', 04:37:21 INFO - 'cart', 04:37:21 INFO - 'tsvg_static']}, 04:37:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:37:21 INFO - 'talos_options': ['--disable-e10s'], 04:37:21 INFO - 'tests': ['tp5o']}, 04:37:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:37:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:37:21 INFO - 'talos_options': ['--disable-e10s', 04:37:21 INFO - '--xperf_path', 04:37:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:37:21 INFO - 'tests': ['tp5n']}, 04:37:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:37:21 INFO - 'talos_options': ['--xperf_path', 04:37:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:37:21 INFO - 'tests': ['tp5n']}}, 04:37:21 INFO - 'talos.zip': {'path': '', 04:37:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:37:21 INFO - Running post-action listener: _resource_record_post_action 04:37:21 INFO - [mozharness: 2017-03-20 11:37:21.575595Z] Finished populate-webroot step (success) 04:37:21 INFO - [mozharness: 2017-03-20 11:37:21.575731Z] Running create-virtualenv step. 04:37:21 INFO - Running pre-action listener: _resource_record_pre_action 04:37:21 INFO - Running main action method: create_virtualenv 04:37:21 INFO - Creating virtualenv /builds/slave/test/build/venv 04:37:21 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 04:37:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:37:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:37:21 INFO - Using real prefix '/tools/python27' 04:37:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:37:22 INFO - Installing distribute.............................................................................................................................................................................................done. 04:37:25 INFO - Installing pip.................done. 04:37:25 INFO - Return code: 0 04:37:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:37:25 INFO - Reading from file tmpfile_stdout 04:37:25 INFO - Output received: 04:37:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:37:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:37:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:25 INFO - 'HOME': '/Users/cltbld', 04:37:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:25 INFO - 'LOGNAME': 'cltbld', 04:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:25 INFO - 'MOZ_NO_REMOTE': '1', 04:37:25 INFO - 'NO_EM_RESTART': '1', 04:37:25 INFO - 'PAGER': '/bin/cat', 04:37:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:25 INFO - 'PWD': '/builds/slave/test', 04:37:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:25 INFO - 'SHELL': '/bin/bash', 04:37:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:25 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:25 INFO - 'USER': 'cltbld', 04:37:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:25 INFO - 'XPC_FLAGS': '0x0', 04:37:25 INFO - 'XPC_SERVICE_NAME': '0', 04:37:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:25 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 04:37:25 INFO - Cleaning up... 04:37:25 INFO - Return code: 0 04:37:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:37:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:25 INFO - 'HOME': '/Users/cltbld', 04:37:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:25 INFO - 'LOGNAME': 'cltbld', 04:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:25 INFO - 'MOZ_NO_REMOTE': '1', 04:37:25 INFO - 'NO_EM_RESTART': '1', 04:37:25 INFO - 'PAGER': '/bin/cat', 04:37:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:25 INFO - 'PWD': '/builds/slave/test', 04:37:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:25 INFO - 'SHELL': '/bin/bash', 04:37:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:25 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:25 INFO - 'USER': 'cltbld', 04:37:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:25 INFO - 'XPC_FLAGS': '0x0', 04:37:25 INFO - 'XPC_SERVICE_NAME': '0', 04:37:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:26 INFO - Downloading/unpacking psutil>=3.1.1 04:37:26 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 04:37:26 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 04:37:26 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 04:37:26 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 04:37:26 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 04:37:26 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 04:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:37:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:37:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:37:34 INFO - Installing collected packages: psutil 04:37:34 INFO - Running setup.py install for psutil 04:37:35 INFO - building 'psutil._psutil_osx' extension 04:37:35 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 04:37:35 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 04:37:35 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 04:37:35 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 04:37:35 INFO - building 'psutil._psutil_posix' extension 04:37:35 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 04:37:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:37:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:37:35 INFO - ^ 04:37:35 INFO - 1 warning generated. 04:37:35 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 04:37:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:37:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:37:35 INFO - Successfully installed psutil 04:37:35 INFO - Cleaning up... 04:37:36 INFO - Return code: 0 04:37:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:37:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:36 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 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37:36 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 04:37:36 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 04:37:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:36 INFO - 'HOME': '/Users/cltbld', 04:37:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:36 INFO - 'LOGNAME': 'cltbld', 04:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:36 INFO - 'MOZ_NO_REMOTE': '1', 04:37:36 INFO - 'NO_EM_RESTART': '1', 04:37:36 INFO - 'PAGER': '/bin/cat', 04:37:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:36 INFO - 'PWD': '/builds/slave/test', 04:37:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:36 INFO - 'SHELL': '/bin/bash', 04:37:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:36 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:36 INFO - 'USER': 'cltbld', 04:37:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:36 INFO - 'XPC_FLAGS': '0x0', 04:37:36 INFO - 'XPC_SERVICE_NAME': '0', 04:37:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:37:39 INFO - Installing collected packages: mozsystemmonitor 04:37:39 INFO - Running setup.py install for mozsystemmonitor 04:37:39 INFO - Successfully installed mozsystemmonitor 04:37:39 INFO - Cleaning up... 04:37:39 INFO - Return code: 0 04:37:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:37:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:37:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:39 INFO - 'HOME': '/Users/cltbld', 04:37:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:39 INFO - 'LOGNAME': 'cltbld', 04:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:39 INFO - 'MOZ_NO_REMOTE': '1', 04:37:39 INFO - 'NO_EM_RESTART': '1', 04:37:39 INFO - 'PAGER': '/bin/cat', 04:37:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:39 INFO - 'PWD': '/builds/slave/test', 04:37:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:39 INFO - 'SHELL': '/bin/bash', 04:37:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:39 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:39 INFO - 'USER': 'cltbld', 04:37:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:39 INFO - 'XPC_FLAGS': '0x0', 04:37:39 INFO - 'XPC_SERVICE_NAME': '0', 04:37:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:39 INFO - Downloading/unpacking jsonschema==2.5.1 04:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:37:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:43 INFO - Downloading functools32-3.2.3-2.tar.gz 04:37:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:37:43 INFO - warning: no files found matching '*.txt' 04:37:43 INFO - no previously-included directories found matching 'build' 04:37:43 INFO - no previously-included directories found matching 'dist' 04:37:43 INFO - no previously-included directories found matching '.git*' 04:37:43 INFO - Installing collected packages: jsonschema, functools32 04:37:43 INFO - Running setup.py install for functools32 04:37:44 INFO - warning: no files found matching '*.txt' 04:37:44 INFO - no previously-included directories found matching 'build' 04:37:44 INFO - no previously-included directories found matching 'dist' 04:37:44 INFO - no previously-included directories found matching '.git*' 04:37:44 INFO - Successfully installed jsonschema functools32 04:37:44 INFO - Cleaning up... 04:37:44 INFO - Return code: 0 04:37:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:37:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:44 INFO - 'HOME': '/Users/cltbld', 04:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:44 INFO - 'LOGNAME': 'cltbld', 04:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:44 INFO - 'MOZ_NO_REMOTE': '1', 04:37:44 INFO - 'NO_EM_RESTART': '1', 04:37:44 INFO - 'PAGER': '/bin/cat', 04:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:44 INFO - 'PWD': '/builds/slave/test', 04:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:44 INFO - 'SHELL': '/bin/bash', 04:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:44 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:44 INFO - 'USER': 'cltbld', 04:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:44 INFO - 'XPC_FLAGS': '0x0', 04:37:44 INFO - 'XPC_SERVICE_NAME': '0', 04:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:37:44 INFO - Cleaning up... 04:37:44 INFO - Return code: 0 04:37:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:37:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:44 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 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37:44 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 04:37:44 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 04:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:44 INFO - 'HOME': '/Users/cltbld', 04:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:44 INFO - 'LOGNAME': 'cltbld', 04:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:44 INFO - 'MOZ_NO_REMOTE': '1', 04:37:44 INFO - 'NO_EM_RESTART': '1', 04:37:44 INFO - 'PAGER': '/bin/cat', 04:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:44 INFO - 'PWD': '/builds/slave/test', 04:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:44 INFO - 'SHELL': '/bin/bash', 04:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:44 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:44 INFO - 'USER': 'cltbld', 04:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:44 INFO - 'XPC_FLAGS': '0x0', 04:37:44 INFO - 'XPC_SERVICE_NAME': '0', 04:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:44 INFO - Downloading/unpacking blobuploader==1.2.4 04:37:44 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 04:37:44 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 04:37:44 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 04:37:44 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 04:37:44 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 04:37:44 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 04:37:49 INFO - Downloading blobuploader-1.2.4.tar.gz 04:37:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:37:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:37:50 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 04:37:50 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 04:37:50 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 04:37:50 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 04:37:50 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 04:37:50 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 04:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:37:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37:52 INFO - Downloading docopt-0.6.1.tar.gz 04:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:37:52 INFO - Installing collected packages: blobuploader, requests, docopt 04:37:52 INFO - Running setup.py install for blobuploader 04:37:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:37:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:37:52 INFO - Running setup.py install for requests 04:37:53 INFO - Running setup.py install for docopt 04:37:53 INFO - Successfully installed blobuploader requests docopt 04:37:53 INFO - Cleaning up... 04:37:53 INFO - Return code: 0 04:37:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37: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 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37: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 04:37: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 04:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:53 INFO - 'HOME': '/Users/cltbld', 04:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:53 INFO - 'LOGNAME': 'cltbld', 04:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:53 INFO - 'MOZ_NO_REMOTE': '1', 04:37:53 INFO - 'NO_EM_RESTART': '1', 04:37:53 INFO - 'PAGER': '/bin/cat', 04:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:53 INFO - 'PWD': '/builds/slave/test', 04:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:53 INFO - 'SHELL': '/bin/bash', 04:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:53 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:53 INFO - 'USER': 'cltbld', 04:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:53 INFO - 'XPC_FLAGS': '0x0', 04:37:53 INFO - 'XPC_SERVICE_NAME': '0', 04:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:37:53 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-GTa6Nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:37:53 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-53jFAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:37:53 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-tslNMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Lp4Ols-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-WtnDEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-bd4m9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-C425kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-fNwQ9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-YVxqlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-CI68PV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-WcoJ6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:37:54 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-QY49vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:37:55 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-wbrWRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:37:55 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-rPuGEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:55 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-91YTN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:37:55 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-ipXm1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:37:55 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Q3EbJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:37:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:37:55 INFO - Running setup.py install for manifestparser 04:37:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:37:55 INFO - Running setup.py install for mozcrash 04:37:55 INFO - Running setup.py install for mozdebug 04:37:55 INFO - Running setup.py install for mozdevice 04:37:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Running setup.py install for mozfile 04:37:56 INFO - Running setup.py install for mozhttpd 04:37:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Running setup.py install for mozinfo 04:37:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Running setup.py install for mozInstall 04:37:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:37:56 INFO - Running setup.py install for mozleak 04:37:56 INFO - Running setup.py install for mozlog 04:37:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:37:57 INFO - Running setup.py install for moznetwork 04:37:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:37:57 INFO - Running setup.py install for mozprocess 04:37:57 INFO - Running setup.py install for mozprofile 04:37:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:37:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:37:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:37:57 INFO - Running setup.py install for mozrunner 04:37:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:37:57 INFO - Running setup.py install for mozscreenshot 04:37:57 INFO - Running setup.py install for moztest 04:37:57 INFO - Running setup.py install for mozversion 04:37:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:37:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:37:58 INFO - Cleaning up... 04:37:58 INFO - Return code: 0 04:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37: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 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:37: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 04:37: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 04:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:58 INFO - 'HOME': '/Users/cltbld', 04:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:58 INFO - 'LOGNAME': 'cltbld', 04:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:58 INFO - 'MOZ_NO_REMOTE': '1', 04:37:58 INFO - 'NO_EM_RESTART': '1', 04:37:58 INFO - 'PAGER': '/bin/cat', 04:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:58 INFO - 'PWD': '/builds/slave/test', 04:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:58 INFO - 'SHELL': '/bin/bash', 04:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:37:58 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:58 INFO - 'USER': 'cltbld', 04:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:58 INFO - 'XPC_FLAGS': '0x0', 04:37:58 INFO - 'XPC_SERVICE_NAME': '0', 04:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:37:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xyLVv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:37: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)) 04:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:37:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-1qvmq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 04:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:37:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-BqDYE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:37:58 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)) 04:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:37:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-qsKp9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:37:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-jegciJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:37:58 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)) 04:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:37:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-pngUI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:37: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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-kC93mJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:37: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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-vySgcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:37: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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-b3JySf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:37: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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-9DwbwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-VV3dox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:37: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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Azg25i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-OF8XV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:37:59 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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-VcflNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 04:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-OdgzfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:38: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)) 04:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:38:00 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-lxdnje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 04:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:38:00 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-JuFt1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:38: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)) 04:38: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:38: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:38: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)) 04:38: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:38: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:38:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:38: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 04:38: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 04:38: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 04:38: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 04:38: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 04:38: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 04:38:02 INFO - Downloading blessings-1.6.tar.gz 04:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:38:02 INFO - Installing collected packages: blessings 04:38:02 INFO - Running setup.py install for blessings 04:38:03 INFO - Successfully installed blessings 04:38:03 INFO - Cleaning up... 04:38:03 INFO - Return code: 0 04:38:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:38:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:38:03 INFO - Reading from file tmpfile_stdout 04:38:03 INFO - Current package versions: 04:38:03 INFO - blessings == 1.6 04:38:03 INFO - blobuploader == 1.2.4 04:38:03 INFO - docopt == 0.6.1 04:38:03 INFO - functools32 == 3.2.3-2 04:38:03 INFO - jsonschema == 2.5.1 04:38:03 INFO - manifestparser == 1.1 04:38:03 INFO - mozInstall == 1.12 04:38:03 INFO - mozcrash == 1.0 04:38:03 INFO - mozdebug == 0.1 04:38:03 INFO - mozdevice == 0.49 04:38:03 INFO - mozfile == 1.2 04:38:03 INFO - mozhttpd == 0.7 04:38:03 INFO - mozinfo == 0.9 04:38:03 INFO - mozleak == 0.1 04:38:03 INFO - mozlog == 3.4 04:38:03 INFO - moznetwork == 0.27 04:38:03 INFO - mozprocess == 0.25 04:38:03 INFO - mozprofile == 0.28 04:38:03 INFO - mozrunner == 6.13 04:38:03 INFO - mozscreenshot == 0.1 04:38:03 INFO - mozsystemmonitor == 0.3 04:38:03 INFO - moztest == 0.8 04:38:03 INFO - mozversion == 1.4 04:38:03 INFO - psutil == 3.1.1 04:38:03 INFO - requests == 1.2.3 04:38:03 INFO - wsgiref == 0.1.2 04:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:38: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:38: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:38: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 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:38: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 04:38: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 04:38:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:38:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:38:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:38:03 INFO - 'HOME': '/Users/cltbld', 04:38:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:38:03 INFO - 'LOGNAME': 'cltbld', 04:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:38:03 INFO - 'MOZ_NO_REMOTE': '1', 04:38:03 INFO - 'NO_EM_RESTART': '1', 04:38:03 INFO - 'PAGER': '/bin/cat', 04:38:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:38:03 INFO - 'PWD': '/builds/slave/test', 04:38:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:38:03 INFO - 'SHELL': '/bin/bash', 04:38:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:38:03 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:38:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:38:03 INFO - 'USER': 'cltbld', 04:38:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:38:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:38:03 INFO - 'XPC_FLAGS': '0x0', 04:38:03 INFO - 'XPC_SERVICE_NAME': '0', 04:38:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38: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)) 04:38:03 INFO - Cleaning up... 04:38:03 INFO - Return code: 0 04:38:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:38: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:38: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:38: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 0x101f18b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f896b57e800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, '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.cdEJq2er9w/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.44Mq868gYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 04:38: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 04:38: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 04:38:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:38:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:38:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:38:03 INFO - 'HOME': '/Users/cltbld', 04:38:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:38:03 INFO - 'LOGNAME': 'cltbld', 04:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:38:03 INFO - 'MOZ_NO_REMOTE': '1', 04:38:03 INFO - 'NO_EM_RESTART': '1', 04:38:03 INFO - 'PAGER': '/bin/cat', 04:38:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:38:03 INFO - 'PWD': '/builds/slave/test', 04:38:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:38:03 INFO - 'SHELL': '/bin/bash', 04:38:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:38:03 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:38:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:38:03 INFO - 'USER': 'cltbld', 04:38:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:38:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:38:03 INFO - 'XPC_FLAGS': '0x0', 04:38:03 INFO - 'XPC_SERVICE_NAME': '0', 04:38:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:38:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:38:04 INFO - Cleaning up... 04:38:04 INFO - Return code: 0 04:38:04 INFO - Running post-action listener: _resource_record_post_action 04:38:04 INFO - Running post-action listener: _start_resource_monitoring 04:38:04 INFO - Starting resource monitoring. 04:38:04 INFO - [mozharness: 2017-03-20 11:38:04.115605Z] Finished create-virtualenv step (success) 04:38:04 INFO - [mozharness: 2017-03-20 11:38:04.116547Z] Running install step. 04:38:04 INFO - Running pre-action listener: _resource_record_pre_action 04:38:04 INFO - Running main action method: install 04:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:38:04 INFO - Reading from file tmpfile_stdout 04:38:04 INFO - Detecting whether we're running mozinstall >=1.0... 04:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:38:04 INFO - Reading from file tmpfile_stdout 04:38:04 INFO - Output received: 04:38:04 INFO - Usage: mozinstall [options] installer 04:38:04 INFO - Options: 04:38:04 INFO - -h, --help show this help message and exit 04:38:04 INFO - -d DEST, --destination=DEST 04:38:04 INFO - Directory to install application into. [default: 04:38:04 INFO - "/builds/slave/test"] 04:38:04 INFO - --app=APP Application being installed. [default: firefox] 04:38:04 INFO - mkdir: /builds/slave/test/build/application 04:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:38:24 INFO - Reading from file tmpfile_stdout 04:38:24 INFO - Output received: 04:38:24 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:38:24 INFO - Running post-action listener: _resource_record_post_action 04:38:24 INFO - [mozharness: 2017-03-20 11:38:24.732448Z] Finished install step (success) 04:38:24 INFO - [mozharness: 2017-03-20 11:38:24.732628Z] Running run-tests step. 04:38:24 INFO - Running pre-action listener: _resource_record_pre_action 04:38:24 INFO - Running main action method: run_tests 04:38:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:38:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:38:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:38:24 INFO - 2017-03-20 04:38:24.747 screenresolution[1632:7617] starting screenresolution argv=screenresolution get 04:38:24 INFO - 2017-03-20 04:38:24.750 screenresolution[1632:7617] Display 0: 1600x1200x32@60 04:38:24 INFO - 2017-03-20 04:38:24.755 screenresolution[1633:7620] starting screenresolution argv=screenresolution list 04:38:24 INFO - Available Modes on Display 0 04:38:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:38:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:38:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:38:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:38:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:38:24 INFO - Intel Iris: 04:38:24 INFO - Chipset Model: Intel Iris 04:38:24 INFO - Type: GPU 04:38:24 INFO - Bus: Built-In 04:38:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:38:24 INFO - Vendor: Intel (0x8086) 04:38:24 INFO - Device ID: 0x0a2e 04:38:24 INFO - Revision ID: 0x0009 04:38:24 INFO - Displays: 04:38:24 INFO - Display: 04:38:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:38:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:38:24 INFO - Display Serial Number: 1600x1200 60 04:38:24 INFO - Main Display: Yes 04:38:24 INFO - Mirror: Off 04:38:24 INFO - Online: Yes 04:38:24 INFO - Rotation: Supported 04:38:24 INFO - Return code: 0 04:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:38:24 INFO - Python 2.7.3 04:38:24 INFO - Return code: 0 04:38:24 INFO - grabbing minidump binary from tooltool 04:38: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:38:24 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 0x101cf6d30>, '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 0x101c1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cebbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:38:24 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 04:38:24 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 04:38:24 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 04:38:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:38:25 INFO - Return code: 0 04:38:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:38:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:38:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:38:25 INFO - ENV: RUST_BACKTRACE is now 1 04:38:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:38:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:38:25 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 04:38:25 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0265 --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 04:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdEJq2er9w/Render', 04:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:38:25 INFO - 'HOME': '/Users/cltbld', 04:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:38:25 INFO - 'LOGNAME': 'cltbld', 04:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:38:25 INFO - 'MOZ_NO_REMOTE': '1', 04:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:38:25 INFO - 'NO_EM_RESTART': '1', 04:38:25 INFO - 'PAGER': '/bin/cat', 04:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:38:25 INFO - 'PWD': '/builds/slave/test', 04:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:38:25 INFO - 'RUST_BACKTRACE': '1', 04:38:25 INFO - 'SHELL': '/bin/bash', 04:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44Mq868gYD/Listeners', 04:38:25 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 04:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:38:25 INFO - 'USER': 'cltbld', 04:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:38:25 INFO - 'XPC_FLAGS': '0x0', 04:38:25 INFO - 'XPC_SERVICE_NAME': '0', 04:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:38:25 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 04:38:25 INFO - mozversion application_buildid: 20170320030209 04:38:25 INFO - mozversion application_changeset: 1b9293be51637f841275541d8991314ca56561a5 04:38:25 INFO - mozversion application_display_name: Nightly 04:38:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:38:25 INFO - mozversion application_name: Firefox 04:38:25 INFO - mozversion application_remotingname: firefox 04:38:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:38:25 INFO - mozversion application_vendor: Mozilla 04:38:25 INFO - mozversion application_version: 55.0a1 04:38:25 INFO - mozversion platform_buildid: 20170320030209 04:38:25 INFO - mozversion platform_changeset: 1b9293be51637f841275541d8991314ca56561a5 04:38:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:38:25 INFO - mozversion platform_version: 55.0a1 04:38:25 INFO - using testdate: 1490009905 04:38:25 INFO - actual date: 1490009905 04:38:25 INFO - starting webserver on 'localhost:49245' 04:38:25 INFO - SUITE-START | Running 2 tests 04:38:25 INFO - TEST-START | dromaeo_css 04:38:25 INFO - operating with platform_type : mac_ 04:38:25 INFO - Initialising browser for dromaeo_css test... 04:38:25 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpi50DJl/profile http://localhost:49245/getInfo.html) 04:38:28 INFO - PID 1643 | __metrics Screen width/height:1600/1200 04:38:28 INFO - PID 1643 | colorDepth:24 04:38:28 INFO - PID 1643 | Browser inner width/height: 1024/689 04:38:28 INFO - PID 1643 | __metrics 04:38:28 INFO - PID 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:38:28 INFO - PID 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:38:28 INFO - TEST-INFO | 1643: exit 0 04:38:28 INFO - Browser initialized. 04:38:28 INFO - Running cycle 1/1 for dromaeo_css test... 04:38:28 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpi50DJl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:38:41 INFO - PID 1646 | RSS: Main: 233775104 04:38:41 INFO - PID 1646 | 04:41:45 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 04:41:47 INFO - PID 1646 | RSS: Main: 412348416 04:41:47 INFO - PID 1646 | 04:43:30 INFO - PID 1646 | ************************* 04:43:30 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 04:43:30 INFO - PID 1646 | 04:43:30 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:43:30 INFO - PID 1646 | Full stack: 04:43:30 INFO - PID 1646 | ************************* 04:44:50 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 04:44:51 INFO - PID 1646 | RSS: Main: 366383104 04:44:51 INFO - PID 1646 | 04:47:53 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 04:47:54 INFO - PID 1646 | RSS: Main: 335872000 04:47:54 INFO - PID 1646 | 04:50:56 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 04:50:57 INFO - PID 1646 | RSS: Main: 333094912 04:50:57 INFO - PID 1646 | 04:54:01 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 04:54:01 INFO - PID 1646 | RSS: Main: 331501568 04:54:01 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 04:57:05 INFO - PID 1646 | RSS: Main: 391110656 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | __start_tp_report 04:57:05 INFO - PID 1646 | _x_x_mozilla_page_load 04:57:05 INFO - PID 1646 | _x_x_mozilla_page_load_details 04:57:05 INFO - PID 1646 | |i|pagename|runs| 04:57:05 INFO - PID 1646 | |0;dojo.html;3360.815155166465;3366.755771084459;3368.949465758014;3411.385950528905;3433.7939723616587;5754.769809207637;5820.679862607555;5836.533077353812;5881.264841894763;5913.349531551526;5776.653400795955;5805.854853628651;5889.499392551627;5920.99343111671;5926.585139040269;5172.896542069157;5241.449647786984;5259.631825772198;5265.394479634833;5300.284461597693;5349.8127565535215;5378.596605254602;5391.595630327722;5397.54120899724;5457.781688732446;5203.947960520384;5258;5262.789488420459;5295;5339.145736682131;4422.911541769179;5288.576913846883;5307.840764777063;5317.255584218213;5371.435999220103;5235.58115350771;5257.158854583248;5274.393444753864;5288.0745869472685;5353.304070470853;5671.035923892948;5745.368009518949;5853.824385268449;5855.882882342329;5872.618279811842;6223.719932603044;6310.242770867525;6352.301246862841;6355.142055822497;6363.840903977438;6225.688715564249;6372.330905254974;6380.1705778249;6393.520485963525;6402.647854368008;5931.08068249422;5978.8505287367725;5984.461398474158;5994.520438364964;5996.610220335664;6331.113644089831;6352.841178970562;6393;6403.711832967528;6417.807465776034;6103.071896073912;6142.775043102318;6199.977003794414;6264.906026409608;6301.8109456716375;6254;6376.585521941059;6381.266154392304;6385.074164246158;6393.9360606394275;6343.809685709436;6359.141515895388;6363.3636636336;6364.299927008025;6364.522660800459;6216.129741836146;6292.2134733158355;6303.338149494329;6349.2698339690605;6366.236051673829;5051.570616497639;5068.974655126701;5087.847364579068;5099.36257967754;5119.769610367543;4486.551344865487;4500.662450316239;4515.909681806345;4517.209488339593;4554.931676024862;5738.942610573841;5998.670073145931;6022.427869352405;6024.728887200086;6029.698515074228;5777.422257774273;5903.793367232126;6295.622262664255;6310.968445157837;6331.461825744771;5822.854428639318;5879.088741245114;5887.528997680175;5887.646741195542;5901.616394934315;5520.475554822286;5522.088855338824;5526.281583394213;5527.032769265363;5527.032769265363;6446.709898054599;6451.225852897683;6453.838654033687;6455.386738259858;6456.386643268882;6352.491800656029;6357.078223657636;6358.459530939922;6363.236411630449;6373.044043393528;5468.699221542854;6429.7106630200815;6465.2564955030775;6547.148870646785;6566.212054553484;6305;6307.3377295383325;6309.432151106423;6313.6843157841;6330.177076979963;6213.9067913980425;6331.556791024584;6348.619082854859;6363.045543168561;6363.649999250086;6099.176611157614;6121.54443000217;6170.136180934763;6195.535334849814;6216.316205217513;6217.595856269328;6221.5333849960525;6236.095766113797;6255.343188965093;6257.2491301044165;5963.13534537481;6110.633361998294;6113.938860611337;6116.602420842631;6140.385961403824;6087.908681369871;6112.807455516588;6120.9652521188555;6193.535484838745;6240.843978900382;6079.483243924316;6087.4521293083835;6095.146679464967;6100.267967843887;6129.295131059986 04:57:05 INFO - PID 1646 | |1;ext.html;715.8711431942252;776.6155752902313;795.490885833067;866.9783255418614;879.8284334554764;27741.445171096566;37608.62391376086;37738.622613773885;37809.054773630676;37938.810305948435;39255;39429.211415771664;39514.40728389077;39527.604723952754;39554;7152.928470715305;8869.60086796094;9105.63577456901;9181.678641247558;9208.49353285569;7300.087489063867;10636.734081647943;10844.240903136784;10852.457377131152;10852.891471085306;10911.018008379244;10987.945060274687;10990.560377584927;11014.229003969725;11194.776104477905;7122.5726456412785;7329;10997.285176463543;11086.722831929226;11103.222774405795;7239.782806515813;7291.380232680229;7319.414446844293;7319.5242309249725;10442.895571044268;37451.87644370673;37709.6801611945;37749.62250377489;37793.62206377956;37846.81076594627;3704.759190652599;3800.657940785343;3849.2301539692176;3849.4225866120028;4024.3963405489117;23069.385143039995;27536.58695119575;28429.431411371657;28603;28721.994730184553;2692.5018871508832;3193.7285330746913;3256.316173603522;3258.7393008559498;3263.233140212048;368946;371953.1402342998;372495;372520.1373993113;373645;53684.19473707898;53980;53986;54075.45924540783;54078.64803379947;55351;55635.88728225413;55716;55721.72139139359;55790;14839.554813355617;14958.551243462714;14967;14971;14977.775333370008;10208.336458130198;10331.896681033244;12111.152219344562;12133.817992730115;12161.452734626962;13539.661508462368;13752;13766.65583360404;13772.24252666093;13988.300584970711;4947.604191664658;5467.781288748405;5765.135229715493;6101.664408457489;6102.572819902653;894.8120894612074;917.8256131335025;948.374073111743;950.3822515365068;986.2553771902311;25167.37081572975;26571.468570628866;27431.86284068567;27894.442111157663;28237.29406764806;838.8741688746737;1016.4307987527009;1052.1109662335314;1061.4851796878509;1064.9307794993301;13774.03581749288;14229.85770142306;14505;14510.533209354224;14514.564563063124;468729;471647;472447;473044;473468;57199;57755.711221444464;57779;57801.71099144477;58035;1364.3178410794603;1444.812174417319;1448.1528305941142;1457.9635509112356;1564.7261729197344;25869.48261034731;26614.467710646066;27210.72789272161;27356.452870942867;27530.724692753618;42793.35809962916;42807.571924281605;42822.57177428186;42899.356509651756;42935;440898;442490;442757;442765;443612;33456.832715836266;33791.662083378855;34394.48408273828;34429.65570344365;34489.65510344865;41933.58066419297;42863.35704964366;42970.57029429666;43145.568544315414;43341.34987975246;28007.57988630213;28075;33040.66959330472;33943.4908476378;34596;34209;34625.15159765324;34933;35411.46882796709;35523.28953420969;35095.47356789599;35821.82089089538;35829.64170358263;35873.28253434969;36128.819355903055;28815.40009764541;28843.990460334626;31112.68887311098;31161;31254;23390;23518.176863809684;23677.816109194126;23874.880625597456;23876.880615596812 04:57:05 INFO - PID 1646 | |2;jquery.html;390.44556729444224;404.11902053523306;419.79430079261164;422.98519551815673;438.89247134452063;13369.465221391145;15264.69470610587;21629.242976495825;21806.45483862904;21839.672404913937;22685;23077.769222307772;23217.18739844106;23331.533369332647;23510.647340289866;6186.164867742865;6811.421029212523;6866.656667166647;6897.3447522485285;6898.37914587687;6631.3700198481065;6661.600303981777;6666.133402657648;6752.763653272136;6772.661366931658;6561.245456772461;6741.460683145337;6748.4938629602975;6756.2568117507035;6775.254721980578;6389.233292004943;6665.566738162004;6692.899606505857;6703.430208432289;6727.831804204885;6516.446102081281;6519.250286217099;6540.607563546202;7069.151701795818;7358.448116391292;27392.45215095707;27396.45207095867;27753;27820.443591128267;28169.57745633817;2849.401625658614;2948.8967886123883;2977.240803595091;2980.0463851567506;2980.910572682801;15784.842151578452;16152.757708634379;16372;16478.75281870773;17157.656846862992;1927.1809480970671;1985.6128055029276;1991.571812060414;2005.6891181866833;2080.0951586060114;162043.18978405034;162960.18519907442;167325.8168545796;168047;171911;17276.913615432095;21900.780992190194;21979.560408791735;22034.779652203593;22401.439964000703;37676;37690.62309376926;37779.4333085004;37889.43165852514;37906.62093379086;10409.635662751767;10655.094316982988;10806.459677016117;10836.187285953585;11035.44822758859;8686;8720.345974051972;8735.257503112307;8898.332625053146;8925;8772.956135219283;9434.726311947936;10012.949935250277;10346.586136554453;10538.525766340534;3530.541029666127;3732.570754363229;4043.8989025274013;4095.4266402703647;4233.872983810491;1002.2433063037506;1006.7634105985088;1010.6109147977934;1027.9537420816082;1031.654395777423;20053.197872084955;22592.548149036927;23135.652965205536;23161.420964476023;23182.3045308641;999.7200783780553;1009.9444530550891;1034.1158309645296;1036.9792604147874;1039.7556574205055;9257.305702072372;11149.554017839195;11720.359149719046;11953.282803031845;11955.462004209832;684889;694925;701285;730832;740324;144858.27570862288;151735;161092;166383;166736.16631916762;1235.4008305971597;1257.9874201257871;1305.4712841299288;1315.7302752935816;1331.4141777617817;19351.322703705304;20859.8957005214;21855.45361365915;21874.781252187273;21928;49209.26186107283;62124.689376554634;62387;62813.37186628075;63308.36691633209;88849;89980.5500972491;90230;90337.19325613583;91151;11743.647690569296;12344.3827808611;12438.937805310916;12441;12495.375971046182;12796.04029697795;13539.458421663418;13645.249511276976;14135.222562759147;14169.504067357593;10214.285000049926;11667.183297169116;11991.280523168638;12094.75810483803;12169.513219471122;11461.083113351078;11595.478203480694;11735.882641173479;11875.287482751062;11935.522579097085;9985.650502232387;11250.099992000783;11585.304881707125;12099.33453660057;12230.266184028987;11556.422178890854;12129.332886691316;12470.937645311715;12513.562025329069;12634.178778379375;11714.707132321419;11776.941115294369;12122.212056216318;12137.69655758613;12186.634400967985 04:57:05 INFO - PID 1646 | |3;mootools.html;1272.9299888506127;1558.5791836204219;1575.3462313140049;1602.1268408717249;1635.3622087385918;10499.947500262497;10994.230403871723;10994.725131871708;11007.779844403107;11010.944945275263;10727.517261723222;10865.891341086586;11060.281081729681;11071.16966227532;11089.8336524952;2180.945476363088;2257.3679369776487;2332.8600283982973;2379.631157170633;2382.9880850595723;2312.4681323295567;2349.7767712067325;2349.988250058756;2350.670906073151;2365.562370961369;2310.295359915225;2329.440934175794;2338.7895089441945;2341.16888504581;2356.9882150589224;2296.2996286132725;2306.4695120122383;2318.652202169671;2324.9883750581307;2325.383773300077;2277.259890535566;2336.5794157051723;2338.7661233876474;2339.7309309429465;2342.6954495915593;11061.723456913562;11171.994520493196;11197.048250898684;11208.495617697141;11211.215214934959;5696.857578560528;5946.554008449383;5961.8807623847715;5964.880702385972;5968;3107.7979931304394;3392.4402473591826;3464.9653503465147;3568.1971556399862;3688.1517251032146;1110.7523409751357;1165.7409997203001;1213.0416970593221;1226.9815952760716;1236.931968741719;12821.871781282254;13805.171689698549;14153.646158846112;14922.477713280092;15429.845701542954;3538.663826936437;4404.647628189737;4698.976505117453;4746.952530474651;4750.239961606127;9769;11601.941990289995;11635.185537012328;11789.351585662871;11801.881981180079;4880.609551235963;5780.248567686174;5839.941600583939;5863.794767183128;5869.882602347929;4646.697964632288;5195.428502864682;5244.580433565306;5254.15933450646;5266.34170728659;3650.5071815305123;3817.2365526894732;3826.2921359548573;3875.6899448044087;3899.29812633723;1014.8274793285159;1017.1252722658508;1024.283001898674;1026.830572955454;1034.5499707627207;788.7239466186903;792.2671528835918;808.7625932323555;811.6469335838929;813.8046868751456;6389.265234498;6577.585819048927;6750.3924646782025;6813.746371714679;6818;977.3842479238034;1001.8697569315942;1007.3099926550336;1020.8417695257099;1027.1936529824052;3287.8684852606107;4135.870026936131;4139.3583994480905;4170.207660544487;4178.770167640809;13364.064515483822;13595;13596.524121655695;13783.655408614864;13808.895288376905;11080.390578518098;13581.524646637321;13713.588592342046;13736.450541978209;13740;10040;10065.949670251603;10094.848577271347;10149.594016239416;10163.847542286872;5001.649884508195;6437.324080971431;6590;6624.470042396693;6673.566218195801;11278.379689117177;12011.159218854596;12031.578894738643;12118.03055755555;12246.326452045223;12212;12461.93769031149;12470.750584988067;12619.558315458915;12640.30478323701;8115.675372985014;8310.044344900172;8396.286315662992;8432.957835210786;8435.873461897954;10134;10146.746331341776;10247.641332553325;10275.332103413255;10329.328593641389;9046.954765226132;9078.909210907806;9096.636134554543;9172.35793494449;9189.218916392181;7476.4018878488505;8826.249768769727;8886;9049.321300902327;9072.863907041532;7714.074311082706;7788.299053085023;7789.961050194713;7874.645640946285;7880.881786773318;4440.378347031483;5630.23991761107;5725.2843394575675;5743.942560574341;5988.431099045583;5518.392976772471;5546.639468434539;5573.749181286771;5611.466910643482;5658.915116273177 04:57:05 INFO - PID 1646 | |4;prototype.html;379.2509793158512;383.8618097484906;393.4118492853185;395.8871721559356;397.1302846765583;4747.2879068139755;5501.174823776426;5736.913946290799;5842.561807864411;5867.530597552196;4502.639788816895;5589.776408943648;5594.888102237953;5746.597738158331;5764.394738552444;4903.338049363335;4963.553280204799;4986.227134794112;4992.728308619569;4996;4043.919121617566;4772;4969.329140566031;4989.675671081368;5009.799608015675;4103.363978583325;4924.507549245082;4939.901201975958;4954.7027178369235;4983.302337672729;4300.741955482666;4371.322445021026;4687.812487500496;4986.5013498650205;4994.875128121826;3975.741576797499;4441.955580444186;4692.131955588227;4773.80904763809;4775.474697783241;5648.350439699446;5663.235463212496;5675.829725108253;5685.744141513642;5713.6000479966415;4786.952130478686;5837.708114594253;5837.9124313135335;5842.211301474332;5862.648241105505;5009.499050094962;5669.262995810518;6089.995150800112;6126.785562505291;6170.907436388457;4575.95424045762;4829.758512074382;4842.6440596633;4845.466998630148;4854.83008094715;6240.4071613196675;6433.818018288151;6436.645984470852;6505.284418713938;6554.180727409074;4126.979365103215;4976.129177393942;4981.726005069756;5003.324551185616;5016.749162541858;5042.226775273658;5046.44489106198;5058.291839142523;5083.847484575469;5104.183330667075;5217.8173763918085;5455.5908306877145;5459.962607104638;5478.561715062785;5506.724663766796;5483;6100.905743703968;6118.694065296806;6122.938770612326;6152.938470615237;1401.5515035188641;1686.468762339859;1703.6166862456096;1729.3082766893344;1730.9048002359739;1177.3406892140429;1239.6838806104386;1278.769821432151;1291.6641673165045;1293.8614019662825;5267.815626453055;5548.9722551386985;5740.741666625012;5744.362856585853;5787.045137552341;6249.843753906098;6255.499560035186;6261.092141639437;6279.90101732195;6297.842553936097;4982.3522942018035;5635.380108188096;5772.220750198753;5809.883802324014;5832.591718579743;3266.9019929402157;3279.5080737889507;3288.7369010479583;3293.8260747906747;3317.9004629861142;5496.790706044664;6113.013270686955;6489.902651460232;6526.64103474304;6565.540412171198;6423.197100362455;6424.13274207976;6425.453836423862;6426.646534440651;6452.064380685775;5363.758630861698;6279.654618995908;6441.516886233551;6521.836954076091;6534.281229064799;5981.162637230704;6245.875082498415;6328.493720502257;6352.968235158794;6389.648569328731;5344.510611086551;5655.886882262414;6262.279837818709;6267.843303917439;6307.936920630735;6328.89244382222;6443.484521238196;6583;6597.736090556324;6614.669266536558;4912.238603016538;4950.430700469348;4951;4973.254011898244;4979.701217926936;5239.345081864767;5828.76684932598;6122.2959359674205;6405.615663060231;6411.839704007437;5102.770375333044;6096.146539484387;6108.815461408949;6120.908186377119;6123.693815309128;6084;6111.5332320243715;6111.786087487095;6131.448169664752;6148.262208535004;5760.703841635766;5958.404159584007;6036.577439579363;6041.456268935641;6073.757049717962;6135.233095863017;6154.107654390178;6168.784092556739;6192.195014648067;6196.907046394217;4939.60483161339;5734.713264336683;5955.771402304413;6031.276246850231;6063.575549711478 04:57:05 INFO - PID 1646 | |5;yui.html;754.3135746470713;759.5708424740021;813.1096449387921;818.7420962396844;823.9217274358941;14930.626734331634;15921.36314547418;15965.281562329694;15997.200139993012;16026.278817453214;14827.77758333626;14845.109293442401;14852;14885.181315027696;15153.848461515408;808.6724876425028;919.8574220995739;956.0105291023792;957.3490026781785;958.5494817435793;948.7248697877642;957.7701351675582;972.0813830929774;976.5605477535138;995.8207522645921;1021.8518314844343;1029.583018877356;1033.3799720167876;1034.824079906414;1036;1015.5024038221255;1030.932989355697;1031.339942436841;1031.8245898197288;1033;756.9659365328573;762.9656665450068;765.6669348833275;769.1692971590669;769.365273649236;16040;16258.918705406397;16525.91737041319;16545.91727041357;16636.251368688438;14768.630784230372;14805;14815.48145814902;14962;15789.28948197334;11209.383483908381;11597.826032609517;11637.941810290979;11671.824922626083;11879.881201187964;3552.7513074084823;3618.3848745713294;3846.2499812536566;3882.107115363454;3890.6692931100606;110035.79928401468;112780;112943.43528282388;113919.43040284829;116841.83158168479;11012.339259644446;11372.54509819598;11770.882291176978;11955.701107472209;12086.87913120875;23066.53866922652;25019.124330648705;25039.374015649755;25082;25328.493430131293;7699.499532530368;7746.573938433327;7884.64519096642;7945.364370850318;7983.800404989922;4248.532661407243;4332.819416123775;4334.544872788375;4462.888427789267;4463.799129039201;3378.273671160712;3475.8088305142956;3476.652334766554;3477.965220347815;3479.5824501060033;602.5691630484225;605.8424809549549;622.5299898576545;628.0516420205431;628.9685515724287;671.9227288861844;675.6857465085465;676.8104930619435;682.5392859819602;684.6474065856088;9764.853527197098;9844.852327215098;9972;10256.692299231036;10853.40306283162;577.2812848004262;598.5630489742425;599.2808629644444;648.2514140046264;703.8486725354073;4620.448937237172;4898.510148985073;4950.356453661073;4984.601231901439;5020.322256495399;95073.52463237778;95180.52409737906;95785;95912;96510;81074;85500;85890.57054714687;87882;89205;52612;60129.79740405167;61935.38064619296;63012.36987630248;63983;9218.354715169873;9391.859122113037;9395.812083758423;9411.717648470556;9488.95255523718;48286;48766;49769;49911.00177996492;50776.476705698886;61684;62462;63275.6836215816;63622.36377636164;64201.67899160474;9639.277054221102;11303.547858085913;11652.41737913084;11840.940795295968;12023.27860328383;12635.494580216688;12768.872311276768;12833.486660533474;12848.614541563767;13199.868001319865;11781.587644432746;11792.882071179178;11982.58060967862;12005.39973001329;12181.147319687538;11728.94135529317;11835.45346115651;11923.284602923852;12021.699457513632;12241.693957650774;11341.546338146713;11563.826542601699;11810.527578896748;12117.878821211674;12263.877361226274;9984.500774961078;10091.962259644;10329.9483502582;10401.011903869121;10961.616343427942;7771.2799229523325;8409.369297302605;8940.329475289509;9320.860187097063;10305.639302624371 04:57:05 INFO - PID 1646 | __end_tp_report 04:57:05 INFO - PID 1646 | __start_cc_report 04:57:05 INFO - PID 1646 | _x_x_mozilla_cycle_collect,1954 04:57:05 INFO - PID 1646 | __end_cc_report 04:57:05 INFO - PID 1646 | __startTimestamp1490011025807__endTimestamp 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | ------- Summary: start ------- 04:57:05 INFO - PID 1646 | Number of tests: 6 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:5850.30 Median:6095.15 stddev:656.09 (10.8%) stddev-sans-first:628.36 04:57:05 INFO - PID 1646 | Values: 3360.8 3366.8 3368.9 3411.4 3433.8 5754.8 5820.7 5836.5 5881.3 5913.3 5776.7 5805.9 5889.5 5921.0 5926.6 5172.9 5241.4 5259.6 5265.4 5300.3 5349.8 5378.6 5391.6 5397.5 5457.8 5203.9 5258.0 5262.8 5295.0 5339.1 4422.9 5288.6 5307.8 5317.3 5371.4 5235.6 5257.2 5274.4 5288.1 5353.3 5671.0 5745.4 5853.8 5855.9 5872.6 6223.7 6310.2 6352.3 6355.1 6363.8 6225.7 6372.3 6380.2 6393.5 6402.6 5931.1 5978.9 5984.5 5994.5 5996.6 6331.1 6352.8 6393.0 6403.7 6417.8 6103.1 6142.8 6200.0 6264.9 6301.8 6254.0 6376.6 6381.3 6385.1 6393.9 6343.8 6359.1 6363.4 6364.3 6364.5 6216.1 6292.2 6303.3 6349.3 6366.2 5051.6 5069.0 5087.8 5099.4 5119.8 4486.6 4500.7 4515.9 4517.2 4554.9 5738.9 5998.7 6022.4 6024.7 6029.7 5777.4 5903.8 6295.6 6311.0 6331.5 5822.9 5879.1 5887.5 5887.6 5901.6 5520.5 5522.1 5526.3 5527.0 5527.0 6446.7 6451.2 6453.8 6455.4 6456.4 6352.5 6357.1 6358.5 6363.2 6373.0 5468.7 6429.7 6465.3 6547.1 6566.2 6305.0 6307.3 6309.4 6313.7 6330.2 6213.9 6331.6 6348.6 6363.0 6363.6 6099.2 6121.5 6170.1 6195.5 6216.3 6217.6 6221.5 6236.1 6255.3 6257.2 5963.1 6110.6 6113.9 6116.6 6140.4 6087.9 6112.8 6121.0 6193.5 6240.8 6079.5 6087.5 6095.1 6100.3 6129.3 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:56729.58 Median:35851.46 stddev:114316.13 (318.9%) stddev-sans-first:114559.46 04:57:05 INFO - PID 1646 | Values: 715.9 776.6 795.5 867.0 879.8 27741.4 37608.6 37738.6 37809.1 37938.8 39255.0 39429.2 39514.4 39527.6 39554.0 7152.9 8869.6 9105.6 9181.7 9208.5 7300.1 10636.7 10844.2 10852.5 10852.9 10911.0 10987.9 10990.6 11014.2 11194.8 7122.6 7329.0 10997.3 11086.7 11103.2 7239.8 7291.4 7319.4 7319.5 10442.9 37451.9 37709.7 37749.6 37793.6 37846.8 3704.8 3800.7 3849.2 3849.4 4024.4 23069.4 27536.6 28429.4 28603.0 28722.0 2692.5 3193.7 3256.3 3258.7 3263.2 368946.0 371953.1 372495.0 372520.1 373645.0 53684.2 53980.0 53986.0 54075.5 54078.6 55351.0 55635.9 55716.0 55721.7 55790.0 14839.6 14958.6 14967.0 14971.0 14977.8 10208.3 10331.9 12111.2 12133.8 12161.5 13539.7 13752.0 13766.7 13772.2 13988.3 4947.6 5467.8 5765.1 6101.7 6102.6 894.8 917.8 948.4 950.4 986.3 25167.4 26571.5 27431.9 27894.4 28237.3 838.9 1016.4 1052.1 1061.5 1064.9 13774.0 14229.9 14505.0 14510.5 14514.6 468729.0 471647.0 472447.0 473044.0 473468.0 57199.0 57755.7 57779.0 57801.7 58035.0 1364.3 1444.8 1448.2 1458.0 1564.7 25869.5 26614.5 27210.7 27356.5 27530.7 42793.4 42807.6 42822.6 42899.4 42935.0 440898.0 442490.0 442757.0 442765.0 443612.0 33456.8 33791.7 34394.5 34429.7 34489.7 41933.6 42863.4 42970.6 43145.6 43341.3 28007.6 28075.0 33040.7 33943.5 34596.0 34209.0 34625.2 34933.0 35411.5 35523.3 35095.5 35821.8 35829.6 35873.3 36128.8 28815.4 28844.0 31112.7 31161.0 31254.0 23390.0 23518.2 23677.8 23874.9 23876.9 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:42928.91 Median:21887.78 stddev:119343.03 (545.2%) stddev-sans-first:119635.08 04:57:05 INFO - PID 1646 | Values: 390.4 404.1 419.8 423.0 438.9 13369.5 15264.7 21629.2 21806.5 21839.7 22685.0 23077.8 23217.2 23331.5 23510.6 6186.2 6811.4 6866.7 6897.3 6898.4 6631.4 6661.6 6666.1 6752.8 6772.7 6561.2 6741.5 6748.5 6756.3 6775.3 6389.2 6665.6 6692.9 6703.4 6727.8 6516.4 6519.3 6540.6 7069.2 7358.4 27392.5 27396.5 27753.0 27820.4 28169.6 2849.4 2948.9 2977.2 2980.0 2980.9 15784.8 16152.8 16372.0 16478.8 17157.7 1927.2 1985.6 1991.6 2005.7 2080.1 162043.2 162960.2 167325.8 168047.0 171911.0 17276.9 21900.8 21979.6 22034.8 22401.4 37676.0 37690.6 37779.4 37889.4 37906.6 10409.6 10655.1 10806.5 10836.2 11035.4 8686.0 8720.3 8735.3 8898.3 8925.0 8773.0 9434.7 10012.9 10346.6 10538.5 3530.5 3732.6 4043.9 4095.4 4233.9 1002.2 1006.8 1010.6 1028.0 1031.7 20053.2 22592.5 23135.7 23161.4 23182.3 999.7 1009.9 1034.1 1037.0 1039.8 9257.3 11149.6 11720.4 11953.3 11955.5 684889.0 694925.0 701285.0 730832.0 740324.0 144858.3 151735.0 161092.0 166383.0 166736.2 1235.4 1258.0 1305.5 1315.7 1331.4 19351.3 20859.9 21855.5 21874.8 21928.0 49209.3 62124.7 62387.0 62813.4 63308.4 88849.0 89980.6 90230.0 90337.2 91151.0 11743.6 12344.4 12438.9 12441.0 12495.4 12796.0 13539.5 13645.2 14135.2 14169.5 10214.3 11667.2 11991.3 12094.8 12169.5 11461.1 11595.5 11735.9 11875.3 11935.5 9985.7 11250.1 11585.3 12099.3 12230.3 11556.4 12129.3 12470.9 12513.6 12634.2 11714.7 11776.9 12122.2 12137.7 12186.6 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:6600.11 Median:2346.24 stddev:4151.55 (176.9%) stddev-sans-first:4143.90 04:57:05 INFO - PID 1646 | Values: 1272.9 1558.6 1575.3 1602.1 1635.4 10499.9 10994.2 10994.7 11007.8 11010.9 10727.5 10865.9 11060.3 11071.2 11089.8 2180.9 2257.4 2332.9 2379.6 2383.0 2312.5 2349.8 2350.0 2350.7 2365.6 2310.3 2329.4 2338.8 2341.2 2357.0 2296.3 2306.5 2318.7 2325.0 2325.4 2277.3 2336.6 2338.8 2339.7 2342.7 11061.7 11172.0 11197.0 11208.5 11211.2 5696.9 5946.6 5961.9 5964.9 5968.0 3107.8 3392.4 3465.0 3568.2 3688.2 1110.8 1165.7 1213.0 1227.0 1236.9 12821.9 13805.2 14153.6 14922.5 15429.8 3538.7 4404.6 4699.0 4747.0 4750.2 9769.0 11601.9 11635.2 11789.4 11801.9 4880.6 5780.2 5839.9 5863.8 5869.9 4646.7 5195.4 5244.6 5254.2 5266.3 3650.5 3817.2 3826.3 3875.7 3899.3 1014.8 1017.1 1024.3 1026.8 1034.5 788.7 792.3 808.8 811.6 813.8 6389.3 6577.6 6750.4 6813.7 6818.0 977.4 1001.9 1007.3 1020.8 1027.2 3287.9 4135.9 4139.4 4170.2 4178.8 13364.1 13595.0 13596.5 13783.7 13808.9 11080.4 13581.5 13713.6 13736.5 13740.0 10040.0 10065.9 10094.8 10149.6 10163.8 5001.6 6437.3 6590.0 6624.5 6673.6 11278.4 12011.2 12031.6 12118.0 12246.3 12212.0 12461.9 12470.8 12619.6 12640.3 8115.7 8310.0 8396.3 8433.0 8435.9 10134.0 10146.7 10247.6 10275.3 10329.3 9047.0 9078.9 9096.6 9172.4 9189.2 7476.4 8826.2 8886.0 9049.3 9072.9 7714.1 7788.3 7790.0 7874.6 7880.9 4440.4 5630.2 5725.3 5743.9 5988.4 5518.4 5546.6 5573.7 5611.5 5658.9 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5192.47 Median:5666.25 stddev:1444.70 (25.5%) stddev-sans-first:1402.86 04:57:05 INFO - PID 1646 | Values: 379.3 383.9 393.4 395.9 397.1 4747.3 5501.2 5736.9 5842.6 5867.5 4502.6 5589.8 5594.9 5746.6 5764.4 4903.3 4963.6 4986.2 4992.7 4996.0 4043.9 4772.0 4969.3 4989.7 5009.8 4103.4 4924.5 4939.9 4954.7 4983.3 4300.7 4371.3 4687.8 4986.5 4994.9 3975.7 4442.0 4692.1 4773.8 4775.5 5648.4 5663.2 5675.8 5685.7 5713.6 4787.0 5837.7 5837.9 5842.2 5862.6 5009.5 5669.3 6090.0 6126.8 6170.9 4576.0 4829.8 4842.6 4845.5 4854.8 6240.4 6433.8 6436.6 6505.3 6554.2 4127.0 4976.1 4981.7 5003.3 5016.7 5042.2 5046.4 5058.3 5083.8 5104.2 5217.8 5455.6 5460.0 5478.6 5506.7 5483.0 6100.9 6118.7 6122.9 6152.9 1401.6 1686.5 1703.6 1729.3 1730.9 1177.3 1239.7 1278.8 1291.7 1293.9 5267.8 5549.0 5740.7 5744.4 5787.0 6249.8 6255.5 6261.1 6279.9 6297.8 4982.4 5635.4 5772.2 5809.9 5832.6 3266.9 3279.5 3288.7 3293.8 3317.9 5496.8 6113.0 6489.9 6526.6 6565.5 6423.2 6424.1 6425.5 6426.6 6452.1 5363.8 6279.7 6441.5 6521.8 6534.3 5981.2 6245.9 6328.5 6353.0 6389.6 5344.5 5655.9 6262.3 6267.8 6307.9 6328.9 6443.5 6583.0 6597.7 6614.7 4912.2 4950.4 4951.0 4973.3 4979.7 5239.3 5828.8 6122.3 6405.6 6411.8 5102.8 6096.1 6108.8 6120.9 6123.7 6084.0 6111.5 6111.8 6131.4 6148.3 5760.7 5958.4 6036.6 6041.5 6073.8 6135.2 6154.1 6168.8 6192.2 6196.9 4939.6 5734.7 5955.8 6031.3 6063.6 04:57:05 INFO - PID 1646 | 04:57:05 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:19675.01 Median:4069.60 stddev:28274.02 (694.8%) stddev-sans-first:28317.65 04:57:05 INFO - PID 1646 | Values: 754.3 759.6 813.1 818.7 823.9 14930.6 15921.4 15965.3 15997.2 16026.3 14827.8 14845.1 14852.0 14885.2 15153.8 808.7 919.9 956.0 957.3 958.5 948.7 957.8 972.1 976.6 995.8 1021.9 1029.6 1033.4 1034.8 1036.0 1015.5 1030.9 1031.3 1031.8 1033.0 757.0 763.0 765.7 769.2 769.4 16040.0 16258.9 16525.9 16545.9 16636.3 14768.6 14805.0 14815.5 14962.0 15789.3 11209.4 11597.8 11637.9 11671.8 11879.9 3552.8 3618.4 3846.2 3882.1 3890.7 110035.8 112780.0 112943.4 113919.4 116841.8 11012.3 11372.5 11770.9 11955.7 12086.9 23066.5 25019.1 25039.4 25082.0 25328.5 7699.5 7746.6 7884.6 7945.4 7983.8 4248.5 4332.8 4334.5 4462.9 4463.8 3378.3 3475.8 3476.7 3478.0 3479.6 602.6 605.8 622.5 628.1 629.0 671.9 675.7 676.8 682.5 684.6 9764.9 9844.9 9972.0 10256.7 10853.4 577.3 598.6 599.3 648.3 703.8 4620.4 4898.5 4950.4 4984.6 5020.3 95073.5 95180.5 95785.0 95912.0 96510.0 81074.0 85500.0 85890.6 87882.0 89205.0 52612.0 60129.8 61935.4 63012.4 63983.0 9218.4 9391.9 9395.8 9411.7 9489.0 48286.0 48766.0 49769.0 49911.0 50776.5 61684.0 62462.0 63275.7 63622.4 64201.7 9639.3 11303.5 11652.4 11840.9 12023.3 12635.5 12768.9 12833.5 12848.6 13199.9 11781.6 11792.9 11982.6 12005.4 12181.1 11728.9 11835.5 11923.3 12021.7 12241.7 11341.5 11563.8 11810.5 12117.9 12263.9 9984.5 10092.0 10329.9 10401.0 10961.6 7771.3 8409.4 8940.3 9320.9 10305.6 04:57:05 INFO - PID 1646 | -------- Summary: end -------- 04:57:05 INFO - PID 1646 | 04:57:06 INFO - TEST-INFO | 1646: exit 0 04:57:06 INFO - TEST-OK | dromaeo_css | took 1121147ms 04:57:06 INFO - TEST-START | kraken 04:57:06 INFO - operating with platform_type : mac_ 04:57:06 INFO - Initialising browser for kraken test... 04:57:06 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmptTGCRU/profile http://localhost:49245/getInfo.html) 04:57:08 INFO - PID 1653 | __metrics Screen width/height:1600/1200 04:57:08 INFO - PID 1653 | colorDepth:24 04:57:08 INFO - PID 1653 | Browser inner width/height: 1024/689 04:57:08 INFO - PID 1653 | __metrics 04:57:08 INFO - PID 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:57:08 INFO - PID 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:57:09 INFO - TEST-INFO | 1653: exit 0 04:57:09 INFO - Browser initialized. 04:57:09 INFO - Running cycle 1/1 for kraken test... 04:57:09 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmptTGCRU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:21 INFO - PID 1655 | RSS: Main: 234008576 04:57:21 INFO - PID 1655 | 04:57:24 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 04:57:25 INFO - PID 1655 | RSS: Main: 284536832 04:57:25 INFO - PID 1655 | 04:57:28 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 04:57:28 INFO - PID 1655 | RSS: Main: 286769152 04:57:28 INFO - PID 1655 | 04:57:32 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 04:57:32 INFO - PID 1655 | RSS: Main: 380604416 04:57:32 INFO - PID 1655 | 04:57:34 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 04:57:35 INFO - PID 1655 | RSS: Main: 284516352 04:57:35 INFO - PID 1655 | 04:57:37 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:57:38 INFO - PID 1655 | RSS: Main: 285777920 04:57:38 INFO - PID 1655 | 04:57:41 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 04:57:41 INFO - PID 1655 | RSS: Main: 326987776 04:57:41 INFO - PID 1655 | 04:57:44 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 04:57:45 INFO - PID 1655 | RSS: Main: 334544896 04:57:45 INFO - PID 1655 | 04:57:48 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 04:57:48 INFO - PID 1655 | RSS: Main: 339034112 04:57:48 INFO - PID 1655 | 04:57:50 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:57:51 INFO - PID 1655 | RSS: Main: 303370240 04:57:51 INFO - PID 1655 | 04:57:53 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:57:53 INFO - PID 1655 | RSS: Main: 307990528 04:57:53 INFO - PID 1655 | 04:57:56 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:57:56 INFO - PID 1655 | RSS: Main: 309686272 04:57:56 INFO - PID 1655 | 04:57:59 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:58:00 INFO - PID 1655 | RSS: Main: 324104192 04:58:00 INFO - PID 1655 | 04:58:03 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:58:04 INFO - PID 1655 | RSS: Main: 318554112 04:58:04 INFO - PID 1655 | 04:58:05 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 04:58:06 INFO - PID 1655 | RSS: Main: 380051456 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | __start_tp_report 04:58:06 INFO - PID 1655 | _x_x_mozilla_page_load 04:58:06 INFO - PID 1655 | _x_x_mozilla_page_load_details 04:58:06 INFO - PID 1655 | |i|pagename|runs| 04:58:06 INFO - PID 1655 | |0;ai-astar;116;115;116;114;116;117;116;115;116;115 04:58:06 INFO - PID 1655 | |1;audio-beat-detection;153;118;88;130;95;134;140;138;171;110 04:58:06 INFO - PID 1655 | |2;audio-dft;183;182;186;183;186;181;185;182;185;181 04:58:06 INFO - PID 1655 | |3;audio-fft;79;61;65;117;87;83;62;61;76;61 04:58:06 INFO - PID 1655 | |4;audio-oscillator;86;72;89;70;72;70;84;69;70;84 04:58:06 INFO - PID 1655 | |5;imaging-gaussian-blur;88;87;89;88;88;87;90;88;88;88 04:58:06 INFO - PID 1655 | |6;imaging-darkroom;141;152;96;94;95;95;95;94;94;94 04:58:06 INFO - PID 1655 | |7;imaging-desaturate;86;85;92;83;84;84;86;84;86;83 04:58:06 INFO - PID 1655 | |8;json-parse-financial;64;61;60;61;63;61;70;100;97;99 04:58:06 INFO - PID 1655 | |9;json-stringify-tinderbox;55;59;69;68;62;58;59;60;59;60 04:58:06 INFO - PID 1655 | |10;stanford-crypto-aes;84;82;81;83;83;83;100;82;82;82 04:58:06 INFO - PID 1655 | |11;stanford-crypto-ccm;139;148;153;137;138;139;138;137;187;138 04:58:06 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;182;187;186;185;184;186;183;192;184;183 04:58:06 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;67;67;68;67;68;70;68;67;68;69 04:58:06 INFO - PID 1655 | __end_tp_report 04:58:06 INFO - PID 1655 | __start_cc_report 04:58:06 INFO - PID 1655 | _x_x_mozilla_cycle_collect,2137 04:58:06 INFO - PID 1655 | __end_cc_report 04:58:06 INFO - PID 1655 | __startTimestamp1490011086010__endTimestamp 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | ------- Summary: start ------- 04:58:06 INFO - PID 1655 | Number of tests: 14 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.00 stddev:0.84 (0.7%) stddev-sans-first:0.88 04:58:06 INFO - PID 1655 | Values: 116.0 115.0 116.0 114.0 116.0 117.0 116.0 115.0 116.0 115.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:146.50 stddev:25.54 (17.4%) stddev-sans-first:25.39 04:58:06 INFO - PID 1655 | Values: 153.0 118.0 88.0 130.0 95.0 134.0 140.0 138.0 171.0 110.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.96 (1.1%) stddev-sans-first:2.07 04:58:06 INFO - PID 1655 | Values: 183.0 182.0 186.0 183.0 186.0 181.0 185.0 182.0 185.0 181.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:75.20 Median:70.50 stddev:17.78 (25.2%) stddev-sans-first:18.81 04:58:06 INFO - PID 1655 | Values: 79.0 61.0 65.0 117.0 87.0 83.0 62.0 61.0 76.0 61.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:78.00 stddev:8.04 (10.3%) stddev-sans-first:7.78 04:58:06 INFO - PID 1655 | Values: 86.0 72.0 89.0 70.0 72.0 70.0 84.0 69.0 70.0 84.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:88.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 04:58:06 INFO - PID 1655 | Values: 88.0 87.0 89.0 88.0 88.0 87.0 90.0 88.0 88.0 88.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:94.50 stddev:22.04 (23.3%) stddev-sans-first:19.14 04:58:06 INFO - PID 1655 | Values: 141.0 152.0 96.0 94.0 95.0 95.0 95.0 94.0 94.0 94.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:2.63 (3.1%) stddev-sans-first:2.77 04:58:06 INFO - PID 1655 | Values: 86.0 85.0 92.0 83.0 84.0 84.0 86.0 84.0 86.0 83.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:73.60 Median:63.50 stddev:17.54 (27.6%) stddev-sans-first:18.26 04:58:06 INFO - PID 1655 | Values: 64.0 61.0 60.0 61.0 63.0 61.0 70.0 100.0 97.0 99.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:60.00 stddev:4.38 (7.3%) stddev-sans-first:4.10 04:58:06 INFO - PID 1655 | Values: 55.0 59.0 69.0 68.0 62.0 58.0 59.0 60.0 59.0 60.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:82.50 stddev:5.61 (6.8%) stddev-sans-first:5.95 04:58:06 INFO - PID 1655 | Values: 84.0 82.0 81.0 83.0 83.0 83.0 100.0 82.0 82.0 82.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:139.00 stddev:15.57 (11.2%) stddev-sans-first:16.34 04:58:06 INFO - PID 1655 | Values: 139.0 148.0 153.0 137.0 138.0 139.0 138.0 137.0 187.0 138.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:185.50 stddev:2.86 (1.5%) stddev-sans-first:2.79 04:58:06 INFO - PID 1655 | Values: 182.0 187.0 186.0 185.0 184.0 186.0 183.0 192.0 184.0 183.0 04:58:06 INFO - PID 1655 | 04:58:06 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 04:58:06 INFO - PID 1655 | Values: 67.0 67.0 68.0 67.0 68.0 70.0 68.0 67.0 68.0 69.0 04:58:06 INFO - PID 1655 | -------- Summary: end -------- 04:58:06 INFO - PID 1655 | 04:58:06 INFO - TEST-INFO | 1655: exit 0 04:58:06 INFO - TEST-OK | kraken | took 60453ms 04:58:06 INFO - SUITE-END | took 1181s 04:58:07 INFO - Completed test suite (00:19:41) 04:58:07 INFO - javascript benchmark 04:58:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7814.956097246719, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.815155166465, 3366.755771084459, 3368.949465758014, 3411.385950528905, 3433.7939723616587, 5754.769809207637, 5820.679862607555, 5836.533077353812, 5881.264841894763, 5913.349531551526, 5776.653400795955, 5805.854853628651, 5889.499392551627, 5920.99343111671, 5926.585139040269, 5172.896542069157, 5241.449647786984, 5259.631825772198, 5265.394479634833, 5300.284461597693, 5349.8127565535215, 5378.596605254602, 5391.595630327722, 5397.54120899724, 5457.781688732446, 5203.947960520384, 5258.0, 5262.789488420459, 5295.0, 5339.145736682131, 4422.911541769179, 5288.576913846883, 5307.840764777063, 5317.255584218213, 5371.435999220103, 5235.58115350771, 5257.158854583248, 5274.393444753864, 5288.0745869472685, 5353.304070470853, 5671.035923892948, 5745.368009518949, 5853.824385268449, 5855.882882342329, 5872.618279811842, 6223.719932603044, 6310.242770867525, 6352.301246862841, 6355.142055822497, 6363.840903977438, 6225.688715564249, 6372.330905254974, 6380.1705778249, 6393.520485963525, 6402.647854368008, 5931.08068249422, 5978.8505287367725, 5984.461398474158, 5994.520438364964, 5996.610220335664, 6331.113644089831, 6352.841178970562, 6393.0, 6403.711832967528, 6417.807465776034, 6103.071896073912, 6142.775043102318, 6199.977003794414, 6264.906026409608, 6301.8109456716375, 6254.0, 6376.585521941059, 6381.266154392304, 6385.074164246158, 6393.9360606394275, 6343.809685709436, 6359.141515895388, 6363.3636636336, 6364.299927008025, 6364.522660800459, 6216.129741836146, 6292.2134733158355, 6303.338149494329, 6349.2698339690605, 6366.236051673829, 5051.570616497639, 5068.974655126701, 5087.847364579068, 5099.36257967754, 5119.769610367543, 4486.551344865487, 4500.662450316239, 4515.909681806345, 4517.209488339593, 4554.931676024862, 5738.942610573841, 5998.670073145931, 6022.427869352405, 6024.728887200086, 6029.698515074228, 5777.422257774273, 5903.793367232126, 6295.622262664255, 6310.968445157837, 6331.461825744771, 5822.854428639318, 5879.088741245114, 5887.528997680175, 5887.646741195542, 5901.616394934315, 5520.475554822286, 5522.088855338824, 5526.281583394213, 5527.032769265363, 5527.032769265363, 6446.709898054599, 6451.225852897683, 6453.838654033687, 6455.386738259858, 6456.386643268882, 6352.491800656029, 6357.078223657636, 6358.459530939922, 6363.236411630449, 6373.044043393528, 5468.699221542854, 6429.7106630200815, 6465.2564955030775, 6547.148870646785, 6566.212054553484, 6305.0, 6307.3377295383325, 6309.432151106423, 6313.6843157841, 6330.177076979963, 6213.9067913980425, 6331.556791024584, 6348.619082854859, 6363.045543168561, 6363.649999250086, 6099.176611157614, 6121.54443000217, 6170.136180934763, 6195.535334849814, 6216.316205217513, 6217.595856269328, 6221.5333849960525, 6236.095766113797, 6255.343188965093, 6257.2491301044165, 5963.13534537481, 6110.633361998294, 6113.938860611337, 6116.602420842631, 6140.385961403824, 6087.908681369871, 6112.807455516588, 6120.9652521188555, 6193.535484838745, 6240.843978900382, 6079.483243924316, 6087.4521293083835, 6095.146679464967, 6100.267967843887, 6129.295131059986], "value": 5807.579818667035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.8711431942252, 776.6155752902313, 795.490885833067, 866.9783255418614, 879.8284334554764, 27741.445171096566, 37608.62391376086, 37738.622613773885, 37809.054773630676, 37938.810305948435, 39255.0, 39429.211415771664, 39514.40728389077, 39527.604723952754, 39554.0, 7152.928470715305, 8869.60086796094, 9105.63577456901, 9181.678641247558, 9208.49353285569, 7300.087489063867, 10636.734081647943, 10844.240903136784, 10852.457377131152, 10852.891471085306, 10911.018008379244, 10987.945060274687, 10990.560377584927, 11014.229003969725, 11194.776104477905, 7122.5726456412785, 7329.0, 10997.285176463543, 11086.722831929226, 11103.222774405795, 7239.782806515813, 7291.380232680229, 7319.414446844293, 7319.5242309249725, 10442.895571044268, 37451.87644370673, 37709.6801611945, 37749.62250377489, 37793.62206377956, 37846.81076594627, 3704.759190652599, 3800.657940785343, 3849.2301539692176, 3849.4225866120028, 4024.3963405489117, 23069.385143039995, 27536.58695119575, 28429.431411371657, 28603.0, 28721.994730184553, 2692.5018871508832, 3193.7285330746913, 3256.316173603522, 3258.7393008559498, 3263.233140212048, 368946.0, 371953.1402342998, 372495.0, 372520.1373993113, 373645.0, 53684.19473707898, 53980.0, 53986.0, 54075.45924540783, 54078.64803379947, 55351.0, 55635.88728225413, 55716.0, 55721.72139139359, 55790.0, 14839.554813355617, 14958.551243462714, 14967.0, 14971.0, 14977.775333370008, 10208.336458130198, 10331.896681033244, 12111.152219344562, 12133.817992730115, 12161.452734626962, 13539.661508462368, 13752.0, 13766.65583360404, 13772.24252666093, 13988.300584970711, 4947.604191664658, 5467.781288748405, 5765.135229715493, 6101.664408457489, 6102.572819902653, 894.8120894612074, 917.8256131335025, 948.374073111743, 950.3822515365068, 986.2553771902311, 25167.37081572975, 26571.468570628866, 27431.86284068567, 27894.442111157663, 28237.29406764806, 838.8741688746737, 1016.4307987527009, 1052.1109662335314, 1061.4851796878509, 1064.9307794993301, 13774.03581749288, 14229.85770142306, 14505.0, 14510.533209354224, 14514.564563063124, 468729.0, 471647.0, 472447.0, 473044.0, 473468.0, 57199.0, 57755.711221444464, 57779.0, 57801.71099144477, 58035.0, 1364.3178410794603, 1444.812174417319, 1448.1528305941142, 1457.9635509112356, 1564.7261729197344, 25869.48261034731, 26614.467710646066, 27210.72789272161, 27356.452870942867, 27530.724692753618, 42793.35809962916, 42807.571924281605, 42822.57177428186, 42899.356509651756, 42935.0, 440898.0, 442490.0, 442757.0, 442765.0, 443612.0, 33456.832715836266, 33791.662083378855, 34394.48408273828, 34429.65570344365, 34489.65510344865, 41933.58066419297, 42863.35704964366, 42970.57029429666, 43145.568544315414, 43341.34987975246, 28007.57988630213, 28075.0, 33040.66959330472, 33943.4908476378, 34596.0, 34209.0, 34625.15159765324, 34933.0, 35411.46882796709, 35523.28953420969, 35095.47356789599, 35821.82089089538, 35829.64170358263, 35873.28253434969, 36128.819355903055, 28815.40009764541, 28843.990460334626, 31112.68887311098, 31161.0, 31254.0, 23390.0, 23518.176863809684, 23677.816109194126, 23874.880625597456, 23876.880615596812], "value": 18563.550286561236, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.44556729444224, 404.11902053523306, 419.79430079261164, 422.98519551815673, 438.89247134452063, 13369.465221391145, 15264.69470610587, 21629.242976495825, 21806.45483862904, 21839.672404913937, 22685.0, 23077.769222307772, 23217.18739844106, 23331.533369332647, 23510.647340289866, 6186.164867742865, 6811.421029212523, 6866.656667166647, 6897.3447522485285, 6898.37914587687, 6631.3700198481065, 6661.600303981777, 6666.133402657648, 6752.763653272136, 6772.661366931658, 6561.245456772461, 6741.460683145337, 6748.4938629602975, 6756.2568117507035, 6775.254721980578, 6389.233292004943, 6665.566738162004, 6692.899606505857, 6703.430208432289, 6727.831804204885, 6516.446102081281, 6519.250286217099, 6540.607563546202, 7069.151701795818, 7358.448116391292, 27392.45215095707, 27396.45207095867, 27753.0, 27820.443591128267, 28169.57745633817, 2849.401625658614, 2948.8967886123883, 2977.240803595091, 2980.0463851567506, 2980.910572682801, 15784.842151578452, 16152.757708634379, 16372.0, 16478.75281870773, 17157.656846862992, 1927.1809480970671, 1985.6128055029276, 1991.571812060414, 2005.6891181866833, 2080.0951586060114, 162043.18978405034, 162960.18519907442, 167325.8168545796, 168047.0, 171911.0, 17276.913615432095, 21900.780992190194, 21979.560408791735, 22034.779652203593, 22401.439964000703, 37676.0, 37690.62309376926, 37779.4333085004, 37889.43165852514, 37906.62093379086, 10409.635662751767, 10655.094316982988, 10806.459677016117, 10836.187285953585, 11035.44822758859, 8686.0, 8720.345974051972, 8735.257503112307, 8898.332625053146, 8925.0, 8772.956135219283, 9434.726311947936, 10012.949935250277, 10346.586136554453, 10538.525766340534, 3530.541029666127, 3732.570754363229, 4043.8989025274013, 4095.4266402703647, 4233.872983810491, 1002.2433063037506, 1006.7634105985088, 1010.6109147977934, 1027.9537420816082, 1031.654395777423, 20053.197872084955, 22592.548149036927, 23135.652965205536, 23161.420964476023, 23182.3045308641, 999.7200783780553, 1009.9444530550891, 1034.1158309645296, 1036.9792604147874, 1039.7556574205055, 9257.305702072372, 11149.554017839195, 11720.359149719046, 11953.282803031845, 11955.462004209832, 684889.0, 694925.0, 701285.0, 730832.0, 740324.0, 144858.27570862288, 151735.0, 161092.0, 166383.0, 166736.16631916762, 1235.4008305971597, 1257.9874201257871, 1305.4712841299288, 1315.7302752935816, 1331.4141777617817, 19351.322703705304, 20859.8957005214, 21855.45361365915, 21874.781252187273, 21928.0, 49209.26186107283, 62124.689376554634, 62387.0, 62813.37186628075, 63308.36691633209, 88849.0, 89980.5500972491, 90230.0, 90337.19325613583, 91151.0, 11743.647690569296, 12344.3827808611, 12438.937805310916, 12441.0, 12495.375971046182, 12796.04029697795, 13539.458421663418, 13645.249511276976, 14135.222562759147, 14169.504067357593, 10214.285000049926, 11667.183297169116, 11991.280523168638, 12094.75810483803, 12169.513219471122, 11461.083113351078, 11595.478203480694, 11735.882641173479, 11875.287482751062, 11935.522579097085, 9985.650502232387, 11250.099992000783, 11585.304881707125, 12099.33453660057, 12230.266184028987, 11556.422178890854, 12129.332886691316, 12470.937645311715, 12513.562025329069, 12634.178778379375, 11714.707132321419, 11776.941115294369, 12122.212056216318, 12137.69655758613, 12186.634400967985], "value": 11906.803444182873, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1272.9299888506127, 1558.5791836204219, 1575.3462313140049, 1602.1268408717249, 1635.3622087385918, 10499.947500262497, 10994.230403871723, 10994.725131871708, 11007.779844403107, 11010.944945275263, 10727.517261723222, 10865.891341086586, 11060.281081729681, 11071.16966227532, 11089.8336524952, 2180.945476363088, 2257.3679369776487, 2332.8600283982973, 2379.631157170633, 2382.9880850595723, 2312.4681323295567, 2349.7767712067325, 2349.988250058756, 2350.670906073151, 2365.562370961369, 2310.295359915225, 2329.440934175794, 2338.7895089441945, 2341.16888504581, 2356.9882150589224, 2296.2996286132725, 2306.4695120122383, 2318.652202169671, 2324.9883750581307, 2325.383773300077, 2277.259890535566, 2336.5794157051723, 2338.7661233876474, 2339.7309309429465, 2342.6954495915593, 11061.723456913562, 11171.994520493196, 11197.048250898684, 11208.495617697141, 11211.215214934959, 5696.857578560528, 5946.554008449383, 5961.8807623847715, 5964.880702385972, 5968.0, 3107.7979931304394, 3392.4402473591826, 3464.9653503465147, 3568.1971556399862, 3688.1517251032146, 1110.7523409751357, 1165.7409997203001, 1213.0416970593221, 1226.9815952760716, 1236.931968741719, 12821.871781282254, 13805.171689698549, 14153.646158846112, 14922.477713280092, 15429.845701542954, 3538.663826936437, 4404.647628189737, 4698.976505117453, 4746.952530474651, 4750.239961606127, 9769.0, 11601.941990289995, 11635.185537012328, 11789.351585662871, 11801.881981180079, 4880.609551235963, 5780.248567686174, 5839.941600583939, 5863.794767183128, 5869.882602347929, 4646.697964632288, 5195.428502864682, 5244.580433565306, 5254.15933450646, 5266.34170728659, 3650.5071815305123, 3817.2365526894732, 3826.2921359548573, 3875.6899448044087, 3899.29812633723, 1014.8274793285159, 1017.1252722658508, 1024.283001898674, 1026.830572955454, 1034.5499707627207, 788.7239466186903, 792.2671528835918, 808.7625932323555, 811.6469335838929, 813.8046868751456, 6389.265234498, 6577.585819048927, 6750.3924646782025, 6813.746371714679, 6818.0, 977.3842479238034, 1001.8697569315942, 1007.3099926550336, 1020.8417695257099, 1027.1936529824052, 3287.8684852606107, 4135.870026936131, 4139.3583994480905, 4170.207660544487, 4178.770167640809, 13364.064515483822, 13595.0, 13596.524121655695, 13783.655408614864, 13808.895288376905, 11080.390578518098, 13581.524646637321, 13713.588592342046, 13736.450541978209, 13740.0, 10040.0, 10065.949670251603, 10094.848577271347, 10149.594016239416, 10163.847542286872, 5001.649884508195, 6437.324080971431, 6590.0, 6624.470042396693, 6673.566218195801, 11278.379689117177, 12011.159218854596, 12031.578894738643, 12118.03055755555, 12246.326452045223, 12212.0, 12461.93769031149, 12470.750584988067, 12619.558315458915, 12640.30478323701, 8115.675372985014, 8310.044344900172, 8396.286315662992, 8432.957835210786, 8435.873461897954, 10134.0, 10146.746331341776, 10247.641332553325, 10275.332103413255, 10329.328593641389, 9046.954765226132, 9078.909210907806, 9096.636134554543, 9172.35793494449, 9189.218916392181, 7476.4018878488505, 8826.249768769727, 8886.0, 9049.321300902327, 9072.863907041532, 7714.074311082706, 7788.299053085023, 7789.961050194713, 7874.645640946285, 7880.881786773318, 4440.378347031483, 5630.23991761107, 5725.2843394575675, 5743.942560574341, 5988.431099045583, 5518.392976772471, 5546.639468434539, 5573.749181286771, 5611.466910643482, 5658.915116273177], "value": 5012.941168696036, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.2509793158512, 383.8618097484906, 393.4118492853185, 395.8871721559356, 397.1302846765583, 4747.2879068139755, 5501.174823776426, 5736.913946290799, 5842.561807864411, 5867.530597552196, 4502.639788816895, 5589.776408943648, 5594.888102237953, 5746.597738158331, 5764.394738552444, 4903.338049363335, 4963.553280204799, 4986.227134794112, 4992.728308619569, 4996.0, 4043.919121617566, 4772.0, 4969.329140566031, 4989.675671081368, 5009.799608015675, 4103.363978583325, 4924.507549245082, 4939.901201975958, 4954.7027178369235, 4983.302337672729, 4300.741955482666, 4371.322445021026, 4687.812487500496, 4986.5013498650205, 4994.875128121826, 3975.741576797499, 4441.955580444186, 4692.131955588227, 4773.80904763809, 4775.474697783241, 5648.350439699446, 5663.235463212496, 5675.829725108253, 5685.744141513642, 5713.6000479966415, 4786.952130478686, 5837.708114594253, 5837.9124313135335, 5842.211301474332, 5862.648241105505, 5009.499050094962, 5669.262995810518, 6089.995150800112, 6126.785562505291, 6170.907436388457, 4575.95424045762, 4829.758512074382, 4842.6440596633, 4845.466998630148, 4854.83008094715, 6240.4071613196675, 6433.818018288151, 6436.645984470852, 6505.284418713938, 6554.180727409074, 4126.979365103215, 4976.129177393942, 4981.726005069756, 5003.324551185616, 5016.749162541858, 5042.226775273658, 5046.44489106198, 5058.291839142523, 5083.847484575469, 5104.183330667075, 5217.8173763918085, 5455.5908306877145, 5459.962607104638, 5478.561715062785, 5506.724663766796, 5483.0, 6100.905743703968, 6118.694065296806, 6122.938770612326, 6152.938470615237, 1401.5515035188641, 1686.468762339859, 1703.6166862456096, 1729.3082766893344, 1730.9048002359739, 1177.3406892140429, 1239.6838806104386, 1278.769821432151, 1291.6641673165045, 1293.8614019662825, 5267.815626453055, 5548.9722551386985, 5740.741666625012, 5744.362856585853, 5787.045137552341, 6249.843753906098, 6255.499560035186, 6261.092141639437, 6279.90101732195, 6297.842553936097, 4982.3522942018035, 5635.380108188096, 5772.220750198753, 5809.883802324014, 5832.591718579743, 3266.9019929402157, 3279.5080737889507, 3288.7369010479583, 3293.8260747906747, 3317.9004629861142, 5496.790706044664, 6113.013270686955, 6489.902651460232, 6526.64103474304, 6565.540412171198, 6423.197100362455, 6424.13274207976, 6425.453836423862, 6426.646534440651, 6452.064380685775, 5363.758630861698, 6279.654618995908, 6441.516886233551, 6521.836954076091, 6534.281229064799, 5981.162637230704, 6245.875082498415, 6328.493720502257, 6352.968235158794, 6389.648569328731, 5344.510611086551, 5655.886882262414, 6262.279837818709, 6267.843303917439, 6307.936920630735, 6328.89244382222, 6443.484521238196, 6583.0, 6597.736090556324, 6614.669266536558, 4912.238603016538, 4950.430700469348, 4951.0, 4973.254011898244, 4979.701217926936, 5239.345081864767, 5828.76684932598, 6122.2959359674205, 6405.615663060231, 6411.839704007437, 5102.770375333044, 6096.146539484387, 6108.815461408949, 6120.908186377119, 6123.693815309128, 6084.0, 6111.5332320243715, 6111.786087487095, 6131.448169664752, 6148.262208535004, 5760.703841635766, 5958.404159584007, 6036.577439579363, 6041.456268935641, 6073.757049717962, 6135.233095863017, 6154.107654390178, 6168.784092556739, 6192.195014648067, 6196.907046394217, 4939.60483161339, 5734.713264336683, 5955.771402304413, 6031.276246850231, 6063.575549711478], "value": 4755.1532463500525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.3135746470713, 759.5708424740021, 813.1096449387921, 818.7420962396844, 823.9217274358941, 14930.626734331634, 15921.36314547418, 15965.281562329694, 15997.200139993012, 16026.278817453214, 14827.77758333626, 14845.109293442401, 14852.0, 14885.181315027696, 15153.848461515408, 808.6724876425028, 919.8574220995739, 956.0105291023792, 957.3490026781785, 958.5494817435793, 948.7248697877642, 957.7701351675582, 972.0813830929774, 976.5605477535138, 995.8207522645921, 1021.8518314844343, 1029.583018877356, 1033.3799720167876, 1034.824079906414, 1036.0, 1015.5024038221255, 1030.932989355697, 1031.339942436841, 1031.8245898197288, 1033.0, 756.9659365328573, 762.9656665450068, 765.6669348833275, 769.1692971590669, 769.365273649236, 16040.0, 16258.918705406397, 16525.91737041319, 16545.91727041357, 16636.251368688438, 14768.630784230372, 14805.0, 14815.48145814902, 14962.0, 15789.28948197334, 11209.383483908381, 11597.826032609517, 11637.941810290979, 11671.824922626083, 11879.881201187964, 3552.7513074084823, 3618.3848745713294, 3846.2499812536566, 3882.107115363454, 3890.6692931100606, 110035.79928401468, 112780.0, 112943.43528282388, 113919.43040284829, 116841.83158168479, 11012.339259644446, 11372.54509819598, 11770.882291176978, 11955.701107472209, 12086.87913120875, 23066.53866922652, 25019.124330648705, 25039.374015649755, 25082.0, 25328.493430131293, 7699.499532530368, 7746.573938433327, 7884.64519096642, 7945.364370850318, 7983.800404989922, 4248.532661407243, 4332.819416123775, 4334.544872788375, 4462.888427789267, 4463.799129039201, 3378.273671160712, 3475.8088305142956, 3476.652334766554, 3477.965220347815, 3479.5824501060033, 602.5691630484225, 605.8424809549549, 622.5299898576545, 628.0516420205431, 628.9685515724287, 671.9227288861844, 675.6857465085465, 676.8104930619435, 682.5392859819602, 684.6474065856088, 9764.853527197098, 9844.852327215098, 9972.0, 10256.692299231036, 10853.40306283162, 577.2812848004262, 598.5630489742425, 599.2808629644444, 648.2514140046264, 703.8486725354073, 4620.448937237172, 4898.510148985073, 4950.356453661073, 4984.601231901439, 5020.322256495399, 95073.52463237778, 95180.52409737906, 95785.0, 95912.0, 96510.0, 81074.0, 85500.0, 85890.57054714687, 87882.0, 89205.0, 52612.0, 60129.79740405167, 61935.38064619296, 63012.36987630248, 63983.0, 9218.354715169873, 9391.859122113037, 9395.812083758423, 9411.717648470556, 9488.95255523718, 48286.0, 48766.0, 49769.0, 49911.00177996492, 50776.476705698886, 61684.0, 62462.0, 63275.6836215816, 63622.36377636164, 64201.67899160474, 9639.277054221102, 11303.547858085913, 11652.41737913084, 11840.940795295968, 12023.27860328383, 12635.494580216688, 12768.872311276768, 12833.486660533474, 12848.614541563767, 13199.868001319865, 11781.587644432746, 11792.882071179178, 11982.58060967862, 12005.39973001329, 12181.147319687538, 11728.94135529317, 11835.45346115651, 11923.284602923852, 12021.699457513632, 12241.693957650774, 11341.546338146713, 11563.826542601699, 11810.527578896748, 12117.878821211674, 12263.877361226274, 9984.500774961078, 10091.962259644, 10329.9483502582, 10401.011903869121, 10961.616343427942, 7771.2799229523325, 8409.369297302605, 8940.329475289509, 9320.860187097063, 10305.639302624371], "value": 7444.117257672359, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1474.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 114.0, 116.0, 117.0, 116.0, 115.0, 116.0, 115.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 88.0, 130.0, 95.0, 134.0, 140.0, 138.0, 171.0, 110.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 186.0, 183.0, 186.0, 181.0, 185.0, 182.0, 185.0, 181.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 61.0, 65.0, 117.0, 87.0, 83.0, 62.0, 61.0, 76.0, 61.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 89.0, 70.0, 72.0, 70.0, 84.0, 69.0, 70.0, 84.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 88.0, 88.0, 87.0, 90.0, 88.0, 88.0, 88.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 152.0, 96.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 92.0, 83.0, 84.0, 84.0, 86.0, 84.0, 86.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 60.0, 61.0, 63.0, 61.0, 70.0, 100.0, 97.0, 99.0], "value": 73.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 69.0, 68.0, 62.0, 58.0, 59.0, 60.0, 59.0, 60.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 81.0, 83.0, 83.0, 83.0, 100.0, 82.0, 82.0, 82.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 148.0, 153.0, 137.0, 138.0, 139.0, 138.0, 137.0, 187.0, 138.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 186.0, 185.0, 184.0, 186.0, 183.0, 192.0, 184.0, 183.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 68.0, 70.0, 68.0, 67.0, 68.0, 69.0], "value": 67.9, "unit": "score"}]}]} 04:58:07 INFO - Return code: 0 04:58:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:58:07 ERROR - # TBPL SUCCESS # 04:58:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:58:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:58:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:58:07 INFO - 2017-03-20 04:58:07.320 screenresolution[1659:9978] starting screenresolution argv=screenresolution get 04:58:07 INFO - 2017-03-20 04:58:07.324 screenresolution[1659:9978] Display 0: 1600x1200x32@60 04:58:07 INFO - 2017-03-20 04:58:07.330 screenresolution[1660:9981] starting screenresolution argv=screenresolution list 04:58:07 INFO - Available Modes on Display 0 04:58:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:58:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:58:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:58:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:58:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:58:07 INFO - Intel Iris: 04:58:07 INFO - Chipset Model: Intel Iris 04:58:07 INFO - Type: GPU 04:58:07 INFO - Bus: Built-In 04:58:07 INFO - VRAM (Dynamic, Max): 1536 MB 04:58:07 INFO - Vendor: Intel (0x8086) 04:58:07 INFO - Device ID: 0x0a2e 04:58:07 INFO - Revision ID: 0x0009 04:58:07 INFO - Displays: 04:58:07 INFO - Display: 04:58:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:58:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:58:07 INFO - Display Serial Number: 1600x1200 60 04:58:07 INFO - Main Display: Yes 04:58:07 INFO - Mirror: Off 04:58:07 INFO - Online: Yes 04:58:07 INFO - Rotation: Supported 04:58:07 INFO - Return code: 0 04:58:07 INFO - Running post-action listener: _resource_record_post_action 04:58:07 INFO - [mozharness: 2017-03-20 11:58:07.566405Z] Finished run-tests step (success) 04:58:07 INFO - Running post-run listener: _resource_record_post_run 04:58:07 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 32697344; Write bytes: 510793728; Read time: 202; Write time: 1591 04:58:07 INFO - TinderboxPrint: CPU usage
53.3% 04:58:07 INFO - TinderboxPrint: I/O read bytes / time
32,697,344 / 202 04:58:07 INFO - TinderboxPrint: I/O write bytes / time
510,793,728 / 1,591 04:58:07 INFO - TinderboxPrint: CPU idle
2,245.4 (46.7%) 04:58:07 INFO - TinderboxPrint: CPU system
956.0 (19.9%) 04:58:07 INFO - TinderboxPrint: CPU user
1,608.2 (33.4%) 04:58:07 INFO - TinderboxPrint: Swap in / out
238,891,008 / 0 04:58:07 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203805184; Write bytes: 201445376; Read time: 15674; Write time: 432 04:58:07 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 30911488; Write bytes: 298534912; Read time: 188; Write time: 1123 04:58:08 INFO - Running post-run listener: _upload_blobber_files 04:58:08 INFO - Blob upload gear active. 04:58:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:58:08 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 04:58:08 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'] 04:58:08 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 04:58:08 INFO - (blobuploader) - INFO - Open directory for files ... 04:58:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:58:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:58:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:58:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:58:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:58:09 INFO - (blobuploader) - INFO - Done attempting. 04:58:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:58:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:58:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:58:10 INFO - (blobuploader) - INFO - Done attempting. 04:58:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:58:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:58:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:58:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:58:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:58:11 INFO - (blobuploader) - INFO - Done attempting. 04:58:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:58:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:58:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:58:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:58:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:58:13 INFO - (blobuploader) - INFO - Done attempting. 04:58:13 INFO - (blobuploader) - INFO - Iteration through files over. 04:58:13 INFO - Return code: 0 04:58:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:58:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:58:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc03b414830c90aa2b84fc09c4a5caa7a262dca7a7c42b0cdd728cd6dc5a2369de1d3b77b43523ea0999472b013f945dc25f70acbd5632a41494c9a0ddaab2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6230e2d1c91301e6f7cd460bd6c36593eaee4bf87fbeaa4c3e04783a6560916da01185ce2d2fb4dd57683c8bd04e268df39bd5a0b0162f2e65ea5325381399cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27966ef03a9d33a0694a6ce40e482144aec5a1b2ea5cf0914063062cbca78b2777b5f4516f60ad8382cb7e7cdb9bd6c4e77888a44b4f71ae1a271a3be376911a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faddfe9cd31ab31eb89d59382a40b552548335a55664750f4555c31f0a95d96475c25025495089d9fb6d5143dd65cd6d63f15dfd8624a7ce1313a96b5852b75"} 04:58:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:58:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:58:13 INFO - Contents: 04:58:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc03b414830c90aa2b84fc09c4a5caa7a262dca7a7c42b0cdd728cd6dc5a2369de1d3b77b43523ea0999472b013f945dc25f70acbd5632a41494c9a0ddaab2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6230e2d1c91301e6f7cd460bd6c36593eaee4bf87fbeaa4c3e04783a6560916da01185ce2d2fb4dd57683c8bd04e268df39bd5a0b0162f2e65ea5325381399cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27966ef03a9d33a0694a6ce40e482144aec5a1b2ea5cf0914063062cbca78b2777b5f4516f60ad8382cb7e7cdb9bd6c4e77888a44b4f71ae1a271a3be376911a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faddfe9cd31ab31eb89d59382a40b552548335a55664750f4555c31f0a95d96475c25025495089d9fb6d5143dd65cd6d63f15dfd8624a7ce1313a96b5852b75"} 04:58:13 INFO - Running post-run listener: copy_logs_to_upload_dir 04:58:13 INFO - Copying logs to upload dir... 04:58:13 INFO - mkdir: /builds/slave/test/build/upload/logs 04:58:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1354.906062 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 35 secs) (at 2017-03-20 04:58:14.191835) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 04:58:14.194935) ========= 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.cdEJq2er9w/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.44Mq868gYD/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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/fc03b414830c90aa2b84fc09c4a5caa7a262dca7a7c42b0cdd728cd6dc5a2369de1d3b77b43523ea0999472b013f945dc25f70acbd5632a41494c9a0ddaab2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6230e2d1c91301e6f7cd460bd6c36593eaee4bf87fbeaa4c3e04783a6560916da01185ce2d2fb4dd57683c8bd04e268df39bd5a0b0162f2e65ea5325381399cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27966ef03a9d33a0694a6ce40e482144aec5a1b2ea5cf0914063062cbca78b2777b5f4516f60ad8382cb7e7cdb9bd6c4e77888a44b4f71ae1a271a3be376911a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faddfe9cd31ab31eb89d59382a40b552548335a55664750f4555c31f0a95d96475c25025495089d9fb6d5143dd65cd6d63f15dfd8624a7ce1313a96b5852b75"} build_url:https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc03b414830c90aa2b84fc09c4a5caa7a262dca7a7c42b0cdd728cd6dc5a2369de1d3b77b43523ea0999472b013f945dc25f70acbd5632a41494c9a0ddaab2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6230e2d1c91301e6f7cd460bd6c36593eaee4bf87fbeaa4c3e04783a6560916da01185ce2d2fb4dd57683c8bd04e268df39bd5a0b0162f2e65ea5325381399cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27966ef03a9d33a0694a6ce40e482144aec5a1b2ea5cf0914063062cbca78b2777b5f4516f60ad8382cb7e7cdb9bd6c4e77888a44b4f71ae1a271a3be376911a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faddfe9cd31ab31eb89d59382a40b552548335a55664750f4555c31f0a95d96475c25025495089d9fb6d5143dd65cd6d63f15dfd8624a7ce1313a96b5852b75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 04:58:14.229155) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:58:14.229439) ========= 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.cdEJq2er9w/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.44Mq868gYD/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.004889 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:58:14.277067) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 04:58:14.277334) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 04:58:14.277618) ========= ========= Total master_lag: 0.35 =========