builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0325 starttime: 1481220172.47 results: success (0) buildid: 20161208071558 builduid: d28ea995524547d7b09f62354ba63f7c revision: 64e024fe44719575aa56c432e059ca065071ac95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.468313) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.468796) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.469074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.519552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.519844) ========= 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.KG7bunfc4h/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.szeguNZ5tC/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 --2016-12-08 10:02:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.5M=0.001s 2016-12-08 10:02:52 (19.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.193377 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.738521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.738854) ========= 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.KG7bunfc4h/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.szeguNZ5tC/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.025115 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.787211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:52.787522) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 64e024fe44719575aa56c432e059ca065071ac95 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 64e024fe44719575aa56c432e059ca065071ac95 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KG7bunfc4h/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.szeguNZ5tC/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 2016-12-08 10:02:52,888 truncating revision to first 12 chars 2016-12-08 10:02:52,888 Setting DEBUG logging. 2016-12-08 10:02:52,888 attempt 1/10 2016-12-08 10:02:52,888 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/64e024fe4471?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-08 10:02:53,519 unpacking tar archive at: mozilla-central-64e024fe4471/testing/mozharness/ program finished with exit code 0 elapsedTime=0.906682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:53.718349) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:53.718675) ========= script_repo_revision: 64e024fe44719575aa56c432e059ca065071ac95 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:53.719227) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:53.719734) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 10:02:53.739508) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 10:02:53.740146) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KG7bunfc4h/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.szeguNZ5tC/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:02:53 INFO - MultiFileLogger online at 20161208 10:02:53 in /builds/slave/test 10:02:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:02:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:02:53 INFO - {'append_to_log': False, 10:02:53 INFO - 'base_work_dir': '/builds/slave/test', 10:02:53 INFO - 'blob_upload_branch': 'Firefox', 10:02:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:02:53 INFO - 'branch': 'Firefox', 10:02:53 INFO - 'buildbot_json_path': 'buildprops.json', 10:02:53 INFO - 'config_files': ('talos/mac_config.py',), 10:02:53 INFO - 'default_actions': ('clobber', 10:02:53 INFO - 'read-buildbot-config', 10:02:53 INFO - 'download-and-extract', 10:02:53 INFO - 'populate-webroot', 10:02:53 INFO - 'create-virtualenv', 10:02:53 INFO - 'install', 10:02:53 INFO - 'run-tests'), 10:02:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:02:53 INFO - 'download_minidump_stackwalk': True, 10:02:53 INFO - 'download_symbols': 'ondemand', 10:02:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:02:53 INFO - 'tooltool.py': '/tools/tooltool.py', 10:02:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:02:53 INFO - '/tools/misc-python/virtualenv.py')}, 10:02:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:02:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:02:53 INFO - 'installer_path': 'installer.exe', 10:02:53 INFO - 'log_level': 'info', 10:02:53 INFO - 'log_name': 'talos', 10:02:53 INFO - 'log_to_console': True, 10:02:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:02:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:02:53 INFO - 'opt_config_files': (), 10:02:53 INFO - 'pip_index': False, 10:02:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:02:53 INFO - 'cmd': ('bash', 10:02:53 INFO - '-c', 10:02:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:02:53 INFO - 'enabled': True, 10:02:53 INFO - 'halt_on_failure': False, 10:02:53 INFO - 'name': 'check_screen_resolution'},), 10:02:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:02:53 INFO - 'cmd': ('bash', 10:02:53 INFO - '-c', 10:02:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:02:53 INFO - 'enabled': True, 10:02:53 INFO - 'halt_on_failure': False, 10:02:53 INFO - 'name': 'check_screen_resolution'},), 10:02:53 INFO - 'run_cmd_checks_enabled': True, 10:02:53 INFO - 'sps_profile': False, 10:02:53 INFO - 'sps_profile_interval': 0, 10:02:53 INFO - 'suite': 'dromaeojs-e10s', 10:02:53 INFO - 'system_bits': '32', 10:02:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:02:53 INFO - 'title': 't-yosemite-r7-0325', 10:02:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:02:53 INFO - 'use_talos_json': True, 10:02:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:02:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:02:53 INFO - 'work_dir': 'build'} 10:02:53 INFO - [mozharness: 2016-12-08 18:02:53.947857Z] Running clobber step. 10:02:53 INFO - Running pre-action listener: _resource_record_pre_action 10:02:53 INFO - Running main action method: clobber 10:02:53 INFO - rmtree: /builds/slave/test/build 10:02:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:02:56 INFO - Running post-action listener: _resource_record_post_action 10:02:56 INFO - [mozharness: 2016-12-08 18:02:56.390240Z] Finished clobber step (success) 10:02:56 INFO - [mozharness: 2016-12-08 18:02:56.390313Z] Running read-buildbot-config step. 10:02:56 INFO - Running pre-action listener: _resource_record_pre_action 10:02:56 INFO - Running main action method: read_buildbot_config 10:02:56 INFO - Using buildbot properties: 10:02:56 INFO - { 10:02:56 INFO - "project": "", 10:02:56 INFO - "product": "firefox", 10:02:56 INFO - "script_repo_revision": "production", 10:02:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:02:56 INFO - "repository": "", 10:02:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:02:56 INFO - "buildid": "20161208071558", 10:02:56 INFO - "pgo_build": "False", 10:02:56 INFO - "basedir": "/builds/slave/test", 10:02:56 INFO - "buildnumber": 30, 10:02:56 INFO - "slavename": "t-yosemite-r7-0325", 10:02:56 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:02:56 INFO - "platform": "yosemite_r7", 10:02:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:02:56 INFO - "branch": "mozilla-central", 10:02:56 INFO - "repo_path": "mozilla-central", 10:02:56 INFO - "revision": "64e024fe44719575aa56c432e059ca065071ac95", 10:02:56 INFO - "stage_platform": "macosx64", 10:02:56 INFO - "builduid": "d28ea995524547d7b09f62354ba63f7c", 10:02:56 INFO - "slavebuilddir": "test" 10:02:56 INFO - } 10:02:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 10:02:56 INFO - Running post-action listener: _resource_record_post_action 10:02:56 INFO - [mozharness: 2016-12-08 18:02:56.393547Z] Finished read-buildbot-config step (success) 10:02:56 INFO - [mozharness: 2016-12-08 18:02:56.393685Z] Running download-and-extract step. 10:02:56 INFO - Running pre-action listener: _resource_record_pre_action 10:02:56 INFO - Running main action method: download_and_extract 10:02:56 INFO - mkdir: /builds/slave/test/build/tests 10:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:56 INFO - trying https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 10:02:56 INFO - Downloading https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 10:02:57 INFO - Downloaded 1199 bytes. 10:02:57 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:02:57 INFO - Using the following test package requirements: 10:02:57 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:02:57 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 10:02:57 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 10:02:57 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'jsshell-mac.zip'], 10:02:57 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 10:02:57 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:02:57 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 10:02:57 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 10:02:57 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 10:02:57 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:02:57 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 10:02:57 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:02:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:02:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:02:57 INFO - Fetch https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:02:58 INFO - Expected file size: 16284219 10:02:58 INFO - Obtained file size: 16284219 10:03:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:03:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:03:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:03:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:03:06 INFO - Expected file size: 16284219 10:03:06 INFO - Obtained file size: 16284219 10:03:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 10:03:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:03:12 INFO - Fetch https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 10:03:14 INFO - Expected file size: 14409734 10:03:14 INFO - Obtained file size: 14409734 10:03: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')]} 10:03:14 INFO - trying https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:03:14 INFO - Downloading https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 10:03:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 10:03:17 INFO - Downloaded 107640528 bytes. 10:03:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:03:17 INFO - mkdir: /builds/slave/test/properties 10:03:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:03:17 INFO - Writing to file /builds/slave/test/properties/build_url 10:03:17 INFO - Contents: 10:03:17 INFO - build_url:https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:03:17 INFO - Running post-action listener: _resource_record_post_action 10:03:17 INFO - Running post-action listener: set_extra_try_arguments 10:03:17 INFO - [mozharness: 2016-12-08 18:03:17.891757Z] Finished download-and-extract step (success) 10:03:17 INFO - [mozharness: 2016-12-08 18:03:17.891907Z] Running populate-webroot step. 10:03:17 INFO - Running pre-action listener: _resource_record_pre_action 10:03:17 INFO - Running main action method: populate_webroot 10:03:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:03:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:03:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:03:17 INFO - '--tppagecycles', 10:03:17 INFO - '7'], 10:03:17 INFO - 'tests': ['tsvgm']}}, 10:03:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:03:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:03:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:03:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:03:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:03:17 INFO - 'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:03:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:03:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:03:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:03:17 INFO - 'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['damp', 'tps']}, 10:03:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:03:17 INFO - 'tests': ['damp', 'tps']}, 10:03:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['dromaeo_dom']}, 10:03:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:03:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['basic_compositor_video']}, 10:03:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:03:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['a11yr', 10:03:17 INFO - 'ts_paint', 10:03:17 INFO - 'tpaint', 10:03:17 INFO - 'sessionrestore', 10:03:17 INFO - 'sessionrestore_no_auto_restore', 10:03:17 INFO - 'tabpaint']}, 10:03:17 INFO - 'other-e10s': {'tests': ['a11yr', 10:03:17 INFO - 'ts_paint', 10:03:17 INFO - 'tpaint', 10:03:17 INFO - 'sessionrestore', 10:03:17 INFO - 'sessionrestore_no_auto_restore', 10:03:17 INFO - 'tabpaint']}, 10:03:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:03:17 INFO - 'ts_paint', 10:03:17 INFO - 'tpaint', 10:03:17 INFO - 'sessionrestore', 10:03:17 INFO - 'sessionrestore_no_auto_restore', 10:03:17 INFO - 'tabpaint']}, 10:03:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:03:17 INFO - 'ts_paint', 10:03:17 INFO - 'tpaint', 10:03:17 INFO - 'sessionrestore', 10:03:17 INFO - 'sessionrestore_no_auto_restore', 10:03:17 INFO - 'tabpaint']}, 10:03:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['a11yr', 10:03:17 INFO - 'ts_paint', 10:03:17 INFO - 'tpaint', 10:03:17 INFO - 'sessionrestore', 10:03:17 INFO - 'sessionrestore_no_auto_restore', 10:03:17 INFO - 'tabpaint']}, 10:03:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['a11yr', 10:03:17 INFO - 'ts_paint', 10:03:17 INFO - 'tpaint', 10:03:17 INFO - 'sessionrestore', 10:03:17 INFO - 'sessionrestore_no_auto_restore', 10:03:17 INFO - 'tabpaint']}, 10:03:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['tsvgx', 10:03:17 INFO - 'tsvgr_opacity', 10:03:17 INFO - 'tart', 10:03:17 INFO - 'tscrollx', 10:03:17 INFO - 'cart', 10:03:17 INFO - 'tsvg_static']}, 10:03:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:03:17 INFO - 'tsvgr_opacity', 10:03:17 INFO - 'tart', 10:03:17 INFO - 'tscrollx', 10:03:17 INFO - 'cart', 10:03:17 INFO - 'tsvg_static']}, 10:03:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:03:17 INFO - 'talos_options': ['--disable-e10s'], 10:03:17 INFO - 'tests': ['tp5o']}, 10:03:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:03:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:03:17 INFO - 'talos_options': ['--disable-e10s', 10:03:17 INFO - '--xperf_path', 10:03:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:03:17 INFO - 'tests': ['tp5n']}, 10:03:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:03:17 INFO - 'talos_options': ['--xperf_path', 10:03:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:03:17 INFO - 'tests': ['tp5n']}}, 10:03:17 INFO - 'talos.zip': {'path': '', 10:03:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:03:17 INFO - Running post-action listener: _resource_record_post_action 10:03:17 INFO - [mozharness: 2016-12-08 18:03:17.910388Z] Finished populate-webroot step (success) 10:03:17 INFO - [mozharness: 2016-12-08 18:03:17.910525Z] Running create-virtualenv step. 10:03:17 INFO - Running pre-action listener: _resource_record_pre_action 10:03:17 INFO - Running main action method: create_virtualenv 10:03:17 INFO - Creating virtualenv /builds/slave/test/build/venv 10:03:17 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 10:03:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:03:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:03:18 INFO - Using real prefix '/tools/python27' 10:03:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:03:18 INFO - Installing distribute.............................................................................................................................................................................................done. 10:03:21 INFO - Installing pip.................done. 10:03:21 INFO - Return code: 0 10:03:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:03:21 INFO - Reading from file tmpfile_stdout 10:03:21 INFO - Output received: 10:03:21 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) 10:03:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:21 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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:21 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 10:03:21 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 10:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:21 INFO - 'HOME': '/Users/cltbld', 10:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:21 INFO - 'LOGNAME': 'cltbld', 10:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:21 INFO - 'MOZ_NO_REMOTE': '1', 10:03:21 INFO - 'NO_EM_RESTART': '1', 10:03:21 INFO - 'PAGER': '/bin/cat', 10:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:21 INFO - 'PWD': '/builds/slave/test', 10:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:21 INFO - 'SHELL': '/bin/bash', 10:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:21 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:21 INFO - 'USER': 'cltbld', 10:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:21 INFO - 'XPC_FLAGS': '0x0', 10:03:21 INFO - 'XPC_SERVICE_NAME': '0', 10:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:22 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 10:03:22 INFO - Cleaning up... 10:03:22 INFO - Return code: 0 10:03:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:03:22 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')]} 10:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:22 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')]} 10:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:22 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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:22 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 10:03:22 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 10:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:22 INFO - 'HOME': '/Users/cltbld', 10:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:22 INFO - 'LOGNAME': 'cltbld', 10:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:22 INFO - 'MOZ_NO_REMOTE': '1', 10:03:22 INFO - 'NO_EM_RESTART': '1', 10:03:22 INFO - 'PAGER': '/bin/cat', 10:03:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:22 INFO - 'PWD': '/builds/slave/test', 10:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:22 INFO - 'SHELL': '/bin/bash', 10:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:22 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:22 INFO - 'USER': 'cltbld', 10:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:22 INFO - 'XPC_FLAGS': '0x0', 10:03:22 INFO - 'XPC_SERVICE_NAME': '0', 10:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:22 INFO - Downloading/unpacking psutil>=3.1.1 10:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:03:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:03:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:03:26 INFO - Installing collected packages: psutil 10:03:26 INFO - Running setup.py install for psutil 10:03:26 INFO - building 'psutil._psutil_osx' extension 10:03:26 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 10:03:27 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 10:03:27 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 10:03:27 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 10:03:27 INFO - building 'psutil._psutil_posix' extension 10:03:27 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 10:03:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:03:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:03:27 INFO - ^ 10:03:27 INFO - 1 warning generated. 10:03:27 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 10:03:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:03:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:03:27 INFO - Successfully installed psutil 10:03:27 INFO - Cleaning up... 10:03:27 INFO - Return code: 0 10:03:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:03:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:03:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:27 INFO - 'HOME': '/Users/cltbld', 10:03:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:27 INFO - 'LOGNAME': 'cltbld', 10:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:27 INFO - 'MOZ_NO_REMOTE': '1', 10:03:27 INFO - 'NO_EM_RESTART': '1', 10:03:27 INFO - 'PAGER': '/bin/cat', 10:03:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:27 INFO - 'PWD': '/builds/slave/test', 10:03:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:27 INFO - 'SHELL': '/bin/bash', 10:03:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:27 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:27 INFO - 'USER': 'cltbld', 10:03:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:27 INFO - 'XPC_FLAGS': '0x0', 10:03:27 INFO - 'XPC_SERVICE_NAME': '0', 10:03:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:03:27 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 10:03:27 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 10:03:27 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 10:03:27 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 10:03:27 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 10:03:27 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 10:03:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:03:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:03:30 INFO - Installing collected packages: mozsystemmonitor 10:03:30 INFO - Running setup.py install for mozsystemmonitor 10:03:30 INFO - Successfully installed mozsystemmonitor 10:03:30 INFO - Cleaning up... 10:03:30 INFO - Return code: 0 10:03:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:30 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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:30 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 10:03:30 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 10:03:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:30 INFO - 'HOME': '/Users/cltbld', 10:03:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:30 INFO - 'LOGNAME': 'cltbld', 10:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:30 INFO - 'MOZ_NO_REMOTE': '1', 10:03:30 INFO - 'NO_EM_RESTART': '1', 10:03:30 INFO - 'PAGER': '/bin/cat', 10:03:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:30 INFO - 'PWD': '/builds/slave/test', 10:03:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:30 INFO - 'SHELL': '/bin/bash', 10:03:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:30 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:30 INFO - 'USER': 'cltbld', 10:03:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:30 INFO - 'XPC_FLAGS': '0x0', 10:03:30 INFO - 'XPC_SERVICE_NAME': '0', 10:03:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:31 INFO - Downloading/unpacking jsonschema==2.5.1 10:03:31 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 10:03:31 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 10:03:31 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 10:03:31 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 10:03:31 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 10:03:31 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 10:03:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:03:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:03:33 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 10:03:33 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 10:03:33 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 10:03:33 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 10:03:33 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 10:03:33 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 10:03:34 INFO - Downloading functools32-3.2.3-2.tar.gz 10:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:03:34 INFO - warning: no files found matching '*.txt' 10:03:34 INFO - no previously-included directories found matching 'build' 10:03:34 INFO - no previously-included directories found matching 'dist' 10:03:34 INFO - no previously-included directories found matching '.git*' 10:03:34 INFO - Installing collected packages: jsonschema, functools32 10:03:34 INFO - Running setup.py install for functools32 10:03:34 INFO - warning: no files found matching '*.txt' 10:03:34 INFO - no previously-included directories found matching 'build' 10:03:34 INFO - no previously-included directories found matching 'dist' 10:03:34 INFO - no previously-included directories found matching '.git*' 10:03:34 INFO - Successfully installed jsonschema functools32 10:03:34 INFO - Cleaning up... 10:03:34 INFO - Return code: 0 10:03:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:34 INFO - 'HOME': '/Users/cltbld', 10:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:34 INFO - 'LOGNAME': 'cltbld', 10:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:34 INFO - 'MOZ_NO_REMOTE': '1', 10:03:34 INFO - 'NO_EM_RESTART': '1', 10:03:34 INFO - 'PAGER': '/bin/cat', 10:03:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:34 INFO - 'PWD': '/builds/slave/test', 10:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:34 INFO - 'SHELL': '/bin/bash', 10:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:34 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:34 INFO - 'USER': 'cltbld', 10:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:34 INFO - 'XPC_FLAGS': '0x0', 10:03:34 INFO - 'XPC_SERVICE_NAME': '0', 10:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:03:34 INFO - Cleaning up... 10:03:34 INFO - Return code: 0 10:03:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:34 INFO - 'HOME': '/Users/cltbld', 10:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:34 INFO - 'LOGNAME': 'cltbld', 10:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:34 INFO - 'MOZ_NO_REMOTE': '1', 10:03:34 INFO - 'NO_EM_RESTART': '1', 10:03:34 INFO - 'PAGER': '/bin/cat', 10:03:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:34 INFO - 'PWD': '/builds/slave/test', 10:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:34 INFO - 'SHELL': '/bin/bash', 10:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:34 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:34 INFO - 'USER': 'cltbld', 10:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:34 INFO - 'XPC_FLAGS': '0x0', 10:03:34 INFO - 'XPC_SERVICE_NAME': '0', 10:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:34 INFO - Downloading/unpacking blobuploader==1.2.4 10:03:34 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 10:03:34 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 10:03:34 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 10:03:34 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 10:03:34 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 10:03:34 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 10:03:37 INFO - Downloading blobuploader-1.2.4.tar.gz 10:03:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:03:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:03:37 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 10:03:37 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 10:03:37 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 10:03:37 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 10:03:37 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 10:03:37 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 10:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:03:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:03:38 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 10:03:38 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 10:03:38 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 10:03:38 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 10:03:38 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 10:03:38 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 10:03:38 INFO - Downloading docopt-0.6.1.tar.gz 10:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:03:38 INFO - Installing collected packages: blobuploader, requests, docopt 10:03:38 INFO - Running setup.py install for blobuploader 10:03:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:03:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:03:38 INFO - Running setup.py install for requests 10:03:39 INFO - Running setup.py install for docopt 10:03:39 INFO - Successfully installed blobuploader requests docopt 10:03:39 INFO - Cleaning up... 10:03:39 INFO - Return code: 0 10:03:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:03:39 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')]} 10:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:39 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')]} 10:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:39 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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:39 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 10:03:39 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 10:03:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:39 INFO - 'HOME': '/Users/cltbld', 10:03:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:39 INFO - 'LOGNAME': 'cltbld', 10:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:39 INFO - 'MOZ_NO_REMOTE': '1', 10:03:39 INFO - 'NO_EM_RESTART': '1', 10:03:39 INFO - 'PAGER': '/bin/cat', 10:03:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:39 INFO - 'PWD': '/builds/slave/test', 10:03:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:39 INFO - 'SHELL': '/bin/bash', 10:03:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:39 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:39 INFO - 'USER': 'cltbld', 10:03:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:39 INFO - 'XPC_FLAGS': '0x0', 10:03:39 INFO - 'XPC_SERVICE_NAME': '0', 10:03:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:03:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-VEF_Du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-2lVMz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-YQKtVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-uRuhHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-4mSxI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-oufnjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-WkGpcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-FoWRRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-BvMv7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-0ofcIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:03:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-mlM8zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:03:41 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-PxI6i4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:03:41 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-4e4vJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:03:41 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-2WQZbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:03:41 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-nZ7Jtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:03:41 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-XoHldf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:03:41 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-SJitbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:03:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:03:41 INFO - Running setup.py install for manifestparser 10:03:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:03:41 INFO - Running setup.py install for mozcrash 10:03:41 INFO - Running setup.py install for mozdebug 10:03:42 INFO - Running setup.py install for mozdevice 10:03:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Running setup.py install for mozfile 10:03:42 INFO - Running setup.py install for mozhttpd 10:03:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Running setup.py install for mozinfo 10:03:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Running setup.py install for mozInstall 10:03:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:03:42 INFO - Running setup.py install for mozleak 10:03:42 INFO - Running setup.py install for mozlog 10:03:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:03:43 INFO - Running setup.py install for moznetwork 10:03:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:03:43 INFO - Running setup.py install for mozprocess 10:03:43 INFO - Running setup.py install for mozprofile 10:03:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:03:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:03:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:03:43 INFO - Running setup.py install for mozrunner 10:03:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:03:43 INFO - Running setup.py install for mozscreenshot 10:03:43 INFO - Running setup.py install for moztest 10:03:44 INFO - Running setup.py install for mozversion 10:03:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:03:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:03:44 INFO - Cleaning up... 10:03:44 INFO - Return code: 0 10:03:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:03:44 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')]} 10:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:44 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')]} 10:03:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:44 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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:44 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 10:03:44 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 10:03:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:44 INFO - 'HOME': '/Users/cltbld', 10:03:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:44 INFO - 'LOGNAME': 'cltbld', 10:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:44 INFO - 'MOZ_NO_REMOTE': '1', 10:03:44 INFO - 'NO_EM_RESTART': '1', 10:03:44 INFO - 'PAGER': '/bin/cat', 10:03:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:44 INFO - 'PWD': '/builds/slave/test', 10:03:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:44 INFO - 'SHELL': '/bin/bash', 10:03:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:44 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:44 INFO - 'USER': 'cltbld', 10:03:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:44 INFO - 'XPC_FLAGS': '0x0', 10:03:44 INFO - 'XPC_SERVICE_NAME': '0', 10:03:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:03:44 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-M9s8vY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:03:44 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)) 10:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:03:44 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-zOTrcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:03:44 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)) 10:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:03:44 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-dy9e61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:03:44 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)) 10:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:03:44 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-bB7z3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:03:44 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)) 10:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:03:44 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-3UMQuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-CCqvRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-9qQqKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-kyvHkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-VJMhPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-00usF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-JUc15e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-HnAFNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-QIJaRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-gIkTjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:03:45 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)) 10:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:03:45 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-GtSDkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:03:46 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)) 10:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:03:46 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-OT4N4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 10:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:03:46 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-SGAck7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:03:46 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)) 10:03:46 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)) 10:03:46 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)) 10:03:46 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)) 10:03:46 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)) 10:03:46 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)) 10:03:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:03:46 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 10:03:46 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 10:03:46 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 10:03:46 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 10:03:46 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 10:03:46 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 10:03:48 INFO - Downloading blessings-1.6.tar.gz 10:03:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:03:48 INFO - Installing collected packages: blessings 10:03:48 INFO - Running setup.py install for blessings 10:03:49 INFO - Successfully installed blessings 10:03:49 INFO - Cleaning up... 10:03:49 INFO - Return code: 0 10:03:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:03:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:03:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:03:49 INFO - Reading from file tmpfile_stdout 10:03:49 INFO - Current package versions: 10:03:49 INFO - blessings == 1.6 10:03:49 INFO - blobuploader == 1.2.4 10:03:49 INFO - docopt == 0.6.1 10:03:49 INFO - functools32 == 3.2.3-2 10:03:49 INFO - jsonschema == 2.5.1 10:03:49 INFO - manifestparser == 1.1 10:03:49 INFO - mozInstall == 1.12 10:03:49 INFO - mozcrash == 1.0 10:03:49 INFO - mozdebug == 0.1 10:03:49 INFO - mozdevice == 0.49 10:03:49 INFO - mozfile == 1.2 10:03:49 INFO - mozhttpd == 0.7 10:03:49 INFO - mozinfo == 0.9 10:03:49 INFO - mozleak == 0.1 10:03:49 INFO - mozlog == 3.4 10:03:49 INFO - moznetwork == 0.27 10:03:49 INFO - mozprocess == 0.23 10:03:49 INFO - mozprofile == 0.28 10:03:49 INFO - mozrunner == 6.13 10:03:49 INFO - mozscreenshot == 0.1 10:03:49 INFO - mozsystemmonitor == 0.3 10:03:49 INFO - moztest == 0.7 10:03:49 INFO - mozversion == 1.4 10:03:49 INFO - psutil == 3.1.1 10:03:49 INFO - requests == 1.2.3 10:03:49 INFO - wsgiref == 0.1.2 10:03:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:03:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:49 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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:49 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 10:03:49 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 10:03:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:49 INFO - 'HOME': '/Users/cltbld', 10:03:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:49 INFO - 'LOGNAME': 'cltbld', 10:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:49 INFO - 'MOZ_NO_REMOTE': '1', 10:03:49 INFO - 'NO_EM_RESTART': '1', 10:03:49 INFO - 'PAGER': '/bin/cat', 10:03:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:49 INFO - 'PWD': '/builds/slave/test', 10:03:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:49 INFO - 'SHELL': '/bin/bash', 10:03:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:49 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:49 INFO - 'USER': 'cltbld', 10:03:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:49 INFO - 'XPC_FLAGS': '0x0', 10:03:49 INFO - 'XPC_SERVICE_NAME': '0', 10:03:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 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)) 10:03:49 INFO - Cleaning up... 10:03:49 INFO - Return code: 0 10:03:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:03:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:49 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 0x1047cf3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104745570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe949cdcfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, '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.KG7bunfc4h/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.szeguNZ5tC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 10:03:49 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 10:03:49 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 10:03:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:03:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:49 INFO - 'HOME': '/Users/cltbld', 10:03:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:49 INFO - 'LOGNAME': 'cltbld', 10:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:49 INFO - 'MOZ_NO_REMOTE': '1', 10:03:49 INFO - 'NO_EM_RESTART': '1', 10:03:49 INFO - 'PAGER': '/bin/cat', 10:03:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:49 INFO - 'PWD': '/builds/slave/test', 10:03:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:49 INFO - 'SHELL': '/bin/bash', 10:03:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:03:49 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:03:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:49 INFO - 'USER': 'cltbld', 10:03:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:49 INFO - 'XPC_FLAGS': '0x0', 10:03:49 INFO - 'XPC_SERVICE_NAME': '0', 10:03:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:03:50 INFO - Cleaning up... 10:03:50 INFO - Return code: 0 10:03:50 INFO - Running post-action listener: _resource_record_post_action 10:03:50 INFO - Running post-action listener: _start_resource_monitoring 10:03:50 INFO - Starting resource monitoring. 10:03:50 INFO - [mozharness: 2016-12-08 18:03:50.062400Z] Finished create-virtualenv step (success) 10:03:50 INFO - [mozharness: 2016-12-08 18:03:50.063381Z] Running install step. 10:03:50 INFO - Running pre-action listener: _resource_record_pre_action 10:03:50 INFO - Running main action method: install 10:03:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:03:50 INFO - Reading from file tmpfile_stdout 10:03:50 INFO - Detecting whether we're running mozinstall >=1.0... 10:03:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:03:50 INFO - Reading from file tmpfile_stdout 10:03:50 INFO - Output received: 10:03:50 INFO - Usage: mozinstall [options] installer 10:03:50 INFO - Options: 10:03:50 INFO - -h, --help show this help message and exit 10:03:50 INFO - -d DEST, --destination=DEST 10:03:50 INFO - Directory to install application into. [default: 10:03:50 INFO - "/builds/slave/test"] 10:03:50 INFO - --app=APP Application being installed. [default: firefox] 10:03:50 INFO - mkdir: /builds/slave/test/build/application 10:03:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:04:23 INFO - Reading from file tmpfile_stdout 10:04:23 INFO - Output received: 10:04:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:04:23 INFO - Running post-action listener: _resource_record_post_action 10:04:23 INFO - [mozharness: 2016-12-08 18:04:23.805777Z] Finished install step (success) 10:04:23 INFO - [mozharness: 2016-12-08 18:04:23.805956Z] Running run-tests step. 10:04:23 INFO - Running pre-action listener: _resource_record_pre_action 10:04:23 INFO - Running main action method: run_tests 10:04:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:23 INFO - 2016-12-08 10:04:23.820 screenresolution[1632:8135] starting screenresolution argv=screenresolution get 10:04:23 INFO - 2016-12-08 10:04:23.823 screenresolution[1632:8135] Display 0: 1600x1200x32@60 10:04:23 INFO - 2016-12-08 10:04:23.829 screenresolution[1633:8137] starting screenresolution argv=screenresolution list 10:04:23 INFO - Available Modes on Display 0 10:04:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:24 INFO - Intel Iris: 10:04:24 INFO - Chipset Model: Intel Iris 10:04:24 INFO - Type: GPU 10:04:24 INFO - Bus: Built-In 10:04:24 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:24 INFO - Vendor: Intel (0x8086) 10:04:24 INFO - Device ID: 0x0a2e 10:04:24 INFO - Revision ID: 0x0009 10:04:24 INFO - Displays: 10:04:24 INFO - Display: 10:04:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:24 INFO - Display Serial Number: 1600x1200 60 10:04:24 INFO - Main Display: Yes 10:04:24 INFO - Mirror: Off 10:04:24 INFO - Online: Yes 10:04:24 INFO - Rotation: Supported 10:04:24 INFO - Return code: 0 10:04:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:04:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:04:24 INFO - Python 2.7.3 10:04:24 INFO - Return code: 0 10:04:24 INFO - grabbing minidump binary from tooltool 10:04: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')]} 10:04:24 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 0x1045e4d30>, '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 0x10444bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045dcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:04:24 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 10:04:24 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 10:04:24 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 10:04:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:04:24 INFO - Return code: 0 10:04:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:04:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:04:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:04:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:04:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:04:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 10:04:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0325 --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 10:04:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KG7bunfc4h/Render', 10:04:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:24 INFO - 'HOME': '/Users/cltbld', 10:04:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:24 INFO - 'LOGNAME': 'cltbld', 10:04:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:04:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:04:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:24 INFO - 'MOZ_NO_REMOTE': '1', 10:04:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:04:24 INFO - 'NO_EM_RESTART': '1', 10:04:24 INFO - 'PAGER': '/bin/cat', 10:04:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:24 INFO - 'PWD': '/builds/slave/test', 10:04:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:04:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:24 INFO - 'SHELL': '/bin/bash', 10:04:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.szeguNZ5tC/Listeners', 10:04:24 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:04:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:24 INFO - 'USER': 'cltbld', 10:04:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:24 INFO - 'XPC_FLAGS': '0x0', 10:04:24 INFO - 'XPC_SERVICE_NAME': '0', 10:04:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 10:04:24 INFO - mozversion application_buildid: 20161208071558 10:04:24 INFO - mozversion application_changeset: 64e024fe44719575aa56c432e059ca065071ac95 10:04:24 INFO - mozversion application_display_name: Nightly 10:04:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:04:24 INFO - mozversion application_name: Firefox 10:04:24 INFO - mozversion application_remotingname: firefox 10:04:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:04:24 INFO - mozversion application_vendor: Mozilla 10:04:24 INFO - mozversion application_version: 53.0a1 10:04:24 INFO - mozversion platform_buildid: 20161208071558 10:04:24 INFO - mozversion platform_changeset: 64e024fe44719575aa56c432e059ca065071ac95 10:04:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:04:24 INFO - mozversion platform_version: 53.0a1 10:04:24 INFO - using testdate: 1481220264 10:04:24 INFO - actual date: 1481220264 10:04:24 INFO - starting webserver on 'localhost:49241' 10:04:24 INFO - SUITE-START | Running 2 tests 10:04:24 INFO - TEST-START | dromaeo_css 10:04:24 INFO - operating with platform_type : mac_ 10:04:24 INFO - Initialising browser for dromaeo_css test... 10:04:24 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpaDljPC/profile http://localhost:49241/getInfo.html) 10:04:27 INFO - PROCESS | 1643 | 2016-12-08 10:04:27.459 plugin-container[1645:8316] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 10:04:27 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:04:27 INFO - PROCESS | 1643 | 2016-12-08 10:04:27.462 plugin-container[1645:8316] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 10:04:27 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:04:27 INFO - PROCESS | 1643 | 2016-12-08 10:04:27.462 plugin-container[1645:8316] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:04:27 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 10:04:27 INFO - PROCESS | 1643 | colorDepth:24 10:04:27 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 10:04:27 INFO - PROCESS | 1643 | __metrics 10:04:27 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 10:04:28 INFO - TEST-INFO | 1643: exit 0 10:04:28 INFO - Browser initialized. 10:04:28 INFO - Running cycle 1/1 for dromaeo_css test... 10:04:28 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpaDljPC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:04:30 INFO - PROCESS | 1647 | 2016-12-08 10:04:30.843 plugin-container[1648:8447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:04:30 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:04:30 INFO - PROCESS | 1647 | 2016-12-08 10:04:30.844 plugin-container[1648:8447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:04:30 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:04:30 INFO - PROCESS | 1647 | 2016-12-08 10:04:30.844 plugin-container[1648:8447] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:04:40 INFO - PROCESS | 1647 | RSS: Main: 240975872 10:04:40 INFO - PROCESS | 1647 | 10:05:30 INFO - PROCESS | 1647 | 1481220330199 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:07:43 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 10:07:44 INFO - PROCESS | 1647 | RSS: Main: 254197760 10:07:44 INFO - PROCESS | 1647 | 10:10:47 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:10:47 INFO - PROCESS | 1647 | RSS: Main: 265846784 10:10:47 INFO - PROCESS | 1647 | 10:13:49 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:13:50 INFO - PROCESS | 1647 | RSS: Main: 271429632 10:13:50 INFO - PROCESS | 1647 | 10:16:52 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:16:52 INFO - PROCESS | 1647 | RSS: Main: 271863808 10:16:52 INFO - PROCESS | 1647 | 10:19:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:19:56 INFO - PROCESS | 1647 | RSS: Main: 276402176 10:19:56 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:22:59 INFO - PROCESS | 1647 | RSS: Main: 278925312 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | __start_tp_report 10:22:59 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 10:22:59 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 10:22:59 INFO - PROCESS | 1647 | |i|pagename|runs| 10:22:59 INFO - PROCESS | 1647 | |0;dojo.html;2667.5998600209964;2996.8501574921247;3341.8663253469863;3342;3389.559357283553;5537.335519737627;5646.7176641167935;5659.292588426447;5685.602007859451;5724.112762521805;5604;5687.601867869251;5699.031164702;5760.7407666655;5795.739191736371;5135.486451354872;5166.302549155872;5193.935243275119;5199.480051994789;5259.263703081552;5165.018646457161;5243.344581927259;5246.265522826808;5264.578833693315;5316.574674026088;4468.285074388098;5079.161938280216;5111.4121875984365;5192.8961420771375;5250.947490525123;4343.522212556616;5051.06555287273;5129.0767661820855;5141.280220769096;5224.582033437354;5097.031564002865;5118.385793704742;5141.76862041209;5142.1772516397195;5158.690478571298;5113;5257.369115706101;5708.600397972102;5710.571707121941;5710.65736055838;6129.389354848367;6134.386561343875;6144.126041557323;6158.168647232611;6200.906986395207;6283.2460104787715;6295.150154729145;6296.716647750863;6309.5898766580485;6316;5794.130880367937;5802.796902108406;5803.970980145073;5816.88366232673;5877.089051197114;6116.792316687851;6231.719572619243;6247.0629405589525;6256.812295631138;6299.338569450233;5430.348358197041;5957.6127551709;6101.755929762849;6145.4161854623735;6165.136880836686;6238.344973777779;6245.18812554365;6254.21822272216;6259.843503912439;6269.059641053878;6063.272407311151;6101.85309287306;6123.1606109212;6123.357047509948;6138.602983404986;6219.782307619212;6247.437730604267;6250.218722659668;6261.279952805486;6264.310925798159;4865.099956508057;4898.14282500561;4906.435759887659;4907.993861258434;4922.458529561745;4360.825566977285;4380.69335146543;4384.386185933972;4414.028913638995;4451.710638808467;4866.8053277868485;5690.943090569124;5800.390958949334;5843;5885.823425297248;6227.564070515111;6271.717772700239;6293.653849038255;6323.30443651198;6330.335314791972;5718.685472299064;5720.599558030981;5850.678212698257;5854.736536855852;5869.383714709894;5337.466253374631;5361.249425080492;5366.195070739421;5366.651167674167;5376.542993845567;6234.625922444668;6300.432961033437;6306.590071645237;6311.495080393465;6321.273053598803;5625.971870140623;6113.9932453606225;6224.844378890382;6228.626282423069;6242.157308763441;5146.639735218649;5149.562287205629;5473.863153421196;6300.779472718433;6305.243370795534;6101.2068431105445;6111.358307377662;6121.816345509642;6130.111133885473;6142.969285153546;4691.648126390603;5091.821786237464;6124.265088189446;6150.600210986271;6238.438540531374;6136.6011209271255;6150.323464419;6150.846228844314;6156.630602163885;6165.414285642857;6077.513798896166;6107.114468401968;6117.1130186123655;6119.663418511846;6164.568480206522;5125.8466844959585;5566.91649625264;5640.266765320646;5665.830025099253;5811.244538210175;6111.19673459643;6119.472088496418;6138.725610151984;6164.8767024658355;6178.073289006685;6017.789377371771;6018.428249316308;6034.24571928509;6039.547033972557;6064.090386442069 10:22:59 INFO - PROCESS | 1647 | |1;ext.html;426.73968878983817;467.9681302727486;505.94005558355235;507.5381402923338;528.2340606121126;39768.80115599422;41940.16119677605;41972.790136049356;42033.7898310508;42127.57872421275;39746.8012659937;40050;40214.396784048266;40230;40297.395539066936;9269.721908342744;9440.669576564822;9458.905410945905;9459.905400946005;11020.063294619968;11121.443927803617;11391.886081139166;11413;11421.25761825483;11428.942855285712;11019.732730735948;11346.375949322784;11399.202055856093;11402;11407.201495895291;9946.403215807039;11315.264507806965;11316.434178291052;11327;11330.093592512661;11117.499712512956;11139.777204455866;11181.385023823686;11215.719607009809;11225.663230103028;38838;39188.21623567541;39191.60808391908;39318;39391;3276.852541635632;3333.933321333584;3337.766356355056;3397.7451691123265;3696.8336424860677;29167.854160729272;29865.70134298651;29926.55110173349;29933.850330748206;30124.54813177804;3094.633720699627;3211.566438530815;3213.6143662760383;3257.1042963185077;3314.4365457871977;418875;420666;422387.8880605608;422703;422886;58413.70793146092;58643.70678146582;58691;58722.825543489744;58826;61548.69225653843;61855;61881.69059154765;62012;62111;15505.767413488808;15738.9213053934;15745.68508629844;15750.76373854393;15786.447474338343;11023.559057637605;11236.887631123747;12096.697582560506;12122.939385303018;12325.075619328587;16922.831095583326;18768.343107991153;19325.226990920204;19431.4170574883;19549.804501955085;6616.702248398834;7713.873077903295;7921.168277330912;7931.167227441151;8027.036755589366;834.8246868157634;903.408267584733;917.9541022948825;1047.2981405216524;1099.5931505343074;24190.758092419204;25561.616575751377;26136.607950880752;29743;31454.192826008755;965.8792650918635;982.3565564555228;1002.5087707023506;1089.4607169451172;1182.7752726981846;14709.92645036768;14711.337989790485;15279.236038198045;15357.078575285519;15627.296771645304;623048;624838;626169;626779;628943;55865.72067139638;56257.718711407;56342;56367.71816140893;56424.43575564274;1214.7366738591766;1275.1074248026418;1332.194022616303;1333.080174679468;1356.3015047250674;24831.627525587493;28110;28268.30390176596;28895.133146005654;29783.404331913673;48930.75534622304;49089.509104908495;49193.75403122961;49427.76430589264;49461.50538494569;536658;541422;543609;544314;546478;34254.972350829514;34960.82519587487;40473;40852.59147408488;41152;43750.562494375925;44635.33047004363;45316.09367812691;47183.764081179375;47933.5206647943;29134.56298155572;37084;37219.511219552;37331.81334093312;38468.61531384762;35766.64233357633;37339;37416.2516749658;38168.61831381651;38459;36518.26963460769;37049.44425833668;37146.8142659285;37370;37464.438033428974;32084;32911.835440822644;33674.98975030753;33831;34219.80230691914;20473.69289460689;20615.484612884797;21982.230621928156;25016.62475062839;25350.74649253484 10:22:59 INFO - PROCESS | 1647 | |2;jquery.html;367.30395899769934;375.6787946305908;380.6440977685863;383.2603076063197;397.72755662371276;18494.075296235183;18651.90674046631;18916.71624925627;19043.428697139072;19110.331138410158;18248.99630520324;18453.907730461327;18736.531586710338;18928.958907260094;19026.90486547565;4301.827926882921;5714.771409143629;6010.098485227207;6010.699465026732;6013.609115407507;6458.321876203002;6678.465722742193;6687.164104486939;6690.7323707051655;6699.732010719566;6348.111264422984;6512.93487065128;6556.573822701533;6613.4709223262025;6615.834604134911;5693.971530142364;6457.935420645804;6467.870642587122;6473.96416573346;6509.707063182168;5532.336119665666;5659.094544872801;5690.40250773667;5697.088465845486;5709.286339207599;16023.519294421187;22728.88635556828;23258.3022509325;23301.300960971028;25246.747532524623;2313.051648823974;2345.4253707841517;2458.4222707663685;2643;2726.645536080297;14139.575812725634;14311;14355.56933292003;14612.19632920189;14633.41466341345;1780.6349698311815;1826.8081851405543;1833.2941817400263;1847.1318480314364;1847.464235371754;96609.5169524148;97941;99801.5009924953;101001;102100;10317;10353.275270730977;10414.947925260325;10449.68650940473;10737.946310268399;13359.666008349868;13375.130616509896;13378.331083445983;13390;13460.326983650779;7417.03578534787;7819.6481158348015;8087.110417854032;8125.3499720022255;8227.753167404988;7994.840103197904;8049.356051515864;8051.879221811678;8120.431569790058;8130;5403.432639572867;6238;6294.716737746812;6467.902981455282;6515.902261466082;1835.8067256283523;1839.691187559604;1840.6379279333196;1841.3463220556778;1843.6128413033146;843.2200214801285;925.9490728009889;946.4463288975888;984.4241118945354;992.7865508915473;15788;20325.288614898407;20622.690659640117;20867.1653133876;20870.650709360376;832.8964122537661;868.3574155125289;900.8243392538392;965.3869792681697;966.4201479112617;10667.519961601747;10943.890561094446;11061.778764424667;11071.5571377144;11078.390688512209;495985;501495;505673;507149;510167.89832101204;86564.56717716496;102880;105560.47219764006;105689.47155264328;113187.43406283081;942.5098948546854;948.7485816258697;985.4038306824411;986.1026465915838;991.9702408927744;18263.178156983235;18264.90867545707;18644.34744783926;18679.81320186835;18951.336703215322;37721;49471.75264123656;50497.24254136117;51098;51199.74400127975;48389.51610483991;69335.65332173307;70764;71691;72078;6483.740650374121;7134.108236470442;7216.134063912364;7268.745593904188;8115.959420202861;11713.88286117128;11867;11917.642470725892;12209.694757630776;12278.263304201777;10280.2803803736;10433.26967112295;10655.30740501865;10683.198760093179;10693.625723099654;8803.647854085764;9214.2628589714;9611.567479463301;10035.09684128432;10365.9481702591;9454.763630909283;10514.26400151982;10544;10980.176486763685;11003.669889903316;7691.15397306307;7726.652300646597;7883;7888.48724832884;8133.593320334078;7966.16355282687;9650.324477286522;10259.58961641526;10349.948250259002;10640.04239618438 10:22:59 INFO - PROCESS | 1647 | |3;mootools.html;1320.828292321998;1349.5344106283335;1424.9668989982258;1461.9780703289453;1626.8047834259892;7427.702891884332;7671.194524574923;7948.523088614687;8380.832383352337;8404.117567655392;7932.206779322065;8045.436819422643;8121.30968867647;8268.37987150963;8303.75088747337;2241.0923575951724;2269.795718385345;2289.542091581682;2293.289080385086;2295.334353037617;2057.763357213917;2137.4335801012744;2275.1240772302617;2290.6449500327476;2329.9767002330013;2198.120751699317;2276.4422716434415;2278.931632051041;2280.8631482111127;2281.9885900570557;2259.378670865509;2268.3988742983042;2287.885605719707;2288;2294.8393612447135;2282.6119559674894;2283.5318759654397;2302.9309120726407;2307.169419009172;2311.6763653088583;8276.08963014068;8422.989241291023;8425.988881334219;8436.114208008132;8452.112528184514;4650.325702773079;5026.472220416877;5182.093133701587;5185.222216667492;5204.739763011834;2923.7660987121194;3180.9204769880707;3421.7091547218515;3482.04243832948;3513.929721405583;988.619381538113;1027.2552399510296;1031.2626472072504;1058.6929790360864;1074.5379486820664;10374.118199953013;10390.012948769854;10465.84301235482;10477.423741694203;10496.790064198673;4405.933910991338;4579.977100114478;4686.882827929261;4689.906201875943;4699.483056863743;9360.297977651704;9437.056294370645;9437.292203084797;9453.243740500744;9463.242940564738;5170.870728231749;5173.689578625294;5175.663581867167;5176.352955880515;5194.92207616886;4549.385832912514;4632.2125238709505;4679.508651591602;4730.124926888537;4744.501827308152;3727.2918145552258;3774.8112594370173;3783.7729736215915;3794.1842503861803;3865.9420108697827;1168.094726586885;1205.4756181061266;1253.4610117649315;1255.4476030546714;1270.7522033203622;746.8543633991319;796.7649543384715;871.6818361298207;872.9083446238053;902.1564836877541;5498.532624726943;5559.833205003856;5577.9163312550345;5619.550435965195;5638.04153293941;891.2691592893894;899.2446345070173;902.1114202510522;911;932.7248461703814;3725.9254814904093;3731.626837316301;3753.324401607737;3818.7517811342727;3824.2160357126722;9575.329726919048;10108.292419530562;10112.24158188124;10121.443320617289;10137.442440665835;10059.346142500714;10070.59717611302;10099.697009089592;10117.949410252902;10257.897421025695;7504.437167212481;7592.658330375147;7716.459847810599;7786.532808031649;7835;5366.114591092426;5475.780968761205;5476.644018138808;5487.341519017744;5579.525740312119;9281.211097056555;9311.301652375963;9317.254619630548;9339.159475647224;9349.298802589698;9217.400868943498;9423.38747981379;9528.142467177988;9550.61797528119;9590.184834289164;6934.791956241321;7010.684519196547;7011.123609548807;7024.578525288499;7032.6132062735;6600.636964966973;6830.726770929107;7519.323260906326;7693.153753087044;7839.09850367215;7250.818729531593;7283.271672832674;7306.817329566803;7364.116306043309;7435.8884616731875;6068.848278793065;7174.820629484305;7254.709811607688;7299.744508942162;7334.413246940126;6475.190601174853;6540.182477190351;6640.933590664032;6643.136392268974;6655.267920528641;3873.8450461981206;3989.9676246052627;4873.1959226727195;4877.512248775094;5042.647014708935;4925.3597032387;5042.470540593332;5078.771455284447;5091.1854103343285;5173.7671804769625 10:22:59 INFO - PROCESS | 1647 | |4;prototype.html;295.52568128154314;307.0496812365728;309.10514061791116;315.4432426766757;315.9778815482914;5020.694619398955;5440.564754819615;5455.581548797311;5467.589930755189;5488.91766623501;5388.515033646971;5405.459454054592;5408.35099788025;5418.83743487695;5421.322334708161;3473.026394601288;3840.8271627776817;3994.1612261425134;4036.2129384769983;4085.387191921206;3907.433422153786;4074.3684728867074;4089.26393249215;4112.97943510282;4141.855035073773;3737.19710217337;4057.6550993165615;4068.3490641497365;4069.369247766601;4091.9386209206887;3348.983255083709;3559.911002224952;3764.7929363884973;3837.6929845612285;4046.9797651011704;2894.176668698706;2896.2904088498453;3358.7648864579487;3517.402041652925;3525.347810655037;4698.08387364464;5292.788288468457;5300.575953923677;5345.278387417688;5370.409254981949;3709.9055778545126;4056.9797151014054;4301.720388174758;4447.310666846644;4682;4950.6782059166035;5619.971900140514;5683.715814209314;5690.60165788395;5710.628809127394;3855.5951625079524;3855.7879316637573;3868.8065596720053;3872.850567934795;3888.766673999569;5240.475952404805;5266.94661067788;5359.38367087786;5729.598928075036;5759.539236861041;3663.8900832975055;3862.208247309351;3917.5103112110987;3917.9020524486546;3944.526656801145;3353.5137405076125;3544.858205671744;3989.2420440116284;4007.8396864125107;4009.0979529605606;4131.690123240769;4490.832383580293;4683.601893838993;4715.976420117878;4726.71639701619;4700.2009658358165;5404.351477822608;5603.887922241533;5816.389279125631;5846.561507886926;1077.3858900426837;1298.2729671383865;1299.8310219671382;1301.4403806363355;1305.262526672427;840.2647683277132;879.1120967822545;887.2476858205123;888.1207604471526;898.0077014898574;4338.783060846945;5180.7150606717;5311.946880531145;5473;5506.587005974568;5933.042096108274;5970.701464926737;5980.342162362137;5986.281646202484;5996.430339117776;4798.592119669867;4803.967147063398;5133.281340612317;5474.890502190014;5495.890082198333;2438.5854404751235;2780.7358300961378;2948.749356304695;2964.8962286319875;2965.7312156200524;5333.919991200135;5817.505512031525;5836.715922497044;5886.58793884424;5922.378150294243;5177.948220517822;5467.715086954562;5548.91676624851;5738.942610573924;5837.357890632027;5834.620749651259;5870.090136028923;5874.882502349844;5898.911516327258;5918.378570250062;5562.972185139049;5622.297212848394;5729.255196824386;5740.913886291793;5748.741306641127;5882.617629854046;5910.852228694145;5913;5919.319278283053;5963.2844058713235;5956.766212670249;6208.2860471044605;6217.440430361289;6231.53263505248;6243.125962365181;3309.9503507446925;3760.025986357184;3857.96142038576;3910.472086268431;3960.4950118955767;5424.186372044225;5916.73374698131;5966.970165149147;6013.548983826143;6052.701277803696;5112.284280200849;5431.619786614899;5848.56135789826;5857.853553661193;5874.353821079763;4344.978275108604;4842.709437433765;5243.610443232623;5722.971385143048;5772.913406298993;5664.37691853904;5669.7165141741925;5675.602707810579;5682;5686.696887760464;4696.530346965276;5493.203485494501;5531.363893152339;5689.54483641316;5733.913991290217;4485.0581377911;5012.9697472523685;5627.803026894039;5668.858278543069;5677.77288908439 10:22:59 INFO - PROCESS | 1647 | |5;yui.html;684.6097724297151;685.1162001018686;703.9296070392961;737.7971057959064;749.2095838889973;14258.001939864214;15228.467003654874;15406.229688515585;15493.147876866768;15495.147766872817;13725.313734313295;13920.860791392082;13948.790768138486;13953.511627093054;13959;661.9966900165492;680.7311112110714;817.480899628734;820.3601191070959;838.2037064788475;828.0270681948716;833.5082301442147;844.5692696724653;850.5279569838737;859.6217664227751;901.7475106970059;903.8599017152352;904.0868722590199;904.891413030434;906.755176102452;865.3077537969599;878.565110270417;892.1613683137895;896.2158111652304;897.5422534507383;664.64773669955;667.092753854762;667.142721602743;668.6790340636474;669.0699927101333;14792;14888.62778430548;15023.173725445096;15175.696486070327;15247.23763811816;14437.35031923566;14664.193469359288;14725.484608038774;14788.778168327484;14913.701725965528;10773.5690572377;11116.666500004932;11340.659780206606;11545.422728863607;11918.940405298004;3222.548843161959;3222.6358897989576;3760.7179461540495;3797.9430308545393;3798.8860334190017;120732;122523;123649.1621005485;127264;127274;9265.583048762823;11114.777704445865;11123;11136.944315278533;11477.311361318347;23063.07747690107;23442.413939651644;23449.296521104396;23592.174273900673;23753.931073101405;7742.690292388242;7763.650635721406;7844.137144914169;7906.604669766489;8113.594320283963;4120.773357465308;4244.384564238168;4244.72409293395;4318.460192475941;4342.413774140514;3529.400001999666;3584.1577229351083;3627.6916462101017;3628.8911332660064;3635.2184280379847;536.2010604199714;631.6336524815688;631.9652419116993;633.8700566383881;637.343536157749;554.7060058169177;586.6861229242335;646.1632186318728;651.2282944710533;667.6695035957135;9248.445093294424;9274.304427167854;9285.442873427615;9432.528373581294;9653.9034609653;522.4514260026956;586.61576667283;627.3976982097238;630.7382436288981;663.7444583835259;4283.31466965284;4415.40392047076;4588.894953604882;4687.367205427292;4717.9292310615365;105507.41738873922;107931.38102928462;113934;114905;116898.41550792192;95741;96330.03669963211;97090.0290997081;97186.5140674292;98355;87754;88415.55792221126;89673.65489517663;90741;93153;8781.311849023554;8971.551422428722;8987.685431009882;9061.68284110053;9181.49501777382;54001.72999134979;54515.72742136264;56477.71761141168;56745.43254567401;56756.71621641865;73686;78996.60501697454;83013.16986830054;83110.58444707979;86886;10661.413622250851;10963.456802408275;11352.318860868374;12173.939130304292;12598.118131730691;14451.49419770303;14667.779983300046;14781;14855.257237137883;14972.47596334123;13414.731705365642;13568.287204211512;13796.586102416944;13914.443422262995;14302.284885755878;13209.867901320864;13578.3522609136;13649;13991.370388332753;14383;12713.682157945756;13183.209007459584;13192.934035329761;13224.867751322363;13456.192628442324;10514.684559463229;11094.667159985212;11200.551977920792;11260.930211629882;11280.195168772956;9395.015663347798;11192.720181995515;11518.905703958111;11564.306141631192;11588.420578970849 10:22:59 INFO - PROCESS | 1647 | __end_tp_report 10:22:59 INFO - PROCESS | 1647 | __start_cc_report 10:22:59 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,361 10:22:59 INFO - PROCESS | 1647 | __end_cc_report 10:22:59 INFO - PROCESS | 1647 | __startTimestamp1481221379430__endTimestamp 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | ------- Summary: start ------- 10:22:59 INFO - PROCESS | 1647 | Number of tests: 6 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5662.63 Median:5850.68 stddev:688.85 (11.8%) stddev-sans-first:649.66 10:22:59 INFO - PROCESS | 1647 | Values: 2667.6 2996.9 3341.9 3342.0 3389.6 5537.3 5646.7 5659.3 5685.6 5724.1 5604.0 5687.6 5699.0 5760.7 5795.7 5135.5 5166.3 5193.9 5199.5 5259.3 5165.0 5243.3 5246.3 5264.6 5316.6 4468.3 5079.2 5111.4 5192.9 5250.9 4343.5 5051.1 5129.1 5141.3 5224.6 5097.0 5118.4 5141.8 5142.2 5158.7 5113.0 5257.4 5708.6 5710.6 5710.7 6129.4 6134.4 6144.1 6158.2 6200.9 6283.2 6295.2 6296.7 6309.6 6316.0 5794.1 5802.8 5804.0 5816.9 5877.1 6116.8 6231.7 6247.1 6256.8 6299.3 5430.3 5957.6 6101.8 6145.4 6165.1 6238.3 6245.2 6254.2 6259.8 6269.1 6063.3 6101.9 6123.2 6123.4 6138.6 6219.8 6247.4 6250.2 6261.3 6264.3 4865.1 4898.1 4906.4 4908.0 4922.5 4360.8 4380.7 4384.4 4414.0 4451.7 4866.8 5690.9 5800.4 5843.0 5885.8 6227.6 6271.7 6293.7 6323.3 6330.3 5718.7 5720.6 5850.7 5854.7 5869.4 5337.5 5361.2 5366.2 5366.7 5376.5 6234.6 6300.4 6306.6 6311.5 6321.3 5626.0 6114.0 6224.8 6228.6 6242.2 5146.6 5149.6 5473.9 6300.8 6305.2 6101.2 6111.4 6121.8 6130.1 6143.0 4691.6 5091.8 6124.3 6150.6 6238.4 6136.6 6150.3 6150.8 6156.6 6165.4 6077.5 6107.1 6117.1 6119.7 6164.6 5125.8 5566.9 5640.3 5665.8 5811.2 6111.2 6119.5 6138.7 6164.9 6178.1 6017.8 6018.4 6034.2 6039.5 6064.1 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:66851.13 Median:37066.72 stddev:143189.70 (386.3%) stddev-sans-first:143504.54 10:22:59 INFO - PROCESS | 1647 | Values: 426.7 468.0 505.9 507.5 528.2 39768.8 41940.2 41972.8 42033.8 42127.6 39746.8 40050.0 40214.4 40230.0 40297.4 9269.7 9440.7 9458.9 9459.9 11020.1 11121.4 11391.9 11413.0 11421.3 11428.9 11019.7 11346.4 11399.2 11402.0 11407.2 9946.4 11315.3 11316.4 11327.0 11330.1 11117.5 11139.8 11181.4 11215.7 11225.7 38838.0 39188.2 39191.6 39318.0 39391.0 3276.9 3333.9 3337.8 3397.7 3696.8 29167.9 29865.7 29926.6 29933.9 30124.5 3094.6 3211.6 3213.6 3257.1 3314.4 418875.0 420666.0 422387.9 422703.0 422886.0 58413.7 58643.7 58691.0 58722.8 58826.0 61548.7 61855.0 61881.7 62012.0 62111.0 15505.8 15738.9 15745.7 15750.8 15786.4 11023.6 11236.9 12096.7 12122.9 12325.1 16922.8 18768.3 19325.2 19431.4 19549.8 6616.7 7713.9 7921.2 7931.2 8027.0 834.8 903.4 918.0 1047.3 1099.6 24190.8 25561.6 26136.6 29743.0 31454.2 965.9 982.4 1002.5 1089.5 1182.8 14709.9 14711.3 15279.2 15357.1 15627.3 623048.0 624838.0 626169.0 626779.0 628943.0 55865.7 56257.7 56342.0 56367.7 56424.4 1214.7 1275.1 1332.2 1333.1 1356.3 24831.6 28110.0 28268.3 28895.1 29783.4 48930.8 49089.5 49193.8 49427.8 49461.5 536658.0 541422.0 543609.0 544314.0 546478.0 34255.0 34960.8 40473.0 40852.6 41152.0 43750.6 44635.3 45316.1 47183.8 47933.5 29134.6 37084.0 37219.5 37331.8 38468.6 35766.6 37339.0 37416.3 38168.6 38459.0 36518.3 37049.4 37146.8 37370.0 37464.4 32084.0 32911.8 33675.0 33831.0 34219.8 20473.7 20615.5 21982.2 25016.6 25350.7 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:30493.52 Median:2349.78 stddev:83837.84 (3567.9%) stddev-sans-first:84042.51 10:22:59 INFO - PROCESS | 1647 | Values: 367.3 375.7 380.6 383.3 397.7 18494.1 18651.9 18916.7 19043.4 19110.3 18249.0 18453.9 18736.5 18929.0 19026.9 4301.8 5714.8 6010.1 6010.7 6013.6 6458.3 6678.5 6687.2 6690.7 6699.7 6348.1 6512.9 6556.6 6613.5 6615.8 5694.0 6457.9 6467.9 6474.0 6509.7 5532.3 5659.1 5690.4 5697.1 5709.3 16023.5 22728.9 23258.3 23301.3 25246.7 2313.1 2345.4 2458.4 2643.0 2726.6 14139.6 14311.0 14355.6 14612.2 14633.4 1780.6 1826.8 1833.3 1847.1 1847.5 96609.5 97941.0 99801.5 101001.0 102100.0 10317.0 10353.3 10414.9 10449.7 10737.9 13359.7 13375.1 13378.3 13390.0 13460.3 7417.0 7819.6 8087.1 8125.3 8227.8 7994.8 8049.4 8051.9 8120.4 8130.0 5403.4 6238.0 6294.7 6467.9 6515.9 1835.8 1839.7 1840.6 1841.3 1843.6 843.2 925.9 946.4 984.4 992.8 15788.0 20325.3 20622.7 20867.2 20870.7 832.9 868.4 900.8 965.4 966.4 10667.5 10943.9 11061.8 11071.6 11078.4 495985.0 501495.0 505673.0 507149.0 510167.9 86564.6 102880.0 105560.5 105689.5 113187.4 942.5 948.7 985.4 986.1 992.0 18263.2 18264.9 18644.3 18679.8 18951.3 37721.0 49471.8 50497.2 51098.0 51199.7 48389.5 69335.7 70764.0 71691.0 72078.0 6483.7 7134.1 7216.1 7268.7 8116.0 11713.9 11867.0 11917.6 12209.7 12278.3 10280.3 10433.3 10655.3 10683.2 10693.6 8803.6 9214.3 9611.6 10035.1 10365.9 9454.8 10514.3 10544.0 10980.2 11003.7 7691.2 7726.7 7883.0 7888.5 8133.6 7966.2 9650.3 10259.6 10349.9 10640.0 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5389.64 Median:5173.73 stddev:2964.15 (57.3%) stddev-sans-first:2956.69 10:22:59 INFO - PROCESS | 1647 | Values: 1320.8 1349.5 1425.0 1462.0 1626.8 7427.7 7671.2 7948.5 8380.8 8404.1 7932.2 8045.4 8121.3 8268.4 8303.8 2241.1 2269.8 2289.5 2293.3 2295.3 2057.8 2137.4 2275.1 2290.6 2330.0 2198.1 2276.4 2278.9 2280.9 2282.0 2259.4 2268.4 2287.9 2288.0 2294.8 2282.6 2283.5 2302.9 2307.2 2311.7 8276.1 8423.0 8426.0 8436.1 8452.1 4650.3 5026.5 5182.1 5185.2 5204.7 2923.8 3180.9 3421.7 3482.0 3513.9 988.6 1027.3 1031.3 1058.7 1074.5 10374.1 10390.0 10465.8 10477.4 10496.8 4405.9 4580.0 4686.9 4689.9 4699.5 9360.3 9437.1 9437.3 9453.2 9463.2 5170.9 5173.7 5175.7 5176.4 5194.9 4549.4 4632.2 4679.5 4730.1 4744.5 3727.3 3774.8 3783.8 3794.2 3865.9 1168.1 1205.5 1253.5 1255.4 1270.8 746.9 796.8 871.7 872.9 902.2 5498.5 5559.8 5577.9 5619.6 5638.0 891.3 899.2 902.1 911.0 932.7 3725.9 3731.6 3753.3 3818.8 3824.2 9575.3 10108.3 10112.2 10121.4 10137.4 10059.3 10070.6 10099.7 10117.9 10257.9 7504.4 7592.7 7716.5 7786.5 7835.0 5366.1 5475.8 5476.6 5487.3 5579.5 9281.2 9311.3 9317.3 9339.2 9349.3 9217.4 9423.4 9528.1 9550.6 9590.2 6934.8 7010.7 7011.1 7024.6 7032.6 6600.6 6830.7 7519.3 7693.2 7839.1 7250.8 7283.3 7306.8 7364.1 7435.9 6068.8 7174.8 7254.7 7299.7 7334.4 6475.2 6540.2 6640.9 6643.1 6655.3 3873.8 3990.0 4873.2 4877.5 5042.6 4925.4 5042.5 5078.8 5091.2 5173.8 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4583.73 Median:5306.26 stddev:1459.68 (27.5%) stddev-sans-first:1427.85 10:22:59 INFO - PROCESS | 1647 | Values: 295.5 307.0 309.1 315.4 316.0 5020.7 5440.6 5455.6 5467.6 5488.9 5388.5 5405.5 5408.4 5418.8 5421.3 3473.0 3840.8 3994.2 4036.2 4085.4 3907.4 4074.4 4089.3 4113.0 4141.9 3737.2 4057.7 4068.3 4069.4 4091.9 3349.0 3559.9 3764.8 3837.7 4047.0 2894.2 2896.3 3358.8 3517.4 3525.3 4698.1 5292.8 5300.6 5345.3 5370.4 3709.9 4057.0 4301.7 4447.3 4682.0 4950.7 5620.0 5683.7 5690.6 5710.6 3855.6 3855.8 3868.8 3872.9 3888.8 5240.5 5266.9 5359.4 5729.6 5759.5 3663.9 3862.2 3917.5 3917.9 3944.5 3353.5 3544.9 3989.2 4007.8 4009.1 4131.7 4490.8 4683.6 4716.0 4726.7 4700.2 5404.4 5603.9 5816.4 5846.6 1077.4 1298.3 1299.8 1301.4 1305.3 840.3 879.1 887.2 888.1 898.0 4338.8 5180.7 5311.9 5473.0 5506.6 5933.0 5970.7 5980.3 5986.3 5996.4 4798.6 4804.0 5133.3 5474.9 5495.9 2438.6 2780.7 2948.7 2964.9 2965.7 5333.9 5817.5 5836.7 5886.6 5922.4 5177.9 5467.7 5548.9 5738.9 5837.4 5834.6 5870.1 5874.9 5898.9 5918.4 5563.0 5622.3 5729.3 5740.9 5748.7 5882.6 5910.9 5913.0 5919.3 5963.3 5956.8 6208.3 6217.4 6231.5 6243.1 3310.0 3760.0 3858.0 3910.5 3960.5 5424.2 5916.7 5967.0 6013.5 6052.7 5112.3 5431.6 5848.6 5857.9 5874.4 4345.0 4842.7 5243.6 5723.0 5772.9 5664.4 5669.7 5675.6 5682.0 5686.7 4696.5 5493.2 5531.4 5689.5 5733.9 4485.1 5013.0 5627.8 5668.9 5677.8 10:22:59 INFO - PROCESS | 1647 | 10:22:59 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:22284.36 Median:4264.02 stddev:33599.77 (788.0%) stddev-sans-first:33654.88 10:22:59 INFO - PROCESS | 1647 | Values: 684.6 685.1 703.9 737.8 749.2 14258.0 15228.5 15406.2 15493.1 15495.1 13725.3 13920.9 13948.8 13953.5 13959.0 662.0 680.7 817.5 820.4 838.2 828.0 833.5 844.6 850.5 859.6 901.7 903.9 904.1 904.9 906.8 865.3 878.6 892.2 896.2 897.5 664.6 667.1 667.1 668.7 669.1 14792.0 14888.6 15023.2 15175.7 15247.2 14437.4 14664.2 14725.5 14788.8 14913.7 10773.6 11116.7 11340.7 11545.4 11918.9 3222.5 3222.6 3760.7 3797.9 3798.9 120732.0 122523.0 123649.2 127264.0 127274.0 9265.6 11114.8 11123.0 11136.9 11477.3 23063.1 23442.4 23449.3 23592.2 23753.9 7742.7 7763.7 7844.1 7906.6 8113.6 4120.8 4244.4 4244.7 4318.5 4342.4 3529.4 3584.2 3627.7 3628.9 3635.2 536.2 631.6 632.0 633.9 637.3 554.7 586.7 646.2 651.2 667.7 9248.4 9274.3 9285.4 9432.5 9653.9 522.5 586.6 627.4 630.7 663.7 4283.3 4415.4 4588.9 4687.4 4717.9 105507.4 107931.4 113934.0 114905.0 116898.4 95741.0 96330.0 97090.0 97186.5 98355.0 87754.0 88415.6 89673.7 90741.0 93153.0 8781.3 8971.6 8987.7 9061.7 9181.5 54001.7 54515.7 56477.7 56745.4 56756.7 73686.0 78996.6 83013.2 83110.6 86886.0 10661.4 10963.5 11352.3 12173.9 12598.1 14451.5 14667.8 14781.0 14855.3 14972.5 13414.7 13568.3 13796.6 13914.4 14302.3 13209.9 13578.4 13649.0 13991.4 14383.0 12713.7 13183.2 13192.9 13224.9 13456.2 10514.7 11094.7 11200.6 11260.9 11280.2 9395.0 11192.7 11518.9 11564.3 11588.4 10:22:59 INFO - PROCESS | 1647 | -------- Summary: end -------- 10:22:59 INFO - PROCESS | 1647 | 10:23:00 INFO - TEST-INFO | 1647: exit 0 10:23:00 INFO - TEST-OK | dromaeo_css | took 1115646ms 10:23:00 INFO - TEST-START | kraken 10:23:00 INFO - operating with platform_type : mac_ 10:23:00 INFO - Initialising browser for kraken test... 10:23:00 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpXxVOOP/profile http://localhost:49241/getInfo.html) 10:23:03 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 10:23:03 INFO - PROCESS | 1657 | colorDepth:24 10:23:03 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 10:23:03 INFO - PROCESS | 1657 | __metrics 10:23:03 INFO - PROCESS | 1657 | 2016-12-08 10:23:03.245 plugin-container[1658:10745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:23:03 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:03 INFO - PROCESS | 1657 | 2016-12-08 10:23:03.249 plugin-container[1658:10745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 10:23:03 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:03 INFO - PROCESS | 1657 | 2016-12-08 10:23:03.249 plugin-container[1658:10745] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:23:03 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 10:23:04 INFO - TEST-INFO | 1657: exit 0 10:23:04 INFO - Browser initialized. 10:23:04 INFO - Running cycle 1/1 for kraken test... 10:23:04 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpXxVOOP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:23:06 INFO - PROCESS | 1659 | 2016-12-08 10:23:06.823 plugin-container[1660:10865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 10:23:06 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:06 INFO - PROCESS | 1659 | 2016-12-08 10:23:06.824 plugin-container[1660:10865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 10:23:06 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:06 INFO - PROCESS | 1659 | 2016-12-08 10:23:06.824 plugin-container[1660:10865] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:23:16 INFO - PROCESS | 1659 | RSS: Main: 241577984 10:23:16 INFO - PROCESS | 1659 | 10:23:19 INFO - PROCESS | 1659 | 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) 10:23:20 INFO - PROCESS | 1659 | RSS: Main: 253681664 10:23:20 INFO - PROCESS | 1659 | 10:23:23 INFO - PROCESS | 1659 | 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) 10:23:23 INFO - PROCESS | 1659 | RSS: Main: 261185536 10:23:23 INFO - PROCESS | 1659 | 10:23:27 INFO - PROCESS | 1659 | 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) 10:23:27 INFO - PROCESS | 1659 | RSS: Main: 263999488 10:23:27 INFO - PROCESS | 1659 | 10:23:29 INFO - PROCESS | 1659 | 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) 10:23:30 INFO - PROCESS | 1659 | RSS: Main: 265166848 10:23:30 INFO - PROCESS | 1659 | 10:23:32 INFO - PROCESS | 1659 | 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) 10:23:33 INFO - PROCESS | 1659 | RSS: Main: 265531392 10:23:33 INFO - PROCESS | 1659 | 10:23:36 INFO - PROCESS | 1659 | 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) 10:23:36 INFO - PROCESS | 1659 | RSS: Main: 271642624 10:23:36 INFO - PROCESS | 1659 | 10:23:40 INFO - PROCESS | 1659 | 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) 10:23:40 INFO - PROCESS | 1659 | RSS: Main: 270491648 10:23:40 INFO - PROCESS | 1659 | 10:23:44 INFO - PROCESS | 1659 | 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) 10:23:44 INFO - PROCESS | 1659 | RSS: Main: 270733312 10:23:44 INFO - PROCESS | 1659 | 10:23:46 INFO - PROCESS | 1659 | 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) 10:23:46 INFO - PROCESS | 1659 | RSS: Main: 269955072 10:23:46 INFO - PROCESS | 1659 | 10:23:48 INFO - PROCESS | 1659 | 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) 10:23:49 INFO - PROCESS | 1659 | RSS: Main: 271327232 10:23:49 INFO - PROCESS | 1659 | 10:23:51 INFO - PROCESS | 1659 | 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) 10:23:52 INFO - PROCESS | 1659 | RSS: Main: 271364096 10:23:52 INFO - PROCESS | 1659 | 10:23:55 INFO - PROCESS | 1659 | 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) 10:23:56 INFO - PROCESS | 1659 | RSS: Main: 271724544 10:23:56 INFO - PROCESS | 1659 | 10:23:59 INFO - PROCESS | 1659 | 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) 10:23:59 INFO - PROCESS | 1659 | RSS: Main: 271556608 10:23:59 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | 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) 10:24:01 INFO - PROCESS | 1659 | RSS: Main: 273678336 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | __start_tp_report 10:24:01 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 10:24:01 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 10:24:01 INFO - PROCESS | 1659 | |i|pagename|runs| 10:24:01 INFO - PROCESS | 1659 | |0;ai-astar;106;119;120;117;117;118;111;149;112;162 10:24:01 INFO - PROCESS | 1659 | |1;audio-beat-detection;151;118;114;151;114;115;117;115;139;149 10:24:01 INFO - PROCESS | 1659 | |2;audio-dft;205;193;249;277;226;201;194;196;192;200 10:24:01 INFO - PROCESS | 1659 | |3;audio-fft;82;58;97;102;87;88;59;62;73;60 10:24:01 INFO - PROCESS | 1659 | |4;audio-oscillator;84;73;83;72;71;72;83;69;76;84 10:24:01 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;138;133;97;96;107;86;110;129;139;85 10:24:01 INFO - PROCESS | 1659 | |6;imaging-darkroom;147;140;95;133;102;155;109;100;98;150 10:24:01 INFO - PROCESS | 1659 | |7;imaging-desaturate;145;140;92;96;91;122;129;92;110;142 10:24:01 INFO - PROCESS | 1659 | |8;json-parse-financial;59;57;56;57;58;59;59;57;57;58 10:24:01 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;76;71;67;80;74;74;69;87;111;107 10:24:01 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;85;88;88;89;90;91;93;109;89;89 10:24:01 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;155;168;136;143;147;148;149;157;193;165 10:24:01 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;175;158;175;174;177;175;175;175;182;205 10:24:01 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;70;68;67;68;68;67;68;69;67;68 10:24:01 INFO - PROCESS | 1659 | __end_tp_report 10:24:01 INFO - PROCESS | 1659 | __start_cc_report 10:24:01 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,930 10:24:01 INFO - PROCESS | 1659 | __end_cc_report 10:24:01 INFO - PROCESS | 1659 | __startTimestamp1481221441618__endTimestamp 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | ------- Summary: start ------- 10:24:01 INFO - PROCESS | 1659 | Number of tests: 14 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:123.10 Median:118.50 stddev:17.87 (15.1%) stddev-sans-first:17.85 10:24:01 INFO - PROCESS | 1659 | Values: 106.0 119.0 120.0 117.0 117.0 118.0 111.0 149.0 112.0 162.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:128.50 stddev:16.90 (13.2%) stddev-sans-first:15.80 10:24:01 INFO - PROCESS | 1659 | Values: 151.0 118.0 114.0 151.0 114.0 115.0 117.0 115.0 139.0 149.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:213.30 Median:203.00 stddev:28.73 (14.2%) stddev-sans-first:30.31 10:24:01 INFO - PROCESS | 1659 | Values: 205.0 193.0 249.0 277.0 226.0 201.0 194.0 196.0 192.0 200.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:76.80 Median:77.50 stddev:16.62 (21.4%) stddev-sans-first:17.52 10:24:01 INFO - PROCESS | 1659 | Values: 82.0 58.0 97.0 102.0 87.0 88.0 59.0 62.0 73.0 60.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:79.50 stddev:6.11 (7.7%) stddev-sans-first:5.88 10:24:01 INFO - PROCESS | 1659 | Values: 84.0 73.0 83.0 72.0 71.0 72.0 83.0 69.0 76.0 84.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:112.00 Median:112.00 stddev:21.21 (18.9%) stddev-sans-first:20.31 10:24:01 INFO - PROCESS | 1659 | Values: 138.0 133.0 97.0 96.0 107.0 86.0 110.0 129.0 139.0 85.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:122.90 Median:148.50 stddev:24.25 (16.3%) stddev-sans-first:24.10 10:24:01 INFO - PROCESS | 1659 | Values: 147.0 140.0 95.0 133.0 102.0 155.0 109.0 100.0 98.0 150.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:115.90 Median:118.00 stddev:22.39 (19.0%) stddev-sans-first:21.12 10:24:01 INFO - PROCESS | 1659 | Values: 145.0 140.0 92.0 96.0 91.0 122.0 129.0 92.0 110.0 142.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.00 stddev:1.06 (1.8%) stddev-sans-first:1.01 10:24:01 INFO - PROCESS | 1659 | Values: 59.0 57.0 56.0 57.0 58.0 59.0 59.0 57.0 57.0 58.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:74.00 stddev:15.54 (21.0%) stddev-sans-first:16.35 10:24:01 INFO - PROCESS | 1659 | Values: 76.0 71.0 67.0 80.0 74.0 74.0 69.0 87.0 111.0 107.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:89.00 stddev:6.62 (7.4%) stddev-sans-first:6.65 10:24:01 INFO - PROCESS | 1659 | Values: 85.0 88.0 88.0 89.0 90.0 91.0 93.0 109.0 89.0 89.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:156.00 stddev:16.19 (10.4%) stddev-sans-first:17.17 10:24:01 INFO - PROCESS | 1659 | Values: 155.0 168.0 136.0 143.0 147.0 148.0 149.0 157.0 193.0 165.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:175.00 stddev:11.54 (6.6%) stddev-sans-first:12.22 10:24:01 INFO - PROCESS | 1659 | Values: 175.0 158.0 175.0 174.0 177.0 175.0 175.0 175.0 182.0 205.0 10:24:01 INFO - PROCESS | 1659 | 10:24:01 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.94 (1.4%) stddev-sans-first:0.67 10:24:01 INFO - PROCESS | 1659 | Values: 70.0 68.0 67.0 68.0 68.0 67.0 68.0 69.0 67.0 68.0 10:24:01 INFO - PROCESS | 1659 | -------- Summary: end -------- 10:24:01 INFO - PROCESS | 1659 | 10:24:02 INFO - TEST-INFO | 1659: exit 0 10:24:02 INFO - TEST-OK | kraken | took 62551ms 10:24:02 INFO - SUITE-END | took 1178s 10:24:02 INFO - Completed test suite (00:19:38) 10:24:02 INFO - javascript benchmark 10:24:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7206.730891788699, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2667.5998600209964, 2996.8501574921247, 3341.8663253469863, 3342.0, 3389.559357283553, 5537.335519737627, 5646.7176641167935, 5659.292588426447, 5685.602007859451, 5724.112762521805, 5604.0, 5687.601867869251, 5699.031164702, 5760.7407666655, 5795.739191736371, 5135.486451354872, 5166.302549155872, 5193.935243275119, 5199.480051994789, 5259.263703081552, 5165.018646457161, 5243.344581927259, 5246.265522826808, 5264.578833693315, 5316.574674026088, 4468.285074388098, 5079.161938280216, 5111.4121875984365, 5192.8961420771375, 5250.947490525123, 4343.522212556616, 5051.06555287273, 5129.0767661820855, 5141.280220769096, 5224.582033437354, 5097.031564002865, 5118.385793704742, 5141.76862041209, 5142.1772516397195, 5158.690478571298, 5113.0, 5257.369115706101, 5708.600397972102, 5710.571707121941, 5710.65736055838, 6129.389354848367, 6134.386561343875, 6144.126041557323, 6158.168647232611, 6200.906986395207, 6283.2460104787715, 6295.150154729145, 6296.716647750863, 6309.5898766580485, 6316.0, 5794.130880367937, 5802.796902108406, 5803.970980145073, 5816.88366232673, 5877.089051197114, 6116.792316687851, 6231.719572619243, 6247.0629405589525, 6256.812295631138, 6299.338569450233, 5430.348358197041, 5957.6127551709, 6101.755929762849, 6145.4161854623735, 6165.136880836686, 6238.344973777779, 6245.18812554365, 6254.21822272216, 6259.843503912439, 6269.059641053878, 6063.272407311151, 6101.85309287306, 6123.1606109212, 6123.357047509948, 6138.602983404986, 6219.782307619212, 6247.437730604267, 6250.218722659668, 6261.279952805486, 6264.310925798159, 4865.099956508057, 4898.14282500561, 4906.435759887659, 4907.993861258434, 4922.458529561745, 4360.825566977285, 4380.69335146543, 4384.386185933972, 4414.028913638995, 4451.710638808467, 4866.8053277868485, 5690.943090569124, 5800.390958949334, 5843.0, 5885.823425297248, 6227.564070515111, 6271.717772700239, 6293.653849038255, 6323.30443651198, 6330.335314791972, 5718.685472299064, 5720.599558030981, 5850.678212698257, 5854.736536855852, 5869.383714709894, 5337.466253374631, 5361.249425080492, 5366.195070739421, 5366.651167674167, 5376.542993845567, 6234.625922444668, 6300.432961033437, 6306.590071645237, 6311.495080393465, 6321.273053598803, 5625.971870140623, 6113.9932453606225, 6224.844378890382, 6228.626282423069, 6242.157308763441, 5146.639735218649, 5149.562287205629, 5473.863153421196, 6300.779472718433, 6305.243370795534, 6101.2068431105445, 6111.358307377662, 6121.816345509642, 6130.111133885473, 6142.969285153546, 4691.648126390603, 5091.821786237464, 6124.265088189446, 6150.600210986271, 6238.438540531374, 6136.6011209271255, 6150.323464419, 6150.846228844314, 6156.630602163885, 6165.414285642857, 6077.513798896166, 6107.114468401968, 6117.1130186123655, 6119.663418511846, 6164.568480206522, 5125.8466844959585, 5566.91649625264, 5640.266765320646, 5665.830025099253, 5811.244538210175, 6111.19673459643, 6119.472088496418, 6138.725610151984, 6164.8767024658355, 6178.073289006685, 6017.789377371771, 6018.428249316308, 6034.24571928509, 6039.547033972557, 6064.090386442069], "value": 5616.612480692715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.73968878983817, 467.9681302727486, 505.94005558355235, 507.5381402923338, 528.2340606121126, 39768.80115599422, 41940.16119677605, 41972.790136049356, 42033.7898310508, 42127.57872421275, 39746.8012659937, 40050.0, 40214.396784048266, 40230.0, 40297.395539066936, 9269.721908342744, 9440.669576564822, 9458.905410945905, 9459.905400946005, 11020.063294619968, 11121.443927803617, 11391.886081139166, 11413.0, 11421.25761825483, 11428.942855285712, 11019.732730735948, 11346.375949322784, 11399.202055856093, 11402.0, 11407.201495895291, 9946.403215807039, 11315.264507806965, 11316.434178291052, 11327.0, 11330.093592512661, 11117.499712512956, 11139.777204455866, 11181.385023823686, 11215.719607009809, 11225.663230103028, 38838.0, 39188.21623567541, 39191.60808391908, 39318.0, 39391.0, 3276.852541635632, 3333.933321333584, 3337.766356355056, 3397.7451691123265, 3696.8336424860677, 29167.854160729272, 29865.70134298651, 29926.55110173349, 29933.850330748206, 30124.54813177804, 3094.633720699627, 3211.566438530815, 3213.6143662760383, 3257.1042963185077, 3314.4365457871977, 418875.0, 420666.0, 422387.8880605608, 422703.0, 422886.0, 58413.70793146092, 58643.70678146582, 58691.0, 58722.825543489744, 58826.0, 61548.69225653843, 61855.0, 61881.69059154765, 62012.0, 62111.0, 15505.767413488808, 15738.9213053934, 15745.68508629844, 15750.76373854393, 15786.447474338343, 11023.559057637605, 11236.887631123747, 12096.697582560506, 12122.939385303018, 12325.075619328587, 16922.831095583326, 18768.343107991153, 19325.226990920204, 19431.4170574883, 19549.804501955085, 6616.702248398834, 7713.873077903295, 7921.168277330912, 7931.167227441151, 8027.036755589366, 834.8246868157634, 903.408267584733, 917.9541022948825, 1047.2981405216524, 1099.5931505343074, 24190.758092419204, 25561.616575751377, 26136.607950880752, 29743.0, 31454.192826008755, 965.8792650918635, 982.3565564555228, 1002.5087707023506, 1089.4607169451172, 1182.7752726981846, 14709.92645036768, 14711.337989790485, 15279.236038198045, 15357.078575285519, 15627.296771645304, 623048.0, 624838.0, 626169.0, 626779.0, 628943.0, 55865.72067139638, 56257.718711407, 56342.0, 56367.71816140893, 56424.43575564274, 1214.7366738591766, 1275.1074248026418, 1332.194022616303, 1333.080174679468, 1356.3015047250674, 24831.627525587493, 28110.0, 28268.30390176596, 28895.133146005654, 29783.404331913673, 48930.75534622304, 49089.509104908495, 49193.75403122961, 49427.76430589264, 49461.50538494569, 536658.0, 541422.0, 543609.0, 544314.0, 546478.0, 34254.972350829514, 34960.82519587487, 40473.0, 40852.59147408488, 41152.0, 43750.562494375925, 44635.33047004363, 45316.09367812691, 47183.764081179375, 47933.5206647943, 29134.56298155572, 37084.0, 37219.511219552, 37331.81334093312, 38468.61531384762, 35766.64233357633, 37339.0, 37416.2516749658, 38168.61831381651, 38459.0, 36518.26963460769, 37049.44425833668, 37146.8142659285, 37370.0, 37464.438033428974, 32084.0, 32911.835440822644, 33674.98975030753, 33831.0, 34219.80230691914, 20473.69289460689, 20615.484612884797, 21982.230621928156, 25016.62475062839, 25350.74649253484], "value": 19941.13681157794, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.30395899769934, 375.6787946305908, 380.6440977685863, 383.2603076063197, 397.72755662371276, 18494.075296235183, 18651.90674046631, 18916.71624925627, 19043.428697139072, 19110.331138410158, 18248.99630520324, 18453.907730461327, 18736.531586710338, 18928.958907260094, 19026.90486547565, 4301.827926882921, 5714.771409143629, 6010.098485227207, 6010.699465026732, 6013.609115407507, 6458.321876203002, 6678.465722742193, 6687.164104486939, 6690.7323707051655, 6699.732010719566, 6348.111264422984, 6512.93487065128, 6556.573822701533, 6613.4709223262025, 6615.834604134911, 5693.971530142364, 6457.935420645804, 6467.870642587122, 6473.96416573346, 6509.707063182168, 5532.336119665666, 5659.094544872801, 5690.40250773667, 5697.088465845486, 5709.286339207599, 16023.519294421187, 22728.88635556828, 23258.3022509325, 23301.300960971028, 25246.747532524623, 2313.051648823974, 2345.4253707841517, 2458.4222707663685, 2643.0, 2726.645536080297, 14139.575812725634, 14311.0, 14355.56933292003, 14612.19632920189, 14633.41466341345, 1780.6349698311815, 1826.8081851405543, 1833.2941817400263, 1847.1318480314364, 1847.464235371754, 96609.5169524148, 97941.0, 99801.5009924953, 101001.0, 102100.0, 10317.0, 10353.275270730977, 10414.947925260325, 10449.68650940473, 10737.946310268399, 13359.666008349868, 13375.130616509896, 13378.331083445983, 13390.0, 13460.326983650779, 7417.03578534787, 7819.6481158348015, 8087.110417854032, 8125.3499720022255, 8227.753167404988, 7994.840103197904, 8049.356051515864, 8051.879221811678, 8120.431569790058, 8130.0, 5403.432639572867, 6238.0, 6294.716737746812, 6467.902981455282, 6515.902261466082, 1835.8067256283523, 1839.691187559604, 1840.6379279333196, 1841.3463220556778, 1843.6128413033146, 843.2200214801285, 925.9490728009889, 946.4463288975888, 984.4241118945354, 992.7865508915473, 15788.0, 20325.288614898407, 20622.690659640117, 20867.1653133876, 20870.650709360376, 832.8964122537661, 868.3574155125289, 900.8243392538392, 965.3869792681697, 966.4201479112617, 10667.519961601747, 10943.890561094446, 11061.778764424667, 11071.5571377144, 11078.390688512209, 495985.0, 501495.0, 505673.0, 507149.0, 510167.89832101204, 86564.56717716496, 102880.0, 105560.47219764006, 105689.47155264328, 113187.43406283081, 942.5098948546854, 948.7485816258697, 985.4038306824411, 986.1026465915838, 991.9702408927744, 18263.178156983235, 18264.90867545707, 18644.34744783926, 18679.81320186835, 18951.336703215322, 37721.0, 49471.75264123656, 50497.24254136117, 51098.0, 51199.74400127975, 48389.51610483991, 69335.65332173307, 70764.0, 71691.0, 72078.0, 6483.740650374121, 7134.108236470442, 7216.134063912364, 7268.745593904188, 8115.959420202861, 11713.88286117128, 11867.0, 11917.642470725892, 12209.694757630776, 12278.263304201777, 10280.2803803736, 10433.26967112295, 10655.30740501865, 10683.198760093179, 10693.625723099654, 8803.647854085764, 9214.2628589714, 9611.567479463301, 10035.09684128432, 10365.9481702591, 9454.763630909283, 10514.26400151982, 10544.0, 10980.176486763685, 11003.669889903316, 7691.15397306307, 7726.652300646597, 7883.0, 7888.48724832884, 8133.593320334078, 7966.16355282687, 9650.324477286522, 10259.58961641526, 10349.948250259002, 10640.04239618438], "value": 9278.097207036768, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.828292321998, 1349.5344106283335, 1424.9668989982258, 1461.9780703289453, 1626.8047834259892, 7427.702891884332, 7671.194524574923, 7948.523088614687, 8380.832383352337, 8404.117567655392, 7932.206779322065, 8045.436819422643, 8121.30968867647, 8268.37987150963, 8303.75088747337, 2241.0923575951724, 2269.795718385345, 2289.542091581682, 2293.289080385086, 2295.334353037617, 2057.763357213917, 2137.4335801012744, 2275.1240772302617, 2290.6449500327476, 2329.9767002330013, 2198.120751699317, 2276.4422716434415, 2278.931632051041, 2280.8631482111127, 2281.9885900570557, 2259.378670865509, 2268.3988742983042, 2287.885605719707, 2288.0, 2294.8393612447135, 2282.6119559674894, 2283.5318759654397, 2302.9309120726407, 2307.169419009172, 2311.6763653088583, 8276.08963014068, 8422.989241291023, 8425.988881334219, 8436.114208008132, 8452.112528184514, 4650.325702773079, 5026.472220416877, 5182.093133701587, 5185.222216667492, 5204.739763011834, 2923.7660987121194, 3180.9204769880707, 3421.7091547218515, 3482.04243832948, 3513.929721405583, 988.619381538113, 1027.2552399510296, 1031.2626472072504, 1058.6929790360864, 1074.5379486820664, 10374.118199953013, 10390.012948769854, 10465.84301235482, 10477.423741694203, 10496.790064198673, 4405.933910991338, 4579.977100114478, 4686.882827929261, 4689.906201875943, 4699.483056863743, 9360.297977651704, 9437.056294370645, 9437.292203084797, 9453.243740500744, 9463.242940564738, 5170.870728231749, 5173.689578625294, 5175.663581867167, 5176.352955880515, 5194.92207616886, 4549.385832912514, 4632.2125238709505, 4679.508651591602, 4730.124926888537, 4744.501827308152, 3727.2918145552258, 3774.8112594370173, 3783.7729736215915, 3794.1842503861803, 3865.9420108697827, 1168.094726586885, 1205.4756181061266, 1253.4610117649315, 1255.4476030546714, 1270.7522033203622, 746.8543633991319, 796.7649543384715, 871.6818361298207, 872.9083446238053, 902.1564836877541, 5498.532624726943, 5559.833205003856, 5577.9163312550345, 5619.550435965195, 5638.04153293941, 891.2691592893894, 899.2446345070173, 902.1114202510522, 911.0, 932.7248461703814, 3725.9254814904093, 3731.626837316301, 3753.324401607737, 3818.7517811342727, 3824.2160357126722, 9575.329726919048, 10108.292419530562, 10112.24158188124, 10121.443320617289, 10137.442440665835, 10059.346142500714, 10070.59717611302, 10099.697009089592, 10117.949410252902, 10257.897421025695, 7504.437167212481, 7592.658330375147, 7716.459847810599, 7786.532808031649, 7835.0, 5366.114591092426, 5475.780968761205, 5476.644018138808, 5487.341519017744, 5579.525740312119, 9281.211097056555, 9311.301652375963, 9317.254619630548, 9339.159475647224, 9349.298802589698, 9217.400868943498, 9423.38747981379, 9528.142467177988, 9550.61797528119, 9590.184834289164, 6934.791956241321, 7010.684519196547, 7011.123609548807, 7024.578525288499, 7032.6132062735, 6600.636964966973, 6830.726770929107, 7519.323260906326, 7693.153753087044, 7839.09850367215, 7250.818729531593, 7283.271672832674, 7306.817329566803, 7364.116306043309, 7435.8884616731875, 6068.848278793065, 7174.820629484305, 7254.709811607688, 7299.744508942162, 7334.413246940126, 6475.190601174853, 6540.182477190351, 6640.933590664032, 6643.136392268974, 6655.267920528641, 3873.8450461981206, 3989.9676246052627, 4873.1959226727195, 4877.512248775094, 5042.647014708935, 4925.3597032387, 5042.470540593332, 5078.771455284447, 5091.1854103343285, 5173.7671804769625], "value": 4353.445994209198, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.52568128154314, 307.0496812365728, 309.10514061791116, 315.4432426766757, 315.9778815482914, 5020.694619398955, 5440.564754819615, 5455.581548797311, 5467.589930755189, 5488.91766623501, 5388.515033646971, 5405.459454054592, 5408.35099788025, 5418.83743487695, 5421.322334708161, 3473.026394601288, 3840.8271627776817, 3994.1612261425134, 4036.2129384769983, 4085.387191921206, 3907.433422153786, 4074.3684728867074, 4089.26393249215, 4112.97943510282, 4141.855035073773, 3737.19710217337, 4057.6550993165615, 4068.3490641497365, 4069.369247766601, 4091.9386209206887, 3348.983255083709, 3559.911002224952, 3764.7929363884973, 3837.6929845612285, 4046.9797651011704, 2894.176668698706, 2896.2904088498453, 3358.7648864579487, 3517.402041652925, 3525.347810655037, 4698.08387364464, 5292.788288468457, 5300.575953923677, 5345.278387417688, 5370.409254981949, 3709.9055778545126, 4056.9797151014054, 4301.720388174758, 4447.310666846644, 4682.0, 4950.6782059166035, 5619.971900140514, 5683.715814209314, 5690.60165788395, 5710.628809127394, 3855.5951625079524, 3855.7879316637573, 3868.8065596720053, 3872.850567934795, 3888.766673999569, 5240.475952404805, 5266.94661067788, 5359.38367087786, 5729.598928075036, 5759.539236861041, 3663.8900832975055, 3862.208247309351, 3917.5103112110987, 3917.9020524486546, 3944.526656801145, 3353.5137405076125, 3544.858205671744, 3989.2420440116284, 4007.8396864125107, 4009.0979529605606, 4131.690123240769, 4490.832383580293, 4683.601893838993, 4715.976420117878, 4726.71639701619, 4700.2009658358165, 5404.351477822608, 5603.887922241533, 5816.389279125631, 5846.561507886926, 1077.3858900426837, 1298.2729671383865, 1299.8310219671382, 1301.4403806363355, 1305.262526672427, 840.2647683277132, 879.1120967822545, 887.2476858205123, 888.1207604471526, 898.0077014898574, 4338.783060846945, 5180.7150606717, 5311.946880531145, 5473.0, 5506.587005974568, 5933.042096108274, 5970.701464926737, 5980.342162362137, 5986.281646202484, 5996.430339117776, 4798.592119669867, 4803.967147063398, 5133.281340612317, 5474.890502190014, 5495.890082198333, 2438.5854404751235, 2780.7358300961378, 2948.749356304695, 2964.8962286319875, 2965.7312156200524, 5333.919991200135, 5817.505512031525, 5836.715922497044, 5886.58793884424, 5922.378150294243, 5177.948220517822, 5467.715086954562, 5548.91676624851, 5738.942610573924, 5837.357890632027, 5834.620749651259, 5870.090136028923, 5874.882502349844, 5898.911516327258, 5918.378570250062, 5562.972185139049, 5622.297212848394, 5729.255196824386, 5740.913886291793, 5748.741306641127, 5882.617629854046, 5910.852228694145, 5913.0, 5919.319278283053, 5963.2844058713235, 5956.766212670249, 6208.2860471044605, 6217.440430361289, 6231.53263505248, 6243.125962365181, 3309.9503507446925, 3760.025986357184, 3857.96142038576, 3910.472086268431, 3960.4950118955767, 5424.186372044225, 5916.73374698131, 5966.970165149147, 6013.548983826143, 6052.701277803696, 5112.284280200849, 5431.619786614899, 5848.56135789826, 5857.853553661193, 5874.353821079763, 4344.978275108604, 4842.709437433765, 5243.610443232623, 5722.971385143048, 5772.913406298993, 5664.37691853904, 5669.7165141741925, 5675.602707810579, 5682.0, 5686.696887760464, 4696.530346965276, 5493.203485494501, 5531.363893152339, 5689.54483641316, 5733.913991290217, 4485.0581377911, 5012.9697472523685, 5627.803026894039, 5668.858278543069, 5677.77288908439], "value": 4114.139772219426, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.6097724297151, 685.1162001018686, 703.9296070392961, 737.7971057959064, 749.2095838889973, 14258.001939864214, 15228.467003654874, 15406.229688515585, 15493.147876866768, 15495.147766872817, 13725.313734313295, 13920.860791392082, 13948.790768138486, 13953.511627093054, 13959.0, 661.9966900165492, 680.7311112110714, 817.480899628734, 820.3601191070959, 838.2037064788475, 828.0270681948716, 833.5082301442147, 844.5692696724653, 850.5279569838737, 859.6217664227751, 901.7475106970059, 903.8599017152352, 904.0868722590199, 904.891413030434, 906.755176102452, 865.3077537969599, 878.565110270417, 892.1613683137895, 896.2158111652304, 897.5422534507383, 664.64773669955, 667.092753854762, 667.142721602743, 668.6790340636474, 669.0699927101333, 14792.0, 14888.62778430548, 15023.173725445096, 15175.696486070327, 15247.23763811816, 14437.35031923566, 14664.193469359288, 14725.484608038774, 14788.778168327484, 14913.701725965528, 10773.5690572377, 11116.666500004932, 11340.659780206606, 11545.422728863607, 11918.940405298004, 3222.548843161959, 3222.6358897989576, 3760.7179461540495, 3797.9430308545393, 3798.8860334190017, 120732.0, 122523.0, 123649.1621005485, 127264.0, 127274.0, 9265.583048762823, 11114.777704445865, 11123.0, 11136.944315278533, 11477.311361318347, 23063.07747690107, 23442.413939651644, 23449.296521104396, 23592.174273900673, 23753.931073101405, 7742.690292388242, 7763.650635721406, 7844.137144914169, 7906.604669766489, 8113.594320283963, 4120.773357465308, 4244.384564238168, 4244.72409293395, 4318.460192475941, 4342.413774140514, 3529.400001999666, 3584.1577229351083, 3627.6916462101017, 3628.8911332660064, 3635.2184280379847, 536.2010604199714, 631.6336524815688, 631.9652419116993, 633.8700566383881, 637.343536157749, 554.7060058169177, 586.6861229242335, 646.1632186318728, 651.2282944710533, 667.6695035957135, 9248.445093294424, 9274.304427167854, 9285.442873427615, 9432.528373581294, 9653.9034609653, 522.4514260026956, 586.61576667283, 627.3976982097238, 630.7382436288981, 663.7444583835259, 4283.31466965284, 4415.40392047076, 4588.894953604882, 4687.367205427292, 4717.9292310615365, 105507.41738873922, 107931.38102928462, 113934.0, 114905.0, 116898.41550792192, 95741.0, 96330.03669963211, 97090.0290997081, 97186.5140674292, 98355.0, 87754.0, 88415.55792221126, 89673.65489517663, 90741.0, 93153.0, 8781.311849023554, 8971.551422428722, 8987.685431009882, 9061.68284110053, 9181.49501777382, 54001.72999134979, 54515.72742136264, 56477.71761141168, 56745.43254567401, 56756.71621641865, 73686.0, 78996.60501697454, 83013.16986830054, 83110.58444707979, 86886.0, 10661.413622250851, 10963.456802408275, 11352.318860868374, 12173.939130304292, 12598.118131730691, 14451.49419770303, 14667.779983300046, 14781.0, 14855.257237137883, 14972.47596334123, 13414.731705365642, 13568.287204211512, 13796.586102416944, 13914.443422262995, 14302.284885755878, 13209.867901320864, 13578.3522609136, 13649.0, 13991.370388332753, 14383.0, 12713.682157945756, 13183.209007459584, 13192.934035329761, 13224.867751322363, 13456.192628442324, 10514.684559463229, 11094.667159985212, 11200.551977920792, 11260.930211629882, 11280.195168772956, 9395.015663347798, 11192.720181995515, 11518.905703958111, 11564.306141631192, 11588.420578970849], "value": 7526.394692109812, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 120.0, 117.0, 117.0, 118.0, 111.0, 149.0, 112.0, 162.0], "value": 123.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 114.0, 151.0, 114.0, 115.0, 117.0, 115.0, 139.0, 149.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 193.0, 249.0, 277.0, 226.0, 201.0, 194.0, 196.0, 192.0, 200.0], "value": 213.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 97.0, 102.0, 87.0, 88.0, 59.0, 62.0, 73.0, 60.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 83.0, 72.0, 71.0, 72.0, 83.0, 69.0, 76.0, 84.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 133.0, 97.0, 96.0, 107.0, 86.0, 110.0, 129.0, 139.0, 85.0], "value": 112.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 140.0, 95.0, 133.0, 102.0, 155.0, 109.0, 100.0, 98.0, 150.0], "value": 122.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 140.0, 92.0, 96.0, 91.0, 122.0, 129.0, 92.0, 110.0, 142.0], "value": 115.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 56.0, 57.0, 58.0, 59.0, 59.0, 57.0, 57.0, 58.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 71.0, 67.0, 80.0, 74.0, 74.0, 69.0, 87.0, 111.0, 107.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 88.0, 89.0, 90.0, 91.0, 93.0, 109.0, 89.0, 89.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 168.0, 136.0, 143.0, 147.0, 148.0, 149.0, 157.0, 193.0, 165.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 158.0, 175.0, 174.0, 177.0, 175.0, 175.0, 175.0, 182.0, 205.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 69.0, 67.0, 68.0], "value": 68.0, "unit": "score"}]}]} 10:24:02 INFO - Return code: 0 10:24:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:24:02 ERROR - # TBPL SUCCESS # 10:24:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:24:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:24:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:24:02 INFO - 2016-12-08 10:24:02.973 screenresolution[1664:11269] starting screenresolution argv=screenresolution get 10:24:02 INFO - 2016-12-08 10:24:02.976 screenresolution[1664:11269] Display 0: 1600x1200x32@60 10:24:02 INFO - 2016-12-08 10:24:02.982 screenresolution[1665:11271] starting screenresolution argv=screenresolution list 10:24:02 INFO - Available Modes on Display 0 10:24:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:24:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:24:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:24:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:24:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:24:03 INFO - Intel Iris: 10:24:03 INFO - Chipset Model: Intel Iris 10:24:03 INFO - Type: GPU 10:24:03 INFO - Bus: Built-In 10:24:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:24:03 INFO - Vendor: Intel (0x8086) 10:24:03 INFO - Device ID: 0x0a2e 10:24:03 INFO - Revision ID: 0x0009 10:24:03 INFO - Displays: 10:24:03 INFO - Display: 10:24:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:24:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:24:03 INFO - Display Serial Number: 1600x1200 60 10:24:03 INFO - Main Display: Yes 10:24:03 INFO - Mirror: Off 10:24:03 INFO - Online: Yes 10:24:03 INFO - Rotation: Supported 10:24:03 INFO - Return code: 0 10:24:03 INFO - Running post-action listener: _resource_record_post_action 10:24:03 INFO - [mozharness: 2016-12-08 18:24:03.230104Z] Finished run-tests step (success) 10:24:03 INFO - Running post-run listener: _resource_record_post_run 10:24:03 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 33346048; Write bytes: 700403712; Read time: 208; Write time: 2022 10:24:03 INFO - TinderboxPrint: CPU usage
54.6% 10:24:03 INFO - TinderboxPrint: I/O read bytes / time
33,346,048 / 208 10:24:03 INFO - TinderboxPrint: I/O write bytes / time
700,403,712 / 2,022 10:24:03 INFO - TinderboxPrint: CPU idle
2,202.2 (45.4%) 10:24:03 INFO - TinderboxPrint: CPU system
982.4 (20.3%) 10:24:03 INFO - TinderboxPrint: CPU user
1,665.1 (34.3%) 10:24:03 INFO - TinderboxPrint: Swap in / out
414,101,504 / 0 10:24:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 368595456; Write bytes: 367461888; Read time: 28400; Write time: 753 10:24:03 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32391680; Write bytes: 312394240; Read time: 195; Write time: 1208 10:24:03 INFO - Running post-run listener: _upload_blobber_files 10:24:03 INFO - Blob upload gear active. 10:24:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:24:03 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 10:24:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:24:04 INFO - (blobuploader) - INFO - Open directory for files ... 10:24:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:24:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:24:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:04 INFO - (blobuploader) - INFO - Done attempting. 10:24:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:24:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:24:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:06 INFO - (blobuploader) - INFO - Done attempting. 10:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:24:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:24:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:09 INFO - (blobuploader) - INFO - Done attempting. 10:24:09 INFO - (blobuploader) - INFO - Iteration through files over. 10:24:09 INFO - Return code: 0 10:24:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:24:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:24:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e624f09a901c29a70a16cab6b006e2031893310f2cd9f992a1d149f7331ff7ac44f9cddef138453fd047cacc55e48d929cd8325e17941e6255baf3328fdc3f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f02c171ebf3c2c06dbe4ec956f8af0cf84f2117146f7c408f79bdcf1b3b49502ee21787f4c29eab65c0dd2b2b50dc3e1ddcb853d5a1c4490cfe3d714bbec6a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8c244884a4d31b9fbc8707827f16fead4a3db7763f371d9b63eea4d93e31da528900f68faa7ffcbc609b374a138438fb58a4b1ea01fb72be82a8f6a24d6a9b"} 10:24:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:24:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:24:09 INFO - Contents: 10:24:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e624f09a901c29a70a16cab6b006e2031893310f2cd9f992a1d149f7331ff7ac44f9cddef138453fd047cacc55e48d929cd8325e17941e6255baf3328fdc3f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f02c171ebf3c2c06dbe4ec956f8af0cf84f2117146f7c408f79bdcf1b3b49502ee21787f4c29eab65c0dd2b2b50dc3e1ddcb853d5a1c4490cfe3d714bbec6a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8c244884a4d31b9fbc8707827f16fead4a3db7763f371d9b63eea4d93e31da528900f68faa7ffcbc609b374a138438fb58a4b1ea01fb72be82a8f6a24d6a9b"} 10:24:09 INFO - Running post-run listener: copy_logs_to_upload_dir 10:24:09 INFO - Copying logs to upload dir... 10:24:09 INFO - mkdir: /builds/slave/test/build/upload/logs 10:24:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.631003 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 10:24:09.413048) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 10:24:09.416243) ========= 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.KG7bunfc4h/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.szeguNZ5tC/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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/Firefox/sha512/e624f09a901c29a70a16cab6b006e2031893310f2cd9f992a1d149f7331ff7ac44f9cddef138453fd047cacc55e48d929cd8325e17941e6255baf3328fdc3f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f02c171ebf3c2c06dbe4ec956f8af0cf84f2117146f7c408f79bdcf1b3b49502ee21787f4c29eab65c0dd2b2b50dc3e1ddcb853d5a1c4490cfe3d714bbec6a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8c244884a4d31b9fbc8707827f16fead4a3db7763f371d9b63eea4d93e31da528900f68faa7ffcbc609b374a138438fb58a4b1ea01fb72be82a8f6a24d6a9b"} build_url:https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010846 build_url: 'https://queue.taskcluster.net/v1/task/ccUd1r6kSe2dHWE0xVW3Kw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e624f09a901c29a70a16cab6b006e2031893310f2cd9f992a1d149f7331ff7ac44f9cddef138453fd047cacc55e48d929cd8325e17941e6255baf3328fdc3f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f02c171ebf3c2c06dbe4ec956f8af0cf84f2117146f7c408f79bdcf1b3b49502ee21787f4c29eab65c0dd2b2b50dc3e1ddcb853d5a1c4490cfe3d714bbec6a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8c244884a4d31b9fbc8707827f16fead4a3db7763f371d9b63eea4d93e31da528900f68faa7ffcbc609b374a138438fb58a4b1ea01fb72be82a8f6a24d6a9b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 10:24:09.450631) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:24:09.450971) ========= 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.KG7bunfc4h/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.szeguNZ5tC/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.005042 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 10:24:09.499772) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 10:24:09.500093) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 10:24:09.500429) ========= ========= Total master_lag: 0.18 =========