builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0218 starttime: 1491542768.35 results: success (0) buildid: 20170406210242 builduid: af80bb314059429b805be8388031e8c8 revision: 10ea10d9993c9701e5525928257a589dea2c05d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.352224) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.352753) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.353095) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.380199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.380501) ========= 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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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-04-06 22:26:08-- 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% 149M=0s 2017-04-06 22:26:08 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139399 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.547739) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.548521) ========= 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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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.027077 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:08.602190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 22:26:08.603018) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 10ea10d9993c9701e5525928257a589dea2c05d8 --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 10ea10d9993c9701e5525928257a589dea2c05d8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7PtvBvFYo4/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.FcLhuDiNo0/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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-04-06 22:26:08,709 truncating revision to first 12 chars 2017-04-06 22:26:08,709 Setting DEBUG logging. 2017-04-06 22:26:08,709 attempt 1/10 2017-04-06 22:26:08,709 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/10ea10d9993c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 22:26:09,940 unpacking tar archive at: mozilla-central-10ea10d9993c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.513241 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 22:26:10.215093) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:10.215576) ========= script_repo_revision: 10ea10d9993c9701e5525928257a589dea2c05d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:10.216125) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:10.216432) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 22:26:10.236718) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-06 22:26:10.237100) ========= /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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:26:10 INFO - MultiFileLogger online at 20170406 22:26:10 in /builds/slave/test 22:26:10 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 22:26:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:26:10 INFO - {'append_to_log': False, 22:26:10 INFO - 'base_work_dir': '/builds/slave/test', 22:26:10 INFO - 'blob_upload_branch': 'Firefox', 22:26:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:26:10 INFO - 'branch': 'Firefox', 22:26:10 INFO - 'buildbot_json_path': 'buildprops.json', 22:26:10 INFO - 'config_files': ('talos/mac_config.py',), 22:26:10 INFO - 'default_actions': ('clobber', 22:26:10 INFO - 'read-buildbot-config', 22:26:10 INFO - 'download-and-extract', 22:26:10 INFO - 'populate-webroot', 22:26:10 INFO - 'create-virtualenv', 22:26:10 INFO - 'install', 22:26:10 INFO - 'run-tests'), 22:26:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:26:10 INFO - 'download_minidump_stackwalk': True, 22:26:10 INFO - 'download_symbols': 'ondemand', 22:26:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:26:10 INFO - 'tooltool.py': '/tools/tooltool.py', 22:26:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:26:10 INFO - '/tools/misc-python/virtualenv.py')}, 22:26:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:26:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:26:10 INFO - 'gecko_profile': False, 22:26:10 INFO - 'gecko_profile_interval': 0, 22:26:10 INFO - 'installer_path': 'installer.exe', 22:26:10 INFO - 'log_level': 'info', 22:26:10 INFO - 'log_name': 'talos', 22:26:10 INFO - 'log_to_console': True, 22:26:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:26:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:26:10 INFO - 'opt_config_files': (), 22:26:10 INFO - 'pip_index': False, 22:26:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:26:10 INFO - 'cmd': ('bash', 22:26:10 INFO - '-c', 22:26:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:26:10 INFO - 'enabled': True, 22:26:10 INFO - 'halt_on_failure': False, 22:26:10 INFO - 'name': 'check_screen_resolution'},), 22:26:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:26:10 INFO - 'cmd': ('bash', 22:26:10 INFO - '-c', 22:26:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:26:10 INFO - 'enabled': True, 22:26:10 INFO - 'halt_on_failure': False, 22:26:10 INFO - 'name': 'check_screen_resolution'},), 22:26:10 INFO - 'run_cmd_checks_enabled': True, 22:26:10 INFO - 'suite': 'dromaeojs', 22:26:10 INFO - 'system_bits': '32', 22:26:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:26:10 INFO - 'title': 't-yosemite-r7-0218', 22:26:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:26:10 INFO - 'use_talos_json': True, 22:26:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:26:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:26:10 INFO - 'work_dir': 'build'} 22:26:10 INFO - [mozharness: 2017-04-07 05:26:10.449545Z] Running clobber step. 22:26:10 INFO - Running pre-action listener: _resource_record_pre_action 22:26:10 INFO - Running main action method: clobber 22:26:10 INFO - rmtree: /builds/slave/test/build 22:26:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:26:12 INFO - Running post-action listener: _resource_record_post_action 22:26:12 INFO - [mozharness: 2017-04-07 05:26:12.941245Z] Finished clobber step (success) 22:26:12 INFO - [mozharness: 2017-04-07 05:26:12.941394Z] Running read-buildbot-config step. 22:26:12 INFO - Running pre-action listener: _resource_record_pre_action 22:26:12 INFO - Running main action method: read_buildbot_config 22:26:12 INFO - Using buildbot properties: 22:26:12 INFO - { 22:26:12 INFO - "project": "", 22:26:12 INFO - "product": "firefox", 22:26:12 INFO - "script_repo_revision": "production", 22:26:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 22:26:12 INFO - "repository": "", 22:26:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 22:26:12 INFO - "buildid": "20170406210242", 22:26:12 INFO - "pgo_build": "False", 22:26:12 INFO - "basedir": "/builds/slave/test", 22:26:12 INFO - "buildnumber": 8, 22:26:12 INFO - "slavename": "t-yosemite-r7-0218", 22:26:12 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 22:26:12 INFO - "platform": "yosemite_r7", 22:26:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:26:12 INFO - "branch": "mozilla-central", 22:26:12 INFO - "repo_path": "mozilla-central", 22:26:12 INFO - "revision": "10ea10d9993c9701e5525928257a589dea2c05d8", 22:26:12 INFO - "stage_platform": "macosx64", 22:26:12 INFO - "builduid": "af80bb314059429b805be8388031e8c8", 22:26:12 INFO - "slavebuilddir": "test" 22:26:12 INFO - } 22:26:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:26:12 INFO - Running post-action listener: _resource_record_post_action 22:26:12 INFO - [mozharness: 2017-04-07 05:26:12.945364Z] Finished read-buildbot-config step (success) 22:26:12 INFO - [mozharness: 2017-04-07 05:26:12.945520Z] Running download-and-extract step. 22:26:12 INFO - Running pre-action listener: _resource_record_pre_action 22:26:12 INFO - Running main action method: download_and_extract 22:26:12 INFO - mkdir: /builds/slave/test/build/tests 22:26:12 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')]} 22:26:12 INFO - trying https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:26:12 INFO - Downloading https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:26:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 22:26:14 INFO - Downloaded 1325 bytes. 22:26:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:26:14 INFO - Using the following test package requirements: 22:26:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:26:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:26:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:26:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:26:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'jsshell-mac.zip'], 22:26:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:26:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:26:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:26:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:26:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:26:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:26:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:26:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:26:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:26:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:26:14 INFO - Fetch https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:26:15 INFO - Expected file size: 52518405 22:26:15 INFO - Obtained file size: 52518405 22:26:31 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:26:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:26:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:26:31 INFO - Fetch https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:26:33 INFO - Expected file size: 52518405 22:26:33 INFO - Obtained file size: 52518405 22:26:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:26:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:26:49 INFO - Fetch https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:26:50 INFO - Expected file size: 14404138 22:26:50 INFO - Obtained file size: 14404138 22:26:50 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')]} 22:26:50 INFO - trying https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:26:50 INFO - Downloading https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:26:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 22:26:52 INFO - Downloaded 70889175 bytes. 22:26:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:26:52 INFO - mkdir: /builds/slave/test/properties 22:26:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:26:52 INFO - Writing to file /builds/slave/test/properties/build_url 22:26:52 INFO - Contents: 22:26:52 INFO - build_url:https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:26:53 INFO - Running post-action listener: _resource_record_post_action 22:26:53 INFO - Running post-action listener: set_extra_try_arguments 22:26:53 INFO - [mozharness: 2017-04-07 05:26:53.411057Z] Finished download-and-extract step (success) 22:26:53 INFO - [mozharness: 2017-04-07 05:26:53.411164Z] Running populate-webroot step. 22:26:53 INFO - Running pre-action listener: _resource_record_pre_action 22:26:53 INFO - Running main action method: populate_webroot 22:26:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:26:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:26:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:26:53 INFO - '--tppagecycles', 22:26:53 INFO - '7'], 22:26:53 INFO - 'tests': ['tsvgm']}}, 22:26:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:26:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:26:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:26:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:26:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:26:53 INFO - 'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:26:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:26:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:26:53 INFO - 'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['damp', 'tps']}, 22:26:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:26:53 INFO - 'tests': ['damp', 'tps']}, 22:26:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['dromaeo_dom']}, 22:26:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:26:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['basic_compositor_video']}, 22:26:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:26:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['a11yr', 22:26:53 INFO - 'ts_paint', 22:26:53 INFO - 'tpaint', 22:26:53 INFO - 'sessionrestore', 22:26:53 INFO - 'sessionrestore_no_auto_restore', 22:26:53 INFO - 'tabpaint']}, 22:26:53 INFO - 'other-e10s': {'tests': ['a11yr', 22:26:53 INFO - 'ts_paint', 22:26:53 INFO - 'tpaint', 22:26:53 INFO - 'sessionrestore', 22:26:53 INFO - 'sessionrestore_no_auto_restore', 22:26:53 INFO - 'tabpaint']}, 22:26:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:26:53 INFO - 'ts_paint', 22:26:53 INFO - 'tpaint', 22:26:53 INFO - 'sessionrestore', 22:26:53 INFO - 'sessionrestore_no_auto_restore', 22:26:53 INFO - 'tabpaint']}, 22:26:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:26:53 INFO - 'ts_paint', 22:26:53 INFO - 'tpaint', 22:26:53 INFO - 'sessionrestore', 22:26:53 INFO - 'sessionrestore_no_auto_restore', 22:26:53 INFO - 'tabpaint']}, 22:26:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['a11yr', 22:26:53 INFO - 'ts_paint', 22:26:53 INFO - 'tpaint', 22:26:53 INFO - 'sessionrestore', 22:26:53 INFO - 'sessionrestore_no_auto_restore', 22:26:53 INFO - 'tabpaint']}, 22:26:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['a11yr', 22:26:53 INFO - 'ts_paint', 22:26:53 INFO - 'tpaint', 22:26:53 INFO - 'sessionrestore', 22:26:53 INFO - 'sessionrestore_no_auto_restore', 22:26:53 INFO - 'tabpaint']}, 22:26:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['tsvgx', 22:26:53 INFO - 'tsvgr_opacity', 22:26:53 INFO - 'tart', 22:26:53 INFO - 'tscrollx', 22:26:53 INFO - 'cart', 22:26:53 INFO - 'tsvg_static']}, 22:26:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:26:53 INFO - 'tsvgr_opacity', 22:26:53 INFO - 'tart', 22:26:53 INFO - 'tscrollx', 22:26:53 INFO - 'cart', 22:26:53 INFO - 'tsvg_static']}, 22:26:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:26:53 INFO - 'talos_options': ['--disable-e10s'], 22:26:53 INFO - 'tests': ['tp5o']}, 22:26:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:26:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:26:53 INFO - 'talos_options': ['--disable-e10s', 22:26:53 INFO - '--xperf_path', 22:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:26:53 INFO - 'tests': ['tp5n']}, 22:26:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:26:53 INFO - 'talos_options': ['--xperf_path', 22:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:26:53 INFO - 'tests': ['tp5n']}}, 22:26:53 INFO - 'talos.zip': {'path': '', 22:26:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:26:53 INFO - Running post-action listener: _resource_record_post_action 22:26:53 INFO - [mozharness: 2017-04-07 05:26:53.429613Z] Finished populate-webroot step (success) 22:26:53 INFO - [mozharness: 2017-04-07 05:26:53.429731Z] Running create-virtualenv step. 22:26:53 INFO - Running pre-action listener: _resource_record_pre_action 22:26:53 INFO - Running main action method: create_virtualenv 22:26:53 INFO - Creating virtualenv /builds/slave/test/build/venv 22:26:53 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 22:26:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:26:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:26:53 INFO - Using real prefix '/tools/python27' 22:26:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:26:54 INFO - Installing distribute.............................................................................................................................................................................................done. 22:26:57 INFO - Installing pip.................done. 22:26:57 INFO - Return code: 0 22:26:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:26:57 INFO - Reading from file tmpfile_stdout 22:26:57 INFO - Output received: 22:26:57 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) 22:26:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:26:57 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')]} 22:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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')]} 22:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:26:57 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 22:26:57 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 22:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:57 INFO - 'HOME': '/Users/cltbld', 22:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:57 INFO - 'LOGNAME': 'cltbld', 22:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:57 INFO - 'MOZ_NO_REMOTE': '1', 22:26:57 INFO - 'NO_EM_RESTART': '1', 22:26:57 INFO - 'PAGER': '/bin/cat', 22:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:57 INFO - 'PWD': '/builds/slave/test', 22:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:57 INFO - 'SHELL': '/bin/bash', 22:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:26:57 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:57 INFO - 'USER': 'cltbld', 22:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:57 INFO - 'XPC_FLAGS': '0x0', 22:26:57 INFO - 'XPC_SERVICE_NAME': '0', 22:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:57 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 22:26:57 INFO - Cleaning up... 22:26:57 INFO - Return code: 0 22:26:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:26:57 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')]} 22:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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')]} 22:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:26:57 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 22:26:57 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 22:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:57 INFO - 'HOME': '/Users/cltbld', 22:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:57 INFO - 'LOGNAME': 'cltbld', 22:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:57 INFO - 'MOZ_NO_REMOTE': '1', 22:26:57 INFO - 'NO_EM_RESTART': '1', 22:26:57 INFO - 'PAGER': '/bin/cat', 22:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:57 INFO - 'PWD': '/builds/slave/test', 22:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:57 INFO - 'SHELL': '/bin/bash', 22:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:26:57 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:57 INFO - 'USER': 'cltbld', 22:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:57 INFO - 'XPC_FLAGS': '0x0', 22:26:57 INFO - 'XPC_SERVICE_NAME': '0', 22:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:57 INFO - Downloading/unpacking psutil>=3.1.1 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:27:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:27:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:27:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:27:01 INFO - Installing collected packages: psutil 22:27:01 INFO - Running setup.py install for psutil 22:27:01 INFO - building 'psutil._psutil_osx' extension 22:27: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 22:27:02 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 22:27:02 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 22:27:02 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 22:27:02 INFO - building 'psutil._psutil_posix' extension 22:27:02 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 22:27:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:27:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:27:02 INFO - ^ 22:27:02 INFO - 1 warning generated. 22:27:02 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 22:27:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:27:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:27:02 INFO - Successfully installed psutil 22:27:02 INFO - Cleaning up... 22:27:02 INFO - Return code: 0 22:27:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:27: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')]} 22:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27: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')]} 22:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27: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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27: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 22:27: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 22:27:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:02 INFO - 'HOME': '/Users/cltbld', 22:27:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:02 INFO - 'LOGNAME': 'cltbld', 22:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:02 INFO - 'MOZ_NO_REMOTE': '1', 22:27:02 INFO - 'NO_EM_RESTART': '1', 22:27:02 INFO - 'PAGER': '/bin/cat', 22:27:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:02 INFO - 'PWD': '/builds/slave/test', 22:27:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:02 INFO - 'SHELL': '/bin/bash', 22:27:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:02 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:02 INFO - 'USER': 'cltbld', 22:27:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:02 INFO - 'XPC_FLAGS': '0x0', 22:27:02 INFO - 'XPC_SERVICE_NAME': '0', 22:27:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:27: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 22:27: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 22:27: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 22:27: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 22:27: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 22:27: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 22:27:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:27:05 INFO - Installing collected packages: mozsystemmonitor 22:27:05 INFO - Running setup.py install for mozsystemmonitor 22:27:05 INFO - Successfully installed mozsystemmonitor 22:27:05 INFO - Cleaning up... 22:27:05 INFO - Return code: 0 22:27:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:27: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')]} 22:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:06 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')]} 22:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:06 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27:06 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 22:27:06 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 22:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:06 INFO - 'HOME': '/Users/cltbld', 22:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:06 INFO - 'LOGNAME': 'cltbld', 22:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:06 INFO - 'MOZ_NO_REMOTE': '1', 22:27:06 INFO - 'NO_EM_RESTART': '1', 22:27:06 INFO - 'PAGER': '/bin/cat', 22:27:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:06 INFO - 'PWD': '/builds/slave/test', 22:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:06 INFO - 'SHELL': '/bin/bash', 22:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:06 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:06 INFO - 'USER': 'cltbld', 22:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:06 INFO - 'XPC_FLAGS': '0x0', 22:27:06 INFO - 'XPC_SERVICE_NAME': '0', 22:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:06 INFO - Downloading/unpacking jsonschema==2.5.1 22:27:06 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 22:27:06 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 22:27:06 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 22:27:06 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 22:27:06 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 22:27:06 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 22:27:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:27:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:27: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 22:27: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 22:27: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 22:27: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 22:27: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 22:27: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 22:27:10 INFO - Downloading functools32-3.2.3-2.tar.gz 22:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:27:10 INFO - warning: no files found matching '*.txt' 22:27:10 INFO - no previously-included directories found matching 'build' 22:27:10 INFO - no previously-included directories found matching 'dist' 22:27:10 INFO - no previously-included directories found matching '.git*' 22:27:10 INFO - Installing collected packages: jsonschema, functools32 22:27:10 INFO - Running setup.py install for functools32 22:27:10 INFO - warning: no files found matching '*.txt' 22:27:10 INFO - no previously-included directories found matching 'build' 22:27:10 INFO - no previously-included directories found matching 'dist' 22:27:10 INFO - no previously-included directories found matching '.git*' 22:27:10 INFO - Successfully installed jsonschema functools32 22:27:10 INFO - Cleaning up... 22:27:10 INFO - Return code: 0 22:27:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:10 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27:10 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 22:27:10 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 22:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:10 INFO - 'HOME': '/Users/cltbld', 22:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:10 INFO - 'LOGNAME': 'cltbld', 22:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:10 INFO - 'MOZ_NO_REMOTE': '1', 22:27:10 INFO - 'NO_EM_RESTART': '1', 22:27:10 INFO - 'PAGER': '/bin/cat', 22:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:10 INFO - 'PWD': '/builds/slave/test', 22:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:10 INFO - 'SHELL': '/bin/bash', 22:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:10 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:10 INFO - 'USER': 'cltbld', 22:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:10 INFO - 'XPC_FLAGS': '0x0', 22:27:10 INFO - 'XPC_SERVICE_NAME': '0', 22:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:27:10 INFO - Cleaning up... 22:27:10 INFO - Return code: 0 22:27:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:10 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27:10 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 22:27:10 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 22:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:10 INFO - 'HOME': '/Users/cltbld', 22:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:10 INFO - 'LOGNAME': 'cltbld', 22:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:10 INFO - 'MOZ_NO_REMOTE': '1', 22:27:10 INFO - 'NO_EM_RESTART': '1', 22:27:10 INFO - 'PAGER': '/bin/cat', 22:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:10 INFO - 'PWD': '/builds/slave/test', 22:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:10 INFO - 'SHELL': '/bin/bash', 22:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:10 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:10 INFO - 'USER': 'cltbld', 22:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:10 INFO - 'XPC_FLAGS': '0x0', 22:27:10 INFO - 'XPC_SERVICE_NAME': '0', 22:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:11 INFO - Downloading/unpacking blobuploader==1.2.4 22:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:13 INFO - Downloading blobuploader-1.2.4.tar.gz 22:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:27:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:27:13 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 22:27:13 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 22:27:13 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 22:27:13 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 22:27:13 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 22:27:13 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 22:27:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:27:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:27:14 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 22:27:14 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 22:27:14 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 22:27:14 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 22:27:14 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 22:27:14 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 22:27:15 INFO - Downloading docopt-0.6.1.tar.gz 22:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:27:15 INFO - Installing collected packages: blobuploader, requests, docopt 22:27:15 INFO - Running setup.py install for blobuploader 22:27:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:27:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:27:15 INFO - Running setup.py install for requests 22:27:15 INFO - Running setup.py install for docopt 22:27:15 INFO - Successfully installed blobuploader requests docopt 22:27:15 INFO - Cleaning up... 22:27:16 INFO - Return code: 0 22:27:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:16 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')]} 22:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:16 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')]} 22:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:16 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27:16 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 22:27:16 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 22:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:16 INFO - 'HOME': '/Users/cltbld', 22:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:16 INFO - 'LOGNAME': 'cltbld', 22:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:16 INFO - 'MOZ_NO_REMOTE': '1', 22:27:16 INFO - 'NO_EM_RESTART': '1', 22:27:16 INFO - 'PAGER': '/bin/cat', 22:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:16 INFO - 'PWD': '/builds/slave/test', 22:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:16 INFO - 'SHELL': '/bin/bash', 22:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:16 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:16 INFO - 'USER': 'cltbld', 22:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:16 INFO - 'XPC_FLAGS': '0x0', 22:27:16 INFO - 'XPC_SERVICE_NAME': '0', 22:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:27:16 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-PgBlrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:27:16 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-LGqtFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:27:16 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-dCedq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:27:16 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-IFidyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:27:16 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-oP4Uzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:27:16 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-fA0SKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:27:16 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-QTU0Bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-HNbyvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-SIARpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-rKofQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-0Zi3Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-TJi4EL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Gm_6OZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-IN_zPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-31unyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-VmFR63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:27:17 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-AJdAfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:27:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:27:18 INFO - Running setup.py install for manifestparser 22:27:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:27:18 INFO - Running setup.py install for mozcrash 22:27:18 INFO - Running setup.py install for mozdebug 22:27:18 INFO - Running setup.py install for mozdevice 22:27:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:27:18 INFO - Running setup.py install for mozfile 22:27:18 INFO - Running setup.py install for mozhttpd 22:27:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:27:18 INFO - Running setup.py install for mozinfo 22:27:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Running setup.py install for mozInstall 22:27:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Running setup.py install for mozleak 22:27:19 INFO - Running setup.py install for mozlog 22:27:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Running setup.py install for moznetwork 22:27:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Running setup.py install for mozprocess 22:27:19 INFO - Running setup.py install for mozprofile 22:27:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Running setup.py install for mozrunner 22:27:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:27:20 INFO - Running setup.py install for mozscreenshot 22:27:20 INFO - Running setup.py install for moztest 22:27:20 INFO - Running setup.py install for mozversion 22:27:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:27:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:27:20 INFO - Cleaning up... 22:27:20 INFO - Return code: 0 22:27:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:20 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27:20 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 22:27:20 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 22:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:20 INFO - 'HOME': '/Users/cltbld', 22:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:20 INFO - 'LOGNAME': 'cltbld', 22:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:20 INFO - 'MOZ_NO_REMOTE': '1', 22:27:20 INFO - 'NO_EM_RESTART': '1', 22:27:20 INFO - 'PAGER': '/bin/cat', 22:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:20 INFO - 'PWD': '/builds/slave/test', 22:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:20 INFO - 'SHELL': '/bin/bash', 22:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:20 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:20 INFO - 'USER': 'cltbld', 22:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:20 INFO - 'XPC_FLAGS': '0x0', 22:27:20 INFO - 'XPC_SERVICE_NAME': '0', 22:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:27:20 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-JruEfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:27:20 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)) 22:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:27:20 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-WW1zHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-VcRZKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-MpV4JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-eIOmmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-fNfubK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Vk5m4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-QXJsuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-CW1bAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-CawNCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:27:21 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)) 22:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:27:21 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-6cbkMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:27:22 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)) 22:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:27:22 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Ew9WHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:27:22 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-tyDnAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:27:22 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)) 22:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:27:22 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-wRvyz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:27:22 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)) 22:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:22 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-2jsqUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:22 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)) 22:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:27:22 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-6Ax7Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:27:22 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)) 22:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:27:22 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-qOc3YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:27:22 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)) 22:27:22 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)) 22:27:22 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)) 22:27:22 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)) 22:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:27:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:25 INFO - Downloading blessings-1.6.tar.gz 22:27:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:27:25 INFO - Installing collected packages: blessings 22:27:25 INFO - Running setup.py install for blessings 22:27:25 INFO - Successfully installed blessings 22:27:25 INFO - Cleaning up... 22:27:25 INFO - Return code: 0 22:27:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:27:25 INFO - Reading from file tmpfile_stdout 22:27:25 INFO - Current package versions: 22:27:25 INFO - blessings == 1.6 22:27:25 INFO - blobuploader == 1.2.4 22:27:25 INFO - docopt == 0.6.1 22:27:25 INFO - functools32 == 3.2.3-2 22:27:25 INFO - jsonschema == 2.5.1 22:27:25 INFO - manifestparser == 1.1 22:27:25 INFO - mozInstall == 1.12 22:27:25 INFO - mozcrash == 1.0 22:27:25 INFO - mozdebug == 0.1 22:27:25 INFO - mozdevice == 0.50 22:27:25 INFO - mozfile == 1.2 22:27:25 INFO - mozhttpd == 0.7 22:27:25 INFO - mozinfo == 0.9 22:27:25 INFO - mozleak == 0.1 22:27:25 INFO - mozlog == 3.4 22:27:25 INFO - moznetwork == 0.27 22:27:25 INFO - mozprocess == 0.25 22:27:25 INFO - mozprofile == 0.28 22:27:25 INFO - mozrunner == 6.13 22:27:25 INFO - mozscreenshot == 0.1 22:27:25 INFO - mozsystemmonitor == 0.3 22:27:25 INFO - moztest == 0.8 22:27:25 INFO - mozversion == 1.4 22:27:25 INFO - psutil == 3.1.1 22:27:25 INFO - requests == 1.2.3 22:27:25 INFO - wsgiref == 0.1.2 22:27:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:25 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27:25 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 22:27:25 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 22:27:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:25 INFO - 'HOME': '/Users/cltbld', 22:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:25 INFO - 'LOGNAME': 'cltbld', 22:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:25 INFO - 'MOZ_NO_REMOTE': '1', 22:27:25 INFO - 'NO_EM_RESTART': '1', 22:27:25 INFO - 'PAGER': '/bin/cat', 22:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:25 INFO - 'PWD': '/builds/slave/test', 22:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:25 INFO - 'SHELL': '/bin/bash', 22:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:25 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:25 INFO - 'USER': 'cltbld', 22:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:25 INFO - 'XPC_FLAGS': '0x0', 22:27:25 INFO - 'XPC_SERVICE_NAME': '0', 22:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 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)) 22:27:26 INFO - Cleaning up... 22:27:26 INFO - Return code: 0 22:27:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:27:26 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')]} 22:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:26 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')]} 22:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:26 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2467e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 22:27:26 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 22:27:26 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 22:27:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:26 INFO - 'HOME': '/Users/cltbld', 22:27:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:26 INFO - 'LOGNAME': 'cltbld', 22:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:26 INFO - 'MOZ_NO_REMOTE': '1', 22:27:26 INFO - 'NO_EM_RESTART': '1', 22:27:26 INFO - 'PAGER': '/bin/cat', 22:27:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:26 INFO - 'PWD': '/builds/slave/test', 22:27:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:26 INFO - 'SHELL': '/bin/bash', 22:27:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:26 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:26 INFO - 'USER': 'cltbld', 22:27:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:26 INFO - 'XPC_FLAGS': '0x0', 22:27:26 INFO - 'XPC_SERVICE_NAME': '0', 22:27:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:27:26 INFO - Cleaning up... 22:27:26 INFO - Return code: 0 22:27:26 INFO - Running post-action listener: _resource_record_post_action 22:27:26 INFO - Running post-action listener: _start_resource_monitoring 22:27:26 INFO - Starting resource monitoring. 22:27:26 INFO - [mozharness: 2017-04-07 05:27:26.434031Z] Finished create-virtualenv step (success) 22:27:26 INFO - [mozharness: 2017-04-07 05:27:26.434964Z] Running install step. 22:27:26 INFO - Running pre-action listener: _resource_record_pre_action 22:27:26 INFO - Running main action method: install 22:27:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:27:26 INFO - Reading from file tmpfile_stdout 22:27:26 INFO - Detecting whether we're running mozinstall >=1.0... 22:27:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:27:26 INFO - Reading from file tmpfile_stdout 22:27:26 INFO - Output received: 22:27:26 INFO - Usage: mozinstall [options] installer 22:27:26 INFO - Options: 22:27:26 INFO - -h, --help show this help message and exit 22:27:26 INFO - -d DEST, --destination=DEST 22:27:26 INFO - Directory to install application into. [default: 22:27:26 INFO - "/builds/slave/test"] 22:27:26 INFO - --app=APP Application being installed. [default: firefox] 22:27:26 INFO - mkdir: /builds/slave/test/build/application 22:27:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:27:46 INFO - Reading from file tmpfile_stdout 22:27:46 INFO - Output received: 22:27:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:27:46 INFO - Running post-action listener: _resource_record_post_action 22:27:46 INFO - [mozharness: 2017-04-07 05:27:46.642246Z] Finished install step (success) 22:27:46 INFO - [mozharness: 2017-04-07 05:27:46.642411Z] Running run-tests step. 22:27:46 INFO - Running pre-action listener: _resource_record_pre_action 22:27:46 INFO - Running main action method: run_tests 22:27:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:27:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:27:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:27:46 INFO - 2017-04-06 22:27:46.657 screenresolution[1624:7554] starting screenresolution argv=screenresolution get 22:27:46 INFO - 2017-04-06 22:27:46.659 screenresolution[1624:7554] Display 0: 1600x1200x32@60 22:27:46 INFO - 2017-04-06 22:27:46.665 screenresolution[1625:7557] starting screenresolution argv=screenresolution list 22:27:46 INFO - Available Modes on Display 0 22:27:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:27:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:27:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:27:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:27:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:27:46 INFO - Intel Iris: 22:27:46 INFO - Chipset Model: Intel Iris 22:27:46 INFO - Type: GPU 22:27:46 INFO - Bus: Built-In 22:27:46 INFO - VRAM (Dynamic, Max): 1536 MB 22:27:46 INFO - Vendor: Intel (0x8086) 22:27:46 INFO - Device ID: 0x0a2e 22:27:46 INFO - Revision ID: 0x0009 22:27:46 INFO - Displays: 22:27:46 INFO - Display: 22:27:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:27:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:27:46 INFO - Display Serial Number: 1600x1200 60 22:27:46 INFO - Main Display: Yes 22:27:46 INFO - Mirror: Off 22:27:46 INFO - Online: Yes 22:27:46 INFO - Rotation: Supported 22:27:46 INFO - Return code: 0 22:27:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:27:46 INFO - Python 2.7.3 22:27:46 INFO - Return code: 0 22:27:46 INFO - grabbing minidump binary from tooltool 22:27:46 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')]} 22:27:46 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 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:27:46 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 22:27:46 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 22:27:46 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 22:27:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:27:47 INFO - Return code: 0 22:27:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:27:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:27:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:27:47 INFO - ENV: RUST_BACKTRACE is now 1 22:27:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:27:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:27: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/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 22:27: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/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0218 --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 22:27:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PtvBvFYo4/Render', 22:27:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:47 INFO - 'HOME': '/Users/cltbld', 22:27:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:47 INFO - 'LOGNAME': 'cltbld', 22:27:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:27:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:47 INFO - 'MOZ_NO_REMOTE': '1', 22:27:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:27:47 INFO - 'NO_EM_RESTART': '1', 22:27:47 INFO - 'PAGER': '/bin/cat', 22:27:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:47 INFO - 'PWD': '/builds/slave/test', 22:27:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:27:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:47 INFO - 'RUST_BACKTRACE': '1', 22:27:47 INFO - 'SHELL': '/bin/bash', 22:27:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FcLhuDiNo0/Listeners', 22:27:47 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 22:27:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:47 INFO - 'USER': 'cltbld', 22:27:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:47 INFO - 'XPC_FLAGS': '0x0', 22:27:47 INFO - 'XPC_SERVICE_NAME': '0', 22:27:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27: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/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 22:27:47 INFO - mozversion application_buildid: 20170406210242 22:27:47 INFO - mozversion application_changeset: 10ea10d9993c9701e5525928257a589dea2c05d8 22:27:47 INFO - mozversion application_display_name: Nightly 22:27:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:27:47 INFO - mozversion application_name: Firefox 22:27:47 INFO - mozversion application_remotingname: firefox 22:27:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:27:47 INFO - mozversion application_vendor: Mozilla 22:27:47 INFO - mozversion application_version: 55.0a1 22:27:47 INFO - mozversion platform_buildid: 20170406210242 22:27:47 INFO - mozversion platform_changeset: 10ea10d9993c9701e5525928257a589dea2c05d8 22:27:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:27:47 INFO - mozversion platform_version: 55.0a1 22:27:47 INFO - using testdate: 1491542867 22:27:47 INFO - actual date: 1491542867 22:27:47 INFO - starting webserver on 'localhost:49240' 22:27:47 INFO - SUITE-START | Running 2 tests 22:27:47 INFO - TEST-START | dromaeo_css 22:27:47 INFO - operating with platform_type : mac_ 22:27:47 INFO - Initialising browser for dromaeo_css test... 22:27:47 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpa5vv5i/profile http://localhost:49240/getInfo.html) 22:27:47 INFO - PID 1635 | 1491542867929 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 22:27:47 INFO - PID 1635 | 1491542867930 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 22:27:47 INFO - PID 1635 | 1491542867982 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 22:27:50 INFO - PID 1635 | __metrics Screen width/height:1600/1200 22:27:50 INFO - PID 1635 | colorDepth:24 22:27:50 INFO - PID 1635 | Browser inner width/height: 1024/689 22:27:50 INFO - PID 1635 | __metrics 22:27:50 INFO - PID 1635 | 1491542870212 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 22:27:50 INFO - PID 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:27:50 INFO - PID 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:27:51 INFO - TEST-INFO | 1635: exit 0 22:27:51 INFO - Browser initialized. 22:27:51 INFO - Running cycle 1/1 for dromaeo_css test... 22:27:51 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpa5vv5i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:27:51 INFO - PID 1637 | 1491542871778 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 22:27:51 INFO - PID 1637 | 1491542871779 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 22:28:03 INFO - PID 1637 | RSS: Main: 239652864 22:28:03 INFO - PID 1637 | 22:31:08 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 22:31:10 INFO - PID 1637 | RSS: Main: 371867648 22:31:10 INFO - PID 1637 | 22:32:53 INFO - PID 1637 | ************************* 22:32:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:32:53 INFO - PID 1637 | 22:32:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:32:53 INFO - PID 1637 | Full stack: 22:32:53 INFO - PID 1637 | ************************* 22:32:53 INFO - PID 1637 | ************************* 22:32:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:32:53 INFO - PID 1637 | 22:32:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:32:53 INFO - PID 1637 | Full stack: 22:32:53 INFO - PID 1637 | ************************* 22:32:53 INFO - PID 1637 | ************************* 22:32:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:32:53 INFO - PID 1637 | 22:32:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:32:53 INFO - PID 1637 | Full stack: 22:32:53 INFO - PID 1637 | ************************* 22:32:53 INFO - PID 1637 | ************************* 22:32:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:32:53 INFO - PID 1637 | 22:32:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:32:53 INFO - PID 1637 | Full stack: 22:32:53 INFO - PID 1637 | ************************* 22:34:13 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 22:34:14 INFO - PID 1637 | RSS: Main: 367853568 22:34:14 INFO - PID 1637 | 22:37:16 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 22:37:16 INFO - PID 1637 | RSS: Main: 336056320 22:37:16 INFO - PID 1637 | 22:40:19 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 22:40:19 INFO - PID 1637 | RSS: Main: 334528512 22:40:19 INFO - PID 1637 | 22:43:23 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 22:43:24 INFO - PID 1637 | RSS: Main: 342073344 22:43:24 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 22:46:27 INFO - PID 1637 | RSS: Main: 431525888 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | __start_tp_report 22:46:27 INFO - PID 1637 | _x_x_mozilla_page_load 22:46:27 INFO - PID 1637 | _x_x_mozilla_page_load_details 22:46:27 INFO - PID 1637 | |i|pagename|runs| 22:46:27 INFO - PID 1637 | |0;dojo.html;3498.2478767065086;3528.347255757685;3535.9469607955903;3578.2485678007615;3608.4767708682243;6121.265448146229;6171;6195.9999999999945;6227.750889964402;6229.252489701236;5096.694198348093;6133.426475724768;6163.352847950968;6163.476104531121;6205.7517699291975;5280.313559237294;5350.3312085989255;5379.058664733666;5416.729163541827;5577.8047768328115;5518.2826232589705;5533.734921720212;5547.25112109866;5557.444255574451;5560.332760068797;5264.52619264268;5364.544013758835;5386.057439947994;5390.164524498709;5480.99999999998;5358.276632654581;5360.973195134038;5361.2226227197225;5364.222187782789;5374.5700343972385;5295.735213239323;5342.198670199462;5364.195370694388;5379.35447746273;5379.623426360156;6180.81457556274;6181.6909154542545;6184.690765461709;6185.8144255672405;6209.068639704081;6506.381893720137;6512.446442052384;6621.768238111643;6680.966595166993;6736.730530778763;6790.694418751119;6808.931910680929;6820.181578210647;6825.590464572141;6838.521303508756;6277.623342599413;6287.528435367366;6308.716107775161;6311.558190926637;6327.683615819236;6650.70071846768;6731.528792984542;6742.865142697118;6831.726730930806;6987.755428559976;6585.9012114818315;6596.670166491656;6610.30591787865;6616.966915165393;6618.305077966744;6729.798106056728;6769.898451523231;6770.661466926634;6779.966100169468;6789.966050169816;6413;6476;6624.6025238485845;6638.502112341497;6638.5684930479365;6440.2915679276175;6546.9017964730565;6587.571807832572;6627.966860165765;6629.071929929813;5337.172738225581;5367.597430192751;5374.113271310268;5388.515033646991;5399.37907140676;4735.905281894343;4748.074125545554;4751.121042607128;4759.738214398172;4767.952320476751;6236.719347629368;6249.781257656051;6291.496680265568;6304.621722696653;6327.082573026885;6741.561798483181;6746.257911629717;6774.898376524356;6789.0834737310815;6799.184097908283;6299.748010079546;6317.147185129948;6322.652254126067;6344.111824344595;6364.459020983176;5799.681017543991;5864.325602555676;5877.706114694248;5924.733386997509;5967.403259674084;6960.443164546926;7022.859542809218;7026.051483049927;7034.5427547209265;7055.153381594241;6507.316731743288;6587.769428069994;6608.1079054328975;6715.328467153441;6738.157730283715;6977.895331569929;7052.0832291801735;7052.858942821217;7058.082449281561;7063.187733410724;6627.436667883284;6633.170853643294;6645.734170633121;6665.633390163587;6670.39966403026;6500.382463665944;6514.153160089348;6700.229473610597;6700.5309628327495;6757.12157419552;6535.3137920517665;6563.671816409256;6566.8358291043105;6574.408303252539;6579.671016449062;6481.837954050998;6493.090967264681;6527.249366322933;6546.247181573989;6547.836304092436;6234.937650623436;6321.399467050623;6407.8718425630295;6410.326915673787;6414.583052101785;5450.672959622435;5459.208414779755;6561.40947314744;6587.143671322698;6595.934040659532;6379.553431259767;6384.201974753156;6406.775762848278;6408.231012278557;6412.61524308543 22:46:27 INFO - PID 1637 | |1;ext.html;690.4855882367635;814.9144339844316;1136.6817291158472;1159.6868845411739;1160.141495293483;36404.27191456172;37268.62731372686;37293;37318.4402233966;37364.439533406956;39405.60594394062;39843.800780996055;39846.8007659962;39880;39935.60064399355;6175.907361389561;8580.656773729059;8739.257163141141;8741.956290218572;8749.781255468632;6243.594166379194;8487.66049358025;8706.868107146094;8708.869366959501;8711.346649001318;8720.43317184384;8779.429337093101;8785.560721963908;8785.912140878574;8788.47269163849;8017.238362355625;8173;8693.304535637133;8762.167594078552;8795.076516965755;8570.100139485388;8641.827163456759;8647.65409383624;8654.48073115615;8656.6537338507;32651.69393224268;35673.64326356729;37661;37702.81148594267;37794;3010.9913179084815;3300.884469043572;3335.949960750591;3370.208001119735;3375.038114137459;30167;30187.547186792217;30281.63732632015;30291.242718931982;30313.545296820565;3099.767517436201;3120.6567277599447;3151.038933125396;3154.3375891062906;3161.652218256013;193921;262190.0671489929;264845;265533;266025.9999999981;36874.26251474956;39725.60274397277;48729.75635121802;48842.29051983164;48845;42731.14537709291;50459.49540504621;51124;51139.232911506355;51293.7435312821;13541.86458135406;14390.640233994234;14397.568072957829;14400.927995359956;14428.350724217435;11565.363904985139;11581.594644187413;11582.76834463306;11599.304041757521;11633.6509904703;12932.547360842325;12944.990274799224;12987.805182922264;12989.350532473338;13049.673758156123;5234.3718753749035;5291.391489978701;5297.278384525045;5299.740659494383;5304.867378315573;928.2388441477923;952.2953014769159;963.5037955452807;978.7014960437135;996.5515518016921;22157.778422215895;22412.439689007904;23826.761732382452;23876.686782227145;23882.880585596962;1055.5619417941623;1060.3266925502362;1083.3932997521413;1106.1758989552675;1114.4817659788237;12118.454669539891;12120.45457954394;12213.572524961584;12311.076669249842;12437.62687119388;344127;345675;345834;346240;347114;51367;51432.74283628558;51441.22838157431;51485;51703;1442.2932762946082;1460.2552698124034;1503.4211828446128;1510.7960425342658;1516.0600427734876;23371.078615173104;23412;23516.882415587814;23530.764692353543;23885.761142388354;38326.61673383231;38383;38493.230135397695;38520.036999074124;38604.64883729056;307997;310236;310776;311212;311711.9729612218;30114.79540818343;30198;32600.510992335607;33480.49779253362;33614.99155025353;41317.793411032755;41654.79172604118;41977.580224197365;42308.36537452002;42746.717598472096;28320.43359132847;28925;29312;29517;29651.555226671186;28470.85764571164;29389.853050735466;29573;29849.10452686423;30803;34588.30823383568;35754.463683044254;36021.81989090038;36026.739064133675;36149.73475928435;28116.156515304574;28864.711352887043;29838.165374290274;29930;30179.245518862204;23133.768662313163;23285.88357058204;23355.649665255376;23830.404239894142;23927.76072239255 22:46:27 INFO - PID 1637 | |2;jquery.html;357.561986566456;391.9196564704236;397.4495323976292;419.1700433142376;430.8212091981829;14111.788323175148;21363.679544806822;22341.329760107183;23006.88496557519;23148.537029259427;23087;23167;23283.185088521906;23304.65043024356;23507.882460587673;5302.2841916341185;6047.848803779919;6211.378862113797;6295.622262664255;6353.745850165988;5299.390570084451;6115.8165255042195;6252.06219067139;6261.373862613747;6265.185525881629;3642.981785091071;3756.229972855558;5073.264376665381;6260.18617579714;6262.561620686553;6052.394760523913;6054.031354983225;6237.875242495147;6251.624902505864;6256.624602523841;5544.085225937715;5553.055980483287;5557.277553918006;5557.972210138923;5558.86102847428;26993;27125.744910610854;27176;27271;27822.443551129065;2152.0853637204127;2195.2755590655206;2196.560687862434;2198.9890050549643;2201.5486825200796;16908;17681.911590442094;17708.468745937644;17945;17972.55068623282;2025.48350170707;2073.2536286936693;2112.091800525773;2116.1429621003363;2128.4146859613575;109481.45259273732;138127.99999999898;138159;139348.30325848307;139916;11672.241304315192;12050.276983381025;12056.698582535331;12061.216020958609;12166.57416990401;16062.357505699874;16069;16085.597860053593;16093.839061609468;16108.75836862448;8586.570671466401;8771.912280877237;8800.119988001277;8850.380473366802;8984.101589840964;7037.612931288726;8394.748157555283;8409.915900841035;8598.355123365773;8602.096779838026;7452.627368631547;7538.436931443335;7575.6969721210535;7581.279778421042;7603.885941710879;1781.4566557199919;2126.5109024924354;2131.7335333083365;2132.3496333618396;2147.3772605944414;923.676713150392;924.4176169013613;958.9260028767825;971.8007808399261;975.7999610080059;17024.914875425544;18490.722639160424;18572.349967751063;19303.710444343345;20793.272235471686;946.1248345280588;970.0929630795229;970.2722957781664;998.7453199434098;1001.5092604623682;10359.274850760525;10751.569937202581;10762.623308184176;10767.676969690921;10782.838257426145;635920.820395895;651619;656246.9103746552;672030;672831;148501;150799;151632;152076.47923520845;156367;1050.721558786922;1158.1487606609135;1172.5727743570897;1203.1517779965104;1207.4204381896914;16714.164291785604;17395.520895820635;17763.644727105126;17940.461786146436;18144.002079885733;61569.38430615637;62176.68911655413;64596.677016614616;65433;66135.33864661484;82291.17708822835;83907.16092838993;84860.15139848522;86842;87603.68594471215;8684.78288042804;8709.869351959853;9171.816563668823;12654.936725316682;12677.746445070863;12276.570320038756;13592.388342524808;13808.861911380758;13877.028607997747;13969.022168448111;11184.94407527957;11920;12204.267743935392;12242.265464072185;12330.938345308216;10554.577816887546;11353.318800871974;11396.658100257006;11492.885071149181;11716.121290903387;11922.52309907594;11947.283163010246;12093.81859272094;12307.44616492278;12368.628941131781;12692;12801.743965120831;13081.215127092404;13128.934355328161;13147.60557183286;11625.24435911663;11824.516851461289;12004.639860804189;12026.639200823989;12097.637070887888 22:46:27 INFO - PID 1637 | |3;mootools.html;1378.5244090788678;1439.3379045639006;1450.6735984403504;1469.302081511282;1470.7352676518221;11414.029807466364;11931.045516358692;11942.940285298571;11963.401829908513;11971.580994665188;11457.197996140274;11558.653240402802;11878.28730276184;11919;11922.701932451671;2494.07719143917;2528;2528.4816612594373;2535.733747956466;2537.5178716043984;2447.657327974085;2451.509698060386;2499.1377974598827;2502.324372419446;2502.799776017914;2238.630625946717;2343.5547246023284;2346.343023953296;2346.378209774411;2346.741858395575;2438.1222759806456;2489.7385774493687;2502.3744063984004;2515.4969006198835;2517.886695098725;2460.188137914502;2479.466914613367;2502.7622375874444;2511.460036092231;2512.635667828173;11747.00150487201;11854.525818967317;11857.347845868473;11864.762704745943;11895.405229738564;6441.581297215713;6687.364700353507;6693.598384096969;6710.3289671033;6729.932700673028;3336.8832090876704;3821.312163810513;4142.937855932163;4272;4310.202612516695;1023.3961962442121;1147.913906457019;1226.1049433913292;1254.8180513825444;1276.1577358943146;11914.46384912681;15515.301811418512;15641.608959775982;15654.608634784108;16506.917465412716;5328.17413300939;5463.48096930791;5614.550835933115;5670.943290567124;5700.429957004267;12306.138570299992;12321.322327271906;12325.630231093082;12325.815112773316;12362.938185309016;5503.889922201533;5509.311336082989;6553.115329430561;6555.115059466911;6559.180102487189;5980.611260268069;6082.422169893853;6102.359252278536;6150.938490615126;6202.472789812826;3568.678818906259;3622.8188590570367;4204.600562946515;4349.173657005159;4400.69195156336;1272;1272.4782839035952;1273.5036332309498;1288.7486940046601;1293.178831442041;734.5592644413416;746.6528064449948;766.7009866152205;770.3259647808168;802.4823988527368;5983.19226904371;6232.439080482688;6240.5319601030105;6249.500039996789;6323.652199129092;562.693332133988;570.8801151758178;855.1918437076903;872.1365847810622;925.2135684668112;3759.774413535197;3761.0785357587565;3798.848046078126;4047.777372244496;4301;14831.406743730346;15490;15502.457413990456;15512.301946412439;15538.922305388402;15249.23753812327;15394.461193858162;15406.537803865902;15442.0734755915;15464.690706185813;10499.160067194605;11008.504617292241;11017.83473247886;11025.669229923114;11026.117910567136;5719.28508936383;6284.340144284784;6466.482681385572;6516.478681705547;7123.928760712327;12893.871061289266;12944.41750121228;13048.869511304765;13352.933235333761;14023.789643155565;11949.522019119138;14020.948428867672;14217.715645686822;14304;14356.138631682132;8651.005134409372;8666.610002549774;8767.29861611057;8858.645654174019;8867.73396798097;10421.739456513344;10490.685279441628;10559.419231942316;10562.049415552638;10567.94716026415;8791.428557143765;9083.954580227057;9148.542572871464;9158.816823663356;9213.493257870881;6701.894187459015;8830.249428798625;8855.158759917798;8940.642374304955;8986.59560319774;6629.43649789754;8141.755747327589;8154.510729356258;8158.673653054048;8166.550839703873;5837.416258374129;5959.374265702038;6056.63660180373;6134.693265336805;6136.355682653436;6032.939670603413;6104.664243466652;6177;6230.345813689497;6237.875242495215 22:46:27 INFO - PID 1637 | |4;prototype.html;294.6331816887973;324.88466594359;388.6735142480316;390.16310015017785;399.83806558343883;5171.301874246976;5193.5325820676135;6017.699115044246;6037.667928263943;6092.725827337769;4337.240982828008;5625.1843482694985;5626.846496468224;5913.881722365551;5921;3740.5511338639403;4231.97884010581;4241.915161696764;4336.783160841961;4369.126174765044;3735.4023356263006;4118.423420721087;4224.493060832704;4328.372386004044;4356.847510337139;4294.291441912081;4302.354052257854;4328.783560821962;4335.176316499871;4351.347297905324;4200.705950583445;4291.592298731615;4297.720648157891;4342.97828510857;4358.215521206182;3316.9170770730925;3320.3857286402094;3546.2375589248436;3717.0893131183034;3730.850765969358;5222.477752224747;5335.439778823245;5450.890982180374;5460.7542660579975;5476;4233.216854881826;4855.635827312965;4859.58693511052;5108.565771909392;5133.794648214104;5160.974195129038;5568.66588004717;5799.884002319972;6188.319284878659;6260.217472815898;4231.132617813371;4245.980964568512;4247.638950689196;4257.531671516131;4258.6380157686635;6454.741810327581;6659.567128136656;6669.166354205724;6670.232923213795;6692.0296556998965;4302.397664326997;4314.245007123741;4321.2437823381415;4329.9216376139;4331.5018772840895;3742.7754334740002;4182.602652747984;4340.717853339523;4359.280718681445;4380.255356589388;4686.765661716969;5046.571041461517;5139.306193663882;5142.254373115952;5294.326365185557;5164.406093299243;6299.779507717208;6312.210973628296;6313.55805093639;6363.809085727436;1341.222091187109;1613.7095322841767;1624.4558073045425;1628.8534031937183;1632.4857669833964;1079.228351728518;1122.3995162587976;1131.3607811586428;1139.9626340030532;1150.223599070624;5945.997271377926;5972.462478376967;5980.461758441761;5993.4905533029305;6001.5198784097165;6184;6424.164858568356;6439.7424103035355;6450.870982580323;6472.902906456313;5314.867128321823;5448.019356515786;5889.526398912467;5915.3732723500525;5945.1973983512535;2901.405211931549;3345.3142105868237;3362.36115138123;3372.4941258811373;3377.223238655123;5029.4719054498755;5558.749856256478;6433.292337842833;6642.645464040628;6685.598864068172;5765.221695071195;5877.735501902339;6292.119103325538;6326.002458058175;6440.5933176846065;5327.760250788724;6527.3472652734345;6570.638614876132;6724.562903411263;6724.966375168288;5720.456556627114;6193.907091393723;6490.350964903472;6490.578112422678;6502.2443940136845;6326.7856250312025;6331.2085989251345;6342.460890824147;6391.392817682313;6404.615723056631;6596.559273068803;6604.339566043549;6635.568688035262;6762.7056818919355;6775.661216939035;3778.848846046127;4497.2129877272;4517.616002639812;4591.288350305708;4602.033572949585;5357.142857142839;5836.153757705194;6340.936590634219;6423.454006409507;6445;4758.690685105457;5960.701964901824;6091.543134264859;6267.561270711007;6317;5261.473852614861;5436.809711660073;6317.936820631735;6343.2406902997345;6363.236411630634;6193.81418557444;6227.349752315549;6230.6884655767935;6248.718807653576;6254.718537665725;5645.802396916089;6063.575549711654;6124.816255512342;6367.61794292344;6390.9041364378645;6217.689115544114;6220.2535695715;6220.626762394271;6224.751009959551;6228.875422491616 22:46:27 INFO - PID 1637 | |5;yui.html;800.2397722163942;818.0551463060166;834.3075247544539;846.1200351634301;847.292510753521;15700.057996520203;16119.355225790969;17899.284028638856;18660.06699665015;18820;17517.562060948483;17936.461906142806;18111.547211319725;18216.635667286646;18276.634467310647;978.5792109392958;993.4883534979491;995.8904520502739;996.1134590214715;997.1823105053859;963.7446076789656;992.0327680511516;1007.4056306778999;1010.8433192855083;1010.8989101089869;1063.984040239397;1067.978640427195;1070.5931745936573;1070.855434516342;1071.0414179309514;1060.167768301887;1060.9257351985364;1061.723951772537;1063.7340664833791;1064.106150833296;787.4802630264055;788.1566723605744;790.1387487638501;790.707438247852;790.7430085222268;16591.917040414843;16865.747013794804;17021.82978170215;17030.23363948613;17315.567110822205;14886.181260030695;14997.325120369609;15014.699706005928;15056.698866022727;15899.92050039754;13311.068225224239;13411.061225714204;13522.459101635923;13629.045966782329;13695.109817861907;3343.130785995634;3404.7616666833333;3405;3414.897553073412;3422.709069729076;105381;105729.47135264275;108435;109249;112830.61508154769;11376.829347559793;11462.891292820963;11539.19225654213;11711.707207319885;12023.819642705366;23795.524089518112;24124.63813042806;24774;24847.62728559073;24864.751352486244;8133.349332053422;8197.918020819716;8201.056878458934;8249.175082491704;8254.215849494289;4138.6482149017065;4142.047329114321;4209.452771139793;4219.873403797891;4256.808443620044;4230.1539692061715;4274.722143060691;4310.698251122454;4338.501072376654;4358.8038538265855;923.8475651517424;946.0255936385533;950.9809803803885;951.0537015669453;953.3803028031696;608.9482393996472;610.8472881779555;634.2832599163008;643.996780016097;674.844785699282;9977.25170612207;10011.649592264235;10222.795544089076;10277.747435528248;10526;577.5812535911515;602.9336772954971;610.4627927423838;629.8771739510843;646.6411141816337;4749.525047495223;4908.705477671351;4975.578310564564;5192.952563042032;5225.773971817528;86192.56903715442;96769;106644;107362;107604;84814;93112;93674;94459.05540944502;96107.03892961121;68488.28779280558;82738;83035;83182.16817831744;84175.15824841795;9561.426314421156;10665.786684266115;10832.945835270773;11005.339679619248;11017.228793984345;40126;41037.58962410457;41104;41627.583724162374;41733.16533669249;54687.45312546983;55609.44390556042;55805;56467.43532564622;56766;13262.27057511846;13455.932720336335;13463.865361346652;13497.322590579797;13944.58166255014;14370.353334099782;14456;14756.48352307707;14861.85138148648;15162.696746065238;13616.596350200725;13720.588382348546;13752.106113102616;13890.58328250154;14019.719605608036;13682.93763156186;13755.38100785487;14078.184363127211;14590.635234118807;14666.120032798066;13126.474941002252;13154.29996832448;13382.263975481443;13436.932815336251;13598.320083995959;9482.013165852733;9529.428234305964;9831.213502919893;9912.405255684682;10304.948475257575;8321.334293256647;10326.948365258126;10926.344419334866;11038.227324087236;11186.216964812385 22:46:27 INFO - PID 1637 | __end_tp_report 22:46:27 INFO - PID 1637 | __start_cc_report 22:46:27 INFO - PID 1637 | _x_x_mozilla_cycle_collect,2061 22:46:27 INFO - PID 1637 | __end_cc_report 22:46:27 INFO - PID 1637 | __startTimestamp1491543987676__endTimestamp 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | ------- Summary: start ------- 22:46:27 INFO - PID 1637 | Number of tests: 6 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6159.85 Median:6407.87 stddev:739.02 (11.5%) stddev-sans-first:711.18 22:46:27 INFO - PID 1637 | Values: 3498.2 3528.3 3535.9 3578.2 3608.5 6121.3 6171.0 6196.0 6227.8 6229.3 5096.7 6133.4 6163.4 6163.5 6205.8 5280.3 5350.3 5379.1 5416.7 5577.8 5518.3 5533.7 5547.3 5557.4 5560.3 5264.5 5364.5 5386.1 5390.2 5481.0 5358.3 5361.0 5361.2 5364.2 5374.6 5295.7 5342.2 5364.2 5379.4 5379.6 6180.8 6181.7 6184.7 6185.8 6209.1 6506.4 6512.4 6621.8 6681.0 6736.7 6790.7 6808.9 6820.2 6825.6 6838.5 6277.6 6287.5 6308.7 6311.6 6327.7 6650.7 6731.5 6742.9 6831.7 6987.8 6585.9 6596.7 6610.3 6617.0 6618.3 6729.8 6769.9 6770.7 6780.0 6790.0 6413.0 6476.0 6624.6 6638.5 6638.6 6440.3 6546.9 6587.6 6628.0 6629.1 5337.2 5367.6 5374.1 5388.5 5399.4 4735.9 4748.1 4751.1 4759.7 4768.0 6236.7 6249.8 6291.5 6304.6 6327.1 6741.6 6746.3 6774.9 6789.1 6799.2 6299.7 6317.1 6322.7 6344.1 6364.5 5799.7 5864.3 5877.7 5924.7 5967.4 6960.4 7022.9 7026.1 7034.5 7055.2 6507.3 6587.8 6608.1 6715.3 6738.2 6977.9 7052.1 7052.9 7058.1 7063.2 6627.4 6633.2 6645.7 6665.6 6670.4 6500.4 6514.2 6700.2 6700.5 6757.1 6535.3 6563.7 6566.8 6574.4 6579.7 6481.8 6493.1 6527.2 6546.2 6547.8 6234.9 6321.4 6407.9 6410.3 6414.6 5450.7 5459.2 6561.4 6587.1 6595.9 6379.6 6384.2 6406.8 6408.2 6412.6 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:44770.18 Median:33547.74 stddev:80237.19 (239.2%) stddev-sans-first:80394.01 22:46:27 INFO - PID 1637 | Values: 690.5 814.9 1136.7 1159.7 1160.1 36404.3 37268.6 37293.0 37318.4 37364.4 39405.6 39843.8 39846.8 39880.0 39935.6 6175.9 8580.7 8739.3 8742.0 8749.8 6243.6 8487.7 8706.9 8708.9 8711.3 8720.4 8779.4 8785.6 8785.9 8788.5 8017.2 8173.0 8693.3 8762.2 8795.1 8570.1 8641.8 8647.7 8654.5 8656.7 32651.7 35673.6 37661.0 37702.8 37794.0 3011.0 3300.9 3335.9 3370.2 3375.0 30167.0 30187.5 30281.6 30291.2 30313.5 3099.8 3120.7 3151.0 3154.3 3161.7 193921.0 262190.1 264845.0 265533.0 266026.0 36874.3 39725.6 48729.8 48842.3 48845.0 42731.1 50459.5 51124.0 51139.2 51293.7 13541.9 14390.6 14397.6 14400.9 14428.4 11565.4 11581.6 11582.8 11599.3 11633.7 12932.5 12945.0 12987.8 12989.4 13049.7 5234.4 5291.4 5297.3 5299.7 5304.9 928.2 952.3 963.5 978.7 996.6 22157.8 22412.4 23826.8 23876.7 23882.9 1055.6 1060.3 1083.4 1106.2 1114.5 12118.5 12120.5 12213.6 12311.1 12437.6 344127.0 345675.0 345834.0 346240.0 347114.0 51367.0 51432.7 51441.2 51485.0 51703.0 1442.3 1460.3 1503.4 1510.8 1516.1 23371.1 23412.0 23516.9 23530.8 23885.8 38326.6 38383.0 38493.2 38520.0 38604.6 307997.0 310236.0 310776.0 311212.0 311712.0 30114.8 30198.0 32600.5 33480.5 33615.0 41317.8 41654.8 41977.6 42308.4 42746.7 28320.4 28925.0 29312.0 29517.0 29651.6 28470.9 29389.9 29573.0 29849.1 30803.0 34588.3 35754.5 36021.8 36026.7 36149.7 28116.2 28864.7 29838.2 29930.0 30179.2 23133.8 23285.9 23355.6 23830.4 23927.8 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:38979.11 Median:2173.68 stddev:110228.29 (5071.0%) stddev-sans-first:110499.36 22:46:27 INFO - PID 1637 | Values: 357.6 391.9 397.4 419.2 430.8 14111.8 21363.7 22341.3 23006.9 23148.5 23087.0 23167.0 23283.2 23304.7 23507.9 5302.3 6047.8 6211.4 6295.6 6353.7 5299.4 6115.8 6252.1 6261.4 6265.2 3643.0 3756.2 5073.3 6260.2 6262.6 6052.4 6054.0 6237.9 6251.6 6256.6 5544.1 5553.1 5557.3 5558.0 5558.9 26993.0 27125.7 27176.0 27271.0 27822.4 2152.1 2195.3 2196.6 2199.0 2201.5 16908.0 17681.9 17708.5 17945.0 17972.6 2025.5 2073.3 2112.1 2116.1 2128.4 109481.5 138128.0 138159.0 139348.3 139916.0 11672.2 12050.3 12056.7 12061.2 12166.6 16062.4 16069.0 16085.6 16093.8 16108.8 8586.6 8771.9 8800.1 8850.4 8984.1 7037.6 8394.7 8409.9 8598.4 8602.1 7452.6 7538.4 7575.7 7581.3 7603.9 1781.5 2126.5 2131.7 2132.3 2147.4 923.7 924.4 958.9 971.8 975.8 17024.9 18490.7 18572.3 19303.7 20793.3 946.1 970.1 970.3 998.7 1001.5 10359.3 10751.6 10762.6 10767.7 10782.8 635920.8 651619.0 656246.9 672030.0 672831.0 148501.0 150799.0 151632.0 152076.5 156367.0 1050.7 1158.1 1172.6 1203.2 1207.4 16714.2 17395.5 17763.6 17940.5 18144.0 61569.4 62176.7 64596.7 65433.0 66135.3 82291.2 83907.2 84860.2 86842.0 87603.7 8684.8 8709.9 9171.8 12654.9 12677.7 12276.6 13592.4 13808.9 13877.0 13969.0 11184.9 11920.0 12204.3 12242.3 12330.9 10554.6 11353.3 11396.7 11492.9 11716.1 11922.5 11947.3 12093.8 12307.4 12368.6 12692.0 12801.7 13081.2 13128.9 13147.6 11625.2 11824.5 12004.6 12026.6 12097.6 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7086.98 Median:2522.94 stddev:4557.74 (180.7%) stddev-sans-first:4550.34 22:46:27 INFO - PID 1637 | Values: 1378.5 1439.3 1450.7 1469.3 1470.7 11414.0 11931.0 11942.9 11963.4 11971.6 11457.2 11558.7 11878.3 11919.0 11922.7 2494.1 2528.0 2528.5 2535.7 2537.5 2447.7 2451.5 2499.1 2502.3 2502.8 2238.6 2343.6 2346.3 2346.4 2346.7 2438.1 2489.7 2502.4 2515.5 2517.9 2460.2 2479.5 2502.8 2511.5 2512.6 11747.0 11854.5 11857.3 11864.8 11895.4 6441.6 6687.4 6693.6 6710.3 6729.9 3336.9 3821.3 4142.9 4272.0 4310.2 1023.4 1147.9 1226.1 1254.8 1276.2 11914.5 15515.3 15641.6 15654.6 16506.9 5328.2 5463.5 5614.6 5670.9 5700.4 12306.1 12321.3 12325.6 12325.8 12362.9 5503.9 5509.3 6553.1 6555.1 6559.2 5980.6 6082.4 6102.4 6150.9 6202.5 3568.7 3622.8 4204.6 4349.2 4400.7 1272.0 1272.5 1273.5 1288.7 1293.2 734.6 746.7 766.7 770.3 802.5 5983.2 6232.4 6240.5 6249.5 6323.7 562.7 570.9 855.2 872.1 925.2 3759.8 3761.1 3798.8 4047.8 4301.0 14831.4 15490.0 15502.5 15512.3 15538.9 15249.2 15394.5 15406.5 15442.1 15464.7 10499.2 11008.5 11017.8 11025.7 11026.1 5719.3 6284.3 6466.5 6516.5 7123.9 12893.9 12944.4 13048.9 13352.9 14023.8 11949.5 14020.9 14217.7 14304.0 14356.1 8651.0 8666.6 8767.3 8858.6 8867.7 10421.7 10490.7 10559.4 10562.0 10567.9 8791.4 9084.0 9148.5 9158.8 9213.5 6701.9 8830.2 8855.2 8940.6 8986.6 6629.4 8141.8 8154.5 8158.7 8166.6 5837.4 5959.4 6056.6 6134.7 6136.4 6032.9 6104.7 6177.0 6230.3 6237.9 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5039.38 Median:5455.82 stddev:1563.43 (28.7%) stddev-sans-first:1526.72 22:46:27 INFO - PID 1637 | Values: 294.6 324.9 388.7 390.2 399.8 5171.3 5193.5 6017.7 6037.7 6092.7 4337.2 5625.2 5626.8 5913.9 5921.0 3740.6 4232.0 4241.9 4336.8 4369.1 3735.4 4118.4 4224.5 4328.4 4356.8 4294.3 4302.4 4328.8 4335.2 4351.3 4200.7 4291.6 4297.7 4343.0 4358.2 3316.9 3320.4 3546.2 3717.1 3730.9 5222.5 5335.4 5450.9 5460.8 5476.0 4233.2 4855.6 4859.6 5108.6 5133.8 5161.0 5568.7 5799.9 6188.3 6260.2 4231.1 4246.0 4247.6 4257.5 4258.6 6454.7 6659.6 6669.2 6670.2 6692.0 4302.4 4314.2 4321.2 4329.9 4331.5 3742.8 4182.6 4340.7 4359.3 4380.3 4686.8 5046.6 5139.3 5142.3 5294.3 5164.4 6299.8 6312.2 6313.6 6363.8 1341.2 1613.7 1624.5 1628.9 1632.5 1079.2 1122.4 1131.4 1140.0 1150.2 5946.0 5972.5 5980.5 5993.5 6001.5 6184.0 6424.2 6439.7 6450.9 6472.9 5314.9 5448.0 5889.5 5915.4 5945.2 2901.4 3345.3 3362.4 3372.5 3377.2 5029.5 5558.7 6433.3 6642.6 6685.6 5765.2 5877.7 6292.1 6326.0 6440.6 5327.8 6527.3 6570.6 6724.6 6725.0 5720.5 6193.9 6490.4 6490.6 6502.2 6326.8 6331.2 6342.5 6391.4 6404.6 6596.6 6604.3 6635.6 6762.7 6775.7 3778.8 4497.2 4517.6 4591.3 4602.0 5357.1 5836.2 6340.9 6423.5 6445.0 4758.7 5960.7 6091.5 6267.6 6317.0 5261.5 5436.8 6317.9 6343.2 6363.2 6193.8 6227.3 6230.7 6248.7 6254.7 5645.8 6063.6 6124.8 6367.6 6390.9 6217.7 6220.3 6220.6 6224.8 6228.9 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:20549.17 Median:24449.32 stddev:29072.26 (118.9%) stddev-sans-first:29115.99 22:46:27 INFO - PID 1637 | Values: 800.2 818.1 834.3 846.1 847.3 15700.1 16119.4 17899.3 18660.1 18820.0 17517.6 17936.5 18111.5 18216.6 18276.6 978.6 993.5 995.9 996.1 997.2 963.7 992.0 1007.4 1010.8 1010.9 1064.0 1068.0 1070.6 1070.9 1071.0 1060.2 1060.9 1061.7 1063.7 1064.1 787.5 788.2 790.1 790.7 790.7 16591.9 16865.7 17021.8 17030.2 17315.6 14886.2 14997.3 15014.7 15056.7 15899.9 13311.1 13411.1 13522.5 13629.0 13695.1 3343.1 3404.8 3405.0 3414.9 3422.7 105381.0 105729.5 108435.0 109249.0 112830.6 11376.8 11462.9 11539.2 11711.7 12023.8 23795.5 24124.6 24774.0 24847.6 24864.8 8133.3 8197.9 8201.1 8249.2 8254.2 4138.6 4142.0 4209.5 4219.9 4256.8 4230.2 4274.7 4310.7 4338.5 4358.8 923.8 946.0 951.0 951.1 953.4 608.9 610.8 634.3 644.0 674.8 9977.3 10011.6 10222.8 10277.7 10526.0 577.6 602.9 610.5 629.9 646.6 4749.5 4908.7 4975.6 5193.0 5225.8 86192.6 96769.0 106644.0 107362.0 107604.0 84814.0 93112.0 93674.0 94459.1 96107.0 68488.3 82738.0 83035.0 83182.2 84175.2 9561.4 10665.8 10832.9 11005.3 11017.2 40126.0 41037.6 41104.0 41627.6 41733.2 54687.5 55609.4 55805.0 56467.4 56766.0 13262.3 13455.9 13463.9 13497.3 13944.6 14370.4 14456.0 14756.5 14861.9 15162.7 13616.6 13720.6 13752.1 13890.6 14019.7 13682.9 13755.4 14078.2 14590.6 14666.1 13126.5 13154.3 13382.3 13436.9 13598.3 9482.0 9529.4 9831.2 9912.4 10304.9 8321.3 10326.9 10926.3 11038.2 11186.2 22:46:27 INFO - PID 1637 | -------- Summary: end -------- 22:46:27 INFO - PID 1637 | 22:46:27 INFO - PID 1637 | 1491543987704 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 22:46:28 INFO - TEST-INFO | 1637: exit 0 22:46:28 INFO - TEST-OK | dromaeo_css | took 1121089ms 22:46:28 INFO - TEST-START | kraken 22:46:28 INFO - operating with platform_type : mac_ 22:46:28 INFO - Initialising browser for kraken test... 22:46:28 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpRWfE1_/profile http://localhost:49240/getInfo.html) 22:46:28 INFO - PID 1648 | 1491543988954 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 22:46:28 INFO - PID 1648 | 1491543988955 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 22:46:29 INFO - PID 1648 | 1491543989007 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 22:46:31 INFO - PID 1648 | __metrics Screen width/height:1600/1200 22:46:31 INFO - PID 1648 | colorDepth:24 22:46:31 INFO - PID 1648 | Browser inner width/height: 1024/689 22:46:31 INFO - PID 1648 | __metrics 22:46:31 INFO - PID 1648 | 1491543991151 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 22:46:31 INFO - PID 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:46:31 INFO - PID 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:46:32 INFO - TEST-INFO | 1648: exit 0 22:46:32 INFO - Browser initialized. 22:46:32 INFO - Running cycle 1/1 for kraken test... 22:46:32 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpRWfE1_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:32 INFO - PID 1649 | 1491543992588 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 22:46:32 INFO - PID 1649 | 1491543992589 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 22:46:44 INFO - PID 1649 | RSS: Main: 237895680 22:46:44 INFO - PID 1649 | 22:46:47 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 22:46:48 INFO - PID 1649 | RSS: Main: 292298752 22:46:48 INFO - PID 1649 | 22:46:51 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 22:46:51 INFO - PID 1649 | RSS: Main: 292106240 22:46:51 INFO - PID 1649 | 22:46:55 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 22:46:55 INFO - PID 1649 | RSS: Main: 386383872 22:46:55 INFO - PID 1649 | 22:46:58 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 22:46:58 INFO - PID 1649 | RSS: Main: 287150080 22:46:58 INFO - PID 1649 | 22:47:00 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:47:01 INFO - PID 1649 | RSS: Main: 291676160 22:47:01 INFO - PID 1649 | 22:47:04 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 22:47:05 INFO - PID 1649 | RSS: Main: 333213696 22:47:05 INFO - PID 1649 | 22:47:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 22:47:09 INFO - PID 1649 | RSS: Main: 348467200 22:47:09 INFO - PID 1649 | 22:47:12 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 22:47:12 INFO - PID 1649 | RSS: Main: 361742336 22:47:12 INFO - PID 1649 | 22:47:14 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:47:14 INFO - PID 1649 | RSS: Main: 308207616 22:47:14 INFO - PID 1649 | 22:47:16 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:47:17 INFO - PID 1649 | RSS: Main: 312795136 22:47:17 INFO - PID 1649 | 22:47:19 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:47:20 INFO - PID 1649 | RSS: Main: 314724352 22:47:20 INFO - PID 1649 | 22:47:23 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:47:24 INFO - PID 1649 | RSS: Main: 333361152 22:47:24 INFO - PID 1649 | 22:47:28 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:47:28 INFO - PID 1649 | RSS: Main: 316362752 22:47:28 INFO - PID 1649 | 22:47:30 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 22:47:31 INFO - PID 1649 | RSS: Main: 382337024 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | __start_tp_report 22:47:31 INFO - PID 1649 | _x_x_mozilla_page_load 22:47:31 INFO - PID 1649 | _x_x_mozilla_page_load_details 22:47:31 INFO - PID 1649 | |i|pagename|runs| 22:47:31 INFO - PID 1649 | |0;ai-astar;111;123;123;124;123;124;124;124;125;123 22:47:31 INFO - PID 1649 | |1;audio-beat-detection;156;119;117;161;117;121;123;114;142;118 22:47:31 INFO - PID 1649 | |2;audio-dft;219;208;216;220;212;216;209;212;212;215 22:47:31 INFO - PID 1649 | |3;audio-fft;84;63;65;106;94;63;67;66;81;65 22:47:31 INFO - PID 1649 | |4;audio-oscillator;97;75;95;76;75;76;94;75;74;91 22:47:31 INFO - PID 1649 | |5;imaging-gaussian-blur;93;92;94;108;136;94;93;94;94;92 22:47:31 INFO - PID 1649 | |6;imaging-darkroom;103;102;102;102;101;102;102;101;102;102 22:47:31 INFO - PID 1649 | |7;imaging-desaturate;102;100;101;101;102;100;101;100;100;102 22:47:31 INFO - PID 1649 | |8;json-parse-financial;52;51;52;52;52;54;52;52;51;51 22:47:31 INFO - PID 1649 | |9;json-stringify-tinderbox;57;65;64;71;66;65;62;64;62;63 22:47:31 INFO - PID 1649 | |10;stanford-crypto-aes;95;94;107;118;120;95;90;93;92;90 22:47:31 INFO - PID 1649 | |11;stanford-crypto-ccm;154;157;175;178;156;159;169;171;233;199 22:47:31 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;201;207;258;199;199;206;206;200;204;204 22:47:31 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;74;80;105;102;107;74;76;75;73;74 22:47:31 INFO - PID 1649 | __end_tp_report 22:47:31 INFO - PID 1649 | __start_cc_report 22:47:31 INFO - PID 1649 | _x_x_mozilla_cycle_collect,2192 22:47:31 INFO - PID 1649 | __end_cc_report 22:47:31 INFO - PID 1649 | __startTimestamp1491544051020__endTimestamp 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | ------- Summary: start ------- 22:47:31 INFO - PID 1649 | Number of tests: 14 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:122.40 Median:124.00 stddev:4.06 (3.3%) stddev-sans-first:0.71 22:47:31 INFO - PID 1649 | Values: 111.0 123.0 123.0 124.0 123.0 124.0 124.0 124.0 125.0 123.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:122.00 stddev:17.50 (14.3%) stddev-sans-first:15.55 22:47:31 INFO - PID 1649 | Values: 156.0 119.0 117.0 161.0 117.0 121.0 123.0 114.0 142.0 118.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:213.90 Median:215.50 stddev:3.98 (1.8%) stddev-sans-first:3.77 22:47:31 INFO - PID 1649 | Values: 219.0 208.0 216.0 220.0 212.0 216.0 209.0 212.0 212.0 215.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:75.40 Median:66.50 stddev:15.17 (22.8%) stddev-sans-first:15.76 22:47:31 INFO - PID 1649 | Values: 84.0 63.0 65.0 106.0 94.0 63.0 67.0 66.0 81.0 65.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:83.50 stddev:9.98 (11.9%) stddev-sans-first:9.16 22:47:31 INFO - PID 1649 | Values: 97.0 75.0 95.0 76.0 75.0 76.0 94.0 75.0 74.0 91.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:93.50 stddev:13.82 (14.8%) stddev-sans-first:14.49 22:47:31 INFO - PID 1649 | Values: 93.0 92.0 94.0 108.0 136.0 94.0 93.0 94.0 94.0 92.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:102.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 22:47:31 INFO - PID 1649 | Values: 103.0 102.0 102.0 102.0 101.0 102.0 102.0 101.0 102.0 102.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:101.00 stddev:0.88 (0.9%) stddev-sans-first:0.83 22:47:31 INFO - PID 1649 | Values: 102.0 100.0 101.0 101.0 102.0 100.0 101.0 100.0 100.0 102.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:0.88 (1.7%) stddev-sans-first:0.93 22:47:31 INFO - PID 1649 | Values: 52.0 51.0 52.0 52.0 52.0 54.0 52.0 52.0 51.0 51.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.50 stddev:3.54 (5.5%) stddev-sans-first:2.74 22:47:31 INFO - PID 1649 | Values: 57.0 65.0 64.0 71.0 66.0 65.0 62.0 64.0 62.0 63.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:99.40 Median:92.50 stddev:11.39 (12.3%) stddev-sans-first:11.97 22:47:31 INFO - PID 1649 | Values: 95.0 94.0 107.0 118.0 120.0 95.0 90.0 93.0 92.0 90.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:175.10 Median:173.00 stddev:24.46 (14.1%) stddev-sans-first:24.72 22:47:31 INFO - PID 1649 | Values: 154.0 157.0 175.0 178.0 156.0 159.0 169.0 171.0 233.0 199.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.40 Median:205.00 stddev:17.68 (8.6%) stddev-sans-first:18.55 22:47:31 INFO - PID 1649 | Values: 201.0 207.0 258.0 199.0 199.0 206.0 206.0 200.0 204.0 204.0 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.00 Median:74.00 stddev:14.44 (19.5%) stddev-sans-first:14.85 22:47:31 INFO - PID 1649 | Values: 74.0 80.0 105.0 102.0 107.0 74.0 76.0 75.0 73.0 74.0 22:47:31 INFO - PID 1649 | -------- Summary: end -------- 22:47:31 INFO - PID 1649 | 22:47:31 INFO - PID 1649 | 1491544051045 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 22:47:32 INFO - TEST-INFO | 1649: exit 0 22:47:32 INFO - TEST-OK | kraken | took 63776ms 22:47:32 INFO - SUITE-END | took 1184s 22:47:32 INFO - Completed test suite (00:19:44) 22:47:32 INFO - javascript benchmark 22:47:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7715.377126817612, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3498.2478767065086, 3528.347255757685, 3535.9469607955903, 3578.2485678007615, 3608.4767708682243, 6121.265448146229, 6171.0, 6195.9999999999945, 6227.750889964402, 6229.252489701236, 5096.694198348093, 6133.426475724768, 6163.352847950968, 6163.476104531121, 6205.7517699291975, 5280.313559237294, 5350.3312085989255, 5379.058664733666, 5416.729163541827, 5577.8047768328115, 5518.2826232589705, 5533.734921720212, 5547.25112109866, 5557.444255574451, 5560.332760068797, 5264.52619264268, 5364.544013758835, 5386.057439947994, 5390.164524498709, 5480.99999999998, 5358.276632654581, 5360.973195134038, 5361.2226227197225, 5364.222187782789, 5374.5700343972385, 5295.735213239323, 5342.198670199462, 5364.195370694388, 5379.35447746273, 5379.623426360156, 6180.81457556274, 6181.6909154542545, 6184.690765461709, 6185.8144255672405, 6209.068639704081, 6506.381893720137, 6512.446442052384, 6621.768238111643, 6680.966595166993, 6736.730530778763, 6790.694418751119, 6808.931910680929, 6820.181578210647, 6825.590464572141, 6838.521303508756, 6277.623342599413, 6287.528435367366, 6308.716107775161, 6311.558190926637, 6327.683615819236, 6650.70071846768, 6731.528792984542, 6742.865142697118, 6831.726730930806, 6987.755428559976, 6585.9012114818315, 6596.670166491656, 6610.30591787865, 6616.966915165393, 6618.305077966744, 6729.798106056728, 6769.898451523231, 6770.661466926634, 6779.966100169468, 6789.966050169816, 6413.0, 6476.0, 6624.6025238485845, 6638.502112341497, 6638.5684930479365, 6440.2915679276175, 6546.9017964730565, 6587.571807832572, 6627.966860165765, 6629.071929929813, 5337.172738225581, 5367.597430192751, 5374.113271310268, 5388.515033646991, 5399.37907140676, 4735.905281894343, 4748.074125545554, 4751.121042607128, 4759.738214398172, 4767.952320476751, 6236.719347629368, 6249.781257656051, 6291.496680265568, 6304.621722696653, 6327.082573026885, 6741.561798483181, 6746.257911629717, 6774.898376524356, 6789.0834737310815, 6799.184097908283, 6299.748010079546, 6317.147185129948, 6322.652254126067, 6344.111824344595, 6364.459020983176, 5799.681017543991, 5864.325602555676, 5877.706114694248, 5924.733386997509, 5967.403259674084, 6960.443164546926, 7022.859542809218, 7026.051483049927, 7034.5427547209265, 7055.153381594241, 6507.316731743288, 6587.769428069994, 6608.1079054328975, 6715.328467153441, 6738.157730283715, 6977.895331569929, 7052.0832291801735, 7052.858942821217, 7058.082449281561, 7063.187733410724, 6627.436667883284, 6633.170853643294, 6645.734170633121, 6665.633390163587, 6670.39966403026, 6500.382463665944, 6514.153160089348, 6700.229473610597, 6700.5309628327495, 6757.12157419552, 6535.3137920517665, 6563.671816409256, 6566.8358291043105, 6574.408303252539, 6579.671016449062, 6481.837954050998, 6493.090967264681, 6527.249366322933, 6546.247181573989, 6547.836304092436, 6234.937650623436, 6321.399467050623, 6407.8718425630295, 6410.326915673787, 6414.583052101785, 5450.672959622435, 5459.208414779755, 6561.40947314744, 6587.143671322698, 6595.934040659532, 6379.553431259767, 6384.201974753156, 6406.775762848278, 6408.231012278557, 6412.61524308543], "value": 6109.46565736341, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.4855882367635, 814.9144339844316, 1136.6817291158472, 1159.6868845411739, 1160.141495293483, 36404.27191456172, 37268.62731372686, 37293.0, 37318.4402233966, 37364.439533406956, 39405.60594394062, 39843.800780996055, 39846.8007659962, 39880.0, 39935.60064399355, 6175.907361389561, 8580.656773729059, 8739.257163141141, 8741.956290218572, 8749.781255468632, 6243.594166379194, 8487.66049358025, 8706.868107146094, 8708.869366959501, 8711.346649001318, 8720.43317184384, 8779.429337093101, 8785.560721963908, 8785.912140878574, 8788.47269163849, 8017.238362355625, 8173.0, 8693.304535637133, 8762.167594078552, 8795.076516965755, 8570.100139485388, 8641.827163456759, 8647.65409383624, 8654.48073115615, 8656.6537338507, 32651.69393224268, 35673.64326356729, 37661.0, 37702.81148594267, 37794.0, 3010.9913179084815, 3300.884469043572, 3335.949960750591, 3370.208001119735, 3375.038114137459, 30167.0, 30187.547186792217, 30281.63732632015, 30291.242718931982, 30313.545296820565, 3099.767517436201, 3120.6567277599447, 3151.038933125396, 3154.3375891062906, 3161.652218256013, 193921.0, 262190.0671489929, 264845.0, 265533.0, 266025.9999999981, 36874.26251474956, 39725.60274397277, 48729.75635121802, 48842.29051983164, 48845.0, 42731.14537709291, 50459.49540504621, 51124.0, 51139.232911506355, 51293.7435312821, 13541.86458135406, 14390.640233994234, 14397.568072957829, 14400.927995359956, 14428.350724217435, 11565.363904985139, 11581.594644187413, 11582.76834463306, 11599.304041757521, 11633.6509904703, 12932.547360842325, 12944.990274799224, 12987.805182922264, 12989.350532473338, 13049.673758156123, 5234.3718753749035, 5291.391489978701, 5297.278384525045, 5299.740659494383, 5304.867378315573, 928.2388441477923, 952.2953014769159, 963.5037955452807, 978.7014960437135, 996.5515518016921, 22157.778422215895, 22412.439689007904, 23826.761732382452, 23876.686782227145, 23882.880585596962, 1055.5619417941623, 1060.3266925502362, 1083.3932997521413, 1106.1758989552675, 1114.4817659788237, 12118.454669539891, 12120.45457954394, 12213.572524961584, 12311.076669249842, 12437.62687119388, 344127.0, 345675.0, 345834.0, 346240.0, 347114.0, 51367.0, 51432.74283628558, 51441.22838157431, 51485.0, 51703.0, 1442.2932762946082, 1460.2552698124034, 1503.4211828446128, 1510.7960425342658, 1516.0600427734876, 23371.078615173104, 23412.0, 23516.882415587814, 23530.764692353543, 23885.761142388354, 38326.61673383231, 38383.0, 38493.230135397695, 38520.036999074124, 38604.64883729056, 307997.0, 310236.0, 310776.0, 311212.0, 311711.9729612218, 30114.79540818343, 30198.0, 32600.510992335607, 33480.49779253362, 33614.99155025353, 41317.793411032755, 41654.79172604118, 41977.580224197365, 42308.36537452002, 42746.717598472096, 28320.43359132847, 28925.0, 29312.0, 29517.0, 29651.555226671186, 28470.85764571164, 29389.853050735466, 29573.0, 29849.10452686423, 30803.0, 34588.30823383568, 35754.463683044254, 36021.81989090038, 36026.739064133675, 36149.73475928435, 28116.156515304574, 28864.711352887043, 29838.165374290274, 29930.0, 30179.245518862204, 23133.768662313163, 23285.88357058204, 23355.649665255376, 23830.404239894142, 23927.76072239255], "value": 17169.273712185004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.561986566456, 391.9196564704236, 397.4495323976292, 419.1700433142376, 430.8212091981829, 14111.788323175148, 21363.679544806822, 22341.329760107183, 23006.88496557519, 23148.537029259427, 23087.0, 23167.0, 23283.185088521906, 23304.65043024356, 23507.882460587673, 5302.2841916341185, 6047.848803779919, 6211.378862113797, 6295.622262664255, 6353.745850165988, 5299.390570084451, 6115.8165255042195, 6252.06219067139, 6261.373862613747, 6265.185525881629, 3642.981785091071, 3756.229972855558, 5073.264376665381, 6260.18617579714, 6262.561620686553, 6052.394760523913, 6054.031354983225, 6237.875242495147, 6251.624902505864, 6256.624602523841, 5544.085225937715, 5553.055980483287, 5557.277553918006, 5557.972210138923, 5558.86102847428, 26993.0, 27125.744910610854, 27176.0, 27271.0, 27822.443551129065, 2152.0853637204127, 2195.2755590655206, 2196.560687862434, 2198.9890050549643, 2201.5486825200796, 16908.0, 17681.911590442094, 17708.468745937644, 17945.0, 17972.55068623282, 2025.48350170707, 2073.2536286936693, 2112.091800525773, 2116.1429621003363, 2128.4146859613575, 109481.45259273732, 138127.99999999898, 138159.0, 139348.30325848307, 139916.0, 11672.241304315192, 12050.276983381025, 12056.698582535331, 12061.216020958609, 12166.57416990401, 16062.357505699874, 16069.0, 16085.597860053593, 16093.839061609468, 16108.75836862448, 8586.570671466401, 8771.912280877237, 8800.119988001277, 8850.380473366802, 8984.101589840964, 7037.612931288726, 8394.748157555283, 8409.915900841035, 8598.355123365773, 8602.096779838026, 7452.627368631547, 7538.436931443335, 7575.6969721210535, 7581.279778421042, 7603.885941710879, 1781.4566557199919, 2126.5109024924354, 2131.7335333083365, 2132.3496333618396, 2147.3772605944414, 923.676713150392, 924.4176169013613, 958.9260028767825, 971.8007808399261, 975.7999610080059, 17024.914875425544, 18490.722639160424, 18572.349967751063, 19303.710444343345, 20793.272235471686, 946.1248345280588, 970.0929630795229, 970.2722957781664, 998.7453199434098, 1001.5092604623682, 10359.274850760525, 10751.569937202581, 10762.623308184176, 10767.676969690921, 10782.838257426145, 635920.820395895, 651619.0, 656246.9103746552, 672030.0, 672831.0, 148501.0, 150799.0, 151632.0, 152076.47923520845, 156367.0, 1050.721558786922, 1158.1487606609135, 1172.5727743570897, 1203.1517779965104, 1207.4204381896914, 16714.164291785604, 17395.520895820635, 17763.644727105126, 17940.461786146436, 18144.002079885733, 61569.38430615637, 62176.68911655413, 64596.677016614616, 65433.0, 66135.33864661484, 82291.17708822835, 83907.16092838993, 84860.15139848522, 86842.0, 87603.68594471215, 8684.78288042804, 8709.869351959853, 9171.816563668823, 12654.936725316682, 12677.746445070863, 12276.570320038756, 13592.388342524808, 13808.861911380758, 13877.028607997747, 13969.022168448111, 11184.94407527957, 11920.0, 12204.267743935392, 12242.265464072185, 12330.938345308216, 10554.577816887546, 11353.318800871974, 11396.658100257006, 11492.885071149181, 11716.121290903387, 11922.52309907594, 11947.283163010246, 12093.81859272094, 12307.44616492278, 12368.628941131781, 12692.0, 12801.743965120831, 13081.215127092404, 13128.934355328161, 13147.60557183286, 11625.24435911663, 11824.516851461289, 12004.639860804189, 12026.639200823989, 12097.637070887888], "value": 10495.533709008578, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.5244090788678, 1439.3379045639006, 1450.6735984403504, 1469.302081511282, 1470.7352676518221, 11414.029807466364, 11931.045516358692, 11942.940285298571, 11963.401829908513, 11971.580994665188, 11457.197996140274, 11558.653240402802, 11878.28730276184, 11919.0, 11922.701932451671, 2494.07719143917, 2528.0, 2528.4816612594373, 2535.733747956466, 2537.5178716043984, 2447.657327974085, 2451.509698060386, 2499.1377974598827, 2502.324372419446, 2502.799776017914, 2238.630625946717, 2343.5547246023284, 2346.343023953296, 2346.378209774411, 2346.741858395575, 2438.1222759806456, 2489.7385774493687, 2502.3744063984004, 2515.4969006198835, 2517.886695098725, 2460.188137914502, 2479.466914613367, 2502.7622375874444, 2511.460036092231, 2512.635667828173, 11747.00150487201, 11854.525818967317, 11857.347845868473, 11864.762704745943, 11895.405229738564, 6441.581297215713, 6687.364700353507, 6693.598384096969, 6710.3289671033, 6729.932700673028, 3336.8832090876704, 3821.312163810513, 4142.937855932163, 4272.0, 4310.202612516695, 1023.3961962442121, 1147.913906457019, 1226.1049433913292, 1254.8180513825444, 1276.1577358943146, 11914.46384912681, 15515.301811418512, 15641.608959775982, 15654.608634784108, 16506.917465412716, 5328.17413300939, 5463.48096930791, 5614.550835933115, 5670.943290567124, 5700.429957004267, 12306.138570299992, 12321.322327271906, 12325.630231093082, 12325.815112773316, 12362.938185309016, 5503.889922201533, 5509.311336082989, 6553.115329430561, 6555.115059466911, 6559.180102487189, 5980.611260268069, 6082.422169893853, 6102.359252278536, 6150.938490615126, 6202.472789812826, 3568.678818906259, 3622.8188590570367, 4204.600562946515, 4349.173657005159, 4400.69195156336, 1272.0, 1272.4782839035952, 1273.5036332309498, 1288.7486940046601, 1293.178831442041, 734.5592644413416, 746.6528064449948, 766.7009866152205, 770.3259647808168, 802.4823988527368, 5983.19226904371, 6232.439080482688, 6240.5319601030105, 6249.500039996789, 6323.652199129092, 562.693332133988, 570.8801151758178, 855.1918437076903, 872.1365847810622, 925.2135684668112, 3759.774413535197, 3761.0785357587565, 3798.848046078126, 4047.777372244496, 4301.0, 14831.406743730346, 15490.0, 15502.457413990456, 15512.301946412439, 15538.922305388402, 15249.23753812327, 15394.461193858162, 15406.537803865902, 15442.0734755915, 15464.690706185813, 10499.160067194605, 11008.504617292241, 11017.83473247886, 11025.669229923114, 11026.117910567136, 5719.28508936383, 6284.340144284784, 6466.482681385572, 6516.478681705547, 7123.928760712327, 12893.871061289266, 12944.41750121228, 13048.869511304765, 13352.933235333761, 14023.789643155565, 11949.522019119138, 14020.948428867672, 14217.715645686822, 14304.0, 14356.138631682132, 8651.005134409372, 8666.610002549774, 8767.29861611057, 8858.645654174019, 8867.73396798097, 10421.739456513344, 10490.685279441628, 10559.419231942316, 10562.049415552638, 10567.94716026415, 8791.428557143765, 9083.954580227057, 9148.542572871464, 9158.816823663356, 9213.493257870881, 6701.894187459015, 8830.249428798625, 8855.158759917798, 8940.642374304955, 8986.59560319774, 6629.43649789754, 8141.755747327589, 8154.510729356258, 8158.673653054048, 8166.550839703873, 5837.416258374129, 5959.374265702038, 6056.63660180373, 6134.693265336805, 6136.355682653436, 6032.939670603413, 6104.664243466652, 6177.0, 6230.345813689497, 6237.875242495215], "value": 5300.295379981783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.6331816887973, 324.88466594359, 388.6735142480316, 390.16310015017785, 399.83806558343883, 5171.301874246976, 5193.5325820676135, 6017.699115044246, 6037.667928263943, 6092.725827337769, 4337.240982828008, 5625.1843482694985, 5626.846496468224, 5913.881722365551, 5921.0, 3740.5511338639403, 4231.97884010581, 4241.915161696764, 4336.783160841961, 4369.126174765044, 3735.4023356263006, 4118.423420721087, 4224.493060832704, 4328.372386004044, 4356.847510337139, 4294.291441912081, 4302.354052257854, 4328.783560821962, 4335.176316499871, 4351.347297905324, 4200.705950583445, 4291.592298731615, 4297.720648157891, 4342.97828510857, 4358.215521206182, 3316.9170770730925, 3320.3857286402094, 3546.2375589248436, 3717.0893131183034, 3730.850765969358, 5222.477752224747, 5335.439778823245, 5450.890982180374, 5460.7542660579975, 5476.0, 4233.216854881826, 4855.635827312965, 4859.58693511052, 5108.565771909392, 5133.794648214104, 5160.974195129038, 5568.66588004717, 5799.884002319972, 6188.319284878659, 6260.217472815898, 4231.132617813371, 4245.980964568512, 4247.638950689196, 4257.531671516131, 4258.6380157686635, 6454.741810327581, 6659.567128136656, 6669.166354205724, 6670.232923213795, 6692.0296556998965, 4302.397664326997, 4314.245007123741, 4321.2437823381415, 4329.9216376139, 4331.5018772840895, 3742.7754334740002, 4182.602652747984, 4340.717853339523, 4359.280718681445, 4380.255356589388, 4686.765661716969, 5046.571041461517, 5139.306193663882, 5142.254373115952, 5294.326365185557, 5164.406093299243, 6299.779507717208, 6312.210973628296, 6313.55805093639, 6363.809085727436, 1341.222091187109, 1613.7095322841767, 1624.4558073045425, 1628.8534031937183, 1632.4857669833964, 1079.228351728518, 1122.3995162587976, 1131.3607811586428, 1139.9626340030532, 1150.223599070624, 5945.997271377926, 5972.462478376967, 5980.461758441761, 5993.4905533029305, 6001.5198784097165, 6184.0, 6424.164858568356, 6439.7424103035355, 6450.870982580323, 6472.902906456313, 5314.867128321823, 5448.019356515786, 5889.526398912467, 5915.3732723500525, 5945.1973983512535, 2901.405211931549, 3345.3142105868237, 3362.36115138123, 3372.4941258811373, 3377.223238655123, 5029.4719054498755, 5558.749856256478, 6433.292337842833, 6642.645464040628, 6685.598864068172, 5765.221695071195, 5877.735501902339, 6292.119103325538, 6326.002458058175, 6440.5933176846065, 5327.760250788724, 6527.3472652734345, 6570.638614876132, 6724.562903411263, 6724.966375168288, 5720.456556627114, 6193.907091393723, 6490.350964903472, 6490.578112422678, 6502.2443940136845, 6326.7856250312025, 6331.2085989251345, 6342.460890824147, 6391.392817682313, 6404.615723056631, 6596.559273068803, 6604.339566043549, 6635.568688035262, 6762.7056818919355, 6775.661216939035, 3778.848846046127, 4497.2129877272, 4517.616002639812, 4591.288350305708, 4602.033572949585, 5357.142857142839, 5836.153757705194, 6340.936590634219, 6423.454006409507, 6445.0, 4758.690685105457, 5960.701964901824, 6091.543134264859, 6267.561270711007, 6317.0, 5261.473852614861, 5436.809711660073, 6317.936820631735, 6343.2406902997345, 6363.236411630634, 6193.81418557444, 6227.349752315549, 6230.6884655767935, 6248.718807653576, 6254.718537665725, 5645.802396916089, 6063.575549711654, 6124.816255512342, 6367.61794292344, 6390.9041364378645, 6217.689115544114, 6220.2535695715, 6220.626762394271, 6224.751009959551, 6228.875422491616], "value": 4562.812931402499, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.2397722163942, 818.0551463060166, 834.3075247544539, 846.1200351634301, 847.292510753521, 15700.057996520203, 16119.355225790969, 17899.284028638856, 18660.06699665015, 18820.0, 17517.562060948483, 17936.461906142806, 18111.547211319725, 18216.635667286646, 18276.634467310647, 978.5792109392958, 993.4883534979491, 995.8904520502739, 996.1134590214715, 997.1823105053859, 963.7446076789656, 992.0327680511516, 1007.4056306778999, 1010.8433192855083, 1010.8989101089869, 1063.984040239397, 1067.978640427195, 1070.5931745936573, 1070.855434516342, 1071.0414179309514, 1060.167768301887, 1060.9257351985364, 1061.723951772537, 1063.7340664833791, 1064.106150833296, 787.4802630264055, 788.1566723605744, 790.1387487638501, 790.707438247852, 790.7430085222268, 16591.917040414843, 16865.747013794804, 17021.82978170215, 17030.23363948613, 17315.567110822205, 14886.181260030695, 14997.325120369609, 15014.699706005928, 15056.698866022727, 15899.92050039754, 13311.068225224239, 13411.061225714204, 13522.459101635923, 13629.045966782329, 13695.109817861907, 3343.130785995634, 3404.7616666833333, 3405.0, 3414.897553073412, 3422.709069729076, 105381.0, 105729.47135264275, 108435.0, 109249.0, 112830.61508154769, 11376.829347559793, 11462.891292820963, 11539.19225654213, 11711.707207319885, 12023.819642705366, 23795.524089518112, 24124.63813042806, 24774.0, 24847.62728559073, 24864.751352486244, 8133.349332053422, 8197.918020819716, 8201.056878458934, 8249.175082491704, 8254.215849494289, 4138.6482149017065, 4142.047329114321, 4209.452771139793, 4219.873403797891, 4256.808443620044, 4230.1539692061715, 4274.722143060691, 4310.698251122454, 4338.501072376654, 4358.8038538265855, 923.8475651517424, 946.0255936385533, 950.9809803803885, 951.0537015669453, 953.3803028031696, 608.9482393996472, 610.8472881779555, 634.2832599163008, 643.996780016097, 674.844785699282, 9977.25170612207, 10011.649592264235, 10222.795544089076, 10277.747435528248, 10526.0, 577.5812535911515, 602.9336772954971, 610.4627927423838, 629.8771739510843, 646.6411141816337, 4749.525047495223, 4908.705477671351, 4975.578310564564, 5192.952563042032, 5225.773971817528, 86192.56903715442, 96769.0, 106644.0, 107362.0, 107604.0, 84814.0, 93112.0, 93674.0, 94459.05540944502, 96107.03892961121, 68488.28779280558, 82738.0, 83035.0, 83182.16817831744, 84175.15824841795, 9561.426314421156, 10665.786684266115, 10832.945835270773, 11005.339679619248, 11017.228793984345, 40126.0, 41037.58962410457, 41104.0, 41627.583724162374, 41733.16533669249, 54687.45312546983, 55609.44390556042, 55805.0, 56467.43532564622, 56766.0, 13262.27057511846, 13455.932720336335, 13463.865361346652, 13497.322590579797, 13944.58166255014, 14370.353334099782, 14456.0, 14756.48352307707, 14861.85138148648, 15162.696746065238, 13616.596350200725, 13720.588382348546, 13752.106113102616, 13890.58328250154, 14019.719605608036, 13682.93763156186, 13755.38100785487, 14078.184363127211, 14590.635234118807, 14666.120032798066, 13126.474941002252, 13154.29996832448, 13382.263975481443, 13436.932815336251, 13598.320083995959, 9482.013165852733, 9529.428234305964, 9831.213502919893, 9912.405255684682, 10304.948475257575, 8321.334293256647, 10326.948365258126, 10926.344419334866, 11038.227324087236, 11186.216964812385], "value": 7921.703472075465, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 123.0, 124.0, 123.0, 124.0, 124.0, 124.0, 125.0, 123.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 117.0, 161.0, 117.0, 121.0, 123.0, 114.0, 142.0, 118.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 208.0, 216.0, 220.0, 212.0, 216.0, 209.0, 212.0, 212.0, 215.0], "value": 213.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 63.0, 65.0, 106.0, 94.0, 63.0, 67.0, 66.0, 81.0, 65.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 75.0, 95.0, 76.0, 75.0, 76.0, 94.0, 75.0, 74.0, 91.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 108.0, 136.0, 94.0, 93.0, 94.0, 94.0, 92.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 102.0, 102.0, 102.0, 101.0, 102.0, 102.0, 101.0, 102.0, 102.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 100.0, 101.0, 101.0, 102.0, 100.0, 101.0, 100.0, 100.0, 102.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 52.0, 54.0, 52.0, 52.0, 51.0, 51.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 64.0, 71.0, 66.0, 65.0, 62.0, 64.0, 62.0, 63.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 107.0, 118.0, 120.0, 95.0, 90.0, 93.0, 92.0, 90.0], "value": 99.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 175.0, 178.0, 156.0, 159.0, 169.0, 171.0, 233.0, 199.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 207.0, 258.0, 199.0, 199.0, 206.0, 206.0, 200.0, 204.0, 204.0], "value": 208.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 105.0, 102.0, 107.0, 74.0, 76.0, 75.0, 73.0, 74.0], "value": 84.0, "unit": "score"}]}]} 22:47:32 INFO - Return code: 0 22:47:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:47:32 ERROR - # TBPL SUCCESS # 22:47:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:47:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:47:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:47:32 INFO - 2017-04-06 22:47:32.376 screenresolution[1653:10010] starting screenresolution argv=screenresolution get 22:47:32 INFO - 2017-04-06 22:47:32.379 screenresolution[1653:10010] Display 0: 1600x1200x32@60 22:47:32 INFO - 2017-04-06 22:47:32.385 screenresolution[1654:10013] starting screenresolution argv=screenresolution list 22:47:32 INFO - Available Modes on Display 0 22:47:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:47:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:47:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:47:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:47:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:47:32 INFO - Intel Iris: 22:47:32 INFO - Chipset Model: Intel Iris 22:47:32 INFO - Type: GPU 22:47:32 INFO - Bus: Built-In 22:47:32 INFO - VRAM (Dynamic, Max): 1536 MB 22:47:32 INFO - Vendor: Intel (0x8086) 22:47:32 INFO - Device ID: 0x0a2e 22:47:32 INFO - Revision ID: 0x0009 22:47:32 INFO - Displays: 22:47:32 INFO - Display: 22:47:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:47:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:47:32 INFO - Display Serial Number: 1600x1200 60 22:47:32 INFO - Main Display: Yes 22:47:32 INFO - Mirror: Off 22:47:32 INFO - Online: Yes 22:47:32 INFO - Rotation: Supported 22:47:32 INFO - Return code: 0 22:47:32 INFO - Running post-action listener: _resource_record_post_action 22:47:32 INFO - [mozharness: 2017-04-07 05:47:32.629610Z] Finished run-tests step (success) 22:47:32 INFO - Running post-run listener: _resource_record_post_run 22:47:32 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 38568960; Write bytes: 516891648; Read time: 251; Write time: 1594 22:47:32 INFO - TinderboxPrint: CPU usage
53.3% 22:47:32 INFO - TinderboxPrint: I/O read bytes / time
38,568,960 / 251 22:47:32 INFO - TinderboxPrint: I/O write bytes / time
516,891,648 / 1,594 22:47:32 INFO - TinderboxPrint: CPU idle
2,249.9 (46.7%) 22:47:32 INFO - TinderboxPrint: CPU system
958.2 (19.9%) 22:47:32 INFO - TinderboxPrint: CPU user
1,613.7 (33.5%) 22:47:32 INFO - TinderboxPrint: Swap in / out
245,354,496 / 0 22:47:32 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207000064; Write bytes: 205412352; Read time: 16030; Write time: 427 22:47:32 INFO - run-tests - Wall time: 1186s; CPU: 53.0%; Read bytes: 34698240; Write bytes: 304266240; Read time: 225; Write time: 1138 22:47:33 INFO - Running post-run listener: _upload_blobber_files 22:47:33 INFO - Blob upload gear active. 22:47:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:47:33 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 22:47:33 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'] 22:47:33 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 22:47:33 INFO - (blobuploader) - INFO - Open directory for files ... 22:47:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:47:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:47:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:47:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:47:34 INFO - (blobuploader) - INFO - Done attempting. 22:47:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:47:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:47:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:47:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:47:35 INFO - (blobuploader) - INFO - Done attempting. 22:47:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:47:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:47:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:47:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:47:36 INFO - (blobuploader) - INFO - Done attempting. 22:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:47:39 INFO - (blobuploader) - INFO - Done attempting. 22:47:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:47:39 INFO - Return code: 0 22:47:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:47:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:47:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7459bd057701da2d9091c686e1ecdfc51a5bd505e1a9c1050795528219335d7147babb078253a26b695444009e9135ff9b94e8b09555fed506a4d84d70bade20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8c7b2d424861f4024b9df82c720457c206d1ca175546e4e4e3881a90dd58c740d2488e86098d1a2a4c58f86cfc90c5fc43e593dbede163a72e23501d8c4434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1766c8cbca896f3ea9be3b171ba1827969820a3da114e930bf77ce82104afd3dd1ff14dab50de0fc70788adbe21249856e53bd823ec848374ea7183f2fecd23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6364a66dbe45a85da2a3d3173b29ff27107b918b0fa57517bbb90435a5f9e7d8038bef20bea290e635e59476ae69558f25179d539c218e2b02344fb81bc973e0"} 22:47:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:47:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:47:39 INFO - Contents: 22:47:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7459bd057701da2d9091c686e1ecdfc51a5bd505e1a9c1050795528219335d7147babb078253a26b695444009e9135ff9b94e8b09555fed506a4d84d70bade20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8c7b2d424861f4024b9df82c720457c206d1ca175546e4e4e3881a90dd58c740d2488e86098d1a2a4c58f86cfc90c5fc43e593dbede163a72e23501d8c4434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1766c8cbca896f3ea9be3b171ba1827969820a3da114e930bf77ce82104afd3dd1ff14dab50de0fc70788adbe21249856e53bd823ec848374ea7183f2fecd23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6364a66dbe45a85da2a3d3173b29ff27107b918b0fa57517bbb90435a5f9e7d8038bef20bea290e635e59476ae69558f25179d539c218e2b02344fb81bc973e0"} 22:47:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:47:39 INFO - Copying logs to upload dir... 22:47:39 INFO - mkdir: /builds/slave/test/build/upload/logs 22:47:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.027750 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-06 22:47:39.303748) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 22:47:39.307361) ========= 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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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/7459bd057701da2d9091c686e1ecdfc51a5bd505e1a9c1050795528219335d7147babb078253a26b695444009e9135ff9b94e8b09555fed506a4d84d70bade20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8c7b2d424861f4024b9df82c720457c206d1ca175546e4e4e3881a90dd58c740d2488e86098d1a2a4c58f86cfc90c5fc43e593dbede163a72e23501d8c4434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1766c8cbca896f3ea9be3b171ba1827969820a3da114e930bf77ce82104afd3dd1ff14dab50de0fc70788adbe21249856e53bd823ec848374ea7183f2fecd23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6364a66dbe45a85da2a3d3173b29ff27107b918b0fa57517bbb90435a5f9e7d8038bef20bea290e635e59476ae69558f25179d539c218e2b02344fb81bc973e0"} build_url:https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011019 build_url: 'https://queue.taskcluster.net/v1/task/GqFm3LtjQouvm65LQzXrwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7459bd057701da2d9091c686e1ecdfc51a5bd505e1a9c1050795528219335d7147babb078253a26b695444009e9135ff9b94e8b09555fed506a4d84d70bade20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8c7b2d424861f4024b9df82c720457c206d1ca175546e4e4e3881a90dd58c740d2488e86098d1a2a4c58f86cfc90c5fc43e593dbede163a72e23501d8c4434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1766c8cbca896f3ea9be3b171ba1827969820a3da114e930bf77ce82104afd3dd1ff14dab50de0fc70788adbe21249856e53bd823ec848374ea7183f2fecd23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6364a66dbe45a85da2a3d3173b29ff27107b918b0fa57517bbb90435a5f9e7d8038bef20bea290e635e59476ae69558f25179d539c218e2b02344fb81bc973e0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 22:47:39.341076) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 22:47:39.341407) ========= 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.7PtvBvFYo4/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.FcLhuDiNo0/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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.005011 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 22:47:39.389797) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 22:47:39.390120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 22:47:39.390440) ========= ========= Total master_lag: 0.26 =========