builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0264 starttime: 1489420097.51 results: success (0) buildid: 20170313072037 builduid: c6d413669beb4ce6b9e9837809e83caa revision: 419c70029023afa80bee2f534bee483224e303bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.511923) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.512419) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.512709) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.540332) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.540755) ========= 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.OLVpBdafud/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.NtUEi0syaw/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-13 08:48:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2017-03-13 08:48:17 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117909 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.683493) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.683858) ========= 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.OLVpBdafud/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.NtUEi0syaw/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.026578 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.732415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:17.732904) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 419c70029023afa80bee2f534bee483224e303bd --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 419c70029023afa80bee2f534bee483224e303bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OLVpBdafud/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.NtUEi0syaw/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-13 08:48:17,840 truncating revision to first 12 chars 2017-03-13 08:48:17,841 Setting DEBUG logging. 2017-03-13 08:48:17,841 attempt 1/10 2017-03-13 08:48:17,841 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/419c70029023?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-13 08:48:18,497 unpacking tar archive at: mozilla-central-419c70029023/testing/mozharness/ program finished with exit code 0 elapsedTime=0.936217 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:18.692538) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:18.692874) ========= script_repo_revision: 419c70029023afa80bee2f534bee483224e303bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:18.693301) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:18.693574) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 08:48:18.713256) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-13 08:48:18.713681) ========= /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.OLVpBdafud/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.NtUEi0syaw/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:48:18 INFO - MultiFileLogger online at 20170313 08:48:18 in /builds/slave/test 08:48:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:48:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:18 INFO - {'append_to_log': False, 08:48:18 INFO - 'base_work_dir': '/builds/slave/test', 08:48:18 INFO - 'blob_upload_branch': 'Firefox', 08:48:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:18 INFO - 'branch': 'Firefox', 08:48:18 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:18 INFO - 'config_files': ('talos/mac_config.py',), 08:48:18 INFO - 'default_actions': ('clobber', 08:48:18 INFO - 'read-buildbot-config', 08:48:18 INFO - 'download-and-extract', 08:48:18 INFO - 'populate-webroot', 08:48:18 INFO - 'create-virtualenv', 08:48:18 INFO - 'install', 08:48:18 INFO - 'run-tests'), 08:48:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:18 INFO - 'download_minidump_stackwalk': True, 08:48:18 INFO - 'download_symbols': 'ondemand', 08:48:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:18 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:18 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:18 INFO - 'gecko_profile': False, 08:48:18 INFO - 'gecko_profile_interval': 0, 08:48:18 INFO - 'installer_path': 'installer.exe', 08:48:18 INFO - 'log_level': 'info', 08:48:18 INFO - 'log_name': 'talos', 08:48:18 INFO - 'log_to_console': True, 08:48:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:48:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:48:18 INFO - 'opt_config_files': (), 08:48:18 INFO - 'pip_index': False, 08:48:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:18 INFO - 'cmd': ('bash', 08:48:18 INFO - '-c', 08:48:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:18 INFO - 'enabled': True, 08:48:18 INFO - 'halt_on_failure': False, 08:48:18 INFO - 'name': 'check_screen_resolution'},), 08:48:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:18 INFO - 'cmd': ('bash', 08:48:18 INFO - '-c', 08:48:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:18 INFO - 'enabled': True, 08:48:18 INFO - 'halt_on_failure': False, 08:48:18 INFO - 'name': 'check_screen_resolution'},), 08:48:18 INFO - 'run_cmd_checks_enabled': True, 08:48:18 INFO - 'suite': 'dromaeojs', 08:48:18 INFO - 'system_bits': '32', 08:48:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:18 INFO - 'title': 't-yosemite-r7-0264', 08:48:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:18 INFO - 'use_talos_json': True, 08:48:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:18 INFO - 'work_dir': 'build'} 08:48:18 INFO - [mozharness: 2017-03-13 15:48:18.932422Z] Running clobber step. 08:48:18 INFO - Running pre-action listener: _resource_record_pre_action 08:48:18 INFO - Running main action method: clobber 08:48:18 INFO - rmtree: /builds/slave/test/build 08:48:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:21 INFO - Running post-action listener: _resource_record_post_action 08:48:21 INFO - [mozharness: 2017-03-13 15:48:21.468875Z] Finished clobber step (success) 08:48:21 INFO - [mozharness: 2017-03-13 15:48:21.469069Z] Running read-buildbot-config step. 08:48:21 INFO - Running pre-action listener: _resource_record_pre_action 08:48:21 INFO - Running main action method: read_buildbot_config 08:48:21 INFO - Using buildbot properties: 08:48:21 INFO - { 08:48:21 INFO - "project": "", 08:48:21 INFO - "product": "firefox", 08:48:21 INFO - "script_repo_revision": "production", 08:48:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:48:21 INFO - "repository": "", 08:48:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:48:21 INFO - "buildid": "20170313072037", 08:48:21 INFO - "pgo_build": "False", 08:48:21 INFO - "basedir": "/builds/slave/test", 08:48:21 INFO - "buildnumber": 21, 08:48:21 INFO - "slavename": "t-yosemite-r7-0264", 08:48:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:48:21 INFO - "platform": "yosemite_r7", 08:48:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:48:21 INFO - "branch": "mozilla-central", 08:48:21 INFO - "repo_path": "mozilla-central", 08:48:21 INFO - "revision": "419c70029023afa80bee2f534bee483224e303bd", 08:48:21 INFO - "stage_platform": "macosx64", 08:48:21 INFO - "builduid": "c6d413669beb4ce6b9e9837809e83caa", 08:48:21 INFO - "slavebuilddir": "test" 08:48:21 INFO - } 08:48:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:48:21 INFO - Running post-action listener: _resource_record_post_action 08:48:21 INFO - [mozharness: 2017-03-13 15:48:21.474405Z] Finished read-buildbot-config step (success) 08:48:21 INFO - [mozharness: 2017-03-13 15:48:21.474647Z] Running download-and-extract step. 08:48:21 INFO - Running pre-action listener: _resource_record_pre_action 08:48:21 INFO - Running main action method: download_and_extract 08:48:21 INFO - mkdir: /builds/slave/test/build/tests 08:48: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')]} 08:48:21 INFO - trying https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:48:21 INFO - Downloading https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/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 08:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/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 08:48:22 INFO - Downloaded 1199 bytes. 08:48:22 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:48:22 INFO - Using the following test package requirements: 08:48:22 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:48:22 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:48:22 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:48:22 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'jsshell-mac.zip'], 08:48:22 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:48:22 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:48:22 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:48:22 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:48:22 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:48:22 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:48:22 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:48:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:48:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:48:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:48:22 INFO - Fetch https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:48:24 INFO - Expected file size: 51787023 08:48:24 INFO - Obtained file size: 51787023 08:48:40 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 08:48:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:48:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:48:40 INFO - Fetch https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:48:41 INFO - Expected file size: 51787023 08:48:41 INFO - Obtained file size: 51787023 08:48:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:48:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:48:56 INFO - Fetch https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:48:58 INFO - Expected file size: 14404438 08:48:58 INFO - Obtained file size: 14404438 08:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:58 INFO - trying https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:48:58 INFO - Downloading https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:48:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/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 08:49:00 INFO - Downloaded 74906679 bytes. 08:49:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:49:00 INFO - mkdir: /builds/slave/test/properties 08:49:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:00 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:00 INFO - Contents: 08:49:00 INFO - build_url:https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:49:01 INFO - Running post-action listener: _resource_record_post_action 08:49:01 INFO - Running post-action listener: set_extra_try_arguments 08:49:01 INFO - [mozharness: 2017-03-13 15:49:01.291976Z] Finished download-and-extract step (success) 08:49:01 INFO - [mozharness: 2017-03-13 15:49:01.292110Z] Running populate-webroot step. 08:49:01 INFO - Running pre-action listener: _resource_record_pre_action 08:49:01 INFO - Running main action method: populate_webroot 08:49:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:01 INFO - '--tppagecycles', 08:49:01 INFO - '7'], 08:49:01 INFO - 'tests': ['tsvgm']}}, 08:49:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:49:01 INFO - 'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:49:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:49:01 INFO - 'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['damp', 'tps']}, 08:49:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:49:01 INFO - 'tests': ['damp', 'tps']}, 08:49:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['dromaeo_dom']}, 08:49:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['basic_compositor_video']}, 08:49:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['a11yr', 08:49:01 INFO - 'ts_paint', 08:49:01 INFO - 'tpaint', 08:49:01 INFO - 'sessionrestore', 08:49:01 INFO - 'sessionrestore_no_auto_restore', 08:49:01 INFO - 'tabpaint']}, 08:49:01 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:01 INFO - 'ts_paint', 08:49:01 INFO - 'tpaint', 08:49:01 INFO - 'sessionrestore', 08:49:01 INFO - 'sessionrestore_no_auto_restore', 08:49:01 INFO - 'tabpaint']}, 08:49:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:01 INFO - 'ts_paint', 08:49:01 INFO - 'tpaint', 08:49:01 INFO - 'sessionrestore', 08:49:01 INFO - 'sessionrestore_no_auto_restore', 08:49:01 INFO - 'tabpaint']}, 08:49:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:01 INFO - 'ts_paint', 08:49:01 INFO - 'tpaint', 08:49:01 INFO - 'sessionrestore', 08:49:01 INFO - 'sessionrestore_no_auto_restore', 08:49:01 INFO - 'tabpaint']}, 08:49:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['a11yr', 08:49:01 INFO - 'ts_paint', 08:49:01 INFO - 'tpaint', 08:49:01 INFO - 'sessionrestore', 08:49:01 INFO - 'sessionrestore_no_auto_restore', 08:49:01 INFO - 'tabpaint']}, 08:49:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['a11yr', 08:49:01 INFO - 'ts_paint', 08:49:01 INFO - 'tpaint', 08:49:01 INFO - 'sessionrestore', 08:49:01 INFO - 'sessionrestore_no_auto_restore', 08:49:01 INFO - 'tabpaint']}, 08:49:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['tsvgx', 08:49:01 INFO - 'tsvgr_opacity', 08:49:01 INFO - 'tart', 08:49:01 INFO - 'tscrollx', 08:49:01 INFO - 'cart', 08:49:01 INFO - 'tsvg_static']}, 08:49:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:01 INFO - 'tsvgr_opacity', 08:49:01 INFO - 'tart', 08:49:01 INFO - 'tscrollx', 08:49:01 INFO - 'cart', 08:49:01 INFO - 'tsvg_static']}, 08:49:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:49:01 INFO - 'talos_options': ['--disable-e10s'], 08:49:01 INFO - 'tests': ['tp5o']}, 08:49:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:49:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:49:01 INFO - 'talos_options': ['--disable-e10s', 08:49:01 INFO - '--xperf_path', 08:49:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:01 INFO - 'tests': ['tp5n']}, 08:49:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:49:01 INFO - 'talos_options': ['--xperf_path', 08:49:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:01 INFO - 'tests': ['tp5n']}}, 08:49:01 INFO - 'talos.zip': {'path': '', 08:49:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:01 INFO - Running post-action listener: _resource_record_post_action 08:49:01 INFO - [mozharness: 2017-03-13 15:49:01.310892Z] Finished populate-webroot step (success) 08:49:01 INFO - [mozharness: 2017-03-13 15:49:01.311026Z] Running create-virtualenv step. 08:49:01 INFO - Running pre-action listener: _resource_record_pre_action 08:49:01 INFO - Running main action method: create_virtualenv 08:49:01 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:01 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 08:49:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:01 INFO - Using real prefix '/tools/python27' 08:49:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:02 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:05 INFO - Installing pip.................done. 08:49:05 INFO - Return code: 0 08:49:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:49:05 INFO - Reading from file tmpfile_stdout 08:49:05 INFO - Output received: 08:49:05 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) 08:49:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49:05 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')]} 08:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:05 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')]} 08:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:05 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:05 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 08:49:05 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 08:49:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:05 INFO - 'HOME': '/Users/cltbld', 08:49:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:05 INFO - 'LOGNAME': 'cltbld', 08:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:05 INFO - 'MOZ_NO_REMOTE': '1', 08:49:05 INFO - 'NO_EM_RESTART': '1', 08:49:05 INFO - 'PAGER': '/bin/cat', 08:49:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:05 INFO - 'PWD': '/builds/slave/test', 08:49:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:05 INFO - 'SHELL': '/bin/bash', 08:49:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:05 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:05 INFO - 'USER': 'cltbld', 08:49:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:05 INFO - 'XPC_FLAGS': '0x0', 08:49:05 INFO - 'XPC_SERVICE_NAME': '0', 08:49:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:05 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 08:49:05 INFO - Cleaning up... 08:49:05 INFO - Return code: 0 08:49:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49:05 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')]} 08:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:05 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')]} 08:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:05 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:05 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 08:49:05 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 08:49:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:05 INFO - 'HOME': '/Users/cltbld', 08:49:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:05 INFO - 'LOGNAME': 'cltbld', 08:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:05 INFO - 'MOZ_NO_REMOTE': '1', 08:49:05 INFO - 'NO_EM_RESTART': '1', 08:49:05 INFO - 'PAGER': '/bin/cat', 08:49:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:05 INFO - 'PWD': '/builds/slave/test', 08:49:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:05 INFO - 'SHELL': '/bin/bash', 08:49:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:05 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:05 INFO - 'USER': 'cltbld', 08:49:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:05 INFO - 'XPC_FLAGS': '0x0', 08:49:05 INFO - 'XPC_SERVICE_NAME': '0', 08:49:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:05 INFO - Downloading/unpacking psutil>=3.1.1 08:49:05 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 08:49:05 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 08:49:05 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 08:49:05 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 08:49:05 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 08:49:05 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 08:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:09 INFO - Installing collected packages: psutil 08:49:09 INFO - Running setup.py install for psutil 08:49:09 INFO - building 'psutil._psutil_osx' extension 08:49:09 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 08:49:10 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 08:49:10 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 08:49:10 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 08:49:10 INFO - building 'psutil._psutil_posix' extension 08:49:10 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 08:49:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:49:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:49:10 INFO - ^ 08:49:10 INFO - 1 warning generated. 08:49:10 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 08:49:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:10 INFO - Successfully installed psutil 08:49:10 INFO - Cleaning up... 08:49:10 INFO - Return code: 0 08:49:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:49:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:11 INFO - 'HOME': '/Users/cltbld', 08:49:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:11 INFO - 'LOGNAME': 'cltbld', 08:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:11 INFO - 'MOZ_NO_REMOTE': '1', 08:49:11 INFO - 'NO_EM_RESTART': '1', 08:49:11 INFO - 'PAGER': '/bin/cat', 08:49:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:11 INFO - 'PWD': '/builds/slave/test', 08:49:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:11 INFO - 'SHELL': '/bin/bash', 08:49:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:11 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:11 INFO - 'USER': 'cltbld', 08:49:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:11 INFO - 'XPC_FLAGS': '0x0', 08:49:11 INFO - 'XPC_SERVICE_NAME': '0', 08:49:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:14 INFO - Installing collected packages: mozsystemmonitor 08:49:14 INFO - Running setup.py install for mozsystemmonitor 08:49:14 INFO - Successfully installed mozsystemmonitor 08:49:14 INFO - Cleaning up... 08:49:15 INFO - Return code: 0 08:49:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:15 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:15 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 08:49:15 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 08:49:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:15 INFO - 'HOME': '/Users/cltbld', 08:49:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:15 INFO - 'LOGNAME': 'cltbld', 08:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:15 INFO - 'MOZ_NO_REMOTE': '1', 08:49:15 INFO - 'NO_EM_RESTART': '1', 08:49:15 INFO - 'PAGER': '/bin/cat', 08:49:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:15 INFO - 'PWD': '/builds/slave/test', 08:49:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:15 INFO - 'SHELL': '/bin/bash', 08:49:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:15 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:15 INFO - 'USER': 'cltbld', 08:49:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:15 INFO - 'XPC_FLAGS': '0x0', 08:49:15 INFO - 'XPC_SERVICE_NAME': '0', 08:49:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:15 INFO - Downloading/unpacking jsonschema==2.5.1 08:49:15 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 08:49:15 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 08:49:15 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 08:49:15 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 08:49:15 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 08:49:15 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 08:49:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:18 INFO - warning: no files found matching '*.txt' 08:49:18 INFO - no previously-included directories found matching 'build' 08:49:18 INFO - no previously-included directories found matching 'dist' 08:49:18 INFO - no previously-included directories found matching '.git*' 08:49:18 INFO - Installing collected packages: jsonschema, functools32 08:49:18 INFO - Running setup.py install for functools32 08:49:18 INFO - warning: no files found matching '*.txt' 08:49:18 INFO - no previously-included directories found matching 'build' 08:49:18 INFO - no previously-included directories found matching 'dist' 08:49:18 INFO - no previously-included directories found matching '.git*' 08:49:18 INFO - Successfully installed jsonschema functools32 08:49:18 INFO - Cleaning up... 08:49:18 INFO - Return code: 0 08:49:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:18 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 08:49:18 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 08:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:18 INFO - 'HOME': '/Users/cltbld', 08:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:18 INFO - 'LOGNAME': 'cltbld', 08:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:18 INFO - 'MOZ_NO_REMOTE': '1', 08:49:18 INFO - 'NO_EM_RESTART': '1', 08:49:18 INFO - 'PAGER': '/bin/cat', 08:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:18 INFO - 'PWD': '/builds/slave/test', 08:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:18 INFO - 'SHELL': '/bin/bash', 08:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:18 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:18 INFO - 'USER': 'cltbld', 08:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:18 INFO - 'XPC_FLAGS': '0x0', 08:49:18 INFO - 'XPC_SERVICE_NAME': '0', 08:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:18 INFO - Cleaning up... 08:49:18 INFO - Return code: 0 08:49:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:18 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 08:49:18 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 08:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:18 INFO - 'HOME': '/Users/cltbld', 08:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:18 INFO - 'LOGNAME': 'cltbld', 08:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:18 INFO - 'MOZ_NO_REMOTE': '1', 08:49:18 INFO - 'NO_EM_RESTART': '1', 08:49:18 INFO - 'PAGER': '/bin/cat', 08:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:18 INFO - 'PWD': '/builds/slave/test', 08:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:18 INFO - 'SHELL': '/bin/bash', 08:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:18 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:18 INFO - 'USER': 'cltbld', 08:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:18 INFO - 'XPC_FLAGS': '0x0', 08:49:18 INFO - 'XPC_SERVICE_NAME': '0', 08:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:19 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 08:49:19 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 08:49:19 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 08:49:19 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 08:49:19 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 08:49:19 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 08:49:21 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:22 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 08:49:22 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 08:49:22 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 08:49:22 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 08:49:22 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 08:49:22 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 08:49:22 INFO - Downloading docopt-0.6.1.tar.gz 08:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:23 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:23 INFO - Running setup.py install for blobuploader 08:49:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:23 INFO - Running setup.py install for requests 08:49:23 INFO - Running setup.py install for docopt 08:49:23 INFO - Successfully installed blobuploader requests docopt 08:49:23 INFO - Cleaning up... 08:49:23 INFO - Return code: 0 08:49:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:23 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:23 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 08:49:23 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 08:49:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:23 INFO - 'HOME': '/Users/cltbld', 08:49:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:23 INFO - 'LOGNAME': 'cltbld', 08:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:23 INFO - 'MOZ_NO_REMOTE': '1', 08:49:23 INFO - 'NO_EM_RESTART': '1', 08:49:23 INFO - 'PAGER': '/bin/cat', 08:49:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:23 INFO - 'PWD': '/builds/slave/test', 08:49:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:23 INFO - 'SHELL': '/bin/bash', 08:49:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:23 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:23 INFO - 'USER': 'cltbld', 08:49:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:23 INFO - 'XPC_FLAGS': '0x0', 08:49:23 INFO - 'XPC_SERVICE_NAME': '0', 08:49:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-7DCSfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-q2ZME0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Jwopyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-34Iokb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-P5fnSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-4MsubM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-WT1hLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:24 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Az8zQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-KhILEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-76VZ1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-PGSn9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Rd20ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-0wfdfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-MZ3YqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-xjGpUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-RZ5zhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:25 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-z78vYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:25 INFO - Running setup.py install for manifestparser 08:49:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:26 INFO - Running setup.py install for mozcrash 08:49:26 INFO - Running setup.py install for mozdebug 08:49:26 INFO - Running setup.py install for mozdevice 08:49:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:26 INFO - Running setup.py install for mozfile 08:49:26 INFO - Running setup.py install for mozhttpd 08:49:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:26 INFO - Running setup.py install for mozinfo 08:49:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:26 INFO - Running setup.py install for mozInstall 08:49:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Running setup.py install for mozleak 08:49:27 INFO - Running setup.py install for mozlog 08:49:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Running setup.py install for moznetwork 08:49:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Running setup.py install for mozprocess 08:49:27 INFO - Running setup.py install for mozprofile 08:49:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:27 INFO - Running setup.py install for mozrunner 08:49:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:28 INFO - Running setup.py install for mozscreenshot 08:49:28 INFO - Running setup.py install for moztest 08:49:28 INFO - Running setup.py install for mozversion 08:49:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:28 INFO - Cleaning up... 08:49:28 INFO - Return code: 0 08:49:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:28 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:28 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 08:49:28 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 08:49:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:28 INFO - 'HOME': '/Users/cltbld', 08:49:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:28 INFO - 'LOGNAME': 'cltbld', 08:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:28 INFO - 'MOZ_NO_REMOTE': '1', 08:49:28 INFO - 'NO_EM_RESTART': '1', 08:49:28 INFO - 'PAGER': '/bin/cat', 08:49:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:28 INFO - 'PWD': '/builds/slave/test', 08:49:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:28 INFO - 'SHELL': '/bin/bash', 08:49:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:28 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:28 INFO - 'USER': 'cltbld', 08:49:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:28 INFO - 'XPC_FLAGS': '0x0', 08:49:28 INFO - 'XPC_SERVICE_NAME': '0', 08:49:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:28 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-qP66wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:28 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)) 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:28 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-UuLBzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-dt67T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-6LBR02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-qRahA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-uLjpj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-djEPXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-mXt51A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-TC_LoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-g0EDo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:29 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)) 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:29 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-19vxp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:30 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)) 08:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:30 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-zH0RMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:30 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)) 08:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:30 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-i5cCf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:30 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)) 08:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:30 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-szqund-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:30 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)) 08:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:30 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-WlxECH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:30 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)) 08:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:30 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-3RAAIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:30 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)) 08:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:30 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-jeQcNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:30 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)) 08:49:30 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)) 08:49:30 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)) 08:49:30 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)) 08:49:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:30 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 08:49:30 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 08:49:30 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 08:49:30 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 08:49:30 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 08:49:30 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 08:49:33 INFO - Downloading blessings-1.6.tar.gz 08:49:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:33 INFO - Installing collected packages: blessings 08:49:33 INFO - Running setup.py install for blessings 08:49:33 INFO - Successfully installed blessings 08:49:33 INFO - Cleaning up... 08:49:33 INFO - Return code: 0 08:49:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:33 INFO - Reading from file tmpfile_stdout 08:49:33 INFO - Current package versions: 08:49:33 INFO - blessings == 1.6 08:49:33 INFO - blobuploader == 1.2.4 08:49:33 INFO - docopt == 0.6.1 08:49:33 INFO - functools32 == 3.2.3-2 08:49:33 INFO - jsonschema == 2.5.1 08:49:33 INFO - manifestparser == 1.1 08:49:33 INFO - mozInstall == 1.12 08:49:33 INFO - mozcrash == 1.0 08:49:33 INFO - mozdebug == 0.1 08:49:33 INFO - mozdevice == 0.49 08:49:33 INFO - mozfile == 1.2 08:49:33 INFO - mozhttpd == 0.7 08:49:33 INFO - mozinfo == 0.9 08:49:33 INFO - mozleak == 0.1 08:49:33 INFO - mozlog == 3.4 08:49:33 INFO - moznetwork == 0.27 08:49:33 INFO - mozprocess == 0.25 08:49:33 INFO - mozprofile == 0.28 08:49:33 INFO - mozrunner == 6.13 08:49:33 INFO - mozscreenshot == 0.1 08:49:33 INFO - mozsystemmonitor == 0.3 08:49:33 INFO - moztest == 0.8 08:49:33 INFO - mozversion == 1.4 08:49:33 INFO - psutil == 3.1.1 08:49:33 INFO - requests == 1.2.3 08:49:33 INFO - wsgiref == 0.1.2 08:49:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:33 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')]} 08:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:33 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')]} 08:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:33 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:33 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 08:49:33 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 08:49:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:33 INFO - 'HOME': '/Users/cltbld', 08:49:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:33 INFO - 'LOGNAME': 'cltbld', 08:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:33 INFO - 'MOZ_NO_REMOTE': '1', 08:49:33 INFO - 'NO_EM_RESTART': '1', 08:49:33 INFO - 'PAGER': '/bin/cat', 08:49:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:33 INFO - 'PWD': '/builds/slave/test', 08:49:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:33 INFO - 'SHELL': '/bin/bash', 08:49:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:33 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:33 INFO - 'USER': 'cltbld', 08:49:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:33 INFO - 'XPC_FLAGS': '0x0', 08:49:33 INFO - 'XPC_SERVICE_NAME': '0', 08:49:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 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)) 08:49:34 INFO - Cleaning up... 08:49:34 INFO - Return code: 0 08:49:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:34 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 0x1015c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5bacd9150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, '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.OLVpBdafud/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.NtUEi0syaw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 08:49:34 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 08:49:34 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 08:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:34 INFO - 'HOME': '/Users/cltbld', 08:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:34 INFO - 'LOGNAME': 'cltbld', 08:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:34 INFO - 'MOZ_NO_REMOTE': '1', 08:49:34 INFO - 'NO_EM_RESTART': '1', 08:49:34 INFO - 'PAGER': '/bin/cat', 08:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:34 INFO - 'PWD': '/builds/slave/test', 08:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:34 INFO - 'SHELL': '/bin/bash', 08:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:34 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:34 INFO - 'USER': 'cltbld', 08:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:34 INFO - 'XPC_FLAGS': '0x0', 08:49:34 INFO - 'XPC_SERVICE_NAME': '0', 08:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:34 INFO - Cleaning up... 08:49:34 INFO - Return code: 0 08:49:34 INFO - Running post-action listener: _resource_record_post_action 08:49:34 INFO - Running post-action listener: _start_resource_monitoring 08:49:34 INFO - Starting resource monitoring. 08:49:34 INFO - [mozharness: 2017-03-13 15:49:34.478435Z] Finished create-virtualenv step (success) 08:49:34 INFO - [mozharness: 2017-03-13 15:49:34.479406Z] Running install step. 08:49:34 INFO - Running pre-action listener: _resource_record_pre_action 08:49:34 INFO - Running main action method: install 08:49:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:34 INFO - Reading from file tmpfile_stdout 08:49:34 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:34 INFO - Reading from file tmpfile_stdout 08:49:34 INFO - Output received: 08:49:34 INFO - Usage: mozinstall [options] installer 08:49:34 INFO - Options: 08:49:34 INFO - -h, --help show this help message and exit 08:49:34 INFO - -d DEST, --destination=DEST 08:49:34 INFO - Directory to install application into. [default: 08:49:34 INFO - "/builds/slave/test"] 08:49:34 INFO - --app=APP Application being installed. [default: firefox] 08:49:34 INFO - mkdir: /builds/slave/test/build/application 08:49:34 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'] 08:49:34 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 08:49:55 INFO - Reading from file tmpfile_stdout 08:49:55 INFO - Output received: 08:49:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:49:55 INFO - Running post-action listener: _resource_record_post_action 08:49:55 INFO - [mozharness: 2017-03-13 15:49:55.094977Z] Finished install step (success) 08:49:55 INFO - [mozharness: 2017-03-13 15:49:55.095117Z] Running run-tests step. 08:49:55 INFO - Running pre-action listener: _resource_record_pre_action 08:49:55 INFO - Running main action method: run_tests 08:49:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:49:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:49:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:49:55 INFO - 2017-03-13 08:49:55.109 screenresolution[1625:7442] starting screenresolution argv=screenresolution get 08:49:55 INFO - 2017-03-13 08:49:55.111 screenresolution[1625:7442] Display 0: 1600x1200x32@60 08:49:55 INFO - 2017-03-13 08:49:55.117 screenresolution[1626:7445] starting screenresolution argv=screenresolution list 08:49:55 INFO - Available Modes on Display 0 08:49:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:49:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:49:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:49:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:49:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:49:55 INFO - Intel Iris: 08:49:55 INFO - Chipset Model: Intel Iris 08:49:55 INFO - Type: GPU 08:49:55 INFO - Bus: Built-In 08:49:55 INFO - VRAM (Dynamic, Max): 1536 MB 08:49:55 INFO - Vendor: Intel (0x8086) 08:49:55 INFO - Device ID: 0x0a2e 08:49:55 INFO - Revision ID: 0x0009 08:49:55 INFO - Displays: 08:49:55 INFO - Display: 08:49:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:49:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:49:55 INFO - Display Serial Number: 1600x1200 60 08:49:55 INFO - Main Display: Yes 08:49:55 INFO - Mirror: Off 08:49:55 INFO - Online: Yes 08:49:55 INFO - Rotation: Supported 08:49:55 INFO - Return code: 0 08:49:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:55 INFO - Python 2.7.3 08:49:55 INFO - Return code: 0 08:49:55 INFO - grabbing minidump binary from tooltool 08:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:55 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 0x1013a2d30>, '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 0x1012c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101397be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49:55 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 08:49:55 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 08:49:55 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 08:49:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:55 INFO - Return code: 0 08:49:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:49:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:55 INFO - ENV: RUST_BACKTRACE is now 1 08:49:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:49:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:55 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/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--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 08:49:55 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/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0264 --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 08:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OLVpBdafud/Render', 08:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:55 INFO - 'HOME': '/Users/cltbld', 08:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:55 INFO - 'LOGNAME': 'cltbld', 08:49:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:55 INFO - 'MOZ_NO_REMOTE': '1', 08:49:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:55 INFO - 'NO_EM_RESTART': '1', 08:49:55 INFO - 'PAGER': '/bin/cat', 08:49:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:55 INFO - 'PWD': '/builds/slave/test', 08:49:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:55 INFO - 'RUST_BACKTRACE': '1', 08:49:55 INFO - 'SHELL': '/bin/bash', 08:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NtUEi0syaw/Listeners', 08:49:55 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 08:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:55 INFO - 'USER': 'cltbld', 08:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:55 INFO - 'XPC_FLAGS': '0x0', 08:49:55 INFO - 'XPC_SERVICE_NAME': '0', 08:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:55 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/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--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 08:49:55 INFO - mozversion application_buildid: 20170313072037 08:49:55 INFO - mozversion application_changeset: 419c70029023afa80bee2f534bee483224e303bd 08:49:55 INFO - mozversion application_display_name: Nightly 08:49:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:55 INFO - mozversion application_name: Firefox 08:49:55 INFO - mozversion application_remotingname: firefox 08:49:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:49:55 INFO - mozversion application_vendor: Mozilla 08:49:55 INFO - mozversion application_version: 55.0a1 08:49:55 INFO - mozversion platform_buildid: 20170313072037 08:49:55 INFO - mozversion platform_changeset: 419c70029023afa80bee2f534bee483224e303bd 08:49:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:49:55 INFO - mozversion platform_version: 55.0a1 08:49:55 INFO - using testdate: 1489420195 08:49:55 INFO - actual date: 1489420195 08:49:55 INFO - starting webserver on 'localhost:49249' 08:49:55 INFO - SUITE-START | Running 2 tests 08:49:55 INFO - TEST-START | dromaeo_css 08:49:55 INFO - operating with platform_type : mac_ 08:49:55 INFO - Initialising browser for dromaeo_css test... 08:49:55 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpcGvNtm/profile http://localhost:49249/getInfo.html) 08:49:58 INFO - PID 1636 | __metrics Screen width/height:1600/1200 08:49:58 INFO - PID 1636 | colorDepth:24 08:49:58 INFO - PID 1636 | Browser inner width/height: 1024/689 08:49:58 INFO - PID 1636 | __metrics 08:49:58 INFO - PID 1636 | 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:49:58 INFO - PID 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:49:59 INFO - TEST-INFO | 1636: exit 0 08:49:59 INFO - Browser initialized. 08:49:59 INFO - Running cycle 1/1 for dromaeo_css test... 08:49:59 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpcGvNtm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:11 INFO - PID 1638 | RSS: Main: 232865792 08:50:11 INFO - PID 1638 | 08:53:15 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 08:53:18 INFO - PID 1638 | RSS: Main: 473206784 08:53:18 INFO - PID 1638 | 08:55:01 INFO - PID 1638 | ************************* 08:55:01 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 08:55:01 INFO - PID 1638 | 08:55:01 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:55:01 INFO - PID 1638 | Full stack: 08:55:01 INFO - PID 1638 | ************************* 08:56:21 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 08:56:22 INFO - PID 1638 | RSS: Main: 366022656 08:56:22 INFO - PID 1638 | 08:59:24 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 08:59:25 INFO - PID 1638 | RSS: Main: 353103872 08:59:25 INFO - PID 1638 | 09:02:27 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 09:02:28 INFO - PID 1638 | RSS: Main: 359591936 09:02:28 INFO - PID 1638 | 09:05:32 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 09:05:33 INFO - PID 1638 | RSS: Main: 344932352 09:05:33 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 09:08:37 INFO - PID 1638 | RSS: Main: 466481152 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | __start_tp_report 09:08:37 INFO - PID 1638 | _x_x_mozilla_page_load 09:08:37 INFO - PID 1638 | _x_x_mozilla_page_load_details 09:08:37 INFO - PID 1638 | |i|pagename|runs| 09:08:37 INFO - PID 1638 | |0;dojo.html;3370.235530128541;3388.81361525116;3397.1676939149897;3406.3017081498297;3449.344624521341;5682.232898558694;5801.216835727176;5915.053591425372;5984.431479009492;5996.280446346434;5730.968425683375;5825.30096388433;5827.592068555203;5835.35811060783;5836.883262334751;5218;5260.053190425722;5263.394709608406;5302.602304827154;5321.574274058066;5331.093714068598;5348;5355.3305836770405;5355.410904800488;5361.115415956362;5351.625386222966;5358.624896257264;5360.356757189143;5362.329708786402;5372.328458942632;4477.664175186874;5315.521603055724;5332.760025798849;5336.839894803143;5346.839594812123;5166.044281807877;5315.44187860273;5320.148776195829;5323.201519772027;5347.598930080259;5979.1629171915965;6010.188624535675;6022.638641681513;6027.246594175728;6027.306859711145;6321;6331.2719037310835;6342.080398342261;6358.618482890995;6374.298827129012;6220;6241.87516249677;6248.094026366151;6301.716422760941;6311.93688063118;4100.794960252006;5751.971240143773;5910.438508341701;6007.249093863267;6036.879262414771;6027.9095813562835;6365.681715914186;6380.617162970237;6386.9680651596445;6400.3599640036555;5759.308882934075;6354.332795056453;6358.841028974312;6367.554271200971;6370.55406121567;6195.473384762256;6255.96872015637;6270.216222972129;6287.874242515124;6313.621182729051;6297.842553936097;6360.872782544416;6361.332060133712;6367;6380.042993550912;5402.594805389612;6269.435750782452;6283;6329.810105696836;6358.650274234961;4934.925976110289;5029.446760856303;5061.215511595635;5139.948600514022;5163.380394352702;4507.909841803145;4558.111168322145;4571.3600095986585;4571.839985600488;4592.7933243004145;4939.728314942713;5056.26684130799;5756.280464941882;5796.0436527972415;5864.149698293723;6189.009758438719;6251.71867265965;6304.873902522016;6309.211348581427;6317.715702793385;5805.709714514257;5812.041013232853;5830.533557315404;5852.678102704393;5863.384344643751;5564.610477266633;5576.386597474275;5591.9720401397735;5595.4124816894455;5602.159676048625;6304.7162877671535;6312.810615681537;6323.557350985387;6342.429181373698;6364.554481186457;6293.905591416224;6294.276158241861;6296.6851657417865;6301.558890877594;6305.684715764285;6214.564980451325;6429.4856411486035;6432.935670643234;6438.6136831790245;6459.903101453576;6229.4393504582995;6236.407541283571;6237.4698150657705;6240.188775459162;6241.719122639402;6229.314775374796;6291;6303.306636270098;6314;6331.176946997045;6197.690115494117;6258.405451482102;6299.874002520016;6309.621422714652;6322.114903913363;6274.968625156845;6291.779787707408;6292.590981586182;6293.653849038347;6299.905501417574;5944.227250457413;6002.429769172097;6188.44304012641;6190.659513726789;6196.008638617799;6147.877042459215;6164.260288765377;6170.167027451416;6170.691465426621;6171.814845554641;5968.985272503772;6024.608400453956;6041.426064523863;6043.909341359971;6044.304904935989 09:08:37 INFO - PID 1638 | |1;ext.html;756.7011030642897;781.3788038509383;843.3632607381426;858.8969323681156;931.7204838548433;36291.72978945737;36559;36793.63206367939;37973;38009.42985855215;36249.45625815615;36408.81795591025;36425.45361819568;36453.27093458129;36547.81726091366;7146.249643787405;7330.780076597684;7517.398608111365;7648.885266721004;7665;7290.489665723401;7371.852562948738;7373.225811289818;10715.981981711724;10969.012788849002;10872.075873550757;10907.072898803612;10911.563537458533;10937.507812148433;10963.232573719843;10896.346219226833;10928.890711092867;10932.289401188897;10937.289076210021;10957.50691218897;7172.569645821215;7340.559566426032;7406.629668516553;10573.73565660857;10714.410707411089;36996.630033699585;37759.678411255474;39139.41290880639;39202.60797392018;39205;3756.417755247941;3783.886483405502;3825.7513261637905;3838.6161383861668;3862.208247309295;27146.778394972076;27464.03875864355;27564.724352756417;27606.447871042466;28204.43591128166;3104.7205751482475;3128.3586864692684;3151.9684803151904;3259.5925509311337;3260.396826587089;329729;329983;330595;331125;332411.99999999756;41418.585814142076;47363.5263647366;48758;48781.78045548889;48844;51504.48495515072;51525.7423712879;51529.96940061178;51595.742021289654;51611.741941290806;14236.786448203286;14633.341499632543;14644.560663180122;14645.267736613127;14702.55892323232;11808.937195652432;11838;11845.881541184477;11857.525698971944;11867.643970680894;11169.609063682732;13289.810445493013;13306.73386532264;13345.065845390729;13443.529476468277;4878.629814731171;5709.25779648643;5731.60925567997;5751.1085781703905;5753.88492230153;926.6571368593528;990.3562684255292;1056.9894301057045;1142.405948906564;1147.8048731715628;25452.363690907874;25604.975800968125;25626.23121306364;25733.099341522957;25887.870560647076;1055.2349546578823;1140.4582823159099;1194.2834299420279;1224.1981502116128;1254.0907841814612;11844.526218951318;12322;13031.413586388635;13080.629169593813;13149.53976610814;200520;200525;201095.98904011064;201503;201765;53346.73326633342;53654;53781.46218537765;53819.46180538223;53848.46151538513;1238.1704258147063;1262.2489618676689;1407.0572716279912;1438.8561143885527;1440.9351579178963;21974.670379943993;25323.987040518172;26511.734653149106;28011;28543.42913141767;39730.60942866986;39807.80096099501;39851.605193818075;39988.400173997994;40016.399754003134;348532;349026;349138;350524;351424;31996.84001579977;32310.515342270355;32393.676063239065;35137;35785.821070894475;42466.78766606147;42648.57351426446;43286.48497302705;43782.56217437912;43931;30291;34478;35395;35627.28745425129;37008.07479813026;34293.82853085719;34923.65076349204;35050.649493505756;35914.28171436609;36345.81827090848;36342.818285908405;36837.44743828791;36877.893663190145;36969.44545831761;37058;24402.511949760552;31751.206219843763;31773.682263177998;31884.6811531891;31895.840520797246;23778;23785.643215351436;24009.399765006016;24179.032838686257;24278 09:08:37 INFO - PID 1638 | |2;jquery.html;364.7519686613103;379.36267071320174;389.7739311199503;401.64454457804845;439.1370956071321;14048.789268160974;14083.366248518816;16348.83651163488;19711.5072123197;21824.626213713425;22295.665565016538;22624.547509049808;22688.298377621704;22866.31401057971;23028.539429211407;6426.485881129522;6522.217333919936;6666.966665166691;6759.7296108155615;6806.3533964273565;4215.978920105411;6630.668466576676;6797.388235058844;6812.591244525344;6815.11403517542;4299.742015479065;5827.941720582782;7158.033665455149;7276.1268647762345;7304.160021597531;7187.748428804966;7701.768946931601;7751.0311211098615;7777.416693747991;7777.572233527154;6968.372846443794;7086.362227399559;7335.633218339115;7519.736809211706;7552.395808335375;26573.734262657512;26652.60021099685;26822.5976610351;26902.730972690413;27170.1848944532;3250.7886987345746;3341.331733653279;3346.0798280472823;3389.813560254185;3418.9316213675834;15645.765313520305;16453.341866325332;16646.58383540409;16917.661646766996;17566.736498952527;1950.4246247357064;2006.0741687979557;2028.6348457277684;2046.1917542570602;2050.8256798172174;138366;144657.55342446547;145841.8123728145;146095;148631.25684371617;19701.310454134036;21049.000344967204;21142.682859757115;21298.574028519342;21334.253301134388;35373.29253414917;35452.290954180775;35517.46723799145;35522.28955420929;35534;10437.112845408074;10460.424676642857;10662.946685266525;10737.624183153552;10829;8546.05993340732;8568.485890846569;8569.657213711507;8747.125287471203;8777.692780752643;10051.547680354402;10351.430671312999;10522.210834187314;10555.313904596176;10558.894411055791;3551.8579256829953;4057.756534607933;4140.60664236897;4181.790910454465;4195.364122644434;956.660385563129;958.5973890965811;970.1511177719495;983.341161421849;991.4844280973854;22901.19845805389;23557.29328120159;23565.882170589037;23566.7643323568;23596;848.9108643592334;885.3050355470924;948.3882895532481;974.2790335152079;980.3088822380205;11005.724856878642;11213.663590092146;11479.770404592029;11820.704482387837;11856.407179641155;670025;675809;678924;685353;701643;139664;146396;146643.26678366752;149541;154775.71953841753;1162.4187906046977;1231.0766924806396;1258.8111783232405;1259.5591542960074;1262.7853264944981;20131.79868201299;20474.38576842697;20517.58964820725;20562.177512899318;20638.04686875169;59600.70199648974;59727.10409343777;60118.79762404815;61103.08345374734;62198;81087;82521.92258851642;82868;85460.71808922995;85969.57015214884;11737.823932641188;12182.147249692436;12267.447964841782;12604.810927836274;12679.556215532411;13401.731965360443;13504.527341542998;14056.929715351767;14240.35918383696;14478.420863165355;11488;11973.221740586834;12054.75890482203;12056.553213614423;12066.638000859988;11511.424428778695;11735.823962640396;11879.04967602607;12075.128355104973;12129.150959432754;11565.901239382581;11652.766944661229;11817.940910295394;12093.455794489446;12155.331456769962;12126;12160.513579457076;12234.694132646398;12526.997840172582;12561.685957851127;9838.852417213891;11967.222130561486;12019.338936358585;12044.819327709916;12114.152009359614 09:08:37 INFO - PID 1638 | |3;mootools.html;1569.8273189949105;1581.7785510028605;1601.7277062899298;1605.3257631794647;1607.5739928918836;7601.467897247195;10841.186910981669;10847.915208479148;10859.945700271497;10925.453727313623;10244.334118282306;10661.573537058528;10764.569417223302;10768.892311076886;10773.83839242412;2314.9421264468356;2340.754220806816;2344.6834677318598;2351.8941647625898;2353.9293821185306;2266.3880752196897;2305.2162264829954;2305.561943230789;2308.122913292946;2314.0975019742314;2304.1704986204954;2317.2932255662017;2323.6282194848827;2325.8720770357622;2328.7671232876746;1604.8314926932737;2260.4235919840335;2284.497410569672;2286.7713228677167;2286.8284878634086;1943.5529828139465;2263.2418139923147;2323.256557901472;2324.5467133908896;2325.1164557468223;10719.356838589632;10803.97362250585;10810.837837432446;10812;10835.349879007284;5451.727413629303;5608.102703567451;5619.129034999541;5622;5629.971850140723;3222.3233121044373;3348.916277093068;3592.46113083037;3592.515010473605;3594.51474051005;1163.6043745126613;1184.7512022475291;1190.8332833403244;1216.1486959128556;1239.1387985350186;13308.733825323536;13718.862811371859;13751.656208594764;14827.110373377633;14899.627509312353;4091.4067460218102;4220.11377610699;4220.303649897733;4229.91540169201;4394.428724265825;11271.94364028191;11283.379414132138;11302.830457543143;11320.320780753182;11322.660320190407;5516.91724624131;5521.613487055948;5524.14375771756;5525.060739674184;5528.917066244009;4353.869383918487;4507.760365899404;4854;5117.923231151536;5184.637075404686;3075.861586228625;3425.5546778918583;3511.3152935177395;3551.9644803551632;3579.1410061585034;1003.5333569890037;1008.5360734062267;1017.9083882450615;1020.0666390252835;1021.4790456866903;895.4537731983485;918.1231923513029;935.4387367579398;962.499500259861;989.312427862643;4664.440267167962;6396.424321810966;6416.990066932671;6690.531662783558;6834.453243740489;968.5447839515416;981.7103954333346;985.1970643925179;1016.4968340671415;1039.1790485516358;4037.9394309085387;4058.7564746115327;4190.497140343178;4224.8732538023905;4238.6397156242065;12893.935530322477;13363.665908352368;13367.665808354868;13385.665358365924;13428.127171733806;9310.627574896927;11944.64166075019;13318.134321269086;13321.66695832612;13324.333783310796;9675.951620241995;9739.026097390204;9956.253281003808;9971.252156088321;9971.700848974542;6535.934640653722;6585.901211481927;6749.86500269982;6774.42417394527;6798.8640227196165;9698.951505242429;11617.128715346214;11873.287602743863;11972.940135299268;12049.156559040783;11168.776624467628;11370.46498722695;11421.37182454973;12141.392930353624;12218.0225581955;7809.726659566888;8775.956120219358;9007.144321289466;9060.184583387263;9091.499967501852;9772.31593788428;9941.005899410291;10061.647842325483;10089.545970431202;10120.493975301059;8770.517621530877;8966.506842123747;9018.458892466473;9072.863907041532;9108.225800807006;8688.956555217437;8706.60820263077;8707.564621768759;8806.779830504289;8829.16122968317;7567.394608431202;7790.415718820997;7793.2596403341595;7794.142644309235;7797.727079552188;5682.801101961411;5754.798582049608;5868.061110222515;5879.235699359056;5939.406059394026;5717.8570535736935;5821.155932389865;5902.4097590240635;6045.455908968214;6151.72317245716 09:08:37 INFO - PID 1638 | |4;prototype.html;333.22192680091985;336.7020137370436;361.54264854958467;373.07477455909344;384.8556791203297;4640.791164397602;4694.507076756942;4749;4901.534354236351;5027.220780778976;4173.60184338247;4248.723832950856;4743.99884146554;4926.950730492694;4962.1564334063205;4177.874663760084;4376.2997920332755;4423.66822488313;4427.535108813577;4435.602785122692;3615.6384361563896;3743.5320584926885;3860.517435320585;4047.7368971016936;4381.948332400233;3336.796675191819;3487.808170550619;3777.9055523611855;4237.406763053175;4256.914861702764;3934.2263704088837;4086.5492750073595;4125.23683118624;4208.75925537336;4225.2394568977725;3525.700315473188;3746.419305007701;3782.6217378262227;3795.7532760370486;3877.2827027000094;3948.440366055392;4773.088416066197;4895.31465594817;4951.900961980777;4953.028183090153;4198.412222288882;4435.846679863226;4685.554872287127;4868.269759536062;4911.765146858171;4244.639205667522;4836.573210902792;4938.506149385069;5042.546170844642;5562.527185189264;3601.819909004539;3603.045193023851;3675.9816200918826;4067.3898915162667;4333.922914730545;4817.9518204817505;5355.892882142374;5849.870319379112;5860.384659610722;5869.618474799167;3946.565877753445;4150.837765425686;4162.396452514368;4414.713043652152;4457.768118114341;3893.279743247508;4481.775911204427;4562.486885098456;4573.193588532511;4576.359309696645;4574.908501829945;4632.559906808848;4763.261694437368;4876.975615121973;4910.607151427877;4881;4884.535969082932;4999.925001124986;5115.462876397999;5406.91889621656;1385.3765805387616;1441.6467965348347;1448.7537118689847;1462.817147856518;1522.444307827657;1084.4306738962107;1111.744298811278;1164.3479651395246;1174.1311429542077;1186.4186548591301;4748.525147485224;4950.381202349707;5011.872328726007;5065.125179237886;5083.847484575469;4788.497207793202;4915;4915.600537518972;5576;5647.858803529863;4218.303979843292;4640.930386044212;4683.484816670163;5117.718525481134;5177.974110129425;2375.7267914189742;2549.68128983877;2905.6730044099386;2950.5279155335043;2969.3615872587498;4797.712137271775;5107.642465027487;5317.5539251943765;5615;5838.970805146032;5040.747962601855;5302.310699609026;5557.499825015687;5792.710364481759;5803.622764520293;5377.300950876361;5658.066419040894;5800.564957628195;5808.796692115756;5830.941690583125;4748.430188377417;4981.626378021735;5225.634205605571;5667.858303542444;5735.673309480575;4601.401817763669;4943.579795717261;4953.405591329064;5521.116621340727;5671.262735844314;5033.5721463676;5237;5254.211868219798;5404.702741349263;5877.617954832922;3972.9273096264424;4153.439285696392;4239.523590655633;4332.913341733085;4532.5694059064335;5303.655262407931;5467.457632200886;5658.708223169623;5678.4605462480995;5747.224124743107;4968.677035992649;5345.1714984177515;5365.8999905020155;5373.516383525345;5400.891982160256;4781.354517140142;5152.896942061063;5325.254464375068;5364.9731751341;5457.863553411196;4600.42494688164;5060.7105381502315;5083.67824365675;5237.580993520433;5312.423474614731;5297.993381257549;5475.370332411823;5497.811269463781;5510.666047041482;5537.723113844372;4896.853094407174;5240.554552863049;5352.501299635953;5352.892942141058;5378.489043541014 09:08:37 INFO - PID 1638 | |5;yui.html;714.0870808835215;786.2677196741321;794.5550491724634;814.9324385055581;843.2200214801308;15126.24368781562;15644.765328520081;15646.295916683748;15707.764383534242;15739.999999999985;14300.14199148052;14359.138451692907;14379.928100359484;14399.136051836871;14406.639834004156;927.1609193679714;940.3323640215456;950.5722424908785;972.6449845806266;973.8782652168479;968.6367612145445;971.3346357744967;974.8294048541512;976.2677991506371;976.623999760094;1028.0233777911014;1030.4332617060586;1030.8762948446197;1033.5607366869087;1034.2966782587837;1021.7598864266891;1022.9821327778908;1023.8822535408449;1024.497995981971;1024.5952848624788;754.9584772837492;755.6788364944877;758.3932853717005;759.092884003616;763.4350580570393;15691;16025;16077.19614019306;16104;16281.51155465338;13368.799468007988;13909.582712518539;14583;14715.55853324402;14938.402463901431;11511.251768635011;11519.94240028803;11592.246503977214;11819.888363848224;12127.211731237441;3651.324504966563;3759.5488541374934;3778.659920607131;3918.6473217410285;3919.1573811630637;108056;110400.4479977595;111679;112334;114207;11162.609308674157;11273.323600583992;11825.940870295766;11834.526618935146;12048.277103373826;24944.251672449856;24960.251192464257;24999.62500562493;25001;25028.998840046195;7611.086669599573;7707.768766937001;7719.884201736979;7797.298243158029;7808.5314881107315;3971.2454633620096;4170.332746760504;4223.97888010558;4240.151969606091;4264.31770916658;3383.3233353329433;3423.2126610879636;3459.4810778383444;3485.5294535237927;3495.8252087395526;602.4096385542193;607.3046361915582;610.6244659534354;618.9597676151126;646.6572716460283;590.5364289033089;602.9577929544978;626.884384608495;646.7283740828769;653.9280679125293;9701.70894873155;9977.401355918668;10045.748856278506;10238;10390.688279351632;636.1157990393357;678.925318214996;703.8451540661047;712.707789806177;722.731606031422;4574.656900732459;4851.539103785135;4854.868728467927;4901.926471102936;4924.901501969941;91041.54479227694;98463.50768246113;98753.70563857027;98822.5058874701;99174;57227;59435;65939.67030164819;86899.56550217209;92218.61672074924;59770.701146493986;76679.61660191663;77213.6139319311;77339;77807.22192778112;10342.741431464274;10751.025984970742;10771.141920645807;10943.781124377627;10994.835077473685;41910.161796763285;42438;42872;43106;43732.56267437285;40440;58671.70664146652;58796.4120358791;59022.40977590341;59237.703811480664;13022.934885325512;13088.541901033419;13115.737685246433;13382;13605.707457791494;12394.876051239733;14474.059186152866;14494.710105798036;14527.201003944885;14661.340239689027;13193.538226162038;13570.52503162424;13571.59285221445;13855.376508057363;13939.930300348433;13085.08404411682;13338.490605334755;13357.66605834862;13737.107088039245;14194.077384969944;12895.032872534784;12932.968101788234;13067;13090.280034598189;13400.463981440633;10573.682789516326;10579.5239214234;10761.946190268998;10779.353238805697;11650.36984119874;8927.776805579913;10502.369857808557;10835.241533092609;11064.114870810155;11080.335179889233 09:08:37 INFO - PID 1638 | __end_tp_report 09:08:37 INFO - PID 1638 | __start_cc_report 09:08:37 INFO - PID 1638 | _x_x_mozilla_cycle_collect,3059 09:08:37 INFO - PID 1638 | __end_cc_report 09:08:37 INFO - PID 1638 | __startTimestamp1489421317327__endTimestamp 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | ------- Summary: start ------- 09:08:37 INFO - PID 1638 | Number of tests: 6 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:5825.29 Median:6041.43 stddev:662.06 (11.0%) stddev-sans-first:635.46 09:08:37 INFO - PID 1638 | Values: 3370.2 3388.8 3397.2 3406.3 3449.3 5682.2 5801.2 5915.1 5984.4 5996.3 5731.0 5825.3 5827.6 5835.4 5836.9 5218.0 5260.1 5263.4 5302.6 5321.6 5331.1 5348.0 5355.3 5355.4 5361.1 5351.6 5358.6 5360.4 5362.3 5372.3 4477.7 5315.5 5332.8 5336.8 5346.8 5166.0 5315.4 5320.1 5323.2 5347.6 5979.2 6010.2 6022.6 6027.2 6027.3 6321.0 6331.3 6342.1 6358.6 6374.3 6220.0 6241.9 6248.1 6301.7 6311.9 4100.8 5752.0 5910.4 6007.2 6036.9 6027.9 6365.7 6380.6 6387.0 6400.4 5759.3 6354.3 6358.8 6367.6 6370.6 6195.5 6256.0 6270.2 6287.9 6313.6 6297.8 6360.9 6361.3 6367.0 6380.0 5402.6 6269.4 6283.0 6329.8 6358.7 4934.9 5029.4 5061.2 5139.9 5163.4 4507.9 4558.1 4571.4 4571.8 4592.8 4939.7 5056.3 5756.3 5796.0 5864.1 6189.0 6251.7 6304.9 6309.2 6317.7 5805.7 5812.0 5830.5 5852.7 5863.4 5564.6 5576.4 5592.0 5595.4 5602.2 6304.7 6312.8 6323.6 6342.4 6364.6 6293.9 6294.3 6296.7 6301.6 6305.7 6214.6 6429.5 6432.9 6438.6 6459.9 6229.4 6236.4 6237.5 6240.2 6241.7 6229.3 6291.0 6303.3 6314.0 6331.2 6197.7 6258.4 6299.9 6309.6 6322.1 6275.0 6291.8 6292.6 6293.7 6299.9 5944.2 6002.4 6188.4 6190.7 6196.0 6147.9 6164.3 6170.2 6170.7 6171.8 5969.0 6024.6 6041.4 6043.9 6044.3 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:44959.27 Median:183352.91 stddev:79193.61 (43.2%) stddev-sans-first:79346.23 09:08:37 INFO - PID 1638 | Values: 756.7 781.4 843.4 858.9 931.7 36291.7 36559.0 36793.6 37973.0 38009.4 36249.5 36408.8 36425.5 36453.3 36547.8 7146.2 7330.8 7517.4 7648.9 7665.0 7290.5 7371.9 7373.2 10716.0 10969.0 10872.1 10907.1 10911.6 10937.5 10963.2 10896.3 10928.9 10932.3 10937.3 10957.5 7172.6 7340.6 7406.6 10573.7 10714.4 36996.6 37759.7 39139.4 39202.6 39205.0 3756.4 3783.9 3825.8 3838.6 3862.2 27146.8 27464.0 27564.7 27606.4 28204.4 3104.7 3128.4 3152.0 3259.6 3260.4 329729.0 329983.0 330595.0 331125.0 332412.0 41418.6 47363.5 48758.0 48781.8 48844.0 51504.5 51525.7 51530.0 51595.7 51611.7 14236.8 14633.3 14644.6 14645.3 14702.6 11808.9 11838.0 11845.9 11857.5 11867.6 11169.6 13289.8 13306.7 13345.1 13443.5 4878.6 5709.3 5731.6 5751.1 5753.9 926.7 990.4 1057.0 1142.4 1147.8 25452.4 25605.0 25626.2 25733.1 25887.9 1055.2 1140.5 1194.3 1224.2 1254.1 11844.5 12322.0 13031.4 13080.6 13149.5 200520.0 200525.0 201096.0 201503.0 201765.0 53346.7 53654.0 53781.5 53819.5 53848.5 1238.2 1262.2 1407.1 1438.9 1440.9 21974.7 25324.0 26511.7 28011.0 28543.4 39730.6 39807.8 39851.6 39988.4 40016.4 348532.0 349026.0 349138.0 350524.0 351424.0 31996.8 32310.5 32393.7 35137.0 35785.8 42466.8 42648.6 43286.5 43782.6 43931.0 30291.0 34478.0 35395.0 35627.3 37008.1 34293.8 34923.7 35050.6 35914.3 36345.8 36342.8 36837.4 36877.9 36969.4 37058.0 24402.5 31751.2 31773.7 31884.7 31895.8 23778.0 23785.6 24009.4 24179.0 24278.0 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:41009.25 Median:22656.42 stddev:113941.60 (502.9%) stddev-sans-first:114220.37 09:08:37 INFO - PID 1638 | Values: 364.8 379.4 389.8 401.6 439.1 14048.8 14083.4 16348.8 19711.5 21824.6 22295.7 22624.5 22688.3 22866.3 23028.5 6426.5 6522.2 6667.0 6759.7 6806.4 4216.0 6630.7 6797.4 6812.6 6815.1 4299.7 5827.9 7158.0 7276.1 7304.2 7187.7 7701.8 7751.0 7777.4 7777.6 6968.4 7086.4 7335.6 7519.7 7552.4 26573.7 26652.6 26822.6 26902.7 27170.2 3250.8 3341.3 3346.1 3389.8 3418.9 15645.8 16453.3 16646.6 16917.7 17566.7 1950.4 2006.1 2028.6 2046.2 2050.8 138366.0 144657.6 145841.8 146095.0 148631.3 19701.3 21049.0 21142.7 21298.6 21334.3 35373.3 35452.3 35517.5 35522.3 35534.0 10437.1 10460.4 10662.9 10737.6 10829.0 8546.1 8568.5 8569.7 8747.1 8777.7 10051.5 10351.4 10522.2 10555.3 10558.9 3551.9 4057.8 4140.6 4181.8 4195.4 956.7 958.6 970.2 983.3 991.5 22901.2 23557.3 23565.9 23566.8 23596.0 848.9 885.3 948.4 974.3 980.3 11005.7 11213.7 11479.8 11820.7 11856.4 670025.0 675809.0 678924.0 685353.0 701643.0 139664.0 146396.0 146643.3 149541.0 154775.7 1162.4 1231.1 1258.8 1259.6 1262.8 20131.8 20474.4 20517.6 20562.2 20638.0 59600.7 59727.1 60118.8 61103.1 62198.0 81087.0 82521.9 82868.0 85460.7 85969.6 11737.8 12182.1 12267.4 12604.8 12679.6 13401.7 13504.5 14056.9 14240.4 14478.4 11488.0 11973.2 12054.8 12056.6 12066.6 11511.4 11735.8 11879.0 12075.1 12129.2 11565.9 11652.8 11817.9 12093.5 12155.3 12126.0 12160.5 12234.7 12527.0 12561.7 9838.9 11967.2 12019.3 12044.8 12114.2 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:6464.11 Median:3468.43 stddev:4012.76 (115.7%) stddev-sans-first:4007.17 09:08:37 INFO - PID 1638 | Values: 1569.8 1581.8 1601.7 1605.3 1607.6 7601.5 10841.2 10847.9 10859.9 10925.5 10244.3 10661.6 10764.6 10768.9 10773.8 2314.9 2340.8 2344.7 2351.9 2353.9 2266.4 2305.2 2305.6 2308.1 2314.1 2304.2 2317.3 2323.6 2325.9 2328.8 1604.8 2260.4 2284.5 2286.8 2286.8 1943.6 2263.2 2323.3 2324.5 2325.1 10719.4 10804.0 10810.8 10812.0 10835.3 5451.7 5608.1 5619.1 5622.0 5630.0 3222.3 3348.9 3592.5 3592.5 3594.5 1163.6 1184.8 1190.8 1216.1 1239.1 13308.7 13718.9 13751.7 14827.1 14899.6 4091.4 4220.1 4220.3 4229.9 4394.4 11271.9 11283.4 11302.8 11320.3 11322.7 5516.9 5521.6 5524.1 5525.1 5528.9 4353.9 4507.8 4854.0 5117.9 5184.6 3075.9 3425.6 3511.3 3552.0 3579.1 1003.5 1008.5 1017.9 1020.1 1021.5 895.5 918.1 935.4 962.5 989.3 4664.4 6396.4 6417.0 6690.5 6834.5 968.5 981.7 985.2 1016.5 1039.2 4037.9 4058.8 4190.5 4224.9 4238.6 12893.9 13363.7 13367.7 13385.7 13428.1 9310.6 11944.6 13318.1 13321.7 13324.3 9676.0 9739.0 9956.3 9971.3 9971.7 6535.9 6585.9 6749.9 6774.4 6798.9 9699.0 11617.1 11873.3 11972.9 12049.2 11168.8 11370.5 11421.4 12141.4 12218.0 7809.7 8776.0 9007.1 9060.2 9091.5 9772.3 9941.0 10061.6 10089.5 10120.5 8770.5 8966.5 9018.5 9072.9 9108.2 8689.0 8706.6 8707.6 8806.8 8829.2 7567.4 7790.4 7793.3 7794.1 7797.7 5682.8 5754.8 5868.1 5879.2 5939.4 5717.9 5821.2 5902.4 6045.5 6151.7 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:4458.56 Median:4827.26 stddev:1258.65 (26.1%) stddev-sans-first:1223.51 09:08:37 INFO - PID 1638 | Values: 333.2 336.7 361.5 373.1 384.9 4640.8 4694.5 4749.0 4901.5 5027.2 4173.6 4248.7 4744.0 4927.0 4962.2 4177.9 4376.3 4423.7 4427.5 4435.6 3615.6 3743.5 3860.5 4047.7 4381.9 3336.8 3487.8 3777.9 4237.4 4256.9 3934.2 4086.5 4125.2 4208.8 4225.2 3525.7 3746.4 3782.6 3795.8 3877.3 3948.4 4773.1 4895.3 4951.9 4953.0 4198.4 4435.8 4685.6 4868.3 4911.8 4244.6 4836.6 4938.5 5042.5 5562.5 3601.8 3603.0 3676.0 4067.4 4333.9 4818.0 5355.9 5849.9 5860.4 5869.6 3946.6 4150.8 4162.4 4414.7 4457.8 3893.3 4481.8 4562.5 4573.2 4576.4 4574.9 4632.6 4763.3 4877.0 4910.6 4881.0 4884.5 4999.9 5115.5 5406.9 1385.4 1441.6 1448.8 1462.8 1522.4 1084.4 1111.7 1164.3 1174.1 1186.4 4748.5 4950.4 5011.9 5065.1 5083.8 4788.5 4915.0 4915.6 5576.0 5647.9 4218.3 4640.9 4683.5 5117.7 5178.0 2375.7 2549.7 2905.7 2950.5 2969.4 4797.7 5107.6 5317.6 5615.0 5839.0 5040.7 5302.3 5557.5 5792.7 5803.6 5377.3 5658.1 5800.6 5808.8 5830.9 4748.4 4981.6 5225.6 5667.9 5735.7 4601.4 4943.6 4953.4 5521.1 5671.3 5033.6 5237.0 5254.2 5404.7 5877.6 3972.9 4153.4 4239.5 4332.9 4532.6 5303.7 5467.5 5658.7 5678.5 5747.2 4968.7 5345.2 5365.9 5373.5 5400.9 4781.4 5152.9 5325.3 5365.0 5457.9 4600.4 5060.7 5083.7 5237.6 5312.4 5298.0 5475.4 5497.8 5510.7 5537.7 4896.9 5240.6 5352.5 5352.9 5378.5 09:08:37 INFO - PID 1638 | 09:08:37 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:19515.89 Median:25015.00 stddev:27629.77 (110.5%) stddev-sans-first:27671.21 09:08:37 INFO - PID 1638 | Values: 714.1 786.3 794.6 814.9 843.2 15126.2 15644.8 15646.3 15707.8 15740.0 14300.1 14359.1 14379.9 14399.1 14406.6 927.2 940.3 950.6 972.6 973.9 968.6 971.3 974.8 976.3 976.6 1028.0 1030.4 1030.9 1033.6 1034.3 1021.8 1023.0 1023.9 1024.5 1024.6 755.0 755.7 758.4 759.1 763.4 15691.0 16025.0 16077.2 16104.0 16281.5 13368.8 13909.6 14583.0 14715.6 14938.4 11511.3 11519.9 11592.2 11819.9 12127.2 3651.3 3759.5 3778.7 3918.6 3919.2 108056.0 110400.4 111679.0 112334.0 114207.0 11162.6 11273.3 11825.9 11834.5 12048.3 24944.3 24960.3 24999.6 25001.0 25029.0 7611.1 7707.8 7719.9 7797.3 7808.5 3971.2 4170.3 4224.0 4240.2 4264.3 3383.3 3423.2 3459.5 3485.5 3495.8 602.4 607.3 610.6 619.0 646.7 590.5 603.0 626.9 646.7 653.9 9701.7 9977.4 10045.7 10238.0 10390.7 636.1 678.9 703.8 712.7 722.7 4574.7 4851.5 4854.9 4901.9 4924.9 91041.5 98463.5 98753.7 98822.5 99174.0 57227.0 59435.0 65939.7 86899.6 92218.6 59770.7 76679.6 77213.6 77339.0 77807.2 10342.7 10751.0 10771.1 10943.8 10994.8 41910.2 42438.0 42872.0 43106.0 43732.6 40440.0 58671.7 58796.4 59022.4 59237.7 13022.9 13088.5 13115.7 13382.0 13605.7 12394.9 14474.1 14494.7 14527.2 14661.3 13193.5 13570.5 13571.6 13855.4 13939.9 13085.1 13338.5 13357.7 13737.1 14194.1 12895.0 12933.0 13067.0 13090.3 13400.5 10573.7 10579.5 10761.9 10779.4 11650.4 8927.8 10502.4 10835.2 11064.1 11080.3 09:08:37 INFO - PID 1638 | -------- Summary: end -------- 09:08:37 INFO - PID 1638 | 09:08:38 INFO - TEST-INFO | 1638: exit 0 09:08:38 INFO - TEST-OK | dromaeo_css | took 1122365ms 09:08:38 INFO - TEST-START | kraken 09:08:38 INFO - operating with platform_type : mac_ 09:08:38 INFO - Initialising browser for kraken test... 09:08:38 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpAOtvIM/profile http://localhost:49249/getInfo.html) 09:08:40 INFO - PID 1648 | __metrics Screen width/height:1600/1200 09:08:40 INFO - PID 1648 | colorDepth:24 09:08:40 INFO - PID 1648 | Browser inner width/height: 1024/689 09:08:40 INFO - PID 1648 | __metrics 09:08:40 INFO - PID 1648 | 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" 09:08:40 INFO - PID 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:41 INFO - TEST-INFO | 1648: exit 0 09:08:41 INFO - Browser initialized. 09:08:41 INFO - Running cycle 1/1 for kraken test... 09:08:41 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpAOtvIM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:53 INFO - PID 1649 | RSS: Main: 233455616 09:08:53 INFO - PID 1649 | 09:08:56 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:57 INFO - PID 1649 | RSS: Main: 285196288 09:08:57 INFO - PID 1649 | 09:09:00 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 09:09:00 INFO - PID 1649 | RSS: Main: 287375360 09:09:00 INFO - PID 1649 | 09:09:04 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 09:09:04 INFO - PID 1649 | RSS: Main: 376938496 09:09:04 INFO - PID 1649 | 09:09:06 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 09:09:07 INFO - PID 1649 | RSS: Main: 281579520 09:09:07 INFO - PID 1649 | 09:09:09 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:10 INFO - PID 1649 | RSS: Main: 283246592 09:09:10 INFO - PID 1649 | 09:09:13 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:13 INFO - PID 1649 | RSS: Main: 325251072 09:09:13 INFO - PID 1649 | 09:09:17 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:17 INFO - PID 1649 | RSS: Main: 330489856 09:09:17 INFO - PID 1649 | 09:09:20 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 09:09:21 INFO - PID 1649 | RSS: Main: 325849088 09:09:21 INFO - PID 1649 | 09:09:22 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:23 INFO - PID 1649 | RSS: Main: 302424064 09:09:23 INFO - PID 1649 | 09:09:25 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:26 INFO - PID 1649 | RSS: Main: 304156672 09:09:26 INFO - PID 1649 | 09:09:28 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:28 INFO - PID 1649 | RSS: Main: 307539968 09:09:28 INFO - PID 1649 | 09:09:31 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:32 INFO - PID 1649 | RSS: Main: 325566464 09:09:32 INFO - PID 1649 | 09:09:35 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:36 INFO - PID 1649 | RSS: Main: 319696896 09:09:36 INFO - PID 1649 | 09:09:37 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 09:09:38 INFO - PID 1649 | RSS: Main: 364302336 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | __start_tp_report 09:09:38 INFO - PID 1649 | _x_x_mozilla_page_load 09:09:38 INFO - PID 1649 | _x_x_mozilla_page_load_details 09:09:38 INFO - PID 1649 | |i|pagename|runs| 09:09:38 INFO - PID 1649 | |0;ai-astar;115;117;115;114;116;117;116;115;116;118 09:09:38 INFO - PID 1649 | |1;audio-beat-detection;145;112;109;151;111;113;110;111;135;122 09:09:38 INFO - PID 1649 | |2;audio-dft;184;186;184;182;187;186;182;183;187;187 09:09:38 INFO - PID 1649 | |3;audio-fft;91;62;61;83;80;83;85;72;77;59 09:09:38 INFO - PID 1649 | |4;audio-oscillator;86;70;90;71;70;69;87;69;69;85 09:09:38 INFO - PID 1649 | |5;imaging-gaussian-blur;90;89;86;124;135;103;89;90;91;89 09:09:38 INFO - PID 1649 | |6;imaging-darkroom;97;98;97;97;97;96;113;149;100;97 09:09:38 INFO - PID 1649 | |7;imaging-desaturate;85;84;86;84;85;86;84;93;86;85 09:09:38 INFO - PID 1649 | |8;json-parse-financial;66;63;63;62;63;64;62;61;60;61 09:09:38 INFO - PID 1649 | |9;json-stringify-tinderbox;61;59;55;64;63;59;58;58;59;60 09:09:38 INFO - PID 1649 | |10;stanford-crypto-aes;88;85;85;85;75;98;98;82;87;86 09:09:38 INFO - PID 1649 | |11;stanford-crypto-ccm;142;140;139;140;139;148;156;141;181;158 09:09:38 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;173;174;170;177;176;173;174;174;170;174 09:09:38 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;68;67;66;66;66;67;67;65;65;66 09:09:38 INFO - PID 1649 | __end_tp_report 09:09:38 INFO - PID 1649 | __start_cc_report 09:09:38 INFO - PID 1649 | _x_x_mozilla_cycle_collect,2126 09:09:38 INFO - PID 1649 | __end_cc_report 09:09:38 INFO - PID 1649 | __startTimestamp1489421378252__endTimestamp 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | ------- Summary: start ------- 09:09:38 INFO - PID 1649 | Number of tests: 14 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:115.90 Median:116.00 stddev:1.20 (1.0%) stddev-sans-first:1.22 09:09:38 INFO - PID 1649 | Values: 115.0 117.0 115.0 114.0 116.0 117.0 116.0 115.0 116.0 118.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:117.50 stddev:15.90 (13.5%) stddev-sans-first:14.50 09:09:38 INFO - PID 1649 | Values: 145.0 112.0 109.0 151.0 111.0 113.0 110.0 111.0 135.0 122.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:184.80 Median:186.00 stddev:2.04 (1.1%) stddev-sans-first:2.15 09:09:38 INFO - PID 1649 | Values: 184.0 186.0 184.0 182.0 187.0 186.0 182.0 183.0 187.0 187.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:75.30 Median:81.50 stddev:11.26 (13.8%) stddev-sans-first:10.42 09:09:38 INFO - PID 1649 | Values: 91.0 62.0 61.0 83.0 80.0 83.0 85.0 72.0 77.0 59.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:78.00 stddev:9.06 (11.6%) stddev-sans-first:8.95 09:09:38 INFO - PID 1649 | Values: 86.0 70.0 90.0 71.0 70.0 69.0 87.0 69.0 69.0 85.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:89.00 stddev:17.10 (19.2%) stddev-sans-first:17.85 09:09:38 INFO - PID 1649 | Values: 90.0 89.0 86.0 124.0 135.0 103.0 89.0 90.0 91.0 89.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:97.00 stddev:16.56 (17.1%) stddev-sans-first:17.36 09:09:38 INFO - PID 1649 | Values: 97.0 98.0 97.0 97.0 97.0 96.0 113.0 149.0 100.0 97.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:85.50 stddev:2.66 (3.1%) stddev-sans-first:2.80 09:09:38 INFO - PID 1649 | Values: 85.0 84.0 86.0 84.0 85.0 86.0 84.0 93.0 86.0 85.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.72 (2.7%) stddev-sans-first:1.27 09:09:38 INFO - PID 1649 | Values: 66.0 63.0 63.0 62.0 63.0 64.0 62.0 61.0 60.0 61.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.50 stddev:2.59 (4.4%) stddev-sans-first:2.70 09:09:38 INFO - PID 1649 | Values: 61.0 59.0 55.0 64.0 63.0 59.0 58.0 58.0 59.0 60.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.50 stddev:6.87 (7.9%) stddev-sans-first:7.28 09:09:38 INFO - PID 1649 | Values: 88.0 85.0 85.0 85.0 75.0 98.0 98.0 82.0 87.0 86.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:145.00 stddev:13.44 (9.3%) stddev-sans-first:14.06 09:09:38 INFO - PID 1649 | Values: 142.0 140.0 139.0 140.0 139.0 148.0 156.0 141.0 181.0 158.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:174.00 stddev:2.22 (1.3%) stddev-sans-first:2.35 09:09:38 INFO - PID 1649 | Values: 173.0 174.0 170.0 177.0 176.0 173.0 174.0 174.0 170.0 174.0 09:09:38 INFO - PID 1649 | 09:09:38 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:0.78 09:09:38 INFO - PID 1649 | Values: 68.0 67.0 66.0 66.0 66.0 67.0 67.0 65.0 65.0 66.0 09:09:38 INFO - PID 1649 | -------- Summary: end -------- 09:09:38 INFO - PID 1649 | 09:09:39 INFO - TEST-INFO | 1649: exit 0 09:09:39 INFO - TEST-OK | kraken | took 61121ms 09:09:39 INFO - SUITE-END | took 1183s 09:09:39 INFO - Completed test suite (00:19:43) 09:09:39 INFO - javascript benchmark 09:09:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7552.852736534667, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3370.235530128541, 3388.81361525116, 3397.1676939149897, 3406.3017081498297, 3449.344624521341, 5682.232898558694, 5801.216835727176, 5915.053591425372, 5984.431479009492, 5996.280446346434, 5730.968425683375, 5825.30096388433, 5827.592068555203, 5835.35811060783, 5836.883262334751, 5218.0, 5260.053190425722, 5263.394709608406, 5302.602304827154, 5321.574274058066, 5331.093714068598, 5348.0, 5355.3305836770405, 5355.410904800488, 5361.115415956362, 5351.625386222966, 5358.624896257264, 5360.356757189143, 5362.329708786402, 5372.328458942632, 4477.664175186874, 5315.521603055724, 5332.760025798849, 5336.839894803143, 5346.839594812123, 5166.044281807877, 5315.44187860273, 5320.148776195829, 5323.201519772027, 5347.598930080259, 5979.1629171915965, 6010.188624535675, 6022.638641681513, 6027.246594175728, 6027.306859711145, 6321.0, 6331.2719037310835, 6342.080398342261, 6358.618482890995, 6374.298827129012, 6220.0, 6241.87516249677, 6248.094026366151, 6301.716422760941, 6311.93688063118, 4100.794960252006, 5751.971240143773, 5910.438508341701, 6007.249093863267, 6036.879262414771, 6027.9095813562835, 6365.681715914186, 6380.617162970237, 6386.9680651596445, 6400.3599640036555, 5759.308882934075, 6354.332795056453, 6358.841028974312, 6367.554271200971, 6370.55406121567, 6195.473384762256, 6255.96872015637, 6270.216222972129, 6287.874242515124, 6313.621182729051, 6297.842553936097, 6360.872782544416, 6361.332060133712, 6367.0, 6380.042993550912, 5402.594805389612, 6269.435750782452, 6283.0, 6329.810105696836, 6358.650274234961, 4934.925976110289, 5029.446760856303, 5061.215511595635, 5139.948600514022, 5163.380394352702, 4507.909841803145, 4558.111168322145, 4571.3600095986585, 4571.839985600488, 4592.7933243004145, 4939.728314942713, 5056.26684130799, 5756.280464941882, 5796.0436527972415, 5864.149698293723, 6189.009758438719, 6251.71867265965, 6304.873902522016, 6309.211348581427, 6317.715702793385, 5805.709714514257, 5812.041013232853, 5830.533557315404, 5852.678102704393, 5863.384344643751, 5564.610477266633, 5576.386597474275, 5591.9720401397735, 5595.4124816894455, 5602.159676048625, 6304.7162877671535, 6312.810615681537, 6323.557350985387, 6342.429181373698, 6364.554481186457, 6293.905591416224, 6294.276158241861, 6296.6851657417865, 6301.558890877594, 6305.684715764285, 6214.564980451325, 6429.4856411486035, 6432.935670643234, 6438.6136831790245, 6459.903101453576, 6229.4393504582995, 6236.407541283571, 6237.4698150657705, 6240.188775459162, 6241.719122639402, 6229.314775374796, 6291.0, 6303.306636270098, 6314.0, 6331.176946997045, 6197.690115494117, 6258.405451482102, 6299.874002520016, 6309.621422714652, 6322.114903913363, 6274.968625156845, 6291.779787707408, 6292.590981586182, 6293.653849038347, 6299.905501417574, 5944.227250457413, 6002.429769172097, 6188.44304012641, 6190.659513726789, 6196.008638617799, 6147.877042459215, 6164.260288765377, 6170.167027451416, 6170.691465426621, 6171.814845554641, 5968.985272503772, 6024.608400453956, 6041.426064523863, 6043.909341359971, 6044.304904935989], "value": 5783.9660766171, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.7011030642897, 781.3788038509383, 843.3632607381426, 858.8969323681156, 931.7204838548433, 36291.72978945737, 36559.0, 36793.63206367939, 37973.0, 38009.42985855215, 36249.45625815615, 36408.81795591025, 36425.45361819568, 36453.27093458129, 36547.81726091366, 7146.249643787405, 7330.780076597684, 7517.398608111365, 7648.885266721004, 7665.0, 7290.489665723401, 7371.852562948738, 7373.225811289818, 10715.981981711724, 10969.012788849002, 10872.075873550757, 10907.072898803612, 10911.563537458533, 10937.507812148433, 10963.232573719843, 10896.346219226833, 10928.890711092867, 10932.289401188897, 10937.289076210021, 10957.50691218897, 7172.569645821215, 7340.559566426032, 7406.629668516553, 10573.73565660857, 10714.410707411089, 36996.630033699585, 37759.678411255474, 39139.41290880639, 39202.60797392018, 39205.0, 3756.417755247941, 3783.886483405502, 3825.7513261637905, 3838.6161383861668, 3862.208247309295, 27146.778394972076, 27464.03875864355, 27564.724352756417, 27606.447871042466, 28204.43591128166, 3104.7205751482475, 3128.3586864692684, 3151.9684803151904, 3259.5925509311337, 3260.396826587089, 329729.0, 329983.0, 330595.0, 331125.0, 332411.99999999756, 41418.585814142076, 47363.5263647366, 48758.0, 48781.78045548889, 48844.0, 51504.48495515072, 51525.7423712879, 51529.96940061178, 51595.742021289654, 51611.741941290806, 14236.786448203286, 14633.341499632543, 14644.560663180122, 14645.267736613127, 14702.55892323232, 11808.937195652432, 11838.0, 11845.881541184477, 11857.525698971944, 11867.643970680894, 11169.609063682732, 13289.810445493013, 13306.73386532264, 13345.065845390729, 13443.529476468277, 4878.629814731171, 5709.25779648643, 5731.60925567997, 5751.1085781703905, 5753.88492230153, 926.6571368593528, 990.3562684255292, 1056.9894301057045, 1142.405948906564, 1147.8048731715628, 25452.363690907874, 25604.975800968125, 25626.23121306364, 25733.099341522957, 25887.870560647076, 1055.2349546578823, 1140.4582823159099, 1194.2834299420279, 1224.1981502116128, 1254.0907841814612, 11844.526218951318, 12322.0, 13031.413586388635, 13080.629169593813, 13149.53976610814, 200520.0, 200525.0, 201095.98904011064, 201503.0, 201765.0, 53346.73326633342, 53654.0, 53781.46218537765, 53819.46180538223, 53848.46151538513, 1238.1704258147063, 1262.2489618676689, 1407.0572716279912, 1438.8561143885527, 1440.9351579178963, 21974.670379943993, 25323.987040518172, 26511.734653149106, 28011.0, 28543.42913141767, 39730.60942866986, 39807.80096099501, 39851.605193818075, 39988.400173997994, 40016.399754003134, 348532.0, 349026.0, 349138.0, 350524.0, 351424.0, 31996.84001579977, 32310.515342270355, 32393.676063239065, 35137.0, 35785.821070894475, 42466.78766606147, 42648.57351426446, 43286.48497302705, 43782.56217437912, 43931.0, 30291.0, 34478.0, 35395.0, 35627.28745425129, 37008.07479813026, 34293.82853085719, 34923.65076349204, 35050.649493505756, 35914.28171436609, 36345.81827090848, 36342.818285908405, 36837.44743828791, 36877.893663190145, 36969.44545831761, 37058.0, 24402.511949760552, 31751.206219843763, 31773.682263177998, 31884.6811531891, 31895.840520797246, 23778.0, 23785.643215351436, 24009.399765006016, 24179.032838686257, 24278.0], "value": 17778.67907803924, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.7519686613103, 379.36267071320174, 389.7739311199503, 401.64454457804845, 439.1370956071321, 14048.789268160974, 14083.366248518816, 16348.83651163488, 19711.5072123197, 21824.626213713425, 22295.665565016538, 22624.547509049808, 22688.298377621704, 22866.31401057971, 23028.539429211407, 6426.485881129522, 6522.217333919936, 6666.966665166691, 6759.7296108155615, 6806.3533964273565, 4215.978920105411, 6630.668466576676, 6797.388235058844, 6812.591244525344, 6815.11403517542, 4299.742015479065, 5827.941720582782, 7158.033665455149, 7276.1268647762345, 7304.160021597531, 7187.748428804966, 7701.768946931601, 7751.0311211098615, 7777.416693747991, 7777.572233527154, 6968.372846443794, 7086.362227399559, 7335.633218339115, 7519.736809211706, 7552.395808335375, 26573.734262657512, 26652.60021099685, 26822.5976610351, 26902.730972690413, 27170.1848944532, 3250.7886987345746, 3341.331733653279, 3346.0798280472823, 3389.813560254185, 3418.9316213675834, 15645.765313520305, 16453.341866325332, 16646.58383540409, 16917.661646766996, 17566.736498952527, 1950.4246247357064, 2006.0741687979557, 2028.6348457277684, 2046.1917542570602, 2050.8256798172174, 138366.0, 144657.55342446547, 145841.8123728145, 146095.0, 148631.25684371617, 19701.310454134036, 21049.000344967204, 21142.682859757115, 21298.574028519342, 21334.253301134388, 35373.29253414917, 35452.290954180775, 35517.46723799145, 35522.28955420929, 35534.0, 10437.112845408074, 10460.424676642857, 10662.946685266525, 10737.624183153552, 10829.0, 8546.05993340732, 8568.485890846569, 8569.657213711507, 8747.125287471203, 8777.692780752643, 10051.547680354402, 10351.430671312999, 10522.210834187314, 10555.313904596176, 10558.894411055791, 3551.8579256829953, 4057.756534607933, 4140.60664236897, 4181.790910454465, 4195.364122644434, 956.660385563129, 958.5973890965811, 970.1511177719495, 983.341161421849, 991.4844280973854, 22901.19845805389, 23557.29328120159, 23565.882170589037, 23566.7643323568, 23596.0, 848.9108643592334, 885.3050355470924, 948.3882895532481, 974.2790335152079, 980.3088822380205, 11005.724856878642, 11213.663590092146, 11479.770404592029, 11820.704482387837, 11856.407179641155, 670025.0, 675809.0, 678924.0, 685353.0, 701643.0, 139664.0, 146396.0, 146643.26678366752, 149541.0, 154775.71953841753, 1162.4187906046977, 1231.0766924806396, 1258.8111783232405, 1259.5591542960074, 1262.7853264944981, 20131.79868201299, 20474.38576842697, 20517.58964820725, 20562.177512899318, 20638.04686875169, 59600.70199648974, 59727.10409343777, 60118.79762404815, 61103.08345374734, 62198.0, 81087.0, 82521.92258851642, 82868.0, 85460.71808922995, 85969.57015214884, 11737.823932641188, 12182.147249692436, 12267.447964841782, 12604.810927836274, 12679.556215532411, 13401.731965360443, 13504.527341542998, 14056.929715351767, 14240.35918383696, 14478.420863165355, 11488.0, 11973.221740586834, 12054.75890482203, 12056.553213614423, 12066.638000859988, 11511.424428778695, 11735.823962640396, 11879.04967602607, 12075.128355104973, 12129.150959432754, 11565.901239382581, 11652.766944661229, 11817.940910295394, 12093.455794489446, 12155.331456769962, 12126.0, 12160.513579457076, 12234.694132646398, 12526.997840172582, 12561.685957851127, 9838.852417213891, 11967.222130561486, 12019.338936358585, 12044.819327709916, 12114.152009359614], "value": 11780.586788392298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1569.8273189949105, 1581.7785510028605, 1601.7277062899298, 1605.3257631794647, 1607.5739928918836, 7601.467897247195, 10841.186910981669, 10847.915208479148, 10859.945700271497, 10925.453727313623, 10244.334118282306, 10661.573537058528, 10764.569417223302, 10768.892311076886, 10773.83839242412, 2314.9421264468356, 2340.754220806816, 2344.6834677318598, 2351.8941647625898, 2353.9293821185306, 2266.3880752196897, 2305.2162264829954, 2305.561943230789, 2308.122913292946, 2314.0975019742314, 2304.1704986204954, 2317.2932255662017, 2323.6282194848827, 2325.8720770357622, 2328.7671232876746, 1604.8314926932737, 2260.4235919840335, 2284.497410569672, 2286.7713228677167, 2286.8284878634086, 1943.5529828139465, 2263.2418139923147, 2323.256557901472, 2324.5467133908896, 2325.1164557468223, 10719.356838589632, 10803.97362250585, 10810.837837432446, 10812.0, 10835.349879007284, 5451.727413629303, 5608.102703567451, 5619.129034999541, 5622.0, 5629.971850140723, 3222.3233121044373, 3348.916277093068, 3592.46113083037, 3592.515010473605, 3594.51474051005, 1163.6043745126613, 1184.7512022475291, 1190.8332833403244, 1216.1486959128556, 1239.1387985350186, 13308.733825323536, 13718.862811371859, 13751.656208594764, 14827.110373377633, 14899.627509312353, 4091.4067460218102, 4220.11377610699, 4220.303649897733, 4229.91540169201, 4394.428724265825, 11271.94364028191, 11283.379414132138, 11302.830457543143, 11320.320780753182, 11322.660320190407, 5516.91724624131, 5521.613487055948, 5524.14375771756, 5525.060739674184, 5528.917066244009, 4353.869383918487, 4507.760365899404, 4854.0, 5117.923231151536, 5184.637075404686, 3075.861586228625, 3425.5546778918583, 3511.3152935177395, 3551.9644803551632, 3579.1410061585034, 1003.5333569890037, 1008.5360734062267, 1017.9083882450615, 1020.0666390252835, 1021.4790456866903, 895.4537731983485, 918.1231923513029, 935.4387367579398, 962.499500259861, 989.312427862643, 4664.440267167962, 6396.424321810966, 6416.990066932671, 6690.531662783558, 6834.453243740489, 968.5447839515416, 981.7103954333346, 985.1970643925179, 1016.4968340671415, 1039.1790485516358, 4037.9394309085387, 4058.7564746115327, 4190.497140343178, 4224.8732538023905, 4238.6397156242065, 12893.935530322477, 13363.665908352368, 13367.665808354868, 13385.665358365924, 13428.127171733806, 9310.627574896927, 11944.64166075019, 13318.134321269086, 13321.66695832612, 13324.333783310796, 9675.951620241995, 9739.026097390204, 9956.253281003808, 9971.252156088321, 9971.700848974542, 6535.934640653722, 6585.901211481927, 6749.86500269982, 6774.42417394527, 6798.8640227196165, 9698.951505242429, 11617.128715346214, 11873.287602743863, 11972.940135299268, 12049.156559040783, 11168.776624467628, 11370.46498722695, 11421.37182454973, 12141.392930353624, 12218.0225581955, 7809.726659566888, 8775.956120219358, 9007.144321289466, 9060.184583387263, 9091.499967501852, 9772.31593788428, 9941.005899410291, 10061.647842325483, 10089.545970431202, 10120.493975301059, 8770.517621530877, 8966.506842123747, 9018.458892466473, 9072.863907041532, 9108.225800807006, 8688.956555217437, 8706.60820263077, 8707.564621768759, 8806.779830504289, 8829.16122968317, 7567.394608431202, 7790.415718820997, 7793.2596403341595, 7794.142644309235, 7797.727079552188, 5682.801101961411, 5754.798582049608, 5868.061110222515, 5879.235699359056, 5939.406059394026, 5717.8570535736935, 5821.155932389865, 5902.4097590240635, 6045.455908968214, 6151.72317245716], "value": 4952.786307166993, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.22192680091985, 336.7020137370436, 361.54264854958467, 373.07477455909344, 384.8556791203297, 4640.791164397602, 4694.507076756942, 4749.0, 4901.534354236351, 5027.220780778976, 4173.60184338247, 4248.723832950856, 4743.99884146554, 4926.950730492694, 4962.1564334063205, 4177.874663760084, 4376.2997920332755, 4423.66822488313, 4427.535108813577, 4435.602785122692, 3615.6384361563896, 3743.5320584926885, 3860.517435320585, 4047.7368971016936, 4381.948332400233, 3336.796675191819, 3487.808170550619, 3777.9055523611855, 4237.406763053175, 4256.914861702764, 3934.2263704088837, 4086.5492750073595, 4125.23683118624, 4208.75925537336, 4225.2394568977725, 3525.700315473188, 3746.419305007701, 3782.6217378262227, 3795.7532760370486, 3877.2827027000094, 3948.440366055392, 4773.088416066197, 4895.31465594817, 4951.900961980777, 4953.028183090153, 4198.412222288882, 4435.846679863226, 4685.554872287127, 4868.269759536062, 4911.765146858171, 4244.639205667522, 4836.573210902792, 4938.506149385069, 5042.546170844642, 5562.527185189264, 3601.819909004539, 3603.045193023851, 3675.9816200918826, 4067.3898915162667, 4333.922914730545, 4817.9518204817505, 5355.892882142374, 5849.870319379112, 5860.384659610722, 5869.618474799167, 3946.565877753445, 4150.837765425686, 4162.396452514368, 4414.713043652152, 4457.768118114341, 3893.279743247508, 4481.775911204427, 4562.486885098456, 4573.193588532511, 4576.359309696645, 4574.908501829945, 4632.559906808848, 4763.261694437368, 4876.975615121973, 4910.607151427877, 4881.0, 4884.535969082932, 4999.925001124986, 5115.462876397999, 5406.91889621656, 1385.3765805387616, 1441.6467965348347, 1448.7537118689847, 1462.817147856518, 1522.444307827657, 1084.4306738962107, 1111.744298811278, 1164.3479651395246, 1174.1311429542077, 1186.4186548591301, 4748.525147485224, 4950.381202349707, 5011.872328726007, 5065.125179237886, 5083.847484575469, 4788.497207793202, 4915.0, 4915.600537518972, 5576.0, 5647.858803529863, 4218.303979843292, 4640.930386044212, 4683.484816670163, 5117.718525481134, 5177.974110129425, 2375.7267914189742, 2549.68128983877, 2905.6730044099386, 2950.5279155335043, 2969.3615872587498, 4797.712137271775, 5107.642465027487, 5317.5539251943765, 5615.0, 5838.970805146032, 5040.747962601855, 5302.310699609026, 5557.499825015687, 5792.710364481759, 5803.622764520293, 5377.300950876361, 5658.066419040894, 5800.564957628195, 5808.796692115756, 5830.941690583125, 4748.430188377417, 4981.626378021735, 5225.634205605571, 5667.858303542444, 5735.673309480575, 4601.401817763669, 4943.579795717261, 4953.405591329064, 5521.116621340727, 5671.262735844314, 5033.5721463676, 5237.0, 5254.211868219798, 5404.702741349263, 5877.617954832922, 3972.9273096264424, 4153.439285696392, 4239.523590655633, 4332.913341733085, 4532.5694059064335, 5303.655262407931, 5467.457632200886, 5658.708223169623, 5678.4605462480995, 5747.224124743107, 4968.677035992649, 5345.1714984177515, 5365.8999905020155, 5373.516383525345, 5400.891982160256, 4781.354517140142, 5152.896942061063, 5325.254464375068, 5364.9731751341, 5457.863553411196, 4600.42494688164, 5060.7105381502315, 5083.67824365675, 5237.580993520433, 5312.423474614731, 5297.993381257549, 5475.370332411823, 5497.811269463781, 5510.666047041482, 5537.723113844372, 4896.853094407174, 5240.554552863049, 5352.501299635953, 5352.892942141058, 5378.489043541014], "value": 4091.0546015279715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.0870808835215, 786.2677196741321, 794.5550491724634, 814.9324385055581, 843.2200214801308, 15126.24368781562, 15644.765328520081, 15646.295916683748, 15707.764383534242, 15739.999999999985, 14300.14199148052, 14359.138451692907, 14379.928100359484, 14399.136051836871, 14406.639834004156, 927.1609193679714, 940.3323640215456, 950.5722424908785, 972.6449845806266, 973.8782652168479, 968.6367612145445, 971.3346357744967, 974.8294048541512, 976.2677991506371, 976.623999760094, 1028.0233777911014, 1030.4332617060586, 1030.8762948446197, 1033.5607366869087, 1034.2966782587837, 1021.7598864266891, 1022.9821327778908, 1023.8822535408449, 1024.497995981971, 1024.5952848624788, 754.9584772837492, 755.6788364944877, 758.3932853717005, 759.092884003616, 763.4350580570393, 15691.0, 16025.0, 16077.19614019306, 16104.0, 16281.51155465338, 13368.799468007988, 13909.582712518539, 14583.0, 14715.55853324402, 14938.402463901431, 11511.251768635011, 11519.94240028803, 11592.246503977214, 11819.888363848224, 12127.211731237441, 3651.324504966563, 3759.5488541374934, 3778.659920607131, 3918.6473217410285, 3919.1573811630637, 108056.0, 110400.4479977595, 111679.0, 112334.0, 114207.0, 11162.609308674157, 11273.323600583992, 11825.940870295766, 11834.526618935146, 12048.277103373826, 24944.251672449856, 24960.251192464257, 24999.62500562493, 25001.0, 25028.998840046195, 7611.086669599573, 7707.768766937001, 7719.884201736979, 7797.298243158029, 7808.5314881107315, 3971.2454633620096, 4170.332746760504, 4223.97888010558, 4240.151969606091, 4264.31770916658, 3383.3233353329433, 3423.2126610879636, 3459.4810778383444, 3485.5294535237927, 3495.8252087395526, 602.4096385542193, 607.3046361915582, 610.6244659534354, 618.9597676151126, 646.6572716460283, 590.5364289033089, 602.9577929544978, 626.884384608495, 646.7283740828769, 653.9280679125293, 9701.70894873155, 9977.401355918668, 10045.748856278506, 10238.0, 10390.688279351632, 636.1157990393357, 678.925318214996, 703.8451540661047, 712.707789806177, 722.731606031422, 4574.656900732459, 4851.539103785135, 4854.868728467927, 4901.926471102936, 4924.901501969941, 91041.54479227694, 98463.50768246113, 98753.70563857027, 98822.5058874701, 99174.0, 57227.0, 59435.0, 65939.67030164819, 86899.56550217209, 92218.61672074924, 59770.701146493986, 76679.61660191663, 77213.6139319311, 77339.0, 77807.22192778112, 10342.741431464274, 10751.025984970742, 10771.141920645807, 10943.781124377627, 10994.835077473685, 41910.161796763285, 42438.0, 42872.0, 43106.0, 43732.56267437285, 40440.0, 58671.70664146652, 58796.4120358791, 59022.40977590341, 59237.703811480664, 13022.934885325512, 13088.541901033419, 13115.737685246433, 13382.0, 13605.707457791494, 12394.876051239733, 14474.059186152866, 14494.710105798036, 14527.201003944885, 14661.340239689027, 13193.538226162038, 13570.52503162424, 13571.59285221445, 13855.376508057363, 13939.930300348433, 13085.08404411682, 13338.490605334755, 13357.66605834862, 13737.107088039245, 14194.077384969944, 12895.032872534784, 12932.968101788234, 13067.0, 13090.280034598189, 13400.463981440633, 10573.682789516326, 10579.5239214234, 10761.946190268998, 10779.353238805697, 11650.36984119874, 8927.776805579913, 10502.369857808557, 10835.241533092609, 11064.114870810155, 11080.335179889233], "value": 7562.170206830687, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1460.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 115.0, 114.0, 116.0, 117.0, 116.0, 115.0, 116.0, 118.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 109.0, 151.0, 111.0, 113.0, 110.0, 111.0, 135.0, 122.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 182.0, 187.0, 186.0, 182.0, 183.0, 187.0, 187.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 62.0, 61.0, 83.0, 80.0, 83.0, 85.0, 72.0, 77.0, 59.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 90.0, 71.0, 70.0, 69.0, 87.0, 69.0, 69.0, 85.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 86.0, 124.0, 135.0, 103.0, 89.0, 90.0, 91.0, 89.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 97.0, 97.0, 96.0, 113.0, 149.0, 100.0, 97.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 84.0, 85.0, 86.0, 84.0, 93.0, 86.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 63.0, 62.0, 63.0, 64.0, 62.0, 61.0, 60.0, 61.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 55.0, 64.0, 63.0, 59.0, 58.0, 58.0, 59.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 85.0, 75.0, 98.0, 98.0, 82.0, 87.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 139.0, 140.0, 139.0, 148.0, 156.0, 141.0, 181.0, 158.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 170.0, 177.0, 176.0, 173.0, 174.0, 174.0, 170.0, 174.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 66.0, 67.0, 67.0, 65.0, 65.0, 66.0], "value": 66.3, "unit": "score"}]}]} 09:09:39 INFO - Return code: 0 09:09:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:39 ERROR - # TBPL SUCCESS # 09:09:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:39 INFO - 2017-03-13 09:09:39.591 screenresolution[1653:10297] starting screenresolution argv=screenresolution get 09:09:39 INFO - 2017-03-13 09:09:39.594 screenresolution[1653:10297] Display 0: 1600x1200x32@60 09:09:39 INFO - 2017-03-13 09:09:39.599 screenresolution[1654:10301] starting screenresolution argv=screenresolution list 09:09:39 INFO - Available Modes on Display 0 09:09:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:39 INFO - Intel Iris: 09:09:39 INFO - Chipset Model: Intel Iris 09:09:39 INFO - Type: GPU 09:09:39 INFO - Bus: Built-In 09:09:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:39 INFO - Vendor: Intel (0x8086) 09:09:39 INFO - Device ID: 0x0a2e 09:09:39 INFO - Revision ID: 0x0009 09:09:39 INFO - Displays: 09:09:39 INFO - Display: 09:09:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:39 INFO - Display Serial Number: 1600x1200 60 09:09:39 INFO - Main Display: Yes 09:09:39 INFO - Mirror: Off 09:09:39 INFO - Online: Yes 09:09:39 INFO - Rotation: Supported 09:09:39 INFO - Return code: 0 09:09:39 INFO - Running post-action listener: _resource_record_post_action 09:09:39 INFO - [mozharness: 2017-03-13 16:09:39.837425Z] Finished run-tests step (success) 09:09:39 INFO - Running post-run listener: _resource_record_post_run 09:09:39 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 37905408; Write bytes: 511658496; Read time: 242; Write time: 1599 09:09:39 INFO - TinderboxPrint: CPU usage
54.1% 09:09:39 INFO - TinderboxPrint: I/O read bytes / time
37,905,408 / 242 09:09:39 INFO - TinderboxPrint: I/O write bytes / time
511,658,496 / 1,599 09:09:39 INFO - TinderboxPrint: CPU idle
2,211.1 (45.9%) 09:09:39 INFO - TinderboxPrint: CPU system
963.6 (20.0%) 09:09:39 INFO - TinderboxPrint: CPU user
1,643.0 (34.1%) 09:09:39 INFO - TinderboxPrint: Swap in / out
247,435,264 / 0 09:09:39 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203383296; Write bytes: 203339264; Read time: 15582; Write time: 439 09:09:39 INFO - run-tests - Wall time: 1185s; CPU: 54.0%; Read bytes: 36078592; Write bytes: 297538560; Read time: 224; Write time: 1125 09:09:40 INFO - Running post-run listener: _upload_blobber_files 09:09:40 INFO - Blob upload gear active. 09:09:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:40 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 09:09:40 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'] 09:09:40 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 09:09:40 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:42 INFO - (blobuploader) - INFO - Done attempting. 09:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:43 INFO - (blobuploader) - INFO - Done attempting. 09:09:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:09:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:09:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:44 INFO - (blobuploader) - INFO - Done attempting. 09:09:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:46 INFO - (blobuploader) - INFO - Done attempting. 09:09:46 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:46 INFO - Return code: 0 09:09:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5852793f665ce0b33d339f290f5311e4c1f054a5bb6073e48a953f372500e8b2788aaa485b6508b58d7eda462ad0ba0ef2c496314924f5886c44cf33c94eac07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41d22687a242749c16a37846f4720769e0857d2c8fed9641b476aa5b251971f4805e6f5e6ef584ab99db31d7679d68d07c995ae0e120dd07704f168843196ec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9986e3fabad07b93638a78ffeb0b24e3721f23d2c7394b95fdf98237f5133122c5880385e95e4a40669e3a3b735ba7770e38dca858c66abfa99503c68f216bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ad9431a5c9331b61c32951bd05e916fe8c081ef635ccb40a49e1a9bd70605acc6c35d0471a69cfc8efc2020c3caf685fd60e73b9848b166ba070693152e219a"} 09:09:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:46 INFO - Contents: 09:09:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5852793f665ce0b33d339f290f5311e4c1f054a5bb6073e48a953f372500e8b2788aaa485b6508b58d7eda462ad0ba0ef2c496314924f5886c44cf33c94eac07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41d22687a242749c16a37846f4720769e0857d2c8fed9641b476aa5b251971f4805e6f5e6ef584ab99db31d7679d68d07c995ae0e120dd07704f168843196ec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9986e3fabad07b93638a78ffeb0b24e3721f23d2c7394b95fdf98237f5133122c5880385e95e4a40669e3a3b735ba7770e38dca858c66abfa99503c68f216bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ad9431a5c9331b61c32951bd05e916fe8c081ef635ccb40a49e1a9bd70605acc6c35d0471a69cfc8efc2020c3caf685fd60e73b9848b166ba070693152e219a"} 09:09:46 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:46 INFO - Copying logs to upload dir... 09:09:46 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.655776 ========= master_lag: 1.85 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-13 09:09:48.224394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:09:48.229602) ========= 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.OLVpBdafud/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.NtUEi0syaw/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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/5852793f665ce0b33d339f290f5311e4c1f054a5bb6073e48a953f372500e8b2788aaa485b6508b58d7eda462ad0ba0ef2c496314924f5886c44cf33c94eac07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41d22687a242749c16a37846f4720769e0857d2c8fed9641b476aa5b251971f4805e6f5e6ef584ab99db31d7679d68d07c995ae0e120dd07704f168843196ec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9986e3fabad07b93638a78ffeb0b24e3721f23d2c7394b95fdf98237f5133122c5880385e95e4a40669e3a3b735ba7770e38dca858c66abfa99503c68f216bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ad9431a5c9331b61c32951bd05e916fe8c081ef635ccb40a49e1a9bd70605acc6c35d0471a69cfc8efc2020c3caf685fd60e73b9848b166ba070693152e219a"} build_url:https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/GDaK3LtkSR6bKW8Q42xYsA/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/5852793f665ce0b33d339f290f5311e4c1f054a5bb6073e48a953f372500e8b2788aaa485b6508b58d7eda462ad0ba0ef2c496314924f5886c44cf33c94eac07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41d22687a242749c16a37846f4720769e0857d2c8fed9641b476aa5b251971f4805e6f5e6ef584ab99db31d7679d68d07c995ae0e120dd07704f168843196ec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9986e3fabad07b93638a78ffeb0b24e3721f23d2c7394b95fdf98237f5133122c5880385e95e4a40669e3a3b735ba7770e38dca858c66abfa99503c68f216bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ad9431a5c9331b61c32951bd05e916fe8c081ef635ccb40a49e1a9bd70605acc6c35d0471a69cfc8efc2020c3caf685fd60e73b9848b166ba070693152e219a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:09:48.265151) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:09:48.265457) ========= 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.OLVpBdafud/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.NtUEi0syaw/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.005156 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:09:48.313910) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:09:48.314195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:09:48.314566) ========= ========= Total master_lag: 1.99 =========