builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0225 starttime: 1491431305.01 results: success (0) buildid: 20170405141720 builduid: 06605c2324824bfab261a3fd42decdb7 revision: 867df9483d5af4c8c12e19fab9b0de18bee30db7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.009371) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.010402) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.010777) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.037326) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.037999) ========= 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.qMixxoWH19/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.9V7MNm1p6T/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-05 15:28:25-- 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% 8.09M=0.001s 2017-04-05 15:28:25 (8.09 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153661 ========= master_lag: 0.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.545353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.545872) ========= 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.qMixxoWH19/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.9V7MNm1p6T/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.021520 ========= master_lag: 0.22 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:25.789910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-05 15:28:25.790332) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 867df9483d5af4c8c12e19fab9b0de18bee30db7 --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 867df9483d5af4c8c12e19fab9b0de18bee30db7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qMixxoWH19/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.9V7MNm1p6T/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-05 15:28:25,878 truncating revision to first 12 chars 2017-04-05 15:28:25,878 Setting DEBUG logging. 2017-04-05 15:28:25,878 attempt 1/10 2017-04-05 15:28:25,878 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/867df9483d5a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-05 15:28:27,917 unpacking tar archive at: mozilla-central-867df9483d5a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.306260 ========= master_lag: 1.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-05 15:28:29.476684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:29.477007) ========= script_repo_revision: 867df9483d5af4c8c12e19fab9b0de18bee30db7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:29.477420) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:29.477682) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 15:28:29.965018) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-05 15:28:29.965370) ========= /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.qMixxoWH19/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.9V7MNm1p6T/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:28:30 INFO - MultiFileLogger online at 20170405 15:28:30 in /builds/slave/test 15:28:30 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 15:28:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:28:30 INFO - {'append_to_log': False, 15:28:30 INFO - 'base_work_dir': '/builds/slave/test', 15:28:30 INFO - 'blob_upload_branch': 'Firefox', 15:28:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:28:30 INFO - 'branch': 'Firefox', 15:28:30 INFO - 'buildbot_json_path': 'buildprops.json', 15:28:30 INFO - 'config_files': ('talos/mac_config.py',), 15:28:30 INFO - 'default_actions': ('clobber', 15:28:30 INFO - 'read-buildbot-config', 15:28:30 INFO - 'download-and-extract', 15:28:30 INFO - 'populate-webroot', 15:28:30 INFO - 'create-virtualenv', 15:28:30 INFO - 'install', 15:28:30 INFO - 'run-tests'), 15:28:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:28:30 INFO - 'download_minidump_stackwalk': True, 15:28:30 INFO - 'download_symbols': 'ondemand', 15:28:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:28:30 INFO - 'tooltool.py': '/tools/tooltool.py', 15:28:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:28:30 INFO - '/tools/misc-python/virtualenv.py')}, 15:28:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:28:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:28:30 INFO - 'gecko_profile': False, 15:28:30 INFO - 'gecko_profile_interval': 0, 15:28:30 INFO - 'installer_path': 'installer.exe', 15:28:30 INFO - 'log_level': 'info', 15:28:30 INFO - 'log_name': 'talos', 15:28:30 INFO - 'log_to_console': True, 15:28:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:28:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:28:30 INFO - 'opt_config_files': (), 15:28:30 INFO - 'pip_index': False, 15:28:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:28:30 INFO - 'cmd': ('bash', 15:28:30 INFO - '-c', 15:28:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:28:30 INFO - 'enabled': True, 15:28:30 INFO - 'halt_on_failure': False, 15:28:30 INFO - 'name': 'check_screen_resolution'},), 15:28:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:28:30 INFO - 'cmd': ('bash', 15:28:30 INFO - '-c', 15:28:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:28:30 INFO - 'enabled': True, 15:28:30 INFO - 'halt_on_failure': False, 15:28:30 INFO - 'name': 'check_screen_resolution'},), 15:28:30 INFO - 'run_cmd_checks_enabled': True, 15:28:30 INFO - 'suite': 'dromaeojs-e10s', 15:28:30 INFO - 'system_bits': '32', 15:28:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:28:30 INFO - 'title': 't-yosemite-r7-0225', 15:28:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:28:30 INFO - 'use_talos_json': True, 15:28:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:28:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:28:30 INFO - 'work_dir': 'build'} 15:28:30 INFO - [mozharness: 2017-04-05 22:28:30.170073Z] Running clobber step. 15:28:30 INFO - Running pre-action listener: _resource_record_pre_action 15:28:30 INFO - Running main action method: clobber 15:28:30 INFO - rmtree: /builds/slave/test/build 15:28:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:28:33 INFO - Running post-action listener: _resource_record_post_action 15:28:33 INFO - [mozharness: 2017-04-05 22:28:33.551676Z] Finished clobber step (success) 15:28:33 INFO - [mozharness: 2017-04-05 22:28:33.551916Z] Running read-buildbot-config step. 15:28:33 INFO - Running pre-action listener: _resource_record_pre_action 15:28:33 INFO - Running main action method: read_buildbot_config 15:28:33 INFO - Using buildbot properties: 15:28:33 INFO - { 15:28:33 INFO - "project": "", 15:28:33 INFO - "product": "firefox", 15:28:33 INFO - "script_repo_revision": "production", 15:28:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:28:33 INFO - "repository": "", 15:28:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:28:33 INFO - "buildid": "20170405141720", 15:28:33 INFO - "pgo_build": "False", 15:28:33 INFO - "basedir": "/builds/slave/test", 15:28:33 INFO - "buildnumber": 189, 15:28:33 INFO - "slavename": "t-yosemite-r7-0225", 15:28:33 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:28:33 INFO - "platform": "yosemite_r7", 15:28:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:28:33 INFO - "branch": "mozilla-central", 15:28:33 INFO - "repo_path": "mozilla-central", 15:28:33 INFO - "revision": "867df9483d5af4c8c12e19fab9b0de18bee30db7", 15:28:33 INFO - "stage_platform": "macosx64", 15:28:33 INFO - "builduid": "06605c2324824bfab261a3fd42decdb7", 15:28:33 INFO - "slavebuilddir": "test" 15:28:33 INFO - } 15:28:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:28:33 INFO - Running post-action listener: _resource_record_post_action 15:28:33 INFO - [mozharness: 2017-04-05 22:28:33.557212Z] Finished read-buildbot-config step (success) 15:28:33 INFO - [mozharness: 2017-04-05 22:28:33.557374Z] Running download-and-extract step. 15:28:33 INFO - Running pre-action listener: _resource_record_pre_action 15:28:33 INFO - Running main action method: download_and_extract 15:28:33 INFO - mkdir: /builds/slave/test/build/tests 15:28:33 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')]} 15:28:33 INFO - trying https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:28:33 INFO - Downloading https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:28:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:28:34 INFO - Downloaded 1325 bytes. 15:28:34 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:28:34 INFO - Using the following test package requirements: 15:28:34 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:28:34 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:28:34 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:28:34 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:28:34 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'jsshell-mac.zip'], 15:28:34 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:28:34 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:28:34 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:28:34 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:28:34 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:28:34 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:28:34 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:28:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:28:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:28:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:28:34 INFO - Fetch https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:28:36 INFO - Expected file size: 52518272 15:28:36 INFO - Obtained file size: 52518272 15:28:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:28:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:28:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:28:53 INFO - Fetch https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:28:55 INFO - Expected file size: 52518272 15:28:55 INFO - Obtained file size: 52518272 15:29:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:29:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:29:10 INFO - Fetch https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:29:11 INFO - Expected file size: 14404152 15:29:11 INFO - Obtained file size: 14404152 15:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:12 INFO - trying https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:29:12 INFO - Downloading https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:29:14 INFO - Downloaded 70743314 bytes. 15:29:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:29:14 INFO - mkdir: /builds/slave/test/properties 15:29:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:14 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:14 INFO - Contents: 15:29:14 INFO - build_url:https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:29:14 INFO - Running post-action listener: _resource_record_post_action 15:29:14 INFO - Running post-action listener: set_extra_try_arguments 15:29:14 INFO - [mozharness: 2017-04-05 22:29:14.950220Z] Finished download-and-extract step (success) 15:29:14 INFO - [mozharness: 2017-04-05 22:29:14.950371Z] Running populate-webroot step. 15:29:14 INFO - Running pre-action listener: _resource_record_pre_action 15:29:14 INFO - Running main action method: populate_webroot 15:29:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:14 INFO - '--tppagecycles', 15:29:14 INFO - '7'], 15:29:14 INFO - 'tests': ['tsvgm']}}, 15:29:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:29:14 INFO - 'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:29:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:29:14 INFO - 'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['damp', 'tps']}, 15:29:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:29:14 INFO - 'tests': ['damp', 'tps']}, 15:29:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['dromaeo_dom']}, 15:29:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['basic_compositor_video']}, 15:29:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:29:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['a11yr', 15:29:14 INFO - 'ts_paint', 15:29:14 INFO - 'tpaint', 15:29:14 INFO - 'sessionrestore', 15:29:14 INFO - 'sessionrestore_no_auto_restore', 15:29:14 INFO - 'tabpaint']}, 15:29:14 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:14 INFO - 'ts_paint', 15:29:14 INFO - 'tpaint', 15:29:14 INFO - 'sessionrestore', 15:29:14 INFO - 'sessionrestore_no_auto_restore', 15:29:14 INFO - 'tabpaint']}, 15:29:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:14 INFO - 'ts_paint', 15:29:14 INFO - 'tpaint', 15:29:14 INFO - 'sessionrestore', 15:29:14 INFO - 'sessionrestore_no_auto_restore', 15:29:14 INFO - 'tabpaint']}, 15:29:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:14 INFO - 'ts_paint', 15:29:14 INFO - 'tpaint', 15:29:14 INFO - 'sessionrestore', 15:29:14 INFO - 'sessionrestore_no_auto_restore', 15:29:14 INFO - 'tabpaint']}, 15:29:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['a11yr', 15:29:14 INFO - 'ts_paint', 15:29:14 INFO - 'tpaint', 15:29:14 INFO - 'sessionrestore', 15:29:14 INFO - 'sessionrestore_no_auto_restore', 15:29:14 INFO - 'tabpaint']}, 15:29:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['a11yr', 15:29:14 INFO - 'ts_paint', 15:29:14 INFO - 'tpaint', 15:29:14 INFO - 'sessionrestore', 15:29:14 INFO - 'sessionrestore_no_auto_restore', 15:29:14 INFO - 'tabpaint']}, 15:29:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['tsvgx', 15:29:14 INFO - 'tsvgr_opacity', 15:29:14 INFO - 'tart', 15:29:14 INFO - 'tscrollx', 15:29:14 INFO - 'cart', 15:29:14 INFO - 'tsvg_static']}, 15:29:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:14 INFO - 'tsvgr_opacity', 15:29:14 INFO - 'tart', 15:29:14 INFO - 'tscrollx', 15:29:14 INFO - 'cart', 15:29:14 INFO - 'tsvg_static']}, 15:29:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:29:14 INFO - 'talos_options': ['--disable-e10s'], 15:29:14 INFO - 'tests': ['tp5o']}, 15:29:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:29:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:29:14 INFO - 'talos_options': ['--disable-e10s', 15:29:14 INFO - '--xperf_path', 15:29:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:14 INFO - 'tests': ['tp5n']}, 15:29:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:29:14 INFO - 'talos_options': ['--xperf_path', 15:29:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:14 INFO - 'tests': ['tp5n']}}, 15:29:14 INFO - 'talos.zip': {'path': '', 15:29:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:14 INFO - Running post-action listener: _resource_record_post_action 15:29:14 INFO - [mozharness: 2017-04-05 22:29:14.968789Z] Finished populate-webroot step (success) 15:29:14 INFO - [mozharness: 2017-04-05 22:29:14.968880Z] Running create-virtualenv step. 15:29:14 INFO - Running pre-action listener: _resource_record_pre_action 15:29:14 INFO - Running main action method: create_virtualenv 15:29:14 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:14 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 15:29:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:15 INFO - Using real prefix '/tools/python27' 15:29:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:15 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:18 INFO - Installing pip.................done. 15:29:18 INFO - Return code: 0 15:29:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:29:18 INFO - Reading from file tmpfile_stdout 15:29:18 INFO - Output received: 15:29:18 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) 15:29:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:19 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:19 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 15:29:19 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 15:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:19 INFO - 'HOME': '/Users/cltbld', 15:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:19 INFO - 'LOGNAME': 'cltbld', 15:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:19 INFO - 'MOZ_NO_REMOTE': '1', 15:29:19 INFO - 'NO_EM_RESTART': '1', 15:29:19 INFO - 'PAGER': '/bin/cat', 15:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:19 INFO - 'PWD': '/builds/slave/test', 15:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:19 INFO - 'SHELL': '/bin/bash', 15:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:19 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:19 INFO - 'USER': 'cltbld', 15:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:19 INFO - 'XPC_FLAGS': '0x0', 15:29:19 INFO - 'XPC_SERVICE_NAME': '0', 15:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:19 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 15:29:19 INFO - Cleaning up... 15:29:19 INFO - Return code: 0 15:29:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:20 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:20 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 15:29:20 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 15:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:20 INFO - 'HOME': '/Users/cltbld', 15:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:20 INFO - 'LOGNAME': 'cltbld', 15:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:20 INFO - 'MOZ_NO_REMOTE': '1', 15:29:20 INFO - 'NO_EM_RESTART': '1', 15:29:20 INFO - 'PAGER': '/bin/cat', 15:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:20 INFO - 'PWD': '/builds/slave/test', 15:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:20 INFO - 'SHELL': '/bin/bash', 15:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:20 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:20 INFO - 'USER': 'cltbld', 15:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:20 INFO - 'XPC_FLAGS': '0x0', 15:29:20 INFO - 'XPC_SERVICE_NAME': '0', 15:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:20 INFO - Downloading/unpacking psutil>=3.1.1 15:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:24 INFO - Installing collected packages: psutil 15:29:24 INFO - Running setup.py install for psutil 15:29:25 INFO - building 'psutil._psutil_osx' extension 15:29:25 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 15:29:25 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 15:29:25 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 15:29:25 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 15:29:25 INFO - building 'psutil._psutil_posix' extension 15:29:25 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 15:29:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:29:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:29:25 INFO - ^ 15:29:25 INFO - 1 warning generated. 15:29:25 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 15:29:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:25 INFO - Successfully installed psutil 15:29:25 INFO - Cleaning up... 15:29:25 INFO - Return code: 0 15:29:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:25 INFO - 'HOME': '/Users/cltbld', 15:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:25 INFO - 'LOGNAME': 'cltbld', 15:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:25 INFO - 'MOZ_NO_REMOTE': '1', 15:29:25 INFO - 'NO_EM_RESTART': '1', 15:29:25 INFO - 'PAGER': '/bin/cat', 15:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:25 INFO - 'PWD': '/builds/slave/test', 15:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:25 INFO - 'SHELL': '/bin/bash', 15:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:25 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:25 INFO - 'USER': 'cltbld', 15:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:25 INFO - 'XPC_FLAGS': '0x0', 15:29:25 INFO - 'XPC_SERVICE_NAME': '0', 15:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29:26 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 15:29:26 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 15:29:26 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 15:29:26 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 15:29:26 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 15:29:26 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 15:29:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:29:28 INFO - Installing collected packages: mozsystemmonitor 15:29:28 INFO - Running setup.py install for mozsystemmonitor 15:29:28 INFO - Successfully installed mozsystemmonitor 15:29:28 INFO - Cleaning up... 15:29:28 INFO - Return code: 0 15:29:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:29:28 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')]} 15:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:28 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')]} 15:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:28 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:28 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 15:29:28 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 15:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:28 INFO - 'HOME': '/Users/cltbld', 15:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:28 INFO - 'LOGNAME': 'cltbld', 15:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:28 INFO - 'MOZ_NO_REMOTE': '1', 15:29:28 INFO - 'NO_EM_RESTART': '1', 15:29:28 INFO - 'PAGER': '/bin/cat', 15:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:28 INFO - 'PWD': '/builds/slave/test', 15:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:28 INFO - 'SHELL': '/bin/bash', 15:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:28 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:28 INFO - 'USER': 'cltbld', 15:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:28 INFO - 'XPC_FLAGS': '0x0', 15:29:28 INFO - 'XPC_SERVICE_NAME': '0', 15:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:29 INFO - Downloading/unpacking jsonschema==2.5.1 15:29:29 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 15:29:29 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 15:29:29 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 15:29:29 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 15:29:29 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 15:29:29 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 15:29:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:29:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29:32 INFO - Downloading functools32-3.2.3-2.tar.gz 15:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:29:32 INFO - warning: no files found matching '*.txt' 15:29:32 INFO - no previously-included directories found matching 'build' 15:29:32 INFO - no previously-included directories found matching 'dist' 15:29:32 INFO - no previously-included directories found matching '.git*' 15:29:32 INFO - Installing collected packages: jsonschema, functools32 15:29:32 INFO - Running setup.py install for functools32 15:29:32 INFO - warning: no files found matching '*.txt' 15:29:32 INFO - no previously-included directories found matching 'build' 15:29:32 INFO - no previously-included directories found matching 'dist' 15:29:32 INFO - no previously-included directories found matching '.git*' 15:29:32 INFO - Successfully installed jsonschema functools32 15:29:32 INFO - Cleaning up... 15:29:32 INFO - Return code: 0 15:29:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:32 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:32 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 15:29:32 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 15:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:32 INFO - 'HOME': '/Users/cltbld', 15:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:32 INFO - 'LOGNAME': 'cltbld', 15:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:32 INFO - 'MOZ_NO_REMOTE': '1', 15:29:32 INFO - 'NO_EM_RESTART': '1', 15:29:32 INFO - 'PAGER': '/bin/cat', 15:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:32 INFO - 'PWD': '/builds/slave/test', 15:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:32 INFO - 'SHELL': '/bin/bash', 15:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:32 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:32 INFO - 'USER': 'cltbld', 15:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:32 INFO - 'XPC_FLAGS': '0x0', 15:29:32 INFO - 'XPC_SERVICE_NAME': '0', 15:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:29:32 INFO - Cleaning up... 15:29:32 INFO - Return code: 0 15:29:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:32 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:32 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 15:29:32 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 15:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:32 INFO - 'HOME': '/Users/cltbld', 15:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:32 INFO - 'LOGNAME': 'cltbld', 15:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:32 INFO - 'MOZ_NO_REMOTE': '1', 15:29:32 INFO - 'NO_EM_RESTART': '1', 15:29:32 INFO - 'PAGER': '/bin/cat', 15:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:32 INFO - 'PWD': '/builds/slave/test', 15:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:32 INFO - 'SHELL': '/bin/bash', 15:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:32 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:32 INFO - 'USER': 'cltbld', 15:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:32 INFO - 'XPC_FLAGS': '0x0', 15:29:32 INFO - 'XPC_SERVICE_NAME': '0', 15:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:33 INFO - Downloading/unpacking blobuploader==1.2.4 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29:35 INFO - Downloading blobuploader-1.2.4.tar.gz 15:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:29:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:29:35 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 15:29:35 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 15:29:35 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 15:29:35 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 15:29:35 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 15:29:35 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 15:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:29:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:29:36 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 15:29:36 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 15:29:36 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 15:29:36 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 15:29:36 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 15:29:36 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 15:29:36 INFO - Downloading docopt-0.6.1.tar.gz 15:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:29:37 INFO - Installing collected packages: blobuploader, requests, docopt 15:29:37 INFO - Running setup.py install for blobuploader 15:29:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:29:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:29:37 INFO - Running setup.py install for requests 15:29:37 INFO - Running setup.py install for docopt 15:29:37 INFO - Successfully installed blobuploader requests docopt 15:29:37 INFO - Cleaning up... 15:29:37 INFO - Return code: 0 15:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:37 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')]} 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 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')]} 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:37 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 15:29:37 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 15:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:37 INFO - 'HOME': '/Users/cltbld', 15:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:37 INFO - 'LOGNAME': 'cltbld', 15:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:37 INFO - 'MOZ_NO_REMOTE': '1', 15:29:37 INFO - 'NO_EM_RESTART': '1', 15:29:37 INFO - 'PAGER': '/bin/cat', 15:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:37 INFO - 'PWD': '/builds/slave/test', 15:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:37 INFO - 'SHELL': '/bin/bash', 15:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:37 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:37 INFO - 'USER': 'cltbld', 15:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:37 INFO - 'XPC_FLAGS': '0x0', 15:29:37 INFO - 'XPC_SERVICE_NAME': '0', 15:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-6E8yid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-2GbWnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-L4tlh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-DWw857-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-u7b0U6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-87KTCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-RdOiqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:38 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-4cemzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-SrWUD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-4B6iv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-dE10Pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-FYSQPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-02ah60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Yvvy3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-z0gNqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-XAakYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:39 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-zpgtsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:29:39 INFO - Running setup.py install for manifestparser 15:29:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozcrash 15:29:40 INFO - Running setup.py install for mozdebug 15:29:40 INFO - Running setup.py install for mozdevice 15:29:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozfile 15:29:40 INFO - Running setup.py install for mozhttpd 15:29:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozinfo 15:29:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozInstall 15:29:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for mozleak 15:29:41 INFO - Running setup.py install for mozlog 15:29:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for moznetwork 15:29:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for mozprocess 15:29:41 INFO - Running setup.py install for mozprofile 15:29:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for mozrunner 15:29:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:29:42 INFO - Running setup.py install for mozscreenshot 15:29:42 INFO - Running setup.py install for moztest 15:29:42 INFO - Running setup.py install for mozversion 15:29:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:29:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:29:42 INFO - Cleaning up... 15:29:42 INFO - Return code: 0 15:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:42 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')]} 15:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:42 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')]} 15:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:42 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:42 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 15:29:42 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 15:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:42 INFO - 'HOME': '/Users/cltbld', 15:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:42 INFO - 'LOGNAME': 'cltbld', 15:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:42 INFO - 'MOZ_NO_REMOTE': '1', 15:29:42 INFO - 'NO_EM_RESTART': '1', 15:29:42 INFO - 'PAGER': '/bin/cat', 15:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:42 INFO - 'PWD': '/builds/slave/test', 15:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:42 INFO - 'SHELL': '/bin/bash', 15:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:42 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:42 INFO - 'USER': 'cltbld', 15:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:42 INFO - 'XPC_FLAGS': '0x0', 15:29:42 INFO - 'XPC_SERVICE_NAME': '0', 15:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:42 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-o5unzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:42 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)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:42 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-qeyELq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-3_C3F3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-SLsqzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-pp_Rvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-a3fPsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-WLUVGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-q6y0Tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Dhx1Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-U5hJSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:43 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)) 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:43 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Ri49Qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:44 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)) 15:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:44 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-WcNNZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:44 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Jd6MLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:44 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)) 15:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:44 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Tm4a3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:44 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)) 15:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:44 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-GhzpPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:44 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)) 15:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:44 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-wH22lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:44 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Uhy_0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:44 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)) 15:29:44 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)) 15:29:44 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)) 15:29:44 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)) 15:29:44 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)) 15:29:44 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)) 15:29:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:44 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 15:29:44 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 15:29:44 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 15:29:44 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 15:29:44 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 15:29:44 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 15:29:47 INFO - Downloading blessings-1.6.tar.gz 15:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:29:47 INFO - Installing collected packages: blessings 15:29:47 INFO - Running setup.py install for blessings 15:29:47 INFO - Successfully installed blessings 15:29:47 INFO - Cleaning up... 15:29:47 INFO - Return code: 0 15:29:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:47 INFO - Reading from file tmpfile_stdout 15:29:47 INFO - Current package versions: 15:29:47 INFO - blessings == 1.6 15:29:47 INFO - blobuploader == 1.2.4 15:29:47 INFO - docopt == 0.6.1 15:29:47 INFO - functools32 == 3.2.3-2 15:29:47 INFO - jsonschema == 2.5.1 15:29:47 INFO - manifestparser == 1.1 15:29:47 INFO - mozInstall == 1.12 15:29:47 INFO - mozcrash == 1.0 15:29:47 INFO - mozdebug == 0.1 15:29:47 INFO - mozdevice == 0.49 15:29:47 INFO - mozfile == 1.2 15:29:47 INFO - mozhttpd == 0.7 15:29:47 INFO - mozinfo == 0.9 15:29:47 INFO - mozleak == 0.1 15:29:47 INFO - mozlog == 3.4 15:29:47 INFO - moznetwork == 0.27 15:29:47 INFO - mozprocess == 0.25 15:29:47 INFO - mozprofile == 0.28 15:29:47 INFO - mozrunner == 6.13 15:29:47 INFO - mozscreenshot == 0.1 15:29:47 INFO - mozsystemmonitor == 0.3 15:29:47 INFO - moztest == 0.8 15:29:47 INFO - mozversion == 1.4 15:29:47 INFO - psutil == 3.1.1 15:29:47 INFO - requests == 1.2.3 15:29:47 INFO - wsgiref == 0.1.2 15:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:47 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:47 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 15:29:47 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 15:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:47 INFO - 'HOME': '/Users/cltbld', 15:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:47 INFO - 'LOGNAME': 'cltbld', 15:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:47 INFO - 'MOZ_NO_REMOTE': '1', 15:29:47 INFO - 'NO_EM_RESTART': '1', 15:29:47 INFO - 'PAGER': '/bin/cat', 15:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:47 INFO - 'PWD': '/builds/slave/test', 15:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:47 INFO - 'SHELL': '/bin/bash', 15:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:47 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:47 INFO - 'USER': 'cltbld', 15:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:47 INFO - 'XPC_FLAGS': '0x0', 15:29:47 INFO - 'XPC_SERVICE_NAME': '0', 15:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 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)) 15:29:48 INFO - Cleaning up... 15:29:48 INFO - Return code: 0 15:29:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:48 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 0x10b12fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e3da5a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, '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.qMixxoWH19/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.9V7MNm1p6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:29:48 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 15:29:48 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 15:29:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:29:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:48 INFO - 'HOME': '/Users/cltbld', 15:29:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:48 INFO - 'LOGNAME': 'cltbld', 15:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:48 INFO - 'MOZ_NO_REMOTE': '1', 15:29:48 INFO - 'NO_EM_RESTART': '1', 15:29:48 INFO - 'PAGER': '/bin/cat', 15:29:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:48 INFO - 'PWD': '/builds/slave/test', 15:29:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:48 INFO - 'SHELL': '/bin/bash', 15:29:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:29:48 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:29:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:48 INFO - 'USER': 'cltbld', 15:29:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:48 INFO - 'XPC_FLAGS': '0x0', 15:29:48 INFO - 'XPC_SERVICE_NAME': '0', 15:29:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:29:48 INFO - Cleaning up... 15:29:48 INFO - Return code: 0 15:29:48 INFO - Running post-action listener: _resource_record_post_action 15:29:48 INFO - Running post-action listener: _start_resource_monitoring 15:29:48 INFO - Starting resource monitoring. 15:29:48 INFO - [mozharness: 2017-04-05 22:29:48.531196Z] Finished create-virtualenv step (success) 15:29:48 INFO - [mozharness: 2017-04-05 22:29:48.532183Z] Running install step. 15:29:48 INFO - Running pre-action listener: _resource_record_pre_action 15:29:48 INFO - Running main action method: install 15:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:48 INFO - Reading from file tmpfile_stdout 15:29:48 INFO - Detecting whether we're running mozinstall >=1.0... 15:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:29:48 INFO - Reading from file tmpfile_stdout 15:29:48 INFO - Output received: 15:29:48 INFO - Usage: mozinstall [options] installer 15:29:48 INFO - Options: 15:29:48 INFO - -h, --help show this help message and exit 15:29:48 INFO - -d DEST, --destination=DEST 15:29:48 INFO - Directory to install application into. [default: 15:29:48 INFO - "/builds/slave/test"] 15:29:48 INFO - --app=APP Application being installed. [default: firefox] 15:29:48 INFO - mkdir: /builds/slave/test/build/application 15:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:30:08 INFO - Reading from file tmpfile_stdout 15:30:08 INFO - Output received: 15:30:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:30:08 INFO - Running post-action listener: _resource_record_post_action 15:30:08 INFO - [mozharness: 2017-04-05 22:30:08.580584Z] Finished install step (success) 15:30:08 INFO - [mozharness: 2017-04-05 22:30:08.580728Z] Running run-tests step. 15:30:08 INFO - Running pre-action listener: _resource_record_pre_action 15:30:08 INFO - Running main action method: run_tests 15:30:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:30:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:30:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:30:08 INFO - 2017-04-05 15:30:08.595 screenresolution[2593:85912] starting screenresolution argv=screenresolution get 15:30:08 INFO - 2017-04-05 15:30:08.598 screenresolution[2593:85912] Display 0: 1600x1200x32@60 15:30:08 INFO - 2017-04-05 15:30:08.603 screenresolution[2594:85915] starting screenresolution argv=screenresolution list 15:30:08 INFO - Available Modes on Display 0 15:30:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:30:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:30:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:30:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:30:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:30:08 INFO - Intel Iris: 15:30:08 INFO - Chipset Model: Intel Iris 15:30:08 INFO - Type: GPU 15:30:08 INFO - Bus: Built-In 15:30:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:30:08 INFO - Vendor: Intel (0x8086) 15:30:08 INFO - Device ID: 0x0a2e 15:30:08 INFO - Revision ID: 0x0009 15:30:08 INFO - Displays: 15:30:08 INFO - Display: 15:30:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:30:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:30:08 INFO - Display Serial Number: 1600x1200 60 15:30:08 INFO - Main Display: Yes 15:30:08 INFO - Mirror: Off 15:30:08 INFO - Online: Yes 15:30:08 INFO - Rotation: Supported 15:30:08 INFO - Return code: 0 15:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:30:08 INFO - Python 2.7.3 15:30:08 INFO - Return code: 0 15:30:08 INFO - grabbing minidump binary from tooltool 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:08 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 0x10af0dd30>, '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 0x10ae34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af02be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:30:08 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 15:30:08 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 15:30:08 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 15:30:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:30:08 INFO - Return code: 0 15:30:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:30:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:30:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:30:08 INFO - ENV: RUST_BACKTRACE is now 1 15:30:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:30:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:30:08 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/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--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 15:30:08 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/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0225 --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 15:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMixxoWH19/Render', 15:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:08 INFO - 'HOME': '/Users/cltbld', 15:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:08 INFO - 'LOGNAME': 'cltbld', 15:30:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:08 INFO - 'MOZ_NO_REMOTE': '1', 15:30:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:08 INFO - 'NO_EM_RESTART': '1', 15:30:08 INFO - 'PAGER': '/bin/cat', 15:30:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:08 INFO - 'PWD': '/builds/slave/test', 15:30:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:08 INFO - 'RUST_BACKTRACE': '1', 15:30:08 INFO - 'SHELL': '/bin/bash', 15:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9V7MNm1p6T/Listeners', 15:30:08 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:08 INFO - 'USER': 'cltbld', 15:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:08 INFO - 'XPC_FLAGS': '0x0', 15:30:08 INFO - 'XPC_SERVICE_NAME': '0', 15:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:08 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/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--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 15:30:09 INFO - mozversion application_buildid: 20170405141720 15:30:09 INFO - mozversion application_changeset: 867df9483d5af4c8c12e19fab9b0de18bee30db7 15:30:09 INFO - mozversion application_display_name: Nightly 15:30:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:30:09 INFO - mozversion application_name: Firefox 15:30:09 INFO - mozversion application_remotingname: firefox 15:30:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:30:09 INFO - mozversion application_vendor: Mozilla 15:30:09 INFO - mozversion application_version: 55.0a1 15:30:09 INFO - mozversion platform_buildid: 20170405141720 15:30:09 INFO - mozversion platform_changeset: 867df9483d5af4c8c12e19fab9b0de18bee30db7 15:30:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:30:09 INFO - mozversion platform_version: 55.0a1 15:30:09 INFO - using testdate: 1491431409 15:30:09 INFO - actual date: 1491431409 15:30:09 INFO - starting webserver on 'localhost:49226' 15:30:09 INFO - SUITE-START | Running 2 tests 15:30:09 INFO - TEST-START | dromaeo_css 15:30:09 INFO - operating with platform_type : mac_ 15:30:09 INFO - Initialising browser for dromaeo_css test... 15:30:09 INFO - TEST-INFO | started process 2604 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpz0ZpTP/profile http://localhost:49226/getInfo.html) 15:30:12 INFO - PID 2604 | 2017-04-05 15:30:12.122 plugin-container[2606:86088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483f, name = 'com.apple.tsm.portname' 15:30:12 INFO - PID 2604 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:12 INFO - PID 2604 | 2017-04-05 15:30:12.126 plugin-container[2606:86088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 15:30:12 INFO - PID 2604 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:12 INFO - PID 2604 | 2017-04-05 15:30:12.126 plugin-container[2606:86088] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:12 INFO - PID 2604 | __metrics Screen width/height:1600/1200 15:30:12 INFO - PID 2604 | colorDepth:24 15:30:12 INFO - PID 2604 | Browser inner width/height: 1024/689 15:30:12 INFO - PID 2604 | __metrics 15:30:12 ERROR - PID 2604 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 15:30:13 INFO - TEST-INFO | 2604: exit 0 15:30:13 INFO - Browser initialized. 15:30:13 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:13 INFO - TEST-INFO | started process 2607 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpz0ZpTP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:15 INFO - PID 2607 | 2017-04-05 15:30:15.629 plugin-container[2608:86214] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 15:30:15 INFO - PID 2607 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:15 INFO - PID 2607 | 2017-04-05 15:30:15.630 plugin-container[2608:86214] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 15:30:15 INFO - PID 2607 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:15 INFO - PID 2607 | 2017-04-05 15:30:15.630 plugin-container[2608:86214] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:25 INFO - PID 2607 | RSS: Main: 235438080 15:30:25 INFO - PID 2607 | 15:33:30 INFO - PID 2607 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 15:33:31 INFO - PID 2607 | RSS: Main: 252104704 15:33:31 INFO - PID 2607 | 15:35:13 INFO - PID 2607 | ************************* 15:35:13 INFO - PID 2607 | A coding exception was thrown and uncaught in a Task. 15:35:13 INFO - PID 2607 | 15:35:13 ERROR - PID 2607 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:35:13 INFO - PID 2607 | Full stack: 15:35:13 INFO - PID 2607 | ************************* 15:35:13 INFO - PID 2607 | ************************* 15:35:13 INFO - PID 2607 | A coding exception was thrown and uncaught in a Task. 15:35:13 INFO - PID 2607 | 15:35:13 ERROR - PID 2607 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:35:13 INFO - PID 2607 | Full stack: 15:35:13 INFO - PID 2607 | ************************* 15:35:13 INFO - PID 2607 | ************************* 15:35:13 INFO - PID 2607 | A coding exception was thrown and uncaught in a Task. 15:35:13 INFO - PID 2607 | 15:35:13 ERROR - PID 2607 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:35:13 INFO - PID 2607 | Full stack: 15:35:13 INFO - PID 2607 | ************************* 15:35:13 INFO - PID 2607 | ************************* 15:35:13 INFO - PID 2607 | A coding exception was thrown and uncaught in a Task. 15:35:13 INFO - PID 2607 | 15:35:13 ERROR - PID 2607 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:35:13 INFO - PID 2607 | Full stack: 15:35:13 INFO - PID 2607 | ************************* 15:36:33 INFO - PID 2607 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 15:36:34 INFO - PID 2607 | RSS: Main: 260481024 15:36:34 INFO - PID 2607 | 15:39:36 INFO - PID 2607 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 15:39:36 INFO - PID 2607 | RSS: Main: 272760832 15:39:36 INFO - PID 2607 | 15:42:38 INFO - PID 2607 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 15:42:39 INFO - PID 2607 | RSS: Main: 265117696 15:42:39 INFO - PID 2607 | 15:45:41 INFO - PID 2607 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 15:45:42 INFO - PID 2607 | RSS: Main: 275390464 15:45:42 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 15:48:45 INFO - PID 2607 | RSS: Main: 269053952 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | __start_tp_report 15:48:45 INFO - PID 2607 | _x_x_mozilla_page_load 15:48:45 INFO - PID 2607 | _x_x_mozilla_page_load_details 15:48:45 INFO - PID 2607 | |i|pagename|runs| 15:48:45 INFO - PID 2607 | |0;dojo.html;3520.999999999998;3533.1873669056117;3576.713862890969;3601.1357274254187;3601.873934412295;6130.581587307925;6213.347598502154;6229.688515574226;6246.250449946007;6266.060090986354;6136.856084434195;6171.382861713826;6183.598066125699;6205.875882482348;6258.718357673904;5437.184422336662;5469.890602187954;5479.561635069185;5509.779608815643;5571.275734154554;5417.3770016448025;5518.392976772552;5549.861253468655;5569.526590239834;5595.328560572717;5368.4363141869935;5405.081136206854;5480.068388373986;5502.147167189092;5513.448655134494;5397.8380648580605;5419.810306639249;5421.268128802601;5461.9453805461835;5525.226468294442;5263.99984003042;5364.517193452569;5370.140777475585;5391.973040134813;5417.674939503642;6046.244219472566;6086.786962456337;6112.199536562771;6133.4264757247465;6251.593646412968;5556.194351818964;6538.411542961108;6645.60126392418;6675.866482670368;6675.966620166917;6761;6773.119494465688;6777.423918966893;6782.8304292392595;6793.082933803923;6084.421979911901;6318.62088274705;6329.461995730322;6340.873182536324;6345.968270158666;5745.569082318842;6733.3266673333255;6745.5952642841585;6776.627285499247;6795.558288711218;6261.467775239064;6411.519136064814;6427.807165785034;6507.34926507355;6583.637899915455;5449.618526703092;5457.20870473787;6440.6135631862235;6626.83432914181;6743.1908171019795;6638.402543770987;6649.933500665028;6652.135222421054;6670;6670.199576050905;5595.496405323541;6654.367835055662;6658.1011563438315;6680.699368528428;6704.061431399608;5200.427952925175;5221.503957124067;5244.711540865213;5271.341082364705;5309.469053094737;4337.785420082382;4740;4763.7141771493125;4772.093302272557;4829.372181616367;5901.262342207224;6163.3836616338;6211.9689401552705;6282.151909492251;6336.334684858116;6504.772332968323;6596.109525214035;6654.500912431587;6682.231543372477;6769.086173366532;5487.917681234785;6334.778282760173;6334.923063079287;6337.8732425352155;6338.58799178052;4862.878428039327;5838.036723940587;5939.643621382731;5950.9107363389585;5965.075413310944;5770.336411312669;6616.239132499824;6857.588544687334;6950.443964482829;6963.234044255027;5433.429489903503;6470.967645161744;6594.439472644879;6750.919852823525;6750.966245168743;6850.280720524473;6883.586984780929;6890.517663763489;6891.965540172267;6896.069030680794;6451.1290975717675;6509.088727578238;6573.671316434255;6586.934130658632;6587.571807832475;5969.820905372846;6105.823680184288;6576.980568011966;6586.473082153512;6697.3637504437;6313.305536390902;6529.608223506605;6547.836304092436;6556.737730490918;6561.212654481493;5142.485751424828;6271.592346497463;6323.114763933145;6344.651044192429;6521.282658907421;4697.7651117444675;5124.871878203075;6373.171487706569;6381.202349706286;6391.616503009835;6282.685865706605;6378.107065010995;6383.744650214126;6495.870082598227;6528.608283503005;6283.59156654816;6314.242290925118;6390.265119511129;6397.296297407189;6418.454431373386 15:48:45 INFO - PID 2607 | |1;ext.html;712.8039789058008;773.9613019349033;802.9237222463868;1266.4934026389449;1274.2927675140293;36761.81619091904;37971.62028379722;37995.81002094986;38012.429813552786;38070.23859522811;38064;38268.04329891747;38389;38475.422868656926;38573.80713096438;7931.563763992978;8069.878951815727;8420.4947703138;8625.26685231756;8664.826703465927;8638.265747411477;8684.392092553524;8762.60568274426;8773.12268773124;8781.95609021954;8717.564121793885;8753.868691969625;8779.034306226342;8789.956050219773;8802.03177650458;8692.652293908237;8707.60815763289;8729.258013068897;8755.430896991675;8759.51822649754;8374.204450577185;8400.621972011275;8414.621342039623;8421.663133474654;8461.026981897037;37049.814750926074;37063;37196.81401593002;37270.62729372699;37295.62704372949;3432.6567343265724;3466.861325546975;3474.5483087198754;3475.391806433864;3476.4785282207617;29382.85308573465;29617.70382296171;29907.850460747555;29932.95234666798;30081;2933.5306350983956;2979.478591246545;2987.895423660183;2991.5213565829363;3002.24943764059;270493.2950670488;271715;274756;275078;275248;46233;46299.768501157276;46487.30269045967;46577.7671111649;46596.767016164704;48912.510874890795;49112.75443622759;49186;49289.52131436062;49450.75274623604;12278.324692142018;12357.629271121881;12420.81368779469;12614.369281535886;12628.242305461701;9210.81578368429;9752.658656946973;9796.412215267106;9804.362716423553;9812.901870981199;13188.27464489443;13203.867961320457;13216.603501894959;13248.73502529944;13314.06801523884;4980.252962055721;5224.582033437316;5919.260092488439;5947.821565353046;5948.791792287249;958.1281034258791;972.1882228339274;982.7346616413529;986.3490096536395;1011.1960991012163;21167.576648466944;23522;23556.52886942286;23591.51415969826;23617.409564760674;947.9810403791886;1019.179560453827;1056.4612047855496;1091.3015669971226;1101.3832253937821;10114.039166279192;11345.432728363548;12111.273323600612;12163.391830408444;12174.513019479298;325239;382350;382877;386196;387015;51518.742406287725;51822;51992.22011669828;52253.477465225624;52474.21288680673;1451.332387101945;1476.0922032949873;1486.0266525425864;1489.2107183192925;1505.4881340344336;28139.859300703367;28274;29107.7089229105;29124.854375727984;29672.258193545335;38559.80720096494;43083;47728.761356193;49178.50821491739;49407.50592494029;350801;351447;353675;355078;355334;33929.151771205914;33946;34214.657853421144;34256.14359640929;34289.94260344387;41533.169336613704;41628.58371416247;42013.78993105015;42162.36756448595;42893.78553107214;28423.857880710464;29676;29759.809607615454;30140.84929575426;30207.69792302137;28941.276468087624;29793.702062979093;29890.402191955603;29966.70033299639;30066.398672026873;32801.34397312088;35675.75134870267;35733.64266357303;35734.8213258932;35886.64113358833;29193.562096568145;29655.25861853471;29679;30220.64007119641;30363.2409189772;22556.210532631278;23809.761902380753;24133.396665083514;24430;24741.87629061843 15:48:45 INFO - PID 2607 | |2;jquery.html;348.87440521412293;418.10108267225473;424.2681374628764;424.77911486027267;452.3237759549472;20965.47586310341;21195.364139075835;21856.89071554642;22870;23140.80162384575;19514.512137196576;22427.663585046197;22758.544829103448;23121.653175202384;23378.415539611517;6045.909311360333;6352.364763523657;6388.968055159741;6408.551401401904;6414.454771344418;6050.818475445743;6252.593581417216;6346;6383.457406120486;6406.167198264219;6184.443400093991;6355.2373715154245;6358;6362.141110950032;6366.108744775735;6328.208973878265;6415.166028416323;6465.127207826978;6465.773697920574;6469.320721324288;5388.622796404254;5591.832245032656;5628.634138781007;5715.971420142914;5744.712764361765;27158.45683086347;27785;28189.01338453144;28205.012824551042;28562.285942851595;2144.892755362241;2207.0178770447155;2207.2826331442348;2208.8232941364813;2212.982028266983;17428.564285892826;17751.11244437773;17981.280748770034;18361;18466;2090.7595626502994;2129.073852874005;2151.440625437382;2152.9031193596325;2182.127149140341;109325;119772.40113799376;158915.2054239733;159481;162734;12667;13117.212967221998;13174.341282935815;13189.73620527584;13195.934020330029;13774.449022039005;16417.83582164187;16634.33462661504;16663.00021998684;16667.999920004837;8035.276825085653;8143.615585350504;8221.301189398968;8335.333173346118;8401.117882622237;7333.559986400833;7444.143923448873;7534.571769844708;7662.118856331482;7670.002899623013;7899.447038707235;7900.84198316042;7960.40296977729;8012.318952888953;8019.278264956182;2187.8359123065516;2201.4573167990675;2202.757696653367;2203.4381232785536;2372.7983121434872;803.9638216280281;882.6381183714647;900.5542256583034;909.8908131024187;949.5110018340561;20209.696854547194;20579.58840823205;20800.375988720363;21051.634202048514;21155.471113221985;922.4557511068367;929.9953500232591;958.4680502321139;963.9325247232766;979.3536266064364;9913.504324783731;10430.895691043144;10891.83662245067;10939.945300273448;10965.067969222546;603747;646675;679575;689384;728206;160958;164654;167294;168504.62990740116;169970.15014925093;1129.9378534180535;1133.1557989298026;1175.3594291111365;1177.12892459581;1177.8174382970653;18017.549561261072;18141.727874082164;18658.813411865707;21083;22900.198493052663;64473;64901.70196595947;65054;65125;65270.34729652643;79735;86174.56912715397;87060.5646971761;87608.56195719236;91039;8606.870896936676;8679.522626255617;8879.200871921557;8917.554122293988;8953.59708813118;13972.510962116683;14081.436742530594;14111.576652700436;14403.567892963229;14472.276386180438;11710.1217408693;11807.409629518661;11898.345590992578;12051.51793928233;12185.268883866642;9689.854652180364;9980.451075190644;10920.836187457353;11158.665240042812;11233.887661123612;11569.36368499707;11675.42381778483;11822.34977076269;12097.818532722193;12215.206011609573;8675.609597567998;8756.430831996155;8926.330525210506;8975.685850995184;9049.683261085831;11630.185886987829;11644.6506604802;11852.466639001093;11937.16439849237;12009.759804804029 15:48:45 INFO - PID 2607 | |3;mootools.html;1381.5648070857678;1419.4038503828397;1435.13891665001;1436.2890369267213;1452.6586252230727;11330.886691133106;11385.487653055612;11593.420328983539;11932.940335298323;11942.641720748392;11684.298942063482;11741;11895.286282823037;11906.642800715992;11920.582779602715;2497.6378425128346;2539.263613552068;2545.172818833877;2546.6943966723925;2548.3119557719406;2461.4092617771694;2470.6788117544693;2511.309389917778;2529.9747002529925;2532.4555220627562;2456.1280745335416;2486.5772818620876;2492.314613481289;2506.711728151268;2513.3088400689776;2124.022949443258;2409.6746939163163;2454.0797201049604;2459.3605662527693;2490.3276115448907;2396.880155992211;2444.7310795812446;2472.8763561822016;2499.9250022499355;2503.987480062606;11639;11706.063514918786;11708.414579271088;11835.289882606985;11863.46614402354;6512.804615861532;6701.262861085277;6750.1562304711915;6777.559458635173;6783.118194634715;3463.8268086595567;3845.61543845616;3999.420084087791;4066.1054567994993;4138.875833724963;1163.1973937982764;1163.738158914246;1189.8274750161265;1211.9212251203733;1212.0727643352843;13444.932775336158;15419;16406;16483.258253378626;16576;5877.52979761618;5911.9113213301835;6169.2288463942;6187.195664563578;6236.191504463745;12726.618201453972;12767.93616031914;13550.728985420237;13628.65928351799;13646.863531364757;6674.499412543982;6713.597184168965;6717.865642687118;6726;6728.192616886005;5362.70505122222;5711.829075039607;6063.878722425615;6114.357992410733;6154.661493617894;4686.250199967989;4769.66612337133;4772.379590653193;4809.591184749265;4885.5358740919555;1436.5833908166724;1467.200375795194;1475.4319586959098;1479.6522817137964;1498.9400423983136;729.5841370418916;732.4543215304632;753.9811504712426;759.4038679636459;787.0673252196166;6278.466330361879;6433.324500927429;6590.5716128451495;6629.801105966829;6647.66761661915;552.9115341545333;563.391537139888;563.5378989228875;566.379814103556;577.8959787238254;3921;4060.309747342959;4107.835686572564;4134.751914885056;4164.271252530795;14409;14831.184284864436;14985.700285994219;15004.774928376082;15024.549263522113;14322.57032289033;14881.851181488262;14954.476593319181;14970.925145374204;14971.401143954337;8150.144234855255;9291.860622090675;9870.802583948282;10273.589056437659;10550.314229575053;5692.857678558069;6105.9694701526205;6770.984352347187;6974.825629359307;7056.964715176391;10503.789924201626;12453.688657783629;12456.948047728365;12717.236965782082;12732.427040783;12601.369931503204;12611.621651350475;12733.872661273268;12751;13008.414621341873;9217.216536594464;9365.484898330657;9394.81210375775;9480.099390557885;9494.525273736424;10393.688189354332;10397.32417392867;10528;10548.894511054792;10587.311824731369;8663.610137543952;8803.163699448542;8864.290856731317;8877;8910.064443233367;8627.568621569022;8657.307415406627;8662.393632445921;8687.91312086871;8757.299416046828;7232.710691572278;7462.216467270859;7553.395728341829;7590.240975902587;7603.505772124794;5118.74406279692;5785;5822.155787410717;5941.821745347646;5977;4931.3835770528685;5578.414266502121;5818.389069147678;5979.162917191509;6001.519878409629 15:48:45 INFO - PID 2607 | |4;prototype.html;321.747428268809;396.60735871487236;404.9655779258763;410.0405052177903;410.31272618364244;5758.939947337128;5777.624454410466;5779.884402311951;6041.818745437633;6349.4443861254;4559.452865656126;5353.571714262859;5965.373635768247;5992.520598352132;6112.083187521874;3880.553736320331;3888.6500214980647;4039.414284928691;4456.821727130911;4518.954810451886;4362.8909277268;4363.578214091178;4439.66702497314;4476.61948734358;4519.706219095749;3568.80371579563;3714.941241746103;4387.473503179607;4413.293872980324;4472.865814025585;3850.903727406809;4089.325261331877;4387.166438376698;4407.964128429817;4420.513743488214;3523.841427135759;3732.757370770891;3813.2945405099867;3817.1602247505502;3870.4968354114067;5004.049230646202;5485.588580856452;5842.912356314659;5891.293044834604;5947.405259474018;5328.360596728378;5333.546648534879;5346.304980352568;5354.357477102733;5407.83686525389;5066.771995260186;6291.842703932438;6449.258335291454;6473.255575608819;6477.935220647781;4311.762853043081;4319.935200971988;4340.435743353375;4344.326629372453;4364.781760911942;5555.583331250132;6563.278039415661;6644.468442524635;6790.962711186624;6884.449244060464;4416.205083085011;4446.177457170434;4450.866474005785;4453.77731113443;4475.4181956345465;4516.36770852081;4533.52397998212;4538.2738761797955;4539.954600454019;4550.567696068934;4652.534746525388;5175.7153356564995;5252.474752524717;5285.55072818807;5340.30576025122;5120.48795120485;6514.902276465857;6560.770373036921;6606.735730570819;6614.974678924678;1486.3162945045185;1503.150719843307;1515.8484151584973;1535.2247115206972;1540.2221877951563;982.2436723722693;997.2869398380047;1001.7796084861465;1006.9043440873105;1023.9283250172565;5506.724663766796;6023.759049638053;6121.602095863755;6136.2636483622255;6150.323464418911;6301.936980630135;6371.47084699678;6500.609963402211;6503.512236582275;6738.326167383222;5600.663960162403;6068.362821903725;6070.2412198475195;6078.088286757022;6135.754569817246;2845.5304874695858;2873.6695280042645;3105.1150422129585;3135.419947309755;3491.1970246843366;6532.281449040602;6574.838751580082;6699.665016749143;7041.5422997505;7043.436525077981;5694.629849059798;6448.290688024314;6892.758753443605;6941.479389045841;6988.371046605728;6386.489080873519;6744.696488658022;6761.290064543152;6967.825804354931;7026.894596581157;5360.517553420211;6153.969230154;6726.125603671491;6748.751480976035;6764.391204791593;5877.853053673693;6485.319041500575;6617.808794417051;6709.899351509634;6720.663966801738;5578.023845827045;6963.7214511421;7020.015788789812;7034.366906978396;7038.190608080136;4429.955700442954;4434.82260709568;4450.109978004347;4451.955480445284;4458.8439404620685;6350.968245158745;6669.433098186709;6729.596224226562;6762.188537375547;6845.760398386232;6344.206974128234;6451.000094985285;6606.933930660632;6634.104395906491;6674.16572928384;6350.364963503613;6499.902501462388;6601.33986601336;6621.1392518972225;6662.633555154319;6438.420542151229;6473.320301368289;6487.643179624977;6493.545451818327;6544.640044797582;6227.501799856092;6461.61230326182;6584.473242140713;6648.933510664831;6730.798076057726;6404.903926441014;6411.6794160290865;6446.742130314735;6468.579542329733;6474.967625161844 15:48:45 INFO - PID 2607 | |5;yui.html;752.2590248605123;775.6005657086602;824.8905222475768;833.4832403909575;837.3677873205729;15596.688066238683;15631.843681563196;16251.756223656655;16317.755233671474;16410.933289336186;12914.870851291484;14768.040077394962;14779.187144707037;16515.33938642453;17367;992.5930368548896;1024.9897501025007;1027.6557353286657;1030.4899074957868;1031.747221930628;860.2859626509982;968.1238479176357;1010.8028934357803;1013.5084484025242;1015.8120747661669;975.2344409638436;1042.5777560088177;1071.164491696478;1074.505727365413;1080.168270431767;689.6207086102648;761.6458346868677;778.8598052350575;784.4116912315764;795.9204079592052;570.090705325006;579.872428065829;587.3861814403958;590.0618017352431;590.6249531547498;11044.613438529614;13838.377273022637;14147.151170929777;14153.716925661533;17107.657846842994;15012.249387530688;15135.470258541009;15202.923985380114;15229.466968656045;15489;12678.302693351861;12996.545120920817;13134.73730525384;13167.2757998311;13212.735745285041;2866.3264132928743;3296.917577060569;3320.236345640516;3353.0946644406117;3356.0770788033487;92779.60830587546;111884.76230475426;121229;122481;123627;11405.543778248777;11427.714307142222;11453.484593193327;11793.823092653616;11985;20298.797012029692;25198.748012520005;25333.240002799575;25387.492250154894;25390.873045634653;6366.427021567988;7769.922300777033;7836.529808211411;7842.411819113589;7847.607619619111;4014.397840323975;4017.8995525111523;4088.550259471456;4138.937915931264;4157.189348077157;4080.591940805955;4168.937465938014;4170.145120250341;4175.749455032707;4176.62410383067;811.9675212991532;817.9672813087528;823.2343920154194;824.8432797768405;825.3562221467145;549.7141486427035;555.6499405374589;557.7732815661379;568.8720037991502;599.6941559804444;8886.289096872235;9291.767705807273;9355.719328420158;9378.671746488977;9405.90594094064;513.9537441630271;536.9704666243316;598.6348327520209;607.5230943709167;618.9164462797555;3530.91172720684;3909.667678247381;4245.257080010986;4375.212461756851;4511.300748384006;98184;98610.5069474648;99426.50860237102;100341;100557.49721251347;83502.58248708717;87775.56112219398;89564;90086.54956725174;90327.09672903319;62716;77733.99999999886;81145.59427202826;81642.18357816465;82503.58748206221;8256.463329883538;8984;9886.555105020147;9971.950140249251;9991.850122248317;37402.43896341503;38406.80796596111;38937.8053109744;39306;39352.606473934895;50974.490255098455;51531;51600;51622.48377516177;51887;12584.370781461073;12642.683932901751;12770.361481926053;12778.808317875053;12964.546240881524;14156.929215353857;14174.929125354307;14308.57074287773;14407.639809004859;14564.126152430888;12657.367131643565;12692.682682933;12761.808572871229;12932.870671293167;12946.546870859474;12738.235705857678;12789.872101278868;12917.868551689242;13149.34253287351;13274.07081504324;11873.109516786448;11881.881181188077;11886.16796824214;11920;12389.380530973234;9053.68312109073;9058.057818240077;9456.479893605918;9467.100625440573;9584.28117891175;9704.805903882025;10074.393832656298;10195.031472010147;10219.540120694437;10240.692779216635 15:48:45 INFO - PID 2607 | __end_tp_report 15:48:45 INFO - PID 2607 | __start_cc_report 15:48:45 INFO - PID 2607 | _x_x_mozilla_cycle_collect,427 15:48:45 INFO - PID 2607 | __end_cc_report 15:48:45 INFO - PID 2607 | __startTimestamp1491432525596__endTimestamp 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | ------- Summary: start ------- 15:48:45 INFO - PID 2607 | Number of tests: 6 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | [#0] dojo.html Cycles:165 Average:6054.29 Median:6313.31 stddev:725.84 (11.5%) stddev-sans-first:700.33 15:48:45 INFO - PID 2607 | Values: 3521.0 3533.2 3576.7 3601.1 3601.9 6130.6 6213.3 6229.7 6246.3 6266.1 6136.9 6171.4 6183.6 6205.9 6258.7 5437.2 5469.9 5479.6 5509.8 5571.3 5417.4 5518.4 5549.9 5569.5 5595.3 5368.4 5405.1 5480.1 5502.1 5513.4 5397.8 5419.8 5421.3 5461.9 5525.2 5264.0 5364.5 5370.1 5392.0 5417.7 6046.2 6086.8 6112.2 6133.4 6251.6 5556.2 6538.4 6645.6 6675.9 6676.0 6761.0 6773.1 6777.4 6782.8 6793.1 6084.4 6318.6 6329.5 6340.9 6346.0 5745.6 6733.3 6745.6 6776.6 6795.6 6261.5 6411.5 6427.8 6507.3 6583.6 5449.6 5457.2 6440.6 6626.8 6743.2 6638.4 6649.9 6652.1 6670.0 6670.2 5595.5 6654.4 6658.1 6680.7 6704.1 5200.4 5221.5 5244.7 5271.3 5309.5 4337.8 4740.0 4763.7 4772.1 4829.4 5901.3 6163.4 6212.0 6282.2 6336.3 6504.8 6596.1 6654.5 6682.2 6769.1 5487.9 6334.8 6334.9 6337.9 6338.6 4862.9 5838.0 5939.6 5950.9 5965.1 5770.3 6616.2 6857.6 6950.4 6963.2 5433.4 6471.0 6594.4 6750.9 6751.0 6850.3 6883.6 6890.5 6892.0 6896.1 6451.1 6509.1 6573.7 6586.9 6587.6 5969.8 6105.8 6577.0 6586.5 6697.4 6313.3 6529.6 6547.8 6556.7 6561.2 5142.5 6271.6 6323.1 6344.7 6521.3 4697.8 5124.9 6373.2 6381.2 6391.6 6282.7 6378.1 6383.7 6495.9 6528.6 6283.6 6314.2 6390.3 6397.3 6418.5 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | [#1] ext.html Cycles:180 Average:47740.25 Median:35734.23 stddev:88647.44 (248.1%) stddev-sans-first:88825.80 15:48:45 INFO - PID 2607 | Values: 712.8 774.0 802.9 1266.5 1274.3 36761.8 37971.6 37995.8 38012.4 38070.2 38064.0 38268.0 38389.0 38475.4 38573.8 7931.6 8069.9 8420.5 8625.3 8664.8 8638.3 8684.4 8762.6 8773.1 8782.0 8717.6 8753.9 8779.0 8790.0 8802.0 8692.7 8707.6 8729.3 8755.4 8759.5 8374.2 8400.6 8414.6 8421.7 8461.0 37049.8 37063.0 37196.8 37270.6 37295.6 3432.7 3466.9 3474.5 3475.4 3476.5 29382.9 29617.7 29907.9 29933.0 30081.0 2933.5 2979.5 2987.9 2991.5 3002.2 270493.3 271715.0 274756.0 275078.0 275248.0 46233.0 46299.8 46487.3 46577.8 46596.8 48912.5 49112.8 49186.0 49289.5 49450.8 12278.3 12357.6 12420.8 12614.4 12628.2 9210.8 9752.7 9796.4 9804.4 9812.9 13188.3 13203.9 13216.6 13248.7 13314.1 4980.3 5224.6 5919.3 5947.8 5948.8 958.1 972.2 982.7 986.3 1011.2 21167.6 23522.0 23556.5 23591.5 23617.4 948.0 1019.2 1056.5 1091.3 1101.4 10114.0 11345.4 12111.3 12163.4 12174.5 325239.0 382350.0 382877.0 386196.0 387015.0 51518.7 51822.0 51992.2 52253.5 52474.2 1451.3 1476.1 1486.0 1489.2 1505.5 28139.9 28274.0 29107.7 29124.9 29672.3 38559.8 43083.0 47728.8 49178.5 49407.5 350801.0 351447.0 353675.0 355078.0 355334.0 33929.2 33946.0 34214.7 34256.1 34289.9 41533.2 41628.6 42013.8 42162.4 42893.8 28423.9 29676.0 29759.8 30140.8 30207.7 28941.3 29793.7 29890.4 29966.7 30066.4 32801.3 35675.8 35733.6 35734.8 35886.6 29193.6 29655.3 29679.0 30220.6 30363.2 22556.2 23809.8 24133.4 24430.0 24741.9 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | [#2] jquery.html Cycles:180 Average:40066.27 Median:12875.61 stddev:112984.59 (877.5%) stddev-sans-first:113262.18 15:48:45 INFO - PID 2607 | Values: 348.9 418.1 424.3 424.8 452.3 20965.5 21195.4 21856.9 22870.0 23140.8 19514.5 22427.7 22758.5 23121.7 23378.4 6045.9 6352.4 6389.0 6408.6 6414.5 6050.8 6252.6 6346.0 6383.5 6406.2 6184.4 6355.2 6358.0 6362.1 6366.1 6328.2 6415.2 6465.1 6465.8 6469.3 5388.6 5591.8 5628.6 5716.0 5744.7 27158.5 27785.0 28189.0 28205.0 28562.3 2144.9 2207.0 2207.3 2208.8 2213.0 17428.6 17751.1 17981.3 18361.0 18466.0 2090.8 2129.1 2151.4 2152.9 2182.1 109325.0 119772.4 158915.2 159481.0 162734.0 12667.0 13117.2 13174.3 13189.7 13195.9 13774.4 16417.8 16634.3 16663.0 16668.0 8035.3 8143.6 8221.3 8335.3 8401.1 7333.6 7444.1 7534.6 7662.1 7670.0 7899.4 7900.8 7960.4 8012.3 8019.3 2187.8 2201.5 2202.8 2203.4 2372.8 804.0 882.6 900.6 909.9 949.5 20209.7 20579.6 20800.4 21051.6 21155.5 922.5 930.0 958.5 963.9 979.4 9913.5 10430.9 10891.8 10939.9 10965.1 603747.0 646675.0 679575.0 689384.0 728206.0 160958.0 164654.0 167294.0 168504.6 169970.2 1129.9 1133.2 1175.4 1177.1 1177.8 18017.5 18141.7 18658.8 21083.0 22900.2 64473.0 64901.7 65054.0 65125.0 65270.3 79735.0 86174.6 87060.6 87608.6 91039.0 8606.9 8679.5 8879.2 8917.6 8953.6 13972.5 14081.4 14111.6 14403.6 14472.3 11710.1 11807.4 11898.3 12051.5 12185.3 9689.9 9980.5 10920.8 11158.7 11233.9 11569.4 11675.4 11822.3 12097.8 12215.2 8675.6 8756.4 8926.3 8975.7 9049.7 11630.2 11644.7 11852.5 11937.2 12009.8 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | [#3] mootools.html Cycles:180 Average:7043.88 Median:2547.50 stddev:4443.42 (174.4%) stddev-sans-first:4435.51 15:48:45 INFO - PID 2607 | Values: 1381.6 1419.4 1435.1 1436.3 1452.7 11330.9 11385.5 11593.4 11932.9 11942.6 11684.3 11741.0 11895.3 11906.6 11920.6 2497.6 2539.3 2545.2 2546.7 2548.3 2461.4 2470.7 2511.3 2530.0 2532.5 2456.1 2486.6 2492.3 2506.7 2513.3 2124.0 2409.7 2454.1 2459.4 2490.3 2396.9 2444.7 2472.9 2499.9 2504.0 11639.0 11706.1 11708.4 11835.3 11863.5 6512.8 6701.3 6750.2 6777.6 6783.1 3463.8 3845.6 3999.4 4066.1 4138.9 1163.2 1163.7 1189.8 1211.9 1212.1 13444.9 15419.0 16406.0 16483.3 16576.0 5877.5 5911.9 6169.2 6187.2 6236.2 12726.6 12767.9 13550.7 13628.7 13646.9 6674.5 6713.6 6717.9 6726.0 6728.2 5362.7 5711.8 6063.9 6114.4 6154.7 4686.3 4769.7 4772.4 4809.6 4885.5 1436.6 1467.2 1475.4 1479.7 1498.9 729.6 732.5 754.0 759.4 787.1 6278.5 6433.3 6590.6 6629.8 6647.7 552.9 563.4 563.5 566.4 577.9 3921.0 4060.3 4107.8 4134.8 4164.3 14409.0 14831.2 14985.7 15004.8 15024.5 14322.6 14881.9 14954.5 14970.9 14971.4 8150.1 9291.9 9870.8 10273.6 10550.3 5692.9 6106.0 6771.0 6974.8 7057.0 10503.8 12453.7 12456.9 12717.2 12732.4 12601.4 12611.6 12733.9 12751.0 13008.4 9217.2 9365.5 9394.8 9480.1 9494.5 10393.7 10397.3 10528.0 10548.9 10587.3 8663.6 8803.2 8864.3 8877.0 8910.1 8627.6 8657.3 8662.4 8687.9 8757.3 7232.7 7462.2 7553.4 7590.2 7603.5 5118.7 5785.0 5822.2 5941.8 5977.0 4931.4 5578.4 5818.4 5979.2 6001.5 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | [#4] prototype.html Cycles:180 Average:5234.12 Median:5919.35 stddev:1676.08 (28.3%) stddev-sans-first:1639.73 15:48:45 INFO - PID 2607 | Values: 321.7 396.6 405.0 410.0 410.3 5758.9 5777.6 5779.9 6041.8 6349.4 4559.5 5353.6 5965.4 5992.5 6112.1 3880.6 3888.7 4039.4 4456.8 4519.0 4362.9 4363.6 4439.7 4476.6 4519.7 3568.8 3714.9 4387.5 4413.3 4472.9 3850.9 4089.3 4387.2 4408.0 4420.5 3523.8 3732.8 3813.3 3817.2 3870.5 5004.0 5485.6 5842.9 5891.3 5947.4 5328.4 5333.5 5346.3 5354.4 5407.8 5066.8 6291.8 6449.3 6473.3 6477.9 4311.8 4319.9 4340.4 4344.3 4364.8 5555.6 6563.3 6644.5 6791.0 6884.4 4416.2 4446.2 4450.9 4453.8 4475.4 4516.4 4533.5 4538.3 4540.0 4550.6 4652.5 5175.7 5252.5 5285.6 5340.3 5120.5 6514.9 6560.8 6606.7 6615.0 1486.3 1503.2 1515.8 1535.2 1540.2 982.2 997.3 1001.8 1006.9 1023.9 5506.7 6023.8 6121.6 6136.3 6150.3 6301.9 6371.5 6500.6 6503.5 6738.3 5600.7 6068.4 6070.2 6078.1 6135.8 2845.5 2873.7 3105.1 3135.4 3491.2 6532.3 6574.8 6699.7 7041.5 7043.4 5694.6 6448.3 6892.8 6941.5 6988.4 6386.5 6744.7 6761.3 6967.8 7026.9 5360.5 6154.0 6726.1 6748.8 6764.4 5877.9 6485.3 6617.8 6709.9 6720.7 5578.0 6963.7 7020.0 7034.4 7038.2 4430.0 4434.8 4450.1 4452.0 4458.8 6351.0 6669.4 6729.6 6762.2 6845.8 6344.2 6451.0 6606.9 6634.1 6674.2 6350.4 6499.9 6601.3 6621.1 6662.6 6438.4 6473.3 6487.6 6493.5 6544.6 6227.5 6461.6 6584.5 6648.9 6730.8 6404.9 6411.7 6446.7 6468.6 6475.0 15:48:45 INFO - PID 2607 | 15:48:45 INFO - PID 2607 | [#5] yui.html Cycles:180 Average:19783.02 Median:21607.83 stddev:29010.43 (134.3%) stddev-sans-first:29056.62 15:48:45 INFO - PID 2607 | Values: 752.3 775.6 824.9 833.5 837.4 15596.7 15631.8 16251.8 16317.8 16410.9 12914.9 14768.0 14779.2 16515.3 17367.0 992.6 1025.0 1027.7 1030.5 1031.7 860.3 968.1 1010.8 1013.5 1015.8 975.2 1042.6 1071.2 1074.5 1080.2 689.6 761.6 778.9 784.4 795.9 570.1 579.9 587.4 590.1 590.6 11044.6 13838.4 14147.2 14153.7 17107.7 15012.2 15135.5 15202.9 15229.5 15489.0 12678.3 12996.5 13134.7 13167.3 13212.7 2866.3 3296.9 3320.2 3353.1 3356.1 92779.6 111884.8 121229.0 122481.0 123627.0 11405.5 11427.7 11453.5 11793.8 11985.0 20298.8 25198.7 25333.2 25387.5 25390.9 6366.4 7769.9 7836.5 7842.4 7847.6 4014.4 4017.9 4088.6 4138.9 4157.2 4080.6 4168.9 4170.1 4175.7 4176.6 812.0 818.0 823.2 824.8 825.4 549.7 555.6 557.8 568.9 599.7 8886.3 9291.8 9355.7 9378.7 9405.9 514.0 537.0 598.6 607.5 618.9 3530.9 3909.7 4245.3 4375.2 4511.3 98184.0 98610.5 99426.5 100341.0 100557.5 83502.6 87775.6 89564.0 90086.5 90327.1 62716.0 77734.0 81145.6 81642.2 82503.6 8256.5 8984.0 9886.6 9972.0 9991.9 37402.4 38406.8 38937.8 39306.0 39352.6 50974.5 51531.0 51600.0 51622.5 51887.0 12584.4 12642.7 12770.4 12778.8 12964.5 14156.9 14174.9 14308.6 14407.6 14564.1 12657.4 12692.7 12761.8 12932.9 12946.5 12738.2 12789.9 12917.9 13149.3 13274.1 11873.1 11881.9 11886.2 11920.0 12389.4 9053.7 9058.1 9456.5 9467.1 9584.3 9704.8 10074.4 10195.0 10219.5 10240.7 15:48:45 INFO - PID 2607 | -------- Summary: end -------- 15:48:45 INFO - PID 2607 | 15:48:46 INFO - TEST-INFO | 2607: exit 0 15:48:46 INFO - TEST-OK | dromaeo_css | took 1117045ms 15:48:46 INFO - TEST-START | kraken 15:48:46 INFO - operating with platform_type : mac_ 15:48:46 INFO - Initialising browser for kraken test... 15:48:46 INFO - TEST-INFO | started process 2611 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpF8vgS7/profile http://localhost:49226/getInfo.html) 15:48:49 INFO - PID 2611 | 2017-04-05 15:48:49.414 plugin-container[2612:88493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 15:48:49 INFO - PID 2611 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:49 INFO - PID 2611 | 2017-04-05 15:48:49.417 plugin-container[2612:88493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 15:48:49 INFO - PID 2611 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:49 INFO - PID 2611 | 2017-04-05 15:48:49.417 plugin-container[2612:88493] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:48:49 INFO - PID 2611 | __metrics Screen width/height:1600/1200 15:48:49 INFO - PID 2611 | colorDepth:24 15:48:49 INFO - PID 2611 | Browser inner width/height: 1024/689 15:48:49 INFO - PID 2611 | __metrics 15:48:49 ERROR - PID 2611 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 15:48:50 INFO - TEST-INFO | 2611: exit 0 15:48:50 INFO - Browser initialized. 15:48:50 INFO - Running cycle 1/1 for kraken test... 15:48:50 INFO - TEST-INFO | started process 2613 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpF8vgS7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:53 INFO - PID 2613 | 2017-04-05 15:48:53.178 plugin-container[2614:88617] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 15:48:53 INFO - PID 2613 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:53 INFO - PID 2613 | 2017-04-05 15:48:53.180 plugin-container[2614:88617] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 15:48:53 INFO - PID 2613 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:53 INFO - PID 2613 | 2017-04-05 15:48:53.180 plugin-container[2614:88617] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:49:02 INFO - PID 2613 | RSS: Main: 235122688 15:49:02 INFO - PID 2613 | 15:49:06 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 15:49:07 INFO - PID 2613 | RSS: Main: 249880576 15:49:07 INFO - PID 2613 | 15:49:09 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 15:49:10 INFO - PID 2613 | RSS: Main: 258326528 15:49:10 INFO - PID 2613 | 15:49:14 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 15:49:14 INFO - PID 2613 | RSS: Main: 259543040 15:49:14 INFO - PID 2613 | 15:49:16 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 15:49:17 INFO - PID 2613 | RSS: Main: 260669440 15:49:17 INFO - PID 2613 | 15:49:19 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:20 INFO - PID 2613 | RSS: Main: 261046272 15:49:20 INFO - PID 2613 | 15:49:23 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:23 INFO - PID 2613 | RSS: Main: 266489856 15:49:23 INFO - PID 2613 | 15:49:27 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:27 INFO - PID 2613 | RSS: Main: 267272192 15:49:27 INFO - PID 2613 | 15:49:31 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 15:49:32 INFO - PID 2613 | RSS: Main: 264388608 15:49:32 INFO - PID 2613 | 15:49:33 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:49:34 INFO - PID 2613 | RSS: Main: 266637312 15:49:34 INFO - PID 2613 | 15:49:36 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:49:36 INFO - PID 2613 | RSS: Main: 267005952 15:49:36 INFO - PID 2613 | 15:49:39 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:49:39 INFO - PID 2613 | RSS: Main: 267309056 15:49:39 INFO - PID 2613 | 15:49:42 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:49:43 INFO - PID 2613 | RSS: Main: 267886592 15:49:43 INFO - PID 2613 | 15:49:46 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:49:47 INFO - PID 2613 | RSS: Main: 267382784 15:49:47 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 15:49:49 INFO - PID 2613 | RSS: Main: 270602240 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | __start_tp_report 15:49:49 INFO - PID 2613 | _x_x_mozilla_page_load 15:49:49 INFO - PID 2613 | _x_x_mozilla_page_load_details 15:49:49 INFO - PID 2613 | |i|pagename|runs| 15:49:49 INFO - PID 2613 | |0;ai-astar;100;181;182;158;123;123;135;139;179;137 15:49:49 INFO - PID 2613 | |1;audio-beat-detection;156;117;111;151;126;154;146;119;178;152 15:49:49 INFO - PID 2613 | |2;audio-dft;219;226;259;270;314;226;211;212;216;298 15:49:49 INFO - PID 2613 | |3;audio-fft;88;61;58;93;63;63;65;89;67;51 15:49:49 INFO - PID 2613 | |4;audio-oscillator;89;72;87;73;73;72;111;107;108;118 15:49:49 INFO - PID 2613 | |5;imaging-gaussian-blur;104;92;93;90;91;91;91;92;91;93 15:49:49 INFO - PID 2613 | |6;imaging-darkroom;100;100;100;102;100;141;164;120;117;123 15:49:49 INFO - PID 2613 | |7;imaging-desaturate;102;149;137;102;103;141;113;158;164;102 15:49:49 INFO - PID 2613 | |8;json-parse-financial;51;52;50;52;52;55;52;52;58;52 15:49:49 INFO - PID 2613 | |9;json-stringify-tinderbox;58;62;65;66;65;52;69;84;89;88 15:49:49 INFO - PID 2613 | |10;stanford-crypto-aes;86;89;87;89;87;87;88;90;87;87 15:49:49 INFO - PID 2613 | |11;stanford-crypto-ccm;147;149;166;173;168;144;160;151;226;179 15:49:49 INFO - PID 2613 | |12;stanford-crypto-pbkdf2;195;195;194;198;193;196;206;230;199;231 15:49:49 INFO - PID 2613 | |13;stanford-crypto-sha256-iterative;70;77;71;71;71;70;68;70;73;69 15:49:49 INFO - PID 2613 | __end_tp_report 15:49:49 INFO - PID 2613 | __start_cc_report 15:49:49 INFO - PID 2613 | _x_x_mozilla_cycle_collect,995 15:49:49 INFO - PID 2613 | __end_cc_report 15:49:49 INFO - PID 2613 | __startTimestamp1491432589350__endTimestamp 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | ------- Summary: start ------- 15:49:49 INFO - PID 2613 | Number of tests: 14 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#0] ai-astar Cycles:10 Average:145.70 Median:148.50 stddev:28.24 (19.0%) stddev-sans-first:24.64 15:49:49 INFO - PID 2613 | Values: 100.0 181.0 182.0 158.0 123.0 123.0 135.0 139.0 179.0 137.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:151.50 stddev:21.59 (14.2%) stddev-sans-first:22.20 15:49:49 INFO - PID 2613 | Values: 156.0 117.0 111.0 151.0 126.0 154.0 146.0 119.0 178.0 152.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#2] audio-dft Cycles:10 Average:245.10 Median:242.50 stddev:37.82 (15.6%) stddev-sans-first:38.92 15:49:49 INFO - PID 2613 | Values: 219.0 226.0 259.0 270.0 314.0 226.0 211.0 212.0 216.0 298.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#3] audio-fft Cycles:10 Average:69.80 Median:66.00 stddev:14.65 (22.2%) stddev-sans-first:13.98 15:49:49 INFO - PID 2613 | Values: 88.0 61.0 58.0 93.0 63.0 63.0 65.0 89.0 67.0 51.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:72.50 stddev:18.45 (25.4%) stddev-sans-first:19.56 15:49:49 INFO - PID 2613 | Values: 89.0 72.0 87.0 73.0 73.0 72.0 111.0 107.0 108.0 118.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:91.50 stddev:4.05 (4.4%) stddev-sans-first:1.01 15:49:49 INFO - PID 2613 | Values: 104.0 92.0 93.0 90.0 91.0 91.0 91.0 92.0 91.0 93.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#6] imaging-darkroom Cycles:10 Average:116.70 Median:118.50 stddev:21.63 (18.3%) stddev-sans-first:22.08 15:49:49 INFO - PID 2613 | Values: 100.0 100.0 100.0 102.0 100.0 141.0 164.0 120.0 117.0 123.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#7] imaging-desaturate Cycles:10 Average:127.10 Median:139.00 stddev:25.29 (18.2%) stddev-sans-first:25.14 15:49:49 INFO - PID 2613 | Values: 102.0 149.0 137.0 102.0 103.0 141.0 113.0 158.0 164.0 102.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.00 stddev:2.27 (4.4%) stddev-sans-first:2.33 15:49:49 INFO - PID 2613 | Values: 51.0 52.0 50.0 52.0 52.0 55.0 52.0 52.0 58.0 52.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:67.50 stddev:12.82 (19.0%) stddev-sans-first:12.87 15:49:49 INFO - PID 2613 | Values: 58.0 62.0 65.0 66.0 65.0 52.0 69.0 84.0 89.0 88.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.50 stddev:1.25 (1.4%) stddev-sans-first:1.17 15:49:49 INFO - PID 2613 | Values: 86.0 89.0 87.0 89.0 87.0 87.0 88.0 90.0 87.0 87.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#11] stanford-crypto-ccm Cycles:10 Average:166.30 Median:167.00 stddev:24.07 (14.4%) stddev-sans-first:24.50 15:49:49 INFO - PID 2613 | Values: 147.0 149.0 166.0 173.0 168.0 144.0 160.0 151.0 226.0 179.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.70 Median:198.50 stddev:14.59 (7.4%) stddev-sans-first:15.13 15:49:49 INFO - PID 2613 | Values: 195.0 195.0 194.0 198.0 193.0 196.0 206.0 230.0 199.0 231.0 15:49:49 INFO - PID 2613 | 15:49:49 INFO - PID 2613 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.00 stddev:2.49 (3.5%) stddev-sans-first:2.62 15:49:49 INFO - PID 2613 | Values: 70.0 77.0 71.0 71.0 71.0 70.0 68.0 70.0 73.0 69.0 15:49:49 INFO - PID 2613 | -------- Summary: end -------- 15:49:49 INFO - PID 2613 | 15:49:50 INFO - TEST-INFO | 2613: exit 0 15:49:50 INFO - TEST-OK | kraken | took 64211ms 15:49:50 INFO - SUITE-END | took 1181s 15:49:50 INFO - Completed test suite (00:19:41) 15:49:50 INFO - javascript benchmark 15:49:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7663.399360584959, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3520.999999999998, 3533.1873669056117, 3576.713862890969, 3601.1357274254187, 3601.873934412295, 6130.581587307925, 6213.347598502154, 6229.688515574226, 6246.250449946007, 6266.060090986354, 6136.856084434195, 6171.382861713826, 6183.598066125699, 6205.875882482348, 6258.718357673904, 5437.184422336662, 5469.890602187954, 5479.561635069185, 5509.779608815643, 5571.275734154554, 5417.3770016448025, 5518.392976772552, 5549.861253468655, 5569.526590239834, 5595.328560572717, 5368.4363141869935, 5405.081136206854, 5480.068388373986, 5502.147167189092, 5513.448655134494, 5397.8380648580605, 5419.810306639249, 5421.268128802601, 5461.9453805461835, 5525.226468294442, 5263.99984003042, 5364.517193452569, 5370.140777475585, 5391.973040134813, 5417.674939503642, 6046.244219472566, 6086.786962456337, 6112.199536562771, 6133.4264757247465, 6251.593646412968, 5556.194351818964, 6538.411542961108, 6645.60126392418, 6675.866482670368, 6675.966620166917, 6761.0, 6773.119494465688, 6777.423918966893, 6782.8304292392595, 6793.082933803923, 6084.421979911901, 6318.62088274705, 6329.461995730322, 6340.873182536324, 6345.968270158666, 5745.569082318842, 6733.3266673333255, 6745.5952642841585, 6776.627285499247, 6795.558288711218, 6261.467775239064, 6411.519136064814, 6427.807165785034, 6507.34926507355, 6583.637899915455, 5449.618526703092, 5457.20870473787, 6440.6135631862235, 6626.83432914181, 6743.1908171019795, 6638.402543770987, 6649.933500665028, 6652.135222421054, 6670.0, 6670.199576050905, 5595.496405323541, 6654.367835055662, 6658.1011563438315, 6680.699368528428, 6704.061431399608, 5200.427952925175, 5221.503957124067, 5244.711540865213, 5271.341082364705, 5309.469053094737, 4337.785420082382, 4740.0, 4763.7141771493125, 4772.093302272557, 4829.372181616367, 5901.262342207224, 6163.3836616338, 6211.9689401552705, 6282.151909492251, 6336.334684858116, 6504.772332968323, 6596.109525214035, 6654.500912431587, 6682.231543372477, 6769.086173366532, 5487.917681234785, 6334.778282760173, 6334.923063079287, 6337.8732425352155, 6338.58799178052, 4862.878428039327, 5838.036723940587, 5939.643621382731, 5950.9107363389585, 5965.075413310944, 5770.336411312669, 6616.239132499824, 6857.588544687334, 6950.443964482829, 6963.234044255027, 5433.429489903503, 6470.967645161744, 6594.439472644879, 6750.919852823525, 6750.966245168743, 6850.280720524473, 6883.586984780929, 6890.517663763489, 6891.965540172267, 6896.069030680794, 6451.1290975717675, 6509.088727578238, 6573.671316434255, 6586.934130658632, 6587.571807832475, 5969.820905372846, 6105.823680184288, 6576.980568011966, 6586.473082153512, 6697.3637504437, 6313.305536390902, 6529.608223506605, 6547.836304092436, 6556.737730490918, 6561.212654481493, 5142.485751424828, 6271.592346497463, 6323.114763933145, 6344.651044192429, 6521.282658907421, 4697.7651117444675, 5124.871878203075, 6373.171487706569, 6381.202349706286, 6391.616503009835, 6282.685865706605, 6378.107065010995, 6383.744650214126, 6495.870082598227, 6528.608283503005, 6283.59156654816, 6314.242290925118, 6390.265119511129, 6397.296297407189, 6418.454431373386], "value": 6010.765351725514, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.8039789058008, 773.9613019349033, 802.9237222463868, 1266.4934026389449, 1274.2927675140293, 36761.81619091904, 37971.62028379722, 37995.81002094986, 38012.429813552786, 38070.23859522811, 38064.0, 38268.04329891747, 38389.0, 38475.422868656926, 38573.80713096438, 7931.563763992978, 8069.878951815727, 8420.4947703138, 8625.26685231756, 8664.826703465927, 8638.265747411477, 8684.392092553524, 8762.60568274426, 8773.12268773124, 8781.95609021954, 8717.564121793885, 8753.868691969625, 8779.034306226342, 8789.956050219773, 8802.03177650458, 8692.652293908237, 8707.60815763289, 8729.258013068897, 8755.430896991675, 8759.51822649754, 8374.204450577185, 8400.621972011275, 8414.621342039623, 8421.663133474654, 8461.026981897037, 37049.814750926074, 37063.0, 37196.81401593002, 37270.62729372699, 37295.62704372949, 3432.6567343265724, 3466.861325546975, 3474.5483087198754, 3475.391806433864, 3476.4785282207617, 29382.85308573465, 29617.70382296171, 29907.850460747555, 29932.95234666798, 30081.0, 2933.5306350983956, 2979.478591246545, 2987.895423660183, 2991.5213565829363, 3002.24943764059, 270493.2950670488, 271715.0, 274756.0, 275078.0, 275248.0, 46233.0, 46299.768501157276, 46487.30269045967, 46577.7671111649, 46596.767016164704, 48912.510874890795, 49112.75443622759, 49186.0, 49289.52131436062, 49450.75274623604, 12278.324692142018, 12357.629271121881, 12420.81368779469, 12614.369281535886, 12628.242305461701, 9210.81578368429, 9752.658656946973, 9796.412215267106, 9804.362716423553, 9812.901870981199, 13188.27464489443, 13203.867961320457, 13216.603501894959, 13248.73502529944, 13314.06801523884, 4980.252962055721, 5224.582033437316, 5919.260092488439, 5947.821565353046, 5948.791792287249, 958.1281034258791, 972.1882228339274, 982.7346616413529, 986.3490096536395, 1011.1960991012163, 21167.576648466944, 23522.0, 23556.52886942286, 23591.51415969826, 23617.409564760674, 947.9810403791886, 1019.179560453827, 1056.4612047855496, 1091.3015669971226, 1101.3832253937821, 10114.039166279192, 11345.432728363548, 12111.273323600612, 12163.391830408444, 12174.513019479298, 325239.0, 382350.0, 382877.0, 386196.0, 387015.0, 51518.742406287725, 51822.0, 51992.22011669828, 52253.477465225624, 52474.21288680673, 1451.332387101945, 1476.0922032949873, 1486.0266525425864, 1489.2107183192925, 1505.4881340344336, 28139.859300703367, 28274.0, 29107.7089229105, 29124.854375727984, 29672.258193545335, 38559.80720096494, 43083.0, 47728.761356193, 49178.50821491739, 49407.50592494029, 350801.0, 351447.0, 353675.0, 355078.0, 355334.0, 33929.151771205914, 33946.0, 34214.657853421144, 34256.14359640929, 34289.94260344387, 41533.169336613704, 41628.58371416247, 42013.78993105015, 42162.36756448595, 42893.78553107214, 28423.857880710464, 29676.0, 29759.809607615454, 30140.84929575426, 30207.69792302137, 28941.276468087624, 29793.702062979093, 29890.402191955603, 29966.70033299639, 30066.398672026873, 32801.34397312088, 35675.75134870267, 35733.64266357303, 35734.8213258932, 35886.64113358833, 29193.562096568145, 29655.25861853471, 29679.0, 30220.64007119641, 30363.2409189772, 22556.210532631278, 23809.761902380753, 24133.396665083514, 24430.0, 24741.87629061843], "value": 17432.290221352196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.87440521412293, 418.10108267225473, 424.2681374628764, 424.77911486027267, 452.3237759549472, 20965.47586310341, 21195.364139075835, 21856.89071554642, 22870.0, 23140.80162384575, 19514.512137196576, 22427.663585046197, 22758.544829103448, 23121.653175202384, 23378.415539611517, 6045.909311360333, 6352.364763523657, 6388.968055159741, 6408.551401401904, 6414.454771344418, 6050.818475445743, 6252.593581417216, 6346.0, 6383.457406120486, 6406.167198264219, 6184.443400093991, 6355.2373715154245, 6358.0, 6362.141110950032, 6366.108744775735, 6328.208973878265, 6415.166028416323, 6465.127207826978, 6465.773697920574, 6469.320721324288, 5388.622796404254, 5591.832245032656, 5628.634138781007, 5715.971420142914, 5744.712764361765, 27158.45683086347, 27785.0, 28189.01338453144, 28205.012824551042, 28562.285942851595, 2144.892755362241, 2207.0178770447155, 2207.2826331442348, 2208.8232941364813, 2212.982028266983, 17428.564285892826, 17751.11244437773, 17981.280748770034, 18361.0, 18466.0, 2090.7595626502994, 2129.073852874005, 2151.440625437382, 2152.9031193596325, 2182.127149140341, 109325.0, 119772.40113799376, 158915.2054239733, 159481.0, 162734.0, 12667.0, 13117.212967221998, 13174.341282935815, 13189.73620527584, 13195.934020330029, 13774.449022039005, 16417.83582164187, 16634.33462661504, 16663.00021998684, 16667.999920004837, 8035.276825085653, 8143.615585350504, 8221.301189398968, 8335.333173346118, 8401.117882622237, 7333.559986400833, 7444.143923448873, 7534.571769844708, 7662.118856331482, 7670.002899623013, 7899.447038707235, 7900.84198316042, 7960.40296977729, 8012.318952888953, 8019.278264956182, 2187.8359123065516, 2201.4573167990675, 2202.757696653367, 2203.4381232785536, 2372.7983121434872, 803.9638216280281, 882.6381183714647, 900.5542256583034, 909.8908131024187, 949.5110018340561, 20209.696854547194, 20579.58840823205, 20800.375988720363, 21051.634202048514, 21155.471113221985, 922.4557511068367, 929.9953500232591, 958.4680502321139, 963.9325247232766, 979.3536266064364, 9913.504324783731, 10430.895691043144, 10891.83662245067, 10939.945300273448, 10965.067969222546, 603747.0, 646675.0, 679575.0, 689384.0, 728206.0, 160958.0, 164654.0, 167294.0, 168504.62990740116, 169970.15014925093, 1129.9378534180535, 1133.1557989298026, 1175.3594291111365, 1177.12892459581, 1177.8174382970653, 18017.549561261072, 18141.727874082164, 18658.813411865707, 21083.0, 22900.198493052663, 64473.0, 64901.70196595947, 65054.0, 65125.0, 65270.34729652643, 79735.0, 86174.56912715397, 87060.5646971761, 87608.56195719236, 91039.0, 8606.870896936676, 8679.522626255617, 8879.200871921557, 8917.554122293988, 8953.59708813118, 13972.510962116683, 14081.436742530594, 14111.576652700436, 14403.567892963229, 14472.276386180438, 11710.1217408693, 11807.409629518661, 11898.345590992578, 12051.51793928233, 12185.268883866642, 9689.854652180364, 9980.451075190644, 10920.836187457353, 11158.665240042812, 11233.887661123612, 11569.36368499707, 11675.42381778483, 11822.34977076269, 12097.818532722193, 12215.206011609573, 8675.609597567998, 8756.430831996155, 8926.330525210506, 8975.685850995184, 9049.683261085831, 11630.185886987829, 11644.6506604802, 11852.466639001093, 11937.16439849237, 12009.759804804029], "value": 10577.042859804747, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.5648070857678, 1419.4038503828397, 1435.13891665001, 1436.2890369267213, 1452.6586252230727, 11330.886691133106, 11385.487653055612, 11593.420328983539, 11932.940335298323, 11942.641720748392, 11684.298942063482, 11741.0, 11895.286282823037, 11906.642800715992, 11920.582779602715, 2497.6378425128346, 2539.263613552068, 2545.172818833877, 2546.6943966723925, 2548.3119557719406, 2461.4092617771694, 2470.6788117544693, 2511.309389917778, 2529.9747002529925, 2532.4555220627562, 2456.1280745335416, 2486.5772818620876, 2492.314613481289, 2506.711728151268, 2513.3088400689776, 2124.022949443258, 2409.6746939163163, 2454.0797201049604, 2459.3605662527693, 2490.3276115448907, 2396.880155992211, 2444.7310795812446, 2472.8763561822016, 2499.9250022499355, 2503.987480062606, 11639.0, 11706.063514918786, 11708.414579271088, 11835.289882606985, 11863.46614402354, 6512.804615861532, 6701.262861085277, 6750.1562304711915, 6777.559458635173, 6783.118194634715, 3463.8268086595567, 3845.61543845616, 3999.420084087791, 4066.1054567994993, 4138.875833724963, 1163.1973937982764, 1163.738158914246, 1189.8274750161265, 1211.9212251203733, 1212.0727643352843, 13444.932775336158, 15419.0, 16406.0, 16483.258253378626, 16576.0, 5877.52979761618, 5911.9113213301835, 6169.2288463942, 6187.195664563578, 6236.191504463745, 12726.618201453972, 12767.93616031914, 13550.728985420237, 13628.65928351799, 13646.863531364757, 6674.499412543982, 6713.597184168965, 6717.865642687118, 6726.0, 6728.192616886005, 5362.70505122222, 5711.829075039607, 6063.878722425615, 6114.357992410733, 6154.661493617894, 4686.250199967989, 4769.66612337133, 4772.379590653193, 4809.591184749265, 4885.5358740919555, 1436.5833908166724, 1467.200375795194, 1475.4319586959098, 1479.6522817137964, 1498.9400423983136, 729.5841370418916, 732.4543215304632, 753.9811504712426, 759.4038679636459, 787.0673252196166, 6278.466330361879, 6433.324500927429, 6590.5716128451495, 6629.801105966829, 6647.66761661915, 552.9115341545333, 563.391537139888, 563.5378989228875, 566.379814103556, 577.8959787238254, 3921.0, 4060.309747342959, 4107.835686572564, 4134.751914885056, 4164.271252530795, 14409.0, 14831.184284864436, 14985.700285994219, 15004.774928376082, 15024.549263522113, 14322.57032289033, 14881.851181488262, 14954.476593319181, 14970.925145374204, 14971.401143954337, 8150.144234855255, 9291.860622090675, 9870.802583948282, 10273.589056437659, 10550.314229575053, 5692.857678558069, 6105.9694701526205, 6770.984352347187, 6974.825629359307, 7056.964715176391, 10503.789924201626, 12453.688657783629, 12456.948047728365, 12717.236965782082, 12732.427040783, 12601.369931503204, 12611.621651350475, 12733.872661273268, 12751.0, 13008.414621341873, 9217.216536594464, 9365.484898330657, 9394.81210375775, 9480.099390557885, 9494.525273736424, 10393.688189354332, 10397.32417392867, 10528.0, 10548.894511054792, 10587.311824731369, 8663.610137543952, 8803.163699448542, 8864.290856731317, 8877.0, 8910.064443233367, 8627.568621569022, 8657.307415406627, 8662.393632445921, 8687.91312086871, 8757.299416046828, 7232.710691572278, 7462.216467270859, 7553.395728341829, 7590.240975902587, 7603.505772124794, 5118.74406279692, 5785.0, 5822.155787410717, 5941.821745347646, 5977.0, 4931.3835770528685, 5578.414266502121, 5818.389069147678, 5979.162917191509, 6001.519878409629], "value": 5280.792721981304, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.747428268809, 396.60735871487236, 404.9655779258763, 410.0405052177903, 410.31272618364244, 5758.939947337128, 5777.624454410466, 5779.884402311951, 6041.818745437633, 6349.4443861254, 4559.452865656126, 5353.571714262859, 5965.373635768247, 5992.520598352132, 6112.083187521874, 3880.553736320331, 3888.6500214980647, 4039.414284928691, 4456.821727130911, 4518.954810451886, 4362.8909277268, 4363.578214091178, 4439.66702497314, 4476.61948734358, 4519.706219095749, 3568.80371579563, 3714.941241746103, 4387.473503179607, 4413.293872980324, 4472.865814025585, 3850.903727406809, 4089.325261331877, 4387.166438376698, 4407.964128429817, 4420.513743488214, 3523.841427135759, 3732.757370770891, 3813.2945405099867, 3817.1602247505502, 3870.4968354114067, 5004.049230646202, 5485.588580856452, 5842.912356314659, 5891.293044834604, 5947.405259474018, 5328.360596728378, 5333.546648534879, 5346.304980352568, 5354.357477102733, 5407.83686525389, 5066.771995260186, 6291.842703932438, 6449.258335291454, 6473.255575608819, 6477.935220647781, 4311.762853043081, 4319.935200971988, 4340.435743353375, 4344.326629372453, 4364.781760911942, 5555.583331250132, 6563.278039415661, 6644.468442524635, 6790.962711186624, 6884.449244060464, 4416.205083085011, 4446.177457170434, 4450.866474005785, 4453.77731113443, 4475.4181956345465, 4516.36770852081, 4533.52397998212, 4538.2738761797955, 4539.954600454019, 4550.567696068934, 4652.534746525388, 5175.7153356564995, 5252.474752524717, 5285.55072818807, 5340.30576025122, 5120.48795120485, 6514.902276465857, 6560.770373036921, 6606.735730570819, 6614.974678924678, 1486.3162945045185, 1503.150719843307, 1515.8484151584973, 1535.2247115206972, 1540.2221877951563, 982.2436723722693, 997.2869398380047, 1001.7796084861465, 1006.9043440873105, 1023.9283250172565, 5506.724663766796, 6023.759049638053, 6121.602095863755, 6136.2636483622255, 6150.323464418911, 6301.936980630135, 6371.47084699678, 6500.609963402211, 6503.512236582275, 6738.326167383222, 5600.663960162403, 6068.362821903725, 6070.2412198475195, 6078.088286757022, 6135.754569817246, 2845.5304874695858, 2873.6695280042645, 3105.1150422129585, 3135.419947309755, 3491.1970246843366, 6532.281449040602, 6574.838751580082, 6699.665016749143, 7041.5422997505, 7043.436525077981, 5694.629849059798, 6448.290688024314, 6892.758753443605, 6941.479389045841, 6988.371046605728, 6386.489080873519, 6744.696488658022, 6761.290064543152, 6967.825804354931, 7026.894596581157, 5360.517553420211, 6153.969230154, 6726.125603671491, 6748.751480976035, 6764.391204791593, 5877.853053673693, 6485.319041500575, 6617.808794417051, 6709.899351509634, 6720.663966801738, 5578.023845827045, 6963.7214511421, 7020.015788789812, 7034.366906978396, 7038.190608080136, 4429.955700442954, 4434.82260709568, 4450.109978004347, 4451.955480445284, 4458.8439404620685, 6350.968245158745, 6669.433098186709, 6729.596224226562, 6762.188537375547, 6845.760398386232, 6344.206974128234, 6451.000094985285, 6606.933930660632, 6634.104395906491, 6674.16572928384, 6350.364963503613, 6499.902501462388, 6601.33986601336, 6621.1392518972225, 6662.633555154319, 6438.420542151229, 6473.320301368289, 6487.643179624977, 6493.545451818327, 6544.640044797582, 6227.501799856092, 6461.61230326182, 6584.473242140713, 6648.933510664831, 6730.798076057726, 6404.903926441014, 6411.6794160290865, 6446.742130314735, 6468.579542329733, 6474.967625161844], "value": 4709.3067732035925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.2590248605123, 775.6005657086602, 824.8905222475768, 833.4832403909575, 837.3677873205729, 15596.688066238683, 15631.843681563196, 16251.756223656655, 16317.755233671474, 16410.933289336186, 12914.870851291484, 14768.040077394962, 14779.187144707037, 16515.33938642453, 17367.0, 992.5930368548896, 1024.9897501025007, 1027.6557353286657, 1030.4899074957868, 1031.747221930628, 860.2859626509982, 968.1238479176357, 1010.8028934357803, 1013.5084484025242, 1015.8120747661669, 975.2344409638436, 1042.5777560088177, 1071.164491696478, 1074.505727365413, 1080.168270431767, 689.6207086102648, 761.6458346868677, 778.8598052350575, 784.4116912315764, 795.9204079592052, 570.090705325006, 579.872428065829, 587.3861814403958, 590.0618017352431, 590.6249531547498, 11044.613438529614, 13838.377273022637, 14147.151170929777, 14153.716925661533, 17107.657846842994, 15012.249387530688, 15135.470258541009, 15202.923985380114, 15229.466968656045, 15489.0, 12678.302693351861, 12996.545120920817, 13134.73730525384, 13167.2757998311, 13212.735745285041, 2866.3264132928743, 3296.917577060569, 3320.236345640516, 3353.0946644406117, 3356.0770788033487, 92779.60830587546, 111884.76230475426, 121229.0, 122481.0, 123627.0, 11405.543778248777, 11427.714307142222, 11453.484593193327, 11793.823092653616, 11985.0, 20298.797012029692, 25198.748012520005, 25333.240002799575, 25387.492250154894, 25390.873045634653, 6366.427021567988, 7769.922300777033, 7836.529808211411, 7842.411819113589, 7847.607619619111, 4014.397840323975, 4017.8995525111523, 4088.550259471456, 4138.937915931264, 4157.189348077157, 4080.591940805955, 4168.937465938014, 4170.145120250341, 4175.749455032707, 4176.62410383067, 811.9675212991532, 817.9672813087528, 823.2343920154194, 824.8432797768405, 825.3562221467145, 549.7141486427035, 555.6499405374589, 557.7732815661379, 568.8720037991502, 599.6941559804444, 8886.289096872235, 9291.767705807273, 9355.719328420158, 9378.671746488977, 9405.90594094064, 513.9537441630271, 536.9704666243316, 598.6348327520209, 607.5230943709167, 618.9164462797555, 3530.91172720684, 3909.667678247381, 4245.257080010986, 4375.212461756851, 4511.300748384006, 98184.0, 98610.5069474648, 99426.50860237102, 100341.0, 100557.49721251347, 83502.58248708717, 87775.56112219398, 89564.0, 90086.54956725174, 90327.09672903319, 62716.0, 77733.99999999886, 81145.59427202826, 81642.18357816465, 82503.58748206221, 8256.463329883538, 8984.0, 9886.555105020147, 9971.950140249251, 9991.850122248317, 37402.43896341503, 38406.80796596111, 38937.8053109744, 39306.0, 39352.606473934895, 50974.490255098455, 51531.0, 51600.0, 51622.48377516177, 51887.0, 12584.370781461073, 12642.683932901751, 12770.361481926053, 12778.808317875053, 12964.546240881524, 14156.929215353857, 14174.929125354307, 14308.57074287773, 14407.639809004859, 14564.126152430888, 12657.367131643565, 12692.682682933, 12761.808572871229, 12932.870671293167, 12946.546870859474, 12738.235705857678, 12789.872101278868, 12917.868551689242, 13149.34253287351, 13274.07081504324, 11873.109516786448, 11881.881181188077, 11886.16796824214, 11920.0, 12389.380530973234, 9053.68312109073, 9058.057818240077, 9456.479893605918, 9467.100625440573, 9584.28117891175, 9704.805903882025, 10074.393832656298, 10195.031472010147, 10219.540120694437, 10240.692779216635], "value": 7348.393579910213, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1680.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 181.0, 182.0, 158.0, 123.0, 123.0, 135.0, 139.0, 179.0, 137.0], "value": 145.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 117.0, 111.0, 151.0, 126.0, 154.0, 146.0, 119.0, 178.0, 152.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 226.0, 259.0, 270.0, 314.0, 226.0, 211.0, 212.0, 216.0, 298.0], "value": 245.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 61.0, 58.0, 93.0, 63.0, 63.0, 65.0, 89.0, 67.0, 51.0], "value": 69.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 87.0, 73.0, 73.0, 72.0, 111.0, 107.0, 108.0, 118.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 92.0, 93.0, 90.0, 91.0, 91.0, 91.0, 92.0, 91.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 100.0, 102.0, 100.0, 141.0, 164.0, 120.0, 117.0, 123.0], "value": 116.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 149.0, 137.0, 102.0, 103.0, 141.0, 113.0, 158.0, 164.0, 102.0], "value": 127.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 50.0, 52.0, 52.0, 55.0, 52.0, 52.0, 58.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 65.0, 66.0, 65.0, 52.0, 69.0, 84.0, 89.0, 88.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 87.0, 89.0, 87.0, 87.0, 88.0, 90.0, 87.0, 87.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 166.0, 173.0, 168.0, 144.0, 160.0, 151.0, 226.0, 179.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 195.0, 194.0, 198.0, 193.0, 196.0, 206.0, 230.0, 199.0, 231.0], "value": 203.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 71.0, 71.0, 71.0, 70.0, 68.0, 70.0, 73.0, 69.0], "value": 71.0, "unit": "score"}]}]} 15:49:50 INFO - Return code: 0 15:49:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:49:50 ERROR - # TBPL SUCCESS # 15:49:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:49:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:49:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:49:50 INFO - 2017-04-05 15:49:50.698 screenresolution[2618:89007] starting screenresolution argv=screenresolution get 15:49:50 INFO - 2017-04-05 15:49:50.701 screenresolution[2618:89007] Display 0: 1600x1200x32@60 15:49:50 INFO - 2017-04-05 15:49:50.707 screenresolution[2619:89010] starting screenresolution argv=screenresolution list 15:49:50 INFO - Available Modes on Display 0 15:49:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:49:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:49:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:49:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:49:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:49:50 INFO - Intel Iris: 15:49:50 INFO - Chipset Model: Intel Iris 15:49:50 INFO - Type: GPU 15:49:50 INFO - Bus: Built-In 15:49:50 INFO - VRAM (Dynamic, Max): 1536 MB 15:49:50 INFO - Vendor: Intel (0x8086) 15:49:50 INFO - Device ID: 0x0a2e 15:49:50 INFO - Revision ID: 0x0009 15:49:50 INFO - Displays: 15:49:50 INFO - Display: 15:49:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:49:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:49:50 INFO - Display Serial Number: 1600x1200 60 15:49:50 INFO - Main Display: Yes 15:49:50 INFO - Mirror: Off 15:49:50 INFO - Online: Yes 15:49:50 INFO - Rotation: Supported 15:49:50 INFO - Return code: 0 15:49:50 INFO - Running post-action listener: _resource_record_post_action 15:49:50 INFO - [mozharness: 2017-04-05 22:49:50.955059Z] Finished run-tests step (success) 15:49:50 INFO - Running post-run listener: _resource_record_post_run 15:49:51 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 49152; Write bytes: 516738048; Read time: 1; Write time: 1653 15:49:51 INFO - TinderboxPrint: CPU usage
54.3% 15:49:51 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 1 15:49:51 INFO - TinderboxPrint: I/O write bytes / time
516,738,048 / 1,653 15:49:51 INFO - TinderboxPrint: CPU idle
2,198.0 (45.7%) 15:49:51 INFO - TinderboxPrint: CPU system
969.4 (20.2%) 15:49:51 INFO - TinderboxPrint: CPU user
1,638.4 (34.1%) 15:49:51 INFO - TinderboxPrint: Swap in / out
206,413,824 / 0 15:49:51 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207938048; Write bytes: 210413568; Read time: 16012; Write time: 478 15:49:51 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 49152; Write bytes: 305058816; Read time: 1; Write time: 1164 15:49:51 INFO - Running post-run listener: _upload_blobber_files 15:49:51 INFO - Blob upload gear active. 15:49:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:51 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 15:49:51 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'] 15:49:51 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 15:49:51 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:49:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:49:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:52 INFO - (blobuploader) - INFO - Done attempting. 15:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:49:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:53 INFO - (blobuploader) - INFO - Done attempting. 15:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:49:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:54 INFO - (blobuploader) - INFO - Done attempting. 15:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:56 INFO - (blobuploader) - INFO - Done attempting. 15:49:56 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:56 INFO - Return code: 0 15:49:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30635cf7a9f684373fe4e07ead4ef3fca1bebaac2ea278f7036ff42a6ac6f41324ba698dbe0c147a4c4b5e621cbe2472725ba1147865eec4d2ba4bce39990c21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ffbc1e69d9f15795cbd2b62edbdaf19c6788970e010b791f15f191d782380a323980ccf0449d76620644f4e26f790e7831bc8647106ffe5694ed6940a6336c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3148af289baee56d7a8ce6dbe2145cdf30cda69a3de293fead38d6d2e968826c8e5e15deb1919b8b9c67a3e90d829079abf91cd33cb15f54bba3139814b6f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a5b9cb83935e5ed5b25e5fad98798e547b22e0826aacea4941af8f6e67a68c35d2f5639c4a9eb549270777f3c73c466ed78a3f9acfcf9698c66bc795aecb5b"} 15:49:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:56 INFO - Contents: 15:49:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30635cf7a9f684373fe4e07ead4ef3fca1bebaac2ea278f7036ff42a6ac6f41324ba698dbe0c147a4c4b5e621cbe2472725ba1147865eec4d2ba4bce39990c21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ffbc1e69d9f15795cbd2b62edbdaf19c6788970e010b791f15f191d782380a323980ccf0449d76620644f4e26f790e7831bc8647106ffe5694ed6940a6336c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3148af289baee56d7a8ce6dbe2145cdf30cda69a3de293fead38d6d2e968826c8e5e15deb1919b8b9c67a3e90d829079abf91cd33cb15f54bba3139814b6f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a5b9cb83935e5ed5b25e5fad98798e547b22e0826aacea4941af8f6e67a68c35d2f5639c4a9eb549270777f3c73c466ed78a3f9acfcf9698c66bc795aecb5b"} 15:49:56 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:56 INFO - Copying logs to upload dir... 15:49:56 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.500668 ========= master_lag: 3.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-05 15:49:59.477824) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2017-04-05 15:49:59.481641) ========= 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.qMixxoWH19/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.9V7MNm1p6T/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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/30635cf7a9f684373fe4e07ead4ef3fca1bebaac2ea278f7036ff42a6ac6f41324ba698dbe0c147a4c4b5e621cbe2472725ba1147865eec4d2ba4bce39990c21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ffbc1e69d9f15795cbd2b62edbdaf19c6788970e010b791f15f191d782380a323980ccf0449d76620644f4e26f790e7831bc8647106ffe5694ed6940a6336c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3148af289baee56d7a8ce6dbe2145cdf30cda69a3de293fead38d6d2e968826c8e5e15deb1919b8b9c67a3e90d829079abf91cd33cb15f54bba3139814b6f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a5b9cb83935e5ed5b25e5fad98798e547b22e0826aacea4941af8f6e67a68c35d2f5639c4a9eb549270777f3c73c466ed78a3f9acfcf9698c66bc795aecb5b"} build_url:https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011136 build_url: 'https://queue.taskcluster.net/v1/task/bhUgaK_SRRuXkf9n6svxcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30635cf7a9f684373fe4e07ead4ef3fca1bebaac2ea278f7036ff42a6ac6f41324ba698dbe0c147a4c4b5e621cbe2472725ba1147865eec4d2ba4bce39990c21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ffbc1e69d9f15795cbd2b62edbdaf19c6788970e010b791f15f191d782380a323980ccf0449d76620644f4e26f790e7831bc8647106ffe5694ed6940a6336c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3148af289baee56d7a8ce6dbe2145cdf30cda69a3de293fead38d6d2e968826c8e5e15deb1919b8b9c67a3e90d829079abf91cd33cb15f54bba3139814b6f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a5b9cb83935e5ed5b25e5fad98798e547b22e0826aacea4941af8f6e67a68c35d2f5639c4a9eb549270777f3c73c466ed78a3f9acfcf9698c66bc795aecb5b"}' ========= master_lag: 6.78 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2017-04-05 15:50:06.277146) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:50:06.277503) ========= 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.qMixxoWH19/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.9V7MNm1p6T/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.005426 ========= master_lag: 0.58 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:50:06.864738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 15:50:06.865046) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 15:50:06.870682) ========= ========= Total master_lag: 12.33 =========