builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0052 starttime: 1493995291.15 results: success (0) buildid: 20170505062208 builduid: 586a921cc1c0408a9b9f976167bc0219 revision: 9348b76977e833f108cf77dff75b0fab887a2fc1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.151312) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.151764) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.152048) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.179786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.180250) ========= 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.3QUlVIXnyu/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.hirS2HsMT3/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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-05-05 07:41:31-- 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% 12.0M=0.001s 2017-05-05 07:41:31 (12.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.292347 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.498776) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.499097) ========= 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.3QUlVIXnyu/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.hirS2HsMT3/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.027756 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:31.550617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 07:41:31.551080) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9348b76977e833f108cf77dff75b0fab887a2fc1 --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 9348b76977e833f108cf77dff75b0fab887a2fc1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3QUlVIXnyu/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.hirS2HsMT3/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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-05-05 07:41:31,653 truncating revision to first 12 chars 2017-05-05 07:41:31,653 Setting DEBUG logging. 2017-05-05 07:41:31,653 attempt 1/10 2017-05-05 07:41:31,653 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9348b76977e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 07:41:33,148 unpacking tar archive at: mozilla-central-9348b76977e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.772905 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 07:41:33.349459) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:33.349797) ========= script_repo_revision: 9348b76977e833f108cf77dff75b0fab887a2fc1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:33.350232) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:33.350508) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 07:41:33.371381) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-05 07:41:33.371729) ========= /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.3QUlVIXnyu/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.hirS2HsMT3/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:41:33 INFO - MultiFileLogger online at 20170505 07:41:33 in /builds/slave/test 07:41:33 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 07:41:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:41:33 INFO - {'append_to_log': False, 07:41:33 INFO - 'base_work_dir': '/builds/slave/test', 07:41:33 INFO - 'blob_upload_branch': 'Firefox', 07:41:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:41:33 INFO - 'branch': 'Firefox', 07:41:33 INFO - 'buildbot_json_path': 'buildprops.json', 07:41:33 INFO - 'config_files': ('talos/mac_config.py',), 07:41:33 INFO - 'default_actions': ('clobber', 07:41:33 INFO - 'read-buildbot-config', 07:41:33 INFO - 'download-and-extract', 07:41:33 INFO - 'populate-webroot', 07:41:33 INFO - 'create-virtualenv', 07:41:33 INFO - 'install', 07:41:33 INFO - 'run-tests'), 07:41:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:41:33 INFO - 'download_minidump_stackwalk': True, 07:41:33 INFO - 'download_symbols': 'ondemand', 07:41:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:41:33 INFO - 'tooltool.py': '/tools/tooltool.py', 07:41:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:41:33 INFO - '/tools/misc-python/virtualenv.py')}, 07:41:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:41:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:41:33 INFO - 'gecko_profile': False, 07:41:33 INFO - 'gecko_profile_interval': 0, 07:41:33 INFO - 'installer_path': 'installer.exe', 07:41:33 INFO - 'log_level': 'info', 07:41:33 INFO - 'log_name': 'talos', 07:41:33 INFO - 'log_to_console': True, 07:41:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:41:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:41:33 INFO - 'opt_config_files': (), 07:41:33 INFO - 'pip_index': False, 07:41:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:41:33 INFO - 'cmd': ('bash', 07:41:33 INFO - '-c', 07:41:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:41:33 INFO - 'enabled': True, 07:41:33 INFO - 'halt_on_failure': False, 07:41:33 INFO - 'name': 'check_screen_resolution'},), 07:41:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:41:33 INFO - 'cmd': ('bash', 07:41:33 INFO - '-c', 07:41:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:41:33 INFO - 'enabled': True, 07:41:33 INFO - 'halt_on_failure': False, 07:41:33 INFO - 'name': 'check_screen_resolution'},), 07:41:33 INFO - 'run_cmd_checks_enabled': True, 07:41:33 INFO - 'suite': 'dromaeojs', 07:41:33 INFO - 'system_bits': '32', 07:41:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:41:33 INFO - 'title': 't-yosemite-r7-0052', 07:41:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:41:33 INFO - 'use_talos_json': True, 07:41:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:41:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:41:33 INFO - 'work_dir': 'build'} 07:41:33 INFO - [mozharness: 2017-05-05 14:41:33.578955Z] Running clobber step. 07:41:33 INFO - Running pre-action listener: _resource_record_pre_action 07:41:33 INFO - Running main action method: clobber 07:41:33 INFO - rmtree: /builds/slave/test/build 07:41:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:41:36 INFO - Running post-action listener: _resource_record_post_action 07:41:36 INFO - [mozharness: 2017-05-05 14:41:36.008611Z] Finished clobber step (success) 07:41:36 INFO - [mozharness: 2017-05-05 14:41:36.008735Z] Running read-buildbot-config step. 07:41:36 INFO - Running pre-action listener: _resource_record_pre_action 07:41:36 INFO - Running main action method: read_buildbot_config 07:41:36 INFO - Using buildbot properties: 07:41:36 INFO - { 07:41:36 INFO - "project": "", 07:41:36 INFO - "product": "firefox", 07:41:36 INFO - "script_repo_revision": "production", 07:41:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:41:36 INFO - "repository": "", 07:41:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:41:36 INFO - "buildid": "20170505062208", 07:41:36 INFO - "pgo_build": "False", 07:41:36 INFO - "basedir": "/builds/slave/test", 07:41:36 INFO - "buildnumber": 61, 07:41:36 INFO - "slavename": "t-yosemite-r7-0052", 07:41:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:41:36 INFO - "platform": "yosemite_r7", 07:41:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:41:36 INFO - "branch": "mozilla-central", 07:41:36 INFO - "repo_path": "mozilla-central", 07:41:36 INFO - "revision": "9348b76977e833f108cf77dff75b0fab887a2fc1", 07:41:36 INFO - "stage_platform": "macosx64", 07:41:36 INFO - "builduid": "586a921cc1c0408a9b9f976167bc0219", 07:41:36 INFO - "slavebuilddir": "test" 07:41:36 INFO - } 07:41:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:41:36 INFO - Running post-action listener: _resource_record_post_action 07:41:36 INFO - [mozharness: 2017-05-05 14:41:36.011969Z] Finished read-buildbot-config step (success) 07:41:36 INFO - [mozharness: 2017-05-05 14:41:36.012079Z] Running download-and-extract step. 07:41:36 INFO - Running pre-action listener: _resource_record_pre_action 07:41:36 INFO - Running main action method: download_and_extract 07:41:36 INFO - mkdir: /builds/slave/test/build/tests 07:41: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')]} 07:41:36 INFO - trying https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:41:36 INFO - Downloading https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/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 07:41:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/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 07:41:36 INFO - Downloaded 1325 bytes. 07:41:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:41:36 INFO - Using the following test package requirements: 07:41:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:41:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:41:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:41:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:41:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'jsshell-mac.zip'], 07:41:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:41:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:41:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:41:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:41:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:41:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:41:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:41:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:41:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:41:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:41:36 INFO - Fetch https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:41:38 INFO - Content-Length response header: 52708952 07:41:38 INFO - Bytes received: 52708952 07:41:55 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 07:41:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:41:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:41:55 INFO - Fetch https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:41:57 INFO - Content-Length response header: 52708952 07:41:57 INFO - Bytes received: 52708952 07:42:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:42:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:42:12 INFO - Fetch https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:42:14 INFO - Content-Length response header: 14408697 07:42:14 INFO - Bytes received: 14408697 07:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:14 INFO - trying https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:42:14 INFO - Downloading https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:42:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/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 07:42:16 INFO - Downloaded 70888929 bytes. 07:42:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:42:16 INFO - mkdir: /builds/slave/test/properties 07:42:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:42:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:42:16 INFO - Contents: 07:42:16 INFO - build_url:https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:42:17 INFO - Running post-action listener: _resource_record_post_action 07:42:17 INFO - Running post-action listener: set_extra_try_arguments 07:42:17 INFO - [mozharness: 2017-05-05 14:42:17.196007Z] Finished download-and-extract step (success) 07:42:17 INFO - [mozharness: 2017-05-05 14:42:17.196150Z] Running populate-webroot step. 07:42:17 INFO - Running pre-action listener: _resource_record_pre_action 07:42:17 INFO - Running main action method: populate_webroot 07:42:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:42:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:42:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:42:17 INFO - '--tppagecycles', 07:42:17 INFO - '7'], 07:42:17 INFO - 'tests': ['tsvgm']}}, 07:42:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:42:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:42:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:42:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:42:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:42:17 INFO - 'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:42:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:42:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:42:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:42:17 INFO - 'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['damp', 'tps']}, 07:42:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:42:17 INFO - 'tests': ['damp', 'tps']}, 07:42:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['dromaeo_dom']}, 07:42:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:42:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:42:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:42:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['a11yr', 07:42:17 INFO - 'ts_paint', 07:42:17 INFO - 'tpaint', 07:42:17 INFO - 'sessionrestore', 07:42:17 INFO - 'sessionrestore_no_auto_restore', 07:42:17 INFO - 'tabpaint']}, 07:42:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:42:17 INFO - 'ts_paint', 07:42:17 INFO - 'tpaint', 07:42:17 INFO - 'sessionrestore', 07:42:17 INFO - 'sessionrestore_no_auto_restore', 07:42:17 INFO - 'tabpaint']}, 07:42:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:42:17 INFO - 'ts_paint', 07:42:17 INFO - 'tpaint', 07:42:17 INFO - 'sessionrestore', 07:42:17 INFO - 'sessionrestore_no_auto_restore', 07:42:17 INFO - 'tabpaint']}, 07:42:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:42:17 INFO - 'ts_paint', 07:42:17 INFO - 'tpaint', 07:42:17 INFO - 'sessionrestore', 07:42:17 INFO - 'sessionrestore_no_auto_restore', 07:42:17 INFO - 'tabpaint']}, 07:42:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['a11yr', 07:42:17 INFO - 'ts_paint', 07:42:17 INFO - 'tpaint', 07:42:17 INFO - 'sessionrestore', 07:42:17 INFO - 'sessionrestore_no_auto_restore', 07:42:17 INFO - 'tabpaint']}, 07:42:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['a11yr', 07:42:17 INFO - 'ts_paint', 07:42:17 INFO - 'tpaint', 07:42:17 INFO - 'sessionrestore', 07:42:17 INFO - 'sessionrestore_no_auto_restore', 07:42:17 INFO - 'tabpaint']}, 07:42:17 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:42:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:42:17 INFO - 'bloom_basic_ref']}, 07:42:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['tsvgx', 07:42:17 INFO - 'tsvgr_opacity', 07:42:17 INFO - 'tart', 07:42:17 INFO - 'tscrollx', 07:42:17 INFO - 'cart', 07:42:17 INFO - 'tsvg_static']}, 07:42:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:42:17 INFO - 'tsvgr_opacity', 07:42:17 INFO - 'tart', 07:42:17 INFO - 'tscrollx', 07:42:17 INFO - 'cart', 07:42:17 INFO - 'tsvg_static']}, 07:42:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:42:17 INFO - 'talos_options': ['--disable-e10s'], 07:42:17 INFO - 'tests': ['tp5o']}, 07:42:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:42:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:42:17 INFO - 'talos_options': ['--disable-e10s', 07:42:17 INFO - '--xperf_path', 07:42:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:42:17 INFO - 'tests': ['tp5n']}, 07:42:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:42:17 INFO - 'talos_options': ['--xperf_path', 07:42:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:42:17 INFO - 'tests': ['tp5n']}}, 07:42:17 INFO - 'talos.zip': {'path': '', 07:42:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:42:17 INFO - Running post-action listener: _resource_record_post_action 07:42:17 INFO - [mozharness: 2017-05-05 14:42:17.215729Z] Finished populate-webroot step (success) 07:42:17 INFO - [mozharness: 2017-05-05 14:42:17.215840Z] Running create-virtualenv step. 07:42:17 INFO - Running pre-action listener: _resource_record_pre_action 07:42:17 INFO - Running main action method: create_virtualenv 07:42:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:42:17 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 07:42:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:42:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:42:17 INFO - Using real prefix '/tools/python27' 07:42:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:42:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:42:20 INFO - Installing pip.................done. 07:42:20 INFO - Return code: 0 07:42:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:42:21 INFO - Reading from file tmpfile_stdout 07:42:21 INFO - Output received: 07:42:21 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) 07:42:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:21 INFO - 'HOME': '/Users/cltbld', 07:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:21 INFO - 'LOGNAME': 'cltbld', 07:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:21 INFO - 'MOZ_NO_REMOTE': '1', 07:42:21 INFO - 'NO_EM_RESTART': '1', 07:42:21 INFO - 'PAGER': '/bin/cat', 07:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:21 INFO - 'PWD': '/builds/slave/test', 07:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:21 INFO - 'SHELL': '/bin/bash', 07:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:21 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:21 INFO - 'USER': 'cltbld', 07:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:21 INFO - 'XPC_FLAGS': '0x0', 07:42:21 INFO - 'XPC_SERVICE_NAME': '0', 07:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:21 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 07:42:21 INFO - Cleaning up... 07:42:21 INFO - Return code: 0 07:42:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:21 INFO - 'HOME': '/Users/cltbld', 07:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:21 INFO - 'LOGNAME': 'cltbld', 07:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:21 INFO - 'MOZ_NO_REMOTE': '1', 07:42:21 INFO - 'NO_EM_RESTART': '1', 07:42:21 INFO - 'PAGER': '/bin/cat', 07:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:21 INFO - 'PWD': '/builds/slave/test', 07:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:21 INFO - 'SHELL': '/bin/bash', 07:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:21 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:21 INFO - 'USER': 'cltbld', 07:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:21 INFO - 'XPC_FLAGS': '0x0', 07:42:21 INFO - 'XPC_SERVICE_NAME': '0', 07:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:21 INFO - Downloading/unpacking psutil>=3.1.1 07:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:42:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:42:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:42:23 INFO - Installing collected packages: psutil 07:42:23 INFO - Running setup.py install for psutil 07:42:24 INFO - building 'psutil._psutil_osx' extension 07:42:24 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 07:42:24 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 07:42:24 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 07:42:24 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 07:42:24 INFO - building 'psutil._psutil_posix' extension 07:42:24 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 07:42:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:42:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:42:24 INFO - ^ 07:42:24 INFO - 1 warning generated. 07:42:24 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 07:42:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:42:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:42:25 INFO - Successfully installed psutil 07:42:25 INFO - Cleaning up... 07:42:25 INFO - Return code: 0 07:42:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:42: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')]} 07:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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')]} 07:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:42: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 mozsystemmonitor==0.3 07:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:25 INFO - 'HOME': '/Users/cltbld', 07:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:25 INFO - 'LOGNAME': 'cltbld', 07:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:25 INFO - 'MOZ_NO_REMOTE': '1', 07:42:25 INFO - 'NO_EM_RESTART': '1', 07:42:25 INFO - 'PAGER': '/bin/cat', 07:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:25 INFO - 'PWD': '/builds/slave/test', 07:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:25 INFO - 'SHELL': '/bin/bash', 07:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:25 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:25 INFO - 'USER': 'cltbld', 07:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:25 INFO - 'XPC_FLAGS': '0x0', 07:42:25 INFO - 'XPC_SERVICE_NAME': '0', 07:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:42:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:42:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:42:26 INFO - Installing collected packages: mozsystemmonitor 07:42:26 INFO - Running setup.py install for mozsystemmonitor 07:42:26 INFO - Successfully installed mozsystemmonitor 07:42:26 INFO - Cleaning up... 07:42:27 INFO - Return code: 0 07:42:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:42:27 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')]} 07:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:27 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')]} 07:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:27 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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:27 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 07:42:27 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 07:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:27 INFO - 'HOME': '/Users/cltbld', 07:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:27 INFO - 'LOGNAME': 'cltbld', 07:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:27 INFO - 'MOZ_NO_REMOTE': '1', 07:42:27 INFO - 'NO_EM_RESTART': '1', 07:42:27 INFO - 'PAGER': '/bin/cat', 07:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:27 INFO - 'PWD': '/builds/slave/test', 07:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:27 INFO - 'SHELL': '/bin/bash', 07:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:27 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:27 INFO - 'USER': 'cltbld', 07:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:27 INFO - 'XPC_FLAGS': '0x0', 07:42:27 INFO - 'XPC_SERVICE_NAME': '0', 07:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:27 INFO - Downloading/unpacking jsonschema==2.5.1 07:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:42:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:42:29 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 07:42:29 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 07:42:29 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 07:42:29 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 07:42:29 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 07:42:29 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 07:42:29 INFO - Downloading functools32-3.2.3-2.tar.gz 07:42:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:42:30 INFO - warning: no files found matching '*.txt' 07:42:30 INFO - no previously-included directories found matching 'build' 07:42:30 INFO - no previously-included directories found matching 'dist' 07:42:30 INFO - no previously-included directories found matching '.git*' 07:42:30 INFO - Installing collected packages: jsonschema, functools32 07:42:30 INFO - Running setup.py install for functools32 07:42:30 INFO - warning: no files found matching '*.txt' 07:42:30 INFO - no previously-included directories found matching 'build' 07:42:30 INFO - no previously-included directories found matching 'dist' 07:42:30 INFO - no previously-included directories found matching '.git*' 07:42:30 INFO - Successfully installed jsonschema functools32 07:42:30 INFO - Cleaning up... 07:42:30 INFO - Return code: 0 07:42:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:42:30 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')]} 07:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:30 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')]} 07:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:30 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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:30 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 07:42:30 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 07:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:30 INFO - 'HOME': '/Users/cltbld', 07:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:30 INFO - 'LOGNAME': 'cltbld', 07:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:30 INFO - 'MOZ_NO_REMOTE': '1', 07:42:30 INFO - 'NO_EM_RESTART': '1', 07:42:30 INFO - 'PAGER': '/bin/cat', 07:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:30 INFO - 'PWD': '/builds/slave/test', 07:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:30 INFO - 'SHELL': '/bin/bash', 07:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:30 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:30 INFO - 'USER': 'cltbld', 07:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:30 INFO - 'XPC_FLAGS': '0x0', 07:42:30 INFO - 'XPC_SERVICE_NAME': '0', 07:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:42:30 INFO - Cleaning up... 07:42:30 INFO - Return code: 0 07:42:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:42:30 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')]} 07:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:30 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')]} 07:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:30 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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:30 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 07:42:30 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 07:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:30 INFO - 'HOME': '/Users/cltbld', 07:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:30 INFO - 'LOGNAME': 'cltbld', 07:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:30 INFO - 'MOZ_NO_REMOTE': '1', 07:42:30 INFO - 'NO_EM_RESTART': '1', 07:42:30 INFO - 'PAGER': '/bin/cat', 07:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:30 INFO - 'PWD': '/builds/slave/test', 07:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:30 INFO - 'SHELL': '/bin/bash', 07:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:30 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:30 INFO - 'USER': 'cltbld', 07:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:30 INFO - 'XPC_FLAGS': '0x0', 07:42:30 INFO - 'XPC_SERVICE_NAME': '0', 07:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:31 INFO - Downloading/unpacking blobuploader==1.2.4 07:42:31 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 07:42:31 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 07:42:31 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 07:42:31 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 07:42:31 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 07:42:31 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 07:42:32 INFO - Downloading blobuploader-1.2.4.tar.gz 07:42:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:42:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:42:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:42:33 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 07:42:33 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 07:42:33 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 07:42:33 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 07:42:33 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 07:42:33 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 07:42:33 INFO - Downloading docopt-0.6.1.tar.gz 07:42:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:42:33 INFO - Installing collected packages: blobuploader, requests, docopt 07:42:33 INFO - Running setup.py install for blobuploader 07:42:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:42:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:42:33 INFO - Running setup.py install for requests 07:42:34 INFO - Running setup.py install for docopt 07:42:34 INFO - Successfully installed blobuploader requests docopt 07:42:34 INFO - Cleaning up... 07:42:34 INFO - Return code: 0 07:42:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:34 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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:34 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 07:42:34 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 07:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:34 INFO - 'HOME': '/Users/cltbld', 07:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:34 INFO - 'LOGNAME': 'cltbld', 07:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:34 INFO - 'MOZ_NO_REMOTE': '1', 07:42:34 INFO - 'NO_EM_RESTART': '1', 07:42:34 INFO - 'PAGER': '/bin/cat', 07:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:34 INFO - 'PWD': '/builds/slave/test', 07:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:34 INFO - 'SHELL': '/bin/bash', 07:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:34 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:34 INFO - 'USER': 'cltbld', 07:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:34 INFO - 'XPC_FLAGS': '0x0', 07:42:34 INFO - 'XPC_SERVICE_NAME': '0', 07:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-5YtH4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-4zmDUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-6bo2qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZBju6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-9z4F5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-AhTBTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-kKu3vL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-SsRuIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Pv5rls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-PVZtuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-efNqKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZbRNKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZJpQ4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-J7EUKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:36 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-xCC1bQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:36 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-qQELEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:36 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-h9RXFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:42:36 INFO - Running setup.py install for manifestparser 07:42:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:42:36 INFO - Running setup.py install for mozcrash 07:42:36 INFO - Running setup.py install for mozdebug 07:42:36 INFO - Running setup.py install for mozdevice 07:42:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:42:36 INFO - Running setup.py install for mozfile 07:42:37 INFO - Running setup.py install for mozhttpd 07:42:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Running setup.py install for mozinfo 07:42:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Running setup.py install for mozInstall 07:42:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Running setup.py install for mozleak 07:42:37 INFO - Running setup.py install for mozlog 07:42:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Running setup.py install for moznetwork 07:42:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:42:37 INFO - Running setup.py install for mozprocess 07:42:38 INFO - Running setup.py install for mozprofile 07:42:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:42:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:42:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:42:38 INFO - Running setup.py install for mozrunner 07:42:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:42:38 INFO - Running setup.py install for mozscreenshot 07:42:38 INFO - Running setup.py install for moztest 07:42:38 INFO - Running setup.py install for mozversion 07:42:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:42:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:42:38 INFO - Cleaning up... 07:42:38 INFO - Return code: 0 07:42:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:38 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')]} 07:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:38 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')]} 07:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:38 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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:38 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 07:42:38 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 07:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:38 INFO - 'HOME': '/Users/cltbld', 07:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:38 INFO - 'LOGNAME': 'cltbld', 07:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:38 INFO - 'MOZ_NO_REMOTE': '1', 07:42:38 INFO - 'NO_EM_RESTART': '1', 07:42:38 INFO - 'PAGER': '/bin/cat', 07:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:38 INFO - 'PWD': '/builds/slave/test', 07:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:38 INFO - 'SHELL': '/bin/bash', 07:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:38 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:38 INFO - 'USER': 'cltbld', 07:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:38 INFO - 'XPC_FLAGS': '0x0', 07:42:38 INFO - 'XPC_SERVICE_NAME': '0', 07:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-bbR329-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:39 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)) 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-63E0TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:39 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)) 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-jsuA4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:39 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)) 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-vp3CcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-H0Bt9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:39 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)) 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-vJHgAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:39 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)) 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-CMZJpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:39 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)) 07:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:39 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ded_NT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-RX1GXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-IG8pE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-cHTrkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-E9Os5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-zmnMGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-BMRbmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-RRYed8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-7_75eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:40 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)) 07:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:40 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-8KnFBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:40 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)) 07:42:40 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)) 07:42:40 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)) 07:42:40 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)) 07:42:40 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:40 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:42:41 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 07:42:41 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 07:42:41 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 07:42:41 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 07:42:41 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 07:42:41 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 07:42:42 INFO - Downloading blessings-1.6.tar.gz 07:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:42:42 INFO - Installing collected packages: blessings 07:42:42 INFO - Running setup.py install for blessings 07:42:42 INFO - Successfully installed blessings 07:42:42 INFO - Cleaning up... 07:42:42 INFO - Return code: 0 07:42:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:42:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:42 INFO - Reading from file tmpfile_stdout 07:42:42 INFO - Current package versions: 07:42:42 INFO - blessings == 1.6 07:42:42 INFO - blobuploader == 1.2.4 07:42:42 INFO - docopt == 0.6.1 07:42:42 INFO - functools32 == 3.2.3-2 07:42:42 INFO - jsonschema == 2.5.1 07:42:42 INFO - manifestparser == 1.1 07:42:42 INFO - mozInstall == 1.12 07:42:42 INFO - mozcrash == 1.0 07:42:42 INFO - mozdebug == 0.1 07:42:42 INFO - mozdevice == 0.50 07:42:42 INFO - mozfile == 1.2 07:42:42 INFO - mozhttpd == 0.7 07:42:42 INFO - mozinfo == 0.9 07:42:42 INFO - mozleak == 0.1 07:42:42 INFO - mozlog == 3.4 07:42:42 INFO - moznetwork == 0.27 07:42:42 INFO - mozprocess == 0.25 07:42:42 INFO - mozprofile == 0.28 07:42:42 INFO - mozrunner == 6.13 07:42:42 INFO - mozscreenshot == 0.1 07:42:42 INFO - mozsystemmonitor == 0.3 07:42:42 INFO - moztest == 0.8 07:42:42 INFO - mozversion == 1.4 07:42:42 INFO - psutil == 3.1.1 07:42:42 INFO - requests == 1.2.3 07:42:42 INFO - wsgiref == 0.1.2 07:42:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:42 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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:42 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 07:42:42 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 07:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:42 INFO - 'HOME': '/Users/cltbld', 07:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:42 INFO - 'LOGNAME': 'cltbld', 07:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:42 INFO - 'MOZ_NO_REMOTE': '1', 07:42:42 INFO - 'NO_EM_RESTART': '1', 07:42:42 INFO - 'PAGER': '/bin/cat', 07:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:42 INFO - 'PWD': '/builds/slave/test', 07:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:42 INFO - 'SHELL': '/bin/bash', 07:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:42 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:42 INFO - 'USER': 'cltbld', 07:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:42 INFO - 'XPC_FLAGS': '0x0', 07:42:42 INFO - 'XPC_SERVICE_NAME': '0', 07:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 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)) 07:42:43 INFO - Cleaning up... 07:42:43 INFO - Return code: 0 07:42:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:42:43 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')]} 07:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:43 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')]} 07:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:43 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 0x107820b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107823768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87bb5708b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, '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.3QUlVIXnyu/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.hirS2HsMT3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:42:43 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 07:42:43 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 07:42:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:42:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:43 INFO - 'HOME': '/Users/cltbld', 07:42:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:43 INFO - 'LOGNAME': 'cltbld', 07:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:43 INFO - 'MOZ_NO_REMOTE': '1', 07:42:43 INFO - 'NO_EM_RESTART': '1', 07:42:43 INFO - 'PAGER': '/bin/cat', 07:42:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:43 INFO - 'PWD': '/builds/slave/test', 07:42:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:43 INFO - 'SHELL': '/bin/bash', 07:42:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:42:43 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:42:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:43 INFO - 'USER': 'cltbld', 07:42:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:43 INFO - 'XPC_FLAGS': '0x0', 07:42:43 INFO - 'XPC_SERVICE_NAME': '0', 07:42:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:42:43 INFO - Cleaning up... 07:42:43 INFO - Return code: 0 07:42:43 INFO - Running post-action listener: _resource_record_post_action 07:42:43 INFO - Running post-action listener: _start_resource_monitoring 07:42:43 INFO - Starting resource monitoring. 07:42:43 INFO - [mozharness: 2017-05-05 14:42:43.512041Z] Finished create-virtualenv step (success) 07:42:43 INFO - [mozharness: 2017-05-05 14:42:43.513058Z] Running install step. 07:42:43 INFO - Running pre-action listener: _resource_record_pre_action 07:42:43 INFO - Running main action method: install 07:42:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:43 INFO - Reading from file tmpfile_stdout 07:42:43 INFO - Detecting whether we're running mozinstall >=1.0... 07:42:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:42:43 INFO - Reading from file tmpfile_stdout 07:42:43 INFO - Output received: 07:42:43 INFO - Usage: mozinstall [options] installer 07:42:43 INFO - Options: 07:42:43 INFO - -h, --help show this help message and exit 07:42:43 INFO - -d DEST, --destination=DEST 07:42:43 INFO - Directory to install application into. [default: 07:42:43 INFO - "/builds/slave/test"] 07:42:43 INFO - --app=APP Application being installed. [default: firefox] 07:42:43 INFO - mkdir: /builds/slave/test/build/application 07:42:43 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'] 07:42:43 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 07:43:03 INFO - Reading from file tmpfile_stdout 07:43:03 INFO - Output received: 07:43:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:43:03 INFO - Running post-action listener: _resource_record_post_action 07:43:03 INFO - [mozharness: 2017-05-05 14:43:03.393129Z] Finished install step (success) 07:43:03 INFO - [mozharness: 2017-05-05 14:43:03.393261Z] Running run-tests step. 07:43:03 INFO - Running pre-action listener: _resource_record_pre_action 07:43:03 INFO - Running main action method: run_tests 07:43:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:43:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:43:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:43:03 INFO - 2017-05-05 07:43:03.407 screenresolution[1624:7418] starting screenresolution argv=screenresolution get 07:43:03 INFO - 2017-05-05 07:43:03.410 screenresolution[1624:7418] Display 0: 1600x1200x32@60 07:43:03 INFO - 2017-05-05 07:43:03.416 screenresolution[1625:7421] starting screenresolution argv=screenresolution list 07:43:03 INFO - Available Modes on Display 0 07:43:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:43:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:43:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:43:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:43:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:43:03 INFO - Intel Iris: 07:43:03 INFO - Chipset Model: Intel Iris 07:43:03 INFO - Type: GPU 07:43:03 INFO - Bus: Built-In 07:43:03 INFO - VRAM (Dynamic, Max): 1536 MB 07:43:03 INFO - Vendor: Intel (0x8086) 07:43:03 INFO - Device ID: 0x0a2e 07:43:03 INFO - Revision ID: 0x0009 07:43:03 INFO - Displays: 07:43:03 INFO - Display: 07:43:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:43:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:43:03 INFO - Display Serial Number: 1600x1200 60 07:43:03 INFO - Main Display: Yes 07:43:03 INFO - Mirror: Off 07:43:03 INFO - Online: Yes 07:43:03 INFO - Rotation: Supported 07:43:03 INFO - Return code: 0 07:43:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:43:03 INFO - Python 2.7.3 07:43:03 INFO - Return code: 0 07:43:03 INFO - grabbing minidump binary from tooltool 07:43: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')]} 07:43:03 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 0x1075fed30>, '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 0x107524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075f3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:43:03 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 07:43:03 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 07:43:03 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 07:43:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:43:03 INFO - Return code: 0 07:43:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:43:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:43:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:43:03 INFO - ENV: RUST_BACKTRACE is now 1 07:43:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:43:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:43:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 07:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 07:43:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3QUlVIXnyu/Render', 07:43:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:03 INFO - 'HOME': '/Users/cltbld', 07:43:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:03 INFO - 'LOGNAME': 'cltbld', 07:43:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:43:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:03 INFO - 'MOZ_NO_REMOTE': '1', 07:43:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:43:03 INFO - 'NO_EM_RESTART': '1', 07:43:03 INFO - 'PAGER': '/bin/cat', 07:43:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:03 INFO - 'PWD': '/builds/slave/test', 07:43:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:43:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:03 INFO - 'RUST_BACKTRACE': '1', 07:43:03 INFO - 'SHELL': '/bin/bash', 07:43:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hirS2HsMT3/Listeners', 07:43:03 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:43:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:03 INFO - 'USER': 'cltbld', 07:43:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:03 INFO - 'XPC_FLAGS': '0x0', 07:43:03 INFO - 'XPC_SERVICE_NAME': '0', 07:43:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 07:43:04 INFO - mozversion application_buildid: 20170505062208 07:43:04 INFO - mozversion application_changeset: 9348b76977e833f108cf77dff75b0fab887a2fc1 07:43:04 INFO - mozversion application_display_name: Nightly 07:43:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:43:04 INFO - mozversion application_name: Firefox 07:43:04 INFO - mozversion application_remotingname: firefox 07:43:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:43:04 INFO - mozversion application_vendor: Mozilla 07:43:04 INFO - mozversion application_version: 55.0a1 07:43:04 INFO - mozversion platform_buildid: 20170505062208 07:43:04 INFO - mozversion platform_changeset: 9348b76977e833f108cf77dff75b0fab887a2fc1 07:43:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:43:04 INFO - mozversion platform_version: 55.0a1 07:43:04 INFO - using testdate: 1493995384 07:43:04 INFO - actual date: 1493995384 07:43:04 INFO - starting webserver on 'localhost:49239' 07:43:04 INFO - SUITE-START | Running 2 tests 07:43:04 INFO - TEST-START | dromaeo_css 07:43:04 INFO - operating with platform_type : mac_ 07:43:04 INFO - Initialising browser for dromaeo_css test... 07:43:04 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpnnmyWG/profile http://localhost:49239/getInfo.html) 07:43:06 INFO - PID 1635 | __metrics Screen width/height:1600/1200 07:43:06 INFO - PID 1635 | colorDepth:24 07:43:06 INFO - PID 1635 | Browser inner width/height: 1024/691 07:43:06 INFO - PID 1635 | __metrics 07:43:06 INFO - PID 1635 | 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" 07:43:06 INFO - PID 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:43:07 INFO - TEST-INFO | 1635: exit 0 07:43:07 INFO - Browser initialized. 07:43:07 INFO - Running cycle 1/1 for dromaeo_css test... 07:43:07 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpnnmyWG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:43:20 INFO - PID 1637 | RSS: Main: 240373760 07:43:20 INFO - PID 1637 | 07:46:23 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 07:46:26 INFO - PID 1637 | RSS: Main: 481927168 07:46:26 INFO - PID 1637 | 07:49:29 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 07:49:30 INFO - PID 1637 | RSS: Main: 367800320 07:49:30 INFO - PID 1637 | 07:52:32 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 07:52:32 INFO - PID 1637 | RSS: Main: 326623232 07:52:32 INFO - PID 1637 | 07:55:35 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 07:55:35 INFO - PID 1637 | RSS: Main: 359120896 07:55:35 INFO - PID 1637 | 07:58:39 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 07:58:39 INFO - PID 1637 | RSS: Main: 322637824 07:58:39 INFO - PID 1637 | 08:01:41 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 08:01:42 INFO - PID 1637 | RSS: Main: 375808000 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | __start_tp_report 08:01:42 INFO - PID 1637 | _x_x_mozilla_page_load 08:01:42 INFO - PID 1637 | _x_x_mozilla_page_load_details 08:01:42 INFO - PID 1637 | |i|pagename|runs| 08:01:42 INFO - PID 1637 | |0;dojo.html;2996.265914850861;3496.7552271340983;3515.4375299952003;3515.6660117288848;3593.371160046991;5992.910106348409;6132.785352512663;6179.9382006179985;6219.564630475868;6310.4636105930995;6114.144019837216;6119.0151432184075;6162.753489860412;6237.251529816417;6281.748730050793;5263.921041184385;5305.389880163782;5341.626086173969;5375.11310633745;5388.191771234307;5271.4201437842;5407.783688652449;5527;5533.418991005947;5564.137558678412;5290.788368465257;5371.113766228567;5388.649737767033;5428.45715428458;5438.320209973754;5329.907368989348;5335.759890804923;5401.351837779453;5427.104527752916;5462.371827239878;5374.086405311106;5399.379071406799;5421.972890135564;5424.294841670597;5479.013777520045;6300.653464059475;6317.810465686037;6343.746250149989;6344.6827658616885;6355.205599300088;5401.459854014606;6402.263739669905;6450.548461607689;6452.322506136882;6649.966750166266;6669.43309818666;6770.356816102462;6770.356816102511;6770.966145169292;6772.08576842125;6311.526635502356;6381.361863813628;6389.233292004989;6394.1687580614225;6403.199600049994;6785.93214067853;6867.07294515244;6871.072405225231;6881.724731010803;6894.620795856175;6319.33646967071;6375.71309291083;6389.584676995981;6480.384363485461;6520.902186467207;6677.232118306457;6687.732490700318;6689.13041304637;6696.229933557605;6705.4970877184405;6519.152510173742;6580.440662543738;6587.143671322793;6593.703283352261;6599.637019963948;6357.618542887441;6407.967960160169;6420.5184611154355;6420.582662126947;6606.174228221473;5179.119549676564;5180.766865491062;5200.349956255468;5204.609654275944;5212.635115541876;4095.488063992001;4522.118186953512;4675.696079754805;4676.69601475903;4695.037517308943;6174.567780255339;6268.843278918063;6287.811365659038;6312.715927783261;6323.462505687068;6745.527813053137;6746.392824645805;6762;6766.391024807693;6779.62712050832;6281.780137695158;6296.8425789354715;6299.212598425197;6316.557840951089;6325.209348831396;5686.2039314496005;5801.361850196475;5804.361520232771;5809.157672137517;5892.086726557305;6896.689648965808;6937.167539895144;6939.236683964659;6952.478564107813;6957.82605434858;6726.899096513458;6764.830879228059;6773.966130169318;6777.93222067773;6778.96610516964;6629.668516574248;6722.697478613376;6728.091707619409;6811.863762724817;6906.654667266516;5160.73561977318;6581.276059633341;6617.569857959217;6619.106420633153;6661.100751398693;6628.9393697008245;6660.500462465237;6739.6967136478;6747.089142965637;6768.458523318024;6320.5259605528845;6548.377904099103;6555.934440655532;6563.146790917342;6570.047343135123;6344.936550634435;6354;6359.332270111757;6364.93635063662;6365;6327.11420401135;6365.554411191172;6367.363263673781;6375.553711240169;6378.74485020594;5377.247185394126;6424.164858568356;6461.870762584816;6481.5138864584405;6489.5781774184525;6354.777582784581;6356.809295721136;6357.491400688026;6375.362463753588;6387.393197646216 08:01:42 INFO - PID 1637 | |1;ext.html;792.9762107136785;801.3949468151546;1101.911847052236;1117.5138814615648;1126.003486914081;34061.318773624545;34089.977300680985;34293.79971700994;36906.8154659227;37097.25805483889;39282;39590.40614390779;39610.8019459903;39687.809365719004;39717.801410992906;8215.34277257818;8356.665733370657;8362.749117526484;8366.581670916461;8372.790680232982;5901.5278777697895;5999.550033747465;8220.424570280084;8295.917040829605;8416.663333466655;8496.150384961516;8511.10633383495;8534.61594228258;8535.445196062235;8584.926884139482;8417.789555261137;8505.532195729233;8522;8525.019622743403;8537.231649151576;5830.941690583082;5959.225300710923;8036.879446808302;8185.140560241149;8296.585170741439;37411.812940935124;37765;37772.244555108744;37796.81101594502;37823;3527.470879368115;3563.625819288963;3566.1797786508987;3568.07230120168;3570.321638888629;30681;30708.2322941926;30723.539146912815;30761.384772304653;30784.846075769477;3128.436881361354;3193.1538142392283;3201.08769000836;3210.2455922858107;3221.9355612887844;263442;266308.66845665645;266615;266679;267792;49804.50195498071;49826;49903.75048124736;49942;49971.000579988926;41424.37863432051;41435.58564414378;41631.58368416338;50110.49889501058;50648.74675626598;13477.258750768802;13530.255835929118;13532.79700804469;13536.864631353757;13542.322883855768;10885.455727213608;10892.346459212473;10900.236983411243;10901.182411319183;10933.617323393642;12815.102942793916;12824.551140710031;12858.9357053216;12925.289109098901;12932.935335323264;4550.544945505423;5499.890002199933;5509.063459211943;5519.9448005520235;5529.917051244234;1042.2599954032721;1046.9738256543646;1048.501961568249;1085.2891356161726;1089.2865173311493;23634.88182559076;24641.50716985686;24659.136930207358;24672.13647522364;24724;1125.7973564758422;1126.3354620773782;1158.860936687586;1161.9825702614298;1186.6262127429832;11593.098191393548;12552.686182845502;12585.993120550334;12586.18189817659;12592.4333404997;296394;296954;297126;298203;299983.50008250255;52558;52630.73684631552;52841.735791320796;52882.73558632259;52914.47085529096;1463.5609317204796;1519.8252200996585;1523.588631069605;1526.3513006972214;1544.8455154484461;19969.900150499154;23930.803459827286;24158.75841241565;24170.63744043876;24174.51650967006;38801.805990969864;38844.41733374058;38865;38870.2225955485;38933.2213355737;268378;269427;269506;269745;270120;29956.850215749655;30013.249668758457;34446.48330274998;34482.65517344794;34868.82565587257;42089.58059403719;42185;43137;43525.78237108794;43740.34389484097;25328.480291182586;29229.415411692073;29250;30192;30643.846780765954;24898.12856550012;26276.474470510864;30001.54997674993;30180.5472917902;30364;34650.13374665553;35471.46792798162;35704.64295357013;35721;35790.4631430534;29284;29329;30313.848430757706;30322.545161823033;30712.385752285278;21955.890220548794;22696.432589185402;23950.64074038856;24087.879560602083;24322.756772432756 08:01:42 INFO - PID 1637 | |2;jquery.html;425.48990572091407;434.43957295089336;436.7641473604254;441.7702794546836;456.7670488051095;19660.410187694357;21061.578768424642;21608.243711470102;21791;21812.018459169336;21238.681419778717;21574.676379854274;21630.783692163073;21821.563568728656;21872.8906355468;5808.390119037493;6100.084987251903;6108.786192483264;6120.051392034219;6125.387461253861;4559.475660299075;5651.350094739117;5900.321463031742;5968.910466343008;6114.541409394291;5885.558583106263;5919.408059194089;5919.792807251747;5924.43717846806;5938.584299099065;5863.2670916135485;6121.204243448346;6172.660503672296;6180.3201647818705;6185.814425567218;5359;5402.35171779385;5467.043267428184;5469.535089517396;5470.316210473691;26216.60675089875;26682.73317266822;26689.466210675873;26889.32776680579;27181;2190.3100523335256;2254.785795349439;2255.5037891663806;2258.977410225893;2260.559190957764;17240.482785516455;17271.654566908717;17809.910950445166;17810.37663681778;17837.39463448296;2069.658506346451;2111.714918486;2145.560160167162;2147.50607360308;2151.354593621907;127984.36007819994;147458;158445;158819;161703;9890.615313856066;11357.772844543062;12004.2197257178;12018.399080045963;12188.634340969786;16041.919790401207;16050.678986420206;16067;16088.517344479684;16101.919490402473;8231.835363292701;8514.70198543048;8567.74296771098;8722.258608018263;8724;7986.880196797053;8002.199780022068;8033.3974951878845;8190.058143313476;8194.262516373556;6054.727537260834;7344.412447004227;7451.552906825608;7467.887981680279;7518.173000969889;2062.5359294158993;2074.7510298764246;2085.7497100348055;2119.0782009825775;2222.2555443926467;930.4370855632245;953.594722243044;959.7138741244819;963.5209952722537;999.4203362050139;19669.50826229327;20100.899495502428;20643.380698579065;20662.276820311217;21001.264955726478;999.2106236073421;1001.7695929936156;1003.668789299544;1031.783325501638;1035.2132379391567;9312.627494900264;10341.689749307532;10596.841047384296;10630.255882088335;10837.241393102408;508269;523081;533122;533476;539662;82439;115840.42079789715;118370.4081479587;121542;122457.38771306086;1107.9390633515395;1118.6867677050438;1159.3160035579049;1160.5067846165346;1163.5055101581793;21135.471613209793;21387.72245550865;21640;21893.781062189173;21919;54749.1787623178;57737.13394299173;58615;58885.41114588799;58904;74219.6289018573;74934.25065749272;74945;78374.608126959;80120;8420;8708.78228044304;8720.215180633773;8798.252148567444;8814.338924580554;13287.06990510655;13385.866141338462;13393.732125357634;13740;13790;10777.08394786452;11683;11798.823017654913;11820.822687659864;11956.641300760992;8394.538300393537;10147.797044059225;10591.788164236827;10779.89220107789;11102.944485277521;11735.061195104201;11893;11912.034514305042;11975.700607484883;12023.63929082129;8529.658813647633;8535.658573657232;8539.316854651488;8747;9018.323625728228;11464;11650.70873228176;11869.881301186877;11961.222520536137;11962.46168922418 08:01:42 INFO - PID 1637 | |3;mootools.html;1369.1579678497724;1422.9359678814453;1442.0554536778407;1443.4370595467763;1446.7395868743624;10988.835167472484;11451.770964580703;11471.483783229754;11473.713157171074;11518.596849110283;10888.183386246024;11176.105911527078;11533.942330288357;11556.017738492217;11566.59516916908;2448.155386391692;2519.496100779842;2523.974760252392;2526.6589010483535;2528.8229823912334;2454.9263522094366;2509.134348649713;2510.57320255557;2512.849229046254;2513.6355228491857;2429.708434987804;2520.3069155982066;2526.2168727694475;2527.3807917060344;2528.5575024370755;2492.501499700067;2502.4869901670204;2513.522430738154;2522.672052633164;2523.179966510882;2492.887820048102;2507.8746062696973;2514.8365356251784;2516.207394670673;2517.710463296726;11114.610988615359;11378.37418941958;11399.658010259705;11402.71493212668;11404.771904561945;6319.778807741707;6570.967145164292;6594.043863639746;6594.637294948776;6598.47212223021;3861.6717579005817;4181.832726690929;4264.9147017059795;4269.594388533084;4273.487181538204;1159.8550181227347;1198.640407877642;1267.5500197429872;1310.7837206860952;1313.868613138688;14724.852751472563;14959;15408.460703875311;15496.845031549765;15763.68472630541;5478.863028424242;5706.828795136153;5949.167116603679;5992.250968628921;5995.010823214208;13448.260345680887;13451.461941522426;13452.1256118352;13458.93270533641;13515.86484135146;6614.437772789271;6618.437432818168;6619.106420633249;6619.5035372347265;6624.238212605515;5851.210086638334;5997.310309314397;6092.878142437126;6159.876802463926;6165.260168779685;3795.6773674237998;4369.956300436955;4515.954840451619;4532.410786597721;4601.72389656615;1343.1739480219594;1404.4241860837008;1413.9434422623185;1423.7152569486145;1432.1621851216946;805.8066064144563;806.2622700229342;822.2394285286086;829.9336053115736;855.2901092093672;5724;5837.9124313135335;5995.280566332068;6352.110704501373;6781.152355955505;595.9433853783884;599.1671576508656;613.2150846915955;619.6839611797925;621.0994058614936;4520.977395113004;4540.5913467787395;4570.223062079388;4710.882227944328;4775.832845850448;14310.069845460011;14348;14531.418743250151;14572.562823115324;14596.12423254608;14019.158850469004;14321.287096726086;14525.636859078608;14536.27318634064;14543.200123993282;7166;7397.630118494054;8200.42597018215;8468.49189048659;8473.067962524117;5745.770169193353;6882.655867206719;7064.470164737562;7071.646417679199;7129.607871567114;12498.562550310695;12696.365181740692;12796.680082998;13150.868491315347;13161.407736652065;12973.286469244282;13282.402291896695;13296.667583310495;13298.800517992044;13457.461701531829;8895.06601806801;8999.145081217273;9159.816803664022;9186.770330741785;9190.724278271662;10311.278210525192;10409.84385234207;10529.684109476728;10535.367877927349;10546.8417973732;8521.636538154113;8664.220220180214;8677.739667809976;8785.165409286108;8847.424917380607;7502.21226771203;8656.610452529778;8749.825003499767;8784.824303514022;8800.955995219983;8008.7597372078935;8144.959275203823;8155.184481551797;8163.387745918961;8166.7133202703335;4907.705537667752;5650.23721797552;5949.6430214187285;5960.105984102419;6178.907316390168;5939.4357536034;5964.761409543569;6091.7563297467605;6125.9693701531205;6269.749210031548 08:01:42 INFO - PID 1637 | |4;prototype.html;342.4076347918103;379.7417755925969;393.08803575704366;404.0706375336728;404.95950404959507;5128.384593848738;5971.805638872227;6035.18524999125;6060.484858786997;6079.209702738649;5597.888042239153;5814.244148260731;5877.648140927578;5899;5942.286925568927;3930.8820735377985;4207.936880946788;4296.398504209413;4369.715968462056;4375.321825117104;4356.651467882578;4366.607005369516;4371.256886329315;4373.322135069054;4421.226285400042;3524.4008518551777;4277.272863613178;4311.116221174668;4350.847720329789;4368.475782906055;3177.380410819891;3935.330993731073;4313.503947046099;4317.460317460317;4320.179165958473;3741.38267185914;3756.4553139794575;3766.209096089797;3768.8869333920024;3781.3193625147464;4774.713517188945;5001.349824522825;5390.5418039466695;5661.320641523043;5879.941200587982;5118.956024740451;5119.667221630582;5120.283160357553;5136.255242989783;5157.896842063175;6246.687665616746;6266.404691554295;6305.68471576424;6334.113224148623;6348.238211414614;4113.424120623115;4198.706090573661;4239;4245.299525578276;4252.533530552303;6278.874422511524;6420.0690899819265;6423.678816059271;6564.179477565304;6633.635150066744;4087.346024636044;4237.957620423818;4239.830406783694;4246.596573325529;4293.506246781598;4131.50421949368;4344.522102568715;4359;4368.929612244957;4371.475422949266;4901.5588597026435;4956.256561515729;4981.975090124526;5040.974795126001;5065.8733531662;6198.2871969723155;6212.47193988507;6218.170732316785;6232.657203853741;6241.313455519889;1328.7775246772992;1344.9932750336186;1419.7160567886258;1442.2212005517113;1474.3881289264834;953.2374100719396;962.534805946104;962.8748262725941;978.3542861711236;979.7893452907659;5134.460881607452;5752.856178595569;5782.190493330937;5816.382627817499;5820.417958204231;6478.546501744832;6478.643674597943;6481.902771458432;6489.513286503531;6499.252585952581;5262.473752624707;5575.4424557543925;5683.232763576947;5728.911506813692;5837.620554663933;2835.8582070896373;3294.1105901406486;3326.417876871538;3380.492926061061;3390.135515443546;6512.6092434454085;6621.735130594819;6662.800115996528;6675.632840193836;6781.406369503163;5722.456366645162;6491.285958544557;6663.966680166568;6664.333566643297;6694.230163531256;5786.826395208151;6528.804135875836;6683.465322774166;6684.766033188815;6707.832304192434;5939.227900372924;6382.20222472191;6424.682939997383;6469.8706025880165;6502.659906591036;5668.348139963957;6186.597871138361;6376.090410384071;6381.202349706286;6433.388828061326;4791.832285869978;6387.936120638734;6617.305182955721;6763.86472270542;6949.374556289756;3824.655780979725;4082.101937573729;4247.447831781848;4274.380214868765;4282.464691913511;5820.534357251325;6501.414872661483;6543.803685889431;6593.835154121185;6631.535792494479;5853.619514731528;5905.881882362242;6293.811185664437;6333.36666333363;6350.206224221973;5779.393163717749;6039.78860739872;6314.273858506147;6324.810255692337;6330;5214.634975551675;5486.670799751868;6220.968895155495;6294.716737746904;6322.557420980487;6216.627002379872;6427.260865000585;6431.871362572816;6449.258335291502;6472.773452929125;5232.424433312256;6272.968635156977;6305.527085468517;6309.621422714652;6408.58344207625 08:01:42 INFO - PID 1637 | |5;yui.html;829.7510746775965;834.7328854766474;852.2968550945468;854.5769843927253;863.9006514250859;16145.19274036298;16269.593260168502;16580.00519968803;16614;17851.464456066307;17748.201330940108;17887.284508619672;17948.897374892535;18159.54601134972;18177;1013.2856336282921;1045.900639439252;1046.8586740789972;1048.1614708233421;1049.7375656085978;1028.3932479836894;1035.177034257767;1037.823569993103;1037.9827768786595;1040.9687709368693;1059.8887116852736;1062.219268837404;1062.5324857062867;1065.5631191211605;1101.245646731987;1056.910162636177;1057.7249915022073;1057.989619912985;1059.6715018344262;1090.9127269818434;789.9170587088388;790.1624278265058;790.8853216283607;791.2404092071619;820.3150369441523;16505.257263423176;16609.501714948572;17113.828861711347;17132.828671713247;17168;17091.803573749843;17334.82665173345;17464.65070698579;17686.027268500227;17698.203580838894;11830.349330786803;12474.18917770351;12581.559645412366;13297.933510332483;13591.184528928197;3394;3407.386670399327;3446.4141096013486;3446.586409630835;3449.689527942497;93155;115653;116351;119911;120005.39997300044;11171.776564468664;13143.868561314264;13225.338733063501;13233.404496797668;13300.73398532024;21344;25342;25646;25861.965521378934;25947.351316217246;7766.728164514215;7833.960830195812;7848.372130229568;7851.882221766678;7858.017747781528;3318.6515415881468;3883.611638836094;3897.8830635080994;3944.7435916665327;3946.1121247719034;4288.614024737789;4389.990302230441;4394.252976993918;4397.450318710161;4397.934030989538;937.8499440089553;958.5015791788231;961.33667769239;963.802420503795;964.4984608003799;584.7398855466477;633.9683015849189;641.377863472431;655.3315618069543;661.8113837556274;10664.840027399596;11178.105751539857;11714.472848721658;11725.882741172649;11726.237794543327;618.2118644911482;635.8791829552371;638.4158494977137;652.7780554611455;667.2893368562466;5190.169572868323;5281.815136470282;5290.28581141541;5376.274202982626;5400.513953744182;96285.0371496276;96905.0309496896;97337;97506.5124674372;98177;89762.10237897537;91015.1796964057;91296;91597;93378.53310733539;79734;80762;80780;80987;82249;9842.803143936939;10445.791084178121;10593.417362044853;10663.786724265627;10908.454577270946;35987.46018809667;36184.276314473034;36544.72093476715;36657;37715.62284377121;46074.308885366074;46497;46699.431531266106;47188.82027949329;47302.763486182346;13843.861561384258;14047.36786844574;14136.750855629587;14352.784708229594;14385.7842132366;15355.616109596902;15738.9213053934;17580.73628895593;18030.72953905666;18862.811371886106;14704.926475367554;16100.919495402448;17002.82997170062;17108.230129644446;17270.22283997198;16430.424935127212;16525.25636346343;17314.567135821704;17386.826131738522;17503.82496175022;13757.79363309531;14037.1577705338;14084.859151408764;14284.357203926056;16724.832751672813;11707.473163707824;11931.105167112328;11975.820362694742;12115.636530904087;12146.331951742739;11388.316700997966;12176.573819916615;13060.151090179108;13561.321933903462;13854.182670625038 08:01:42 INFO - PID 1637 | __end_tp_report 08:01:42 INFO - PID 1637 | __start_cc_report 08:01:42 INFO - PID 1637 | _x_x_mozilla_cycle_collect,2931 08:01:42 INFO - PID 1637 | __end_cc_report 08:01:42 INFO - PID 1637 | __startTimestamp1493996502220__endTimestamp 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | ------- Summary: start ------- 08:01:42 INFO - PID 1637 | Number of tests: 6 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6111.78 Median:6357.62 stddev:756.60 (11.9%) stddev-sans-first:718.36 08:01:42 INFO - PID 1637 | Values: 2996.3 3496.8 3515.4 3515.7 3593.4 5992.9 6132.8 6179.9 6219.6 6310.5 6114.1 6119.0 6162.8 6237.3 6281.7 5263.9 5305.4 5341.6 5375.1 5388.2 5271.4 5407.8 5527.0 5533.4 5564.1 5290.8 5371.1 5388.6 5428.5 5438.3 5329.9 5335.8 5401.4 5427.1 5462.4 5374.1 5399.4 5422.0 5424.3 5479.0 6300.7 6317.8 6343.7 6344.7 6355.2 5401.5 6402.3 6450.5 6452.3 6650.0 6669.4 6770.4 6770.4 6771.0 6772.1 6311.5 6381.4 6389.2 6394.2 6403.2 6785.9 6867.1 6871.1 6881.7 6894.6 6319.3 6375.7 6389.6 6480.4 6520.9 6677.2 6687.7 6689.1 6696.2 6705.5 6519.2 6580.4 6587.1 6593.7 6599.6 6357.6 6408.0 6420.5 6420.6 6606.2 5179.1 5180.8 5200.3 5204.6 5212.6 4095.5 4522.1 4675.7 4676.7 4695.0 6174.6 6268.8 6287.8 6312.7 6323.5 6745.5 6746.4 6762.0 6766.4 6779.6 6281.8 6296.8 6299.2 6316.6 6325.2 5686.2 5801.4 5804.4 5809.2 5892.1 6896.7 6937.2 6939.2 6952.5 6957.8 6726.9 6764.8 6774.0 6777.9 6779.0 6629.7 6722.7 6728.1 6811.9 6906.7 5160.7 6581.3 6617.6 6619.1 6661.1 6628.9 6660.5 6739.7 6747.1 6768.5 6320.5 6548.4 6555.9 6563.1 6570.0 6344.9 6354.0 6359.3 6364.9 6365.0 6327.1 6365.6 6367.4 6375.6 6378.7 5377.2 6424.2 6461.9 6481.5 6489.6 6354.8 6356.8 6357.5 6375.4 6387.4 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:42757.08 Median:3160.80 stddev:72795.43 (2303.1%) stddev-sans-first:72931.45 08:01:42 INFO - PID 1637 | Values: 793.0 801.4 1101.9 1117.5 1126.0 34061.3 34090.0 34293.8 36906.8 37097.3 39282.0 39590.4 39610.8 39687.8 39717.8 8215.3 8356.7 8362.7 8366.6 8372.8 5901.5 5999.6 8220.4 8295.9 8416.7 8496.2 8511.1 8534.6 8535.4 8584.9 8417.8 8505.5 8522.0 8525.0 8537.2 5830.9 5959.2 8036.9 8185.1 8296.6 37411.8 37765.0 37772.2 37796.8 37823.0 3527.5 3563.6 3566.2 3568.1 3570.3 30681.0 30708.2 30723.5 30761.4 30784.8 3128.4 3193.2 3201.1 3210.2 3221.9 263442.0 266308.7 266615.0 266679.0 267792.0 49804.5 49826.0 49903.8 49942.0 49971.0 41424.4 41435.6 41631.6 50110.5 50648.7 13477.3 13530.3 13532.8 13536.9 13542.3 10885.5 10892.3 10900.2 10901.2 10933.6 12815.1 12824.6 12858.9 12925.3 12932.9 4550.5 5499.9 5509.1 5519.9 5529.9 1042.3 1047.0 1048.5 1085.3 1089.3 23634.9 24641.5 24659.1 24672.1 24724.0 1125.8 1126.3 1158.9 1162.0 1186.6 11593.1 12552.7 12586.0 12586.2 12592.4 296394.0 296954.0 297126.0 298203.0 299983.5 52558.0 52630.7 52841.7 52882.7 52914.5 1463.6 1519.8 1523.6 1526.4 1544.8 19969.9 23930.8 24158.8 24170.6 24174.5 38801.8 38844.4 38865.0 38870.2 38933.2 268378.0 269427.0 269506.0 269745.0 270120.0 29956.9 30013.2 34446.5 34482.7 34868.8 42089.6 42185.0 43137.0 43525.8 43740.3 25328.5 29229.4 29250.0 30192.0 30643.8 24898.1 26276.5 30001.5 30180.5 30364.0 34650.1 35471.5 35704.6 35721.0 35790.5 29284.0 29329.0 30313.8 30322.5 30712.4 21955.9 22696.4 23950.6 24087.9 24322.8 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:34201.66 Median:2238.52 stddev:89343.57 (3991.2%) stddev-sans-first:89558.21 08:01:42 INFO - PID 1637 | Values: 425.5 434.4 436.8 441.8 456.8 19660.4 21061.6 21608.2 21791.0 21812.0 21238.7 21574.7 21630.8 21821.6 21872.9 5808.4 6100.1 6108.8 6120.1 6125.4 4559.5 5651.4 5900.3 5968.9 6114.5 5885.6 5919.4 5919.8 5924.4 5938.6 5863.3 6121.2 6172.7 6180.3 6185.8 5359.0 5402.4 5467.0 5469.5 5470.3 26216.6 26682.7 26689.5 26889.3 27181.0 2190.3 2254.8 2255.5 2259.0 2260.6 17240.5 17271.7 17809.9 17810.4 17837.4 2069.7 2111.7 2145.6 2147.5 2151.4 127984.4 147458.0 158445.0 158819.0 161703.0 9890.6 11357.8 12004.2 12018.4 12188.6 16041.9 16050.7 16067.0 16088.5 16101.9 8231.8 8514.7 8567.7 8722.3 8724.0 7986.9 8002.2 8033.4 8190.1 8194.3 6054.7 7344.4 7451.6 7467.9 7518.2 2062.5 2074.8 2085.7 2119.1 2222.3 930.4 953.6 959.7 963.5 999.4 19669.5 20100.9 20643.4 20662.3 21001.3 999.2 1001.8 1003.7 1031.8 1035.2 9312.6 10341.7 10596.8 10630.3 10837.2 508269.0 523081.0 533122.0 533476.0 539662.0 82439.0 115840.4 118370.4 121542.0 122457.4 1107.9 1118.7 1159.3 1160.5 1163.5 21135.5 21387.7 21640.0 21893.8 21919.0 54749.2 57737.1 58615.0 58885.4 58904.0 74219.6 74934.3 74945.0 78374.6 80120.0 8420.0 8708.8 8720.2 8798.3 8814.3 13287.1 13385.9 13393.7 13740.0 13790.0 10777.1 11683.0 11798.8 11820.8 11956.6 8394.5 10147.8 10591.8 10779.9 11102.9 11735.1 11893.0 11912.0 11975.7 12023.6 8529.7 8535.7 8539.3 8747.0 9018.3 11464.0 11650.7 11869.9 11961.2 11962.5 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6987.98 Median:3828.67 stddev:4353.27 (113.7%) stddev-sans-first:4345.00 08:01:42 INFO - PID 1637 | Values: 1369.2 1422.9 1442.1 1443.4 1446.7 10988.8 11451.8 11471.5 11473.7 11518.6 10888.2 11176.1 11533.9 11556.0 11566.6 2448.2 2519.5 2524.0 2526.7 2528.8 2454.9 2509.1 2510.6 2512.8 2513.6 2429.7 2520.3 2526.2 2527.4 2528.6 2492.5 2502.5 2513.5 2522.7 2523.2 2492.9 2507.9 2514.8 2516.2 2517.7 11114.6 11378.4 11399.7 11402.7 11404.8 6319.8 6571.0 6594.0 6594.6 6598.5 3861.7 4181.8 4264.9 4269.6 4273.5 1159.9 1198.6 1267.6 1310.8 1313.9 14724.9 14959.0 15408.5 15496.8 15763.7 5478.9 5706.8 5949.2 5992.3 5995.0 13448.3 13451.5 13452.1 13458.9 13515.9 6614.4 6618.4 6619.1 6619.5 6624.2 5851.2 5997.3 6092.9 6159.9 6165.3 3795.7 4370.0 4516.0 4532.4 4601.7 1343.2 1404.4 1413.9 1423.7 1432.2 805.8 806.3 822.2 829.9 855.3 5724.0 5837.9 5995.3 6352.1 6781.2 595.9 599.2 613.2 619.7 621.1 4521.0 4540.6 4570.2 4710.9 4775.8 14310.1 14348.0 14531.4 14572.6 14596.1 14019.2 14321.3 14525.6 14536.3 14543.2 7166.0 7397.6 8200.4 8468.5 8473.1 5745.8 6882.7 7064.5 7071.6 7129.6 12498.6 12696.4 12796.7 13150.9 13161.4 12973.3 13282.4 13296.7 13298.8 13457.5 8895.1 8999.1 9159.8 9186.8 9190.7 10311.3 10409.8 10529.7 10535.4 10546.8 8521.6 8664.2 8677.7 8785.2 8847.4 7502.2 8656.6 8749.8 8784.8 8801.0 8008.8 8145.0 8155.2 8163.4 8166.7 4907.7 5650.2 5949.6 5960.1 6178.9 5939.4 5964.8 6091.8 6126.0 6269.7 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5080.65 Median:5800.54 stddev:1611.43 (27.8%) stddev-sans-first:1576.21 08:01:42 INFO - PID 1637 | Values: 342.4 379.7 393.1 404.1 405.0 5128.4 5971.8 6035.2 6060.5 6079.2 5597.9 5814.2 5877.6 5899.0 5942.3 3930.9 4207.9 4296.4 4369.7 4375.3 4356.7 4366.6 4371.3 4373.3 4421.2 3524.4 4277.3 4311.1 4350.8 4368.5 3177.4 3935.3 4313.5 4317.5 4320.2 3741.4 3756.5 3766.2 3768.9 3781.3 4774.7 5001.3 5390.5 5661.3 5879.9 5119.0 5119.7 5120.3 5136.3 5157.9 6246.7 6266.4 6305.7 6334.1 6348.2 4113.4 4198.7 4239.0 4245.3 4252.5 6278.9 6420.1 6423.7 6564.2 6633.6 4087.3 4238.0 4239.8 4246.6 4293.5 4131.5 4344.5 4359.0 4368.9 4371.5 4901.6 4956.3 4982.0 5041.0 5065.9 6198.3 6212.5 6218.2 6232.7 6241.3 1328.8 1345.0 1419.7 1442.2 1474.4 953.2 962.5 962.9 978.4 979.8 5134.5 5752.9 5782.2 5816.4 5820.4 6478.5 6478.6 6481.9 6489.5 6499.3 5262.5 5575.4 5683.2 5728.9 5837.6 2835.9 3294.1 3326.4 3380.5 3390.1 6512.6 6621.7 6662.8 6675.6 6781.4 5722.5 6491.3 6664.0 6664.3 6694.2 5786.8 6528.8 6683.5 6684.8 6707.8 5939.2 6382.2 6424.7 6469.9 6502.7 5668.3 6186.6 6376.1 6381.2 6433.4 4791.8 6387.9 6617.3 6763.9 6949.4 3824.7 4082.1 4247.4 4274.4 4282.5 5820.5 6501.4 6543.8 6593.8 6631.5 5853.6 5905.9 6293.8 6333.4 6350.2 5779.4 6039.8 6314.3 6324.8 6330.0 5214.6 5486.7 6221.0 6294.7 6322.6 6216.6 6427.3 6431.9 6449.3 6472.8 5232.4 6273.0 6305.5 6309.6 6408.6 08:01:42 INFO - PID 1637 | 08:01:42 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:20672.13 Median:17799.83 stddev:28695.32 (161.2%) stddev-sans-first:28737.14 08:01:42 INFO - PID 1637 | Values: 829.8 834.7 852.3 854.6 863.9 16145.2 16269.6 16580.0 16614.0 17851.5 17748.2 17887.3 17948.9 18159.5 18177.0 1013.3 1045.9 1046.9 1048.2 1049.7 1028.4 1035.2 1037.8 1038.0 1041.0 1059.9 1062.2 1062.5 1065.6 1101.2 1056.9 1057.7 1058.0 1059.7 1090.9 789.9 790.2 790.9 791.2 820.3 16505.3 16609.5 17113.8 17132.8 17168.0 17091.8 17334.8 17464.7 17686.0 17698.2 11830.3 12474.2 12581.6 13297.9 13591.2 3394.0 3407.4 3446.4 3446.6 3449.7 93155.0 115653.0 116351.0 119911.0 120005.4 11171.8 13143.9 13225.3 13233.4 13300.7 21344.0 25342.0 25646.0 25862.0 25947.4 7766.7 7834.0 7848.4 7851.9 7858.0 3318.7 3883.6 3897.9 3944.7 3946.1 4288.6 4390.0 4394.3 4397.5 4397.9 937.8 958.5 961.3 963.8 964.5 584.7 634.0 641.4 655.3 661.8 10664.8 11178.1 11714.5 11725.9 11726.2 618.2 635.9 638.4 652.8 667.3 5190.2 5281.8 5290.3 5376.3 5400.5 96285.0 96905.0 97337.0 97506.5 98177.0 89762.1 91015.2 91296.0 91597.0 93378.5 79734.0 80762.0 80780.0 80987.0 82249.0 9842.8 10445.8 10593.4 10663.8 10908.5 35987.5 36184.3 36544.7 36657.0 37715.6 46074.3 46497.0 46699.4 47188.8 47302.8 13843.9 14047.4 14136.8 14352.8 14385.8 15355.6 15738.9 17580.7 18030.7 18862.8 14704.9 16100.9 17002.8 17108.2 17270.2 16430.4 16525.3 17314.6 17386.8 17503.8 13757.8 14037.2 14084.9 14284.4 16724.8 11707.5 11931.1 11975.8 12115.6 12146.3 11388.3 12176.6 13060.2 13561.3 13854.2 08:01:42 INFO - PID 1637 | -------- Summary: end -------- 08:01:42 INFO - PID 1637 | 08:01:42 INFO - TEST-INFO | 1637: exit 0 08:01:42 INFO - TEST-OK | dromaeo_css | took 1118864ms 08:01:42 INFO - TEST-START | kraken 08:01:42 INFO - operating with platform_type : mac_ 08:01:42 INFO - Initialising browser for kraken test... 08:01:42 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpHi5pus/profile http://localhost:49239/getInfo.html) 08:01:45 INFO - PID 1649 | __metrics Screen width/height:1600/1200 08:01:45 INFO - PID 1649 | colorDepth:24 08:01:45 INFO - PID 1649 | Browser inner width/height: 1024/691 08:01:45 INFO - PID 1649 | __metrics 08:01:45 INFO - PID 1649 | 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" 08:01:45 INFO - PID 1649 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:01:46 INFO - TEST-INFO | 1649: exit 0 08:01:46 INFO - Browser initialized. 08:01:46 INFO - Running cycle 1/1 for kraken test... 08:01:46 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpHi5pus/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:58 INFO - PID 1650 | RSS: Main: 239460352 08:01:58 INFO - PID 1650 | 08:02:01 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 08:02:02 INFO - PID 1650 | RSS: Main: 308006912 08:02:02 INFO - PID 1650 | 08:02:04 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 08:02:05 INFO - PID 1650 | RSS: Main: 289959936 08:02:05 INFO - PID 1650 | 08:02:08 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 08:02:09 INFO - PID 1650 | RSS: Main: 382853120 08:02:09 INFO - PID 1650 | 08:02:11 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 08:02:11 INFO - PID 1650 | RSS: Main: 284790784 08:02:11 INFO - PID 1650 | 08:02:13 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:02:14 INFO - PID 1650 | RSS: Main: 288219136 08:02:14 INFO - PID 1650 | 08:02:17 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 08:02:17 INFO - PID 1650 | RSS: Main: 352894976 08:02:17 INFO - PID 1650 | 08:02:20 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 08:02:21 INFO - PID 1650 | RSS: Main: 374665216 08:02:21 INFO - PID 1650 | 08:02:24 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 08:02:25 INFO - PID 1650 | RSS: Main: 387117056 08:02:25 INFO - PID 1650 | 08:02:26 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:02:27 INFO - PID 1650 | RSS: Main: 328306688 08:02:27 INFO - PID 1650 | 08:02:29 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:02:29 INFO - PID 1650 | RSS: Main: 331821056 08:02:29 INFO - PID 1650 | 08:02:31 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:02:32 INFO - PID 1650 | RSS: Main: 315613184 08:02:32 INFO - PID 1650 | 08:02:35 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:02:36 INFO - PID 1650 | RSS: Main: 335802368 08:02:36 INFO - PID 1650 | 08:02:39 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:02:39 INFO - PID 1650 | RSS: Main: 346152960 08:02:39 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 08:02:41 INFO - PID 1650 | RSS: Main: 388870144 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | __start_tp_report 08:02:41 INFO - PID 1650 | _x_x_mozilla_page_load 08:02:41 INFO - PID 1650 | _x_x_mozilla_page_load_details 08:02:41 INFO - PID 1650 | |i|pagename|runs| 08:02:41 INFO - PID 1650 | |0;ai-astar;110;115;144;165;112;110;110;109;111;111 08:02:41 INFO - PID 1650 | |1;audio-beat-detection;140;107;104;147;107;108;104;105;124;114 08:02:41 INFO - PID 1650 | |2;audio-dft;183;185;184;185;186;185;184;183;181;183 08:02:41 INFO - PID 1650 | |3;audio-fft;81;60;57;104;87;88;71;57;81;57 08:02:41 INFO - PID 1650 | |4;audio-oscillator;86;70;85;69;69;67;87;69;68;89 08:02:41 INFO - PID 1650 | |5;imaging-gaussian-blur;86;86;87;86;87;86;87;87;87;86 08:02:41 INFO - PID 1650 | |6;imaging-darkroom;95;94;94;94;94;94;93;94;94;94 08:02:41 INFO - PID 1650 | |7;imaging-desaturate;86;86;97;138;68;112;129;90;83;83 08:02:41 INFO - PID 1650 | |8;json-parse-financial;53;49;50;49;50;50;49;48;49;47 08:02:41 INFO - PID 1650 | |9;json-stringify-tinderbox;54;60;56;71;62;60;58;55;56;58 08:02:41 INFO - PID 1650 | |10;stanford-crypto-aes;83;83;70;82;85;108;103;103;82;82 08:02:41 INFO - PID 1650 | |11;stanford-crypto-ccm;142;155;151;135;134;136;134;136;177;153 08:02:41 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;181;182;182;183;185;221;181;176;182;182 08:02:41 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;67;67;67;67;69;66;67;67;68;71 08:02:41 INFO - PID 1650 | __end_tp_report 08:02:41 INFO - PID 1650 | __start_cc_report 08:02:41 INFO - PID 1650 | _x_x_mozilla_cycle_collect,2208 08:02:41 INFO - PID 1650 | __end_cc_report 08:02:41 INFO - PID 1650 | __startTimestamp1493996561787__endTimestamp 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | ------- Summary: start ------- 08:02:41 INFO - PID 1650 | Number of tests: 14 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:119.70 Median:111.50 stddev:19.07 (17.1%) stddev-sans-first:19.90 08:02:41 INFO - PID 1650 | Values: 110.0 115.0 144.0 165.0 112.0 110.0 110.0 109.0 111.0 111.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:116.00 Median:111.00 stddev:15.78 (14.2%) stddev-sans-first:14.14 08:02:41 INFO - PID 1650 | Values: 140.0 107.0 104.0 147.0 107.0 108.0 104.0 105.0 124.0 114.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.45 (0.8%) stddev-sans-first:1.50 08:02:41 INFO - PID 1650 | Values: 183.0 185.0 184.0 185.0 186.0 185.0 184.0 183.0 181.0 183.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:74.30 Median:76.00 stddev:16.45 (21.6%) stddev-sans-first:17.26 08:02:41 INFO - PID 1650 | Values: 81.0 60.0 57.0 104.0 87.0 88.0 71.0 57.0 81.0 57.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.50 stddev:9.42 (12.2%) stddev-sans-first:9.26 08:02:41 INFO - PID 1650 | Values: 86.0 70.0 85.0 69.0 69.0 67.0 87.0 69.0 68.0 89.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 08:02:41 INFO - PID 1650 | Values: 86.0 86.0 87.0 86.0 87.0 86.0 87.0 87.0 87.0 86.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 08:02:41 INFO - PID 1650 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:84.50 stddev:22.25 (26.3%) stddev-sans-first:23.22 08:02:41 INFO - PID 1650 | Values: 86.0 86.0 97.0 138.0 68.0 112.0 129.0 90.0 83.0 83.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:49.50 stddev:1.58 (3.2%) stddev-sans-first:1.00 08:02:41 INFO - PID 1650 | Values: 53.0 49.0 50.0 49.0 50.0 50.0 49.0 48.0 49.0 47.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:4.90 (8.3%) stddev-sans-first:4.85 08:02:41 INFO - PID 1650 | Values: 54.0 60.0 56.0 71.0 62.0 60.0 58.0 55.0 56.0 58.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:82.00 stddev:12.21 (14.9%) stddev-sans-first:12.81 08:02:41 INFO - PID 1650 | Values: 83.0 83.0 70.0 82.0 85.0 108.0 103.0 103.0 82.0 82.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:146.50 stddev:13.89 (9.5%) stddev-sans-first:14.68 08:02:41 INFO - PID 1650 | Values: 142.0 155.0 151.0 135.0 134.0 136.0 134.0 136.0 177.0 153.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.50 Median:182.00 stddev:12.68 (7.0%) stddev-sans-first:13.34 08:02:41 INFO - PID 1650 | Values: 181.0 182.0 182.0 183.0 185.0 221.0 181.0 176.0 182.0 182.0 08:02:41 INFO - PID 1650 | 08:02:41 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.00 stddev:1.43 (2.1%) stddev-sans-first:1.50 08:02:41 INFO - PID 1650 | Values: 67.0 67.0 67.0 67.0 69.0 66.0 67.0 67.0 68.0 71.0 08:02:41 INFO - PID 1650 | -------- Summary: end -------- 08:02:41 INFO - PID 1650 | 08:02:42 INFO - TEST-INFO | 1650: exit 0 08:02:42 INFO - TEST-OK | kraken | took 60021ms 08:02:42 INFO - SUITE-END | took 1178s 08:02:42 INFO - Completed test suite (00:19:38) 08:02:42 INFO - javascript benchmark 08:02:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7699.804108643939, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2996.265914850861, 3496.7552271340983, 3515.4375299952003, 3515.6660117288848, 3593.371160046991, 5992.910106348409, 6132.785352512663, 6179.9382006179985, 6219.564630475868, 6310.4636105930995, 6114.144019837216, 6119.0151432184075, 6162.753489860412, 6237.251529816417, 6281.748730050793, 5263.921041184385, 5305.389880163782, 5341.626086173969, 5375.11310633745, 5388.191771234307, 5271.4201437842, 5407.783688652449, 5527.0, 5533.418991005947, 5564.137558678412, 5290.788368465257, 5371.113766228567, 5388.649737767033, 5428.45715428458, 5438.320209973754, 5329.907368989348, 5335.759890804923, 5401.351837779453, 5427.104527752916, 5462.371827239878, 5374.086405311106, 5399.379071406799, 5421.972890135564, 5424.294841670597, 5479.013777520045, 6300.653464059475, 6317.810465686037, 6343.746250149989, 6344.6827658616885, 6355.205599300088, 5401.459854014606, 6402.263739669905, 6450.548461607689, 6452.322506136882, 6649.966750166266, 6669.43309818666, 6770.356816102462, 6770.356816102511, 6770.966145169292, 6772.08576842125, 6311.526635502356, 6381.361863813628, 6389.233292004989, 6394.1687580614225, 6403.199600049994, 6785.93214067853, 6867.07294515244, 6871.072405225231, 6881.724731010803, 6894.620795856175, 6319.33646967071, 6375.71309291083, 6389.584676995981, 6480.384363485461, 6520.902186467207, 6677.232118306457, 6687.732490700318, 6689.13041304637, 6696.229933557605, 6705.4970877184405, 6519.152510173742, 6580.440662543738, 6587.143671322793, 6593.703283352261, 6599.637019963948, 6357.618542887441, 6407.967960160169, 6420.5184611154355, 6420.582662126947, 6606.174228221473, 5179.119549676564, 5180.766865491062, 5200.349956255468, 5204.609654275944, 5212.635115541876, 4095.488063992001, 4522.118186953512, 4675.696079754805, 4676.69601475903, 4695.037517308943, 6174.567780255339, 6268.843278918063, 6287.811365659038, 6312.715927783261, 6323.462505687068, 6745.527813053137, 6746.392824645805, 6762.0, 6766.391024807693, 6779.62712050832, 6281.780137695158, 6296.8425789354715, 6299.212598425197, 6316.557840951089, 6325.209348831396, 5686.2039314496005, 5801.361850196475, 5804.361520232771, 5809.157672137517, 5892.086726557305, 6896.689648965808, 6937.167539895144, 6939.236683964659, 6952.478564107813, 6957.82605434858, 6726.899096513458, 6764.830879228059, 6773.966130169318, 6777.93222067773, 6778.96610516964, 6629.668516574248, 6722.697478613376, 6728.091707619409, 6811.863762724817, 6906.654667266516, 5160.73561977318, 6581.276059633341, 6617.569857959217, 6619.106420633153, 6661.100751398693, 6628.9393697008245, 6660.500462465237, 6739.6967136478, 6747.089142965637, 6768.458523318024, 6320.5259605528845, 6548.377904099103, 6555.934440655532, 6563.146790917342, 6570.047343135123, 6344.936550634435, 6354.0, 6359.332270111757, 6364.93635063662, 6365.0, 6327.11420401135, 6365.554411191172, 6367.363263673781, 6375.553711240169, 6378.74485020594, 5377.247185394126, 6424.164858568356, 6461.870762584816, 6481.5138864584405, 6489.5781774184525, 6354.777582784581, 6356.809295721136, 6357.491400688026, 6375.362463753588, 6387.393197646216], "value": 6058.36691255196, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.9762107136785, 801.3949468151546, 1101.911847052236, 1117.5138814615648, 1126.003486914081, 34061.318773624545, 34089.977300680985, 34293.79971700994, 36906.8154659227, 37097.25805483889, 39282.0, 39590.40614390779, 39610.8019459903, 39687.809365719004, 39717.801410992906, 8215.34277257818, 8356.665733370657, 8362.749117526484, 8366.581670916461, 8372.790680232982, 5901.5278777697895, 5999.550033747465, 8220.424570280084, 8295.917040829605, 8416.663333466655, 8496.150384961516, 8511.10633383495, 8534.61594228258, 8535.445196062235, 8584.926884139482, 8417.789555261137, 8505.532195729233, 8522.0, 8525.019622743403, 8537.231649151576, 5830.941690583082, 5959.225300710923, 8036.879446808302, 8185.140560241149, 8296.585170741439, 37411.812940935124, 37765.0, 37772.244555108744, 37796.81101594502, 37823.0, 3527.470879368115, 3563.625819288963, 3566.1797786508987, 3568.07230120168, 3570.321638888629, 30681.0, 30708.2322941926, 30723.539146912815, 30761.384772304653, 30784.846075769477, 3128.436881361354, 3193.1538142392283, 3201.08769000836, 3210.2455922858107, 3221.9355612887844, 263442.0, 266308.66845665645, 266615.0, 266679.0, 267792.0, 49804.50195498071, 49826.0, 49903.75048124736, 49942.0, 49971.000579988926, 41424.37863432051, 41435.58564414378, 41631.58368416338, 50110.49889501058, 50648.74675626598, 13477.258750768802, 13530.255835929118, 13532.79700804469, 13536.864631353757, 13542.322883855768, 10885.455727213608, 10892.346459212473, 10900.236983411243, 10901.182411319183, 10933.617323393642, 12815.102942793916, 12824.551140710031, 12858.9357053216, 12925.289109098901, 12932.935335323264, 4550.544945505423, 5499.890002199933, 5509.063459211943, 5519.9448005520235, 5529.917051244234, 1042.2599954032721, 1046.9738256543646, 1048.501961568249, 1085.2891356161726, 1089.2865173311493, 23634.88182559076, 24641.50716985686, 24659.136930207358, 24672.13647522364, 24724.0, 1125.7973564758422, 1126.3354620773782, 1158.860936687586, 1161.9825702614298, 1186.6262127429832, 11593.098191393548, 12552.686182845502, 12585.993120550334, 12586.18189817659, 12592.4333404997, 296394.0, 296954.0, 297126.0, 298203.0, 299983.50008250255, 52558.0, 52630.73684631552, 52841.735791320796, 52882.73558632259, 52914.47085529096, 1463.5609317204796, 1519.8252200996585, 1523.588631069605, 1526.3513006972214, 1544.8455154484461, 19969.900150499154, 23930.803459827286, 24158.75841241565, 24170.63744043876, 24174.51650967006, 38801.805990969864, 38844.41733374058, 38865.0, 38870.2225955485, 38933.2213355737, 268378.0, 269427.0, 269506.0, 269745.0, 270120.0, 29956.850215749655, 30013.249668758457, 34446.48330274998, 34482.65517344794, 34868.82565587257, 42089.58059403719, 42185.0, 43137.0, 43525.78237108794, 43740.34389484097, 25328.480291182586, 29229.415411692073, 29250.0, 30192.0, 30643.846780765954, 24898.12856550012, 26276.474470510864, 30001.54997674993, 30180.5472917902, 30364.0, 34650.13374665553, 35471.46792798162, 35704.64295357013, 35721.0, 35790.4631430534, 29284.0, 29329.0, 30313.848430757706, 30322.545161823033, 30712.385752285278, 21955.890220548794, 22696.432589185402, 23950.64074038856, 24087.879560602083, 24322.756772432756], "value": 17050.54058626819, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.48990572091407, 434.43957295089336, 436.7641473604254, 441.7702794546836, 456.7670488051095, 19660.410187694357, 21061.578768424642, 21608.243711470102, 21791.0, 21812.018459169336, 21238.681419778717, 21574.676379854274, 21630.783692163073, 21821.563568728656, 21872.8906355468, 5808.390119037493, 6100.084987251903, 6108.786192483264, 6120.051392034219, 6125.387461253861, 4559.475660299075, 5651.350094739117, 5900.321463031742, 5968.910466343008, 6114.541409394291, 5885.558583106263, 5919.408059194089, 5919.792807251747, 5924.43717846806, 5938.584299099065, 5863.2670916135485, 6121.204243448346, 6172.660503672296, 6180.3201647818705, 6185.814425567218, 5359.0, 5402.35171779385, 5467.043267428184, 5469.535089517396, 5470.316210473691, 26216.60675089875, 26682.73317266822, 26689.466210675873, 26889.32776680579, 27181.0, 2190.3100523335256, 2254.785795349439, 2255.5037891663806, 2258.977410225893, 2260.559190957764, 17240.482785516455, 17271.654566908717, 17809.910950445166, 17810.37663681778, 17837.39463448296, 2069.658506346451, 2111.714918486, 2145.560160167162, 2147.50607360308, 2151.354593621907, 127984.36007819994, 147458.0, 158445.0, 158819.0, 161703.0, 9890.615313856066, 11357.772844543062, 12004.2197257178, 12018.399080045963, 12188.634340969786, 16041.919790401207, 16050.678986420206, 16067.0, 16088.517344479684, 16101.919490402473, 8231.835363292701, 8514.70198543048, 8567.74296771098, 8722.258608018263, 8724.0, 7986.880196797053, 8002.199780022068, 8033.3974951878845, 8190.058143313476, 8194.262516373556, 6054.727537260834, 7344.412447004227, 7451.552906825608, 7467.887981680279, 7518.173000969889, 2062.5359294158993, 2074.7510298764246, 2085.7497100348055, 2119.0782009825775, 2222.2555443926467, 930.4370855632245, 953.594722243044, 959.7138741244819, 963.5209952722537, 999.4203362050139, 19669.50826229327, 20100.899495502428, 20643.380698579065, 20662.276820311217, 21001.264955726478, 999.2106236073421, 1001.7695929936156, 1003.668789299544, 1031.783325501638, 1035.2132379391567, 9312.627494900264, 10341.689749307532, 10596.841047384296, 10630.255882088335, 10837.241393102408, 508269.0, 523081.0, 533122.0, 533476.0, 539662.0, 82439.0, 115840.42079789715, 118370.4081479587, 121542.0, 122457.38771306086, 1107.9390633515395, 1118.6867677050438, 1159.3160035579049, 1160.5067846165346, 1163.5055101581793, 21135.471613209793, 21387.72245550865, 21640.0, 21893.781062189173, 21919.0, 54749.1787623178, 57737.13394299173, 58615.0, 58885.41114588799, 58904.0, 74219.6289018573, 74934.25065749272, 74945.0, 78374.608126959, 80120.0, 8420.0, 8708.78228044304, 8720.215180633773, 8798.252148567444, 8814.338924580554, 13287.06990510655, 13385.866141338462, 13393.732125357634, 13740.0, 13790.0, 10777.08394786452, 11683.0, 11798.823017654913, 11820.822687659864, 11956.641300760992, 8394.538300393537, 10147.797044059225, 10591.788164236827, 10779.89220107789, 11102.944485277521, 11735.061195104201, 11893.0, 11912.034514305042, 11975.700607484883, 12023.63929082129, 8529.658813647633, 8535.658573657232, 8539.316854651488, 8747.0, 9018.323625728228, 11464.0, 11650.70873228176, 11869.881301186877, 11961.222520536137, 11962.46168922418], "value": 10193.526679716728, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.1579678497724, 1422.9359678814453, 1442.0554536778407, 1443.4370595467763, 1446.7395868743624, 10988.835167472484, 11451.770964580703, 11471.483783229754, 11473.713157171074, 11518.596849110283, 10888.183386246024, 11176.105911527078, 11533.942330288357, 11556.017738492217, 11566.59516916908, 2448.155386391692, 2519.496100779842, 2523.974760252392, 2526.6589010483535, 2528.8229823912334, 2454.9263522094366, 2509.134348649713, 2510.57320255557, 2512.849229046254, 2513.6355228491857, 2429.708434987804, 2520.3069155982066, 2526.2168727694475, 2527.3807917060344, 2528.5575024370755, 2492.501499700067, 2502.4869901670204, 2513.522430738154, 2522.672052633164, 2523.179966510882, 2492.887820048102, 2507.8746062696973, 2514.8365356251784, 2516.207394670673, 2517.710463296726, 11114.610988615359, 11378.37418941958, 11399.658010259705, 11402.71493212668, 11404.771904561945, 6319.778807741707, 6570.967145164292, 6594.043863639746, 6594.637294948776, 6598.47212223021, 3861.6717579005817, 4181.832726690929, 4264.9147017059795, 4269.594388533084, 4273.487181538204, 1159.8550181227347, 1198.640407877642, 1267.5500197429872, 1310.7837206860952, 1313.868613138688, 14724.852751472563, 14959.0, 15408.460703875311, 15496.845031549765, 15763.68472630541, 5478.863028424242, 5706.828795136153, 5949.167116603679, 5992.250968628921, 5995.010823214208, 13448.260345680887, 13451.461941522426, 13452.1256118352, 13458.93270533641, 13515.86484135146, 6614.437772789271, 6618.437432818168, 6619.106420633249, 6619.5035372347265, 6624.238212605515, 5851.210086638334, 5997.310309314397, 6092.878142437126, 6159.876802463926, 6165.260168779685, 3795.6773674237998, 4369.956300436955, 4515.954840451619, 4532.410786597721, 4601.72389656615, 1343.1739480219594, 1404.4241860837008, 1413.9434422623185, 1423.7152569486145, 1432.1621851216946, 805.8066064144563, 806.2622700229342, 822.2394285286086, 829.9336053115736, 855.2901092093672, 5724.0, 5837.9124313135335, 5995.280566332068, 6352.110704501373, 6781.152355955505, 595.9433853783884, 599.1671576508656, 613.2150846915955, 619.6839611797925, 621.0994058614936, 4520.977395113004, 4540.5913467787395, 4570.223062079388, 4710.882227944328, 4775.832845850448, 14310.069845460011, 14348.0, 14531.418743250151, 14572.562823115324, 14596.12423254608, 14019.158850469004, 14321.287096726086, 14525.636859078608, 14536.27318634064, 14543.200123993282, 7166.0, 7397.630118494054, 8200.42597018215, 8468.49189048659, 8473.067962524117, 5745.770169193353, 6882.655867206719, 7064.470164737562, 7071.646417679199, 7129.607871567114, 12498.562550310695, 12696.365181740692, 12796.680082998, 13150.868491315347, 13161.407736652065, 12973.286469244282, 13282.402291896695, 13296.667583310495, 13298.800517992044, 13457.461701531829, 8895.06601806801, 8999.145081217273, 9159.816803664022, 9186.770330741785, 9190.724278271662, 10311.278210525192, 10409.84385234207, 10529.684109476728, 10535.367877927349, 10546.8417973732, 8521.636538154113, 8664.220220180214, 8677.739667809976, 8785.165409286108, 8847.424917380607, 7502.21226771203, 8656.610452529778, 8749.825003499767, 8784.824303514022, 8800.955995219983, 8008.7597372078935, 8144.959275203823, 8155.184481551797, 8163.387745918961, 8166.7133202703335, 4907.705537667752, 5650.23721797552, 5949.6430214187285, 5960.105984102419, 6178.907316390168, 5939.4357536034, 5964.761409543569, 6091.7563297467605, 6125.9693701531205, 6269.749210031548], "value": 5292.567327078015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.4076347918103, 379.7417755925969, 393.08803575704366, 404.0706375336728, 404.95950404959507, 5128.384593848738, 5971.805638872227, 6035.18524999125, 6060.484858786997, 6079.209702738649, 5597.888042239153, 5814.244148260731, 5877.648140927578, 5899.0, 5942.286925568927, 3930.8820735377985, 4207.936880946788, 4296.398504209413, 4369.715968462056, 4375.321825117104, 4356.651467882578, 4366.607005369516, 4371.256886329315, 4373.322135069054, 4421.226285400042, 3524.4008518551777, 4277.272863613178, 4311.116221174668, 4350.847720329789, 4368.475782906055, 3177.380410819891, 3935.330993731073, 4313.503947046099, 4317.460317460317, 4320.179165958473, 3741.38267185914, 3756.4553139794575, 3766.209096089797, 3768.8869333920024, 3781.3193625147464, 4774.713517188945, 5001.349824522825, 5390.5418039466695, 5661.320641523043, 5879.941200587982, 5118.956024740451, 5119.667221630582, 5120.283160357553, 5136.255242989783, 5157.896842063175, 6246.687665616746, 6266.404691554295, 6305.68471576424, 6334.113224148623, 6348.238211414614, 4113.424120623115, 4198.706090573661, 4239.0, 4245.299525578276, 4252.533530552303, 6278.874422511524, 6420.0690899819265, 6423.678816059271, 6564.179477565304, 6633.635150066744, 4087.346024636044, 4237.957620423818, 4239.830406783694, 4246.596573325529, 4293.506246781598, 4131.50421949368, 4344.522102568715, 4359.0, 4368.929612244957, 4371.475422949266, 4901.5588597026435, 4956.256561515729, 4981.975090124526, 5040.974795126001, 5065.8733531662, 6198.2871969723155, 6212.47193988507, 6218.170732316785, 6232.657203853741, 6241.313455519889, 1328.7775246772992, 1344.9932750336186, 1419.7160567886258, 1442.2212005517113, 1474.3881289264834, 953.2374100719396, 962.534805946104, 962.8748262725941, 978.3542861711236, 979.7893452907659, 5134.460881607452, 5752.856178595569, 5782.190493330937, 5816.382627817499, 5820.417958204231, 6478.546501744832, 6478.643674597943, 6481.902771458432, 6489.513286503531, 6499.252585952581, 5262.473752624707, 5575.4424557543925, 5683.232763576947, 5728.911506813692, 5837.620554663933, 2835.8582070896373, 3294.1105901406486, 3326.417876871538, 3380.492926061061, 3390.135515443546, 6512.6092434454085, 6621.735130594819, 6662.800115996528, 6675.632840193836, 6781.406369503163, 5722.456366645162, 6491.285958544557, 6663.966680166568, 6664.333566643297, 6694.230163531256, 5786.826395208151, 6528.804135875836, 6683.465322774166, 6684.766033188815, 6707.832304192434, 5939.227900372924, 6382.20222472191, 6424.682939997383, 6469.8706025880165, 6502.659906591036, 5668.348139963957, 6186.597871138361, 6376.090410384071, 6381.202349706286, 6433.388828061326, 4791.832285869978, 6387.936120638734, 6617.305182955721, 6763.86472270542, 6949.374556289756, 3824.655780979725, 4082.101937573729, 4247.447831781848, 4274.380214868765, 4282.464691913511, 5820.534357251325, 6501.414872661483, 6543.803685889431, 6593.835154121185, 6631.535792494479, 5853.619514731528, 5905.881882362242, 6293.811185664437, 6333.36666333363, 6350.206224221973, 5779.393163717749, 6039.78860739872, 6314.273858506147, 6324.810255692337, 6330.0, 5214.634975551675, 5486.670799751868, 6220.968895155495, 6294.716737746904, 6322.557420980487, 6216.627002379872, 6427.260865000585, 6431.871362572816, 6449.258335291502, 6472.773452929125, 5232.424433312256, 6272.968635156977, 6305.527085468517, 6309.621422714652, 6408.58344207625], "value": 4574.7624815686795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.7510746775965, 834.7328854766474, 852.2968550945468, 854.5769843927253, 863.9006514250859, 16145.19274036298, 16269.593260168502, 16580.00519968803, 16614.0, 17851.464456066307, 17748.201330940108, 17887.284508619672, 17948.897374892535, 18159.54601134972, 18177.0, 1013.2856336282921, 1045.900639439252, 1046.8586740789972, 1048.1614708233421, 1049.7375656085978, 1028.3932479836894, 1035.177034257767, 1037.823569993103, 1037.9827768786595, 1040.9687709368693, 1059.8887116852736, 1062.219268837404, 1062.5324857062867, 1065.5631191211605, 1101.245646731987, 1056.910162636177, 1057.7249915022073, 1057.989619912985, 1059.6715018344262, 1090.9127269818434, 789.9170587088388, 790.1624278265058, 790.8853216283607, 791.2404092071619, 820.3150369441523, 16505.257263423176, 16609.501714948572, 17113.828861711347, 17132.828671713247, 17168.0, 17091.803573749843, 17334.82665173345, 17464.65070698579, 17686.027268500227, 17698.203580838894, 11830.349330786803, 12474.18917770351, 12581.559645412366, 13297.933510332483, 13591.184528928197, 3394.0, 3407.386670399327, 3446.4141096013486, 3446.586409630835, 3449.689527942497, 93155.0, 115653.0, 116351.0, 119911.0, 120005.39997300044, 11171.776564468664, 13143.868561314264, 13225.338733063501, 13233.404496797668, 13300.73398532024, 21344.0, 25342.0, 25646.0, 25861.965521378934, 25947.351316217246, 7766.728164514215, 7833.960830195812, 7848.372130229568, 7851.882221766678, 7858.017747781528, 3318.6515415881468, 3883.611638836094, 3897.8830635080994, 3944.7435916665327, 3946.1121247719034, 4288.614024737789, 4389.990302230441, 4394.252976993918, 4397.450318710161, 4397.934030989538, 937.8499440089553, 958.5015791788231, 961.33667769239, 963.802420503795, 964.4984608003799, 584.7398855466477, 633.9683015849189, 641.377863472431, 655.3315618069543, 661.8113837556274, 10664.840027399596, 11178.105751539857, 11714.472848721658, 11725.882741172649, 11726.237794543327, 618.2118644911482, 635.8791829552371, 638.4158494977137, 652.7780554611455, 667.2893368562466, 5190.169572868323, 5281.815136470282, 5290.28581141541, 5376.274202982626, 5400.513953744182, 96285.0371496276, 96905.0309496896, 97337.0, 97506.5124674372, 98177.0, 89762.10237897537, 91015.1796964057, 91296.0, 91597.0, 93378.53310733539, 79734.0, 80762.0, 80780.0, 80987.0, 82249.0, 9842.803143936939, 10445.791084178121, 10593.417362044853, 10663.786724265627, 10908.454577270946, 35987.46018809667, 36184.276314473034, 36544.72093476715, 36657.0, 37715.62284377121, 46074.308885366074, 46497.0, 46699.431531266106, 47188.82027949329, 47302.763486182346, 13843.861561384258, 14047.36786844574, 14136.750855629587, 14352.784708229594, 14385.7842132366, 15355.616109596902, 15738.9213053934, 17580.73628895593, 18030.72953905666, 18862.811371886106, 14704.926475367554, 16100.919495402448, 17002.82997170062, 17108.230129644446, 17270.22283997198, 16430.424935127212, 16525.25636346343, 17314.567135821704, 17386.826131738522, 17503.82496175022, 13757.79363309531, 14037.1577705338, 14084.859151408764, 14284.357203926056, 16724.832751672813, 11707.473163707824, 11931.105167112328, 11975.820362694742, 12115.636530904087, 12146.331951742739, 11388.316700997966, 12176.573819916615, 13060.151090179108, 13561.321933903462, 13854.182670625038], "value": 8173.225252737491, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1442.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 144.0, 165.0, 112.0, 110.0, 110.0, 109.0, 111.0, 111.0], "value": 119.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 104.0, 147.0, 107.0, 108.0, 104.0, 105.0, 124.0, 114.0], "value": 116.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 185.0, 186.0, 185.0, 184.0, 183.0, 181.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 57.0, 104.0, 87.0, 88.0, 71.0, 57.0, 81.0, 57.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 85.0, 69.0, 69.0, 67.0, 87.0, 69.0, 68.0, 89.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 87.0, 86.0, 87.0, 87.0, 87.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 97.0, 138.0, 68.0, 112.0, 129.0, 90.0, 83.0, 83.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 49.0, 50.0, 49.0, 50.0, 50.0, 49.0, 48.0, 49.0, 47.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 60.0, 56.0, 71.0, 62.0, 60.0, 58.0, 55.0, 56.0, 58.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 70.0, 82.0, 85.0, 108.0, 103.0, 103.0, 82.0, 82.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 155.0, 151.0, 135.0, 134.0, 136.0, 134.0, 136.0, 177.0, 153.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 182.0, 183.0, 185.0, 221.0, 181.0, 176.0, 182.0, 182.0], "value": 185.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 69.0, 66.0, 67.0, 67.0, 68.0, 71.0], "value": 67.6, "unit": "score"}]}]} 08:02:43 INFO - Return code: 0 08:02:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:02:43 INFO - # TBPL SUCCESS # 08:02:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:02:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:02:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:02:43 INFO - 2017-05-05 08:02:43.103 screenresolution[1655:9896] starting screenresolution argv=screenresolution get 08:02:43 INFO - 2017-05-05 08:02:43.106 screenresolution[1655:9896] Display 0: 1600x1200x32@60 08:02:43 INFO - 2017-05-05 08:02:43.112 screenresolution[1656:9899] starting screenresolution argv=screenresolution list 08:02:43 INFO - Available Modes on Display 0 08:02:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:02:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:02:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:02:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:02:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:02:43 INFO - Intel Iris: 08:02:43 INFO - Chipset Model: Intel Iris 08:02:43 INFO - Type: GPU 08:02:43 INFO - Bus: Built-In 08:02:43 INFO - VRAM (Dynamic, Max): 1536 MB 08:02:43 INFO - Vendor: Intel (0x8086) 08:02:43 INFO - Device ID: 0x0a2e 08:02:43 INFO - Revision ID: 0x0009 08:02:43 INFO - Displays: 08:02:43 INFO - Display: 08:02:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:02:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:02:43 INFO - Display Serial Number: 1600x1200 60 08:02:43 INFO - Main Display: Yes 08:02:43 INFO - Mirror: Off 08:02:43 INFO - Online: Yes 08:02:43 INFO - Rotation: Supported 08:02:43 INFO - Return code: 0 08:02:43 INFO - Running post-action listener: _resource_record_post_action 08:02:43 INFO - [mozharness: 2017-05-05 15:02:43.357829Z] Finished run-tests step (success) 08:02:43 INFO - Running post-run listener: _resource_record_post_run 08:02:43 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 37164544; Write bytes: 509656576; Read time: 384; Write time: 1593 08:02:43 INFO - TinderboxPrint: CPU usage
53.6% 08:02:43 INFO - TinderboxPrint: I/O read bytes / time
37,164,544 / 384 08:02:43 INFO - TinderboxPrint: I/O write bytes / time
509,656,576 / 1,593 08:02:43 INFO - TinderboxPrint: CPU idle
2,223.7 (46.3%) 08:02:43 INFO - TinderboxPrint: CPU system
960.1 (20.0%) 08:02:43 INFO - TinderboxPrint: CPU user
1,613.8 (33.6%) 08:02:43 INFO - TinderboxPrint: Swap in / out
245,710,848 / 0 08:02:43 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 196975104; Write bytes: 195012096; Read time: 15043; Write time: 436 08:02:43 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36242944; Write bytes: 297748480; Read time: 370; Write time: 1105 08:02:43 INFO - Running post-run listener: _upload_blobber_files 08:02:43 INFO - Blob upload gear active. 08:02:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:02:43 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 08:02:43 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'] 08:02:43 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 08:02:44 INFO - (blobuploader) - INFO - Open directory for files ... 08:02:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:02:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:02:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:45 INFO - (blobuploader) - INFO - Done attempting. 08:02:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:02:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:02:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:46 INFO - (blobuploader) - INFO - Done attempting. 08:02:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:02:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:02:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:47 INFO - (blobuploader) - INFO - Done attempting. 08:02:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:02:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:02:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:49 INFO - (blobuploader) - INFO - Done attempting. 08:02:49 INFO - (blobuploader) - INFO - Iteration through files over. 08:02:49 INFO - Return code: 0 08:02:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:02:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:02:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d076aaacfa02346a490769cbfe0910ce919adfd40c301e8637258c50d6569a3a5e40a01e904e08ba7ae5a4b4d79b29a031cf6bc540e51e815485c07c6329b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e738fe0abac429a7dc941c54082b5300ba93cf311bd1076d127db763f5bbd17891330812687a00aee6a9f22b7b10e182896481951504bc7a0e503b42b8ede02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d789a6a37f751ab1cb81c26cafa6d568f8b5a53583f300a7fad5e3fcba8b95c30281f6f2def74f2536cec5e53f24929ab0d8279efc3d24b84cd7ae4a9afb0eec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67549ca6c49f8bca2d23720924c0cc55fdd995f0c1eb4e0a09f24036867cda88b7b9b60d3def8165a767f8919fb77f773d03f318cf5ae949b1973a0f238cccd6"} 08:02:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:02:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:02:49 INFO - Contents: 08:02:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d076aaacfa02346a490769cbfe0910ce919adfd40c301e8637258c50d6569a3a5e40a01e904e08ba7ae5a4b4d79b29a031cf6bc540e51e815485c07c6329b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e738fe0abac429a7dc941c54082b5300ba93cf311bd1076d127db763f5bbd17891330812687a00aee6a9f22b7b10e182896481951504bc7a0e503b42b8ede02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d789a6a37f751ab1cb81c26cafa6d568f8b5a53583f300a7fad5e3fcba8b95c30281f6f2def74f2536cec5e53f24929ab0d8279efc3d24b84cd7ae4a9afb0eec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67549ca6c49f8bca2d23720924c0cc55fdd995f0c1eb4e0a09f24036867cda88b7b9b60d3def8165a767f8919fb77f773d03f318cf5ae949b1973a0f238cccd6"} 08:02:49 INFO - Running post-run listener: copy_logs_to_upload_dir 08:02:49 INFO - Copying logs to upload dir... 08:02:49 INFO - mkdir: /builds/slave/test/build/upload/logs 08:02:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.225215 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-05 08:02:49.637172) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 08:02:49.640578) ========= 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.3QUlVIXnyu/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.hirS2HsMT3/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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/1d076aaacfa02346a490769cbfe0910ce919adfd40c301e8637258c50d6569a3a5e40a01e904e08ba7ae5a4b4d79b29a031cf6bc540e51e815485c07c6329b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e738fe0abac429a7dc941c54082b5300ba93cf311bd1076d127db763f5bbd17891330812687a00aee6a9f22b7b10e182896481951504bc7a0e503b42b8ede02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d789a6a37f751ab1cb81c26cafa6d568f8b5a53583f300a7fad5e3fcba8b95c30281f6f2def74f2536cec5e53f24929ab0d8279efc3d24b84cd7ae4a9afb0eec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67549ca6c49f8bca2d23720924c0cc55fdd995f0c1eb4e0a09f24036867cda88b7b9b60d3def8165a767f8919fb77f773d03f318cf5ae949b1973a0f238cccd6"} build_url:https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/CzbxHifST028ApWsIPa79Q/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/1d076aaacfa02346a490769cbfe0910ce919adfd40c301e8637258c50d6569a3a5e40a01e904e08ba7ae5a4b4d79b29a031cf6bc540e51e815485c07c6329b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e738fe0abac429a7dc941c54082b5300ba93cf311bd1076d127db763f5bbd17891330812687a00aee6a9f22b7b10e182896481951504bc7a0e503b42b8ede02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d789a6a37f751ab1cb81c26cafa6d568f8b5a53583f300a7fad5e3fcba8b95c30281f6f2def74f2536cec5e53f24929ab0d8279efc3d24b84cd7ae4a9afb0eec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67549ca6c49f8bca2d23720924c0cc55fdd995f0c1eb4e0a09f24036867cda88b7b9b60d3def8165a767f8919fb77f773d03f318cf5ae949b1973a0f238cccd6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 08:02:49.676261) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 08:02:49.676600) ========= 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.3QUlVIXnyu/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.hirS2HsMT3/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.005133 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 08:02:49.725836) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 08:02:49.726223) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 08:02:49.726590) ========= ========= Total master_lag: 0.18 =========