builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0364 starttime: 1485912927.75 results: success (0) buildid: 20170131160608 builduid: deffccb8bc9b47bf987b492a69458a2b revision: 0d5fd88afc55a306466f7d98f895ea9181cc5526 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.754876) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.755296) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.755570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.783884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.784145) ========= 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.4NSXz8NLdk/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.dmdCT31xUY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-01-31 17:35:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 74.0M=0s 2017-01-31 17:35:27 (74.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127102 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.934383) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.934695) ========= 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.4NSXz8NLdk/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.dmdCT31xUY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.024956 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:27.985697) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 17:35:27.986016) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d5fd88afc55a306466f7d98f895ea9181cc5526 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d5fd88afc55a306466f7d98f895ea9181cc5526 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4NSXz8NLdk/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.dmdCT31xUY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-01-31 17:35:28,095 truncating revision to first 12 chars 2017-01-31 17:35:28,096 Setting DEBUG logging. 2017-01-31 17:35:28,096 attempt 1/10 2017-01-31 17:35:28,096 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0d5fd88afc55?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 17:35:29,095 unpacking tar archive at: mozilla-aurora-0d5fd88afc55/testing/mozharness/ program finished with exit code 0 elapsedTime=1.274688 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 17:35:29.289606) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:29.289958) ========= script_repo_revision: 0d5fd88afc55a306466f7d98f895ea9181cc5526 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:29.290346) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:29.290608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 17:35:29.309957) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-31 17:35:29.310253) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4NSXz8NLdk/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.dmdCT31xUY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:35:29 INFO - MultiFileLogger online at 20170131 17:35:29 in /builds/slave/test 17:35:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:35:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:35:29 INFO - {'append_to_log': False, 17:35:29 INFO - 'base_work_dir': '/builds/slave/test', 17:35:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:35:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:35:29 INFO - 'branch': 'Mozilla-Aurora', 17:35:29 INFO - 'buildbot_json_path': 'buildprops.json', 17:35:29 INFO - 'config_files': ('talos/mac_config.py',), 17:35:29 INFO - 'default_actions': ('clobber', 17:35:29 INFO - 'read-buildbot-config', 17:35:29 INFO - 'download-and-extract', 17:35:29 INFO - 'populate-webroot', 17:35:29 INFO - 'create-virtualenv', 17:35:29 INFO - 'install', 17:35:29 INFO - 'run-tests'), 17:35:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:35:29 INFO - 'download_minidump_stackwalk': True, 17:35:29 INFO - 'download_symbols': 'ondemand', 17:35:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:35:29 INFO - 'tooltool.py': '/tools/tooltool.py', 17:35:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:35:29 INFO - '/tools/misc-python/virtualenv.py')}, 17:35:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:35:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:35:29 INFO - 'installer_path': 'installer.exe', 17:35:29 INFO - 'log_level': 'info', 17:35:29 INFO - 'log_name': 'talos', 17:35:29 INFO - 'log_to_console': True, 17:35:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:35:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:35:29 INFO - 'opt_config_files': (), 17:35:29 INFO - 'pip_index': False, 17:35:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:35:29 INFO - 'cmd': ('bash', 17:35:29 INFO - '-c', 17:35:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:35:29 INFO - 'enabled': True, 17:35:29 INFO - 'halt_on_failure': False, 17:35:29 INFO - 'name': 'check_screen_resolution'},), 17:35:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:35:29 INFO - 'cmd': ('bash', 17:35:29 INFO - '-c', 17:35:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:35:29 INFO - 'enabled': True, 17:35:29 INFO - 'halt_on_failure': False, 17:35:29 INFO - 'name': 'check_screen_resolution'},), 17:35:29 INFO - 'run_cmd_checks_enabled': True, 17:35:29 INFO - 'sps_profile': False, 17:35:29 INFO - 'sps_profile_interval': 0, 17:35:29 INFO - 'suite': 'dromaeojs-e10s', 17:35:29 INFO - 'system_bits': '32', 17:35:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:35:29 INFO - 'title': 't-yosemite-r7-0364', 17:35:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:35:29 INFO - 'use_talos_json': True, 17:35:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:35:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:35:29 INFO - 'work_dir': 'build'} 17:35:29 INFO - [mozharness: 2017-02-01 01:35:29.528002Z] Running clobber step. 17:35:29 INFO - Running pre-action listener: _resource_record_pre_action 17:35:29 INFO - Running main action method: clobber 17:35:29 INFO - rmtree: /builds/slave/test/build 17:35:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:35:31 INFO - Running post-action listener: _resource_record_post_action 17:35:31 INFO - [mozharness: 2017-02-01 01:35:31.911758Z] Finished clobber step (success) 17:35:31 INFO - [mozharness: 2017-02-01 01:35:31.911884Z] Running read-buildbot-config step. 17:35:31 INFO - Running pre-action listener: _resource_record_pre_action 17:35:31 INFO - Running main action method: read_buildbot_config 17:35:31 INFO - Using buildbot properties: 17:35:31 INFO - { 17:35:31 INFO - "project": "", 17:35:31 INFO - "product": "firefox", 17:35:31 INFO - "script_repo_revision": "production", 17:35:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 17:35:31 INFO - "repository": "", 17:35:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 17:35:31 INFO - "buildid": "20170131160608", 17:35:31 INFO - "pgo_build": "False", 17:35:31 INFO - "basedir": "/builds/slave/test", 17:35:31 INFO - "buildnumber": 41, 17:35:31 INFO - "slavename": "t-yosemite-r7-0364", 17:35:31 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:35:31 INFO - "platform": "yosemite_r7", 17:35:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:35:31 INFO - "branch": "mozilla-aurora", 17:35:31 INFO - "repo_path": "releases/mozilla-aurora", 17:35:31 INFO - "revision": "0d5fd88afc55a306466f7d98f895ea9181cc5526", 17:35:31 INFO - "stage_platform": "macosx64", 17:35:31 INFO - "builduid": "deffccb8bc9b47bf987b492a69458a2b", 17:35:31 INFO - "slavebuilddir": "test" 17:35:31 INFO - } 17:35:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 17:35:31 INFO - Running post-action listener: _resource_record_post_action 17:35:31 INFO - [mozharness: 2017-02-01 01:35:31.915167Z] Finished read-buildbot-config step (success) 17:35:31 INFO - [mozharness: 2017-02-01 01:35:31.915289Z] Running download-and-extract step. 17:35:31 INFO - Running pre-action listener: _resource_record_pre_action 17:35:31 INFO - Running main action method: download_and_extract 17:35:31 INFO - mkdir: /builds/slave/test/build/tests 17:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:31 INFO - trying https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 17:35:31 INFO - Downloading https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 17:35:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 17:35:32 INFO - Downloaded 1199 bytes. 17:35:32 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 17:35:32 INFO - Using the following test package requirements: 17:35:32 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 17:35:32 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 17:35:32 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 17:35:32 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'jsshell-mac.zip'], 17:35:32 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 17:35:32 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 17:35:32 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 17:35:32 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 17:35:32 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 17:35:32 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:35:32 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 17:35:32 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:35:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 17:35:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:35:32 INFO - Fetch https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 17:35:33 INFO - Expected file size: 16353494 17:35:33 INFO - Obtained file size: 16353494 17:35:40 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 17:35:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 17:35:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:35:40 INFO - Fetch https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 17:35:41 INFO - Expected file size: 16353494 17:35:41 INFO - Obtained file size: 16353494 17:35:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 17:35:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:35:47 INFO - Fetch https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 17:35:48 INFO - Expected file size: 14403485 17:35:48 INFO - Obtained file size: 14403485 17:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:48 INFO - trying https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 17:35:48 INFO - Downloading https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 17:35:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 17:35:50 INFO - Downloaded 73859162 bytes. 17:35:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 17:35:50 INFO - mkdir: /builds/slave/test/properties 17:35:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:35:50 INFO - Writing to file /builds/slave/test/properties/build_url 17:35:50 INFO - Contents: 17:35:50 INFO - build_url:https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 17:35:51 INFO - Running post-action listener: _resource_record_post_action 17:35:51 INFO - Running post-action listener: set_extra_try_arguments 17:35:51 INFO - [mozharness: 2017-02-01 01:35:51.562761Z] Finished download-and-extract step (success) 17:35:51 INFO - [mozharness: 2017-02-01 01:35:51.562882Z] Running populate-webroot step. 17:35:51 INFO - Running pre-action listener: _resource_record_pre_action 17:35:51 INFO - Running main action method: populate_webroot 17:35:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:35:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:35:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:35:51 INFO - '--tppagecycles', 17:35:51 INFO - '7'], 17:35:51 INFO - 'tests': ['tsvgm']}}, 17:35:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:35:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:35:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:35:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:35:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:35:51 INFO - 'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:35:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:35:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:35:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:35:51 INFO - 'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['damp', 'tps']}, 17:35:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:35:51 INFO - 'tests': ['damp', 'tps']}, 17:35:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['dromaeo_dom']}, 17:35:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:35:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['basic_compositor_video']}, 17:35:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:35:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['a11yr', 17:35:51 INFO - 'ts_paint', 17:35:51 INFO - 'tpaint', 17:35:51 INFO - 'sessionrestore', 17:35:51 INFO - 'sessionrestore_no_auto_restore', 17:35:51 INFO - 'tabpaint']}, 17:35:51 INFO - 'other-e10s': {'tests': ['a11yr', 17:35:51 INFO - 'ts_paint', 17:35:51 INFO - 'tpaint', 17:35:51 INFO - 'sessionrestore', 17:35:51 INFO - 'sessionrestore_no_auto_restore', 17:35:51 INFO - 'tabpaint']}, 17:35:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:35:51 INFO - 'ts_paint', 17:35:51 INFO - 'tpaint', 17:35:51 INFO - 'sessionrestore', 17:35:51 INFO - 'sessionrestore_no_auto_restore', 17:35:51 INFO - 'tabpaint']}, 17:35:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:35:51 INFO - 'ts_paint', 17:35:51 INFO - 'tpaint', 17:35:51 INFO - 'sessionrestore', 17:35:51 INFO - 'sessionrestore_no_auto_restore', 17:35:51 INFO - 'tabpaint']}, 17:35:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['a11yr', 17:35:51 INFO - 'ts_paint', 17:35:51 INFO - 'tpaint', 17:35:51 INFO - 'sessionrestore', 17:35:51 INFO - 'sessionrestore_no_auto_restore', 17:35:51 INFO - 'tabpaint']}, 17:35:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['a11yr', 17:35:51 INFO - 'ts_paint', 17:35:51 INFO - 'tpaint', 17:35:51 INFO - 'sessionrestore', 17:35:51 INFO - 'sessionrestore_no_auto_restore', 17:35:51 INFO - 'tabpaint']}, 17:35:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['tsvgx', 17:35:51 INFO - 'tsvgr_opacity', 17:35:51 INFO - 'tart', 17:35:51 INFO - 'tscrollx', 17:35:51 INFO - 'cart', 17:35:51 INFO - 'tsvg_static']}, 17:35:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:35:51 INFO - 'tsvgr_opacity', 17:35:51 INFO - 'tart', 17:35:51 INFO - 'tscrollx', 17:35:51 INFO - 'cart', 17:35:51 INFO - 'tsvg_static']}, 17:35:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:35:51 INFO - 'talos_options': ['--disable-e10s'], 17:35:51 INFO - 'tests': ['tp5o']}, 17:35:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:35:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:35:51 INFO - 'talos_options': ['--disable-e10s', 17:35:51 INFO - '--xperf_path', 17:35:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:35:51 INFO - 'tests': ['tp5n']}, 17:35:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:35:51 INFO - 'talos_options': ['--xperf_path', 17:35:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:35:51 INFO - 'tests': ['tp5n']}}, 17:35:51 INFO - 'talos.zip': {'path': '', 17:35:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:35:51 INFO - Running post-action listener: _resource_record_post_action 17:35:51 INFO - [mozharness: 2017-02-01 01:35:51.581606Z] Finished populate-webroot step (success) 17:35:51 INFO - [mozharness: 2017-02-01 01:35:51.581737Z] Running create-virtualenv step. 17:35:51 INFO - Running pre-action listener: _resource_record_pre_action 17:35:51 INFO - Running main action method: create_virtualenv 17:35:51 INFO - Creating virtualenv /builds/slave/test/build/venv 17:35:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:35:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:35:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:35:51 INFO - Using real prefix '/tools/python27' 17:35:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:35:52 INFO - Installing distribute.............................................................................................................................................................................................done. 17:35:55 INFO - Installing pip.................done. 17:35:55 INFO - Return code: 0 17:35:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:35:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:35:55 INFO - Reading from file tmpfile_stdout 17:35:55 INFO - Output received: 17:35:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:35:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:55 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 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:35:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:35:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:35:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:35:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:55 INFO - 'HOME': '/Users/cltbld', 17:35:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:55 INFO - 'LOGNAME': 'cltbld', 17:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:55 INFO - 'MOZ_NO_REMOTE': '1', 17:35:55 INFO - 'NO_EM_RESTART': '1', 17:35:55 INFO - 'PAGER': '/bin/cat', 17:35:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:55 INFO - 'PWD': '/builds/slave/test', 17:35:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:55 INFO - 'SHELL': '/bin/bash', 17:35:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:35:55 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:35:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:55 INFO - 'USER': 'cltbld', 17:35:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:55 INFO - 'XPC_FLAGS': '0x0', 17:35:55 INFO - 'XPC_SERVICE_NAME': '0', 17:35:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:35:55 INFO - Cleaning up... 17:35:55 INFO - Return code: 0 17:35:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:55 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 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:35:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:35:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:35:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:35:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:55 INFO - 'HOME': '/Users/cltbld', 17:35:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:55 INFO - 'LOGNAME': 'cltbld', 17:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:55 INFO - 'MOZ_NO_REMOTE': '1', 17:35:55 INFO - 'NO_EM_RESTART': '1', 17:35:55 INFO - 'PAGER': '/bin/cat', 17:35:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:55 INFO - 'PWD': '/builds/slave/test', 17:35:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:55 INFO - 'SHELL': '/bin/bash', 17:35:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:35:55 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:35:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:55 INFO - 'USER': 'cltbld', 17:35:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:55 INFO - 'XPC_FLAGS': '0x0', 17:35:55 INFO - 'XPC_SERVICE_NAME': '0', 17:35:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:56 INFO - Downloading/unpacking psutil>=3.1.1 17:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:35:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:35:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:35:59 INFO - Installing collected packages: psutil 17:35:59 INFO - Running setup.py install for psutil 17:35:59 INFO - building 'psutil._psutil_osx' extension 17:35:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:36:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:36:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:36:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:36:00 INFO - building 'psutil._psutil_posix' extension 17:36:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:36:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:36:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:36:00 INFO - ^ 17:36:00 INFO - 1 warning generated. 17:36:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:36:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:36:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:36:00 INFO - Successfully installed psutil 17:36:00 INFO - Cleaning up... 17:36:00 INFO - Return code: 0 17:36:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:00 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 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:00 INFO - 'HOME': '/Users/cltbld', 17:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:00 INFO - 'LOGNAME': 'cltbld', 17:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:00 INFO - 'MOZ_NO_REMOTE': '1', 17:36:00 INFO - 'NO_EM_RESTART': '1', 17:36:00 INFO - 'PAGER': '/bin/cat', 17:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:00 INFO - 'PWD': '/builds/slave/test', 17:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:00 INFO - 'SHELL': '/bin/bash', 17:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:00 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:00 INFO - 'USER': 'cltbld', 17:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:00 INFO - 'XPC_FLAGS': '0x0', 17:36:00 INFO - 'XPC_SERVICE_NAME': '0', 17:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:36:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:36:03 INFO - Installing collected packages: mozsystemmonitor 17:36:03 INFO - Running setup.py install for mozsystemmonitor 17:36:03 INFO - Successfully installed mozsystemmonitor 17:36:03 INFO - Cleaning up... 17:36:04 INFO - Return code: 0 17:36:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:36: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')]} 17:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36: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')]} 17:36:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36: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', '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 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:36: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 jsonschema==2.5.1 17:36:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:04 INFO - 'HOME': '/Users/cltbld', 17:36:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:04 INFO - 'LOGNAME': 'cltbld', 17:36:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:04 INFO - 'MOZ_NO_REMOTE': '1', 17:36:04 INFO - 'NO_EM_RESTART': '1', 17:36:04 INFO - 'PAGER': '/bin/cat', 17:36:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:04 INFO - 'PWD': '/builds/slave/test', 17:36:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:04 INFO - 'SHELL': '/bin/bash', 17:36:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:04 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:04 INFO - 'USER': 'cltbld', 17:36:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:04 INFO - 'XPC_FLAGS': '0x0', 17:36:04 INFO - 'XPC_SERVICE_NAME': '0', 17:36:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:04 INFO - Downloading/unpacking jsonschema==2.5.1 17:36: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 17:36: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 17:36: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 17:36: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 17:36: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 17:36: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 17:36:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:36:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:07 INFO - Downloading functools32-3.2.3-2.tar.gz 17:36:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:36:07 INFO - warning: no files found matching '*.txt' 17:36:07 INFO - no previously-included directories found matching 'build' 17:36:07 INFO - no previously-included directories found matching 'dist' 17:36:07 INFO - no previously-included directories found matching '.git*' 17:36:07 INFO - Installing collected packages: jsonschema, functools32 17:36:07 INFO - Running setup.py install for functools32 17:36:07 INFO - warning: no files found matching '*.txt' 17:36:07 INFO - no previously-included directories found matching 'build' 17:36:07 INFO - no previously-included directories found matching 'dist' 17:36:07 INFO - no previously-included directories found matching '.git*' 17:36:07 INFO - Successfully installed jsonschema functools32 17:36:07 INFO - Cleaning up... 17:36:07 INFO - Return code: 0 17:36:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:36: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')]} 17:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:36:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:36:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:08 INFO - 'HOME': '/Users/cltbld', 17:36:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:08 INFO - 'LOGNAME': 'cltbld', 17:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:08 INFO - 'MOZ_NO_REMOTE': '1', 17:36:08 INFO - 'NO_EM_RESTART': '1', 17:36:08 INFO - 'PAGER': '/bin/cat', 17:36:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:08 INFO - 'PWD': '/builds/slave/test', 17:36:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:08 INFO - 'SHELL': '/bin/bash', 17:36:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:08 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:08 INFO - 'USER': 'cltbld', 17:36:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:08 INFO - 'XPC_FLAGS': '0x0', 17:36:08 INFO - 'XPC_SERVICE_NAME': '0', 17:36:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:36:08 INFO - Cleaning up... 17:36:08 INFO - Return code: 0 17:36:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:36:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:09 INFO - 'HOME': '/Users/cltbld', 17:36:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:09 INFO - 'LOGNAME': 'cltbld', 17:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:09 INFO - 'MOZ_NO_REMOTE': '1', 17:36:09 INFO - 'NO_EM_RESTART': '1', 17:36:09 INFO - 'PAGER': '/bin/cat', 17:36:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:09 INFO - 'PWD': '/builds/slave/test', 17:36:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:09 INFO - 'SHELL': '/bin/bash', 17:36:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:09 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:09 INFO - 'USER': 'cltbld', 17:36:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:09 INFO - 'XPC_FLAGS': '0x0', 17:36:09 INFO - 'XPC_SERVICE_NAME': '0', 17:36:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:09 INFO - Downloading/unpacking blobuploader==1.2.4 17:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:12 INFO - Downloading blobuploader-1.2.4.tar.gz 17:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:36:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:36:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:13 INFO - Downloading docopt-0.6.1.tar.gz 17:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:36:14 INFO - Installing collected packages: blobuploader, requests, docopt 17:36:14 INFO - Running setup.py install for blobuploader 17:36:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:36:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:36:14 INFO - Running setup.py install for requests 17:36:14 INFO - Running setup.py install for docopt 17:36:14 INFO - Successfully installed blobuploader requests docopt 17:36:14 INFO - Cleaning up... 17:36:14 INFO - Return code: 0 17:36:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:36:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:14 INFO - 'HOME': '/Users/cltbld', 17:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:14 INFO - 'LOGNAME': 'cltbld', 17:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:14 INFO - 'MOZ_NO_REMOTE': '1', 17:36:14 INFO - 'NO_EM_RESTART': '1', 17:36:14 INFO - 'PAGER': '/bin/cat', 17:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:14 INFO - 'PWD': '/builds/slave/test', 17:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:14 INFO - 'SHELL': '/bin/bash', 17:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:14 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:14 INFO - 'USER': 'cltbld', 17:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:14 INFO - 'XPC_FLAGS': '0x0', 17:36:14 INFO - 'XPC_SERVICE_NAME': '0', 17:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-kS8OnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-rW7S6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-9IbJgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-i8lvPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-P8Pz6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-iFAlrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-6_p49M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-P9LrVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:36:15 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-tVeWsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-sZ47Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-J2MBlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-0NiJSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-AO7z2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-nlriKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-ehOQyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-FJ38bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:36:16 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-KmU2B_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:36:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:36:16 INFO - Running setup.py install for manifestparser 17:36:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:36:17 INFO - Running setup.py install for mozcrash 17:36:17 INFO - Running setup.py install for mozdebug 17:36:17 INFO - Running setup.py install for mozdevice 17:36:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:36:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:36:17 INFO - Running setup.py install for mozfile 17:36:17 INFO - Running setup.py install for mozhttpd 17:36:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:36:17 INFO - Running setup.py install for mozinfo 17:36:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:36:17 INFO - Running setup.py install for mozInstall 17:36:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Running setup.py install for mozleak 17:36:18 INFO - Running setup.py install for mozlog 17:36:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Running setup.py install for moznetwork 17:36:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Running setup.py install for mozprocess 17:36:18 INFO - Running setup.py install for mozprofile 17:36:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:36:18 INFO - Running setup.py install for mozrunner 17:36:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:36:19 INFO - Running setup.py install for mozscreenshot 17:36:19 INFO - Running setup.py install for moztest 17:36:19 INFO - Running setup.py install for mozversion 17:36:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:36:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:36:19 INFO - Cleaning up... 17:36:19 INFO - Return code: 0 17:36:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:19 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 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:36:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:36:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:19 INFO - 'HOME': '/Users/cltbld', 17:36:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:19 INFO - 'LOGNAME': 'cltbld', 17:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:19 INFO - 'MOZ_NO_REMOTE': '1', 17:36:19 INFO - 'NO_EM_RESTART': '1', 17:36:19 INFO - 'PAGER': '/bin/cat', 17:36:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:19 INFO - 'PWD': '/builds/slave/test', 17:36:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:19 INFO - 'SHELL': '/bin/bash', 17:36:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:19 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:19 INFO - 'USER': 'cltbld', 17:36:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:19 INFO - 'XPC_FLAGS': '0x0', 17:36:19 INFO - 'XPC_SERVICE_NAME': '0', 17:36:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:36:19 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-jxYesL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:36:19 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HI5HDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:36:19 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-xuvKFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HFOylq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-MVvpDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-aDK3uR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Rfhh8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HC0HwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-j0hmZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-zryW76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-cB62gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:36:20 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Vi2Iav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:36:21 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-16qZkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:36:21 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-dQErxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:36:21 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-jkIoQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:36:21 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-H7S3fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:36:21 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Amn3tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:36:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:36:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:36:24 INFO - Downloading blessings-1.6.tar.gz 17:36:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:36:24 INFO - Installing collected packages: blessings 17:36:24 INFO - Running setup.py install for blessings 17:36:24 INFO - Successfully installed blessings 17:36:24 INFO - Cleaning up... 17:36:24 INFO - Return code: 0 17:36:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:36:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:36:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:36:24 INFO - Reading from file tmpfile_stdout 17:36:24 INFO - Current package versions: 17:36:24 INFO - blessings == 1.6 17:36:24 INFO - blobuploader == 1.2.4 17:36:24 INFO - docopt == 0.6.1 17:36:24 INFO - functools32 == 3.2.3-2 17:36:24 INFO - jsonschema == 2.5.1 17:36:24 INFO - manifestparser == 1.1 17:36:24 INFO - mozInstall == 1.12 17:36:24 INFO - mozcrash == 1.0 17:36:24 INFO - mozdebug == 0.1 17:36:24 INFO - mozdevice == 0.49 17:36:24 INFO - mozfile == 1.2 17:36:24 INFO - mozhttpd == 0.7 17:36:24 INFO - mozinfo == 0.9 17:36:24 INFO - mozleak == 0.1 17:36:24 INFO - mozlog == 3.4 17:36:24 INFO - moznetwork == 0.27 17:36:24 INFO - mozprocess == 0.24 17:36:24 INFO - mozprofile == 0.28 17:36:24 INFO - mozrunner == 6.13 17:36:24 INFO - mozscreenshot == 0.1 17:36:24 INFO - mozsystemmonitor == 0.3 17:36:24 INFO - moztest == 0.8 17:36:24 INFO - mozversion == 1.4 17:36:24 INFO - psutil == 3.1.1 17:36:24 INFO - requests == 1.2.3 17:36:24 INFO - wsgiref == 0.1.2 17:36:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:36:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:36:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:24 INFO - 'HOME': '/Users/cltbld', 17:36:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:24 INFO - 'LOGNAME': 'cltbld', 17:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:24 INFO - 'MOZ_NO_REMOTE': '1', 17:36:24 INFO - 'NO_EM_RESTART': '1', 17:36:24 INFO - 'PAGER': '/bin/cat', 17:36:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:24 INFO - 'PWD': '/builds/slave/test', 17:36:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:24 INFO - 'SHELL': '/bin/bash', 17:36:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:24 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:24 INFO - 'USER': 'cltbld', 17:36:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:24 INFO - 'XPC_FLAGS': '0x0', 17:36:24 INFO - 'XPC_SERVICE_NAME': '0', 17:36:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:36:25 INFO - Cleaning up... 17:36:25 INFO - Return code: 0 17:36:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:36:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:36:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:36:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:36:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10449cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe21154b710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, '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.4NSXz8NLdk/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.dmdCT31xUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:36:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:36:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:25 INFO - 'HOME': '/Users/cltbld', 17:36:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:25 INFO - 'LOGNAME': 'cltbld', 17:36:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:25 INFO - 'MOZ_NO_REMOTE': '1', 17:36:25 INFO - 'NO_EM_RESTART': '1', 17:36:25 INFO - 'PAGER': '/bin/cat', 17:36:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:25 INFO - 'PWD': '/builds/slave/test', 17:36:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:25 INFO - 'SHELL': '/bin/bash', 17:36:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:25 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:25 INFO - 'USER': 'cltbld', 17:36:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:25 INFO - 'XPC_FLAGS': '0x0', 17:36:25 INFO - 'XPC_SERVICE_NAME': '0', 17:36:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:36:25 INFO - Cleaning up... 17:36:25 INFO - Return code: 0 17:36:25 INFO - Running post-action listener: _resource_record_post_action 17:36:25 INFO - Running post-action listener: _start_resource_monitoring 17:36:25 INFO - Starting resource monitoring. 17:36:25 INFO - [mozharness: 2017-02-01 01:36:25.460528Z] Finished create-virtualenv step (success) 17:36:25 INFO - [mozharness: 2017-02-01 01:36:25.461464Z] Running install step. 17:36:25 INFO - Running pre-action listener: _resource_record_pre_action 17:36:25 INFO - Running main action method: install 17:36:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:36:25 INFO - Reading from file tmpfile_stdout 17:36:25 INFO - Detecting whether we're running mozinstall >=1.0... 17:36:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:36:25 INFO - Reading from file tmpfile_stdout 17:36:25 INFO - Output received: 17:36:25 INFO - Usage: mozinstall [options] installer 17:36:25 INFO - Options: 17:36:25 INFO - -h, --help show this help message and exit 17:36:25 INFO - -d DEST, --destination=DEST 17:36:25 INFO - Directory to install application into. [default: 17:36:25 INFO - "/builds/slave/test"] 17:36:25 INFO - --app=APP Application being installed. [default: firefox] 17:36:25 INFO - mkdir: /builds/slave/test/build/application 17:36:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 17:36:45 INFO - Reading from file tmpfile_stdout 17:36:45 INFO - Output received: 17:36:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:36:45 INFO - Running post-action listener: _resource_record_post_action 17:36:45 INFO - [mozharness: 2017-02-01 01:36:45.659970Z] Finished install step (success) 17:36:45 INFO - [mozharness: 2017-02-01 01:36:45.660109Z] Running run-tests step. 17:36:45 INFO - Running pre-action listener: _resource_record_pre_action 17:36:45 INFO - Running main action method: run_tests 17:36:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:36:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:36:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:36:45 INFO - 2017-01-31 17:36:45.674 screenresolution[1626:7362] starting screenresolution argv=screenresolution get 17:36:45 INFO - 2017-01-31 17:36:45.677 screenresolution[1626:7362] Display 0: 1600x1200x32@60 17:36:45 INFO - 2017-01-31 17:36:45.682 screenresolution[1627:7364] starting screenresolution argv=screenresolution list 17:36:45 INFO - Available Modes on Display 0 17:36:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:36:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:36:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:36:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:36:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:36:45 INFO - Intel Iris: 17:36:45 INFO - Chipset Model: Intel Iris 17:36:45 INFO - Type: GPU 17:36:45 INFO - Bus: Built-In 17:36:45 INFO - VRAM (Dynamic, Max): 1536 MB 17:36:45 INFO - Vendor: Intel (0x8086) 17:36:45 INFO - Device ID: 0x0a2e 17:36:45 INFO - Revision ID: 0x0009 17:36:45 INFO - Displays: 17:36:45 INFO - Display: 17:36:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:36:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:36:45 INFO - Display Serial Number: 1600x1200 60 17:36:45 INFO - Main Display: Yes 17:36:45 INFO - Mirror: Off 17:36:45 INFO - Online: Yes 17:36:45 INFO - Rotation: Supported 17:36:45 INFO - Return code: 0 17:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:36:45 INFO - Python 2.7.3 17:36:45 INFO - Return code: 0 17:36:45 INFO - grabbing minidump binary from tooltool 17:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:36:45 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 0x10427ad30>, '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 0x1041a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:36:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:36:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:36:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:36:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:36:46 INFO - Return code: 0 17:36:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:36:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:36:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:36:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:36:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:36:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:36:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0364 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:36:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4NSXz8NLdk/Render', 17:36:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:46 INFO - 'HOME': '/Users/cltbld', 17:36:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:46 INFO - 'LOGNAME': 'cltbld', 17:36:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:36:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:46 INFO - 'MOZ_NO_REMOTE': '1', 17:36:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:36:46 INFO - 'NO_EM_RESTART': '1', 17:36:46 INFO - 'PAGER': '/bin/cat', 17:36:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:46 INFO - 'PWD': '/builds/slave/test', 17:36:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:36:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:46 INFO - 'SHELL': '/bin/bash', 17:36:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dmdCT31xUY/Listeners', 17:36:46 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 17:36:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:46 INFO - 'USER': 'cltbld', 17:36:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:46 INFO - 'XPC_FLAGS': '0x0', 17:36:46 INFO - 'XPC_SERVICE_NAME': '0', 17:36:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:36:46 INFO - mozversion application_buildid: 20170131160608 17:36:46 INFO - mozversion application_changeset: 0d5fd88afc55a306466f7d98f895ea9181cc5526 17:36:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:36:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:36:46 INFO - mozversion application_name: Firefox 17:36:46 INFO - mozversion application_remotingname: firefox-dev 17:36:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:36:46 INFO - mozversion application_vendor: Mozilla 17:36:46 INFO - mozversion application_version: 53.0a2 17:36:46 INFO - mozversion platform_buildid: 20170131160608 17:36:46 INFO - mozversion platform_changeset: 0d5fd88afc55a306466f7d98f895ea9181cc5526 17:36:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:36:46 INFO - mozversion platform_version: 53.0a2 17:36:46 INFO - using testdate: 1485913006 17:36:46 INFO - actual date: 1485913006 17:36:46 INFO - starting webserver on 'localhost:49241' 17:36:46 INFO - SUITE-START | Running 2 tests 17:36:46 INFO - TEST-START | dromaeo_css 17:36:46 INFO - operating with platform_type : mac_ 17:36:46 INFO - Initialising browser for dromaeo_css test... 17:36:46 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpCuOMBv/profile http://localhost:49241/getInfo.html) 17:36:49 INFO - PROCESS | 1637 | 2017-01-31 17:36:49.051 plugin-container[1639:7540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 17:36:49 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:49 INFO - PROCESS | 1637 | 2017-01-31 17:36:49.056 plugin-container[1639:7540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a17, name = 'com.apple.CFPasteboardClient' 17:36:49 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:49 INFO - PROCESS | 1637 | 2017-01-31 17:36:49.056 plugin-container[1639:7540] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:36:49 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 17:36:49 INFO - PROCESS | 1637 | colorDepth:24 17:36:49 INFO - PROCESS | 1637 | Browser inner width/height: 1024/663 17:36:49 INFO - PROCESS | 1637 | __metrics 17:36:49 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 17:36:49 INFO - TEST-INFO | 1637: exit 0 17:36:49 INFO - Browser initialized. 17:36:49 INFO - Running cycle 1/1 for dromaeo_css test... 17:36:49 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpCuOMBv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:52 INFO - PROCESS | 1640 | 2017-01-31 17:36:52.585 plugin-container[1641:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 17:36:52 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:52 INFO - PROCESS | 1640 | 2017-01-31 17:36:52.587 plugin-container[1641:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f17, name = 'com.apple.CFPasteboardClient' 17:36:52 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:52 INFO - PROCESS | 1640 | 2017-01-31 17:36:52.587 plugin-container[1641:7672] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:37:02 INFO - PROCESS | 1640 | RSS: Main: 227807232 17:37:02 INFO - PROCESS | 1640 | 17:40:04 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 17:40:06 INFO - PROCESS | 1640 | RSS: Main: 243163136 17:40:06 INFO - PROCESS | 1640 | 17:41:50 INFO - PROCESS | 1640 | ************************* 17:41:50 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 17:41:50 INFO - PROCESS | 1640 | 17:41:50 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:41:50 INFO - PROCESS | 1640 | Full stack: 17:41:50 INFO - PROCESS | 1640 | ************************* 17:43:08 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 17:43:09 INFO - PROCESS | 1640 | RSS: Main: 251211776 17:43:09 INFO - PROCESS | 1640 | 17:46:10 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 17:46:11 INFO - PROCESS | 1640 | RSS: Main: 257617920 17:46:11 INFO - PROCESS | 1640 | 17:49:13 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 17:49:13 INFO - PROCESS | 1640 | RSS: Main: 255381504 17:49:13 INFO - PROCESS | 1640 | 17:52:16 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 17:52:17 INFO - PROCESS | 1640 | RSS: Main: 259080192 17:52:17 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 17:55:20 INFO - PROCESS | 1640 | RSS: Main: 260374528 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | __start_tp_report 17:55:20 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 17:55:20 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 17:55:20 INFO - PROCESS | 1640 | |i|pagename|runs| 17:55:20 INFO - PROCESS | 1640 | |0;dojo.html;3337.332533493302;3341.248219150691;3365.1250674824537;3381.6280209176984;3391.1691635549278;4560.612347950424;5382.515573598375;5636.210930469738;5686.260786097801;5686.61162311829;5625.718714064291;5675.914861277084;5706.201131841535;5706.629069110505;5719.256496655429;4583.633309335245;4911.066897289504;5125.974370128144;5236.6857988520815;5288.709120998344;5183.455737147602;5188.195829646411;5239.921401178985;5246;5251.737413129347;5040.420351659533;5061.367329083864;5167.844964651048;5198.116320225571;5224.791008359661;4498.235299999008;5056.772445239973;5133.30700355451;5150.716710580917;5185.325907632021;5082.695038297714;5108.157154069574;5118.564921981636;5148.150555158394;5162.070827251093;5428.5928555358505;5482.725863706799;5595.916061259084;5689.91891540606;5726.226959360475;6072.848178795522;6138.508919286491;6155.938440615626;6156.292026416974;6237.563370564062;6207.162033125515;6207.441330280297;6209.844753881144;6212.471939885115;6238.688065596702;5762.596618236725;5791.44981226787;5796.362400135981;5803.82588522345;5830.300363956352;5722.884037612625;6021.2473440819895;6145.293291271471;6193.38066193377;6229.0967809667345;5459.208414779835;5862.413758624189;6137.877242455126;6138.204480309982;6145.692715364214;6130.601510901777;6215.595986260879;6220.688965551795;6231.2522497300615;6247.500199983991;6014.669193194416;6051.818445446644;6052.848678782978;6083.665398403042;6170.660613666201;6096.969515152396;6111.54163437744;6116.847078823065;6167.799101998603;6233.594816337014;4922.163232250526;4939.431965323962;4957.082939656175;4958.925616115761;4961.727105009187;4339.652827773771;4361.498427680794;4389.736615803062;4405.757683327448;4471.418715566955;5750.654960702372;5770.971145144247;5835.35811060783;5846.269216347957;5883.823485295448;6171.84570385744;6176.258848938156;6183.752649894044;6184.072389141665;6186.1648677428875;5397.920415916832;5565.16522521625;5662.858428539237;5744.224529688522;5804.738786754606;4648.48866624671;5136.229565565195;5442.564594832483;5459.235707001023;5465.234867118606;6238.469730072904;6258.468030217483;6266.561340706106;6274.811755647338;6337.271213810471;5173.896522069613;5246.658967167122;6108.463491842691;6119.563485741553;6147.108669243024;4741.810327586996;5488.17677348401;6191.938080619136;6248.843778905563;6283.905741413791;6198.442140207403;6199.56603037783;6202.689865506797;6219.191505104308;6220.626762394271;5393.30111015029;6006.669633170218;6036.185115009417;6134.4978165938865;6228.937710622836;6082.270127584541;6116.974953844038;6136.549713441251;6138.878900475427;6152.846178845564;6106.816795496142;6112.480439162543;6117.754831771827;6133.6319820810895;6146.832101900624;5778.711064446677;5873.4126587342635;5931.406859314034;6017.097435384728;6024.759009639741;6001.489873360813;6036.879262414639;6077.665728384981;6106.297775755667;6109.541784366102;5291.656042357235;5831.008728516247;5987.850303742442;6009.789657361971;6012.87974240504 17:55:20 INFO - PROCESS | 1640 | |1;ext.html;465.9190677627903;493.9407271127465;498.9501049895011;513.0969493691102;522.0655027500774;39511.012224694394;41729;41875.371869421986;42058.369124463155;42096.36855447171;43461.7826910865;43972;44063.898402539955;44068.55931440685;44073.8981525462;8494.490330580185;9270.629174833015;9523.238140948708;9527.475988820612;9528;8457.873131903027;11189;11431.599894003712;11435.714107147347;11594.768104637902;11480.598179063734;11513.942430287878;11529.65411037666;11554.075673946107;11562.59530916418;11353.318800871974;11412.885871141349;11417.086633069375;11422.914823091793;11435.485403156878;11309.377984210865;11320.943395283053;11359.602413915556;11378.943105284421;11381.829272560919;40693.389599155744;41037.1792564147;41112.38331425031;41219.7939010303;41232.38151427731;4007.499062617173;4011.83952641894;4055.472788537501;4124.463819703419;4225.936610950838;29419.558706619417;30042.849785750932;30052.398952020838;30189.547156792665;30213.697863021527;3201.775875688703;3225.1775797171804;3260.673932606744;3304.3556506481245;3382.441897086978;489062;492189;493685;494653;496574.9999999964;60875.39124608786;61216.08175877365;61285.08072378918;61304.693476533226;61421;63263;63679;63939.721205575624;64046.67976660087;64168;15866.682666346609;16087.678246435005;16196.75704864428;16339;16348.509544713677;9902.60389584423;11856.466459009365;12549.184303020274;12780.04149688777;12874.742505150032;13453.461861525626;14793.334299956528;15613.531594052196;15633.687326253412;15772.290246938916;5587.497125258747;5975.223220981245;6065;6666.366695163952;6788.1175447191545;864.2869632553169;898.5507246376811;899.5232526760827;920.2867777472378;930.0420566816055;30332.696673033428;30354;30433.239169020508;30610.23474413113;30703.539446908315;1062.6387028410222;1083.3175099687146;1092.3882625729616;1111.9888801111886;1126.718320419895;14539.563813085624;14603.634909127357;15691;15775.895687301778;16064.75902861458;609404;616880;617587;618665;620881;50654;51826.48173518216;60669.69665151646;61747.6912615443;61800;1451.5645306407823;1463.5170393770031;1480.0749531542785;1485.7102864941232;1488.047649504318;26785;27347;30011.69988300089;30263.546046809755;30289.394212115192;49826.50173498219;50259.74870125626;50324.49675503198;50540;50646;545856;551555;553550;555358;555883;33896.15259618431;34838.90966542016;38483.80758096192;39967;40519.79740101399;45606.54393456023;46928.765356173004;47157;47857.5214247867;48117.5188248127;38773.80613096916;39443;40496.79751601341;40598.59401405948;40704;38923;39179.804100979316;39767.602323977546;40111.79944100261;40473;37202.441963371115;37398.691045814354;37518.24963500769;37770.81114594409;38511.80744096261;34003.48994765031;34183.145421364665;34299;34739.305213896085;34888.825555873074;25062;25231.62152567676;25561.84971676242;25688;32133.839330803195 17:55:20 INFO - PROCESS | 1640 | |2;jquery.html;386.9458275841381;387.22941346720035;393.57100760171414;396.7559950630362;415.97712125833067;17417.738733919003;19842.603147937032;21257.36227913163;21324.36026919193;21356.57286854262;20608.793912060875;20962.89518552409;20975.790242097613;21190.470238244052;21230.832304223262;5469.890602187944;5865.912011319833;5920.141579470996;5931;5943.375945525722;5701.144828275771;5872.5889187756875;5944.4352786485215;5950.077737950603;5969.611975221618;5814.302283725938;5822.592418530705;5863.706814659271;5865.912011319833;5866.911996320058;5595.776168953237;5774.7401366938375;5856.677882716449;5873.647581145102;5882.941170588282;5809.3609702932645;5815.447532484407;5826.300843898717;5827.970860145672;5836.9124463133085;13279.535216267384;24606;24743.50512989748;24999.000039998376;25239.74760252411;2824.053941929456;2949.8820047198087;2961.6742158362777;2987;3066.861991210401;10014.3490673106;10084.647037353658;10134.23993200513;10289;10596.364218146859;1422.317287701909;1788.2310606439225;1875.962480750391;1896.933607323751;1899.3637131560943;121611;124567;127188;132193;132826;10101.898981010243;11647;11986.400679965967;11987.820182697267;12073.637790866289;15702.76445853313;15712.21438928049;15792.36830526766;15814.288357023961;15829.050256984618;8589.828203435896;8788.956055219684;8825.426347287534;8889.777755556162;8893.822123557493;8469.534175620276;8503.489790612583;8513.702020429255;8631.180037896389;8643.481391116553;4719.811207551728;4768.47546769855;4798.160321943646;4803.991161856049;4869.9269510957365;1993.0931426201043;2035.4097311779715;2039.653258945953;2039.6940458930983;2043.2950632031925;968.6077138758814;1023.3859684189426;1054.4411461925192;1067.5622994572188;1077.3912739302268;20329.796702033087;21283;21326.466838329165;21443.67834482484;21616.567668646538;1057.8201705710048;1057.8783439904355;1083.3608171179042;1099.8185299425504;1101.3391964821044;11062.723431914106;11219.541459610362;11261.324320540794;11284.32294062359;11284.717882053013;741500;751486;780968;793589.0320548476;807505;130692.3465382667;142789.8581521278;147513.26243368714;152534;153357;976.7069879036261;978.1490103609763;997.7006897930592;997.9201663866874;1008.2287050406445;17664.91167544154;18168.636627267115;18467;18504.537386565444;18591.628167436847;41722.16555668809;54795.1079179733;54906.948788164525;58302;58372.416275836695;83534.88557650756;83546.74679879686;86218.56890715506;86682;87518;7897.960510197642;8099.87850182236;8240;8358.749237522885;8455.492670439548;8973.147550982647;9528.428294302365;12696.301703406032;12951.481940722642;13108.868911310765;11158.274712143684;11234.101271898391;11369.602063927723;11387.605225688674;11533.500644916106;10260.486975651038;10463.319884207502;11207.495662695035;11241;11349.54601815918;8894.644214231359;9332.440053596805;10487.632932847313;10853.240273180802;11249.381284029458;7452.329290363893;8294.336453083619;8413.495190288602;8474.1949514796;8514.74455766328;8060.355171586376;11027.393493357942;11178.832317515407;11200.327980321206;11426.25729327591 17:55:20 INFO - PROCESS | 1640 | |3;mootools.html;1331.8401791784984;1410.5063227870246;1430.5708287513744;1432.6991331820318;1441.5296397674372;7537.510061845976;7796.649150788214;8090.635921383538;8475.788105297363;8528.317734581226;8040.517568945867;8236.423450358478;8255.380846436512;8380.83238335233;8387.993440787099;2216.1467834883565;2247.4830788918557;2249.707538020055;2254.842161048727;2258.3563684349965;2096.5911647228795;2179.705739725133;2316.119874447716;2354.564405584972;2359.7994170495526;2253.616885129532;2333.57995560799;2333.9183128590503;2348.4011577047913;2351.106579499796;2315.6758053872472;2321.7213934327906;2333.94165145871;2343.742188359279;2353.082297903819;2270.9545809083725;2320.385097949045;2357;2377.132346693462;2379.5121999990133;8372.874406883902;8385.664573417056;8410.663573457054;8447.32421406292;8473.95763021181;5085.059264036166;5218.165093585046;5244.160934250502;5256.316678831766;5262.421133675292;3255.951160732591;3436.29555941034;3451.2234747181933;3454.7408944329277;3485.8954231372845;1171.8886705762939;1180.403896032498;1226.1784604315042;1235.4872727818035;1244.5270797097135;10792.838107428395;10817.83773243402;10914.781704365947;10926.180536459797;10937.343759374464;4622.6301895848255;4733.597644200281;4776.307435421844;4786.0667169901535;4905.950940490549;8295.211954864279;8582.957085214533;8880.644774208959;9515.666951656658;9638.759031024281;5235.869103272373;5298.97350513245;5303.814366497231;5308.442613525602;5323.1749078892835;4609.492955774862;4787.976060119677;4854.805807767721;4873.561379475864;4926.113299606105;3353.5305057292;3403.2512847173584;3886;3915.5105611798526;3991.8004099794894;1102.779444111181;1170.2218025013271;1214.9392530373445;1238.207547169812;1286.0483242400744;789.4039999800115;888.8977767556685;897.1163404047008;900.8918929728475;916.5188276155072;4827.203511420576;5566.721663916788;5694.971525142348;5716.942830571724;5878.883012227663;955.4171955107379;991.1278075293695;1007.5516395204211;1014.5586669798514;1016.0195411428008;3617.113807117273;3703.148275896559;3737.5328083989502;3753.5683396409763;3768.8492460301827;9740.610375585038;10082.19342452602;10115.39307641544;10210.285280030475;10234.744131396774;10054.34646747959;10149.492525373702;10174.186065114773;10190.235732320105;10220.795584088277;7795.493292935941;7895.486793358414;7983.640736166886;7989.8402031960195;8013.198680132056;5004.324416203766;5601.831945041656;5611.551075913999;5617.374716846059;5722.513586345207;9270.72187834366;9278.72163835086;9409.106134917172;9413.435193888388;9454.621815127319;9162.770930726518;9406;9410.058994100535;9457.574409151743;9497.857532136884;7239.746608868664;7385.704571817282;7691.88462173079;7884.763457096296;7914.604269786603;7938.483998540077;8099.068607110022;8112.269895709414;8140.470869393471;8349;7059.541129826544;7123.430125590044;7262.709491620276;7293.708251670086;7298.854022919618;5628.60599758013;6935.479839011993;7101.928980710127;7130.714771409085;7296.671649775879;6343.809685709436;6358.2687990879795;6384.425401713868;6406.615603063831;6407.167068281094;4826.710397376169;4901.852944411673;4946.777395017161;4961.08219979305;4993.201087825931;4206.390073439396;4910.92633610489;4914.680545764514;4963.8262660806695;5024.120778863758 17:55:20 INFO - PROCESS | 1640 | |4;prototype.html;357.2087825466591;358.7255749351746;364.5170149551844;367.3864646041111;368.6258447675608;4346.0004199034165;4438.600525952667;5231.659942103761;5458.399576046637;5493.670379777216;4526.103293891912;4811.566958973692;5023.773930173142;5128.8974220515565;5291.650629089587;3492.886803483894;3843.7885916274595;4208.705390622657;4242.193983143208;4272.893177670568;3976.7812770297624;4245;4247.872563823074;4266.5706988158845;4274.119387469519;3536.8585256589704;3636.963630363689;3719.665230129288;3983.8406463741417;4275.166342563201;4200.390943313233;4222.957770422303;4272.743635381856;4276.401303817468;4306.978465107686;3489.930201395983;3765.9811700941596;3786.6024067473068;3797.2785170817456;3827.445020472044;4675.602573781199;5027.346444962168;5228.189630607262;5268.498477933809;5278.2082687596785;4162;4315.935260971088;4385.802638881258;4888.217885138396;4931.161702510582;4389.1784909262615;5098.184290513537;5250.842474725726;5756.827295181151;5786.871560045793;3966.4446977423186;4128.318827393477;4132.442120313749;4147.543770185277;4159.459270294872;5774.873899589582;5989.9101513477335;6014.827108713803;6028;6036.038287556562;3573.6069032406413;3669.284489524517;4252.192083504125;4266.445362102969;4284.807183676742;3772.1135533149686;3945.6448919597374;4214;4326.199653064194;4346.9347959780625;4293.754811104807;4452.797744609003;4688.624910007191;4825.30033145192;4848.272759086165;4762.047590481918;5139.922901156486;5698.857528561736;5764.423557644286;5764.827055188351;1207.8550573931184;1238.7364887814379;1283.663732007737;1325.3174615073244;1354.7019655675736;803.0844836885955;846.6105591428012;881.1805021330097;986.9851952220722;1008.7125169326706;4397.73613583186;4971.90056198874;5006.546792071858;5007.824726134568;5104.259882317043;4699.801550604574;5111.667741596784;5211.530962213419;5562.3881373048935;5700.458456446631;4582.495925448198;4609.1703493371515;4637.634806248838;4798.808047678123;5031.471695471996;2531.822772405914;2537.6320433537403;2736.7947403944786;2857.6711828999614;2893.85530723463;5167.0957582422925;5750.7987220447085;5832.795852145239;5858.736356863951;5869.442402971711;5356.625036247503;5431.85930954504;5456.863578410572;5658.85852853682;5672.376038635747;4969.794482558729;4972.776225069881;5448.618596698193;5830.154627578977;5835.533157347317;5251.553617942518;5323.4676532348;5592.888142237214;5611.765411609439;5645.83062508109;5499.890002200013;5549.861253468695;5808.535317174531;5831.801103205685;5855.677937713466;5236.869078273074;6151.907721384272;6168.290646575701;6182.752689892354;6214.25428948529;3813.5614404342746;3854.4218367244016;3917.2948869203815;3960.9305487518786;4014.1770936958437;5444.891102177855;5527.39198688152;5541.251930989265;5565.693886836263;5845.620034697731;5051.747412629427;5275.630705850707;5539;5593.294045316217;5602.123288698169;4804.807807687654;5032.446430892525;5149.124648809637;5544;5710.457506536873;5383.703896285742;5564.972175139098;5565.3304008798;5584.548017515394;5619.752099160369;5017.799288028438;5712.0860662293835;5718.828435146952;5744.668092506253;5745.454181852718;4946.703197808143;5193.76628051731;5352.9732351338;5402.297701298806;5602.747876345656 17:55:20 INFO - PROCESS | 1640 | |5;yui.html;691.7440546997611;752.947293689442;778.8714862047763;830.7881490219993;843.7300063979527;14872.256387180638;15551.222438878067;15961.760573591406;16055.59861003474;16165.595860103504;13435.395407206675;14754;14798.482053128142;14864.256787160653;14895.92552037241;845.8900342955423;851.2806678356778;853.2193043365313;853.8292341531687;854.9783009303884;837.5853952293623;850.9021462531795;852.4373913217278;852.7271273192581;856.2251162697769;894.49461054504;894.5437826708393;894.8031433084711;896.9147930946582;896.9686060987867;879.9516026618534;886.7251152142824;890.3233542507676;890.8619164029586;894.306912143091;652.1326635574675;659.7558903205796;661.4146480364911;661.4675186474885;665.065582856088;12978.805317920238;13072.411741471657;13960.58118256454;13988.72022559543;14166;11869.347185904771;11979.802019798037;12066.095042871731;12186.329751863645;12280.631581052583;9046.276297896218;9693.951530242373;9938.552765125587;10202.540885660163;11009.889901100967;3554.2180720241504;3667.504886840269;3704.3332200203954;3835.9616403835885;3842.212346468967;102089.97910020953;102428.46357304647;103496.48251758693;103953.96046039519;105567.47216263869;8964.238039766562;9251.861222081674;9303.53482325881;9343.252539796937;9530.237580993504;19637;19849.00754962246;19868.50328741793;19887.900560497106;19971.700424493643;7560.546367217985;7591.620418979029;7683.193264707237;7870.016247969004;7896.368290536743;4319.222539942841;4337.696361254745;4421.889452763707;4466.9106617867465;4481.843135490242;3432.4851272308856;3545.503629491873;3567.554055743032;3570.285942811448;3597.5862775780597;640.7981485831948;652.425865238587;655.3413819111762;659.864727730814;668.4151367553391;599.7780821096222;686.6463771157858;703.6341102626606;709.159645819695;714.892766085081;9476;10389.84415233772;10454.529546170288;10532.52603632823;10844.403557804397;531.9707416092152;535.6223862177155;552.0201642085331;597.0804960361082;599.3646734461485;3521.9823900880333;4960.131976904027;5026.924596131061;5087.923681144785;5123.564497017721;83810.16189838146;102915.48542257241;103503;103932;104575.4771226139;54375.728121359934;68949.31050689529;69583.65208173927;79292;83685;71570.64214678998;73148.63425682943;74478.62760686161;76044.47911041859;77147.61426192833;10981.725456863323;11120.777584448428;11347.855178053545;11432.94283528577;11500;40798;50286;50940;51549.7422512885;52033;66146.00780988386;67345;68920.62158756696;69527.26181845495;70168;10369.844452333373;12665.366731663564;13016.284104373972;13288.335583220607;13685.521006764715;13055;13376.799348009594;13613.931930340284;13739.519116830863;13774.586762397144;12109.244493644846;13400.32998350098;13922.809519564735;14399.208043557705;14571.708565828836;13313;13983.720325593635;14003.929980350033;14628.414863405345;14659.706805864036;13339;13629.522966696119;13636;14013;14494.34775435129;9634.470104144339;11071.114310854951;11461.770764584495;11793.528258869892;11898.464569094585;10769.24615276923;11109.731445288273;11326.943365283121;11334.94332528332;11349.829752553884 17:55:20 INFO - PROCESS | 1640 | __end_tp_report 17:55:20 INFO - PROCESS | 1640 | __start_cc_report 17:55:20 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,380 17:55:20 INFO - PROCESS | 1640 | __end_cc_report 17:55:20 INFO - PROCESS | 1640 | __startTimestamp1485914120363__endTimestamp 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | ------- Summary: start ------- 17:55:20 INFO - PROCESS | 1640 | Number of tests: 6 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5648.88 Median:5835.36 stddev:649.84 (11.1%) stddev-sans-first:626.02 17:55:20 INFO - PROCESS | 1640 | Values: 3337.3 3341.2 3365.1 3381.6 3391.2 4560.6 5382.5 5636.2 5686.3 5686.6 5625.7 5675.9 5706.2 5706.6 5719.3 4583.6 4911.1 5126.0 5236.7 5288.7 5183.5 5188.2 5239.9 5246.0 5251.7 5040.4 5061.4 5167.8 5198.1 5224.8 4498.2 5056.8 5133.3 5150.7 5185.3 5082.7 5108.2 5118.6 5148.2 5162.1 5428.6 5482.7 5595.9 5689.9 5726.2 6072.8 6138.5 6155.9 6156.3 6237.6 6207.2 6207.4 6209.8 6212.5 6238.7 5762.6 5791.4 5796.4 5803.8 5830.3 5722.9 6021.2 6145.3 6193.4 6229.1 5459.2 5862.4 6137.9 6138.2 6145.7 6130.6 6215.6 6220.7 6231.3 6247.5 6014.7 6051.8 6052.8 6083.7 6170.7 6097.0 6111.5 6116.8 6167.8 6233.6 4922.2 4939.4 4957.1 4958.9 4961.7 4339.7 4361.5 4389.7 4405.8 4471.4 5750.7 5771.0 5835.4 5846.3 5883.8 6171.8 6176.3 6183.8 6184.1 6186.2 5397.9 5565.2 5662.9 5744.2 5804.7 4648.5 5136.2 5442.6 5459.2 5465.2 6238.5 6258.5 6266.6 6274.8 6337.3 5173.9 5246.7 6108.5 6119.6 6147.1 4741.8 5488.2 6191.9 6248.8 6283.9 6198.4 6199.6 6202.7 6219.2 6220.6 5393.3 6006.7 6036.2 6134.5 6228.9 6082.3 6117.0 6136.5 6138.9 6152.8 6106.8 6112.5 6117.8 6133.6 6146.8 5778.7 5873.4 5931.4 6017.1 6024.8 6001.5 6036.9 6077.7 6106.3 6109.5 5291.7 5831.0 5987.9 6009.8 6012.9 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:69571.84 Median:39639.31 stddev:148352.41 (374.3%) stddev-sans-first:148677.84 17:55:20 INFO - PROCESS | 1640 | Values: 465.9 493.9 499.0 513.1 522.1 39511.0 41729.0 41875.4 42058.4 42096.4 43461.8 43972.0 44063.9 44068.6 44073.9 8494.5 9270.6 9523.2 9527.5 9528.0 8457.9 11189.0 11431.6 11435.7 11594.8 11480.6 11513.9 11529.7 11554.1 11562.6 11353.3 11412.9 11417.1 11422.9 11435.5 11309.4 11320.9 11359.6 11378.9 11381.8 40693.4 41037.2 41112.4 41219.8 41232.4 4007.5 4011.8 4055.5 4124.5 4225.9 29419.6 30042.8 30052.4 30189.5 30213.7 3201.8 3225.2 3260.7 3304.4 3382.4 489062.0 492189.0 493685.0 494653.0 496575.0 60875.4 61216.1 61285.1 61304.7 61421.0 63263.0 63679.0 63939.7 64046.7 64168.0 15866.7 16087.7 16196.8 16339.0 16348.5 9902.6 11856.5 12549.2 12780.0 12874.7 13453.5 14793.3 15613.5 15633.7 15772.3 5587.5 5975.2 6065.0 6666.4 6788.1 864.3 898.6 899.5 920.3 930.0 30332.7 30354.0 30433.2 30610.2 30703.5 1062.6 1083.3 1092.4 1112.0 1126.7 14539.6 14603.6 15691.0 15775.9 16064.8 609404.0 616880.0 617587.0 618665.0 620881.0 50654.0 51826.5 60669.7 61747.7 61800.0 1451.6 1463.5 1480.1 1485.7 1488.0 26785.0 27347.0 30011.7 30263.5 30289.4 49826.5 50259.7 50324.5 50540.0 50646.0 545856.0 551555.0 553550.0 555358.0 555883.0 33896.2 34838.9 38483.8 39967.0 40519.8 45606.5 46928.8 47157.0 47857.5 48117.5 38773.8 39443.0 40496.8 40598.6 40704.0 38923.0 39179.8 39767.6 40111.8 40473.0 37202.4 37398.7 37518.2 37770.8 38511.8 34003.5 34183.1 34299.0 34739.3 34888.8 25062.0 25231.6 25561.8 25688.0 32133.8 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:40880.94 Median:23111.28 stddev:128672.42 (556.8%) stddev-sans-first:128997.45 17:55:20 INFO - PROCESS | 1640 | Values: 386.9 387.2 393.6 396.8 416.0 17417.7 19842.6 21257.4 21324.4 21356.6 20608.8 20962.9 20975.8 21190.5 21230.8 5469.9 5865.9 5920.1 5931.0 5943.4 5701.1 5872.6 5944.4 5950.1 5969.6 5814.3 5822.6 5863.7 5865.9 5866.9 5595.8 5774.7 5856.7 5873.6 5882.9 5809.4 5815.4 5826.3 5828.0 5836.9 13279.5 24606.0 24743.5 24999.0 25239.7 2824.1 2949.9 2961.7 2987.0 3066.9 10014.3 10084.6 10134.2 10289.0 10596.4 1422.3 1788.2 1876.0 1896.9 1899.4 121611.0 124567.0 127188.0 132193.0 132826.0 10101.9 11647.0 11986.4 11987.8 12073.6 15702.8 15712.2 15792.4 15814.3 15829.1 8589.8 8789.0 8825.4 8889.8 8893.8 8469.5 8503.5 8513.7 8631.2 8643.5 4719.8 4768.5 4798.2 4804.0 4869.9 1993.1 2035.4 2039.7 2039.7 2043.3 968.6 1023.4 1054.4 1067.6 1077.4 20329.8 21283.0 21326.5 21443.7 21616.6 1057.8 1057.9 1083.4 1099.8 1101.3 11062.7 11219.5 11261.3 11284.3 11284.7 741500.0 751486.0 780968.0 793589.0 807505.0 130692.3 142789.9 147513.3 152534.0 153357.0 976.7 978.1 997.7 997.9 1008.2 17664.9 18168.6 18467.0 18504.5 18591.6 41722.2 54795.1 54906.9 58302.0 58372.4 83534.9 83546.7 86218.6 86682.0 87518.0 7898.0 8099.9 8240.0 8358.7 8455.5 8973.1 9528.4 12696.3 12951.5 13108.9 11158.3 11234.1 11369.6 11387.6 11533.5 10260.5 10463.3 11207.5 11241.0 11349.5 8894.6 9332.4 10487.6 10853.2 11249.4 7452.3 8294.3 8413.5 8474.2 8514.7 8060.4 11027.4 11178.8 11200.3 11426.3 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5461.46 Median:4978.51 stddev:2988.62 (60.0%) stddev-sans-first:2980.88 17:55:20 INFO - PROCESS | 1640 | Values: 1331.8 1410.5 1430.6 1432.7 1441.5 7537.5 7796.6 8090.6 8475.8 8528.3 8040.5 8236.4 8255.4 8380.8 8388.0 2216.1 2247.5 2249.7 2254.8 2258.4 2096.6 2179.7 2316.1 2354.6 2359.8 2253.6 2333.6 2333.9 2348.4 2351.1 2315.7 2321.7 2333.9 2343.7 2353.1 2271.0 2320.4 2357.0 2377.1 2379.5 8372.9 8385.7 8410.7 8447.3 8474.0 5085.1 5218.2 5244.2 5256.3 5262.4 3256.0 3436.3 3451.2 3454.7 3485.9 1171.9 1180.4 1226.2 1235.5 1244.5 10792.8 10817.8 10914.8 10926.2 10937.3 4622.6 4733.6 4776.3 4786.1 4906.0 8295.2 8583.0 8880.6 9515.7 9638.8 5235.9 5299.0 5303.8 5308.4 5323.2 4609.5 4788.0 4854.8 4873.6 4926.1 3353.5 3403.3 3886.0 3915.5 3991.8 1102.8 1170.2 1214.9 1238.2 1286.0 789.4 888.9 897.1 900.9 916.5 4827.2 5566.7 5695.0 5716.9 5878.9 955.4 991.1 1007.6 1014.6 1016.0 3617.1 3703.1 3737.5 3753.6 3768.8 9740.6 10082.2 10115.4 10210.3 10234.7 10054.3 10149.5 10174.2 10190.2 10220.8 7795.5 7895.5 7983.6 7989.8 8013.2 5004.3 5601.8 5611.6 5617.4 5722.5 9270.7 9278.7 9409.1 9413.4 9454.6 9162.8 9406.0 9410.1 9457.6 9497.9 7239.7 7385.7 7691.9 7884.8 7914.6 7938.5 8099.1 8112.3 8140.5 8349.0 7059.5 7123.4 7262.7 7293.7 7298.9 5628.6 6935.5 7101.9 7130.7 7296.7 6343.8 6358.3 6384.4 6406.6 6407.2 4826.7 4901.9 4946.8 4961.1 4993.2 4206.4 4910.9 4914.7 4963.8 5024.1 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4546.43 Median:5012.81 stddev:1365.38 (27.2%) stddev-sans-first:1332.52 17:55:20 INFO - PROCESS | 1640 | Values: 357.2 358.7 364.5 367.4 368.6 4346.0 4438.6 5231.7 5458.4 5493.7 4526.1 4811.6 5023.8 5128.9 5291.7 3492.9 3843.8 4208.7 4242.2 4272.9 3976.8 4245.0 4247.9 4266.6 4274.1 3536.9 3637.0 3719.7 3983.8 4275.2 4200.4 4223.0 4272.7 4276.4 4307.0 3489.9 3766.0 3786.6 3797.3 3827.4 4675.6 5027.3 5228.2 5268.5 5278.2 4162.0 4315.9 4385.8 4888.2 4931.2 4389.2 5098.2 5250.8 5756.8 5786.9 3966.4 4128.3 4132.4 4147.5 4159.5 5774.9 5989.9 6014.8 6028.0 6036.0 3573.6 3669.3 4252.2 4266.4 4284.8 3772.1 3945.6 4214.0 4326.2 4346.9 4293.8 4452.8 4688.6 4825.3 4848.3 4762.0 5139.9 5698.9 5764.4 5764.8 1207.9 1238.7 1283.7 1325.3 1354.7 803.1 846.6 881.2 987.0 1008.7 4397.7 4971.9 5006.5 5007.8 5104.3 4699.8 5111.7 5211.5 5562.4 5700.5 4582.5 4609.2 4637.6 4798.8 5031.5 2531.8 2537.6 2736.8 2857.7 2893.9 5167.1 5750.8 5832.8 5858.7 5869.4 5356.6 5431.9 5456.9 5658.9 5672.4 4969.8 4972.8 5448.6 5830.2 5835.5 5251.6 5323.5 5592.9 5611.8 5645.8 5499.9 5549.9 5808.5 5831.8 5855.7 5236.9 6151.9 6168.3 6182.8 6214.3 3813.6 3854.4 3917.3 3960.9 4014.2 5444.9 5527.4 5541.3 5565.7 5845.6 5051.7 5275.6 5539.0 5593.3 5602.1 4804.8 5032.4 5149.1 5544.0 5710.5 5383.7 5565.0 5565.3 5584.5 5619.8 5017.8 5712.1 5718.8 5744.7 5745.5 4946.7 5193.8 5353.0 5402.3 5602.7 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:19561.03 Median:22558.61 stddev:27815.63 (123.3%) stddev-sans-first:27857.58 17:55:20 INFO - PROCESS | 1640 | Values: 691.7 752.9 778.9 830.8 843.7 14872.3 15551.2 15961.8 16055.6 16165.6 13435.4 14754.0 14798.5 14864.3 14895.9 845.9 851.3 853.2 853.8 855.0 837.6 850.9 852.4 852.7 856.2 894.5 894.5 894.8 896.9 897.0 880.0 886.7 890.3 890.9 894.3 652.1 659.8 661.4 661.5 665.1 12978.8 13072.4 13960.6 13988.7 14166.0 11869.3 11979.8 12066.1 12186.3 12280.6 9046.3 9694.0 9938.6 10202.5 11009.9 3554.2 3667.5 3704.3 3836.0 3842.2 102090.0 102428.5 103496.5 103954.0 105567.5 8964.2 9251.9 9303.5 9343.3 9530.2 19637.0 19849.0 19868.5 19887.9 19971.7 7560.5 7591.6 7683.2 7870.0 7896.4 4319.2 4337.7 4421.9 4466.9 4481.8 3432.5 3545.5 3567.6 3570.3 3597.6 640.8 652.4 655.3 659.9 668.4 599.8 686.6 703.6 709.2 714.9 9476.0 10389.8 10454.5 10532.5 10844.4 532.0 535.6 552.0 597.1 599.4 3522.0 4960.1 5026.9 5087.9 5123.6 83810.2 102915.5 103503.0 103932.0 104575.5 54375.7 68949.3 69583.7 79292.0 83685.0 71570.6 73148.6 74478.6 76044.5 77147.6 10981.7 11120.8 11347.9 11432.9 11500.0 40798.0 50286.0 50940.0 51549.7 52033.0 66146.0 67345.0 68920.6 69527.3 70168.0 10369.8 12665.4 13016.3 13288.3 13685.5 13055.0 13376.8 13613.9 13739.5 13774.6 12109.2 13400.3 13922.8 14399.2 14571.7 13313.0 13983.7 14003.9 14628.4 14659.7 13339.0 13629.5 13636.0 14013.0 14494.3 9634.5 11071.1 11461.8 11793.5 11898.5 10769.2 11109.7 11326.9 11334.9 11349.8 17:55:20 INFO - PROCESS | 1640 | -------- Summary: end -------- 17:55:20 INFO - PROCESS | 1640 | 17:55:20 INFO - TEST-INFO | 1640: exit 0 17:55:21 INFO - TEST-OK | dromaeo_css | took 1114771ms 17:55:21 INFO - TEST-START | kraken 17:55:21 INFO - operating with platform_type : mac_ 17:55:21 INFO - Initialising browser for kraken test... 17:55:21 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmprzTSIe/profile http://localhost:49241/getInfo.html) 17:55:23 INFO - PROCESS | 1652 | 2017-01-31 17:55:23.813 plugin-container[1653:9909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 17:55:23 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:55:23 INFO - PROCESS | 1652 | 2017-01-31 17:55:23.816 plugin-container[1653:9909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 17:55:23 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:55:23 INFO - PROCESS | 1652 | 2017-01-31 17:55:23.816 plugin-container[1653:9909] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:55:23 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 17:55:23 INFO - PROCESS | 1652 | colorDepth:24 17:55:23 INFO - PROCESS | 1652 | Browser inner width/height: 1024/663 17:55:23 INFO - PROCESS | 1652 | __metrics 17:55:23 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 17:55:24 INFO - TEST-INFO | 1652: exit 0 17:55:24 INFO - Browser initialized. 17:55:24 INFO - Running cycle 1/1 for kraken test... 17:55:24 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmprzTSIe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:55:27 INFO - PROCESS | 1654 | 2017-01-31 17:55:27.137 plugin-container[1655:10038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 17:55:27 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:55:27 INFO - PROCESS | 1654 | 2017-01-31 17:55:27.137 plugin-container[1655:10038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 17:55:27 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:55:27 INFO - PROCESS | 1654 | 2017-01-31 17:55:27.137 plugin-container[1655:10038] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:55:36 INFO - PROCESS | 1654 | RSS: Main: 228728832 17:55:36 INFO - PROCESS | 1654 | 17:55:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 17:55:40 INFO - PROCESS | 1654 | RSS: Main: 240631808 17:55:40 INFO - PROCESS | 1654 | 17:55:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 17:55:44 INFO - PROCESS | 1654 | RSS: Main: 249012224 17:55:44 INFO - PROCESS | 1654 | 17:55:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 17:55:47 INFO - PROCESS | 1654 | RSS: Main: 249942016 17:55:47 INFO - PROCESS | 1654 | 17:55:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 17:55:50 INFO - PROCESS | 1654 | RSS: Main: 249753600 17:55:50 INFO - PROCESS | 1654 | 17:55:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:55:53 INFO - PROCESS | 1654 | RSS: Main: 250494976 17:55:53 INFO - PROCESS | 1654 | 17:55:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 17:55:56 INFO - PROCESS | 1654 | RSS: Main: 254595072 17:55:56 INFO - PROCESS | 1654 | 17:55:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 17:56:00 INFO - PROCESS | 1654 | RSS: Main: 253517824 17:56:00 INFO - PROCESS | 1654 | 17:56:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 17:56:04 INFO - PROCESS | 1654 | RSS: Main: 253603840 17:56:04 INFO - PROCESS | 1654 | 17:56:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:56:06 INFO - PROCESS | 1654 | RSS: Main: 252899328 17:56:06 INFO - PROCESS | 1654 | 17:56:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:56:08 INFO - PROCESS | 1654 | RSS: Main: 252968960 17:56:08 INFO - PROCESS | 1654 | 17:56:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:56:11 INFO - PROCESS | 1654 | RSS: Main: 252973056 17:56:11 INFO - PROCESS | 1654 | 17:56:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:56:15 INFO - PROCESS | 1654 | RSS: Main: 253272064 17:56:15 INFO - PROCESS | 1654 | 17:56:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:56:19 INFO - PROCESS | 1654 | RSS: Main: 253612032 17:56:19 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 17:56:20 INFO - PROCESS | 1654 | RSS: Main: 255680512 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | __start_tp_report 17:56:20 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 17:56:20 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 17:56:20 INFO - PROCESS | 1654 | |i|pagename|runs| 17:56:20 INFO - PROCESS | 1654 | |0;ai-astar;108;123;111;168;169;133;120;120;122;121 17:56:20 INFO - PROCESS | 1654 | |1;audio-beat-detection;141;118;114;145;114;113;111;112;134;114 17:56:20 INFO - PROCESS | 1654 | |2;audio-dft;212;203;200;205;198;200;197;199;196;227 17:56:20 INFO - PROCESS | 1654 | |3;audio-fft;83;61;59;84;61;62;57;74;92;60 17:56:20 INFO - PROCESS | 1654 | |4;audio-oscillator;85;72;85;63;73;103;90;71;71;81 17:56:20 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;127;87;84;86;90;87;113;131;135;88 17:56:20 INFO - PROCESS | 1654 | |6;imaging-darkroom;94;109;152;95;149;127;95;96;96;96 17:56:20 INFO - PROCESS | 1654 | |7;imaging-desaturate;93;97;91;94;95;93;94;76;145;144 17:56:20 INFO - PROCESS | 1654 | |8;json-parse-financial;84;89;92;92;91;63;62;63;62;62 17:56:20 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;69;79;72;75;75;71;73;71;61;87 17:56:20 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;95;91;85;77;93;105;105;80;86;85 17:56:20 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;151;145;149;143;141;137;144;141;188;141 17:56:20 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;169;175;170;172;174;172;173;174;174;173 17:56:20 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;65;67;69;68;69;68;68;66;66;68 17:56:20 INFO - PROCESS | 1654 | __end_tp_report 17:56:20 INFO - PROCESS | 1654 | __start_cc_report 17:56:20 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,931 17:56:20 INFO - PROCESS | 1654 | __end_cc_report 17:56:20 INFO - PROCESS | 1654 | __startTimestamp1485914180945__endTimestamp 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | ------- Summary: start ------- 17:56:20 INFO - PROCESS | 1654 | Number of tests: 14 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:129.50 Median:122.50 stddev:21.63 (17.7%) stddev-sans-first:21.50 17:56:20 INFO - PROCESS | 1654 | Values: 108.0 123.0 111.0 168.0 169.0 133.0 120.0 120.0 122.0 121.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:121.60 Median:116.00 stddev:13.09 (11.3%) stddev-sans-first:11.85 17:56:20 INFO - PROCESS | 1654 | Values: 141.0 118.0 114.0 145.0 114.0 113.0 111.0 112.0 134.0 114.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:203.70 Median:201.50 stddev:9.43 (4.7%) stddev-sans-first:9.51 17:56:20 INFO - PROCESS | 1654 | Values: 212.0 203.0 200.0 205.0 198.0 200.0 197.0 199.0 196.0 227.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:69.30 Median:68.00 stddev:12.81 (18.8%) stddev-sans-first:12.59 17:56:20 INFO - PROCESS | 1654 | Values: 83.0 61.0 59.0 84.0 61.0 62.0 57.0 74.0 92.0 60.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:77.00 stddev:11.74 (15.2%) stddev-sans-first:12.28 17:56:20 INFO - PROCESS | 1654 | Values: 85.0 72.0 85.0 63.0 73.0 103.0 90.0 71.0 71.0 81.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:86.50 stddev:21.19 (24.5%) stddev-sans-first:20.58 17:56:20 INFO - PROCESS | 1654 | Values: 127.0 87.0 84.0 86.0 90.0 87.0 113.0 131.0 135.0 88.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:95.00 stddev:23.24 (24.5%) stddev-sans-first:23.83 17:56:20 INFO - PROCESS | 1654 | Values: 94.0 109.0 152.0 95.0 149.0 127.0 95.0 96.0 96.0 96.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:93.50 stddev:23.03 (24.6%) stddev-sans-first:24.19 17:56:20 INFO - PROCESS | 1654 | Values: 93.0 97.0 91.0 94.0 95.0 93.0 94.0 76.0 145.0 144.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:76.00 Median:86.50 stddev:14.51 (16.8%) stddev-sans-first:15.10 17:56:20 INFO - PROCESS | 1654 | Values: 84.0 89.0 92.0 92.0 91.0 63.0 62.0 63.0 62.0 62.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:74.00 stddev:6.73 (9.1%) stddev-sans-first:6.96 17:56:20 INFO - PROCESS | 1654 | Values: 69.0 79.0 72.0 75.0 75.0 71.0 73.0 71.0 61.0 87.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:85.50 stddev:9.54 (11.2%) stddev-sans-first:9.96 17:56:20 INFO - PROCESS | 1654 | Values: 95.0 91.0 85.0 77.0 93.0 105.0 105.0 80.0 86.0 85.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:144.50 stddev:14.64 (10.1%) stddev-sans-first:15.48 17:56:20 INFO - PROCESS | 1654 | Values: 151.0 145.0 149.0 143.0 141.0 137.0 144.0 141.0 188.0 141.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.50 stddev:1.90 (1.1%) stddev-sans-first:1.50 17:56:20 INFO - PROCESS | 1654 | Values: 169.0 175.0 170.0 172.0 174.0 172.0 173.0 174.0 174.0 173.0 17:56:20 INFO - PROCESS | 1654 | 17:56:20 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.35 (2.0%) stddev-sans-first:1.12 17:56:20 INFO - PROCESS | 1654 | Values: 65.0 67.0 69.0 68.0 69.0 68.0 68.0 66.0 66.0 68.0 17:56:20 INFO - PROCESS | 1654 | -------- Summary: end -------- 17:56:20 INFO - PROCESS | 1654 | 17:56:21 INFO - TEST-INFO | 1654: exit 0 17:56:21 INFO - TEST-OK | kraken | took 60758ms 17:56:21 INFO - SUITE-END | took 1175s 17:56:22 INFO - Completed test suite (00:19:35) 17:56:22 INFO - javascript benchmark 17:56:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7300.389891852398, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.332533493302, 3341.248219150691, 3365.1250674824537, 3381.6280209176984, 3391.1691635549278, 4560.612347950424, 5382.515573598375, 5636.210930469738, 5686.260786097801, 5686.61162311829, 5625.718714064291, 5675.914861277084, 5706.201131841535, 5706.629069110505, 5719.256496655429, 4583.633309335245, 4911.066897289504, 5125.974370128144, 5236.6857988520815, 5288.709120998344, 5183.455737147602, 5188.195829646411, 5239.921401178985, 5246.0, 5251.737413129347, 5040.420351659533, 5061.367329083864, 5167.844964651048, 5198.116320225571, 5224.791008359661, 4498.235299999008, 5056.772445239973, 5133.30700355451, 5150.716710580917, 5185.325907632021, 5082.695038297714, 5108.157154069574, 5118.564921981636, 5148.150555158394, 5162.070827251093, 5428.5928555358505, 5482.725863706799, 5595.916061259084, 5689.91891540606, 5726.226959360475, 6072.848178795522, 6138.508919286491, 6155.938440615626, 6156.292026416974, 6237.563370564062, 6207.162033125515, 6207.441330280297, 6209.844753881144, 6212.471939885115, 6238.688065596702, 5762.596618236725, 5791.44981226787, 5796.362400135981, 5803.82588522345, 5830.300363956352, 5722.884037612625, 6021.2473440819895, 6145.293291271471, 6193.38066193377, 6229.0967809667345, 5459.208414779835, 5862.413758624189, 6137.877242455126, 6138.204480309982, 6145.692715364214, 6130.601510901777, 6215.595986260879, 6220.688965551795, 6231.2522497300615, 6247.500199983991, 6014.669193194416, 6051.818445446644, 6052.848678782978, 6083.665398403042, 6170.660613666201, 6096.969515152396, 6111.54163437744, 6116.847078823065, 6167.799101998603, 6233.594816337014, 4922.163232250526, 4939.431965323962, 4957.082939656175, 4958.925616115761, 4961.727105009187, 4339.652827773771, 4361.498427680794, 4389.736615803062, 4405.757683327448, 4471.418715566955, 5750.654960702372, 5770.971145144247, 5835.35811060783, 5846.269216347957, 5883.823485295448, 6171.84570385744, 6176.258848938156, 6183.752649894044, 6184.072389141665, 6186.1648677428875, 5397.920415916832, 5565.16522521625, 5662.858428539237, 5744.224529688522, 5804.738786754606, 4648.48866624671, 5136.229565565195, 5442.564594832483, 5459.235707001023, 5465.234867118606, 6238.469730072904, 6258.468030217483, 6266.561340706106, 6274.811755647338, 6337.271213810471, 5173.896522069613, 5246.658967167122, 6108.463491842691, 6119.563485741553, 6147.108669243024, 4741.810327586996, 5488.17677348401, 6191.938080619136, 6248.843778905563, 6283.905741413791, 6198.442140207403, 6199.56603037783, 6202.689865506797, 6219.191505104308, 6220.626762394271, 5393.30111015029, 6006.669633170218, 6036.185115009417, 6134.4978165938865, 6228.937710622836, 6082.270127584541, 6116.974953844038, 6136.549713441251, 6138.878900475427, 6152.846178845564, 6106.816795496142, 6112.480439162543, 6117.754831771827, 6133.6319820810895, 6146.832101900624, 5778.711064446677, 5873.4126587342635, 5931.406859314034, 6017.097435384728, 6024.759009639741, 6001.489873360813, 6036.879262414639, 6077.665728384981, 6106.297775755667, 6109.541784366102, 5291.656042357235, 5831.008728516247, 5987.850303742442, 6009.789657361971, 6012.87974240504], "value": 5609.227182460989, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.9190677627903, 493.9407271127465, 498.9501049895011, 513.0969493691102, 522.0655027500774, 39511.012224694394, 41729.0, 41875.371869421986, 42058.369124463155, 42096.36855447171, 43461.7826910865, 43972.0, 44063.898402539955, 44068.55931440685, 44073.8981525462, 8494.490330580185, 9270.629174833015, 9523.238140948708, 9527.475988820612, 9528.0, 8457.873131903027, 11189.0, 11431.599894003712, 11435.714107147347, 11594.768104637902, 11480.598179063734, 11513.942430287878, 11529.65411037666, 11554.075673946107, 11562.59530916418, 11353.318800871974, 11412.885871141349, 11417.086633069375, 11422.914823091793, 11435.485403156878, 11309.377984210865, 11320.943395283053, 11359.602413915556, 11378.943105284421, 11381.829272560919, 40693.389599155744, 41037.1792564147, 41112.38331425031, 41219.7939010303, 41232.38151427731, 4007.499062617173, 4011.83952641894, 4055.472788537501, 4124.463819703419, 4225.936610950838, 29419.558706619417, 30042.849785750932, 30052.398952020838, 30189.547156792665, 30213.697863021527, 3201.775875688703, 3225.1775797171804, 3260.673932606744, 3304.3556506481245, 3382.441897086978, 489062.0, 492189.0, 493685.0, 494653.0, 496574.9999999964, 60875.39124608786, 61216.08175877365, 61285.08072378918, 61304.693476533226, 61421.0, 63263.0, 63679.0, 63939.721205575624, 64046.67976660087, 64168.0, 15866.682666346609, 16087.678246435005, 16196.75704864428, 16339.0, 16348.509544713677, 9902.60389584423, 11856.466459009365, 12549.184303020274, 12780.04149688777, 12874.742505150032, 13453.461861525626, 14793.334299956528, 15613.531594052196, 15633.687326253412, 15772.290246938916, 5587.497125258747, 5975.223220981245, 6065.0, 6666.366695163952, 6788.1175447191545, 864.2869632553169, 898.5507246376811, 899.5232526760827, 920.2867777472378, 930.0420566816055, 30332.696673033428, 30354.0, 30433.239169020508, 30610.23474413113, 30703.539446908315, 1062.6387028410222, 1083.3175099687146, 1092.3882625729616, 1111.9888801111886, 1126.718320419895, 14539.563813085624, 14603.634909127357, 15691.0, 15775.895687301778, 16064.75902861458, 609404.0, 616880.0, 617587.0, 618665.0, 620881.0, 50654.0, 51826.48173518216, 60669.69665151646, 61747.6912615443, 61800.0, 1451.5645306407823, 1463.5170393770031, 1480.0749531542785, 1485.7102864941232, 1488.047649504318, 26785.0, 27347.0, 30011.69988300089, 30263.546046809755, 30289.394212115192, 49826.50173498219, 50259.74870125626, 50324.49675503198, 50540.0, 50646.0, 545856.0, 551555.0, 553550.0, 555358.0, 555883.0, 33896.15259618431, 34838.90966542016, 38483.80758096192, 39967.0, 40519.79740101399, 45606.54393456023, 46928.765356173004, 47157.0, 47857.5214247867, 48117.5188248127, 38773.80613096916, 39443.0, 40496.79751601341, 40598.59401405948, 40704.0, 38923.0, 39179.804100979316, 39767.602323977546, 40111.79944100261, 40473.0, 37202.441963371115, 37398.691045814354, 37518.24963500769, 37770.81114594409, 38511.80744096261, 34003.48994765031, 34183.145421364665, 34299.0, 34739.305213896085, 34888.825555873074, 25062.0, 25231.62152567676, 25561.84971676242, 25688.0, 32133.839330803195], "value": 20432.95301652419, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.9458275841381, 387.22941346720035, 393.57100760171414, 396.7559950630362, 415.97712125833067, 17417.738733919003, 19842.603147937032, 21257.36227913163, 21324.36026919193, 21356.57286854262, 20608.793912060875, 20962.89518552409, 20975.790242097613, 21190.470238244052, 21230.832304223262, 5469.890602187944, 5865.912011319833, 5920.141579470996, 5931.0, 5943.375945525722, 5701.144828275771, 5872.5889187756875, 5944.4352786485215, 5950.077737950603, 5969.611975221618, 5814.302283725938, 5822.592418530705, 5863.706814659271, 5865.912011319833, 5866.911996320058, 5595.776168953237, 5774.7401366938375, 5856.677882716449, 5873.647581145102, 5882.941170588282, 5809.3609702932645, 5815.447532484407, 5826.300843898717, 5827.970860145672, 5836.9124463133085, 13279.535216267384, 24606.0, 24743.50512989748, 24999.000039998376, 25239.74760252411, 2824.053941929456, 2949.8820047198087, 2961.6742158362777, 2987.0, 3066.861991210401, 10014.3490673106, 10084.647037353658, 10134.23993200513, 10289.0, 10596.364218146859, 1422.317287701909, 1788.2310606439225, 1875.962480750391, 1896.933607323751, 1899.3637131560943, 121611.0, 124567.0, 127188.0, 132193.0, 132826.0, 10101.898981010243, 11647.0, 11986.400679965967, 11987.820182697267, 12073.637790866289, 15702.76445853313, 15712.21438928049, 15792.36830526766, 15814.288357023961, 15829.050256984618, 8589.828203435896, 8788.956055219684, 8825.426347287534, 8889.777755556162, 8893.822123557493, 8469.534175620276, 8503.489790612583, 8513.702020429255, 8631.180037896389, 8643.481391116553, 4719.811207551728, 4768.47546769855, 4798.160321943646, 4803.991161856049, 4869.9269510957365, 1993.0931426201043, 2035.4097311779715, 2039.653258945953, 2039.6940458930983, 2043.2950632031925, 968.6077138758814, 1023.3859684189426, 1054.4411461925192, 1067.5622994572188, 1077.3912739302268, 20329.796702033087, 21283.0, 21326.466838329165, 21443.67834482484, 21616.567668646538, 1057.8201705710048, 1057.8783439904355, 1083.3608171179042, 1099.8185299425504, 1101.3391964821044, 11062.723431914106, 11219.541459610362, 11261.324320540794, 11284.32294062359, 11284.717882053013, 741500.0, 751486.0, 780968.0, 793589.0320548476, 807505.0, 130692.3465382667, 142789.8581521278, 147513.26243368714, 152534.0, 153357.0, 976.7069879036261, 978.1490103609763, 997.7006897930592, 997.9201663866874, 1008.2287050406445, 17664.91167544154, 18168.636627267115, 18467.0, 18504.537386565444, 18591.628167436847, 41722.16555668809, 54795.1079179733, 54906.948788164525, 58302.0, 58372.416275836695, 83534.88557650756, 83546.74679879686, 86218.56890715506, 86682.0, 87518.0, 7897.960510197642, 8099.87850182236, 8240.0, 8358.749237522885, 8455.492670439548, 8973.147550982647, 9528.428294302365, 12696.301703406032, 12951.481940722642, 13108.868911310765, 11158.274712143684, 11234.101271898391, 11369.602063927723, 11387.605225688674, 11533.500644916106, 10260.486975651038, 10463.319884207502, 11207.495662695035, 11241.0, 11349.54601815918, 8894.644214231359, 9332.440053596805, 10487.632932847313, 10853.240273180802, 11249.381284029458, 7452.329290363893, 8294.336453083619, 8413.495190288602, 8474.1949514796, 8514.74455766328, 8060.355171586376, 11027.393493357942, 11178.832317515407, 11200.327980321206, 11426.25729327591], "value": 9862.891822100328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.8401791784984, 1410.5063227870246, 1430.5708287513744, 1432.6991331820318, 1441.5296397674372, 7537.510061845976, 7796.649150788214, 8090.635921383538, 8475.788105297363, 8528.317734581226, 8040.517568945867, 8236.423450358478, 8255.380846436512, 8380.83238335233, 8387.993440787099, 2216.1467834883565, 2247.4830788918557, 2249.707538020055, 2254.842161048727, 2258.3563684349965, 2096.5911647228795, 2179.705739725133, 2316.119874447716, 2354.564405584972, 2359.7994170495526, 2253.616885129532, 2333.57995560799, 2333.9183128590503, 2348.4011577047913, 2351.106579499796, 2315.6758053872472, 2321.7213934327906, 2333.94165145871, 2343.742188359279, 2353.082297903819, 2270.9545809083725, 2320.385097949045, 2357.0, 2377.132346693462, 2379.5121999990133, 8372.874406883902, 8385.664573417056, 8410.663573457054, 8447.32421406292, 8473.95763021181, 5085.059264036166, 5218.165093585046, 5244.160934250502, 5256.316678831766, 5262.421133675292, 3255.951160732591, 3436.29555941034, 3451.2234747181933, 3454.7408944329277, 3485.8954231372845, 1171.8886705762939, 1180.403896032498, 1226.1784604315042, 1235.4872727818035, 1244.5270797097135, 10792.838107428395, 10817.83773243402, 10914.781704365947, 10926.180536459797, 10937.343759374464, 4622.6301895848255, 4733.597644200281, 4776.307435421844, 4786.0667169901535, 4905.950940490549, 8295.211954864279, 8582.957085214533, 8880.644774208959, 9515.666951656658, 9638.759031024281, 5235.869103272373, 5298.97350513245, 5303.814366497231, 5308.442613525602, 5323.1749078892835, 4609.492955774862, 4787.976060119677, 4854.805807767721, 4873.561379475864, 4926.113299606105, 3353.5305057292, 3403.2512847173584, 3886.0, 3915.5105611798526, 3991.8004099794894, 1102.779444111181, 1170.2218025013271, 1214.9392530373445, 1238.207547169812, 1286.0483242400744, 789.4039999800115, 888.8977767556685, 897.1163404047008, 900.8918929728475, 916.5188276155072, 4827.203511420576, 5566.721663916788, 5694.971525142348, 5716.942830571724, 5878.883012227663, 955.4171955107379, 991.1278075293695, 1007.5516395204211, 1014.5586669798514, 1016.0195411428008, 3617.113807117273, 3703.148275896559, 3737.5328083989502, 3753.5683396409763, 3768.8492460301827, 9740.610375585038, 10082.19342452602, 10115.39307641544, 10210.285280030475, 10234.744131396774, 10054.34646747959, 10149.492525373702, 10174.186065114773, 10190.235732320105, 10220.795584088277, 7795.493292935941, 7895.486793358414, 7983.640736166886, 7989.8402031960195, 8013.198680132056, 5004.324416203766, 5601.831945041656, 5611.551075913999, 5617.374716846059, 5722.513586345207, 9270.72187834366, 9278.72163835086, 9409.106134917172, 9413.435193888388, 9454.621815127319, 9162.770930726518, 9406.0, 9410.058994100535, 9457.574409151743, 9497.857532136884, 7239.746608868664, 7385.704571817282, 7691.88462173079, 7884.763457096296, 7914.604269786603, 7938.483998540077, 8099.068607110022, 8112.269895709414, 8140.470869393471, 8349.0, 7059.541129826544, 7123.430125590044, 7262.709491620276, 7293.708251670086, 7298.854022919618, 5628.60599758013, 6935.479839011993, 7101.928980710127, 7130.714771409085, 7296.671649775879, 6343.809685709436, 6358.2687990879795, 6384.425401713868, 6406.615603063831, 6407.167068281094, 4826.710397376169, 4901.852944411673, 4946.777395017161, 4961.08219979305, 4993.201087825931, 4206.390073439396, 4910.92633610489, 4914.680545764514, 4963.8262660806695, 5024.120778863758], "value": 4432.786814243303, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.2087825466591, 358.7255749351746, 364.5170149551844, 367.3864646041111, 368.6258447675608, 4346.0004199034165, 4438.600525952667, 5231.659942103761, 5458.399576046637, 5493.670379777216, 4526.103293891912, 4811.566958973692, 5023.773930173142, 5128.8974220515565, 5291.650629089587, 3492.886803483894, 3843.7885916274595, 4208.705390622657, 4242.193983143208, 4272.893177670568, 3976.7812770297624, 4245.0, 4247.872563823074, 4266.5706988158845, 4274.119387469519, 3536.8585256589704, 3636.963630363689, 3719.665230129288, 3983.8406463741417, 4275.166342563201, 4200.390943313233, 4222.957770422303, 4272.743635381856, 4276.401303817468, 4306.978465107686, 3489.930201395983, 3765.9811700941596, 3786.6024067473068, 3797.2785170817456, 3827.445020472044, 4675.602573781199, 5027.346444962168, 5228.189630607262, 5268.498477933809, 5278.2082687596785, 4162.0, 4315.935260971088, 4385.802638881258, 4888.217885138396, 4931.161702510582, 4389.1784909262615, 5098.184290513537, 5250.842474725726, 5756.827295181151, 5786.871560045793, 3966.4446977423186, 4128.318827393477, 4132.442120313749, 4147.543770185277, 4159.459270294872, 5774.873899589582, 5989.9101513477335, 6014.827108713803, 6028.0, 6036.038287556562, 3573.6069032406413, 3669.284489524517, 4252.192083504125, 4266.445362102969, 4284.807183676742, 3772.1135533149686, 3945.6448919597374, 4214.0, 4326.199653064194, 4346.9347959780625, 4293.754811104807, 4452.797744609003, 4688.624910007191, 4825.30033145192, 4848.272759086165, 4762.047590481918, 5139.922901156486, 5698.857528561736, 5764.423557644286, 5764.827055188351, 1207.8550573931184, 1238.7364887814379, 1283.663732007737, 1325.3174615073244, 1354.7019655675736, 803.0844836885955, 846.6105591428012, 881.1805021330097, 986.9851952220722, 1008.7125169326706, 4397.73613583186, 4971.90056198874, 5006.546792071858, 5007.824726134568, 5104.259882317043, 4699.801550604574, 5111.667741596784, 5211.530962213419, 5562.3881373048935, 5700.458456446631, 4582.495925448198, 4609.1703493371515, 4637.634806248838, 4798.808047678123, 5031.471695471996, 2531.822772405914, 2537.6320433537403, 2736.7947403944786, 2857.6711828999614, 2893.85530723463, 5167.0957582422925, 5750.7987220447085, 5832.795852145239, 5858.736356863951, 5869.442402971711, 5356.625036247503, 5431.85930954504, 5456.863578410572, 5658.85852853682, 5672.376038635747, 4969.794482558729, 4972.776225069881, 5448.618596698193, 5830.154627578977, 5835.533157347317, 5251.553617942518, 5323.4676532348, 5592.888142237214, 5611.765411609439, 5645.83062508109, 5499.890002200013, 5549.861253468695, 5808.535317174531, 5831.801103205685, 5855.677937713466, 5236.869078273074, 6151.907721384272, 6168.290646575701, 6182.752689892354, 6214.25428948529, 3813.5614404342746, 3854.4218367244016, 3917.2948869203815, 3960.9305487518786, 4014.1770936958437, 5444.891102177855, 5527.39198688152, 5541.251930989265, 5565.693886836263, 5845.620034697731, 5051.747412629427, 5275.630705850707, 5539.0, 5593.294045316217, 5602.123288698169, 4804.807807687654, 5032.446430892525, 5149.124648809637, 5544.0, 5710.457506536873, 5383.703896285742, 5564.972175139098, 5565.3304008798, 5584.548017515394, 5619.752099160369, 5017.799288028438, 5712.0860662293835, 5718.828435146952, 5744.668092506253, 5745.454181852718, 4946.703197808143, 5193.76628051731, 5352.9732351338, 5402.297701298806, 5602.747876345656], "value": 4124.433836909187, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.7440546997611, 752.947293689442, 778.8714862047763, 830.7881490219993, 843.7300063979527, 14872.256387180638, 15551.222438878067, 15961.760573591406, 16055.59861003474, 16165.595860103504, 13435.395407206675, 14754.0, 14798.482053128142, 14864.256787160653, 14895.92552037241, 845.8900342955423, 851.2806678356778, 853.2193043365313, 853.8292341531687, 854.9783009303884, 837.5853952293623, 850.9021462531795, 852.4373913217278, 852.7271273192581, 856.2251162697769, 894.49461054504, 894.5437826708393, 894.8031433084711, 896.9147930946582, 896.9686060987867, 879.9516026618534, 886.7251152142824, 890.3233542507676, 890.8619164029586, 894.306912143091, 652.1326635574675, 659.7558903205796, 661.4146480364911, 661.4675186474885, 665.065582856088, 12978.805317920238, 13072.411741471657, 13960.58118256454, 13988.72022559543, 14166.0, 11869.347185904771, 11979.802019798037, 12066.095042871731, 12186.329751863645, 12280.631581052583, 9046.276297896218, 9693.951530242373, 9938.552765125587, 10202.540885660163, 11009.889901100967, 3554.2180720241504, 3667.504886840269, 3704.3332200203954, 3835.9616403835885, 3842.212346468967, 102089.97910020953, 102428.46357304647, 103496.48251758693, 103953.96046039519, 105567.47216263869, 8964.238039766562, 9251.861222081674, 9303.53482325881, 9343.252539796937, 9530.237580993504, 19637.0, 19849.00754962246, 19868.50328741793, 19887.900560497106, 19971.700424493643, 7560.546367217985, 7591.620418979029, 7683.193264707237, 7870.016247969004, 7896.368290536743, 4319.222539942841, 4337.696361254745, 4421.889452763707, 4466.9106617867465, 4481.843135490242, 3432.4851272308856, 3545.503629491873, 3567.554055743032, 3570.285942811448, 3597.5862775780597, 640.7981485831948, 652.425865238587, 655.3413819111762, 659.864727730814, 668.4151367553391, 599.7780821096222, 686.6463771157858, 703.6341102626606, 709.159645819695, 714.892766085081, 9476.0, 10389.84415233772, 10454.529546170288, 10532.52603632823, 10844.403557804397, 531.9707416092152, 535.6223862177155, 552.0201642085331, 597.0804960361082, 599.3646734461485, 3521.9823900880333, 4960.131976904027, 5026.924596131061, 5087.923681144785, 5123.564497017721, 83810.16189838146, 102915.48542257241, 103503.0, 103932.0, 104575.4771226139, 54375.728121359934, 68949.31050689529, 69583.65208173927, 79292.0, 83685.0, 71570.64214678998, 73148.63425682943, 74478.62760686161, 76044.47911041859, 77147.61426192833, 10981.725456863323, 11120.777584448428, 11347.855178053545, 11432.94283528577, 11500.0, 40798.0, 50286.0, 50940.0, 51549.7422512885, 52033.0, 66146.00780988386, 67345.0, 68920.62158756696, 69527.26181845495, 70168.0, 10369.844452333373, 12665.366731663564, 13016.284104373972, 13288.335583220607, 13685.521006764715, 13055.0, 13376.799348009594, 13613.931930340284, 13739.519116830863, 13774.586762397144, 12109.244493644846, 13400.32998350098, 13922.809519564735, 14399.208043557705, 14571.708565828836, 13313.0, 13983.720325593635, 14003.929980350033, 14628.414863405345, 14659.706805864036, 13339.0, 13629.522966696119, 13636.0, 14013.0, 14494.34775435129, 9634.470104144339, 11071.114310854951, 11461.770764584495, 11793.528258869892, 11898.464569094585, 10769.24615276923, 11109.731445288273, 11326.943365283121, 11334.94332528332, 11349.829752553884], "value": 7324.000663703467, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 123.0, 111.0, 168.0, 169.0, 133.0, 120.0, 120.0, 122.0, 121.0], "value": 129.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 118.0, 114.0, 145.0, 114.0, 113.0, 111.0, 112.0, 134.0, 114.0], "value": 121.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 203.0, 200.0, 205.0, 198.0, 200.0, 197.0, 199.0, 196.0, 227.0], "value": 203.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 59.0, 84.0, 61.0, 62.0, 57.0, 74.0, 92.0, 60.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 85.0, 63.0, 73.0, 103.0, 90.0, 71.0, 71.0, 81.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 87.0, 84.0, 86.0, 90.0, 87.0, 113.0, 131.0, 135.0, 88.0], "value": 102.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 109.0, 152.0, 95.0, 149.0, 127.0, 95.0, 96.0, 96.0, 96.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 91.0, 94.0, 95.0, 93.0, 94.0, 76.0, 145.0, 144.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 92.0, 92.0, 91.0, 63.0, 62.0, 63.0, 62.0, 62.0], "value": 76.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 72.0, 75.0, 75.0, 71.0, 73.0, 71.0, 61.0, 87.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 91.0, 85.0, 77.0, 93.0, 105.0, 105.0, 80.0, 86.0, 85.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 145.0, 149.0, 143.0, 141.0, 137.0, 144.0, 141.0, 188.0, 141.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 175.0, 170.0, 172.0, 174.0, 172.0, 173.0, 174.0, 174.0, 173.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 69.0, 68.0, 69.0, 68.0, 68.0, 66.0, 66.0, 68.0], "value": 67.4, "unit": "score"}]}]} 17:56:22 INFO - Return code: 0 17:56:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:56:22 ERROR - # TBPL SUCCESS # 17:56:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:56:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:56:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:56:22 INFO - 2017-01-31 17:56:22.288 screenresolution[1659:10420] starting screenresolution argv=screenresolution get 17:56:22 INFO - 2017-01-31 17:56:22.290 screenresolution[1659:10420] Display 0: 1600x1200x32@60 17:56:22 INFO - 2017-01-31 17:56:22.297 screenresolution[1660:10423] starting screenresolution argv=screenresolution list 17:56:22 INFO - Available Modes on Display 0 17:56:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:56:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:56:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:56:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:56:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:56:22 INFO - Intel Iris: 17:56:22 INFO - Chipset Model: Intel Iris 17:56:22 INFO - Type: GPU 17:56:22 INFO - Bus: Built-In 17:56:22 INFO - VRAM (Dynamic, Max): 1536 MB 17:56:22 INFO - Vendor: Intel (0x8086) 17:56:22 INFO - Device ID: 0x0a2e 17:56:22 INFO - Revision ID: 0x0009 17:56:22 INFO - Displays: 17:56:22 INFO - Display: 17:56:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:56:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:56:22 INFO - Display Serial Number: 1600x1200 60 17:56:22 INFO - Main Display: Yes 17:56:22 INFO - Mirror: Off 17:56:22 INFO - Online: Yes 17:56:22 INFO - Rotation: Supported 17:56:22 INFO - Return code: 0 17:56:22 INFO - Running post-action listener: _resource_record_post_action 17:56:22 INFO - [mozharness: 2017-02-01 01:56:22.532931Z] Finished run-tests step (success) 17:56:22 INFO - Running post-run listener: _resource_record_post_run 17:56:22 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 38668800; Write bytes: 511221760; Read time: 241; Write time: 1646 17:56:22 INFO - TinderboxPrint: CPU usage
54.4% 17:56:22 INFO - TinderboxPrint: I/O read bytes / time
38,668,800 / 241 17:56:22 INFO - TinderboxPrint: I/O write bytes / time
511,221,760 / 1,646 17:56:22 INFO - TinderboxPrint: CPU idle
2,181.2 (45.6%) 17:56:22 INFO - TinderboxPrint: CPU system
963.3 (20.1%) 17:56:22 INFO - TinderboxPrint: CPU user
1,641.2 (34.3%) 17:56:22 INFO - TinderboxPrint: Swap in / out
246,427,648 / 0 17:56:22 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208642560; Write bytes: 209745920; Read time: 15960; Write time: 477 17:56:22 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35219968; Write bytes: 298727424; Read time: 218; Write time: 1156 17:56:23 INFO - Running post-run listener: _upload_blobber_files 17:56:23 INFO - Blob upload gear active. 17:56:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:56:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:56:23 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:56:23 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:56:23 INFO - (blobuploader) - INFO - Open directory for files ... 17:56:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:56:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:56:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:56:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:56:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:56:24 INFO - (blobuploader) - INFO - Done attempting. 17:56:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:56:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:56:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:56:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:56:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:56:25 INFO - (blobuploader) - INFO - Done attempting. 17:56:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:56:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:56:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:56:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:56:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:56:26 INFO - (blobuploader) - INFO - Done attempting. 17:56:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:56:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:56:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:56:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:56:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:56:28 INFO - (blobuploader) - INFO - Done attempting. 17:56:28 INFO - (blobuploader) - INFO - Iteration through files over. 17:56:28 INFO - Return code: 0 17:56:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:56:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:56:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a0f4f352271b2035b831f61877641334e705a1987f1074825617bd7cb20d22851a13d057fac23539483f2314b81defa71a38d8a15415ebdfb53c55cfa14fc14f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5523de43c528923dfed64b09f8d3267da92eee21c0da9b912d1ad0e395fd1e7dc4258011d21a21d3e68e77a87f52fe36c0126c9c67bf99e8122199ded2f893af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e082e5429e7977ad4ec38dd7242ee0a49ee1b37ede44476d4fd8ea27ba2a4841f4ab03409ca9db00cd79caddb2d3984f3aabe35ba88ef62ec0877f88eccd8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0938c787d447f6f9619f8d98e2d8edc15b635556ecb901021f7f4e296589ef2a2ba570babc17fb151d523a59e3a6c3eb1ad3ea7aaf73e2f1a4905c8df8f3f113"} 17:56:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:56:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:56:28 INFO - Contents: 17:56:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a0f4f352271b2035b831f61877641334e705a1987f1074825617bd7cb20d22851a13d057fac23539483f2314b81defa71a38d8a15415ebdfb53c55cfa14fc14f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5523de43c528923dfed64b09f8d3267da92eee21c0da9b912d1ad0e395fd1e7dc4258011d21a21d3e68e77a87f52fe36c0126c9c67bf99e8122199ded2f893af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e082e5429e7977ad4ec38dd7242ee0a49ee1b37ede44476d4fd8ea27ba2a4841f4ab03409ca9db00cd79caddb2d3984f3aabe35ba88ef62ec0877f88eccd8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0938c787d447f6f9619f8d98e2d8edc15b635556ecb901021f7f4e296589ef2a2ba570babc17fb151d523a59e3a6c3eb1ad3ea7aaf73e2f1a4905c8df8f3f113"} 17:56:28 INFO - Running post-run listener: copy_logs_to_upload_dir 17:56:28 INFO - Copying logs to upload dir... 17:56:28 INFO - mkdir: /builds/slave/test/build/upload/logs 17:56:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.550284 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-31 17:56:28.917355) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 17:56:28.921713) ========= 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.4NSXz8NLdk/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.dmdCT31xUY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a0f4f352271b2035b831f61877641334e705a1987f1074825617bd7cb20d22851a13d057fac23539483f2314b81defa71a38d8a15415ebdfb53c55cfa14fc14f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5523de43c528923dfed64b09f8d3267da92eee21c0da9b912d1ad0e395fd1e7dc4258011d21a21d3e68e77a87f52fe36c0126c9c67bf99e8122199ded2f893af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e082e5429e7977ad4ec38dd7242ee0a49ee1b37ede44476d4fd8ea27ba2a4841f4ab03409ca9db00cd79caddb2d3984f3aabe35ba88ef62ec0877f88eccd8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0938c787d447f6f9619f8d98e2d8edc15b635556ecb901021f7f4e296589ef2a2ba570babc17fb151d523a59e3a6c3eb1ad3ea7aaf73e2f1a4905c8df8f3f113"} build_url:https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011055 build_url: 'https://queue.taskcluster.net/v1/task/VduMYrswTJe3iTBwkfa-3w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a0f4f352271b2035b831f61877641334e705a1987f1074825617bd7cb20d22851a13d057fac23539483f2314b81defa71a38d8a15415ebdfb53c55cfa14fc14f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5523de43c528923dfed64b09f8d3267da92eee21c0da9b912d1ad0e395fd1e7dc4258011d21a21d3e68e77a87f52fe36c0126c9c67bf99e8122199ded2f893af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e082e5429e7977ad4ec38dd7242ee0a49ee1b37ede44476d4fd8ea27ba2a4841f4ab03409ca9db00cd79caddb2d3984f3aabe35ba88ef62ec0877f88eccd8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0938c787d447f6f9619f8d98e2d8edc15b635556ecb901021f7f4e296589ef2a2ba570babc17fb151d523a59e3a6c3eb1ad3ea7aaf73e2f1a4905c8df8f3f113"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 17:56:28.963238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 17:56:28.963562) ========= 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.4NSXz8NLdk/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.dmdCT31xUY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.005110 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 17:56:29.011736) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 17:56:29.012020) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 17:56:29.015330) ========= ========= Total master_lag: 0.21 =========