builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0263 starttime: 1486258707.84 results: success (0) buildid: 20170204163708 builduid: 7c9f9ae6ae6045d7a4d7e51478b573f9 revision: e581572bc9c128df28d3f97698d83f9b3c459da5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:27.840061) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:27.840686) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:27.841061) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:27.868936) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:27.869625) ========= 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.1kyASFRdsH/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.45uJHSFAiF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-02-04 17:38:27-- 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% 11.0M=0.001s 2017-02-04 17:38:28 (11.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174998 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:28.118412) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:28.119119) ========= 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.1kyASFRdsH/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.45uJHSFAiF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.020103 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:28.166188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:28.166552) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e581572bc9c128df28d3f97698d83f9b3c459da5 --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 e581572bc9c128df28d3f97698d83f9b3c459da5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1kyASFRdsH/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.45uJHSFAiF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-02-04 17:38:28,264 truncating revision to first 12 chars 2017-02-04 17:38:28,264 Setting DEBUG logging. 2017-02-04 17:38:28,264 attempt 1/10 2017-02-04 17:38:28,264 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e581572bc9c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-04 17:38:28,726 unpacking tar archive at: mozilla-central-e581572bc9c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.719799 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:29.021787) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:29.022157) ========= script_repo_revision: e581572bc9c128df28d3f97698d83f9b3c459da5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:29.022551) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:29.022858) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 17:38:29.112176) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-04 17:38:29.112628) ========= /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.1kyASFRdsH/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.45uJHSFAiF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:38:29 INFO - MultiFileLogger online at 20170204 17:38:29 in /builds/slave/test 17:38:29 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 17:38:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:38:29 INFO - {'append_to_log': False, 17:38:29 INFO - 'base_work_dir': '/builds/slave/test', 17:38:29 INFO - 'blob_upload_branch': 'Firefox', 17:38:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:38:29 INFO - 'branch': 'Firefox', 17:38:29 INFO - 'buildbot_json_path': 'buildprops.json', 17:38:29 INFO - 'config_files': ('talos/mac_config.py',), 17:38:29 INFO - 'default_actions': ('clobber', 17:38:29 INFO - 'read-buildbot-config', 17:38:29 INFO - 'download-and-extract', 17:38:29 INFO - 'populate-webroot', 17:38:29 INFO - 'create-virtualenv', 17:38:29 INFO - 'install', 17:38:29 INFO - 'run-tests'), 17:38:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:38:29 INFO - 'download_minidump_stackwalk': True, 17:38:29 INFO - 'download_symbols': 'ondemand', 17:38:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:38:29 INFO - 'tooltool.py': '/tools/tooltool.py', 17:38:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:38:29 INFO - '/tools/misc-python/virtualenv.py')}, 17:38:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:38:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:38:29 INFO - 'installer_path': 'installer.exe', 17:38:29 INFO - 'log_level': 'info', 17:38:29 INFO - 'log_name': 'talos', 17:38:29 INFO - 'log_to_console': True, 17:38:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:38:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:38:29 INFO - 'opt_config_files': (), 17:38:29 INFO - 'pip_index': False, 17:38:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:38:29 INFO - 'cmd': ('bash', 17:38:29 INFO - '-c', 17:38:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:38:29 INFO - 'enabled': True, 17:38:29 INFO - 'halt_on_failure': False, 17:38:29 INFO - 'name': 'check_screen_resolution'},), 17:38:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:38:29 INFO - 'cmd': ('bash', 17:38:29 INFO - '-c', 17:38:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:38:29 INFO - 'enabled': True, 17:38:29 INFO - 'halt_on_failure': False, 17:38:29 INFO - 'name': 'check_screen_resolution'},), 17:38:29 INFO - 'run_cmd_checks_enabled': True, 17:38:29 INFO - 'sps_profile': False, 17:38:29 INFO - 'sps_profile_interval': 0, 17:38:29 INFO - 'suite': 'dromaeojs', 17:38:29 INFO - 'system_bits': '32', 17:38:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:38:29 INFO - 'title': 't-yosemite-r7-0263', 17:38:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:38:29 INFO - 'use_talos_json': True, 17:38:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:38:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:38:29 INFO - 'work_dir': 'build'} 17:38:29 INFO - [mozharness: 2017-02-05 01:38:29.328477Z] Running clobber step. 17:38:29 INFO - Running pre-action listener: _resource_record_pre_action 17:38:29 INFO - Running main action method: clobber 17:38:29 INFO - rmtree: /builds/slave/test/build 17:38:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:38:30 INFO - Running post-action listener: _resource_record_post_action 17:38:30 INFO - [mozharness: 2017-02-05 01:38:30.235408Z] Finished clobber step (success) 17:38:30 INFO - [mozharness: 2017-02-05 01:38:30.235573Z] Running read-buildbot-config step. 17:38:30 INFO - Running pre-action listener: _resource_record_pre_action 17:38:30 INFO - Running main action method: read_buildbot_config 17:38:30 INFO - Using buildbot properties: 17:38:30 INFO - { 17:38:30 INFO - "project": "", 17:38:30 INFO - "product": "firefox", 17:38:30 INFO - "script_repo_revision": "production", 17:38:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:38:30 INFO - "repository": "", 17:38:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:38:30 INFO - "buildid": "20170204163708", 17:38:30 INFO - "pgo_build": "False", 17:38:30 INFO - "basedir": "/builds/slave/test", 17:38:30 INFO - "buildnumber": 60, 17:38:30 INFO - "slavename": "t-yosemite-r7-0263", 17:38:30 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:38:30 INFO - "platform": "yosemite_r7", 17:38:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:38:30 INFO - "branch": "mozilla-central", 17:38:30 INFO - "repo_path": "mozilla-central", 17:38:30 INFO - "revision": "e581572bc9c128df28d3f97698d83f9b3c459da5", 17:38:30 INFO - "stage_platform": "macosx64", 17:38:30 INFO - "builduid": "7c9f9ae6ae6045d7a4d7e51478b573f9", 17:38:30 INFO - "slavebuilddir": "test" 17:38:30 INFO - } 17:38:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 17:38:30 INFO - Running post-action listener: _resource_record_post_action 17:38:30 INFO - [mozharness: 2017-02-05 01:38:30.238809Z] Finished read-buildbot-config step (success) 17:38:30 INFO - [mozharness: 2017-02-05 01:38:30.238940Z] Running download-and-extract step. 17:38:30 INFO - Running pre-action listener: _resource_record_pre_action 17:38:30 INFO - Running main action method: download_and_extract 17:38:30 INFO - mkdir: /builds/slave/test/build/tests 17:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:30 INFO - trying https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 17:38:30 INFO - Downloading https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 17:38:31 INFO - Downloaded 1199 bytes. 17:38:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:38:31 INFO - Using the following test package requirements: 17:38:31 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:38:31 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 17:38:31 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 17:38:31 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'jsshell-mac.zip'], 17:38:31 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 17:38:31 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:38:31 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 17:38:31 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 17:38:31 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 17:38:31 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:38:31 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 17:38:31 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:38:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:38:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:38:31 INFO - Fetch https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:38:33 INFO - Expected file size: 34851014 17:38:33 INFO - Obtained file size: 34851014 17:38:40 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:38:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:38:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:38:40 INFO - Fetch https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:38:41 INFO - Expected file size: 34851014 17:38:41 INFO - Obtained file size: 34851014 17:38:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 17:38:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:38:48 INFO - Fetch https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 17:38:49 INFO - Expected file size: 14403454 17:38:49 INFO - Obtained file size: 14403454 17:38:49 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')]} 17:38:49 INFO - trying https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:38:49 INFO - Downloading https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 17:38:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 17:38:51 INFO - Downloaded 70279543 bytes. 17:38:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:38:51 INFO - mkdir: /builds/slave/test/properties 17:38:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:38:51 INFO - Writing to file /builds/slave/test/properties/build_url 17:38:51 INFO - Contents: 17:38:51 INFO - build_url:https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:38:52 INFO - Running post-action listener: _resource_record_post_action 17:38:52 INFO - Running post-action listener: set_extra_try_arguments 17:38:52 INFO - [mozharness: 2017-02-05 01:38:52.616967Z] Finished download-and-extract step (success) 17:38:52 INFO - [mozharness: 2017-02-05 01:38:52.617099Z] Running populate-webroot step. 17:38:52 INFO - Running pre-action listener: _resource_record_pre_action 17:38:52 INFO - Running main action method: populate_webroot 17:38:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:38:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:38:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:38:52 INFO - '--tppagecycles', 17:38:52 INFO - '7'], 17:38:52 INFO - 'tests': ['tsvgm']}}, 17:38:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:38:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:38:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:38:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:38:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:38:52 INFO - 'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:38:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:38:52 INFO - 'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['damp', 'tps']}, 17:38:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:38:52 INFO - 'tests': ['damp', 'tps']}, 17:38:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['dromaeo_dom']}, 17:38:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:38:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['basic_compositor_video']}, 17:38:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:38:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['a11yr', 17:38:52 INFO - 'ts_paint', 17:38:52 INFO - 'tpaint', 17:38:52 INFO - 'sessionrestore', 17:38:52 INFO - 'sessionrestore_no_auto_restore', 17:38:52 INFO - 'tabpaint']}, 17:38:52 INFO - 'other-e10s': {'tests': ['a11yr', 17:38:52 INFO - 'ts_paint', 17:38:52 INFO - 'tpaint', 17:38:52 INFO - 'sessionrestore', 17:38:52 INFO - 'sessionrestore_no_auto_restore', 17:38:52 INFO - 'tabpaint']}, 17:38:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:38:52 INFO - 'ts_paint', 17:38:52 INFO - 'tpaint', 17:38:52 INFO - 'sessionrestore', 17:38:52 INFO - 'sessionrestore_no_auto_restore', 17:38:52 INFO - 'tabpaint']}, 17:38:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:38:52 INFO - 'ts_paint', 17:38:52 INFO - 'tpaint', 17:38:52 INFO - 'sessionrestore', 17:38:52 INFO - 'sessionrestore_no_auto_restore', 17:38:52 INFO - 'tabpaint']}, 17:38:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['a11yr', 17:38:52 INFO - 'ts_paint', 17:38:52 INFO - 'tpaint', 17:38:52 INFO - 'sessionrestore', 17:38:52 INFO - 'sessionrestore_no_auto_restore', 17:38:52 INFO - 'tabpaint']}, 17:38:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['a11yr', 17:38:52 INFO - 'ts_paint', 17:38:52 INFO - 'tpaint', 17:38:52 INFO - 'sessionrestore', 17:38:52 INFO - 'sessionrestore_no_auto_restore', 17:38:52 INFO - 'tabpaint']}, 17:38:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['tsvgx', 17:38:52 INFO - 'tsvgr_opacity', 17:38:52 INFO - 'tart', 17:38:52 INFO - 'tscrollx', 17:38:52 INFO - 'cart', 17:38:52 INFO - 'tsvg_static']}, 17:38:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:38:52 INFO - 'tsvgr_opacity', 17:38:52 INFO - 'tart', 17:38:52 INFO - 'tscrollx', 17:38:52 INFO - 'cart', 17:38:52 INFO - 'tsvg_static']}, 17:38:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:38:52 INFO - 'talos_options': ['--disable-e10s'], 17:38:52 INFO - 'tests': ['tp5o']}, 17:38:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:38:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:38:52 INFO - 'talos_options': ['--disable-e10s', 17:38:52 INFO - '--xperf_path', 17:38:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:52 INFO - 'tests': ['tp5n']}, 17:38:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:38:52 INFO - 'talos_options': ['--xperf_path', 17:38:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:52 INFO - 'tests': ['tp5n']}}, 17:38:52 INFO - 'talos.zip': {'path': '', 17:38:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:38:52 INFO - Running post-action listener: _resource_record_post_action 17:38:52 INFO - [mozharness: 2017-02-05 01:38:52.635515Z] Finished populate-webroot step (success) 17:38:52 INFO - [mozharness: 2017-02-05 01:38:52.635650Z] Running create-virtualenv step. 17:38:52 INFO - Running pre-action listener: _resource_record_pre_action 17:38:52 INFO - Running main action method: create_virtualenv 17:38:52 INFO - Creating virtualenv /builds/slave/test/build/venv 17:38:52 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 17:38:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:38:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:38:52 INFO - Using real prefix '/tools/python27' 17:38:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:38:53 INFO - Installing distribute.............................................................................................................................................................................................done. 17:38:56 INFO - Installing pip.................done. 17:38:56 INFO - Return code: 0 17:38:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:38:56 INFO - Reading from file tmpfile_stdout 17:38:56 INFO - Output received: 17:38:56 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) 17:38:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:38:56 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')]} 17:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:56 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')]} 17:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:56 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:38:56 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 17:38:56 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 17:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:56 INFO - 'HOME': '/Users/cltbld', 17:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:56 INFO - 'LOGNAME': 'cltbld', 17:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:56 INFO - 'MOZ_NO_REMOTE': '1', 17:38:56 INFO - 'NO_EM_RESTART': '1', 17:38:56 INFO - 'PAGER': '/bin/cat', 17:38:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:56 INFO - 'PWD': '/builds/slave/test', 17:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:56 INFO - 'SHELL': '/bin/bash', 17:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:38:56 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:56 INFO - 'USER': 'cltbld', 17:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:56 INFO - 'XPC_FLAGS': '0x0', 17:38:56 INFO - 'XPC_SERVICE_NAME': '0', 17:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:56 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 17:38:56 INFO - Cleaning up... 17:38:56 INFO - Return code: 0 17:38:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:38:56 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')]} 17:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:56 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')]} 17:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:56 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:38:56 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 17:38:56 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 17:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:56 INFO - 'HOME': '/Users/cltbld', 17:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:56 INFO - 'LOGNAME': 'cltbld', 17:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:56 INFO - 'MOZ_NO_REMOTE': '1', 17:38:56 INFO - 'NO_EM_RESTART': '1', 17:38:56 INFO - 'PAGER': '/bin/cat', 17:38:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:56 INFO - 'PWD': '/builds/slave/test', 17:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:56 INFO - 'SHELL': '/bin/bash', 17:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:38:56 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:56 INFO - 'USER': 'cltbld', 17:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:56 INFO - 'XPC_FLAGS': '0x0', 17:38:56 INFO - 'XPC_SERVICE_NAME': '0', 17:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:57 INFO - Downloading/unpacking psutil>=3.1.1 17:38:57 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 17:38:57 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 17:38:57 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 17:38:57 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 17:38:57 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 17:38:57 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 17:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:39:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:39:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:39:01 INFO - Installing collected packages: psutil 17:39:01 INFO - Running setup.py install for psutil 17:39:01 INFO - building 'psutil._psutil_osx' extension 17:39:01 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 17:39:01 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 17:39:01 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 17:39:01 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 17:39:01 INFO - building 'psutil._psutil_posix' extension 17:39:01 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 17:39:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:39:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:39:01 INFO - ^ 17:39:01 INFO - 1 warning generated. 17:39:01 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 17:39:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:39:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:39:01 INFO - Successfully installed psutil 17:39:01 INFO - Cleaning up... 17:39:02 INFO - Return code: 0 17:39:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:39:02 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')]} 17:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:02 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')]} 17:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:02 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39:02 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 17:39:02 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 17:39:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:02 INFO - 'HOME': '/Users/cltbld', 17:39:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:02 INFO - 'LOGNAME': 'cltbld', 17:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:02 INFO - 'MOZ_NO_REMOTE': '1', 17:39:02 INFO - 'NO_EM_RESTART': '1', 17:39:02 INFO - 'PAGER': '/bin/cat', 17:39:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:02 INFO - 'PWD': '/builds/slave/test', 17:39:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:02 INFO - 'SHELL': '/bin/bash', 17:39:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:02 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:02 INFO - 'USER': 'cltbld', 17:39:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:02 INFO - 'XPC_FLAGS': '0x0', 17:39:02 INFO - 'XPC_SERVICE_NAME': '0', 17:39:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:39:02 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 17:39:02 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 17:39:02 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 17:39:02 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 17:39:02 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 17:39:02 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 17:39:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:39:05 INFO - Installing collected packages: mozsystemmonitor 17:39:05 INFO - Running setup.py install for mozsystemmonitor 17:39:05 INFO - Successfully installed mozsystemmonitor 17:39:05 INFO - Cleaning up... 17:39:05 INFO - Return code: 0 17:39:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:39: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')]} 17:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39: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')]} 17:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39: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', '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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:39: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 jsonschema==2.5.1 17:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:05 INFO - 'HOME': '/Users/cltbld', 17:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:05 INFO - 'LOGNAME': 'cltbld', 17:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:05 INFO - 'MOZ_NO_REMOTE': '1', 17:39:05 INFO - 'NO_EM_RESTART': '1', 17:39:05 INFO - 'PAGER': '/bin/cat', 17:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:05 INFO - 'PWD': '/builds/slave/test', 17:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:05 INFO - 'SHELL': '/bin/bash', 17:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:05 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:05 INFO - 'USER': 'cltbld', 17:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:05 INFO - 'XPC_FLAGS': '0x0', 17:39:05 INFO - 'XPC_SERVICE_NAME': '0', 17:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:05 INFO - Downloading/unpacking jsonschema==2.5.1 17:39: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 17:39: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 17:39: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 17:39: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 17:39: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 17:39: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 17:39:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:39:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:39:08 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 17:39:08 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 17:39:08 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 17:39:08 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 17:39:08 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 17:39:08 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 17:39:08 INFO - Downloading functools32-3.2.3-2.tar.gz 17:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:39:08 INFO - warning: no files found matching '*.txt' 17:39:08 INFO - no previously-included directories found matching 'build' 17:39:08 INFO - no previously-included directories found matching 'dist' 17:39:08 INFO - no previously-included directories found matching '.git*' 17:39:08 INFO - Installing collected packages: jsonschema, functools32 17:39:08 INFO - Running setup.py install for functools32 17:39:08 INFO - warning: no files found matching '*.txt' 17:39:08 INFO - no previously-included directories found matching 'build' 17:39:08 INFO - no previously-included directories found matching 'dist' 17:39:08 INFO - no previously-included directories found matching '.git*' 17:39:08 INFO - Successfully installed jsonschema functools32 17:39:08 INFO - Cleaning up... 17:39:08 INFO - Return code: 0 17:39:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:39:08 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')]} 17:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:08 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')]} 17:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:08 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39:08 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 17:39:08 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 17:39:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:08 INFO - 'HOME': '/Users/cltbld', 17:39:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:08 INFO - 'LOGNAME': 'cltbld', 17:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:08 INFO - 'MOZ_NO_REMOTE': '1', 17:39:08 INFO - 'NO_EM_RESTART': '1', 17:39:08 INFO - 'PAGER': '/bin/cat', 17:39:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:08 INFO - 'PWD': '/builds/slave/test', 17:39:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:08 INFO - 'SHELL': '/bin/bash', 17:39:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:08 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:08 INFO - 'USER': 'cltbld', 17:39:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:08 INFO - 'XPC_FLAGS': '0x0', 17:39:08 INFO - 'XPC_SERVICE_NAME': '0', 17:39:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:39:09 INFO - Cleaning up... 17:39:09 INFO - Return code: 0 17:39:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:39:09 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')]} 17:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:09 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')]} 17:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:09 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39:09 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 17:39:09 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 17:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:09 INFO - 'HOME': '/Users/cltbld', 17:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:09 INFO - 'LOGNAME': 'cltbld', 17:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:09 INFO - 'MOZ_NO_REMOTE': '1', 17:39:09 INFO - 'NO_EM_RESTART': '1', 17:39:09 INFO - 'PAGER': '/bin/cat', 17:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:09 INFO - 'PWD': '/builds/slave/test', 17:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:09 INFO - 'SHELL': '/bin/bash', 17:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:09 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:09 INFO - 'USER': 'cltbld', 17:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:09 INFO - 'XPC_FLAGS': '0x0', 17:39:09 INFO - 'XPC_SERVICE_NAME': '0', 17:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:09 INFO - Downloading/unpacking blobuploader==1.2.4 17:39:09 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 17:39:09 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 17:39:09 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 17:39:09 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 17:39:09 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 17:39:09 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 17:39:12 INFO - Downloading blobuploader-1.2.4.tar.gz 17:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:39:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:39:12 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 17:39:12 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 17:39:12 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 17:39:12 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 17:39:12 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 17:39:12 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 17:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:39:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:39:12 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 17:39:12 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 17:39:12 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 17:39:12 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 17:39:12 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 17:39:12 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 17:39:13 INFO - Downloading docopt-0.6.1.tar.gz 17:39:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:39:13 INFO - Installing collected packages: blobuploader, requests, docopt 17:39:13 INFO - Running setup.py install for blobuploader 17:39:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:39:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:39:13 INFO - Running setup.py install for requests 17:39:14 INFO - Running setup.py install for docopt 17:39:14 INFO - Successfully installed blobuploader requests docopt 17:39:14 INFO - Cleaning up... 17:39:14 INFO - Return code: 0 17:39:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:14 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39:14 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 17:39:14 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 17:39:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:14 INFO - 'HOME': '/Users/cltbld', 17:39:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:14 INFO - 'LOGNAME': 'cltbld', 17:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:14 INFO - 'MOZ_NO_REMOTE': '1', 17:39:14 INFO - 'NO_EM_RESTART': '1', 17:39:14 INFO - 'PAGER': '/bin/cat', 17:39:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:14 INFO - 'PWD': '/builds/slave/test', 17:39:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:14 INFO - 'SHELL': '/bin/bash', 17:39:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:14 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:14 INFO - 'USER': 'cltbld', 17:39:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:14 INFO - 'XPC_FLAGS': '0x0', 17:39:14 INFO - 'XPC_SERVICE_NAME': '0', 17:39:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:39:14 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3znUBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:39:14 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-5Q97OO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:39:14 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-xX9ef6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:39:14 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-OCBpbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:39:14 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-bGMARz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-9b00cJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-uuQhwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-g4qFk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-nA3zST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BWRk1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-NSoqrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BcjD17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-x14xlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-VXZmRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:39:15 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Qn2KbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:39:16 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-RXM0YK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:39:16 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-jeGZOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:39:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:39:16 INFO - Running setup.py install for manifestparser 17:39:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:39:16 INFO - Running setup.py install for mozcrash 17:39:16 INFO - Running setup.py install for mozdebug 17:39:16 INFO - Running setup.py install for mozdevice 17:39:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:39:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:39:16 INFO - Running setup.py install for mozfile 17:39:17 INFO - Running setup.py install for mozhttpd 17:39:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Running setup.py install for mozinfo 17:39:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Running setup.py install for mozInstall 17:39:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Running setup.py install for mozleak 17:39:17 INFO - Running setup.py install for mozlog 17:39:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Running setup.py install for moznetwork 17:39:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:39:17 INFO - Running setup.py install for mozprocess 17:39:18 INFO - Running setup.py install for mozprofile 17:39:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:39:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:39:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:39:18 INFO - Running setup.py install for mozrunner 17:39:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:39:18 INFO - Running setup.py install for mozscreenshot 17:39:18 INFO - Running setup.py install for moztest 17:39:18 INFO - Running setup.py install for mozversion 17:39:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:39:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:39:18 INFO - Cleaning up... 17:39:18 INFO - Return code: 0 17:39:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:39: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')]} 17:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39: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')]} 17:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:18 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39:18 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 17:39:18 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 17:39:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:18 INFO - 'HOME': '/Users/cltbld', 17:39:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:18 INFO - 'LOGNAME': 'cltbld', 17:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:18 INFO - 'MOZ_NO_REMOTE': '1', 17:39:18 INFO - 'NO_EM_RESTART': '1', 17:39:18 INFO - 'PAGER': '/bin/cat', 17:39:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:18 INFO - 'PWD': '/builds/slave/test', 17:39:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:18 INFO - 'SHELL': '/bin/bash', 17:39:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:18 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:18 INFO - 'USER': 'cltbld', 17:39:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:18 INFO - 'XPC_FLAGS': '0x0', 17:39:18 INFO - 'XPC_SERVICE_NAME': '0', 17:39:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LUeSLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:39:19 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)) 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-UdKJpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:39:19 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)) 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-7HSiIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:39:19 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)) 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-XOY2bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:39:19 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)) 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-ZnPiKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:39:19 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)) 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-ot1rRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:39:19 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)) 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-fjD6hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:39:19 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)) 17:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:39:19 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-7mAzyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-ysfAbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-mOEH5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LQdYGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Mjt5M9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:39:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-2hKDUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-FaEjwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-tq8F_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-YmZHCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:39:20 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)) 17:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:39:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-mUUbqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:39:20 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)) 17:39:20 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)) 17:39:20 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)) 17:39:20 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)) 17:39:20 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)) 17:39:20 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)) 17:39:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:39:20 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 17:39:20 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 17:39:20 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 17:39:20 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 17:39:20 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 17:39:20 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 17:39:23 INFO - Downloading blessings-1.6.tar.gz 17:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:39:23 INFO - Installing collected packages: blessings 17:39:23 INFO - Running setup.py install for blessings 17:39:23 INFO - Successfully installed blessings 17:39:23 INFO - Cleaning up... 17:39:23 INFO - Return code: 0 17:39:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:39:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:39:24 INFO - Reading from file tmpfile_stdout 17:39:24 INFO - Current package versions: 17:39:24 INFO - blessings == 1.6 17:39:24 INFO - blobuploader == 1.2.4 17:39:24 INFO - docopt == 0.6.1 17:39:24 INFO - functools32 == 3.2.3-2 17:39:24 INFO - jsonschema == 2.5.1 17:39:24 INFO - manifestparser == 1.1 17:39:24 INFO - mozInstall == 1.12 17:39:24 INFO - mozcrash == 1.0 17:39:24 INFO - mozdebug == 0.1 17:39:24 INFO - mozdevice == 0.49 17:39:24 INFO - mozfile == 1.2 17:39:24 INFO - mozhttpd == 0.7 17:39:24 INFO - mozinfo == 0.9 17:39:24 INFO - mozleak == 0.1 17:39:24 INFO - mozlog == 3.4 17:39:24 INFO - moznetwork == 0.27 17:39:24 INFO - mozprocess == 0.24 17:39:24 INFO - mozprofile == 0.28 17:39:24 INFO - mozrunner == 6.13 17:39:24 INFO - mozscreenshot == 0.1 17:39:24 INFO - mozsystemmonitor == 0.3 17:39:24 INFO - moztest == 0.8 17:39:24 INFO - mozversion == 1.4 17:39:24 INFO - psutil == 3.1.1 17:39:24 INFO - requests == 1.2.3 17:39:24 INFO - wsgiref == 0.1.2 17:39:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:24 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39:24 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 17:39:24 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 17:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:24 INFO - 'HOME': '/Users/cltbld', 17:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:24 INFO - 'LOGNAME': 'cltbld', 17:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:24 INFO - 'MOZ_NO_REMOTE': '1', 17:39:24 INFO - 'NO_EM_RESTART': '1', 17:39:24 INFO - 'PAGER': '/bin/cat', 17:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:24 INFO - 'PWD': '/builds/slave/test', 17:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:24 INFO - 'SHELL': '/bin/bash', 17:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:24 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:24 INFO - 'USER': 'cltbld', 17:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:24 INFO - 'XPC_FLAGS': '0x0', 17:39:24 INFO - 'XPC_SERVICE_NAME': '0', 17:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 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)) 17:39:24 INFO - Cleaning up... 17:39:24 INFO - Return code: 0 17:39:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:39:24 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 0x100eaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ead768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa17959ea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, '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.1kyASFRdsH/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.45uJHSFAiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 17:39:24 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 17:39:24 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 17:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:24 INFO - 'HOME': '/Users/cltbld', 17:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:24 INFO - 'LOGNAME': 'cltbld', 17:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:24 INFO - 'MOZ_NO_REMOTE': '1', 17:39:24 INFO - 'NO_EM_RESTART': '1', 17:39:24 INFO - 'PAGER': '/bin/cat', 17:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:24 INFO - 'PWD': '/builds/slave/test', 17:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:24 INFO - 'SHELL': '/bin/bash', 17:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:24 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:24 INFO - 'USER': 'cltbld', 17:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:24 INFO - 'XPC_FLAGS': '0x0', 17:39:24 INFO - 'XPC_SERVICE_NAME': '0', 17:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:39:24 INFO - Cleaning up... 17:39:24 INFO - Return code: 0 17:39:24 INFO - Running post-action listener: _resource_record_post_action 17:39:24 INFO - Running post-action listener: _start_resource_monitoring 17:39:24 INFO - Starting resource monitoring. 17:39:24 INFO - [mozharness: 2017-02-05 01:39:24.770158Z] Finished create-virtualenv step (success) 17:39:24 INFO - [mozharness: 2017-02-05 01:39:24.771125Z] Running install step. 17:39:24 INFO - Running pre-action listener: _resource_record_pre_action 17:39:24 INFO - Running main action method: install 17:39:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:39:25 INFO - Reading from file tmpfile_stdout 17:39:25 INFO - Detecting whether we're running mozinstall >=1.0... 17:39:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:39:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:39:25 INFO - Reading from file tmpfile_stdout 17:39:25 INFO - Output received: 17:39:25 INFO - Usage: mozinstall [options] installer 17:39:25 INFO - Options: 17:39:25 INFO - -h, --help show this help message and exit 17:39:25 INFO - -d DEST, --destination=DEST 17:39:25 INFO - Directory to install application into. [default: 17:39:25 INFO - "/builds/slave/test"] 17:39:25 INFO - --app=APP Application being installed. [default: firefox] 17:39:25 INFO - mkdir: /builds/slave/test/build/application 17:39:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:39:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:39:47 INFO - Reading from file tmpfile_stdout 17:39:47 INFO - Output received: 17:39:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:39:47 INFO - Running post-action listener: _resource_record_post_action 17:39:47 INFO - [mozharness: 2017-02-05 01:39:47.192787Z] Finished install step (success) 17:39:47 INFO - [mozharness: 2017-02-05 01:39:47.192927Z] Running run-tests step. 17:39:47 INFO - Running pre-action listener: _resource_record_pre_action 17:39:47 INFO - Running main action method: run_tests 17:39:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:39:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:39:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:39:47 INFO - 2017-02-04 17:39:47.207 screenresolution[1906:16444] starting screenresolution argv=screenresolution get 17:39:47 INFO - 2017-02-04 17:39:47.210 screenresolution[1906:16444] Display 0: 1600x1200x32@60 17:39:47 INFO - 2017-02-04 17:39:47.215 screenresolution[1907:16446] starting screenresolution argv=screenresolution list 17:39:47 INFO - Available Modes on Display 0 17:39:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:39:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:39:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:39:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:39:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:39:47 INFO - Intel Iris: 17:39:47 INFO - Chipset Model: Intel Iris 17:39:47 INFO - Type: GPU 17:39:47 INFO - Bus: Built-In 17:39:47 INFO - VRAM (Dynamic, Max): 1536 MB 17:39:47 INFO - Vendor: Intel (0x8086) 17:39:47 INFO - Device ID: 0x0a2e 17:39:47 INFO - Revision ID: 0x0009 17:39:47 INFO - Displays: 17:39:47 INFO - Display: 17:39:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:39:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:39:47 INFO - Display Serial Number: 1600x1200 60 17:39:47 INFO - Main Display: Yes 17:39:47 INFO - Mirror: Off 17:39:47 INFO - Online: Yes 17:39:47 INFO - Rotation: Supported 17:39:47 INFO - Return code: 0 17:39:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:39:47 INFO - Python 2.7.3 17:39:47 INFO - Return code: 0 17:39:47 INFO - grabbing minidump binary from tooltool 17:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:47 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 0x100c88d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:39:47 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 17:39:47 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 17:39:47 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 17:39:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:39:47 INFO - Return code: 0 17:39:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:39:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:39:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:39:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:39:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:39:47 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/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 17:39:47 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/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --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 17:39:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kyASFRdsH/Render', 17:39:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:47 INFO - 'HOME': '/Users/cltbld', 17:39:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:47 INFO - 'LOGNAME': 'cltbld', 17:39:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:39:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:47 INFO - 'MOZ_NO_REMOTE': '1', 17:39:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:39:47 INFO - 'NO_EM_RESTART': '1', 17:39:47 INFO - 'PAGER': '/bin/cat', 17:39:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:47 INFO - 'PWD': '/builds/slave/test', 17:39:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:39:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:47 INFO - 'SHELL': '/bin/bash', 17:39:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.45uJHSFAiF/Listeners', 17:39:47 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 17:39:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:47 INFO - 'USER': 'cltbld', 17:39:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:47 INFO - 'XPC_FLAGS': '0x0', 17:39:47 INFO - 'XPC_SERVICE_NAME': '0', 17:39:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:47 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/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 17:39:47 INFO - mozversion application_buildid: 20170204163708 17:39:47 INFO - mozversion application_changeset: e581572bc9c128df28d3f97698d83f9b3c459da5 17:39:47 INFO - mozversion application_display_name: Nightly 17:39:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:39:47 INFO - mozversion application_name: Firefox 17:39:47 INFO - mozversion application_remotingname: firefox 17:39:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:39:47 INFO - mozversion application_vendor: Mozilla 17:39:47 INFO - mozversion application_version: 54.0a1 17:39:47 INFO - mozversion platform_buildid: 20170204163708 17:39:47 INFO - mozversion platform_changeset: e581572bc9c128df28d3f97698d83f9b3c459da5 17:39:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:39:47 INFO - mozversion platform_version: 54.0a1 17:39:47 INFO - using testdate: 1486258787 17:39:47 INFO - actual date: 1486258787 17:39:47 INFO - starting webserver on 'localhost:49702' 17:39:47 INFO - SUITE-START | Running 2 tests 17:39:47 INFO - TEST-START | dromaeo_css 17:39:47 INFO - operating with platform_type : mac_ 17:39:47 INFO - Initialising browser for dromaeo_css test... 17:39:47 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpx7lnux/profile http://localhost:49702/getInfo.html) 17:39:49 INFO - PROCESS | 1917 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:39:50 INFO - PROCESS | 1917 | __metrics Screen width/height:1600/1200 17:39:50 INFO - PROCESS | 1917 | colorDepth:24 17:39:50 INFO - PROCESS | 1917 | Browser inner width/height: 1024/689 17:39:50 INFO - PROCESS | 1917 | __metrics 17:39:50 INFO - PROCESS | 1917 | 1486258790262 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:39:50 INFO - PROCESS | 1917 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:39:50 INFO - PROCESS | 1917 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:39:51 INFO - TEST-INFO | 1917: exit 0 17:39:51 INFO - Browser initialized. 17:39:51 INFO - Running cycle 1/1 for dromaeo_css test... 17:39:51 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpx7lnux/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:03 INFO - PROCESS | 1919 | RSS: Main: 236486656 17:40:03 INFO - PROCESS | 1919 | 17:43:06 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49702/tests/dromaeo/cssquery-ext.html) 17:43:08 INFO - PROCESS | 1919 | RSS: Main: 409333760 17:43:08 INFO - PROCESS | 1919 | 17:44:52 INFO - PROCESS | 1919 | ************************* 17:44:52 INFO - PROCESS | 1919 | A coding exception was thrown and uncaught in a Task. 17:44:52 INFO - PROCESS | 1919 | 17:44:52 ERROR - PROCESS | 1919 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:44:52 INFO - PROCESS | 1919 | Full stack: 17:44:52 INFO - PROCESS | 1919 | ************************* 17:46:11 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-ext.html (next: http://localhost:49702/tests/dromaeo/cssquery-jquery.html) 17:46:12 INFO - PROCESS | 1919 | RSS: Main: 373366784 17:46:12 INFO - PROCESS | 1919 | 17:49:14 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49702/tests/dromaeo/cssquery-mootools.html) 17:49:15 INFO - PROCESS | 1919 | RSS: Main: 355160064 17:49:15 INFO - PROCESS | 1919 | 17:52:17 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49702/tests/dromaeo/cssquery-prototype.html) 17:52:18 INFO - PROCESS | 1919 | RSS: Main: 348057600 17:52:18 INFO - PROCESS | 1919 | 17:55:21 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49702/tests/dromaeo/cssquery-yui.html) 17:55:21 INFO - PROCESS | 1919 | RSS: Main: 330395648 17:55:21 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-yui.html (next: http://localhost:49702/tests/dromaeo/cssquery-dojo.html) 17:58:25 INFO - PROCESS | 1919 | RSS: Main: 490741760 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | __start_tp_report 17:58:25 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 17:58:25 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 17:58:25 INFO - PROCESS | 1919 | |i|pagename|runs| 17:58:25 INFO - PROCESS | 1919 | |0;dojo.html;2978.4043191361725;3204.7756657034;3293.654166312537;3317.237035481841;3358.378699940511;5471.206675032128;5575.526080283181;5606.691631960241;5673.914891276634;5700.315962084551;5582.91625625615;5599.944000559994;5647.43525647435;5656.91514627281;5681.091025435931;4390.626796722267;5011.0228505441455;5026.120428924933;5095.796168153288;5248.921266181011;5190.584753219733;5196.428392876781;5240.973795131019;5248.921266181011;5250.18622113573;5016.47327030662;5086.364204474441;5091.567216786559;5096.159133742928;5176;5164.122099243138;5181.481851814826;5196.142636465017;5224.50367215114;5242;5070.6957582545165;5128.538431541142;5154.974225128888;5181.274621552986;5203.219517072469;5415.0523658359625;5445.53712934401;5551.417101204356;5586.86032849178;5598.328200615912;6084.26988761347;6149.938500614981;6207.068939659042;6209.099680546341;6257.374262573706;6252.781152659635;6312.747490100391;6325.14610527582;6334.904976425358;6346.11154438379;5792.913106303409;5832.912506312408;5836.6789826559525;5844.561657875676;5852.531797456193;6159.414855588712;6252.562320637603;6253;6264.310925798159;6297.6536290503545;6030.517558595302;6071.939280607137;6103.428585615297;6128.601640893327;6169.784057557964;6281.7173227204885;6288.434040936338;6288.717007734663;6294.181756371642;6301.905471417932;6146.6004709693725;6238.220222472191;6240;6241.812745617639;6244.313125556185;5084.389873215164;6017.308009578867;6085.208922840091;6093.99449090895;6123.018364072999;4966.006798640286;4977.104121258209;4981.726005069756;4984.053029924303;5005.474425185376;4425.756583387944;4454.4877339106415;4457.398251236106;4460.442444694413;4491.842785502492;5486.286782718221;5533.170024496358;5541.030319694038;5665.575081868877;5678.346990096109;6169.56813023084;6215.409536094064;6238.532110091671;6302.936970630327;6308.747650093945;5691.544676425876;5703.885922281531;5711.942880571224;5718.342390625131;5731.369549349568;4645.628349732014;5445;5446.2647542582035;5448.101063324507;5458.263134476795;6079.787207447718;6206.565540412128;6247.094171345038;6248.4688801452385;6299.464545513682;6083.756649733961;6240.251169859646;6284.5600807943;6288.842778930563;6292.874142517216;6220.346863579259;6230.470410015199;6267.843303917439;6289.119523266655;6297.4017468340435;6162.630242185483;6192.102145189013;6207.565470417027;6211.9068213977735;6215.34738852414;5965.373635768182;6099.268087829311;6117.431594453999;6301.936980630135;6311.2426508819235;5983.431574000463;6044.153818465506;6055.72749226277;6082.2701275847185;6131.132004888217;6123.938760612336;6127.938720612737;6131.601445906002;6141.570090093829;6152.78465253714;5991.191189189403;5994.100884867305;5994.970025149846;5996.760129594767;5997.520198384032;5870.5010074144175;5884.029135192816;5933.584649074523;5948.078047902582;6006.669633170218;5874.911876321944;5964.612300200473;5967.194428752063;5972.940270597413;5977.671228082497 17:58:25 INFO - PROCESS | 1919 | |1;ext.html;430.3265389665172;444.75538453850385;473.0043258939933;513.5839969624602;524.7874610782633;28874;39423.80288098559;40938.97652558684;41004.89951004898;41423.378649320286;41993;42450;42471;42532.149357012844;42576.78711606446;7941.205879412056;9374.343795934254;9402.294827887901;9413.199878010377;9434;7973.362131029504;8141.755747327589;11053.613123540677;11285.943570282177;11293.887061129406;11273.774524509505;11312.490937907773;11313.943430282838;11317.811629778837;11319.981201691846;11152.721181970435;11226.158038147172;11322.547098116065;11333.716657083556;11339.092872570258;7878.80302992424;8088.312493438065;10890.128789696884;11130.443477826157;11160.218784685076;39762.403563946566;40016;40040.59959400427;40106;40121.39817902734;3949.486566746333;3976.602339766029;3993.560708322111;3998.640122388999;4005.9799701001307;29726.959556415637;30053.39893202124;30206.395872082656;30764.53853192204;30833.537496937563;3095.824075088927;3110.12916383413;3129.671384504617;3217.6621454747383;3235.223546348859;331593;334102;335045;335111;335583;50443;59857;60110.39889601136;60181.39818601758;60204.39795602075;50961.23558146631;53261.46738532565;63045;64363.67818160879;64440;15192.696146077016;15200.771988420183;15413.922930385277;15415.460458883885;15433.691326173413;11683.941580292045;11824.349660768741;11830.231034982871;11833.230839995545;11837.526498940118;16594.83405165957;16614.335426583042;16652.418020288053;16653.666926661397;16666.749998750027;7567.394608431422;7641.312281894656;7671.895535888385;7690.155202222676;7722.189170137167;857.7426771968385;863.2648376762627;875.1205038935828;883.3816328570026;899.0515006667897;28869.278268043046;28935;28980.650309196342;29150;29462.558061629094;1036.6734478639205;1048.0776916313596;1051.2483574244452;1052.179300145872;1200.8979236764799;14307.92846035784;14310.856891430953;14581.708365832625;14956.700865982837;15089.094654320776;474019;476244;476256;476665;478608;49107;56017;56071;56142.43857561372;56202;1367.931603419825;1412.6256542016374;1419.134328059862;1424.472945010332;1424.886009119268;27258.72741272562;28442.435666037738;28488.453463960315;28842;29154.708452915198;48467;48827.02345953132;48903.0219395603;48948.265776012675;49010.75494622504;408100;411836;412850.4579450485;414414;414507;36806.26387472289;37614;37665;37792.62207377891;37817.81091094527;34940.82529587336;42692.57307426886;42922.57077429186;43642.56357436385;43908.560914390444;28657.283567910134;35740.28519429545;35909.1022724434;36652.81673591615;36726;35192.824035879654;35347.46978795269;36167.27665446624;36431.635683643886;36475.27049458943;29338.559921600765;35195.29609407849;35241.82379088191;35936.640633594376;36109.63890361063;29358.706412935597;31465.68534314719;31590.684093159693;33072.66927330696;33253.667463326026;21692;23209.95555199986;23564.410889727893;23606.371159393766;23861 17:58:25 INFO - PROCESS | 1919 | |2;jquery.html;364.2987249544629;388.2874924513518;391.4070183671736;400.79960019990017;422.84988828965714;14467.914906382024;14810.77783833242;17787.11064446779;20610.89694551525;21051.368458946217;19597;20247.291344802936;21098;21682.457938551506;21684.02421891015;5266;5355.357357117142;5427.864303392427;5530.889382212354;5554.527865131468;3760.830762615689;5425.701586412746;5467.289252397202;5468.0977638689565;5492.505674489295;3932.4297976793346;5793.449622285897;6114.419130182626;6148.292946311164;6179.66011869347;5623.437656234385;5889.204957330749;6166.6916654167335;6176.629402235858;6185.474234690057;5839.9124013139835;5848.41515848416;5849.795257166022;5850.210221620112;5857.297124345063;13550.661233469144;13767.31163441824;23454.70999095049;24536.386590335205;25377.432062228792;2893;2983.330503480731;2986.1340211338684;3003;3009.5034319337283;10043.74890627733;10148.89851101487;10189.133923616502;10562.683119506428;10591.470426478645;1777.173614269358;1856.647237024961;1862.888226706402;1864.8414884734814;1880.4170707080712;132753;142595.28702356527;143756;145076.54923450737;147389;10859.40273284961;11806.98562488758;11819;11916;12016.038716902627;15991;15996;16009.599760006093;16013.279402426919;16014.679706405806;7032.80442324806;7428.851422971618;7982.9600851995365;8158.755237342889;8616.870746938801;8082.1513741057515;8093.878591821127;8117.512949223065;8270.090290068089;8322.70870519541;4084.1002887949294;4813.446453657874;4819.638527110411;4833.284184114659;4837.250226214942;2003.6393449178995;2044.2947183221765;2048.354768247997;2054.281001649411;2057.4856285928518;978.1217472720697;1023.3092662452815;1042.4735508568124;1043.2146169726848;1062.2245760594808;17189.262575744033;20999.160033598484;21221.469463263235;21493.78506214949;21559.245426410314;889.621910687955;1013.159077965287;1050.222835102019;1051.5497698804058;1053.0785562632695;10768.353898765943;11085.667429977113;11363.37501437429;11416;11443.828342574869;544442;691961;701842;708953;709609;147646.26176869046;150372;154473.99999999776;158035;160171;975.3124047546463;995.1889210293588;997.5560875410373;1011.7672935224939;1024.2983556263835;18912.62174756525;19118.617627647647;19160.90419547893;19190.328338508087;19213.903930480257;55791.884162317336;56899.71550142223;57068;57117.42882571121;57971;75335;79335.80996285175;79692.60153699426;79752.20247797448;80662.80837075463;7497.100347958061;8284.792880177803;8329.625166867627;8384.706535271236;8445.704400345958;10950.835737464104;11112.722181945515;13140.34298285101;13353.399097040463;13435.664108396977;10806.135509159405;10918.290311129751;11061.889381106086;11095.72260693489;11317.434128293387;11138.777224455303;11407.258528195973;11452.911973362518;11718.296902185895;11762.176647634584;11294.661160165208;11519.942400287944;11735.76528469443;11874.82187767167;11892.405379731152;8066.959665201636;8218.794530136554;8281.834363312579;8365.958170209353;8622.353323500636;10947.616833410792;11195.776084478102;11263;11354.54581816718;11357.580302462193 17:58:25 INFO - PROCESS | 1919 | |3;mootools.html;1434.569629111267;1437.631293370369;1441.9495317663884;1458.0741229319385;1462.4369617697184;8242.876356854644;8682.522461264627;8690.391672582922;8698.999615044271;8716.346274029442;8310.335173186146;8444.28223600993;8553.871691924625;8561.657533698644;8680.305575553957;2271.943201419961;2308.746037935826;2312.1329501436962;2312.4681323295654;2313.6992191015142;2279.908803647852;2300.0454811253308;2303.1133013789727;2310.9422264443438;2316.93049208524;2091.8326533877253;2131.914723411062;2263.6830843681896;2282.2696737044153;2285.6342985122383;2222.022310183514;2234.0616940884865;2235.854669446481;2262.072550254388;2263.5699217148854;2223.722034745657;2299.1722979727283;2299.9425014374606;2302.3438320078862;2306.9077236910503;8591.82816343676;8607.956960215159;8609.48343099416;8624.61189246479;8652.134786521297;5050.494950504957;5151.742412879341;5193.818216362409;5194.3247377840635;5221.869453263661;2496.463260399005;3204.439223135942;3377.6115746689197;3409.948850767216;3474.895753127385;1205.2105870654734;1208.6736581123134;1213.4782043721195;1261.1487246108932;1264.3235868810143;10991.010809027226;11028.22802403832;11091.112710983183;11101;11144.219904606762;4221.894452638709;4409.9559004410185;4487.147441986012;4488.890221595845;4498.437695288089;8887.244584210286;8894.332925030649;8895.822083558292;8919.643214271357;8923.241524470493;5139.331886854685;5143.974280128576;5146.125158723026;5147.1507201312115;5167.560757335668;4645.581897629229;4743.075100355398;4747.730094386199;4767.4517430495935;4824.927626085611;3597;3634.636536346397;3646.033801042726;3688.7417880748626;3709.4621279914263;1080.9352787504301;1140.509580880213;1143.74837535742;1156.7570810129798;1161.349644199251;824.7773101262745;868.1275318305189;897.1656359585518;937.1706040154512;955.2167222877174;5431.619786614899;5628.240187574707;5674.829755107354;5720.570957178228;5733.455321744427;1002.1331548210745;1006.1548299428515;1006.8439391894268;1010.7271036820017;1050.1179009631946;3698.239499376192;3808.8857334280015;3849.40334248192;3857.864974725871;3862.304785138702;9806.75483112928;10133.240006999504;10155.796884062425;10312.123469505026;10319.122874555596;9127.726168214964;9214.216791572657;10037.598496060222;10045.045720656526;10073.093421591946;7744.1094274159195;8017.875263055295;8072.878906816402;8081.030276366873;8104.8379032419025;4775.570198682135;5604;5699.145128230799;5713.200151978644;5816.563757718103;9094.363394562582;9313.115254050854;9514.286428517751;9517.666881659108;9522.14300712939;8041.316488098577;9082.18260356571;9470.242380609672;9586.616535338788;9605.03949605034;5772.942270577409;6595.472362210916;6794.082798822095;7170.816815225638;7171.211166771546;7910.921214475357;8021.919780802117;8067.596620168851;8067.991501062367;8101.959490202511;7203.099612548432;7231.674574644258;7235.63821808897;7326.6702998364135;7406.888896666662;6964;7018.876979683226;7125.857482850418;7146.3568278855155;7186.281371862772;6295;6322.114903913363;6322.209723784527;6431.453326467303;6441.291457939717;4195.49654041517;4934.284528743384;4979.153543897619;4998.501348785976;5149.536541711264;4972.925406118983;4987.850364489072;5012.3233363495465;5084.898302033865;5169.586433085419 17:58:25 INFO - PROCESS | 1919 | |4;prototype.html;310.30336893673706;322.4759765381254;350.4730890281073;358.9335972845024;371.4168755054565;4474.597286244238;4849.811796109953;5315.415304316527;5347.171188465784;5360.195970604412;4612.8385506507275;4778.952210477894;4953.4798846121175;5054.696718196911;5286.682799032051;3469.5142680024815;3797.373433383488;4007.8518733111237;4027.597240275978;4053.148838743867;3375.5400714267826;3759.8496060157563;3790.4124860646507;3868.458415821787;4029.899252518696;3158.305172861978;3670.963290367102;3799.537516944413;3957.4063890416523;4036.475258216428;3263.396271689745;3991;3992.301347264246;3994.3409337459284;4029.5567487576345;3456.2569047654874;3457.827108644583;3465.6014558325865;3472.3055388922317;3485.7559970802054;5075.898482030376;5140.971805638887;5147.510986456281;5157.690538567698;5181.549166233461;3534.68187863091;3849.5188101487315;3925.646691797723;4566.862994110182;4569.794359253841;5485.6982865942355;5530.419305972855;5541.722913854291;5550.4449555044575;5559.360673522557;3256.6580509046685;3324.9194011946215;3530.8058056806867;3806.876971293475;3812.55123053238;4895.241237608177;5262.184361423947;5420.130055130973;5531.419200983919;5815.389384114692;3696.7966761828357;3849.1139341722915;3851.5378154621626;3864.942025869614;3866.033491627093;3686.838645826556;3738.289724952305;3900.3564411871725;3933.783641899723;3973.1259122992897;3834.9233015339537;4373.300271956472;4553.248713962159;4563.657725670588;4563.680542362069;4914.066327397783;5551.916721249185;5590.552755779528;5593.776248949996;5608.887822243532;1071.6356438810685;1253.364359510829;1271.3452571925466;1271.9236845789283;1274.5852104164371;748.5246868238708;797.8005498625344;861.1818772166467;871.4640496094983;876.6931573949067;3970.1067259866877;4273.743575385487;4480;4837.911469919311;4894.902101958012;5042.319286896295;5199.48005199477;5412.91880621791;5415.50886592172;5431.9309590081475;4405.339199120158;4492.3710680504755;4897.314375987365;4899.828506002273;4942.555170034642;2555.6805399325085;2559.833610815291;2705.715899830529;2828.60399544064;2832.6175966244705;4926.9014619707405;5046.697198168122;5184.688918664892;5565.554755619541;5619.241402410704;4383.802728877208;4824.607395201372;4974.27872958419;5173.689578625294;5355.919661205085;4955.778743816476;5325.387580428301;5815.924054050014;5833.7083145842535;5850.882982340329;4705.482396936266;4989.9251511228085;5382.811601593926;5650.152477128464;5663.235463212413;4699.882502937454;5302.337207849019;5428.150221133778;5604;5687.374388817309;4875.7074575525585;5871.530277577869;5888.906096226648;5922.851928701817;5922.970385148046;3674.779513229215;3799.8670046548236;3824.6175382461533;3826.846926122924;3845.288621605012;4684.859454216379;5706.857328566819;5724.656520608777;5735.4551317624755;5753.482186603226;4730.314104454766;4881.463039065629;5054.671446355975;5529.861753456195;5569.916451253315;4987.9251811223585;5509.944900551103;5538.446155384429;5538.9169162461785;5545.390007099297;5369.731513424391;5437.564994800486;5450.727463626882;5453.481919217668;5459.481349271971;4701.976490117664;5530.543410368519;5551.528120109836;5551.583631227593;5563.7496312666835;5071.92392114126;5080.517350851663;5430.615193125727;5432.864178395572;5444.482774136451 17:58:25 INFO - PROCESS | 1919 | |5;yui.html;676.5129107042934;715.5992644119293;745.1654147354964;769.0963118335958;869.1916517638596;14266.643333916645;14320.212388318665;14808.925955370236;14877.4792882249;14983;13344.466221351135;13487.865121348783;13797.931010344933;13807.792883106762;13822.861771382282;856.742977106867;870.2646263906988;881.7619242804438;883.3816328569994;883.8011447424342;790.6481615681024;851.4210336970857;881.1981097201548;881.5680316644862;885.1325700813175;914.3188324698109;915.4370062411602;916.8945571259324;917.2570218123308;917.7843206846386;904.5341649050744;905.2124651553191;906.5512571277227;907.3512438606429;909.1908201700492;677.701811203069;678.2708588267583;678.4165617568887;678.443676185528;678.9287124851869;14601;14605.415783368653;14637.268136593128;14639.560813175622;15180.696386072326;11583.826242606368;12551.05867059965;13975.720485590333;14122.22327771972;14151.221682807442;11997.400129993553;12645;12687.238765674088;12719.364031798374;13408.731825363535;4469;4486.798094085774;4490.618297444721;4495.865124046284;4676.438827340707;89380.55309723475;91816;93531.06468935292;94776;96795.51602242014;10600.720845018359;10759.731006724895;10788.946055269673;10853.837192442119;10916.945415272872;20196.29312974039;20230;20473;20582.58834823325;20645.58708825815;7368.410527157815;7369.336759691653;7430.6284685765495;7541.962290188624;7618.923810761821;3899.1811719539214;4025.5974402560096;4083.1833633273463;4149.190907773016;4216.3464662977285;3440.6043305020244;3523.71810255179;3529.400001999666;3533.8056406897354;3535.840887160084;538.4373329870295;665.1353240786957;671.6306031682556;672.6300534705892;673.2762280548333;522.4148953172471;536.3661195401188;538.2249560632677;543.4076856226653;557.5288880895637;9420.24638028956;9808.761562819573;9995.200383969264;10117.544710488046;10131.898681013095;601.7653115285042;626.8746250749869;631.2614241337646;647.8088963755704;674.3762020131359;4647.697899636512;4655.877486323095;4657.627389808875;4659;4733.721895088307;82561.93595160049;86518.56740716255;90377;92781.53609232046;99015;72955.27044729485;76805.61597191979;78326.43347133139;79314.60342698249;80535.19464805395;70384.64807675929;70430.64784676248;70536;70619.64690176619;71093;10570.365778053341;10673.57305707763;10744.19418543628;10943.398113103856;10954.178436617127;40587.57943471964;45255.77372113118;49069.77325566747;49169;49435.3149387825;62208.68895655674;65035.67482162559;65510.672446637465;66650;67328;12662.620121396372;12784.424700888669;12865.421056052688;12900.677483062998;12936.611901642966;13392.665183370495;13414.597562073153;13856.4457421702;13872.028957973249;13933.4426622938;13194;13229.80155297652;13318.866811331764;13385.397657105259;13783.931080344533;12853.228806271654;13030.339297468689;13051.834826028178;13167.078304518593;13174.73650526965;12902.903951589482;13002.154859934075;13285.202887826761;13410.865891340962;13695.315234238447;10121.86394179179;10409.323393979366;10749.785004299714;10972.506237219184;11133;10216.948915255376;10420.11429028541;10644.89355106439;10645.946770266099;10663.680089597325 17:58:25 INFO - PROCESS | 1919 | __end_tp_report 17:58:25 INFO - PROCESS | 1919 | __start_cc_report 17:58:25 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,1903 17:58:25 INFO - PROCESS | 1919 | __end_cc_report 17:58:25 INFO - PROCESS | 1919 | __startTimestamp1486259905408__endTimestamp 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | ------- Summary: start ------- 17:58:25 INFO - PROCESS | 1919 | Number of tests: 6 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | [#0] dojo.html Cycles:165 Average:5699.72 Median:5965.37 stddev:663.09 (11.1%) stddev-sans-first:629.82 17:58:25 INFO - PROCESS | 1919 | Values: 2978.4 3204.8 3293.7 3317.2 3358.4 5471.2 5575.5 5606.7 5673.9 5700.3 5582.9 5599.9 5647.4 5656.9 5681.1 4390.6 5011.0 5026.1 5095.8 5248.9 5190.6 5196.4 5241.0 5248.9 5250.2 5016.5 5086.4 5091.6 5096.2 5176.0 5164.1 5181.5 5196.1 5224.5 5242.0 5070.7 5128.5 5155.0 5181.3 5203.2 5415.1 5445.5 5551.4 5586.9 5598.3 6084.3 6149.9 6207.1 6209.1 6257.4 6252.8 6312.7 6325.1 6334.9 6346.1 5792.9 5832.9 5836.7 5844.6 5852.5 6159.4 6252.6 6253.0 6264.3 6297.7 6030.5 6071.9 6103.4 6128.6 6169.8 6281.7 6288.4 6288.7 6294.2 6301.9 6146.6 6238.2 6240.0 6241.8 6244.3 5084.4 6017.3 6085.2 6094.0 6123.0 4966.0 4977.1 4981.7 4984.1 5005.5 4425.8 4454.5 4457.4 4460.4 4491.8 5486.3 5533.2 5541.0 5665.6 5678.3 6169.6 6215.4 6238.5 6302.9 6308.7 5691.5 5703.9 5711.9 5718.3 5731.4 4645.6 5445.0 5446.3 5448.1 5458.3 6079.8 6206.6 6247.1 6248.5 6299.5 6083.8 6240.3 6284.6 6288.8 6292.9 6220.3 6230.5 6267.8 6289.1 6297.4 6162.6 6192.1 6207.6 6211.9 6215.3 5965.4 6099.3 6117.4 6301.9 6311.2 5983.4 6044.2 6055.7 6082.3 6131.1 6123.9 6127.9 6131.6 6141.6 6152.8 5991.2 5994.1 5995.0 5996.8 5997.5 5870.5 5884.0 5933.6 5948.1 6006.7 5874.9 5964.6 5967.2 5972.9 5977.7 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | [#1] ext.html Cycles:180 Average:56114.87 Median:35922.87 stddev:108958.73 (303.3%) stddev-sans-first:109184.18 17:58:25 INFO - PROCESS | 1919 | Values: 430.3 444.8 473.0 513.6 524.8 28874.0 39423.8 40939.0 41004.9 41423.4 41993.0 42450.0 42471.0 42532.1 42576.8 7941.2 9374.3 9402.3 9413.2 9434.0 7973.4 8141.8 11053.6 11285.9 11293.9 11273.8 11312.5 11313.9 11317.8 11320.0 11152.7 11226.2 11322.5 11333.7 11339.1 7878.8 8088.3 10890.1 11130.4 11160.2 39762.4 40016.0 40040.6 40106.0 40121.4 3949.5 3976.6 3993.6 3998.6 4006.0 29727.0 30053.4 30206.4 30764.5 30833.5 3095.8 3110.1 3129.7 3217.7 3235.2 331593.0 334102.0 335045.0 335111.0 335583.0 50443.0 59857.0 60110.4 60181.4 60204.4 50961.2 53261.5 63045.0 64363.7 64440.0 15192.7 15200.8 15413.9 15415.5 15433.7 11683.9 11824.3 11830.2 11833.2 11837.5 16594.8 16614.3 16652.4 16653.7 16666.7 7567.4 7641.3 7671.9 7690.2 7722.2 857.7 863.3 875.1 883.4 899.1 28869.3 28935.0 28980.7 29150.0 29462.6 1036.7 1048.1 1051.2 1052.2 1200.9 14307.9 14310.9 14581.7 14956.7 15089.1 474019.0 476244.0 476256.0 476665.0 478608.0 49107.0 56017.0 56071.0 56142.4 56202.0 1367.9 1412.6 1419.1 1424.5 1424.9 27258.7 28442.4 28488.5 28842.0 29154.7 48467.0 48827.0 48903.0 48948.3 49010.8 408100.0 411836.0 412850.5 414414.0 414507.0 36806.3 37614.0 37665.0 37792.6 37817.8 34940.8 42692.6 42922.6 43642.6 43908.6 28657.3 35740.3 35909.1 36652.8 36726.0 35192.8 35347.5 36167.3 36431.6 36475.3 29338.6 35195.3 35241.8 35936.6 36109.6 29358.7 31465.7 31590.7 33072.7 33253.7 21692.0 23210.0 23564.4 23606.4 23861.0 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | [#2] jquery.html Cycles:180 Average:38463.04 Median:21074.68 stddev:113208.71 (537.2%) stddev-sans-first:113490.14 17:58:25 INFO - PROCESS | 1919 | Values: 364.3 388.3 391.4 400.8 422.8 14467.9 14810.8 17787.1 20610.9 21051.4 19597.0 20247.3 21098.0 21682.5 21684.0 5266.0 5355.4 5427.9 5530.9 5554.5 3760.8 5425.7 5467.3 5468.1 5492.5 3932.4 5793.4 6114.4 6148.3 6179.7 5623.4 5889.2 6166.7 6176.6 6185.5 5839.9 5848.4 5849.8 5850.2 5857.3 13550.7 13767.3 23454.7 24536.4 25377.4 2893.0 2983.3 2986.1 3003.0 3009.5 10043.7 10148.9 10189.1 10562.7 10591.5 1777.2 1856.6 1862.9 1864.8 1880.4 132753.0 142595.3 143756.0 145076.5 147389.0 10859.4 11807.0 11819.0 11916.0 12016.0 15991.0 15996.0 16009.6 16013.3 16014.7 7032.8 7428.9 7983.0 8158.8 8616.9 8082.2 8093.9 8117.5 8270.1 8322.7 4084.1 4813.4 4819.6 4833.3 4837.3 2003.6 2044.3 2048.4 2054.3 2057.5 978.1 1023.3 1042.5 1043.2 1062.2 17189.3 20999.2 21221.5 21493.8 21559.2 889.6 1013.2 1050.2 1051.5 1053.1 10768.4 11085.7 11363.4 11416.0 11443.8 544442.0 691961.0 701842.0 708953.0 709609.0 147646.3 150372.0 154474.0 158035.0 160171.0 975.3 995.2 997.6 1011.8 1024.3 18912.6 19118.6 19160.9 19190.3 19213.9 55791.9 56899.7 57068.0 57117.4 57971.0 75335.0 79335.8 79692.6 79752.2 80662.8 7497.1 8284.8 8329.6 8384.7 8445.7 10950.8 11112.7 13140.3 13353.4 13435.7 10806.1 10918.3 11061.9 11095.7 11317.4 11138.8 11407.3 11452.9 11718.3 11762.2 11294.7 11519.9 11735.8 11874.8 11892.4 8067.0 8218.8 8281.8 8366.0 8622.4 10947.6 11195.8 11263.0 11354.5 11357.6 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | [#3] mootools.html Cycles:180 Average:5440.12 Median:5067.70 stddev:2999.06 (59.2%) stddev-sans-first:2992.37 17:58:25 INFO - PROCESS | 1919 | Values: 1434.6 1437.6 1441.9 1458.1 1462.4 8242.9 8682.5 8690.4 8699.0 8716.3 8310.3 8444.3 8553.9 8561.7 8680.3 2271.9 2308.7 2312.1 2312.5 2313.7 2279.9 2300.0 2303.1 2310.9 2316.9 2091.8 2131.9 2263.7 2282.3 2285.6 2222.0 2234.1 2235.9 2262.1 2263.6 2223.7 2299.2 2299.9 2302.3 2306.9 8591.8 8608.0 8609.5 8624.6 8652.1 5050.5 5151.7 5193.8 5194.3 5221.9 2496.5 3204.4 3377.6 3409.9 3474.9 1205.2 1208.7 1213.5 1261.1 1264.3 10991.0 11028.2 11091.1 11101.0 11144.2 4221.9 4410.0 4487.1 4488.9 4498.4 8887.2 8894.3 8895.8 8919.6 8923.2 5139.3 5144.0 5146.1 5147.2 5167.6 4645.6 4743.1 4747.7 4767.5 4824.9 3597.0 3634.6 3646.0 3688.7 3709.5 1080.9 1140.5 1143.7 1156.8 1161.3 824.8 868.1 897.2 937.2 955.2 5431.6 5628.2 5674.8 5720.6 5733.5 1002.1 1006.2 1006.8 1010.7 1050.1 3698.2 3808.9 3849.4 3857.9 3862.3 9806.8 10133.2 10155.8 10312.1 10319.1 9127.7 9214.2 10037.6 10045.0 10073.1 7744.1 8017.9 8072.9 8081.0 8104.8 4775.6 5604.0 5699.1 5713.2 5816.6 9094.4 9313.1 9514.3 9517.7 9522.1 8041.3 9082.2 9470.2 9586.6 9605.0 5772.9 6595.5 6794.1 7170.8 7171.2 7910.9 8021.9 8067.6 8068.0 8102.0 7203.1 7231.7 7235.6 7326.7 7406.9 6964.0 7018.9 7125.9 7146.4 7186.3 6295.0 6322.1 6322.2 6431.5 6441.3 4195.5 4934.3 4979.2 4998.5 5149.5 4972.9 4987.9 5012.3 5084.9 5169.6 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | [#4] prototype.html Cycles:180 Average:4377.47 Median:4896.28 stddev:1354.50 (27.7%) stddev-sans-first:1323.45 17:58:25 INFO - PROCESS | 1919 | Values: 310.3 322.5 350.5 358.9 371.4 4474.6 4849.8 5315.4 5347.2 5360.2 4612.8 4779.0 4953.5 5054.7 5286.7 3469.5 3797.4 4007.9 4027.6 4053.1 3375.5 3759.8 3790.4 3868.5 4029.9 3158.3 3671.0 3799.5 3957.4 4036.5 3263.4 3991.0 3992.3 3994.3 4029.6 3456.3 3457.8 3465.6 3472.3 3485.8 5075.9 5141.0 5147.5 5157.7 5181.5 3534.7 3849.5 3925.6 4566.9 4569.8 5485.7 5530.4 5541.7 5550.4 5559.4 3256.7 3324.9 3530.8 3806.9 3812.6 4895.2 5262.2 5420.1 5531.4 5815.4 3696.8 3849.1 3851.5 3864.9 3866.0 3686.8 3738.3 3900.4 3933.8 3973.1 3834.9 4373.3 4553.2 4563.7 4563.7 4914.1 5551.9 5590.6 5593.8 5608.9 1071.6 1253.4 1271.3 1271.9 1274.6 748.5 797.8 861.2 871.5 876.7 3970.1 4273.7 4480.0 4837.9 4894.9 5042.3 5199.5 5412.9 5415.5 5431.9 4405.3 4492.4 4897.3 4899.8 4942.6 2555.7 2559.8 2705.7 2828.6 2832.6 4926.9 5046.7 5184.7 5565.6 5619.2 4383.8 4824.6 4974.3 5173.7 5355.9 4955.8 5325.4 5815.9 5833.7 5850.9 4705.5 4989.9 5382.8 5650.2 5663.2 4699.9 5302.3 5428.2 5604.0 5687.4 4875.7 5871.5 5888.9 5922.9 5923.0 3674.8 3799.9 3824.6 3826.8 3845.3 4684.9 5706.9 5724.7 5735.5 5753.5 4730.3 4881.5 5054.7 5529.9 5569.9 4987.9 5509.9 5538.4 5538.9 5545.4 5369.7 5437.6 5450.7 5453.5 5459.5 4702.0 5530.5 5551.5 5551.6 5563.7 5071.9 5080.5 5430.6 5432.9 5444.5 17:58:25 INFO - PROCESS | 1919 | 17:58:25 INFO - PROCESS | 1919 | [#5] yui.html Cycles:180 Average:18975.58 Median:4493.24 stddev:26072.61 (580.3%) stddev-sans-first:26109.54 17:58:25 INFO - PROCESS | 1919 | Values: 676.5 715.6 745.2 769.1 869.2 14266.6 14320.2 14808.9 14877.5 14983.0 13344.5 13487.9 13797.9 13807.8 13822.9 856.7 870.3 881.8 883.4 883.8 790.6 851.4 881.2 881.6 885.1 914.3 915.4 916.9 917.3 917.8 904.5 905.2 906.6 907.4 909.2 677.7 678.3 678.4 678.4 678.9 14601.0 14605.4 14637.3 14639.6 15180.7 11583.8 12551.1 13975.7 14122.2 14151.2 11997.4 12645.0 12687.2 12719.4 13408.7 4469.0 4486.8 4490.6 4495.9 4676.4 89380.6 91816.0 93531.1 94776.0 96795.5 10600.7 10759.7 10788.9 10853.8 10916.9 20196.3 20230.0 20473.0 20582.6 20645.6 7368.4 7369.3 7430.6 7542.0 7618.9 3899.2 4025.6 4083.2 4149.2 4216.3 3440.6 3523.7 3529.4 3533.8 3535.8 538.4 665.1 671.6 672.6 673.3 522.4 536.4 538.2 543.4 557.5 9420.2 9808.8 9995.2 10117.5 10131.9 601.8 626.9 631.3 647.8 674.4 4647.7 4655.9 4657.6 4659.0 4733.7 82561.9 86518.6 90377.0 92781.5 99015.0 72955.3 76805.6 78326.4 79314.6 80535.2 70384.6 70430.6 70536.0 70619.6 71093.0 10570.4 10673.6 10744.2 10943.4 10954.2 40587.6 45255.8 49069.8 49169.0 49435.3 62208.7 65035.7 65510.7 66650.0 67328.0 12662.6 12784.4 12865.4 12900.7 12936.6 13392.7 13414.6 13856.4 13872.0 13933.4 13194.0 13229.8 13318.9 13385.4 13783.9 12853.2 13030.3 13051.8 13167.1 13174.7 12902.9 13002.2 13285.2 13410.9 13695.3 10121.9 10409.3 10749.8 10972.5 11133.0 10216.9 10420.1 10644.9 10645.9 10663.7 17:58:25 INFO - PROCESS | 1919 | -------- Summary: end -------- 17:58:25 INFO - PROCESS | 1919 | 17:58:26 INFO - TEST-INFO | 1919: exit 0 17:58:26 INFO - TEST-OK | dromaeo_css | took 1118298ms 17:58:26 INFO - TEST-START | kraken 17:58:26 INFO - operating with platform_type : mac_ 17:58:26 INFO - Initialising browser for kraken test... 17:58:26 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpOsTHBy/profile http://localhost:49702/getInfo.html) 17:58:28 INFO - PROCESS | 1921 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:58:28 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 17:58:28 INFO - PROCESS | 1921 | colorDepth:24 17:58:28 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 17:58:28 INFO - PROCESS | 1921 | __metrics 17:58:28 INFO - PROCESS | 1921 | 1486259908596 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:58:28 INFO - PROCESS | 1921 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:58:28 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:58:29 INFO - TEST-INFO | 1921: exit 0 17:58:29 INFO - Browser initialized. 17:58:29 INFO - Running cycle 1/1 for kraken test... 17:58:29 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpOsTHBy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:58:41 INFO - PROCESS | 1922 | RSS: Main: 228474880 17:58:41 INFO - PROCESS | 1922 | 17:58:44 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-beat-detection) 17:58:45 INFO - PROCESS | 1922 | RSS: Main: 288301056 17:58:45 INFO - PROCESS | 1922 | 17:58:47 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-dft) 17:58:48 INFO - PROCESS | 1922 | RSS: Main: 291655680 17:58:48 INFO - PROCESS | 1922 | 17:58:51 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-fft) 17:58:52 INFO - PROCESS | 1922 | RSS: Main: 382603264 17:58:52 INFO - PROCESS | 1922 | 17:58:54 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-oscillator) 17:58:55 INFO - PROCESS | 1922 | RSS: Main: 283754496 17:58:55 INFO - PROCESS | 1922 | 17:58:57 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:58:57 INFO - PROCESS | 1922 | RSS: Main: 287862784 17:58:57 INFO - PROCESS | 1922 | 17:59:00 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-darkroom) 17:59:01 INFO - PROCESS | 1922 | RSS: Main: 329453568 17:59:01 INFO - PROCESS | 1922 | 17:59:04 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-desaturate) 17:59:04 INFO - PROCESS | 1922 | RSS: Main: 332685312 17:59:04 INFO - PROCESS | 1922 | 17:59:07 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49702/tests/kraken/driver.html?testName=json-parse-financial) 17:59:08 INFO - PROCESS | 1922 | RSS: Main: 326545408 17:59:08 INFO - PROCESS | 1922 | 17:59:09 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49702/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:59:10 INFO - PROCESS | 1922 | RSS: Main: 304676864 17:59:10 INFO - PROCESS | 1922 | 17:59:12 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:59:13 INFO - PROCESS | 1922 | RSS: Main: 309223424 17:59:13 INFO - PROCESS | 1922 | 17:59:15 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:59:16 INFO - PROCESS | 1922 | RSS: Main: 310910976 17:59:16 INFO - PROCESS | 1922 | 17:59:19 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:59:19 INFO - PROCESS | 1922 | RSS: Main: 328232960 17:59:19 INFO - PROCESS | 1922 | 17:59:22 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:59:23 INFO - PROCESS | 1922 | RSS: Main: 322240512 17:59:23 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49702/tests/kraken/driver.html?testName=ai-astar) 17:59:25 INFO - PROCESS | 1922 | RSS: Main: 367181824 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | __start_tp_report 17:59:25 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 17:59:25 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 17:59:25 INFO - PROCESS | 1922 | |i|pagename|runs| 17:59:25 INFO - PROCESS | 1922 | |0;ai-astar;112;114;115;115;123;172;147;113;113;114 17:59:25 INFO - PROCESS | 1922 | |1;audio-beat-detection;142;110;107;146;111;109;114;136;159;108 17:59:25 INFO - PROCESS | 1922 | |2;audio-dft;182;184;184;186;187;183;182;180;184;183 17:59:25 INFO - PROCESS | 1922 | |3;audio-fft;80;59;59;88;60;56;58;58;74;56 17:59:25 INFO - PROCESS | 1922 | |4;audio-oscillator;90;70;86;70;67;69;86;67;70;83 17:59:25 INFO - PROCESS | 1922 | |5;imaging-gaussian-blur;88;86;89;86;88;85;90;88;89;85 17:59:25 INFO - PROCESS | 1922 | |6;imaging-darkroom;120;95;94;94;94;95;95;94;94;95 17:59:25 INFO - PROCESS | 1922 | |7;imaging-desaturate;87;87;85;88;102;130;110;87;86;87 17:59:25 INFO - PROCESS | 1922 | |8;json-parse-financial;61;58;59;58;60;60;60;59;61;58 17:59:25 INFO - PROCESS | 1922 | |9;json-stringify-tinderbox;63;70;84;77;74;70;71;70;72;73 17:59:25 INFO - PROCESS | 1922 | |10;stanford-crypto-aes;84;96;75;89;103;105;104;84;84;91 17:59:25 INFO - PROCESS | 1922 | |11;stanford-crypto-ccm;162;156;132;143;144;132;148;138;199;150 17:59:25 INFO - PROCESS | 1922 | |12;stanford-crypto-pbkdf2;170;173;172;172;237;215;173;172;171;170 17:59:25 INFO - PROCESS | 1922 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;66;66;67;64;64 17:59:25 INFO - PROCESS | 1922 | __end_tp_report 17:59:25 INFO - PROCESS | 1922 | __start_cc_report 17:59:25 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,2149 17:59:25 INFO - PROCESS | 1922 | __end_cc_report 17:59:25 INFO - PROCESS | 1922 | __startTimestamp1486259965530__endTimestamp 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | ------- Summary: start ------- 17:59:25 INFO - PROCESS | 1922 | Number of tests: 14 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#0] ai-astar Cycles:10 Average:123.80 Median:115.00 stddev:19.95 (17.3%) stddev-sans-first:20.70 17:59:25 INFO - PROCESS | 1922 | Values: 112.0 114.0 115.0 115.0 123.0 172.0 147.0 113.0 113.0 114.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:125.00 stddev:19.47 (15.6%) stddev-sans-first:19.56 17:59:25 INFO - PROCESS | 1922 | Values: 142.0 110.0 107.0 146.0 111.0 109.0 114.0 136.0 159.0 108.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:2.01 (1.1%) stddev-sans-first:2.06 17:59:25 INFO - PROCESS | 1922 | Values: 182.0 184.0 184.0 186.0 187.0 183.0 182.0 180.0 184.0 183.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#3] audio-fft Cycles:10 Average:64.80 Median:59.50 stddev:11.51 (19.3%) stddev-sans-first:10.81 17:59:25 INFO - PROCESS | 1922 | Values: 80.0 59.0 59.0 88.0 60.0 56.0 58.0 58.0 74.0 56.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.50 stddev:9.21 (12.0%) stddev-sans-first:8.21 17:59:25 INFO - PROCESS | 1922 | Values: 90.0 70.0 86.0 70.0 67.0 69.0 86.0 67.0 70.0 83.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.87 17:59:25 INFO - PROCESS | 1922 | Values: 88.0 86.0 89.0 86.0 88.0 85.0 90.0 88.0 89.0 85.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:94.50 stddev:8.10 (8.6%) stddev-sans-first:0.53 17:59:25 INFO - PROCESS | 1922 | Values: 120.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 95.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:87.00 stddev:14.85 (17.1%) stddev-sans-first:15.47 17:59:25 INFO - PROCESS | 1922 | Values: 87.0 87.0 85.0 88.0 102.0 130.0 110.0 87.0 86.0 87.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:60.00 stddev:1.17 (2.0%) stddev-sans-first:1.09 17:59:25 INFO - PROCESS | 1922 | Values: 61.0 58.0 59.0 58.0 60.0 60.0 60.0 59.0 61.0 58.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.50 stddev:5.44 (7.5%) stddev-sans-first:4.59 17:59:25 INFO - PROCESS | 1922 | Values: 63.0 70.0 84.0 77.0 74.0 70.0 71.0 70.0 72.0 73.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:84.00 stddev:10.21 (12.2%) stddev-sans-first:10.46 17:59:25 INFO - PROCESS | 1922 | Values: 84.0 96.0 75.0 89.0 103.0 105.0 104.0 84.0 84.0 91.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:149.00 stddev:19.61 (13.2%) stddev-sans-first:20.34 17:59:25 INFO - PROCESS | 1922 | Values: 162.0 156.0 132.0 143.0 144.0 132.0 148.0 138.0 199.0 150.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.50 Median:172.50 stddev:23.53 (13.6%) stddev-sans-first:24.52 17:59:25 INFO - PROCESS | 1922 | Values: 170.0 173.0 172.0 172.0 237.0 215.0 173.0 172.0 171.0 170.0 17:59:25 INFO - PROCESS | 1922 | 17:59:25 INFO - PROCESS | 1922 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.34 (2.0%) stddev-sans-first:1.39 17:59:25 INFO - PROCESS | 1922 | Values: 67.0 67.0 68.0 67.0 67.0 66.0 66.0 67.0 64.0 64.0 17:59:25 INFO - PROCESS | 1922 | -------- Summary: end -------- 17:59:25 INFO - PROCESS | 1922 | 17:59:26 INFO - TEST-INFO | 1922: exit 0 17:59:26 INFO - TEST-OK | kraken | took 60345ms 17:59:26 INFO - SUITE-END | took 1178s 17:59:26 INFO - Completed test suite (00:19:38) 17:59:26 INFO - javascript benchmark 17:59:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7162.679182128031, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2978.4043191361725, 3204.7756657034, 3293.654166312537, 3317.237035481841, 3358.378699940511, 5471.206675032128, 5575.526080283181, 5606.691631960241, 5673.914891276634, 5700.315962084551, 5582.91625625615, 5599.944000559994, 5647.43525647435, 5656.91514627281, 5681.091025435931, 4390.626796722267, 5011.0228505441455, 5026.120428924933, 5095.796168153288, 5248.921266181011, 5190.584753219733, 5196.428392876781, 5240.973795131019, 5248.921266181011, 5250.18622113573, 5016.47327030662, 5086.364204474441, 5091.567216786559, 5096.159133742928, 5176.0, 5164.122099243138, 5181.481851814826, 5196.142636465017, 5224.50367215114, 5242.0, 5070.6957582545165, 5128.538431541142, 5154.974225128888, 5181.274621552986, 5203.219517072469, 5415.0523658359625, 5445.53712934401, 5551.417101204356, 5586.86032849178, 5598.328200615912, 6084.26988761347, 6149.938500614981, 6207.068939659042, 6209.099680546341, 6257.374262573706, 6252.781152659635, 6312.747490100391, 6325.14610527582, 6334.904976425358, 6346.11154438379, 5792.913106303409, 5832.912506312408, 5836.6789826559525, 5844.561657875676, 5852.531797456193, 6159.414855588712, 6252.562320637603, 6253.0, 6264.310925798159, 6297.6536290503545, 6030.517558595302, 6071.939280607137, 6103.428585615297, 6128.601640893327, 6169.784057557964, 6281.7173227204885, 6288.434040936338, 6288.717007734663, 6294.181756371642, 6301.905471417932, 6146.6004709693725, 6238.220222472191, 6240.0, 6241.812745617639, 6244.313125556185, 5084.389873215164, 6017.308009578867, 6085.208922840091, 6093.99449090895, 6123.018364072999, 4966.006798640286, 4977.104121258209, 4981.726005069756, 4984.053029924303, 5005.474425185376, 4425.756583387944, 4454.4877339106415, 4457.398251236106, 4460.442444694413, 4491.842785502492, 5486.286782718221, 5533.170024496358, 5541.030319694038, 5665.575081868877, 5678.346990096109, 6169.56813023084, 6215.409536094064, 6238.532110091671, 6302.936970630327, 6308.747650093945, 5691.544676425876, 5703.885922281531, 5711.942880571224, 5718.342390625131, 5731.369549349568, 4645.628349732014, 5445.0, 5446.2647542582035, 5448.101063324507, 5458.263134476795, 6079.787207447718, 6206.565540412128, 6247.094171345038, 6248.4688801452385, 6299.464545513682, 6083.756649733961, 6240.251169859646, 6284.5600807943, 6288.842778930563, 6292.874142517216, 6220.346863579259, 6230.470410015199, 6267.843303917439, 6289.119523266655, 6297.4017468340435, 6162.630242185483, 6192.102145189013, 6207.565470417027, 6211.9068213977735, 6215.34738852414, 5965.373635768182, 6099.268087829311, 6117.431594453999, 6301.936980630135, 6311.2426508819235, 5983.431574000463, 6044.153818465506, 6055.72749226277, 6082.2701275847185, 6131.132004888217, 6123.938760612336, 6127.938720612737, 6131.601445906002, 6141.570090093829, 6152.78465253714, 5991.191189189403, 5994.100884867305, 5994.970025149846, 5996.760129594767, 5997.520198384032, 5870.5010074144175, 5884.029135192816, 5933.584649074523, 5948.078047902582, 6006.669633170218, 5874.911876321944, 5964.612300200473, 5967.194428752063, 5972.940270597413, 5977.671228082497], "value": 5655.074501760054, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.3265389665172, 444.75538453850385, 473.0043258939933, 513.5839969624602, 524.7874610782633, 28874.0, 39423.80288098559, 40938.97652558684, 41004.89951004898, 41423.378649320286, 41993.0, 42450.0, 42471.0, 42532.149357012844, 42576.78711606446, 7941.205879412056, 9374.343795934254, 9402.294827887901, 9413.199878010377, 9434.0, 7973.362131029504, 8141.755747327589, 11053.613123540677, 11285.943570282177, 11293.887061129406, 11273.774524509505, 11312.490937907773, 11313.943430282838, 11317.811629778837, 11319.981201691846, 11152.721181970435, 11226.158038147172, 11322.547098116065, 11333.716657083556, 11339.092872570258, 7878.80302992424, 8088.312493438065, 10890.128789696884, 11130.443477826157, 11160.218784685076, 39762.403563946566, 40016.0, 40040.59959400427, 40106.0, 40121.39817902734, 3949.486566746333, 3976.602339766029, 3993.560708322111, 3998.640122388999, 4005.9799701001307, 29726.959556415637, 30053.39893202124, 30206.395872082656, 30764.53853192204, 30833.537496937563, 3095.824075088927, 3110.12916383413, 3129.671384504617, 3217.6621454747383, 3235.223546348859, 331593.0, 334102.0, 335045.0, 335111.0, 335583.0, 50443.0, 59857.0, 60110.39889601136, 60181.39818601758, 60204.39795602075, 50961.23558146631, 53261.46738532565, 63045.0, 64363.67818160879, 64440.0, 15192.696146077016, 15200.771988420183, 15413.922930385277, 15415.460458883885, 15433.691326173413, 11683.941580292045, 11824.349660768741, 11830.231034982871, 11833.230839995545, 11837.526498940118, 16594.83405165957, 16614.335426583042, 16652.418020288053, 16653.666926661397, 16666.749998750027, 7567.394608431422, 7641.312281894656, 7671.895535888385, 7690.155202222676, 7722.189170137167, 857.7426771968385, 863.2648376762627, 875.1205038935828, 883.3816328570026, 899.0515006667897, 28869.278268043046, 28935.0, 28980.650309196342, 29150.0, 29462.558061629094, 1036.6734478639205, 1048.0776916313596, 1051.2483574244452, 1052.179300145872, 1200.8979236764799, 14307.92846035784, 14310.856891430953, 14581.708365832625, 14956.700865982837, 15089.094654320776, 474019.0, 476244.0, 476256.0, 476665.0, 478608.0, 49107.0, 56017.0, 56071.0, 56142.43857561372, 56202.0, 1367.931603419825, 1412.6256542016374, 1419.134328059862, 1424.472945010332, 1424.886009119268, 27258.72741272562, 28442.435666037738, 28488.453463960315, 28842.0, 29154.708452915198, 48467.0, 48827.02345953132, 48903.0219395603, 48948.265776012675, 49010.75494622504, 408100.0, 411836.0, 412850.4579450485, 414414.0, 414507.0, 36806.26387472289, 37614.0, 37665.0, 37792.62207377891, 37817.81091094527, 34940.82529587336, 42692.57307426886, 42922.57077429186, 43642.56357436385, 43908.560914390444, 28657.283567910134, 35740.28519429545, 35909.1022724434, 36652.81673591615, 36726.0, 35192.824035879654, 35347.46978795269, 36167.27665446624, 36431.635683643886, 36475.27049458943, 29338.559921600765, 35195.29609407849, 35241.82379088191, 35936.640633594376, 36109.63890361063, 29358.706412935597, 31465.68534314719, 31590.684093159693, 33072.66927330696, 33253.667463326026, 21692.0, 23209.95555199986, 23564.410889727893, 23606.371159393766, 23861.0], "value": 19171.05893119363, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.2987249544629, 388.2874924513518, 391.4070183671736, 400.79960019990017, 422.84988828965714, 14467.914906382024, 14810.77783833242, 17787.11064446779, 20610.89694551525, 21051.368458946217, 19597.0, 20247.291344802936, 21098.0, 21682.457938551506, 21684.02421891015, 5266.0, 5355.357357117142, 5427.864303392427, 5530.889382212354, 5554.527865131468, 3760.830762615689, 5425.701586412746, 5467.289252397202, 5468.0977638689565, 5492.505674489295, 3932.4297976793346, 5793.449622285897, 6114.419130182626, 6148.292946311164, 6179.66011869347, 5623.437656234385, 5889.204957330749, 6166.6916654167335, 6176.629402235858, 6185.474234690057, 5839.9124013139835, 5848.41515848416, 5849.795257166022, 5850.210221620112, 5857.297124345063, 13550.661233469144, 13767.31163441824, 23454.70999095049, 24536.386590335205, 25377.432062228792, 2893.0, 2983.330503480731, 2986.1340211338684, 3003.0, 3009.5034319337283, 10043.74890627733, 10148.89851101487, 10189.133923616502, 10562.683119506428, 10591.470426478645, 1777.173614269358, 1856.647237024961, 1862.888226706402, 1864.8414884734814, 1880.4170707080712, 132753.0, 142595.28702356527, 143756.0, 145076.54923450737, 147389.0, 10859.40273284961, 11806.98562488758, 11819.0, 11916.0, 12016.038716902627, 15991.0, 15996.0, 16009.599760006093, 16013.279402426919, 16014.679706405806, 7032.80442324806, 7428.851422971618, 7982.9600851995365, 8158.755237342889, 8616.870746938801, 8082.1513741057515, 8093.878591821127, 8117.512949223065, 8270.090290068089, 8322.70870519541, 4084.1002887949294, 4813.446453657874, 4819.638527110411, 4833.284184114659, 4837.250226214942, 2003.6393449178995, 2044.2947183221765, 2048.354768247997, 2054.281001649411, 2057.4856285928518, 978.1217472720697, 1023.3092662452815, 1042.4735508568124, 1043.2146169726848, 1062.2245760594808, 17189.262575744033, 20999.160033598484, 21221.469463263235, 21493.78506214949, 21559.245426410314, 889.621910687955, 1013.159077965287, 1050.222835102019, 1051.5497698804058, 1053.0785562632695, 10768.353898765943, 11085.667429977113, 11363.37501437429, 11416.0, 11443.828342574869, 544442.0, 691961.0, 701842.0, 708953.0, 709609.0, 147646.26176869046, 150372.0, 154473.99999999776, 158035.0, 160171.0, 975.3124047546463, 995.1889210293588, 997.5560875410373, 1011.7672935224939, 1024.2983556263835, 18912.62174756525, 19118.617627647647, 19160.90419547893, 19190.328338508087, 19213.903930480257, 55791.884162317336, 56899.71550142223, 57068.0, 57117.42882571121, 57971.0, 75335.0, 79335.80996285175, 79692.60153699426, 79752.20247797448, 80662.80837075463, 7497.100347958061, 8284.792880177803, 8329.625166867627, 8384.706535271236, 8445.704400345958, 10950.835737464104, 11112.722181945515, 13140.34298285101, 13353.399097040463, 13435.664108396977, 10806.135509159405, 10918.290311129751, 11061.889381106086, 11095.72260693489, 11317.434128293387, 11138.777224455303, 11407.258528195973, 11452.911973362518, 11718.296902185895, 11762.176647634584, 11294.661160165208, 11519.942400287944, 11735.76528469443, 11874.82187767167, 11892.405379731152, 8066.959665201636, 8218.794530136554, 8281.834363312579, 8365.958170209353, 8622.353323500636, 10947.616833410792, 11195.776084478102, 11263.0, 11354.54581816718, 11357.580302462193], "value": 9779.812195524075, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.569629111267, 1437.631293370369, 1441.9495317663884, 1458.0741229319385, 1462.4369617697184, 8242.876356854644, 8682.522461264627, 8690.391672582922, 8698.999615044271, 8716.346274029442, 8310.335173186146, 8444.28223600993, 8553.871691924625, 8561.657533698644, 8680.305575553957, 2271.943201419961, 2308.746037935826, 2312.1329501436962, 2312.4681323295654, 2313.6992191015142, 2279.908803647852, 2300.0454811253308, 2303.1133013789727, 2310.9422264443438, 2316.93049208524, 2091.8326533877253, 2131.914723411062, 2263.6830843681896, 2282.2696737044153, 2285.6342985122383, 2222.022310183514, 2234.0616940884865, 2235.854669446481, 2262.072550254388, 2263.5699217148854, 2223.722034745657, 2299.1722979727283, 2299.9425014374606, 2302.3438320078862, 2306.9077236910503, 8591.82816343676, 8607.956960215159, 8609.48343099416, 8624.61189246479, 8652.134786521297, 5050.494950504957, 5151.742412879341, 5193.818216362409, 5194.3247377840635, 5221.869453263661, 2496.463260399005, 3204.439223135942, 3377.6115746689197, 3409.948850767216, 3474.895753127385, 1205.2105870654734, 1208.6736581123134, 1213.4782043721195, 1261.1487246108932, 1264.3235868810143, 10991.010809027226, 11028.22802403832, 11091.112710983183, 11101.0, 11144.219904606762, 4221.894452638709, 4409.9559004410185, 4487.147441986012, 4488.890221595845, 4498.437695288089, 8887.244584210286, 8894.332925030649, 8895.822083558292, 8919.643214271357, 8923.241524470493, 5139.331886854685, 5143.974280128576, 5146.125158723026, 5147.1507201312115, 5167.560757335668, 4645.581897629229, 4743.075100355398, 4747.730094386199, 4767.4517430495935, 4824.927626085611, 3597.0, 3634.636536346397, 3646.033801042726, 3688.7417880748626, 3709.4621279914263, 1080.9352787504301, 1140.509580880213, 1143.74837535742, 1156.7570810129798, 1161.349644199251, 824.7773101262745, 868.1275318305189, 897.1656359585518, 937.1706040154512, 955.2167222877174, 5431.619786614899, 5628.240187574707, 5674.829755107354, 5720.570957178228, 5733.455321744427, 1002.1331548210745, 1006.1548299428515, 1006.8439391894268, 1010.7271036820017, 1050.1179009631946, 3698.239499376192, 3808.8857334280015, 3849.40334248192, 3857.864974725871, 3862.304785138702, 9806.75483112928, 10133.240006999504, 10155.796884062425, 10312.123469505026, 10319.122874555596, 9127.726168214964, 9214.216791572657, 10037.598496060222, 10045.045720656526, 10073.093421591946, 7744.1094274159195, 8017.875263055295, 8072.878906816402, 8081.030276366873, 8104.8379032419025, 4775.570198682135, 5604.0, 5699.145128230799, 5713.200151978644, 5816.563757718103, 9094.363394562582, 9313.115254050854, 9514.286428517751, 9517.666881659108, 9522.14300712939, 8041.316488098577, 9082.18260356571, 9470.242380609672, 9586.616535338788, 9605.03949605034, 5772.942270577409, 6595.472362210916, 6794.082798822095, 7170.816815225638, 7171.211166771546, 7910.921214475357, 8021.919780802117, 8067.596620168851, 8067.991501062367, 8101.959490202511, 7203.099612548432, 7231.674574644258, 7235.63821808897, 7326.6702998364135, 7406.888896666662, 6964.0, 7018.876979683226, 7125.857482850418, 7146.3568278855155, 7186.281371862772, 6295.0, 6322.114903913363, 6322.209723784527, 6431.453326467303, 6441.291457939717, 4195.49654041517, 4934.284528743384, 4979.153543897619, 4998.501348785976, 5149.536541711264, 4972.925406118983, 4987.850364489072, 5012.3233363495465, 5084.898302033865, 5169.586433085419], "value": 4408.615663864588, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.30336893673706, 322.4759765381254, 350.4730890281073, 358.9335972845024, 371.4168755054565, 4474.597286244238, 4849.811796109953, 5315.415304316527, 5347.171188465784, 5360.195970604412, 4612.8385506507275, 4778.952210477894, 4953.4798846121175, 5054.696718196911, 5286.682799032051, 3469.5142680024815, 3797.373433383488, 4007.8518733111237, 4027.597240275978, 4053.148838743867, 3375.5400714267826, 3759.8496060157563, 3790.4124860646507, 3868.458415821787, 4029.899252518696, 3158.305172861978, 3670.963290367102, 3799.537516944413, 3957.4063890416523, 4036.475258216428, 3263.396271689745, 3991.0, 3992.301347264246, 3994.3409337459284, 4029.5567487576345, 3456.2569047654874, 3457.827108644583, 3465.6014558325865, 3472.3055388922317, 3485.7559970802054, 5075.898482030376, 5140.971805638887, 5147.510986456281, 5157.690538567698, 5181.549166233461, 3534.68187863091, 3849.5188101487315, 3925.646691797723, 4566.862994110182, 4569.794359253841, 5485.6982865942355, 5530.419305972855, 5541.722913854291, 5550.4449555044575, 5559.360673522557, 3256.6580509046685, 3324.9194011946215, 3530.8058056806867, 3806.876971293475, 3812.55123053238, 4895.241237608177, 5262.184361423947, 5420.130055130973, 5531.419200983919, 5815.389384114692, 3696.7966761828357, 3849.1139341722915, 3851.5378154621626, 3864.942025869614, 3866.033491627093, 3686.838645826556, 3738.289724952305, 3900.3564411871725, 3933.783641899723, 3973.1259122992897, 3834.9233015339537, 4373.300271956472, 4553.248713962159, 4563.657725670588, 4563.680542362069, 4914.066327397783, 5551.916721249185, 5590.552755779528, 5593.776248949996, 5608.887822243532, 1071.6356438810685, 1253.364359510829, 1271.3452571925466, 1271.9236845789283, 1274.5852104164371, 748.5246868238708, 797.8005498625344, 861.1818772166467, 871.4640496094983, 876.6931573949067, 3970.1067259866877, 4273.743575385487, 4480.0, 4837.911469919311, 4894.902101958012, 5042.319286896295, 5199.48005199477, 5412.91880621791, 5415.50886592172, 5431.9309590081475, 4405.339199120158, 4492.3710680504755, 4897.314375987365, 4899.828506002273, 4942.555170034642, 2555.6805399325085, 2559.833610815291, 2705.715899830529, 2828.60399544064, 2832.6175966244705, 4926.9014619707405, 5046.697198168122, 5184.688918664892, 5565.554755619541, 5619.241402410704, 4383.802728877208, 4824.607395201372, 4974.27872958419, 5173.689578625294, 5355.919661205085, 4955.778743816476, 5325.387580428301, 5815.924054050014, 5833.7083145842535, 5850.882982340329, 4705.482396936266, 4989.9251511228085, 5382.811601593926, 5650.152477128464, 5663.235463212413, 4699.882502937454, 5302.337207849019, 5428.150221133778, 5604.0, 5687.374388817309, 4875.7074575525585, 5871.530277577869, 5888.906096226648, 5922.851928701817, 5922.970385148046, 3674.779513229215, 3799.8670046548236, 3824.6175382461533, 3826.846926122924, 3845.288621605012, 4684.859454216379, 5706.857328566819, 5724.656520608777, 5735.4551317624755, 5753.482186603226, 4730.314104454766, 4881.463039065629, 5054.671446355975, 5529.861753456195, 5569.916451253315, 4987.9251811223585, 5509.944900551103, 5538.446155384429, 5538.9169162461785, 5545.390007099297, 5369.731513424391, 5437.564994800486, 5450.727463626882, 5453.481919217668, 5459.481349271971, 4701.976490117664, 5530.543410368519, 5551.528120109836, 5551.583631227593, 5563.7496312666835, 5071.92392114126, 5080.517350851663, 5430.615193125727, 5432.864178395572, 5444.482774136451], "value": 3956.2559537808597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.5129107042934, 715.5992644119293, 745.1654147354964, 769.0963118335958, 869.1916517638596, 14266.643333916645, 14320.212388318665, 14808.925955370236, 14877.4792882249, 14983.0, 13344.466221351135, 13487.865121348783, 13797.931010344933, 13807.792883106762, 13822.861771382282, 856.742977106867, 870.2646263906988, 881.7619242804438, 883.3816328569994, 883.8011447424342, 790.6481615681024, 851.4210336970857, 881.1981097201548, 881.5680316644862, 885.1325700813175, 914.3188324698109, 915.4370062411602, 916.8945571259324, 917.2570218123308, 917.7843206846386, 904.5341649050744, 905.2124651553191, 906.5512571277227, 907.3512438606429, 909.1908201700492, 677.701811203069, 678.2708588267583, 678.4165617568887, 678.443676185528, 678.9287124851869, 14601.0, 14605.415783368653, 14637.268136593128, 14639.560813175622, 15180.696386072326, 11583.826242606368, 12551.05867059965, 13975.720485590333, 14122.22327771972, 14151.221682807442, 11997.400129993553, 12645.0, 12687.238765674088, 12719.364031798374, 13408.731825363535, 4469.0, 4486.798094085774, 4490.618297444721, 4495.865124046284, 4676.438827340707, 89380.55309723475, 91816.0, 93531.06468935292, 94776.0, 96795.51602242014, 10600.720845018359, 10759.731006724895, 10788.946055269673, 10853.837192442119, 10916.945415272872, 20196.29312974039, 20230.0, 20473.0, 20582.58834823325, 20645.58708825815, 7368.410527157815, 7369.336759691653, 7430.6284685765495, 7541.962290188624, 7618.923810761821, 3899.1811719539214, 4025.5974402560096, 4083.1833633273463, 4149.190907773016, 4216.3464662977285, 3440.6043305020244, 3523.71810255179, 3529.400001999666, 3533.8056406897354, 3535.840887160084, 538.4373329870295, 665.1353240786957, 671.6306031682556, 672.6300534705892, 673.2762280548333, 522.4148953172471, 536.3661195401188, 538.2249560632677, 543.4076856226653, 557.5288880895637, 9420.24638028956, 9808.761562819573, 9995.200383969264, 10117.544710488046, 10131.898681013095, 601.7653115285042, 626.8746250749869, 631.2614241337646, 647.8088963755704, 674.3762020131359, 4647.697899636512, 4655.877486323095, 4657.627389808875, 4659.0, 4733.721895088307, 82561.93595160049, 86518.56740716255, 90377.0, 92781.53609232046, 99015.0, 72955.27044729485, 76805.61597191979, 78326.43347133139, 79314.60342698249, 80535.19464805395, 70384.64807675929, 70430.64784676248, 70536.0, 70619.64690176619, 71093.0, 10570.365778053341, 10673.57305707763, 10744.19418543628, 10943.398113103856, 10954.178436617127, 40587.57943471964, 45255.77372113118, 49069.77325566747, 49169.0, 49435.3149387825, 62208.68895655674, 65035.67482162559, 65510.672446637465, 66650.0, 67328.0, 12662.620121396372, 12784.424700888669, 12865.421056052688, 12900.677483062998, 12936.611901642966, 13392.665183370495, 13414.597562073153, 13856.4457421702, 13872.028957973249, 13933.4426622938, 13194.0, 13229.80155297652, 13318.866811331764, 13385.397657105259, 13783.931080344533, 12853.228806271654, 13030.339297468689, 13051.834826028178, 13167.078304518593, 13174.73650526965, 12902.903951589482, 13002.154859934075, 13285.202887826761, 13410.865891340962, 13695.315234238447, 10121.86394179179, 10409.323393979366, 10749.785004299714, 10972.506237219184, 11133.0, 10216.948915255376, 10420.11429028541, 10644.89355106439, 10645.946770266099, 10663.680089597325], "value": 7301.357533534594, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1473.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 115.0, 115.0, 123.0, 172.0, 147.0, 113.0, 113.0, 114.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 107.0, 146.0, 111.0, 109.0, 114.0, 136.0, 159.0, 108.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 186.0, 187.0, 183.0, 182.0, 180.0, 184.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 88.0, 60.0, 56.0, 58.0, 58.0, 74.0, 56.0], "value": 64.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 70.0, 67.0, 69.0, 86.0, 67.0, 70.0, 83.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 85.0, 90.0, 88.0, 89.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 88.0, 102.0, 130.0, 110.0, 87.0, 86.0, 87.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 59.0, 58.0, 60.0, 60.0, 60.0, 59.0, 61.0, 58.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 84.0, 77.0, 74.0, 70.0, 71.0, 70.0, 72.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 96.0, 75.0, 89.0, 103.0, 105.0, 104.0, 84.0, 84.0, 91.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 156.0, 132.0, 143.0, 144.0, 132.0, 148.0, 138.0, 199.0, 150.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 172.0, 172.0, 237.0, 215.0, 173.0, 172.0, 171.0, 170.0], "value": 182.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 66.0, 66.0, 67.0, 64.0, 64.0], "value": 66.3, "unit": "score"}]}]} 17:59:26 INFO - Return code: 0 17:59:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:59:26 ERROR - # TBPL SUCCESS # 17:59:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:59:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:59:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:59:26 INFO - 2017-02-04 17:59:26.855 screenresolution[1926:18461] starting screenresolution argv=screenresolution get 17:59:26 INFO - 2017-02-04 17:59:26.857 screenresolution[1926:18461] Display 0: 1600x1200x32@60 17:59:26 INFO - 2017-02-04 17:59:26.864 screenresolution[1927:18464] starting screenresolution argv=screenresolution list 17:59:26 INFO - Available Modes on Display 0 17:59:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:59:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:59:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:59:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:59:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:59:27 INFO - Intel Iris: 17:59:27 INFO - Chipset Model: Intel Iris 17:59:27 INFO - Type: GPU 17:59:27 INFO - Bus: Built-In 17:59:27 INFO - VRAM (Dynamic, Max): 1536 MB 17:59:27 INFO - Vendor: Intel (0x8086) 17:59:27 INFO - Device ID: 0x0a2e 17:59:27 INFO - Revision ID: 0x0009 17:59:27 INFO - Displays: 17:59:27 INFO - Display: 17:59:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:59:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:59:27 INFO - Display Serial Number: 1600x1200 60 17:59:27 INFO - Main Display: Yes 17:59:27 INFO - Mirror: Off 17:59:27 INFO - Online: Yes 17:59:27 INFO - Rotation: Supported 17:59:27 INFO - Return code: 0 17:59:27 INFO - Running post-action listener: _resource_record_post_action 17:59:27 INFO - [mozharness: 2017-02-05 01:59:27.102052Z] Finished run-tests step (success) 17:59:27 INFO - Running post-run listener: _resource_record_post_run 17:59:27 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 114688; Write bytes: 551006208; Read time: 3; Write time: 1650 17:59:27 INFO - TinderboxPrint: CPU usage
53.8% 17:59:27 INFO - TinderboxPrint: I/O read bytes / time
114,688 / 3 17:59:27 INFO - TinderboxPrint: I/O write bytes / time
551,006,208 / 1,650 17:59:27 INFO - TinderboxPrint: CPU idle
2,220.1 (46.2%) 17:59:27 INFO - TinderboxPrint: CPU system
962.1 (20.0%) 17:59:27 INFO - TinderboxPrint: CPU user
1,623.3 (33.8%) 17:59:27 INFO - TinderboxPrint: Swap in / out
251,269,120 / 0 17:59:27 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 243864064; Write bytes: 238168064; Read time: 17759; Write time: 527 17:59:27 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 114688; Write bytes: 295417856; Read time: 3; Write time: 1075 17:59:27 INFO - Running post-run listener: _upload_blobber_files 17:59:27 INFO - Blob upload gear active. 17:59:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:59:27 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 17:59:27 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'] 17:59:27 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 17:59:27 INFO - (blobuploader) - INFO - Open directory for files ... 17:59:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:59:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:59:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:28 INFO - (blobuploader) - INFO - Done attempting. 17:59:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:59:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:59:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:29 INFO - (blobuploader) - INFO - Done attempting. 17:59:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:59:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:59:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:30 INFO - (blobuploader) - INFO - Done attempting. 17:59:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:59:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:59:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:32 INFO - (blobuploader) - INFO - Done attempting. 17:59:32 INFO - (blobuploader) - INFO - Iteration through files over. 17:59:32 INFO - Return code: 0 17:59:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:59:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:59:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6b4d6d8b2c58604f914a04fa98ea3c37bd9fc9bd18b00619cc908e0bb5476bc028d16294a1ed49361941670aa5e604aa59bf76f580b2a2eb7fbf6c337dffbeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab1b836d3a06cdc9f1a00f0390a202df53d9a45af33ded7362a7c33198e523719d14387a9e37a1269c1a7836bf1332778caf0157bd44b0e333198278d3992720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d1175ab59cde7de8754000c54404f17424affde30170926fb9756fa8947ea2e2a0961acb18919e7cd4aaa11f1d0d8e589d71dfe71232d917f313db3775719c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ac52ef508daa236f51c0c444931459e203c6eebb3b797181c036415cdbe1acce626a6b1d79c429def8c972c59127b337021492da11edc66d84027c229ba96f5"} 17:59:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:59:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:59:32 INFO - Contents: 17:59:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6b4d6d8b2c58604f914a04fa98ea3c37bd9fc9bd18b00619cc908e0bb5476bc028d16294a1ed49361941670aa5e604aa59bf76f580b2a2eb7fbf6c337dffbeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab1b836d3a06cdc9f1a00f0390a202df53d9a45af33ded7362a7c33198e523719d14387a9e37a1269c1a7836bf1332778caf0157bd44b0e333198278d3992720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d1175ab59cde7de8754000c54404f17424affde30170926fb9756fa8947ea2e2a0961acb18919e7cd4aaa11f1d0d8e589d71dfe71232d917f313db3775719c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ac52ef508daa236f51c0c444931459e203c6eebb3b797181c036415cdbe1acce626a6b1d79c429def8c972c59127b337021492da11edc66d84027c229ba96f5"} 17:59:32 INFO - Running post-run listener: copy_logs_to_upload_dir 17:59:32 INFO - Copying logs to upload dir... 17:59:32 INFO - mkdir: /builds/slave/test/build/upload/logs 17:59:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.597900 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-04 17:59:32.753173) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 17:59:32.756331) ========= 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.1kyASFRdsH/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.45uJHSFAiF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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/d6b4d6d8b2c58604f914a04fa98ea3c37bd9fc9bd18b00619cc908e0bb5476bc028d16294a1ed49361941670aa5e604aa59bf76f580b2a2eb7fbf6c337dffbeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab1b836d3a06cdc9f1a00f0390a202df53d9a45af33ded7362a7c33198e523719d14387a9e37a1269c1a7836bf1332778caf0157bd44b0e333198278d3992720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d1175ab59cde7de8754000c54404f17424affde30170926fb9756fa8947ea2e2a0961acb18919e7cd4aaa11f1d0d8e589d71dfe71232d917f313db3775719c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ac52ef508daa236f51c0c444931459e203c6eebb3b797181c036415cdbe1acce626a6b1d79c429def8c972c59127b337021492da11edc66d84027c229ba96f5"} build_url:https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009632 build_url: 'https://queue.taskcluster.net/v1/task/MoNFJfLHTKiw-4j0BPscIA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6b4d6d8b2c58604f914a04fa98ea3c37bd9fc9bd18b00619cc908e0bb5476bc028d16294a1ed49361941670aa5e604aa59bf76f580b2a2eb7fbf6c337dffbeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab1b836d3a06cdc9f1a00f0390a202df53d9a45af33ded7362a7c33198e523719d14387a9e37a1269c1a7836bf1332778caf0157bd44b0e333198278d3992720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d1175ab59cde7de8754000c54404f17424affde30170926fb9756fa8947ea2e2a0961acb18919e7cd4aaa11f1d0d8e589d71dfe71232d917f313db3775719c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ac52ef508daa236f51c0c444931459e203c6eebb3b797181c036415cdbe1acce626a6b1d79c429def8c972c59127b337021492da11edc66d84027c229ba96f5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 17:59:32.787703) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:59:32.788061) ========= 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.1kyASFRdsH/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.45uJHSFAiF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.004302 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 17:59:32.837002) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 17:59:32.837356) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 17:59:32.837683) ========= ========= Total master_lag: 0.35 =========