builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0028 starttime: 1486314448.0 results: success (0) buildid: 20170205073908 builduid: adfacce15fc9426eaac1d0be9f091531 revision: 157ac74dfab5933e6a0dfbdcd2c9b4f160674c93 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.000636) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.001127) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.001415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.049151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.049464) ========= 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.6NeOsfFCGi/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.ccP6djIMyF/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-05 09:07:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2017-02-05 09:07:28 (14.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.491500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.564502) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.564783) ========= 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.6NeOsfFCGi/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.ccP6djIMyF/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.025951 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.612578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:28.612874) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 157ac74dfab5933e6a0dfbdcd2c9b4f160674c93 --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 157ac74dfab5933e6a0dfbdcd2c9b4f160674c93 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6NeOsfFCGi/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.ccP6djIMyF/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-05 09:07:28,709 truncating revision to first 12 chars 2017-02-05 09:07:28,709 Setting DEBUG logging. 2017-02-05 09:07:28,709 attempt 1/10 2017-02-05 09:07:28,709 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/157ac74dfab5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-05 09:07:29,355 unpacking tar archive at: mozilla-central-157ac74dfab5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912927 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:29.551409) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:29.551756) ========= script_repo_revision: 157ac74dfab5933e6a0dfbdcd2c9b4f160674c93 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:29.552130) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:29.554426) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 09:07:29.576542) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-05 09:07:29.576853) ========= /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.6NeOsfFCGi/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.ccP6djIMyF/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:07:29 INFO - MultiFileLogger online at 20170205 09:07:29 in /builds/slave/test 09:07:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:07:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:29 INFO - {'append_to_log': False, 09:07:29 INFO - 'base_work_dir': '/builds/slave/test', 09:07:29 INFO - 'blob_upload_branch': 'Firefox', 09:07:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:29 INFO - 'branch': 'Firefox', 09:07:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:29 INFO - 'config_files': ('talos/mac_config.py',), 09:07:29 INFO - 'default_actions': ('clobber', 09:07:29 INFO - 'read-buildbot-config', 09:07:29 INFO - 'download-and-extract', 09:07:29 INFO - 'populate-webroot', 09:07:29 INFO - 'create-virtualenv', 09:07:29 INFO - 'install', 09:07:29 INFO - 'run-tests'), 09:07:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:29 INFO - 'download_minidump_stackwalk': True, 09:07:29 INFO - 'download_symbols': 'ondemand', 09:07:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:29 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:29 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:29 INFO - 'installer_path': 'installer.exe', 09:07:29 INFO - 'log_level': 'info', 09:07:29 INFO - 'log_name': 'talos', 09:07:29 INFO - 'log_to_console': True, 09:07:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:07:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:07:29 INFO - 'opt_config_files': (), 09:07:29 INFO - 'pip_index': False, 09:07:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:07:29 INFO - 'cmd': ('bash', 09:07:29 INFO - '-c', 09:07:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:07:29 INFO - 'enabled': True, 09:07:29 INFO - 'halt_on_failure': False, 09:07:29 INFO - 'name': 'check_screen_resolution'},), 09:07:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:07:29 INFO - 'cmd': ('bash', 09:07:29 INFO - '-c', 09:07:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:07:29 INFO - 'enabled': True, 09:07:29 INFO - 'halt_on_failure': False, 09:07:29 INFO - 'name': 'check_screen_resolution'},), 09:07:29 INFO - 'run_cmd_checks_enabled': True, 09:07:29 INFO - 'sps_profile': False, 09:07:29 INFO - 'sps_profile_interval': 0, 09:07:29 INFO - 'suite': 'dromaeojs', 09:07:29 INFO - 'system_bits': '32', 09:07:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:29 INFO - 'title': 't-yosemite-r7-0028', 09:07:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:29 INFO - 'use_talos_json': True, 09:07:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:29 INFO - 'work_dir': 'build'} 09:07:29 INFO - [mozharness: 2017-02-05 17:07:29.791988Z] Running clobber step. 09:07:29 INFO - Running pre-action listener: _resource_record_pre_action 09:07:29 INFO - Running main action method: clobber 09:07:29 INFO - rmtree: /builds/slave/test/build 09:07:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:32 INFO - Running post-action listener: _resource_record_post_action 09:07:32 INFO - [mozharness: 2017-02-05 17:07:32.405087Z] Finished clobber step (success) 09:07:32 INFO - [mozharness: 2017-02-05 17:07:32.405219Z] Running read-buildbot-config step. 09:07:32 INFO - Running pre-action listener: _resource_record_pre_action 09:07:32 INFO - Running main action method: read_buildbot_config 09:07:32 INFO - Using buildbot properties: 09:07:32 INFO - { 09:07:32 INFO - "project": "", 09:07:32 INFO - "product": "firefox", 09:07:32 INFO - "script_repo_revision": "production", 09:07:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:07:32 INFO - "repository": "", 09:07:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:07:32 INFO - "buildid": "20170205073908", 09:07:32 INFO - "pgo_build": "False", 09:07:32 INFO - "basedir": "/builds/slave/test", 09:07:32 INFO - "buildnumber": 66, 09:07:32 INFO - "slavename": "t-yosemite-r7-0028", 09:07:32 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:07:32 INFO - "platform": "yosemite_r7", 09:07:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:07:32 INFO - "branch": "mozilla-central", 09:07:32 INFO - "repo_path": "mozilla-central", 09:07:32 INFO - "revision": "157ac74dfab5933e6a0dfbdcd2c9b4f160674c93", 09:07:32 INFO - "stage_platform": "macosx64", 09:07:32 INFO - "builduid": "adfacce15fc9426eaac1d0be9f091531", 09:07:32 INFO - "slavebuilddir": "test" 09:07:32 INFO - } 09:07:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:07:32 INFO - Running post-action listener: _resource_record_post_action 09:07:32 INFO - [mozharness: 2017-02-05 17:07:32.408553Z] Finished read-buildbot-config step (success) 09:07:32 INFO - [mozharness: 2017-02-05 17:07:32.408681Z] Running download-and-extract step. 09:07:32 INFO - Running pre-action listener: _resource_record_pre_action 09:07:32 INFO - Running main action method: download_and_extract 09:07:32 INFO - mkdir: /builds/slave/test/build/tests 09:07:32 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')]} 09:07:32 INFO - trying https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:07:32 INFO - Downloading https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:07:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 09:07:33 INFO - Downloaded 1199 bytes. 09:07:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:07:33 INFO - Using the following test package requirements: 09:07:33 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:07:33 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:07:33 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:07:33 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'jsshell-mac.zip'], 09:07:33 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:07:33 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:07:33 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:07:33 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:07:33 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:07:33 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:07:33 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:07:33 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:07:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:07:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:07:33 INFO - Fetch https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:07:35 INFO - Expected file size: 34854551 09:07:35 INFO - Obtained file size: 34854551 09:07:41 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:07:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:07:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:07:41 INFO - Fetch https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:07:43 INFO - Expected file size: 34854551 09:07:43 INFO - Obtained file size: 34854551 09:07:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:07:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:07:50 INFO - Fetch https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:07:51 INFO - Expected file size: 14403454 09:07:51 INFO - Obtained file size: 14403454 09:07:51 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')]} 09:07:51 INFO - trying https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:07:51 INFO - Downloading https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:07:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 09:07:53 INFO - Downloaded 70287752 bytes. 09:07:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:07:53 INFO - mkdir: /builds/slave/test/properties 09:07:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:53 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:53 INFO - Contents: 09:07:53 INFO - build_url:https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:07:54 INFO - Running post-action listener: _resource_record_post_action 09:07:54 INFO - Running post-action listener: set_extra_try_arguments 09:07:54 INFO - [mozharness: 2017-02-05 17:07:54.956980Z] Finished download-and-extract step (success) 09:07:54 INFO - [mozharness: 2017-02-05 17:07:54.957122Z] Running populate-webroot step. 09:07:54 INFO - Running pre-action listener: _resource_record_pre_action 09:07:54 INFO - Running main action method: populate_webroot 09:07:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:54 INFO - '--tppagecycles', 09:07:54 INFO - '7'], 09:07:54 INFO - 'tests': ['tsvgm']}}, 09:07:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:07:54 INFO - 'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:07:54 INFO - 'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['damp', 'tps']}, 09:07:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:07:54 INFO - 'tests': ['damp', 'tps']}, 09:07:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['dromaeo_dom']}, 09:07:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['basic_compositor_video']}, 09:07:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:07:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['a11yr', 09:07:54 INFO - 'ts_paint', 09:07:54 INFO - 'tpaint', 09:07:54 INFO - 'sessionrestore', 09:07:54 INFO - 'sessionrestore_no_auto_restore', 09:07:54 INFO - 'tabpaint']}, 09:07:54 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:54 INFO - 'ts_paint', 09:07:54 INFO - 'tpaint', 09:07:54 INFO - 'sessionrestore', 09:07:54 INFO - 'sessionrestore_no_auto_restore', 09:07:54 INFO - 'tabpaint']}, 09:07:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:54 INFO - 'ts_paint', 09:07:54 INFO - 'tpaint', 09:07:54 INFO - 'sessionrestore', 09:07:54 INFO - 'sessionrestore_no_auto_restore', 09:07:54 INFO - 'tabpaint']}, 09:07:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:54 INFO - 'ts_paint', 09:07:54 INFO - 'tpaint', 09:07:54 INFO - 'sessionrestore', 09:07:54 INFO - 'sessionrestore_no_auto_restore', 09:07:54 INFO - 'tabpaint']}, 09:07:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['a11yr', 09:07:54 INFO - 'ts_paint', 09:07:54 INFO - 'tpaint', 09:07:54 INFO - 'sessionrestore', 09:07:54 INFO - 'sessionrestore_no_auto_restore', 09:07:54 INFO - 'tabpaint']}, 09:07:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['a11yr', 09:07:54 INFO - 'ts_paint', 09:07:54 INFO - 'tpaint', 09:07:54 INFO - 'sessionrestore', 09:07:54 INFO - 'sessionrestore_no_auto_restore', 09:07:54 INFO - 'tabpaint']}, 09:07:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['tsvgx', 09:07:54 INFO - 'tsvgr_opacity', 09:07:54 INFO - 'tart', 09:07:54 INFO - 'tscrollx', 09:07:54 INFO - 'cart', 09:07:54 INFO - 'tsvg_static']}, 09:07:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:54 INFO - 'tsvgr_opacity', 09:07:54 INFO - 'tart', 09:07:54 INFO - 'tscrollx', 09:07:54 INFO - 'cart', 09:07:54 INFO - 'tsvg_static']}, 09:07:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:07:54 INFO - 'talos_options': ['--disable-e10s'], 09:07:54 INFO - 'tests': ['tp5o']}, 09:07:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:07:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:07:54 INFO - 'talos_options': ['--disable-e10s', 09:07:54 INFO - '--xperf_path', 09:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:54 INFO - 'tests': ['tp5n']}, 09:07:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:07:54 INFO - 'talos_options': ['--xperf_path', 09:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:54 INFO - 'tests': ['tp5n']}}, 09:07:54 INFO - 'talos.zip': {'path': '', 09:07:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:54 INFO - Running post-action listener: _resource_record_post_action 09:07:54 INFO - [mozharness: 2017-02-05 17:07:54.975465Z] Finished populate-webroot step (success) 09:07:54 INFO - [mozharness: 2017-02-05 17:07:54.975596Z] Running create-virtualenv step. 09:07:54 INFO - Running pre-action listener: _resource_record_pre_action 09:07:54 INFO - Running main action method: create_virtualenv 09:07:54 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:54 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 09:07:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:55 INFO - Using real prefix '/tools/python27' 09:07:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:55 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:58 INFO - Installing pip.................done. 09:07:58 INFO - Return code: 0 09:07:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:07:58 INFO - Reading from file tmpfile_stdout 09:07:58 INFO - Output received: 09:07:58 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) 09:07:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:59 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:07:59 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 09:07:59 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 09:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:59 INFO - 'HOME': '/Users/cltbld', 09:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:59 INFO - 'LOGNAME': 'cltbld', 09:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:59 INFO - 'MOZ_NO_REMOTE': '1', 09:07:59 INFO - 'NO_EM_RESTART': '1', 09:07:59 INFO - 'PAGER': '/bin/cat', 09:07:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:59 INFO - 'PWD': '/builds/slave/test', 09:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:59 INFO - 'SHELL': '/bin/bash', 09:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:07:59 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:59 INFO - 'USER': 'cltbld', 09:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:59 INFO - 'XPC_FLAGS': '0x0', 09:07:59 INFO - 'XPC_SERVICE_NAME': '0', 09:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:59 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 09:07:59 INFO - Cleaning up... 09:07:59 INFO - Return code: 0 09:07:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:07:59 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')]} 09:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:59 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')]} 09:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:59 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:07:59 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 09:07:59 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 09:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:59 INFO - 'HOME': '/Users/cltbld', 09:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:59 INFO - 'LOGNAME': 'cltbld', 09:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:59 INFO - 'MOZ_NO_REMOTE': '1', 09:07:59 INFO - 'NO_EM_RESTART': '1', 09:07:59 INFO - 'PAGER': '/bin/cat', 09:07:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:59 INFO - 'PWD': '/builds/slave/test', 09:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:59 INFO - 'SHELL': '/bin/bash', 09:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:07:59 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:59 INFO - 'USER': 'cltbld', 09:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:59 INFO - 'XPC_FLAGS': '0x0', 09:07:59 INFO - 'XPC_SERVICE_NAME': '0', 09:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:59 INFO - Downloading/unpacking psutil>=3.1.1 09:07:59 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 09:07:59 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 09:07:59 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 09:07:59 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 09:07:59 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 09:07:59 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 09:08:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:08:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:03 INFO - Installing collected packages: psutil 09:08:03 INFO - Running setup.py install for psutil 09:08:03 INFO - building 'psutil._psutil_osx' extension 09:08:03 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 09:08:04 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 09:08:04 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 09:08:04 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 09:08:04 INFO - building 'psutil._psutil_posix' extension 09:08:04 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 09:08:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:08:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:08:04 INFO - ^ 09:08:04 INFO - 1 warning generated. 09:08:04 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 09:08:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:04 INFO - Successfully installed psutil 09:08:04 INFO - Cleaning up... 09:08:04 INFO - Return code: 0 09:08:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:08:04 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')]} 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:04 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')]} 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:04 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:04 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 09:08:04 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 09:08:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:04 INFO - 'HOME': '/Users/cltbld', 09:08:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:04 INFO - 'LOGNAME': 'cltbld', 09:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:04 INFO - 'MOZ_NO_REMOTE': '1', 09:08:04 INFO - 'NO_EM_RESTART': '1', 09:08:04 INFO - 'PAGER': '/bin/cat', 09:08:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:04 INFO - 'PWD': '/builds/slave/test', 09:08:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:04 INFO - 'SHELL': '/bin/bash', 09:08:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:04 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:04 INFO - 'USER': 'cltbld', 09:08:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:04 INFO - 'XPC_FLAGS': '0x0', 09:08:04 INFO - 'XPC_SERVICE_NAME': '0', 09:08:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:08:04 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 09:08:04 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 09:08:04 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 09:08:04 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 09:08:04 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 09:08:04 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 09:08:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:08:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:08:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:08:07 INFO - Installing collected packages: mozsystemmonitor 09:08:07 INFO - Running setup.py install for mozsystemmonitor 09:08:07 INFO - Successfully installed mozsystemmonitor 09:08:07 INFO - Cleaning up... 09:08:07 INFO - Return code: 0 09:08:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:08:07 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')]} 09:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:07 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')]} 09:08:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:07 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:07 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 09:08:07 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 09:08:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:07 INFO - 'HOME': '/Users/cltbld', 09:08:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:07 INFO - 'LOGNAME': 'cltbld', 09:08:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:07 INFO - 'MOZ_NO_REMOTE': '1', 09:08:07 INFO - 'NO_EM_RESTART': '1', 09:08:07 INFO - 'PAGER': '/bin/cat', 09:08:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:07 INFO - 'PWD': '/builds/slave/test', 09:08:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:07 INFO - 'SHELL': '/bin/bash', 09:08:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:07 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:07 INFO - 'USER': 'cltbld', 09:08:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:07 INFO - 'XPC_FLAGS': '0x0', 09:08:07 INFO - 'XPC_SERVICE_NAME': '0', 09:08:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:08 INFO - Downloading/unpacking jsonschema==2.5.1 09:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:08:10 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 09:08:10 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 09:08:10 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 09:08:10 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 09:08:10 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 09:08:10 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 09:08:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:11 INFO - warning: no files found matching '*.txt' 09:08:11 INFO - no previously-included directories found matching 'build' 09:08:11 INFO - no previously-included directories found matching 'dist' 09:08:11 INFO - no previously-included directories found matching '.git*' 09:08:11 INFO - Installing collected packages: jsonschema, functools32 09:08:11 INFO - Running setup.py install for functools32 09:08:11 INFO - warning: no files found matching '*.txt' 09:08:11 INFO - no previously-included directories found matching 'build' 09:08:11 INFO - no previously-included directories found matching 'dist' 09:08:11 INFO - no previously-included directories found matching '.git*' 09:08:11 INFO - Successfully installed jsonschema functools32 09:08:11 INFO - Cleaning up... 09:08:11 INFO - Return code: 0 09:08:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:08:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:11 INFO - 'HOME': '/Users/cltbld', 09:08:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:11 INFO - 'LOGNAME': 'cltbld', 09:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:11 INFO - 'MOZ_NO_REMOTE': '1', 09:08:11 INFO - 'NO_EM_RESTART': '1', 09:08:11 INFO - 'PAGER': '/bin/cat', 09:08:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:11 INFO - 'PWD': '/builds/slave/test', 09:08:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:11 INFO - 'SHELL': '/bin/bash', 09:08:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:11 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:11 INFO - 'USER': 'cltbld', 09:08:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:11 INFO - 'XPC_FLAGS': '0x0', 09:08:11 INFO - 'XPC_SERVICE_NAME': '0', 09:08:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:08:12 INFO - Cleaning up... 09:08:12 INFO - Return code: 0 09:08:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:12 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:12 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 09:08:12 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 09:08:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:12 INFO - 'HOME': '/Users/cltbld', 09:08:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:12 INFO - 'LOGNAME': 'cltbld', 09:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:12 INFO - 'MOZ_NO_REMOTE': '1', 09:08:12 INFO - 'NO_EM_RESTART': '1', 09:08:12 INFO - 'PAGER': '/bin/cat', 09:08:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:12 INFO - 'PWD': '/builds/slave/test', 09:08:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:12 INFO - 'SHELL': '/bin/bash', 09:08:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:12 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:12 INFO - 'USER': 'cltbld', 09:08:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:12 INFO - 'XPC_FLAGS': '0x0', 09:08:12 INFO - 'XPC_SERVICE_NAME': '0', 09:08:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:12 INFO - Downloading/unpacking blobuploader==1.2.4 09:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:08:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:08:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:16 INFO - Downloading docopt-0.6.1.tar.gz 09:08:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:08:16 INFO - Installing collected packages: blobuploader, requests, docopt 09:08:16 INFO - Running setup.py install for blobuploader 09:08:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:08:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:08:16 INFO - Running setup.py install for requests 09:08:16 INFO - Running setup.py install for docopt 09:08:17 INFO - Successfully installed blobuploader requests docopt 09:08:17 INFO - Cleaning up... 09:08:17 INFO - Return code: 0 09:08:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:17 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')]} 09:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:17 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')]} 09:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:17 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:17 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 09:08:17 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 09:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:17 INFO - 'HOME': '/Users/cltbld', 09:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:17 INFO - 'LOGNAME': 'cltbld', 09:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:17 INFO - 'MOZ_NO_REMOTE': '1', 09:08:17 INFO - 'NO_EM_RESTART': '1', 09:08:17 INFO - 'PAGER': '/bin/cat', 09:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:17 INFO - 'PWD': '/builds/slave/test', 09:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:17 INFO - 'SHELL': '/bin/bash', 09:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:17 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:17 INFO - 'USER': 'cltbld', 09:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:17 INFO - 'XPC_FLAGS': '0x0', 09:08:17 INFO - 'XPC_SERVICE_NAME': '0', 09:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:17 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-XNnilV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:17 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-hRxFzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:17 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-5hqe2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:17 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-sHuhCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:17 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-WCvy0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:17 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-7KOCh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-CS41Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Q0JUs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-YZXtfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-k3p0WE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-4gqCtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-T0OLsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ZiElsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-BBZXyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-QYy7jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:18 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-nYOiG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:19 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ru_T31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:08:19 INFO - Running setup.py install for manifestparser 09:08:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:08:19 INFO - Running setup.py install for mozcrash 09:08:19 INFO - Running setup.py install for mozdebug 09:08:19 INFO - Running setup.py install for mozdevice 09:08:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:08:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:08:19 INFO - Running setup.py install for mozfile 09:08:19 INFO - Running setup.py install for mozhttpd 09:08:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Running setup.py install for mozinfo 09:08:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Running setup.py install for mozInstall 09:08:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Running setup.py install for mozleak 09:08:20 INFO - Running setup.py install for mozlog 09:08:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Running setup.py install for moznetwork 09:08:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:08:20 INFO - Running setup.py install for mozprocess 09:08:21 INFO - Running setup.py install for mozprofile 09:08:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:08:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:08:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:08:21 INFO - Running setup.py install for mozrunner 09:08:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:08:21 INFO - Running setup.py install for mozscreenshot 09:08:21 INFO - Running setup.py install for moztest 09:08:21 INFO - Running setup.py install for mozversion 09:08:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:08:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:08:21 INFO - Cleaning up... 09:08:21 INFO - Return code: 0 09:08:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:21 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:21 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 09:08:21 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 09:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:21 INFO - 'HOME': '/Users/cltbld', 09:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:21 INFO - 'LOGNAME': 'cltbld', 09:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:21 INFO - 'MOZ_NO_REMOTE': '1', 09:08:21 INFO - 'NO_EM_RESTART': '1', 09:08:21 INFO - 'PAGER': '/bin/cat', 09:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:21 INFO - 'PWD': '/builds/slave/test', 09:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:21 INFO - 'SHELL': '/bin/bash', 09:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:21 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:21 INFO - 'USER': 'cltbld', 09:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:21 INFO - 'XPC_FLAGS': '0x0', 09:08:21 INFO - 'XPC_SERVICE_NAME': '0', 09:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-YInoc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:22 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)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-YCcmr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:22 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)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-3zRscB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:22 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)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-OGkEoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-l0fMH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:22 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)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-DblrJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:22 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)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-mcjVq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:22 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)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-0Ft2PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:22 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)) 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:22 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-JhiEL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:23 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)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-WHQI6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:23 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)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Ou1Z0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:23 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)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-U6KFtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-dAPg0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:23 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)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-mBWmsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:23 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)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-iugKNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:23 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)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-8fgZ8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:23 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)) 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:23 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-5shh6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:23 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)) 09:08:23 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)) 09:08:23 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)) 09:08:23 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)) 09:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08:23 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 09:08:23 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 09:08:23 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 09:08:23 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 09:08:23 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 09:08:23 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 09:08:26 INFO - Downloading blessings-1.6.tar.gz 09:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:26 INFO - Installing collected packages: blessings 09:08:26 INFO - Running setup.py install for blessings 09:08:26 INFO - Successfully installed blessings 09:08:26 INFO - Cleaning up... 09:08:26 INFO - Return code: 0 09:08:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:27 INFO - Reading from file tmpfile_stdout 09:08:27 INFO - Current package versions: 09:08:27 INFO - blessings == 1.6 09:08:27 INFO - blobuploader == 1.2.4 09:08:27 INFO - docopt == 0.6.1 09:08:27 INFO - functools32 == 3.2.3-2 09:08:27 INFO - jsonschema == 2.5.1 09:08:27 INFO - manifestparser == 1.1 09:08:27 INFO - mozInstall == 1.12 09:08:27 INFO - mozcrash == 1.0 09:08:27 INFO - mozdebug == 0.1 09:08:27 INFO - mozdevice == 0.49 09:08:27 INFO - mozfile == 1.2 09:08:27 INFO - mozhttpd == 0.7 09:08:27 INFO - mozinfo == 0.9 09:08:27 INFO - mozleak == 0.1 09:08:27 INFO - mozlog == 3.4 09:08:27 INFO - moznetwork == 0.27 09:08:27 INFO - mozprocess == 0.24 09:08:27 INFO - mozprofile == 0.28 09:08:27 INFO - mozrunner == 6.13 09:08:27 INFO - mozscreenshot == 0.1 09:08:27 INFO - mozsystemmonitor == 0.3 09:08:27 INFO - moztest == 0.8 09:08:27 INFO - mozversion == 1.4 09:08:27 INFO - psutil == 3.1.1 09:08:27 INFO - requests == 1.2.3 09:08:27 INFO - wsgiref == 0.1.2 09:08:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:27 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 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:27 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 09:08:27 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 09:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:27 INFO - 'HOME': '/Users/cltbld', 09:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:27 INFO - 'LOGNAME': 'cltbld', 09:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:27 INFO - 'MOZ_NO_REMOTE': '1', 09:08:27 INFO - 'NO_EM_RESTART': '1', 09:08:27 INFO - 'PAGER': '/bin/cat', 09:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:27 INFO - 'PWD': '/builds/slave/test', 09:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:27 INFO - 'SHELL': '/bin/bash', 09:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:27 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:27 INFO - 'USER': 'cltbld', 09:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:27 INFO - 'XPC_FLAGS': '0x0', 09:08:27 INFO - 'XPC_SERVICE_NAME': '0', 09:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 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)) 09:08:27 INFO - Cleaning up... 09:08:27 INFO - Return code: 0 09:08:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a8cb1d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, '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.6NeOsfFCGi/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.ccP6djIMyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 09:08:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:27 INFO - 'HOME': '/Users/cltbld', 09:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:27 INFO - 'LOGNAME': 'cltbld', 09:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:27 INFO - 'MOZ_NO_REMOTE': '1', 09:08:27 INFO - 'NO_EM_RESTART': '1', 09:08:27 INFO - 'PAGER': '/bin/cat', 09:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:27 INFO - 'PWD': '/builds/slave/test', 09:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:27 INFO - 'SHELL': '/bin/bash', 09:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:27 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:27 INFO - 'USER': 'cltbld', 09:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:27 INFO - 'XPC_FLAGS': '0x0', 09:08:27 INFO - 'XPC_SERVICE_NAME': '0', 09:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:08:27 INFO - Cleaning up... 09:08:27 INFO - Return code: 0 09:08:27 INFO - Running post-action listener: _resource_record_post_action 09:08:27 INFO - Running post-action listener: _start_resource_monitoring 09:08:27 INFO - Starting resource monitoring. 09:08:27 INFO - [mozharness: 2017-02-05 17:08:27.778732Z] Finished create-virtualenv step (success) 09:08:27 INFO - [mozharness: 2017-02-05 17:08:27.779744Z] Running install step. 09:08:27 INFO - Running pre-action listener: _resource_record_pre_action 09:08:27 INFO - Running main action method: install 09:08:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:28 INFO - Reading from file tmpfile_stdout 09:08:28 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:28 INFO - Reading from file tmpfile_stdout 09:08:28 INFO - Output received: 09:08:28 INFO - Usage: mozinstall [options] installer 09:08:28 INFO - Options: 09:08:28 INFO - -h, --help show this help message and exit 09:08:28 INFO - -d DEST, --destination=DEST 09:08:28 INFO - Directory to install application into. [default: 09:08:28 INFO - "/builds/slave/test"] 09:08:28 INFO - --app=APP Application being installed. [default: firefox] 09:08:28 INFO - mkdir: /builds/slave/test/build/application 09:08:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:08:50 INFO - Reading from file tmpfile_stdout 09:08:50 INFO - Output received: 09:08:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:08:50 INFO - Running post-action listener: _resource_record_post_action 09:08:50 INFO - [mozharness: 2017-02-05 17:08:50.486851Z] Finished install step (success) 09:08:50 INFO - [mozharness: 2017-02-05 17:08:50.486972Z] Running run-tests step. 09:08:50 INFO - Running pre-action listener: _resource_record_pre_action 09:08:50 INFO - Running main action method: run_tests 09:08:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:08:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:08:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:08:50 INFO - 2017-02-05 09:08:50.499 screenresolution[1635:8499] starting screenresolution argv=screenresolution get 09:08:50 INFO - 2017-02-05 09:08:50.501 screenresolution[1635:8499] Display 0: 1600x1200x32@60 09:08:50 INFO - 2017-02-05 09:08:50.507 screenresolution[1636:8502] starting screenresolution argv=screenresolution list 09:08:50 INFO - Available Modes on Display 0 09:08:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:08:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:08:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:08:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:08:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:08:50 INFO - Intel Iris: 09:08:50 INFO - Chipset Model: Intel Iris 09:08:50 INFO - Type: GPU 09:08:50 INFO - Bus: Built-In 09:08:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:08:50 INFO - Vendor: Intel (0x8086) 09:08:50 INFO - Device ID: 0x0a2e 09:08:50 INFO - Revision ID: 0x0009 09:08:50 INFO - Displays: 09:08:50 INFO - Display: 09:08:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:08:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:08:50 INFO - Display Serial Number: 1600x1200 60 09:08:50 INFO - Main Display: Yes 09:08:50 INFO - Mirror: Off 09:08:50 INFO - Online: Yes 09:08:50 INFO - Rotation: Supported 09:08:50 INFO - Return code: 0 09:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:50 INFO - Python 2.7.3 09:08:50 INFO - Return code: 0 09:08:50 INFO - grabbing minidump binary from tooltool 09:08: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')]} 09:08:50 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 0x10fc55d30>, '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 0x10fb7cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc4abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:50 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 09:08:50 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 09:08:50 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 09:08:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:50 INFO - Return code: 0 09:08:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:08:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:08:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:50 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/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 09:08:50 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/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0028 --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 09:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6NeOsfFCGi/Render', 09:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:50 INFO - 'HOME': '/Users/cltbld', 09:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:50 INFO - 'LOGNAME': 'cltbld', 09:08:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:50 INFO - 'MOZ_NO_REMOTE': '1', 09:08:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:50 INFO - 'NO_EM_RESTART': '1', 09:08:50 INFO - 'PAGER': '/bin/cat', 09:08:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:50 INFO - 'PWD': '/builds/slave/test', 09:08:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:50 INFO - 'SHELL': '/bin/bash', 09:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ccP6djIMyF/Listeners', 09:08:50 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:50 INFO - 'USER': 'cltbld', 09:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:50 INFO - 'XPC_FLAGS': '0x0', 09:08:50 INFO - 'XPC_SERVICE_NAME': '0', 09:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:50 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/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 09:08:51 INFO - mozversion application_buildid: 20170205073908 09:08:51 INFO - mozversion application_changeset: 157ac74dfab5933e6a0dfbdcd2c9b4f160674c93 09:08:51 INFO - mozversion application_display_name: Nightly 09:08:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:51 INFO - mozversion application_name: Firefox 09:08:51 INFO - mozversion application_remotingname: firefox 09:08:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:08:51 INFO - mozversion application_vendor: Mozilla 09:08:51 INFO - mozversion application_version: 54.0a1 09:08:51 INFO - mozversion platform_buildid: 20170205073908 09:08:51 INFO - mozversion platform_changeset: 157ac74dfab5933e6a0dfbdcd2c9b4f160674c93 09:08:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:08:51 INFO - mozversion platform_version: 54.0a1 09:08:51 INFO - using testdate: 1486314531 09:08:51 INFO - actual date: 1486314531 09:08:51 INFO - starting webserver on 'localhost:49242' 09:08:51 INFO - SUITE-START | Running 2 tests 09:08:51 INFO - TEST-START | dromaeo_css 09:08:51 INFO - operating with platform_type : mac_ 09:08:51 INFO - Initialising browser for dromaeo_css test... 09:08:51 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp0cAO2P/profile http://localhost:49242/getInfo.html) 09:08:53 INFO - PROCESS | 1646 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:08:53 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:08:53 INFO - PROCESS | 1646 | colorDepth:24 09:08:53 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:08:53 INFO - PROCESS | 1646 | __metrics 09:08:53 INFO - PROCESS | 1646 | 1486314533732 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:08:53 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:08:53 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:54 INFO - TEST-INFO | 1646: exit 0 09:08:54 INFO - Browser initialized. 09:08:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:54 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp0cAO2P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:06 INFO - PROCESS | 1649 | RSS: Main: 236482560 09:09:06 INFO - PROCESS | 1649 | 09:12:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:12:12 INFO - PROCESS | 1649 | RSS: Main: 412909568 09:12:12 INFO - PROCESS | 1649 | 09:13:56 INFO - PROCESS | 1649 | ************************* 09:13:56 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 09:13:56 INFO - PROCESS | 1649 | 09:13:56 ERROR - PROCESS | 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:13:56 INFO - PROCESS | 1649 | Full stack: 09:13:56 INFO - PROCESS | 1649 | ************************* 09:15:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:15:16 INFO - PROCESS | 1649 | RSS: Main: 372199424 09:15:16 INFO - PROCESS | 1649 | 09:18:18 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:18:19 INFO - PROCESS | 1649 | RSS: Main: 359395328 09:18:19 INFO - PROCESS | 1649 | 09:21:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:21:22 INFO - PROCESS | 1649 | RSS: Main: 336302080 09:21:22 INFO - PROCESS | 1649 | 09:24:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:24:25 INFO - PROCESS | 1649 | RSS: Main: 337268736 09:24:25 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:27:29 INFO - PROCESS | 1649 | RSS: Main: 438919168 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | __start_tp_report 09:27:29 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:27:29 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:27:29 INFO - PROCESS | 1649 | |i|pagename|runs| 09:27:29 INFO - PROCESS | 1649 | |0;dojo.html;3281.934361312774;3292.7859689120205;3308.768386212966;3310.122817453374;3364.5794275715534;5423.132298832187;5496.587755918302;5506.80726174584;5569.6658200508;5603.83188504345;5508.476694713996;5550.86122846929;5598.104303311471;5602.411746766582;5627.6904770237725;4959.206526955688;4965.925511117345;5074.391073071218;5101;5124.308218390507;4424.446944131983;5164.1995490699;5176.120059589879;5212.035773381936;5228.790848366079;4973.955469351405;5010.198368261097;5013.2229504426505;5041.924371134436;5158.9742051289695;5044.672096313728;5105.642605017651;5120.078385890575;5159.71621560814;5182.455842136596;5057.848264552069;5061.266116413136;5066.290719299301;5097.13348730713;5121.2318152277085;5394.514493695587;5404.135338345845;5600;5606.691631960241;5609.80365687199;6025.367336429699;6116.527564188708;6130.540209484307;6143.941779503872;6195.19462469876;5715.6284841485585;6257.342978987186;6278.654673992928;6280.932241518907;6285.654289014103;5774.769009239667;5777.277840269966;5777.3933736957015;5780.479756821865;5813.79651712188;6035.517158627299;6145.0950205435565;6146.231721034871;6200.472959798468;6204.565680402328;5928.940710592925;5997.250343707036;6013.759449621966;6045.939540604626;6070.816190842744;6178.62928224308;6200.472959798378;6205.81382558524;6226.159468471698;6239.875202495925;6129.509639228851;6144.354842741448;6148.784792532329;6149.446549810538;6150.169727086786;5892.558058145656;5970.761169553256;5988.461038506555;6040.486558642477;6150.354212807591;4895.755212239374;4913.533214344703;4924.4090709115135;4925.1873440881855;4956.801727930914;4365.214261432972;4387.956120438755;4405.3612226227015;4411.889702757392;4424.557544245549;5485.204645326406;5623.2408624835125;5634.887302253932;5647.887042259214;5699.715014249271;6090.725917333642;6118.558266176196;6143.450468055797;6155.10750941111;6176.69116544171;5638.4925356718095;5648.124540696199;5652.97173514138;5653.378128405872;5699.373068962411;5384.434634363335;5402;5415.404305526389;5415.675059496442;5421.241026256327;6131.040261761288;6177.752889884354;6249.78125765596;6251.062340648939;6260.248770147611;6151.6924153793025;6167.599106058271;6219.284782249918;6223.3465486123305;6251.031090181029;6151.3541078186145;6179.227596550431;6189.721462534107;6200.224971878515;6236.469900058546;6082.878342433038;6142.682951206299;6150.969245153746;6173.135760993375;6175.475084617858;6017.638941663514;6075.635461872302;6088.756449741961;6262.279837818709;6274.247090349005;5919.733611987557;5978.581499295007;5998.370171131968;6014.278286605635;6098.75604975796;5039.571636410946;6020.819375418744;6030.939690603038;6031.366706495754;6034.456898879122;5820.94179058204;5842.824715258549;5843.766249349978;5849.824505264849;5855.9121613176685;5774.797882074107;5795.82612521625;5820.30156381237;5913.911291330547;5920.496757775465;5708.54331653475;5747.8563035924435;5755.366909639853;5764.423557644202;5769.307683078057 09:27:29 INFO - PROCESS | 1649 | |1;ext.html;441.35341724373785;468.5548728707727;500.3245618415138;500.39452262762063;512.9919707774222;22779;37410;38751.418728719094;38979.61020389795;39182.80408597953;39803.80098099505;40171.59828401722;40287;40387.59612403875;40437.59562404375;7879.4878332908465;9076.364654474173;9308.441493510378;9312.30157738169;9314.161725444708;7845.490043147206;10797.190210734187;11048.502817373197;11071.557137714482;11158.442077896114;11029.889701103006;11122.666320010372;11133.498992545356;11148.163887708413;11164.609238676649;7846.843063138763;11023.61417350393;11104;11118.888811111867;11123.666290011313;7895.092064412609;7980.640871160812;7981.2018798119725;7982.960085199595;10665.9466702666;38538;38655.806720966495;38737.61262387368;38743.806280968696;38748.41877371842;3865.362215234483;3986.40203969404;3991.840326386941;4009.158076803875;4136.152088821785;24463;28635;29473.11580652584;30221.848890755624;30389;2571.2671888511873;3170.445172094874;3266.379387916312;3291.851866665982;3298.884539041146;445259;452461;454260.9999999967;458997;459713;54099.72950135303;56151.719241404346;66063;67311;67405.98891016639;58540;74312;74334.25665743274;74454;75167;14844.851551484562;15271.160086195367;15271.549202825714;15276.007059541094;15318.080915145127;11391.20261581698;11519.136064795173;11527.942360288145;11535.134864885167;11553.017993470481;16488.752668709738;19853.702194467096;20028.399148025583;20090;20092.69860952087;8627.568621568897;8713.651453941771;8721.869171962426;8795.824083518422;8951.283897288202;810.7527204202775;851.535912927456;857.8541647919868;877.1141147441131;886.2466903132286;24170.07946286505;27741.58387624187;28751.42497150045;29487.26281842971;29553.85223073871;1025.789713108811;1032.948352582383;1042.9634962776418;1060.289605964006;1165.167721785255;13825.654358641115;14135;14904.925475372771;15354.385824566893;15407.537773866801;616105;619189;620890;621821;622568;55695;55915.881682366125;56057.318280451655;56067.71966140225;56147;1337.5652912803378;1389.2428626398637;1402.0816365280757;1407.7677183264648;1408.2254759881819;27701;28146.577801332587;28403.86384544595;28493.715062849937;29129.85435072811;48393.27410088922;48919.75540122276;48984.75507622439;49008.75495622499;49071.754641226566;435576;437338.81330593146;437384;437468;437907;32584.51123233106;32751.508727369586;33338.499922501665;36374.63625363712;39648.40527392034;43671.56328436675;44192.266353387386;44936.77531612321;45102.77448612736;45414.09171816611;28664.713352866205;35263.471047934814;36386.63613363833;37152.628473715995;37433.81283093567;35245.82377088098;35409.64590354064;36506.45240321344;36560.634393655724;36646.45030324494;32775.836120819244;32864;34060.489092663134;35215.647843521234;35403.46894796529;23408.88295558511;28820.443980410666;29371;31026.93157613877;31197.8440107798;18697.81302187015;22015.559688806454;22651.660225096966;22680.65979010349;23566.410839729146 09:27:29 INFO - PROCESS | 1649 | |2;jquery.html;390.1825675210434;393.69488646299135;394.6527056190552;403.8970062634028;425.3343517395276;16409.343626254937;19721.40835774927;20827.27104551341;21058.262960796375;21063.894680526613;12321.014318854492;12641.557545485886;14349.784753228683;20970.475738106554;21257.14971401146;5252.474752524755;5659.179418984245;5690.146478028288;5704.34400043994;5931.228940237763;5905.6751878646655;5946.940530594682;5949.464548190662;5951.702414879261;5964.493018093467;5757.856053598673;5928.940710592904;5946.435088666592;6011.789587364421;6019.729112189962;5971.253593300838;6008.279006519246;6014.699265036731;6018.856417280746;6023.608465449732;5271.20931860224;5495.148252020943;5561.4716601922755;5611.719414029323;5632.014397480466;13736.93131534336;13740.450381984709;13777;13941.86058139426;13991.230482323468;2554.923352299434;2590.5984572391308;2596.974030259692;2646.0376681793064;2741.150243424545;10025.498725063717;10215.591376345012;10311.43287119208;10561.260711750181;10631.627893023784;1761.9119044047745;1801.6576850398383;1824.2429391802386;1827.7349784281205;1842.8341449269496;122846;139993;140231.3676085349;142658.2867085658;145599.88002399457;11397.886021139848;11892.167548271538;11914.940425297818;11928.522859085713;11978.101642376856;15533.068015919082;16034.957727747658;16157.596060098356;16218.837811621968;16234.83765162357;8112.959435202786;8263.669453221804;8324.084350721416;8357.95821020891;8392.118827523143;6718;7800.102988156435;7840.0199975003125;7937.166597507294;7972.6412311444965;4733.219018861849;4769;4825.831095911626;4918.581920536794;4981.2030075187795;1985.1364656374524;2017.8587498875231;2027.2093883385492;2028.2495476673726;2036.175348983664;859.3211363023266;972.0231167676582;1011.8330475471612;1014.4674046125843;1026.3226270661328;19507.609847802963;21396.50224484271;21481.78518214829;21574;21587.568248635253;1015.4922538730634;1019.413837043688;1044.154235069596;1045.7490202351532;1045.8431235314763;10420.114290285259;10499.317544359592;11253.66239012831;11273.267237629529;11337.773244535229;623201;690121;695745;702879;712045;105441;110586.34120488199;130738.9999999981;151117;151526;871.1941454154882;996.1035068438464;1013.5692330759398;1015.263933648114;1016.4358780876537;18379.356722514647;18691.626167476847;18859.339923102627;18872.811271887655;18920.148593313057;58126.4187358121;58272.12591811042;58428;58850;60137;81231.59384203042;83119;83676.16323836683;85047;86175.70736439084;8200.958995204986;8370.28852547516;8463.67249006528;8528.317734581342;8675.17487053109;9393.342466027312;12636.55772047974;13068.80396794068;13398.732025359634;13422.39599218018;11090.5563777448;11242;11341.603043893425;11493.310401375944;11549.42252887369;9927.354721943051;10018.549165287433;10158.39049657023;10222.386656800616;10486.947565262124;9576.47329396862;11169.832452513056;11401;11555.480003400035;11584.884151158381;7183.676734546853;8267.710630127916;8301.58492075406;8326.791830204293;8672.653093876173;10964;11470.4264786762;11528.711782205512;11640.476178572155;11663 09:27:29 INFO - PROCESS | 1649 | |3;mootools.html;1147.2084261859322;1296.429570988765;1423.103444829757;1445.3712635003774;1454.2074569359697;7516.5114267572635;7893.329067029309;7896.842063158733;7901.367890568755;7903.367730581547;7779.611019449019;8278.420510564263;8498.1501849815;8499.107593702649;8597.183267589584;2283.4633861042644;2315.4327189838427;2319.5708793873105;2320.918767843126;2325.4070212095894;2001.729766481521;2267.38780529257;2332.8600283983014;2335.4628435459854;2350.1304517328613;2013.848961327899;2216.0581752755097;2293.5986202414592;2303.8387312888103;2303.9078436862505;2256.9617975731185;2283.1666441748725;2288.3706980580305;2290.1869836208075;2291.770822917695;2295.4605667668084;2315.0739704118487;2316.62933930572;2319.9768002320097;2320.872352020638;8408.663653453854;8591.140885911358;8593.484390936565;8607.82784344316;8634.568271586395;4887;4957.727324997124;5032.295478632995;5040.294358789773;5046.798128074873;3273.836308184605;3337.5327454156436;3480.860765569374;3533.9823300883586;3542.946855797165;1035.7773078788098;1166.7783121206944;1175.3359351966112;1190.571394298052;1243.7512497500536;11120.55517779288;11249.55001799927;11251.874812518765;11253.54985800567;11340.319580825177;4412.139632771643;4469.41897553316;4674.018456124184;4769.35613692154;4776.116418462596;9652.662156824477;9773.02269773017;9775;9778.511074446391;9783.657571984946;5242.030224408497;5323.760430780547;5325.920111198335;5329.573634109262;5332;4655.138799322136;4723.291506274018;4741.976290118528;4829.154897892855;4832.8791780205775;3744.4944932433773;3811.123441608446;3818.1218319786603;3859.980700096538;3907.374820028782;1096.4791723931069;1155.5435602936793;1259.9433025513874;1263.684078980255;1278.284160869916;709.5919846088519;878.9208971192495;881.8941726992801;917.3945196170496;966.3863446711248;5400.378956419983;5478.616496845182;5587.608867379326;5733.713314334266;5750.993576124161;855.8031652719909;934.4907973622551;983.7343917142474;997.780488292589;1028.2545154762738;2939.691332410066;3691.412284111168;3738.9065273367837;3776.773393596393;3893.2213557288655;9931.205503559699;10166.949165254126;10273.972602739666;10290.794184116425;10301.969803019789;10218.795624087477;10225.386476811414;10236.976302369703;10244.743881403025;10246.48767561619;7590.772276831814;7897.763067107996;7931.087924888597;8007.239312265326;8025.357971362277;5620.381758006698;5751.942480575308;5780.479756821906;5805.042168042226;5837.883242335214;9074.954625227096;9228.307876909124;9233.030531794333;9415.435073895589;9518.048195180427;9396.154346108882;9593.376430531993;9601.423914565148;9674.274429417963;9684;7263.382612477999;7423.035005449257;7428.888566671612;7432.814179645552;7447.99452073963;7903.051633803981;7993.600319984093;8050.879236811335;8077.353811694933;8078.919210807817;7234.337658968275;7274.090738657668;7360.043194384696;7368.115826100902;7475.962620186864;7215.242399547972;7249.347558719741;7295.744648937261;7353.264673532604;7445.478816482794;6323;6345.650989195638;6364.6181229126405;6453.806385808433;6487;4999.400071991385;5000;5068.341115654941;5098.209777484496;5156.04613146569;4359.629431498358;5044.697318160922;5046.243063540498;5097.719625420637;5120.436751957356 09:27:29 INFO - PROCESS | 1649 | |4;prototype.html;350.93332266869294;351.2325568632538;361.1566991075838;367.4910249304712;368.1091757945774;4112.136451345214;5046.990601879625;5072.822451214208;5307.25698402223;5352.437994010631;4477.4180655475575;4963.975180124095;4986.351774269349;5205.479452054802;5225.477452254772;3939.463609192417;3945.8618948336884;3947.2894878921784;3958.891510377099;3995.680345572347;3818.2745278397156;3838.827252773618;3993.8003099845037;4008.378701301303;4021.2761702893467;3612.24142929985;3862.86479973201;3999.3201155803436;3999.7600143991453;4014.1369605534805;3354.021127534766;3911.6675082618012;3931.348833489941;3931.4299426582984;3958.34687276599;2676.47808677308;3101.379724055198;3127.390158919012;3410.5395771571016;3412.607550131742;5084.339035925306;5089.974550127263;5157.194981756393;5179.585633149339;5198.16829307309;3910.452536644872;4230.857668429507;4456.17560751262;4483.865484035451;4561.019380833137;5057.595392368602;5429.701366424845;5445.883593863248;5467.890642187134;5478.534324582415;3152.98423507881;3227.790193637406;3548.308079924416;3711.480392745018;3777.82662563448;5176.9741151294;5208.088584497697;5431.321084864392;5648.6893220872835;5734.397888221718;3372.7470439717117;3788.753731007476;3845.65389114981;3867.3038853006165;3871.2838124946975;3668.596454390015;3815.8664446744233;3930.5479869815335;3944.2111577684577;3958.6437220649705;4229.767362795076;4509.977450112794;4555.453345598549;4566.520515345907;4600.240960241589;5248.737563121828;5366.7048312343195;5490.243122200937;5496.642718223303;5554.611177217636;1123;1203.4524291447372;1228.834107395508;1253.2079126848694;1262.7057266302113;800.7917941335178;863.4457975643816;868.2533021601417;875.5840975536569;880.801819590587;4945.8021679132435;4950.8267210647455;4951.356323677971;4954.455009948902;4957.240179736142;5126.948975460022;5142.665726727751;5446.291982042309;5492.725363731797;5502.614816962774;4197.685173611931;4716.457607375127;4790.461055640516;4945.505449455098;4977.352944117314;2554.948901021969;2671.8931242750073;2718.3010618363523;2796.231036464948;2810.3114736889183;5258.684478931276;5316.362036555638;5370.892582148335;5568.638038527482;5601.29983752031;4618.515055919147;5224.477552244821;5252.133397989365;5306.62853600252;5500.064988951888;4872.489528246184;5518.641288316247;5528.585356098309;5536.806211782568;5688.5494198978995;4823.348847905488;5236.319278493771;5392.218128371443;5409.241996351123;5445.801923576807;4665.486796452456;4824.589909857701;4959.752012399294;5251.002309561171;5344.198370244494;4788.760561971957;4919.335889654851;5616.410276921028;5648;5653.886922261614;3114.9532757009115;3607.639236076371;3625.945610815787;3640.6541378568995;3641.5812181598394;4655.86032419028;4669.976650116728;5224.451432599522;5363.168708850135;5484.8354549363585;4574.88562785933;5168.9483105168465;5199.570118217521;5247.816326428557;5259.342582177228;5218.529677764396;5230.267762513327;5286.312779338661;5288.338957630296;5328.7086552782675;4379.518252992232;4890.486498917705;5004.84985450437;5104.41299250591;5130.640855140254;4610.838620648261;4615.469221039623;5023.899522009612;5095.515925987025;5220.921686174786;5007.924881126713;5161.741912904415;5173.860911271044;5188.900768392928;5204.713740744295 09:27:29 INFO - PROCESS | 1649 | |5;yui.html;686.5365878032327;695.1102588686476;765.6210175962899;811.5536454949778;821.7740121466596;14208.857911420882;14821.407143714265;14822.332995015224;14844.777328340084;14848.703025939474;13498.865011349908;13614.251216183105;13614.659633509167;13643.590692279222;13657.521986730466;861.8750281209236;862.4695812075591;875.1685898396548;884.5665623844302;884.8274586455643;880.3133555826433;894.9284057275435;895.1451363947447;896.6592694775976;897.7261935109789;932.5523748600676;932.6269492203105;933.5798890499268;934.0985948559638;935.3452583191759;928.6424726480286;930.4556834251971;932.3566738950103;932.5710173320315;933.6545478173051;696.1854630082764;697.8778713725078;698.2458944340151;698.3261152987341;698.4796326736614;14624.195669238188;14762.335694893649;14807;14973.850261497464;15098.698026039418;11882.703777335984;13931.373088211054;14103;14184.290785460686;14205.218712970782;11433.371164585944;11542.596009139725;11646.359450230233;12094.758104837942;12228.755424891542;3324.3683700097145;3582.22982058856;3644.325799727059;3664.2089047754375;3676.356637588438;100753;101792.98207017983;102137;103708.29604371756;104105.47947260215;10428.739281518023;10753.623623173151;10875.564977400974;10942.539720463461;10974.780504389868;21293.89353053225;21827.890860545594;22138.77861221367;22147;22187.223447179273;7618.6190690465255;7631.656575454118;7666.540007599562;7738.883916741253;7840.764777056584;4172.065301891696;4259.524093274547;4264.040590867092;4324.935125973113;4384.517703052662;3642.9635703642625;3736.663700266989;3739.3456145174487;3743.9438408423894;3746.7189960753053;661.2990230355742;679.2833560593523;685.8662560800695;688.5008368932585;695.391532409142;648.1753862876083;659.6174218952996;665.9533832631766;677.2922296200483;679.1714108787272;10003.749531308586;10023.2482563808;10034.899651003543;10157;10209.846852297072;639.9776007839703;677.2584020497547;678.6945874356461;693.1578132568953;694.8193469697915;3810.580836108026;4933.432655244693;4956.653034287565;4964.553190212898;4974.129527332702;100066.4996675012;103073.96926030793;104518.77406129663;105091;109964.4501777486;87688;92703.53648231715;93538;93987;94980.52509737408;82032.17967820246;82226.58886705647;82847.17152828515;83561.16438835656;84640;9594.66418675343;9609.135177833748;9676.279930549648;9709.048385323676;9859.408435493893;44454.55545444504;52274.693132671986;53670;53797.42721868528;53870.46129538654;67293.66353168203;69658.95511567229;71441;72969.27030729625;73224.63387683028;11405.144614153805;11482.94258528702;11515.654530364103;11566.016888564567;11951.10366722482;15070.040894683621;15094.39622415135;15129.39482420735;15400.537983860502;15716.685666286381;13632.65918352049;14140.222287774272;14149.532803131486;14311.713765724835;14624.195669238294;13954.511592094228;14209.644758881112;14258.857411426168;14268.714625707635;14307.21310327942;12240.75518489643;13534.864651353755;13674;13874.375653095432;14044.578662640137;11060.557577696802;11201.71995700114;11598.88401115978;11648.475818588353;11962.222455540363;10190.337628054152;11060.612878549213;11108.333499990027;11224.494897729784;11256.155788315746 09:27:29 INFO - PROCESS | 1649 | __end_tp_report 09:27:29 INFO - PROCESS | 1649 | __start_cc_report 09:27:29 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2090 09:27:29 INFO - PROCESS | 1649 | __end_cc_report 09:27:29 INFO - PROCESS | 1649 | __startTimestamp1486315649320__endTimestamp 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:27:29 INFO - PROCESS | 1649 | Number of tests: 6 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5641.31 Median:5795.83 stddev:634.42 (10.9%) stddev-sans-first:608.77 09:27:29 INFO - PROCESS | 1649 | Values: 3281.9 3292.8 3308.8 3310.1 3364.6 5423.1 5496.6 5506.8 5569.7 5603.8 5508.5 5550.9 5598.1 5602.4 5627.7 4959.2 4965.9 5074.4 5101.0 5124.3 4424.4 5164.2 5176.1 5212.0 5228.8 4974.0 5010.2 5013.2 5041.9 5159.0 5044.7 5105.6 5120.1 5159.7 5182.5 5057.8 5061.3 5066.3 5097.1 5121.2 5394.5 5404.1 5600.0 5606.7 5609.8 6025.4 6116.5 6130.5 6143.9 6195.2 5715.6 6257.3 6278.7 6280.9 6285.7 5774.8 5777.3 5777.4 5780.5 5813.8 6035.5 6145.1 6146.2 6200.5 6204.6 5928.9 5997.3 6013.8 6045.9 6070.8 6178.6 6200.5 6205.8 6226.2 6239.9 6129.5 6144.4 6148.8 6149.4 6150.2 5892.6 5970.8 5988.5 6040.5 6150.4 4895.8 4913.5 4924.4 4925.2 4956.8 4365.2 4388.0 4405.4 4411.9 4424.6 5485.2 5623.2 5634.9 5647.9 5699.7 6090.7 6118.6 6143.5 6155.1 6176.7 5638.5 5648.1 5653.0 5653.4 5699.4 5384.4 5402.0 5415.4 5415.7 5421.2 6131.0 6177.8 6249.8 6251.1 6260.2 6151.7 6167.6 6219.3 6223.3 6251.0 6151.4 6179.2 6189.7 6200.2 6236.5 6082.9 6142.7 6151.0 6173.1 6175.5 6017.6 6075.6 6088.8 6262.3 6274.2 5919.7 5978.6 5998.4 6014.3 6098.8 5039.6 6020.8 6030.9 6031.4 6034.5 5820.9 5842.8 5843.8 5849.8 5855.9 5774.8 5795.8 5820.3 5913.9 5920.5 5708.5 5747.9 5755.4 5764.4 5769.3 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:64286.97 Median:36603.54 stddev:136220.51 (372.2%) stddev-sans-first:136518.30 09:27:29 INFO - PROCESS | 1649 | Values: 441.4 468.6 500.3 500.4 513.0 22779.0 37410.0 38751.4 38979.6 39182.8 39803.8 40171.6 40287.0 40387.6 40437.6 7879.5 9076.4 9308.4 9312.3 9314.2 7845.5 10797.2 11048.5 11071.6 11158.4 11029.9 11122.7 11133.5 11148.2 11164.6 7846.8 11023.6 11104.0 11118.9 11123.7 7895.1 7980.6 7981.2 7983.0 10665.9 38538.0 38655.8 38737.6 38743.8 38748.4 3865.4 3986.4 3991.8 4009.2 4136.2 24463.0 28635.0 29473.1 30221.8 30389.0 2571.3 3170.4 3266.4 3291.9 3298.9 445259.0 452461.0 454261.0 458997.0 459713.0 54099.7 56151.7 66063.0 67311.0 67406.0 58540.0 74312.0 74334.3 74454.0 75167.0 14844.9 15271.2 15271.5 15276.0 15318.1 11391.2 11519.1 11527.9 11535.1 11553.0 16488.8 19853.7 20028.4 20090.0 20092.7 8627.6 8713.7 8721.9 8795.8 8951.3 810.8 851.5 857.9 877.1 886.2 24170.1 27741.6 28751.4 29487.3 29553.9 1025.8 1032.9 1043.0 1060.3 1165.2 13825.7 14135.0 14904.9 15354.4 15407.5 616105.0 619189.0 620890.0 621821.0 622568.0 55695.0 55915.9 56057.3 56067.7 56147.0 1337.6 1389.2 1402.1 1407.8 1408.2 27701.0 28146.6 28403.9 28493.7 29129.9 48393.3 48919.8 48984.8 49008.8 49071.8 435576.0 437338.8 437384.0 437468.0 437907.0 32584.5 32751.5 33338.5 36374.6 39648.4 43671.6 44192.3 44936.8 45102.8 45414.1 28664.7 35263.5 36386.6 37152.6 37433.8 35245.8 35409.6 36506.5 36560.6 36646.5 32775.8 32864.0 34060.5 35215.6 35403.5 23408.9 28820.4 29371.0 31026.9 31197.8 18697.8 22015.6 22651.7 22680.7 23566.4 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:37978.44 Median:21014.37 stddev:114378.31 (544.3%) stddev-sans-first:114664.35 09:27:29 INFO - PROCESS | 1649 | Values: 390.2 393.7 394.7 403.9 425.3 16409.3 19721.4 20827.3 21058.3 21063.9 12321.0 12641.6 14349.8 20970.5 21257.1 5252.5 5659.2 5690.1 5704.3 5931.2 5905.7 5946.9 5949.5 5951.7 5964.5 5757.9 5928.9 5946.4 6011.8 6019.7 5971.3 6008.3 6014.7 6018.9 6023.6 5271.2 5495.1 5561.5 5611.7 5632.0 13736.9 13740.5 13777.0 13941.9 13991.2 2554.9 2590.6 2597.0 2646.0 2741.2 10025.5 10215.6 10311.4 10561.3 10631.6 1761.9 1801.7 1824.2 1827.7 1842.8 122846.0 139993.0 140231.4 142658.3 145599.9 11397.9 11892.2 11914.9 11928.5 11978.1 15533.1 16035.0 16157.6 16218.8 16234.8 8113.0 8263.7 8324.1 8358.0 8392.1 6718.0 7800.1 7840.0 7937.2 7972.6 4733.2 4769.0 4825.8 4918.6 4981.2 1985.1 2017.9 2027.2 2028.2 2036.2 859.3 972.0 1011.8 1014.5 1026.3 19507.6 21396.5 21481.8 21574.0 21587.6 1015.5 1019.4 1044.2 1045.7 1045.8 10420.1 10499.3 11253.7 11273.3 11337.8 623201.0 690121.0 695745.0 702879.0 712045.0 105441.0 110586.3 130739.0 151117.0 151526.0 871.2 996.1 1013.6 1015.3 1016.4 18379.4 18691.6 18859.3 18872.8 18920.1 58126.4 58272.1 58428.0 58850.0 60137.0 81231.6 83119.0 83676.2 85047.0 86175.7 8201.0 8370.3 8463.7 8528.3 8675.2 9393.3 12636.6 13068.8 13398.7 13422.4 11090.6 11242.0 11341.6 11493.3 11549.4 9927.4 10018.5 10158.4 10222.4 10486.9 9576.5 11169.8 11401.0 11555.5 11584.9 7183.7 8267.7 8301.6 8326.8 8672.7 10964.0 11470.4 11528.7 11640.5 11663.0 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5506.50 Median:5083.03 stddev:3056.07 (60.1%) stddev-sans-first:3047.08 09:27:29 INFO - PROCESS | 1649 | Values: 1147.2 1296.4 1423.1 1445.4 1454.2 7516.5 7893.3 7896.8 7901.4 7903.4 7779.6 8278.4 8498.2 8499.1 8597.2 2283.5 2315.4 2319.6 2320.9 2325.4 2001.7 2267.4 2332.9 2335.5 2350.1 2013.8 2216.1 2293.6 2303.8 2303.9 2257.0 2283.2 2288.4 2290.2 2291.8 2295.5 2315.1 2316.6 2320.0 2320.9 8408.7 8591.1 8593.5 8607.8 8634.6 4887.0 4957.7 5032.3 5040.3 5046.8 3273.8 3337.5 3480.9 3534.0 3542.9 1035.8 1166.8 1175.3 1190.6 1243.8 11120.6 11249.6 11251.9 11253.5 11340.3 4412.1 4469.4 4674.0 4769.4 4776.1 9652.7 9773.0 9775.0 9778.5 9783.7 5242.0 5323.8 5325.9 5329.6 5332.0 4655.1 4723.3 4742.0 4829.2 4832.9 3744.5 3811.1 3818.1 3860.0 3907.4 1096.5 1155.5 1259.9 1263.7 1278.3 709.6 878.9 881.9 917.4 966.4 5400.4 5478.6 5587.6 5733.7 5751.0 855.8 934.5 983.7 997.8 1028.3 2939.7 3691.4 3738.9 3776.8 3893.2 9931.2 10166.9 10274.0 10290.8 10302.0 10218.8 10225.4 10237.0 10244.7 10246.5 7590.8 7897.8 7931.1 8007.2 8025.4 5620.4 5751.9 5780.5 5805.0 5837.9 9075.0 9228.3 9233.0 9415.4 9518.0 9396.2 9593.4 9601.4 9674.3 9684.0 7263.4 7423.0 7428.9 7432.8 7448.0 7903.1 7993.6 8050.9 8077.4 8078.9 7234.3 7274.1 7360.0 7368.1 7476.0 7215.2 7249.3 7295.7 7353.3 7445.5 6323.0 6345.7 6364.6 6453.8 6487.0 4999.4 5000.0 5068.3 5098.2 5156.0 4359.6 5044.7 5046.2 5097.7 5120.4 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4325.83 Median:4948.31 stddev:1308.62 (26.4%) stddev-sans-first:1277.83 09:27:29 INFO - PROCESS | 1649 | Values: 350.9 351.2 361.2 367.5 368.1 4112.1 5047.0 5072.8 5307.3 5352.4 4477.4 4964.0 4986.4 5205.5 5225.5 3939.5 3945.9 3947.3 3958.9 3995.7 3818.3 3838.8 3993.8 4008.4 4021.3 3612.2 3862.9 3999.3 3999.8 4014.1 3354.0 3911.7 3931.3 3931.4 3958.3 2676.5 3101.4 3127.4 3410.5 3412.6 5084.3 5090.0 5157.2 5179.6 5198.2 3910.5 4230.9 4456.2 4483.9 4561.0 5057.6 5429.7 5445.9 5467.9 5478.5 3153.0 3227.8 3548.3 3711.5 3777.8 5177.0 5208.1 5431.3 5648.7 5734.4 3372.7 3788.8 3845.7 3867.3 3871.3 3668.6 3815.9 3930.5 3944.2 3958.6 4229.8 4510.0 4555.5 4566.5 4600.2 5248.7 5366.7 5490.2 5496.6 5554.6 1123.0 1203.5 1228.8 1253.2 1262.7 800.8 863.4 868.3 875.6 880.8 4945.8 4950.8 4951.4 4954.5 4957.2 5126.9 5142.7 5446.3 5492.7 5502.6 4197.7 4716.5 4790.5 4945.5 4977.4 2554.9 2671.9 2718.3 2796.2 2810.3 5258.7 5316.4 5370.9 5568.6 5601.3 4618.5 5224.5 5252.1 5306.6 5500.1 4872.5 5518.6 5528.6 5536.8 5688.5 4823.3 5236.3 5392.2 5409.2 5445.8 4665.5 4824.6 4959.8 5251.0 5344.2 4788.8 4919.3 5616.4 5648.0 5653.9 3115.0 3607.6 3625.9 3640.7 3641.6 4655.9 4670.0 5224.5 5363.2 5484.8 4574.9 5168.9 5199.6 5247.8 5259.3 5218.5 5230.3 5286.3 5288.3 5328.7 4379.5 4890.5 5004.8 5104.4 5130.6 4610.8 4615.5 5023.9 5095.5 5220.9 5007.9 5161.7 5173.9 5188.9 5204.7 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:20794.87 Median:3706.51 stddev:29966.41 (808.5%) stddev-sans-first:30012.44 09:27:29 INFO - PROCESS | 1649 | Values: 686.5 695.1 765.6 811.6 821.8 14208.9 14821.4 14822.3 14844.8 14848.7 13498.9 13614.3 13614.7 13643.6 13657.5 861.9 862.5 875.2 884.6 884.8 880.3 894.9 895.1 896.7 897.7 932.6 932.6 933.6 934.1 935.3 928.6 930.5 932.4 932.6 933.7 696.2 697.9 698.2 698.3 698.5 14624.2 14762.3 14807.0 14973.9 15098.7 11882.7 13931.4 14103.0 14184.3 14205.2 11433.4 11542.6 11646.4 12094.8 12228.8 3324.4 3582.2 3644.3 3664.2 3676.4 100753.0 101793.0 102137.0 103708.3 104105.5 10428.7 10753.6 10875.6 10942.5 10974.8 21293.9 21827.9 22138.8 22147.0 22187.2 7618.6 7631.7 7666.5 7738.9 7840.8 4172.1 4259.5 4264.0 4324.9 4384.5 3643.0 3736.7 3739.3 3743.9 3746.7 661.3 679.3 685.9 688.5 695.4 648.2 659.6 666.0 677.3 679.2 10003.7 10023.2 10034.9 10157.0 10209.8 640.0 677.3 678.7 693.2 694.8 3810.6 4933.4 4956.7 4964.6 4974.1 100066.5 103074.0 104518.8 105091.0 109964.5 87688.0 92703.5 93538.0 93987.0 94980.5 82032.2 82226.6 82847.2 83561.2 84640.0 9594.7 9609.1 9676.3 9709.0 9859.4 44454.6 52274.7 53670.0 53797.4 53870.5 67293.7 69659.0 71441.0 72969.3 73224.6 11405.1 11482.9 11515.7 11566.0 11951.1 15070.0 15094.4 15129.4 15400.5 15716.7 13632.7 14140.2 14149.5 14311.7 14624.2 13954.5 14209.6 14258.9 14268.7 14307.2 12240.8 13534.9 13674.0 13874.4 14044.6 11060.6 11201.7 11598.9 11648.5 11962.2 10190.3 11060.6 11108.3 11224.5 11256.2 09:27:29 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:27:29 INFO - PROCESS | 1649 | 09:27:29 INFO - TEST-INFO | 1649: exit 0 09:27:29 INFO - TEST-OK | dromaeo_css | took 1118854ms 09:27:29 INFO - TEST-START | kraken 09:27:29 INFO - operating with platform_type : mac_ 09:27:29 INFO - Initialising browser for kraken test... 09:27:29 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp7pDINs/profile http://localhost:49242/getInfo.html) 09:27:32 INFO - PROCESS | 1655 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:27:32 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 09:27:32 INFO - PROCESS | 1655 | colorDepth:24 09:27:32 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 09:27:32 INFO - PROCESS | 1655 | __metrics 09:27:32 INFO - PROCESS | 1655 | 1486315652589 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:27:32 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:27:32 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:27:33 INFO - TEST-INFO | 1655: exit 0 09:27:33 INFO - Browser initialized. 09:27:33 INFO - Running cycle 1/1 for kraken test... 09:27:33 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp7pDINs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:45 INFO - PROCESS | 1656 | RSS: Main: 228253696 09:27:45 INFO - PROCESS | 1656 | 09:27:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:49 INFO - PROCESS | 1656 | RSS: Main: 288653312 09:27:49 INFO - PROCESS | 1656 | 09:27:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:27:52 INFO - PROCESS | 1656 | RSS: Main: 291487744 09:27:52 INFO - PROCESS | 1656 | 09:27:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:27:56 INFO - PROCESS | 1656 | RSS: Main: 383213568 09:27:56 INFO - PROCESS | 1656 | 09:27:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:27:58 INFO - PROCESS | 1656 | RSS: Main: 284475392 09:27:58 INFO - PROCESS | 1656 | 09:28:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:28:01 INFO - PROCESS | 1656 | RSS: Main: 287653888 09:28:01 INFO - PROCESS | 1656 | 09:28:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:28:05 INFO - PROCESS | 1656 | RSS: Main: 330133504 09:28:05 INFO - PROCESS | 1656 | 09:28:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:28:08 INFO - PROCESS | 1656 | RSS: Main: 333443072 09:28:08 INFO - PROCESS | 1656 | 09:28:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:28:12 INFO - PROCESS | 1656 | RSS: Main: 330674176 09:28:12 INFO - PROCESS | 1656 | 09:28:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:28:14 INFO - PROCESS | 1656 | RSS: Main: 305745920 09:28:14 INFO - PROCESS | 1656 | 09:28:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:28:16 INFO - PROCESS | 1656 | RSS: Main: 310304768 09:28:16 INFO - PROCESS | 1656 | 09:28:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:28:19 INFO - PROCESS | 1656 | RSS: Main: 311111680 09:28:19 INFO - PROCESS | 1656 | 09:28:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:28:23 INFO - PROCESS | 1656 | RSS: Main: 327483392 09:28:23 INFO - PROCESS | 1656 | 09:28:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:28:27 INFO - PROCESS | 1656 | RSS: Main: 321462272 09:28:27 INFO - PROCESS | 1656 | 09:28:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:28:29 INFO - PROCESS | 1656 | RSS: Main: 366546944 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | __start_tp_report 09:28:29 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:28:29 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:28:29 INFO - PROCESS | 1656 | |i|pagename|runs| 09:28:29 INFO - PROCESS | 1656 | |0;ai-astar;110;116;115;114;115;115;115;114;115;114 09:28:29 INFO - PROCESS | 1656 | |1;audio-beat-detection;142;111;109;149;110;110;109;109;148;107 09:28:29 INFO - PROCESS | 1656 | |2;audio-dft;187;182;185;184;185;182;181;185;183;185 09:28:29 INFO - PROCESS | 1656 | |3;audio-fft;78;59;59;88;59;59;55;49;76;83 09:28:29 INFO - PROCESS | 1656 | |4;audio-oscillator;89;72;86;70;68;70;84;68;69;84 09:28:29 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;86;90;88;89;86;89;86;90;86 09:28:29 INFO - PROCESS | 1656 | |6;imaging-darkroom;99;100;98;98;98;100;98;99;99;116 09:28:29 INFO - PROCESS | 1656 | |7;imaging-desaturate;86;85;88;88;87;88;85;84;86;84 09:28:29 INFO - PROCESS | 1656 | |8;json-parse-financial;58;58;58;58;59;59;58;59;58;58 09:28:29 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;67;71;76;77;71;69;68;70;70;69 09:28:29 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;86;85;85;87;86;88;85;87;94;96 09:28:29 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;143;140;140;141;139;139;140;138;207;167 09:28:29 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;198;176;178;174;175;176;176;181;177;178 09:28:29 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;69;69;70;69;69;68;67;64;67;67 09:28:29 INFO - PROCESS | 1656 | __end_tp_report 09:28:29 INFO - PROCESS | 1656 | __start_cc_report 09:28:29 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2054 09:28:29 INFO - PROCESS | 1656 | __end_cc_report 09:28:29 INFO - PROCESS | 1656 | __startTimestamp1486315709015__endTimestamp 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:28:29 INFO - PROCESS | 1656 | Number of tests: 14 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:1.64 (1.4%) stddev-sans-first:0.67 09:28:29 INFO - PROCESS | 1656 | Values: 110.0 116.0 115.0 114.0 115.0 115.0 115.0 114.0 115.0 114.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:110.50 stddev:18.01 (16.3%) stddev-sans-first:17.33 09:28:29 INFO - PROCESS | 1656 | Values: 142.0 111.0 109.0 149.0 110.0 110.0 109.0 109.0 148.0 107.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:183.90 Median:185.00 stddev:1.85 (1.0%) stddev-sans-first:1.59 09:28:29 INFO - PROCESS | 1656 | Values: 187.0 182.0 185.0 184.0 185.0 182.0 181.0 185.0 183.0 185.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:66.50 Median:67.50 stddev:13.42 (19.9%) stddev-sans-first:13.57 09:28:29 INFO - PROCESS | 1656 | Values: 78.0 59.0 59.0 88.0 59.0 59.0 55.0 49.0 76.0 83.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:78.00 stddev:8.58 (11.0%) stddev-sans-first:7.70 09:28:29 INFO - PROCESS | 1656 | Values: 89.0 72.0 86.0 70.0 68.0 70.0 84.0 68.0 69.0 84.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.50 stddev:1.69 (1.9%) stddev-sans-first:1.79 09:28:29 INFO - PROCESS | 1656 | Values: 88.0 86.0 90.0 88.0 89.0 86.0 89.0 86.0 90.0 86.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:98.00 stddev:5.50 (5.6%) stddev-sans-first:5.81 09:28:29 INFO - PROCESS | 1656 | Values: 99.0 100.0 98.0 98.0 98.0 100.0 98.0 99.0 99.0 116.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.50 stddev:1.60 (1.8%) stddev-sans-first:1.69 09:28:29 INFO - PROCESS | 1656 | Values: 86.0 85.0 88.0 88.0 87.0 88.0 85.0 84.0 86.0 84.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 09:28:29 INFO - PROCESS | 1656 | Values: 58.0 58.0 58.0 58.0 59.0 59.0 58.0 59.0 58.0 58.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.50 stddev:3.26 (4.6%) stddev-sans-first:3.15 09:28:29 INFO - PROCESS | 1656 | Values: 67.0 71.0 76.0 77.0 71.0 69.0 68.0 70.0 70.0 69.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:87.00 stddev:3.90 (4.5%) stddev-sans-first:4.08 09:28:29 INFO - PROCESS | 1656 | Values: 86.0 85.0 85.0 87.0 86.0 88.0 85.0 87.0 94.0 96.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:140.50 stddev:21.99 (15.6%) stddev-sans-first:23.20 09:28:29 INFO - PROCESS | 1656 | Values: 143.0 140.0 140.0 141.0 139.0 139.0 140.0 138.0 207.0 167.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:177.50 stddev:6.98 (3.9%) stddev-sans-first:2.05 09:28:29 INFO - PROCESS | 1656 | Values: 198.0 176.0 178.0 174.0 175.0 176.0 176.0 181.0 177.0 178.0 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:69.00 stddev:1.73 (2.5%) stddev-sans-first:1.79 09:28:29 INFO - PROCESS | 1656 | Values: 69.0 69.0 70.0 69.0 69.0 68.0 67.0 64.0 67.0 67.0 09:28:29 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:28:29 INFO - PROCESS | 1656 | 09:28:29 INFO - TEST-INFO | 1656: exit 0 09:28:29 INFO - TEST-OK | kraken | took 60015ms 09:28:29 INFO - SUITE-END | took 1178s 09:28:30 INFO - Completed test suite (00:19:39) 09:28:30 INFO - javascript benchmark 09:28:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7187.05524782278, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3281.934361312774, 3292.7859689120205, 3308.768386212966, 3310.122817453374, 3364.5794275715534, 5423.132298832187, 5496.587755918302, 5506.80726174584, 5569.6658200508, 5603.83188504345, 5508.476694713996, 5550.86122846929, 5598.104303311471, 5602.411746766582, 5627.6904770237725, 4959.206526955688, 4965.925511117345, 5074.391073071218, 5101.0, 5124.308218390507, 4424.446944131983, 5164.1995490699, 5176.120059589879, 5212.035773381936, 5228.790848366079, 4973.955469351405, 5010.198368261097, 5013.2229504426505, 5041.924371134436, 5158.9742051289695, 5044.672096313728, 5105.642605017651, 5120.078385890575, 5159.71621560814, 5182.455842136596, 5057.848264552069, 5061.266116413136, 5066.290719299301, 5097.13348730713, 5121.2318152277085, 5394.514493695587, 5404.135338345845, 5600.0, 5606.691631960241, 5609.80365687199, 6025.367336429699, 6116.527564188708, 6130.540209484307, 6143.941779503872, 6195.19462469876, 5715.6284841485585, 6257.342978987186, 6278.654673992928, 6280.932241518907, 6285.654289014103, 5774.769009239667, 5777.277840269966, 5777.3933736957015, 5780.479756821865, 5813.79651712188, 6035.517158627299, 6145.0950205435565, 6146.231721034871, 6200.472959798468, 6204.565680402328, 5928.940710592925, 5997.250343707036, 6013.759449621966, 6045.939540604626, 6070.816190842744, 6178.62928224308, 6200.472959798378, 6205.81382558524, 6226.159468471698, 6239.875202495925, 6129.509639228851, 6144.354842741448, 6148.784792532329, 6149.446549810538, 6150.169727086786, 5892.558058145656, 5970.761169553256, 5988.461038506555, 6040.486558642477, 6150.354212807591, 4895.755212239374, 4913.533214344703, 4924.4090709115135, 4925.1873440881855, 4956.801727930914, 4365.214261432972, 4387.956120438755, 4405.3612226227015, 4411.889702757392, 4424.557544245549, 5485.204645326406, 5623.2408624835125, 5634.887302253932, 5647.887042259214, 5699.715014249271, 6090.725917333642, 6118.558266176196, 6143.450468055797, 6155.10750941111, 6176.69116544171, 5638.4925356718095, 5648.124540696199, 5652.97173514138, 5653.378128405872, 5699.373068962411, 5384.434634363335, 5402.0, 5415.404305526389, 5415.675059496442, 5421.241026256327, 6131.040261761288, 6177.752889884354, 6249.78125765596, 6251.062340648939, 6260.248770147611, 6151.6924153793025, 6167.599106058271, 6219.284782249918, 6223.3465486123305, 6251.031090181029, 6151.3541078186145, 6179.227596550431, 6189.721462534107, 6200.224971878515, 6236.469900058546, 6082.878342433038, 6142.682951206299, 6150.969245153746, 6173.135760993375, 6175.475084617858, 6017.638941663514, 6075.635461872302, 6088.756449741961, 6262.279837818709, 6274.247090349005, 5919.733611987557, 5978.581499295007, 5998.370171131968, 6014.278286605635, 6098.75604975796, 5039.571636410946, 6020.819375418744, 6030.939690603038, 6031.366706495754, 6034.456898879122, 5820.94179058204, 5842.824715258549, 5843.766249349978, 5849.824505264849, 5855.9121613176685, 5774.797882074107, 5795.82612521625, 5820.30156381237, 5913.911291330547, 5920.496757775465, 5708.54331653475, 5747.8563035924435, 5755.366909639853, 5764.423557644202, 5769.307683078057], "value": 5600.47214107427, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.35341724373785, 468.5548728707727, 500.3245618415138, 500.39452262762063, 512.9919707774222, 22779.0, 37410.0, 38751.418728719094, 38979.61020389795, 39182.80408597953, 39803.80098099505, 40171.59828401722, 40287.0, 40387.59612403875, 40437.59562404375, 7879.4878332908465, 9076.364654474173, 9308.441493510378, 9312.30157738169, 9314.161725444708, 7845.490043147206, 10797.190210734187, 11048.502817373197, 11071.557137714482, 11158.442077896114, 11029.889701103006, 11122.666320010372, 11133.498992545356, 11148.163887708413, 11164.609238676649, 7846.843063138763, 11023.61417350393, 11104.0, 11118.888811111867, 11123.666290011313, 7895.092064412609, 7980.640871160812, 7981.2018798119725, 7982.960085199595, 10665.9466702666, 38538.0, 38655.806720966495, 38737.61262387368, 38743.806280968696, 38748.41877371842, 3865.362215234483, 3986.40203969404, 3991.840326386941, 4009.158076803875, 4136.152088821785, 24463.0, 28635.0, 29473.11580652584, 30221.848890755624, 30389.0, 2571.2671888511873, 3170.445172094874, 3266.379387916312, 3291.851866665982, 3298.884539041146, 445259.0, 452461.0, 454260.9999999967, 458997.0, 459713.0, 54099.72950135303, 56151.719241404346, 66063.0, 67311.0, 67405.98891016639, 58540.0, 74312.0, 74334.25665743274, 74454.0, 75167.0, 14844.851551484562, 15271.160086195367, 15271.549202825714, 15276.007059541094, 15318.080915145127, 11391.20261581698, 11519.136064795173, 11527.942360288145, 11535.134864885167, 11553.017993470481, 16488.752668709738, 19853.702194467096, 20028.399148025583, 20090.0, 20092.69860952087, 8627.568621568897, 8713.651453941771, 8721.869171962426, 8795.824083518422, 8951.283897288202, 810.7527204202775, 851.535912927456, 857.8541647919868, 877.1141147441131, 886.2466903132286, 24170.07946286505, 27741.58387624187, 28751.42497150045, 29487.26281842971, 29553.85223073871, 1025.789713108811, 1032.948352582383, 1042.9634962776418, 1060.289605964006, 1165.167721785255, 13825.654358641115, 14135.0, 14904.925475372771, 15354.385824566893, 15407.537773866801, 616105.0, 619189.0, 620890.0, 621821.0, 622568.0, 55695.0, 55915.881682366125, 56057.318280451655, 56067.71966140225, 56147.0, 1337.5652912803378, 1389.2428626398637, 1402.0816365280757, 1407.7677183264648, 1408.2254759881819, 27701.0, 28146.577801332587, 28403.86384544595, 28493.715062849937, 29129.85435072811, 48393.27410088922, 48919.75540122276, 48984.75507622439, 49008.75495622499, 49071.754641226566, 435576.0, 437338.81330593146, 437384.0, 437468.0, 437907.0, 32584.51123233106, 32751.508727369586, 33338.499922501665, 36374.63625363712, 39648.40527392034, 43671.56328436675, 44192.266353387386, 44936.77531612321, 45102.77448612736, 45414.09171816611, 28664.713352866205, 35263.471047934814, 36386.63613363833, 37152.628473715995, 37433.81283093567, 35245.82377088098, 35409.64590354064, 36506.45240321344, 36560.634393655724, 36646.45030324494, 32775.836120819244, 32864.0, 34060.489092663134, 35215.647843521234, 35403.46894796529, 23408.88295558511, 28820.443980410666, 29371.0, 31026.93157613877, 31197.8440107798, 18697.81302187015, 22015.559688806454, 22651.660225096966, 22680.65979010349, 23566.410839729146], "value": 19433.75564703068, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.1825675210434, 393.69488646299135, 394.6527056190552, 403.8970062634028, 425.3343517395276, 16409.343626254937, 19721.40835774927, 20827.27104551341, 21058.262960796375, 21063.894680526613, 12321.014318854492, 12641.557545485886, 14349.784753228683, 20970.475738106554, 21257.14971401146, 5252.474752524755, 5659.179418984245, 5690.146478028288, 5704.34400043994, 5931.228940237763, 5905.6751878646655, 5946.940530594682, 5949.464548190662, 5951.702414879261, 5964.493018093467, 5757.856053598673, 5928.940710592904, 5946.435088666592, 6011.789587364421, 6019.729112189962, 5971.253593300838, 6008.279006519246, 6014.699265036731, 6018.856417280746, 6023.608465449732, 5271.20931860224, 5495.148252020943, 5561.4716601922755, 5611.719414029323, 5632.014397480466, 13736.93131534336, 13740.450381984709, 13777.0, 13941.86058139426, 13991.230482323468, 2554.923352299434, 2590.5984572391308, 2596.974030259692, 2646.0376681793064, 2741.150243424545, 10025.498725063717, 10215.591376345012, 10311.43287119208, 10561.260711750181, 10631.627893023784, 1761.9119044047745, 1801.6576850398383, 1824.2429391802386, 1827.7349784281205, 1842.8341449269496, 122846.0, 139993.0, 140231.3676085349, 142658.2867085658, 145599.88002399457, 11397.886021139848, 11892.167548271538, 11914.940425297818, 11928.522859085713, 11978.101642376856, 15533.068015919082, 16034.957727747658, 16157.596060098356, 16218.837811621968, 16234.83765162357, 8112.959435202786, 8263.669453221804, 8324.084350721416, 8357.95821020891, 8392.118827523143, 6718.0, 7800.102988156435, 7840.0199975003125, 7937.166597507294, 7972.6412311444965, 4733.219018861849, 4769.0, 4825.831095911626, 4918.581920536794, 4981.2030075187795, 1985.1364656374524, 2017.8587498875231, 2027.2093883385492, 2028.2495476673726, 2036.175348983664, 859.3211363023266, 972.0231167676582, 1011.8330475471612, 1014.4674046125843, 1026.3226270661328, 19507.609847802963, 21396.50224484271, 21481.78518214829, 21574.0, 21587.568248635253, 1015.4922538730634, 1019.413837043688, 1044.154235069596, 1045.7490202351532, 1045.8431235314763, 10420.114290285259, 10499.317544359592, 11253.66239012831, 11273.267237629529, 11337.773244535229, 623201.0, 690121.0, 695745.0, 702879.0, 712045.0, 105441.0, 110586.34120488199, 130738.9999999981, 151117.0, 151526.0, 871.1941454154882, 996.1035068438464, 1013.5692330759398, 1015.263933648114, 1016.4358780876537, 18379.356722514647, 18691.626167476847, 18859.339923102627, 18872.811271887655, 18920.148593313057, 58126.4187358121, 58272.12591811042, 58428.0, 58850.0, 60137.0, 81231.59384203042, 83119.0, 83676.16323836683, 85047.0, 86175.70736439084, 8200.958995204986, 8370.28852547516, 8463.67249006528, 8528.317734581342, 8675.17487053109, 9393.342466027312, 12636.55772047974, 13068.80396794068, 13398.732025359634, 13422.39599218018, 11090.5563777448, 11242.0, 11341.603043893425, 11493.310401375944, 11549.42252887369, 9927.354721943051, 10018.549165287433, 10158.39049657023, 10222.386656800616, 10486.947565262124, 9576.47329396862, 11169.832452513056, 11401.0, 11555.480003400035, 11584.884151158381, 7183.676734546853, 8267.710630127916, 8301.58492075406, 8326.791830204293, 8672.653093876173, 10964.0, 11470.4264786762, 11528.711782205512, 11640.476178572155, 11663.0], "value": 9593.005868143957, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1147.2084261859322, 1296.429570988765, 1423.103444829757, 1445.3712635003774, 1454.2074569359697, 7516.5114267572635, 7893.329067029309, 7896.842063158733, 7901.367890568755, 7903.367730581547, 7779.611019449019, 8278.420510564263, 8498.1501849815, 8499.107593702649, 8597.183267589584, 2283.4633861042644, 2315.4327189838427, 2319.5708793873105, 2320.918767843126, 2325.4070212095894, 2001.729766481521, 2267.38780529257, 2332.8600283983014, 2335.4628435459854, 2350.1304517328613, 2013.848961327899, 2216.0581752755097, 2293.5986202414592, 2303.8387312888103, 2303.9078436862505, 2256.9617975731185, 2283.1666441748725, 2288.3706980580305, 2290.1869836208075, 2291.770822917695, 2295.4605667668084, 2315.0739704118487, 2316.62933930572, 2319.9768002320097, 2320.872352020638, 8408.663653453854, 8591.140885911358, 8593.484390936565, 8607.82784344316, 8634.568271586395, 4887.0, 4957.727324997124, 5032.295478632995, 5040.294358789773, 5046.798128074873, 3273.836308184605, 3337.5327454156436, 3480.860765569374, 3533.9823300883586, 3542.946855797165, 1035.7773078788098, 1166.7783121206944, 1175.3359351966112, 1190.571394298052, 1243.7512497500536, 11120.55517779288, 11249.55001799927, 11251.874812518765, 11253.54985800567, 11340.319580825177, 4412.139632771643, 4469.41897553316, 4674.018456124184, 4769.35613692154, 4776.116418462596, 9652.662156824477, 9773.02269773017, 9775.0, 9778.511074446391, 9783.657571984946, 5242.030224408497, 5323.760430780547, 5325.920111198335, 5329.573634109262, 5332.0, 4655.138799322136, 4723.291506274018, 4741.976290118528, 4829.154897892855, 4832.8791780205775, 3744.4944932433773, 3811.123441608446, 3818.1218319786603, 3859.980700096538, 3907.374820028782, 1096.4791723931069, 1155.5435602936793, 1259.9433025513874, 1263.684078980255, 1278.284160869916, 709.5919846088519, 878.9208971192495, 881.8941726992801, 917.3945196170496, 966.3863446711248, 5400.378956419983, 5478.616496845182, 5587.608867379326, 5733.713314334266, 5750.993576124161, 855.8031652719909, 934.4907973622551, 983.7343917142474, 997.780488292589, 1028.2545154762738, 2939.691332410066, 3691.412284111168, 3738.9065273367837, 3776.773393596393, 3893.2213557288655, 9931.205503559699, 10166.949165254126, 10273.972602739666, 10290.794184116425, 10301.969803019789, 10218.795624087477, 10225.386476811414, 10236.976302369703, 10244.743881403025, 10246.48767561619, 7590.772276831814, 7897.763067107996, 7931.087924888597, 8007.239312265326, 8025.357971362277, 5620.381758006698, 5751.942480575308, 5780.479756821906, 5805.042168042226, 5837.883242335214, 9074.954625227096, 9228.307876909124, 9233.030531794333, 9415.435073895589, 9518.048195180427, 9396.154346108882, 9593.376430531993, 9601.423914565148, 9674.274429417963, 9684.0, 7263.382612477999, 7423.035005449257, 7428.888566671612, 7432.814179645552, 7447.99452073963, 7903.051633803981, 7993.600319984093, 8050.879236811335, 8077.353811694933, 8078.919210807817, 7234.337658968275, 7274.090738657668, 7360.043194384696, 7368.115826100902, 7475.962620186864, 7215.242399547972, 7249.347558719741, 7295.744648937261, 7353.264673532604, 7445.478816482794, 6323.0, 6345.650989195638, 6364.6181229126405, 6453.806385808433, 6487.0, 4999.400071991385, 5000.0, 5068.341115654941, 5098.209777484496, 5156.04613146569, 4359.629431498358, 5044.697318160922, 5046.243063540498, 5097.719625420637, 5120.436751957356], "value": 4438.267753922113, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.93332266869294, 351.2325568632538, 361.1566991075838, 367.4910249304712, 368.1091757945774, 4112.136451345214, 5046.990601879625, 5072.822451214208, 5307.25698402223, 5352.437994010631, 4477.4180655475575, 4963.975180124095, 4986.351774269349, 5205.479452054802, 5225.477452254772, 3939.463609192417, 3945.8618948336884, 3947.2894878921784, 3958.891510377099, 3995.680345572347, 3818.2745278397156, 3838.827252773618, 3993.8003099845037, 4008.378701301303, 4021.2761702893467, 3612.24142929985, 3862.86479973201, 3999.3201155803436, 3999.7600143991453, 4014.1369605534805, 3354.021127534766, 3911.6675082618012, 3931.348833489941, 3931.4299426582984, 3958.34687276599, 2676.47808677308, 3101.379724055198, 3127.390158919012, 3410.5395771571016, 3412.607550131742, 5084.339035925306, 5089.974550127263, 5157.194981756393, 5179.585633149339, 5198.16829307309, 3910.452536644872, 4230.857668429507, 4456.17560751262, 4483.865484035451, 4561.019380833137, 5057.595392368602, 5429.701366424845, 5445.883593863248, 5467.890642187134, 5478.534324582415, 3152.98423507881, 3227.790193637406, 3548.308079924416, 3711.480392745018, 3777.82662563448, 5176.9741151294, 5208.088584497697, 5431.321084864392, 5648.6893220872835, 5734.397888221718, 3372.7470439717117, 3788.753731007476, 3845.65389114981, 3867.3038853006165, 3871.2838124946975, 3668.596454390015, 3815.8664446744233, 3930.5479869815335, 3944.2111577684577, 3958.6437220649705, 4229.767362795076, 4509.977450112794, 4555.453345598549, 4566.520515345907, 4600.240960241589, 5248.737563121828, 5366.7048312343195, 5490.243122200937, 5496.642718223303, 5554.611177217636, 1123.0, 1203.4524291447372, 1228.834107395508, 1253.2079126848694, 1262.7057266302113, 800.7917941335178, 863.4457975643816, 868.2533021601417, 875.5840975536569, 880.801819590587, 4945.8021679132435, 4950.8267210647455, 4951.356323677971, 4954.455009948902, 4957.240179736142, 5126.948975460022, 5142.665726727751, 5446.291982042309, 5492.725363731797, 5502.614816962774, 4197.685173611931, 4716.457607375127, 4790.461055640516, 4945.505449455098, 4977.352944117314, 2554.948901021969, 2671.8931242750073, 2718.3010618363523, 2796.231036464948, 2810.3114736889183, 5258.684478931276, 5316.362036555638, 5370.892582148335, 5568.638038527482, 5601.29983752031, 4618.515055919147, 5224.477552244821, 5252.133397989365, 5306.62853600252, 5500.064988951888, 4872.489528246184, 5518.641288316247, 5528.585356098309, 5536.806211782568, 5688.5494198978995, 4823.348847905488, 5236.319278493771, 5392.218128371443, 5409.241996351123, 5445.801923576807, 4665.486796452456, 4824.589909857701, 4959.752012399294, 5251.002309561171, 5344.198370244494, 4788.760561971957, 4919.335889654851, 5616.410276921028, 5648.0, 5653.886922261614, 3114.9532757009115, 3607.639236076371, 3625.945610815787, 3640.6541378568995, 3641.5812181598394, 4655.86032419028, 4669.976650116728, 5224.451432599522, 5363.168708850135, 5484.8354549363585, 4574.88562785933, 5168.9483105168465, 5199.570118217521, 5247.816326428557, 5259.342582177228, 5218.529677764396, 5230.267762513327, 5286.312779338661, 5288.338957630296, 5328.7086552782675, 4379.518252992232, 4890.486498917705, 5004.84985450437, 5104.41299250591, 5130.640855140254, 4610.838620648261, 4615.469221039623, 5023.899522009612, 5095.515925987025, 5220.921686174786, 5007.924881126713, 5161.741912904415, 5173.860911271044, 5188.900768392928, 5204.713740744295], "value": 3923.1203102756403, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.5365878032327, 695.1102588686476, 765.6210175962899, 811.5536454949778, 821.7740121466596, 14208.857911420882, 14821.407143714265, 14822.332995015224, 14844.777328340084, 14848.703025939474, 13498.865011349908, 13614.251216183105, 13614.659633509167, 13643.590692279222, 13657.521986730466, 861.8750281209236, 862.4695812075591, 875.1685898396548, 884.5665623844302, 884.8274586455643, 880.3133555826433, 894.9284057275435, 895.1451363947447, 896.6592694775976, 897.7261935109789, 932.5523748600676, 932.6269492203105, 933.5798890499268, 934.0985948559638, 935.3452583191759, 928.6424726480286, 930.4556834251971, 932.3566738950103, 932.5710173320315, 933.6545478173051, 696.1854630082764, 697.8778713725078, 698.2458944340151, 698.3261152987341, 698.4796326736614, 14624.195669238188, 14762.335694893649, 14807.0, 14973.850261497464, 15098.698026039418, 11882.703777335984, 13931.373088211054, 14103.0, 14184.290785460686, 14205.218712970782, 11433.371164585944, 11542.596009139725, 11646.359450230233, 12094.758104837942, 12228.755424891542, 3324.3683700097145, 3582.22982058856, 3644.325799727059, 3664.2089047754375, 3676.356637588438, 100753.0, 101792.98207017983, 102137.0, 103708.29604371756, 104105.47947260215, 10428.739281518023, 10753.623623173151, 10875.564977400974, 10942.539720463461, 10974.780504389868, 21293.89353053225, 21827.890860545594, 22138.77861221367, 22147.0, 22187.223447179273, 7618.6190690465255, 7631.656575454118, 7666.540007599562, 7738.883916741253, 7840.764777056584, 4172.065301891696, 4259.524093274547, 4264.040590867092, 4324.935125973113, 4384.517703052662, 3642.9635703642625, 3736.663700266989, 3739.3456145174487, 3743.9438408423894, 3746.7189960753053, 661.2990230355742, 679.2833560593523, 685.8662560800695, 688.5008368932585, 695.391532409142, 648.1753862876083, 659.6174218952996, 665.9533832631766, 677.2922296200483, 679.1714108787272, 10003.749531308586, 10023.2482563808, 10034.899651003543, 10157.0, 10209.846852297072, 639.9776007839703, 677.2584020497547, 678.6945874356461, 693.1578132568953, 694.8193469697915, 3810.580836108026, 4933.432655244693, 4956.653034287565, 4964.553190212898, 4974.129527332702, 100066.4996675012, 103073.96926030793, 104518.77406129663, 105091.0, 109964.4501777486, 87688.0, 92703.53648231715, 93538.0, 93987.0, 94980.52509737408, 82032.17967820246, 82226.58886705647, 82847.17152828515, 83561.16438835656, 84640.0, 9594.66418675343, 9609.135177833748, 9676.279930549648, 9709.048385323676, 9859.408435493893, 44454.55545444504, 52274.693132671986, 53670.0, 53797.42721868528, 53870.46129538654, 67293.66353168203, 69658.95511567229, 71441.0, 72969.27030729625, 73224.63387683028, 11405.144614153805, 11482.94258528702, 11515.654530364103, 11566.016888564567, 11951.10366722482, 15070.040894683621, 15094.39622415135, 15129.39482420735, 15400.537983860502, 15716.685666286381, 13632.65918352049, 14140.222287774272, 14149.532803131486, 14311.713765724835, 14624.195669238294, 13954.511592094228, 14209.644758881112, 14258.857411426168, 14268.714625707635, 14307.21310327942, 12240.75518489643, 13534.864651353755, 13674.0, 13874.375653095432, 14044.578662640137, 11060.557577696802, 11201.71995700114, 11598.88401115978, 11648.475818588353, 11962.222455540363, 10190.337628054152, 11060.612878549213, 11108.333499990027, 11224.494897729784, 11256.155788315746], "value": 7580.122945416636, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 115.0, 114.0, 115.0, 115.0, 115.0, 114.0, 115.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 109.0, 149.0, 110.0, 110.0, 109.0, 109.0, 148.0, 107.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 185.0, 184.0, 185.0, 182.0, 181.0, 185.0, 183.0, 185.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 59.0, 88.0, 59.0, 59.0, 55.0, 49.0, 76.0, 83.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 86.0, 70.0, 68.0, 70.0, 84.0, 68.0, 69.0, 84.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 88.0, 89.0, 86.0, 89.0, 86.0, 90.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 98.0, 98.0, 98.0, 100.0, 98.0, 99.0, 99.0, 116.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 88.0, 88.0, 87.0, 88.0, 85.0, 84.0, 86.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 76.0, 77.0, 71.0, 69.0, 68.0, 70.0, 70.0, 69.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 87.0, 86.0, 88.0, 85.0, 87.0, 94.0, 96.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 140.0, 141.0, 139.0, 139.0, 140.0, 138.0, 207.0, 167.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 176.0, 178.0, 174.0, 175.0, 176.0, 176.0, 181.0, 177.0, 178.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 69.0, 69.0, 68.0, 67.0, 64.0, 67.0, 67.0], "value": 67.9, "unit": "score"}]}]} 09:28:30 INFO - Return code: 0 09:28:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:28:30 ERROR - # TBPL SUCCESS # 09:28:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:30 INFO - 2017-02-05 09:28:30.340 screenresolution[1660:10706] starting screenresolution argv=screenresolution get 09:28:30 INFO - 2017-02-05 09:28:30.342 screenresolution[1660:10706] Display 0: 1600x1200x32@60 09:28:30 INFO - 2017-02-05 09:28:30.349 screenresolution[1661:10709] starting screenresolution argv=screenresolution list 09:28:30 INFO - Available Modes on Display 0 09:28:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:30 INFO - Intel Iris: 09:28:30 INFO - Chipset Model: Intel Iris 09:28:30 INFO - Type: GPU 09:28:30 INFO - Bus: Built-In 09:28:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:30 INFO - Vendor: Intel (0x8086) 09:28:30 INFO - Device ID: 0x0a2e 09:28:30 INFO - Revision ID: 0x0009 09:28:30 INFO - Displays: 09:28:30 INFO - Display: 09:28:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:30 INFO - Display Serial Number: 1600x1200 60 09:28:30 INFO - Main Display: Yes 09:28:30 INFO - Mirror: Off 09:28:30 INFO - Online: Yes 09:28:30 INFO - Rotation: Supported 09:28:30 INFO - Return code: 0 09:28:30 INFO - Running post-action listener: _resource_record_post_action 09:28:30 INFO - [mozharness: 2017-02-05 17:28:30.589000Z] Finished run-tests step (success) 09:28:30 INFO - Running post-run listener: _resource_record_post_run 09:28:30 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 33729536; Write bytes: 553888768; Read time: 317; Write time: 1874 09:28:30 INFO - TinderboxPrint: CPU usage
54.2% 09:28:30 INFO - TinderboxPrint: I/O read bytes / time
33,729,536 / 317 09:28:30 INFO - TinderboxPrint: I/O write bytes / time
553,888,768 / 1,874 09:28:30 INFO - TinderboxPrint: CPU idle
2,202.1 (45.8%) 09:28:30 INFO - TinderboxPrint: CPU system
968.2 (20.1%) 09:28:30 INFO - TinderboxPrint: CPU user
1,639.3 (34.1%) 09:28:30 INFO - TinderboxPrint: Swap in / out
281,886,720 / 0 09:28:30 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 237801984; Write bytes: 233261056; Read time: 17269; Write time: 457 09:28:30 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32791552; Write bytes: 297972736; Read time: 303; Write time: 1372 09:28:31 INFO - Running post-run listener: _upload_blobber_files 09:28:31 INFO - Blob upload gear active. 09:28:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:28:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:28:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:28:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:28:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:32 INFO - (blobuploader) - INFO - Done attempting. 09:28:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:28:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:28:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:33 INFO - (blobuploader) - INFO - Done attempting. 09:28:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:28:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:28:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:35 INFO - (blobuploader) - INFO - Done attempting. 09:28:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:28:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:28:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:37 INFO - (blobuploader) - INFO - Done attempting. 09:28:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:28:37 INFO - Return code: 0 09:28:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:28:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:28:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b539ca23002e22fffa14ed4addd877f89ab7ec4f26a4ee6ed8c27cab84b34a9b1a1379e5e4a5dfccb3c4ebf7287f4b6fd54f4e3ce018421125f5db49f20fbb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f444d979459ffa83b67e613c99818469ef0175d86365dbe9e550a9162bb726e776869ca251ffa032dbf567f4bc7f3948ae48243c594abd6fbe029b56f44be21b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05f9810ae254698028f59717c074966d39c6d84c9de54ef95a548ae99ad82963b51dd9b60e764e364893212985f73bc898912cec6ad7945762a26c2c41c6e69e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37f53042ddf249a9b31102586c357d466962f3c393f7b80dbac1760ca9f0ab4695780a61c68a68a5ca59db048a4a8bf8f4bf279fca916806e6203c5f67a5a992"} 09:28:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:28:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:28:37 INFO - Contents: 09:28:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b539ca23002e22fffa14ed4addd877f89ab7ec4f26a4ee6ed8c27cab84b34a9b1a1379e5e4a5dfccb3c4ebf7287f4b6fd54f4e3ce018421125f5db49f20fbb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f444d979459ffa83b67e613c99818469ef0175d86365dbe9e550a9162bb726e776869ca251ffa032dbf567f4bc7f3948ae48243c594abd6fbe029b56f44be21b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05f9810ae254698028f59717c074966d39c6d84c9de54ef95a548ae99ad82963b51dd9b60e764e364893212985f73bc898912cec6ad7945762a26c2c41c6e69e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37f53042ddf249a9b31102586c357d466962f3c393f7b80dbac1760ca9f0ab4695780a61c68a68a5ca59db048a4a8bf8f4bf279fca916806e6203c5f67a5a992"} 09:28:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:28:37 INFO - Copying logs to upload dir... 09:28:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:28:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.167729 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-05 09:28:37.814537) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 09:28:37.823651) ========= 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.6NeOsfFCGi/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.ccP6djIMyF/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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/5b539ca23002e22fffa14ed4addd877f89ab7ec4f26a4ee6ed8c27cab84b34a9b1a1379e5e4a5dfccb3c4ebf7287f4b6fd54f4e3ce018421125f5db49f20fbb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f444d979459ffa83b67e613c99818469ef0175d86365dbe9e550a9162bb726e776869ca251ffa032dbf567f4bc7f3948ae48243c594abd6fbe029b56f44be21b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05f9810ae254698028f59717c074966d39c6d84c9de54ef95a548ae99ad82963b51dd9b60e764e364893212985f73bc898912cec6ad7945762a26c2c41c6e69e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37f53042ddf249a9b31102586c357d466962f3c393f7b80dbac1760ca9f0ab4695780a61c68a68a5ca59db048a4a8bf8f4bf279fca916806e6203c5f67a5a992"} build_url:https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/QwUYNqj2Qw664FpV9yTFRA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b539ca23002e22fffa14ed4addd877f89ab7ec4f26a4ee6ed8c27cab84b34a9b1a1379e5e4a5dfccb3c4ebf7287f4b6fd54f4e3ce018421125f5db49f20fbb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f444d979459ffa83b67e613c99818469ef0175d86365dbe9e550a9162bb726e776869ca251ffa032dbf567f4bc7f3948ae48243c594abd6fbe029b56f44be21b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05f9810ae254698028f59717c074966d39c6d84c9de54ef95a548ae99ad82963b51dd9b60e764e364893212985f73bc898912cec6ad7945762a26c2c41c6e69e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37f53042ddf249a9b31102586c357d466962f3c393f7b80dbac1760ca9f0ab4695780a61c68a68a5ca59db048a4a8bf8f4bf279fca916806e6203c5f67a5a992"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 09:28:37.859993) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:28:37.860327) ========= 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.6NeOsfFCGi/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.ccP6djIMyF/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005187 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 09:28:37.913075) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 09:28:37.913394) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 09:28:37.913701) ========= ========= Total master_lag: 0.21 =========