builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0349 starttime: 1486478213.55 results: success (0) buildid: 20170207050956 builduid: 28f326b849f64566a41484e1c4ae4ae9 revision: 6d27535f4fe912068e0a0ac5854f7f39e94964a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.551619) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.552093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.552422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.577136) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.577878) ========= 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.AgEyICBIc3/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.oATCcgoynY/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-07 06:36:53-- 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.21M=0.001s 2017-02-07 06:36:53 (8.21 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.186591 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.789764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.790100) ========= 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.AgEyICBIc3/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.oATCcgoynY/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.020300 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:53.838765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-07 06:36:53.839132) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6d27535f4fe912068e0a0ac5854f7f39e94964a5 --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 6d27535f4fe912068e0a0ac5854f7f39e94964a5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AgEyICBIc3/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.oATCcgoynY/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-07 06:36:53,930 truncating revision to first 12 chars 2017-02-07 06:36:53,930 Setting DEBUG logging. 2017-02-07 06:36:53,930 attempt 1/10 2017-02-07 06:36:53,930 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6d27535f4fe9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 06:36:55,647 unpacking tar archive at: mozilla-central-6d27535f4fe9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.979774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-07 06:36:55.842549) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:55.842932) ========= script_repo_revision: 6d27535f4fe912068e0a0ac5854f7f39e94964a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:55.843330) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:55.843601) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 06:36:55.863392) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-07 06:36:55.863793) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AgEyICBIc3/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.oATCcgoynY/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:36:56 INFO - MultiFileLogger online at 20170207 06:36:56 in /builds/slave/test 06:36:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:36:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:36:56 INFO - {'append_to_log': False, 06:36:56 INFO - 'base_work_dir': '/builds/slave/test', 06:36:56 INFO - 'blob_upload_branch': 'Firefox', 06:36:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:36:56 INFO - 'branch': 'Firefox', 06:36:56 INFO - 'buildbot_json_path': 'buildprops.json', 06:36:56 INFO - 'config_files': ('talos/mac_config.py',), 06:36:56 INFO - 'default_actions': ('clobber', 06:36:56 INFO - 'read-buildbot-config', 06:36:56 INFO - 'download-and-extract', 06:36:56 INFO - 'populate-webroot', 06:36:56 INFO - 'create-virtualenv', 06:36:56 INFO - 'install', 06:36:56 INFO - 'run-tests'), 06:36:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:36:56 INFO - 'download_minidump_stackwalk': True, 06:36:56 INFO - 'download_symbols': 'ondemand', 06:36:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:36:56 INFO - 'tooltool.py': '/tools/tooltool.py', 06:36:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:36:56 INFO - '/tools/misc-python/virtualenv.py')}, 06:36:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:36:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:36:56 INFO - 'installer_path': 'installer.exe', 06:36:56 INFO - 'log_level': 'info', 06:36:56 INFO - 'log_name': 'talos', 06:36:56 INFO - 'log_to_console': True, 06:36:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:36:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:36:56 INFO - 'opt_config_files': (), 06:36:56 INFO - 'pip_index': False, 06:36:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:36:56 INFO - 'cmd': ('bash', 06:36:56 INFO - '-c', 06:36:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:36:56 INFO - 'enabled': True, 06:36:56 INFO - 'halt_on_failure': False, 06:36:56 INFO - 'name': 'check_screen_resolution'},), 06:36:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:36:56 INFO - 'cmd': ('bash', 06:36:56 INFO - '-c', 06:36:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:36:56 INFO - 'enabled': True, 06:36:56 INFO - 'halt_on_failure': False, 06:36:56 INFO - 'name': 'check_screen_resolution'},), 06:36:56 INFO - 'run_cmd_checks_enabled': True, 06:36:56 INFO - 'sps_profile': False, 06:36:56 INFO - 'sps_profile_interval': 0, 06:36:56 INFO - 'suite': 'dromaeojs', 06:36:56 INFO - 'system_bits': '32', 06:36:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:36:56 INFO - 'title': 't-yosemite-r7-0349', 06:36:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:36:56 INFO - 'use_talos_json': True, 06:36:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:36:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:36:56 INFO - 'work_dir': 'build'} 06:36:56 INFO - [mozharness: 2017-02-07 14:36:56.074383Z] Running clobber step. 06:36:56 INFO - Running pre-action listener: _resource_record_pre_action 06:36:56 INFO - Running main action method: clobber 06:36:56 INFO - rmtree: /builds/slave/test/build 06:36:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:36:56 INFO - Running post-action listener: _resource_record_post_action 06:36:56 INFO - [mozharness: 2017-02-07 14:36:56.405679Z] Finished clobber step (success) 06:36:56 INFO - [mozharness: 2017-02-07 14:36:56.405806Z] Running read-buildbot-config step. 06:36:56 INFO - Running pre-action listener: _resource_record_pre_action 06:36:56 INFO - Running main action method: read_buildbot_config 06:36:56 INFO - Using buildbot properties: 06:36:56 INFO - { 06:36:56 INFO - "project": "", 06:36:56 INFO - "product": "firefox", 06:36:56 INFO - "script_repo_revision": "production", 06:36:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:36:56 INFO - "repository": "", 06:36:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:36:56 INFO - "buildid": "20170207050956", 06:36:56 INFO - "pgo_build": "False", 06:36:56 INFO - "basedir": "/builds/slave/test", 06:36:56 INFO - "buildnumber": 63, 06:36:56 INFO - "slavename": "t-yosemite-r7-0349", 06:36:56 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:36:56 INFO - "platform": "yosemite_r7", 06:36:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:36:56 INFO - "branch": "mozilla-central", 06:36:56 INFO - "repo_path": "mozilla-central", 06:36:56 INFO - "revision": "6d27535f4fe912068e0a0ac5854f7f39e94964a5", 06:36:56 INFO - "stage_platform": "macosx64", 06:36:56 INFO - "builduid": "28f326b849f64566a41484e1c4ae4ae9", 06:36:56 INFO - "slavebuilddir": "test" 06:36:56 INFO - } 06:36:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:36:56 INFO - Running post-action listener: _resource_record_post_action 06:36:56 INFO - [mozharness: 2017-02-07 14:36:56.409512Z] Finished read-buildbot-config step (success) 06:36:56 INFO - [mozharness: 2017-02-07 14:36:56.409658Z] Running download-and-extract step. 06:36:56 INFO - Running pre-action listener: _resource_record_pre_action 06:36:56 INFO - Running main action method: download_and_extract 06:36:56 INFO - mkdir: /builds/slave/test/build/tests 06:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:36:56 INFO - trying https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:36:56 INFO - Downloading https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:36:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 06:36:56 INFO - Downloaded 1199 bytes. 06:36:56 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:36:56 INFO - Using the following test package requirements: 06:36:56 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:36:56 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:36:56 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:36:56 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'jsshell-mac.zip'], 06:36:56 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:36:56 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:36:56 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:36:56 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:36:56 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:36:56 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:36:56 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:36:56 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:36:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:36:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:36:56 INFO - Fetch https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:36:58 INFO - Expected file size: 34939850 06:36:58 INFO - Obtained file size: 34939850 06:37:05 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:37:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:37:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:37:05 INFO - Fetch https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:37:06 INFO - Expected file size: 34939850 06:37:06 INFO - Obtained file size: 34939850 06:37:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:37:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:37:12 INFO - Fetch https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:37:13 INFO - Expected file size: 14403454 06:37:13 INFO - Obtained file size: 14403454 06:37:13 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')]} 06:37:13 INFO - trying https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:37:13 INFO - Downloading https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:37:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 06:37:15 INFO - Downloaded 70339722 bytes. 06:37:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:37:15 INFO - mkdir: /builds/slave/test/properties 06:37:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:37:15 INFO - Writing to file /builds/slave/test/properties/build_url 06:37:15 INFO - Contents: 06:37:15 INFO - build_url:https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:37:16 INFO - Running post-action listener: _resource_record_post_action 06:37:16 INFO - Running post-action listener: set_extra_try_arguments 06:37:16 INFO - [mozharness: 2017-02-07 14:37:16.506203Z] Finished download-and-extract step (success) 06:37:16 INFO - [mozharness: 2017-02-07 14:37:16.506323Z] Running populate-webroot step. 06:37:16 INFO - Running pre-action listener: _resource_record_pre_action 06:37:16 INFO - Running main action method: populate_webroot 06:37:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:37:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:37:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:37:16 INFO - '--tppagecycles', 06:37:16 INFO - '7'], 06:37:16 INFO - 'tests': ['tsvgm']}}, 06:37:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:37:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:37:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:37:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:37:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:37:16 INFO - 'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:37:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:37:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:37:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:37:16 INFO - 'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['damp', 'tps']}, 06:37:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:37:16 INFO - 'tests': ['damp', 'tps']}, 06:37:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['dromaeo_dom']}, 06:37:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:37:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['basic_compositor_video']}, 06:37:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:37:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['a11yr', 06:37:16 INFO - 'ts_paint', 06:37:16 INFO - 'tpaint', 06:37:16 INFO - 'sessionrestore', 06:37:16 INFO - 'sessionrestore_no_auto_restore', 06:37:16 INFO - 'tabpaint']}, 06:37:16 INFO - 'other-e10s': {'tests': ['a11yr', 06:37:16 INFO - 'ts_paint', 06:37:16 INFO - 'tpaint', 06:37:16 INFO - 'sessionrestore', 06:37:16 INFO - 'sessionrestore_no_auto_restore', 06:37:16 INFO - 'tabpaint']}, 06:37:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:37:16 INFO - 'ts_paint', 06:37:16 INFO - 'tpaint', 06:37:16 INFO - 'sessionrestore', 06:37:16 INFO - 'sessionrestore_no_auto_restore', 06:37:16 INFO - 'tabpaint']}, 06:37:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:37:16 INFO - 'ts_paint', 06:37:16 INFO - 'tpaint', 06:37:16 INFO - 'sessionrestore', 06:37:16 INFO - 'sessionrestore_no_auto_restore', 06:37:16 INFO - 'tabpaint']}, 06:37:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['a11yr', 06:37:16 INFO - 'ts_paint', 06:37:16 INFO - 'tpaint', 06:37:16 INFO - 'sessionrestore', 06:37:16 INFO - 'sessionrestore_no_auto_restore', 06:37:16 INFO - 'tabpaint']}, 06:37:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['a11yr', 06:37:16 INFO - 'ts_paint', 06:37:16 INFO - 'tpaint', 06:37:16 INFO - 'sessionrestore', 06:37:16 INFO - 'sessionrestore_no_auto_restore', 06:37:16 INFO - 'tabpaint']}, 06:37:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['tsvgx', 06:37:16 INFO - 'tsvgr_opacity', 06:37:16 INFO - 'tart', 06:37:16 INFO - 'tscrollx', 06:37:16 INFO - 'cart', 06:37:16 INFO - 'tsvg_static']}, 06:37:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:37:16 INFO - 'tsvgr_opacity', 06:37:16 INFO - 'tart', 06:37:16 INFO - 'tscrollx', 06:37:16 INFO - 'cart', 06:37:16 INFO - 'tsvg_static']}, 06:37:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:37:16 INFO - 'talos_options': ['--disable-e10s'], 06:37:16 INFO - 'tests': ['tp5o']}, 06:37:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:37:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:37:16 INFO - 'talos_options': ['--disable-e10s', 06:37:16 INFO - '--xperf_path', 06:37:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:37:16 INFO - 'tests': ['tp5n']}, 06:37:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:37:16 INFO - 'talos_options': ['--xperf_path', 06:37:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:37:16 INFO - 'tests': ['tp5n']}}, 06:37:16 INFO - 'talos.zip': {'path': '', 06:37:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:37:16 INFO - Running post-action listener: _resource_record_post_action 06:37:16 INFO - [mozharness: 2017-02-07 14:37:16.524549Z] Finished populate-webroot step (success) 06:37:16 INFO - [mozharness: 2017-02-07 14:37:16.524654Z] Running create-virtualenv step. 06:37:16 INFO - Running pre-action listener: _resource_record_pre_action 06:37:16 INFO - Running main action method: create_virtualenv 06:37:16 INFO - Creating virtualenv /builds/slave/test/build/venv 06:37:16 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 06:37:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:37:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:37:16 INFO - Using real prefix '/tools/python27' 06:37:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:37:17 INFO - Installing distribute.............................................................................................................................................................................................done. 06:37:20 INFO - Installing pip.................done. 06:37:20 INFO - Return code: 0 06:37:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:37:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:37:20 INFO - Reading from file tmpfile_stdout 06:37:20 INFO - Output received: 06:37:20 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) 06:37:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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', 'pip>=1.5'] in /builds/slave/test/build 06:37: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 pip>=1.5 06:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:20 INFO - 'HOME': '/Users/cltbld', 06:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:20 INFO - 'LOGNAME': 'cltbld', 06:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:20 INFO - 'MOZ_NO_REMOTE': '1', 06:37:20 INFO - 'NO_EM_RESTART': '1', 06:37:20 INFO - 'PAGER': '/bin/cat', 06:37:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:20 INFO - 'PWD': '/builds/slave/test', 06:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:20 INFO - 'SHELL': '/bin/bash', 06:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:20 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:20 INFO - 'USER': 'cltbld', 06:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:20 INFO - 'XPC_FLAGS': '0x0', 06:37:20 INFO - 'XPC_SERVICE_NAME': '0', 06:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:20 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 06:37:20 INFO - Cleaning up... 06:37:20 INFO - Return code: 0 06:37:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:20 INFO - 'HOME': '/Users/cltbld', 06:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:20 INFO - 'LOGNAME': 'cltbld', 06:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:20 INFO - 'MOZ_NO_REMOTE': '1', 06:37:20 INFO - 'NO_EM_RESTART': '1', 06:37:20 INFO - 'PAGER': '/bin/cat', 06:37:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:20 INFO - 'PWD': '/builds/slave/test', 06:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:20 INFO - 'SHELL': '/bin/bash', 06:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:20 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:20 INFO - 'USER': 'cltbld', 06:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:20 INFO - 'XPC_FLAGS': '0x0', 06:37:20 INFO - 'XPC_SERVICE_NAME': '0', 06:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:20 INFO - Downloading/unpacking psutil>=3.1.1 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:37:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:37:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:37:24 INFO - Installing collected packages: psutil 06:37:24 INFO - Running setup.py install for psutil 06:37:24 INFO - building 'psutil._psutil_osx' extension 06:37:24 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 06:37:24 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 06:37: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 06:37: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 06:37:25 INFO - building 'psutil._psutil_posix' extension 06:37: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 06:37:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:37:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:37:25 INFO - ^ 06:37:25 INFO - 1 warning generated. 06:37: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 06:37:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:37:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:37:25 INFO - Successfully installed psutil 06:37:25 INFO - Cleaning up... 06:37:25 INFO - Return code: 0 06:37:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:25 INFO - 'HOME': '/Users/cltbld', 06:37:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:25 INFO - 'LOGNAME': 'cltbld', 06:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:25 INFO - 'MOZ_NO_REMOTE': '1', 06:37:25 INFO - 'NO_EM_RESTART': '1', 06:37:25 INFO - 'PAGER': '/bin/cat', 06:37:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:25 INFO - 'PWD': '/builds/slave/test', 06:37:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:25 INFO - 'SHELL': '/bin/bash', 06:37:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:25 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:25 INFO - 'USER': 'cltbld', 06:37:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:25 INFO - 'XPC_FLAGS': '0x0', 06:37:25 INFO - 'XPC_SERVICE_NAME': '0', 06:37:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:37:25 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 06:37:25 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 06:37:25 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 06:37:25 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 06:37:25 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 06:37:25 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 06:37:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:37:28 INFO - Installing collected packages: mozsystemmonitor 06:37:28 INFO - Running setup.py install for mozsystemmonitor 06:37:28 INFO - Successfully installed mozsystemmonitor 06:37:28 INFO - Cleaning up... 06:37:28 INFO - Return code: 0 06:37:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:28 INFO - 'HOME': '/Users/cltbld', 06:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:28 INFO - 'LOGNAME': 'cltbld', 06:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:28 INFO - 'MOZ_NO_REMOTE': '1', 06:37:28 INFO - 'NO_EM_RESTART': '1', 06:37:28 INFO - 'PAGER': '/bin/cat', 06:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:28 INFO - 'PWD': '/builds/slave/test', 06:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:28 INFO - 'SHELL': '/bin/bash', 06:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:28 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:28 INFO - 'USER': 'cltbld', 06:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:28 INFO - 'XPC_FLAGS': '0x0', 06:37:28 INFO - 'XPC_SERVICE_NAME': '0', 06:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:28 INFO - Downloading/unpacking jsonschema==2.5.1 06:37:28 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 06:37:28 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 06:37:28 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 06:37:28 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 06:37:28 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 06:37:28 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 06:37:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:37:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37:31 INFO - Downloading functools32-3.2.3-2.tar.gz 06:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:37:31 INFO - warning: no files found matching '*.txt' 06:37:31 INFO - no previously-included directories found matching 'build' 06:37:31 INFO - no previously-included directories found matching 'dist' 06:37:31 INFO - no previously-included directories found matching '.git*' 06:37:31 INFO - Installing collected packages: jsonschema, functools32 06:37:31 INFO - Running setup.py install for functools32 06:37:32 INFO - warning: no files found matching '*.txt' 06:37:32 INFO - no previously-included directories found matching 'build' 06:37:32 INFO - no previously-included directories found matching 'dist' 06:37:32 INFO - no previously-included directories found matching '.git*' 06:37:32 INFO - Successfully installed jsonschema functools32 06:37:32 INFO - Cleaning up... 06:37:32 INFO - Return code: 0 06:37:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:32 INFO - 'HOME': '/Users/cltbld', 06:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:32 INFO - 'LOGNAME': 'cltbld', 06:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:32 INFO - 'MOZ_NO_REMOTE': '1', 06:37:32 INFO - 'NO_EM_RESTART': '1', 06:37:32 INFO - 'PAGER': '/bin/cat', 06:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:32 INFO - 'PWD': '/builds/slave/test', 06:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:32 INFO - 'SHELL': '/bin/bash', 06:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:32 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:32 INFO - 'USER': 'cltbld', 06:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:32 INFO - 'XPC_FLAGS': '0x0', 06:37:32 INFO - 'XPC_SERVICE_NAME': '0', 06:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:37:32 INFO - Cleaning up... 06:37:32 INFO - Return code: 0 06:37:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:32 INFO - 'HOME': '/Users/cltbld', 06:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:32 INFO - 'LOGNAME': 'cltbld', 06:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:32 INFO - 'MOZ_NO_REMOTE': '1', 06:37:32 INFO - 'NO_EM_RESTART': '1', 06:37:32 INFO - 'PAGER': '/bin/cat', 06:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:32 INFO - 'PWD': '/builds/slave/test', 06:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:32 INFO - 'SHELL': '/bin/bash', 06:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:32 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:32 INFO - 'USER': 'cltbld', 06:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:32 INFO - 'XPC_FLAGS': '0x0', 06:37:32 INFO - 'XPC_SERVICE_NAME': '0', 06:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:32 INFO - Downloading/unpacking blobuploader==1.2.4 06:37:32 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 06:37:32 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 06:37:32 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 06:37:32 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 06:37:32 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 06:37:32 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 06:37:35 INFO - Downloading blobuploader-1.2.4.tar.gz 06:37:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:37:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:37:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37:36 INFO - Downloading docopt-0.6.1.tar.gz 06:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:37:36 INFO - Installing collected packages: blobuploader, requests, docopt 06:37:36 INFO - Running setup.py install for blobuploader 06:37:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:37:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:37:36 INFO - Running setup.py install for requests 06:37:37 INFO - Running setup.py install for docopt 06:37:37 INFO - Successfully installed blobuploader requests docopt 06:37:37 INFO - Cleaning up... 06:37:37 INFO - Return code: 0 06:37:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:37 INFO - 'HOME': '/Users/cltbld', 06:37:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:37 INFO - 'LOGNAME': 'cltbld', 06:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:37 INFO - 'MOZ_NO_REMOTE': '1', 06:37:37 INFO - 'NO_EM_RESTART': '1', 06:37:37 INFO - 'PAGER': '/bin/cat', 06:37:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:37 INFO - 'PWD': '/builds/slave/test', 06:37:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:37 INFO - 'SHELL': '/bin/bash', 06:37:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:37 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:37 INFO - 'USER': 'cltbld', 06:37:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:37 INFO - 'XPC_FLAGS': '0x0', 06:37:37 INFO - 'XPC_SERVICE_NAME': '0', 06:37:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:37:37 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-pfEM_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-UdvbVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-5uH1H2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-bDP8mD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-K1iwG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-xDGIcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-A0OXmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Zt4Fia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-gdwAnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-SpE1PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:37:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-D2iQD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:37:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ZSDVyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:37:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-XCyJQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:37:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-3VePvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:37:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-EPdJhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:37:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-dI72sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:37:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-sNyqfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:37:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:37:39 INFO - Running setup.py install for manifestparser 06:37:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:37:39 INFO - Running setup.py install for mozcrash 06:37:39 INFO - Running setup.py install for mozdebug 06:37:40 INFO - Running setup.py install for mozdevice 06:37:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Running setup.py install for mozfile 06:37:40 INFO - Running setup.py install for mozhttpd 06:37:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Running setup.py install for mozinfo 06:37:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Running setup.py install for mozInstall 06:37:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:37:40 INFO - Running setup.py install for mozleak 06:37:40 INFO - Running setup.py install for mozlog 06:37:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:37:41 INFO - Running setup.py install for moznetwork 06:37:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:37:41 INFO - Running setup.py install for mozprocess 06:37:41 INFO - Running setup.py install for mozprofile 06:37:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:37:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:37:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:37:41 INFO - Running setup.py install for mozrunner 06:37:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:37:41 INFO - Running setup.py install for mozscreenshot 06:37:41 INFO - Running setup.py install for moztest 06:37:42 INFO - Running setup.py install for mozversion 06:37:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:37:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:37:42 INFO - Cleaning up... 06:37:42 INFO - Return code: 0 06:37:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:42 INFO - 'HOME': '/Users/cltbld', 06:37:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:42 INFO - 'LOGNAME': 'cltbld', 06:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:42 INFO - 'MOZ_NO_REMOTE': '1', 06:37:42 INFO - 'NO_EM_RESTART': '1', 06:37:42 INFO - 'PAGER': '/bin/cat', 06:37:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:42 INFO - 'PWD': '/builds/slave/test', 06:37:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:42 INFO - 'SHELL': '/bin/bash', 06:37:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:42 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:42 INFO - 'USER': 'cltbld', 06:37:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:42 INFO - 'XPC_FLAGS': '0x0', 06:37:42 INFO - 'XPC_SERVICE_NAME': '0', 06:37:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:37:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-iVsnCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:37: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)) 06:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:37:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-TvIvJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:37:42 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)) 06:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:37:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-lZIlwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:37:42 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)) 06:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:37:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-jR_aFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:37:42 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)) 06:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:37:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-M2CKR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:37: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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-nFbXFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:37: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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-aojnV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:37: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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-VL0Kne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:37: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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-2EokFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:37: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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-bpTdZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:37: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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-HNs9hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:37:43 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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ffDg1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-W5siSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:37:43 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)) 06:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:37:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ZFZih7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:37: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)) 06:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:37:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-QEmeeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:37: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)) 06:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:37:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-WFf3Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:37: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)) 06:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:37:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-hCDG3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:37: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)) 06:37: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)) 06:37: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)) 06:37: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)) 06:37: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)) 06:37: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)) 06:37:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37: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 06:37:46 INFO - Downloading blessings-1.6.tar.gz 06:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:37:47 INFO - Installing collected packages: blessings 06:37:47 INFO - Running setup.py install for blessings 06:37:47 INFO - Successfully installed blessings 06:37:47 INFO - Cleaning up... 06:37:47 INFO - Return code: 0 06:37:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:37:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:37:47 INFO - Reading from file tmpfile_stdout 06:37:47 INFO - Current package versions: 06:37:47 INFO - blessings == 1.6 06:37:47 INFO - blobuploader == 1.2.4 06:37:47 INFO - docopt == 0.6.1 06:37:47 INFO - functools32 == 3.2.3-2 06:37:47 INFO - jsonschema == 2.5.1 06:37:47 INFO - manifestparser == 1.1 06:37:47 INFO - mozInstall == 1.12 06:37:47 INFO - mozcrash == 1.0 06:37:47 INFO - mozdebug == 0.1 06:37:47 INFO - mozdevice == 0.49 06:37:47 INFO - mozfile == 1.2 06:37:47 INFO - mozhttpd == 0.7 06:37:47 INFO - mozinfo == 0.9 06:37:47 INFO - mozleak == 0.1 06:37:47 INFO - mozlog == 3.4 06:37:47 INFO - moznetwork == 0.27 06:37:47 INFO - mozprocess == 0.24 06:37:47 INFO - mozprofile == 0.28 06:37:47 INFO - mozrunner == 6.13 06:37:47 INFO - mozscreenshot == 0.1 06:37:47 INFO - mozsystemmonitor == 0.3 06:37:47 INFO - moztest == 0.8 06:37:47 INFO - mozversion == 1.4 06:37:47 INFO - psutil == 3.1.1 06:37:47 INFO - requests == 1.2.3 06:37:47 INFO - wsgiref == 0.1.2 06:37:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37: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 06:37: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 06:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:47 INFO - 'HOME': '/Users/cltbld', 06:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:47 INFO - 'LOGNAME': 'cltbld', 06:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:47 INFO - 'MOZ_NO_REMOTE': '1', 06:37:47 INFO - 'NO_EM_RESTART': '1', 06:37:47 INFO - 'PAGER': '/bin/cat', 06:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:47 INFO - 'PWD': '/builds/slave/test', 06:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:47 INFO - 'SHELL': '/bin/bash', 06:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:47 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:47 INFO - 'USER': 'cltbld', 06:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:47 INFO - 'XPC_FLAGS': '0x0', 06:37:47 INFO - 'XPC_SERVICE_NAME': '0', 06:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 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)) 06:37:47 INFO - Cleaning up... 06:37:47 INFO - Return code: 0 06:37:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:37: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')]} 06:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37: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')]} 06:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:37:47 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 0x10e2deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0857b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, '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.AgEyICBIc3/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.oATCcgoynY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:37:47 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 06:37:47 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 06:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:37:47 INFO - 'HOME': '/Users/cltbld', 06:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:37:47 INFO - 'LOGNAME': 'cltbld', 06:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:37:47 INFO - 'MOZ_NO_REMOTE': '1', 06:37:47 INFO - 'NO_EM_RESTART': '1', 06:37:47 INFO - 'PAGER': '/bin/cat', 06:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:37:47 INFO - 'PWD': '/builds/slave/test', 06:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:37:47 INFO - 'SHELL': '/bin/bash', 06:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:37:47 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:37:47 INFO - 'USER': 'cltbld', 06:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:37:47 INFO - 'XPC_FLAGS': '0x0', 06:37:47 INFO - 'XPC_SERVICE_NAME': '0', 06:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:37:48 INFO - Cleaning up... 06:37:48 INFO - Return code: 0 06:37:48 INFO - Running post-action listener: _resource_record_post_action 06:37:48 INFO - Running post-action listener: _start_resource_monitoring 06:37:48 INFO - Starting resource monitoring. 06:37:48 INFO - [mozharness: 2017-02-07 14:37:48.132659Z] Finished create-virtualenv step (success) 06:37:48 INFO - [mozharness: 2017-02-07 14:37:48.133617Z] Running install step. 06:37:48 INFO - Running pre-action listener: _resource_record_pre_action 06:37:48 INFO - Running main action method: install 06:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:37:48 INFO - Reading from file tmpfile_stdout 06:37:48 INFO - Detecting whether we're running mozinstall >=1.0... 06:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:37:48 INFO - Reading from file tmpfile_stdout 06:37:48 INFO - Output received: 06:37:48 INFO - Usage: mozinstall [options] installer 06:37:48 INFO - Options: 06:37:48 INFO - -h, --help show this help message and exit 06:37:48 INFO - -d DEST, --destination=DEST 06:37:48 INFO - Directory to install application into. [default: 06:37:48 INFO - "/builds/slave/test"] 06:37:48 INFO - --app=APP Application being installed. [default: firefox] 06:37:48 INFO - mkdir: /builds/slave/test/build/application 06:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:38:10 INFO - Reading from file tmpfile_stdout 06:38:10 INFO - Output received: 06:38:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:38:10 INFO - Running post-action listener: _resource_record_post_action 06:38:10 INFO - [mozharness: 2017-02-07 14:38:10.556634Z] Finished install step (success) 06:38:10 INFO - [mozharness: 2017-02-07 14:38:10.556759Z] Running run-tests step. 06:38:10 INFO - Running pre-action listener: _resource_record_pre_action 06:38:10 INFO - Running main action method: run_tests 06:38:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:38:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:38:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:38:10 INFO - 2017-02-07 06:38:10.571 screenresolution[2268:24494] starting screenresolution argv=screenresolution get 06:38:10 INFO - 2017-02-07 06:38:10.574 screenresolution[2268:24494] Display 0: 1600x1200x32@60 06:38:10 INFO - 2017-02-07 06:38:10.580 screenresolution[2269:24496] starting screenresolution argv=screenresolution list 06:38:10 INFO - Available Modes on Display 0 06:38:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:38:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:38:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:38:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:38:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:38:10 INFO - Intel Iris: 06:38:10 INFO - Chipset Model: Intel Iris 06:38:10 INFO - Type: GPU 06:38:10 INFO - Bus: Built-In 06:38:10 INFO - VRAM (Dynamic, Max): 1536 MB 06:38:10 INFO - Vendor: Intel (0x8086) 06:38:10 INFO - Device ID: 0x0a2e 06:38:10 INFO - Revision ID: 0x0009 06:38:10 INFO - Displays: 06:38:10 INFO - Display: 06:38:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:38:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:38:10 INFO - Display Serial Number: 1600x1200 60 06:38:10 INFO - Main Display: Yes 06:38:10 INFO - Mirror: Off 06:38:10 INFO - Online: Yes 06:38:10 INFO - Rotation: Supported 06:38:10 INFO - Return code: 0 06:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:38:10 INFO - Python 2.7.3 06:38:10 INFO - Return code: 0 06:38:10 INFO - grabbing minidump binary from tooltool 06:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:38:10 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 0x10e0bcd30>, '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 0x10dfe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:38:10 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 06:38:10 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 06:38:10 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 06:38:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:38:10 INFO - Return code: 0 06:38:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:38:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:38:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:38:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:38:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0349 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AgEyICBIc3/Render', 06:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:38:10 INFO - 'HOME': '/Users/cltbld', 06:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:38:10 INFO - 'LOGNAME': 'cltbld', 06:38:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:38:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:38:10 INFO - 'MOZ_NO_REMOTE': '1', 06:38:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:38:10 INFO - 'NO_EM_RESTART': '1', 06:38:10 INFO - 'PAGER': '/bin/cat', 06:38:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:38:10 INFO - 'PWD': '/builds/slave/test', 06:38:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:38:10 INFO - 'SHELL': '/bin/bash', 06:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oATCcgoynY/Listeners', 06:38:10 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:38:10 INFO - 'USER': 'cltbld', 06:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:38:10 INFO - 'XPC_FLAGS': '0x0', 06:38:10 INFO - 'XPC_SERVICE_NAME': '0', 06:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:38:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:38:11 INFO - mozversion application_buildid: 20170207050956 06:38:11 INFO - mozversion application_changeset: 6d27535f4fe912068e0a0ac5854f7f39e94964a5 06:38:11 INFO - mozversion application_display_name: Nightly 06:38:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:38:11 INFO - mozversion application_name: Firefox 06:38:11 INFO - mozversion application_remotingname: firefox 06:38:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:38:11 INFO - mozversion application_vendor: Mozilla 06:38:11 INFO - mozversion application_version: 54.0a1 06:38:11 INFO - mozversion platform_buildid: 20170207050956 06:38:11 INFO - mozversion platform_changeset: 6d27535f4fe912068e0a0ac5854f7f39e94964a5 06:38:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:38:11 INFO - mozversion platform_version: 54.0a1 06:38:11 INFO - using testdate: 1486478291 06:38:11 INFO - actual date: 1486478291 06:38:11 INFO - starting webserver on 'localhost:49731' 06:38:11 INFO - SUITE-START | Running 2 tests 06:38:11 INFO - TEST-START | dromaeo_css 06:38:11 INFO - operating with platform_type : mac_ 06:38:11 INFO - Initialising browser for dromaeo_css test... 06:38:11 INFO - TEST-INFO | started process 2279 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpjFxSM8/profile http://localhost:49731/getInfo.html) 06:38:13 INFO - PROCESS | 2279 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:38:13 INFO - PROCESS | 2279 | __metrics Screen width/height:1600/1200 06:38:13 INFO - PROCESS | 2279 | colorDepth:24 06:38:13 INFO - PROCESS | 2279 | Browser inner width/height: 1024/689 06:38:13 INFO - PROCESS | 2279 | __metrics 06:38:13 INFO - PROCESS | 2279 | 1486478293638 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:38:13 INFO - PROCESS | 2279 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:38:13 INFO - PROCESS | 2279 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:38:14 INFO - TEST-INFO | 2279: exit 0 06:38:14 INFO - Browser initialized. 06:38:14 INFO - Running cycle 1/1 for dromaeo_css test... 06:38:14 INFO - TEST-INFO | started process 2281 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpjFxSM8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:38:26 INFO - PROCESS | 2281 | RSS: Main: 228356096 06:38:26 INFO - PROCESS | 2281 | 06:41:30 INFO - PROCESS | 2281 | Cycle 1(1): loaded http://localhost:49731/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49731/tests/dromaeo/cssquery-ext.html) 06:41:32 INFO - PROCESS | 2281 | RSS: Main: 371687424 06:41:32 INFO - PROCESS | 2281 | 06:43:16 INFO - PROCESS | 2281 | ************************* 06:43:16 INFO - PROCESS | 2281 | A coding exception was thrown and uncaught in a Task. 06:43:16 INFO - PROCESS | 2281 | 06:43:16 ERROR - PROCESS | 2281 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:43:16 INFO - PROCESS | 2281 | Full stack: 06:43:16 INFO - PROCESS | 2281 | ************************* 06:44:34 INFO - PROCESS | 2281 | Cycle 1(1): loaded http://localhost:49731/tests/dromaeo/cssquery-ext.html (next: http://localhost:49731/tests/dromaeo/cssquery-jquery.html) 06:44:35 INFO - PROCESS | 2281 | RSS: Main: 372846592 06:44:35 INFO - PROCESS | 2281 | 06:47:37 INFO - PROCESS | 2281 | Cycle 1(1): loaded http://localhost:49731/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49731/tests/dromaeo/cssquery-mootools.html) 06:47:38 INFO - PROCESS | 2281 | RSS: Main: 369680384 06:47:38 INFO - PROCESS | 2281 | 06:50:40 INFO - PROCESS | 2281 | Cycle 1(1): loaded http://localhost:49731/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49731/tests/dromaeo/cssquery-prototype.html) 06:50:41 INFO - PROCESS | 2281 | RSS: Main: 347439104 06:50:41 INFO - PROCESS | 2281 | 06:53:44 INFO - PROCESS | 2281 | Cycle 1(1): loaded http://localhost:49731/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49731/tests/dromaeo/cssquery-yui.html) 06:53:45 INFO - PROCESS | 2281 | RSS: Main: 342134784 06:53:45 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | Cycle 1(1): loaded http://localhost:49731/tests/dromaeo/cssquery-yui.html (next: http://localhost:49731/tests/dromaeo/cssquery-dojo.html) 06:56:49 INFO - PROCESS | 2281 | RSS: Main: 433967104 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | __start_tp_report 06:56:49 INFO - PROCESS | 2281 | _x_x_mozilla_page_load 06:56:49 INFO - PROCESS | 2281 | _x_x_mozilla_page_load_details 06:56:49 INFO - PROCESS | 2281 | |i|pagename|runs| 06:56:49 INFO - PROCESS | 2281 | |0;dojo.html;2597.8441293522383;3298.6206586242574;3318.5851768528933;3319.8340082995846;3356.16095976006;5423.078076726956;5514.36584792749;5545.445455454453;5626.887462250757;5638.887222255557;5541.889162216753;5567;5595.552355811536;5605.439456054392;5631.859203519914;5023.899522009558;5067.670601410915;5128.769205385766;5239.371275446952;5241.161414173733;5138.948610513903;5208.765605547759;5220.243064755608;5230.92153617696;5231.607629427789;5016.021875734234;5043.747812609392;5054.342935418409;5055.87360315995;5150.974245128769;5067.645264831426;5096.541311282002;5100.515451032146;5162.586993040548;5165.535101840815;5016.44819069902;5078.873028174288;5111.258867464234;5133.512316329943;5143.614228932845;5382.434844341327;5443.1835224716215;5474.644148130359;5565.526930210937;5576.302962129734;6041.6375017499095;6063.090536419572;6139.662318572477;6153.384661533811;6155.199824022893;6159.7536098556;6206.875862482725;6210.410011048988;6221.097940798604;6225.159603453521;5462.5083742462575;5684.175794509773;5695.943040569624;5709.914351284734;5732.742026608813;6170;6189.164462797555;6224.595401298901;6231.626102433868;6257.155284036688;5773.769049238067;5823.359430462646;5830.271216097988;5923.200367950271;6074.42292982166;6103.738726210847;6122.663253521099;6129.63387754696;6133.407859146218;6140.6315621061985;6176.3514830943;6178.41305076017;6185.381461853869;6188.1027251048345;6190.380961903774;6194.566380353332;6209.782657606962;6212.844678883064;6217.129601855744;6230.06549017651;4772.331873537708;4814.253790662453;4814.711117332901;4907.484714105039;4924.679895806761;4334.371516130206;4383.758893260838;4397.274449715825;4433.866983990485;4484.529124441952;5725.484706376446;5744.224529688522;5747.080467125324;5760.36635970043;5776.942230577641;6216.906746398808;6284.308726040132;6289.276733175652;6301.747930082745;6308.337624549356;5530.778768849201;5576.414476479992;5578.023845826964;5629.324481062217;5741.85645358861;5296.523312901781;5315.654482458628;5317.361916569959;5333.040052790535;5342.813001544927;6269.373062693694;6303.590266632654;6316.463100636405;6319;6320.209973753281;6225.533085018732;6230.190075290183;6261.655608941552;6272.372762723874;6276.623402595859;6164.660943648142;6238.906416403667;6255.155554000152;6269.185005949197;6276.497880169667;6138.756267604092;6147;6152;6161.691915404302;6171.660558669317;5754.51086657622;6034.185384972972;6216.2540495139065;6223.688815559294;6241.906371404523;5778.595498315078;5789.94210057894;5935.940640593539;6014.969925150346;6054.303755068224;5896.204012458435;6016.157737916607;6020.277566692025;6054.122152287981;6066.211392518944;5639.436056394327;5673.8865222694485;5679.744411501575;5801.535877129735;5860.238169037998;6013.3685962975005;6031.849203769941;6068.787592434243;6071.089336599545;6075.2405949256345;5888.823335299948;5891.351951285269;5895.469407753323;5895.469407753323;5930.318013428511 06:56:49 INFO - PROCESS | 2281 | |1;ext.html;456.6529437627402;471.2130741661425;502.1313128288059;505.3556715188136;513.7508308470393;29696.25759356017;39822.20355592886;41980;42050.369244461355;42059.57940420595;34992;42669.7866510667;42820.9294767631;42881.785591072075;42909.35635965463;9173.266138708905;9404.52977351133;9419.858702119474;9429.292803039765;9437.339386242966;8016.519008859458;8219.917800821975;11149.554017839277;11424.828627570552;11447.656570302863;11219.943900280487;11300.378479183642;11305.321680699144;11315.603953861617;11323.830142547868;8011.639476223584;11177.385243811586;11254.606088786935;11279.097672186246;11318.547258109666;8024.277814996678;10832.945835270852;11129.777404451946;11173.888261117447;11311.773764524745;40982.38526422106;41314;41459.7927010363;41468.79265603652;41492.79253603743;3383.712384447325;3404.5744281964753;3920.6863450923856;3924.1759230561615;4000.2799496090693;30090.64592093361;30160.698393016228;30255.394892102035;31329.216769580715;31333;2928.07765553852;3021.305099827052;3038.7113224243662;3097.2566584019673;3198.824064676442;418553;425220;425747;427743;428478;47816.5218347819;57314.42685573174;57724.42275577274;57764;57941.710291448275;48089;50490;58021.709891451115;59000.11499827506;59196.70401647964;12050.698732531757;14313.78529322061;14763.630909227355;14770.48303309379;14776.92611536957;11317.71705707364;11419.942900285612;11422.828657570142;11423;11430.25703329281;14844.70310593782;16975.830241697673;17516.03661798588;17554.91222543879;17622.207000685;6134.723937422737;7373.5944523050675;7450.888236676454;7467.58928258951;7489.550626962399;837.5184269045324;853.7566793463833;859.6470402025918;867.128535821508;883.1389395339493;27482.45035099327;27602.033928812394;29115.41769164605;29143.417131657672;29259.7074029257;1004.2969921055291;1009.2884516415909;1036.3781730961364;1038.1383451735041;1211.2732360583755;13717.17696938187;15285.9235703823;15334.309956052004;15369.334829809037;15402.460913868184;455684.3294701164;457592;458623;459326;462119;55963.720181398836;56582.43417565854;56587;56604;56751.71624141935;1330.846952600444;1381.1160857051495;1396.8114304568753;1398.1680899864564;1402.6423262068108;27252.863735681196;28554.286142846595;29028;29077.41845163127;29129.85435072811;50047.99904001973;50488;50537.74731126321;50641;50675.746621266655;325773.3711331428;385521;388908;391358;391728;22229.444263893532;25353.746462535877;26596.335091622863;26599.468010640066;26678.333041674112;44302.556974431136;45050.324245137;45480.545194548955;45558.86102847323;45648;33649.32701346008;35057.8247108773;35839;37670.68152614645;37763;32395.51406728948;35404.93785186449;35512.64487355093;36730.81634591917;36739.816300918326;35446.822765886005;36087;36104;36306.636933630325;36377.72677956259;30533.2447007908;30730.84634576813;31928;32275.677243227266;32403.837980809945;23234.535309294057;24017.225100288473;24034;24121.51756964886;24274.75725242725 06:56:49 INFO - PROCESS | 2281 | |2;jquery.html;354.03701930748355;379.33237501996484;379.8613506070285;395.82583663188194;407.9877603671889;14004.859951400484;14222.004459687812;15840;16378.918105409457;20722.963851807388;19155.138018789152;19731.901340493314;20175.697364539545;20417.877016764072;20840.583188336223;5630.971845140769;5816.476517113459;5858.03755741827;5869.9706501467645;5878.088896221093;3900.726949113563;5455.563554915597;6380.617162970213;6427.2287325521;6459.677016149197;5577.609567330289;5747.425257474261;6364.872702545947;6374.617522948638;6418.101465794793;5923.378045305246;6086.360932102154;6093.025115981422;6096.634201947897;6105.32841387447;5054.267131265946;5293.841184764463;5358.089124848785;5923.052311630162;5943.3165186003735;13238.933805331008;13421.798673019912;13437.462501499927;16411.91794041022;23835.52328953411;2488.813338999564;2494.0896572751;2503.7871780898645;2575.0214918331103;2576.5748651472486;9331.253499720005;9791.853122203172;9799.11807937282;10291.382517048925;10298.691039268833;1734.9392820736077;1741.1033317841195;1761.2250609731677;1766.1257677449616;1797.0644289007032;115432;122194;122447.99999999911;122478.99999999911;123431.38284308522;11474.311541307548;11578.594749183738;11751.061074922542;11774.94112529432;11940.164188506722;15573.76639350388;15600.453984110502;15602.609934751723;15605.453809116627;15607.3757049719;8099.919000810034;8268.710595129261;8269.421140520106;8302.626381812834;8402.621882015324;6760.8168570499965;7838.843223135619;7883.448158628956;8072.394570407243;8113.8782918256275;4616.16908956391;4710.458297295786;4718.245080787057;4823.107725070873;4829.517048295213;1719.2263481433406;1933.6422761789115;1984.4745222929878;2000.7499062617173;2001.8098280663314;710.8578284343153;831.1107115386599;841.469873979389;856.2293935458138;859.3726579596929;20660.586788264452;21209.045592948354;21225.68161477579;21343.46641333948;21350.78649213519;718.396546900606;833.954132522705;844.9873251901226;847.546562589012;881.4094556647062;7881.921180788233;11065;11085.22403431752;11114.888851111386;11161.107111431065;602209;618854;631604;641987;645199;130565;132407;135253;137507.31246343904;142094.78137663798;997.396575072071;1000.7848312612823;1015.4313584392763;1017.1354348803458;1023.8873723890366;17731.93608383501;18220.90889545544;18296.634067318846;18393.72409413833;18413.723794143367;40896;47180.820479488284;50329;52602.47397525976;55573.722131389084;63123.36876631175;77386.45227095314;77548.2245177541;83220;85465.57267213624;5536.280283563272;7754.961225193838;7925.207479252029;8004.399670024888;8361.414700970874;9530.666426675032;12078.09414293949;12241.959433448257;12432.813507797571;12550;10671.89328106709;10799.784004320027;10817.242793004414;10960.342379457257;10969.561217551527;10281.640142594973;10397.844032339672;10772;11010.064144547803;11014.614488492864;10757.30077544957;10862;10966.342019478856;11037.779244415227;11080.05819505351;7910.7231246906085;8099.392545558989;8155.469894456842;8190.959045204736;8345.733324707116;8017.358611311198;10269.435181065113;10489.947550262505;10918.454077295944;10939.56241750321 06:56:49 INFO - PROCESS | 2281 | |3;mootools.html;1338.7255612599417;1419.730251252262;1427.5431861804227;1440.2870579063358;1442.7330943775398;8129.67481300748;8530.872036919443;8532.146785321465;8538.530380829052;8558.91441085589;8110.107888132316;8384.91615083849;8497.660093596249;8533.91466085339;8550.059493455716;2248.3592176229777;2288.2448791898632;2289.255991802671;2293.54129174165;2295.8507696999727;1973.595412940351;2243.5849367866995;2290.347251033456;2298.2071185440996;2303.216906251874;2007.9598408031832;2040.9183632654676;2095.7904209578996;2276.886155692217;2283.086765293879;2156.8921553922396;2225.810806081485;2246.247507085112;2252.8085112765434;2254.357508110197;2221.3447033125103;2281.1901774870016;2287.7025986621634;2288.0047179476815;2291.3584196425027;8477.74566762998;8531.061583225903;8534.317254619615;8555.914440855637;8558.229759321692;5054.1660625996665;5151.793928242866;5154.12379895415;5222.164453687392;5223.895522089537;3287.96712032879;3327.2513684421056;3492.580890293156;3559.2169722660974;3572.160542272564;1203.8194270859353;1226.0926914083602;1226.7239871029037;1236.9505219791288;1256.3404212788269;9513.952430237874;10819.783604327948;10964.616238431616;10969.286996345212;10983.231173817836;4146.854860079883;4407.801648925806;4418.989359841265;4485.596296333346;4495.505494395614;8761.693340733043;8771.16673915977;8772.298216142693;8775.780605484913;8778;5056.848294551096;5086.160783470686;5091.770870310845;5092.337996060489;5105.361829771278;4582.221022426195;4611.2852507861335;4643.582077612963;4754.833580824655;4803.351547541107;3582.1402863313137;3624.130208749881;3631.5823680276576;3662.6520480554304;3719.642330549384;1140.7262257058246;1145.484531960621;1145.5761368293784;1165.318288801044;1165.580391059218;701.9122609673791;725.9564426134449;783.9882401763978;786.4534148766667;862.3618522293458;5104.000878107684;5470.398256191816;5750.1949727038245;5769.249997500298;5823.621464604787;924.7641851328003;984.2716389871587;992.846108853129;1005.2309982863115;1010.3211236355695;3293.9341213175603;3624.583172935146;3690.833912473945;3743.6069212732273;3794.2790869734663;9778.364406313567;10102.69691909244;10116.645917392856;10135.949320253352;10143.985601439797;10047.547860346302;10047.598096076075;10053.69838904834;10055.245856560641;10061.69814905554;7398.593077380688;7698.961505192438;7808.804779880548;7809.843803123906;7877.606119693993;5533.170024496197;5636.1827535008015;5639.069553523623;5701.515371193329;5741.712914354349;8917.42036767608;8927.553622318728;8974.102589740975;9267;9347.298952578447;9228.676996305097;9403.294752893424;9415.670451534437;9547.570359333708;9550.808983820145;7145.82135446618;7288.198298187302;7290.031691443174;7294.744683936036;7302.817429564303;7141.428685705027;7379.856122301052;7631;7850.136484986761;7869.173736757787;6898.724051037902;7115.82210444743;7154.320339567733;7166.856662866609;7243.963780181065;7002.299770022957;7060.646967651494;7072.2220555737795;7088.0785497884945;7108.964455177691;6311.337309582428;6358.90461643085;6430.421262086435;6439.647673988515;6452.483801295979;4887.731174785279;4921.926171107365;4942.92585611209;5035.093683137071;5150.124478838681;3765.661090501868;4921;4994.225894986283;5019.32239147725;5226.216067589892 06:56:49 INFO - PROCESS | 2281 | |4;prototype.html;354.7038223083725;357.64771699875615;365.66541614422806;374.9662530372266;376.4259504256008;5118.436971933084;5138.920826626413;5303.257543943846;5375.569954403648;5388.299521062257;4539.795709193086;4755.8811029724275;4935.901281974358;5198.610104242188;5264.263003179558;3530.7881527108393;3974.4435778990965;4001;4017.919641607166;4020.2763502569524;3715.4984077149643;4014.959850401488;4028.6374226319626;4041;4072.5316588592245;3693.1136527233407;3706.369917114084;3855.595162507924;3949.1904159647347;4008.5769551809126;3664.6885014773775;3853.0752619371287;3966.3455529837543;3978.1645854370327;3983.9203215935663;2931.516299810529;3066.4173806976605;3456.9110730120183;3459.7405194610255;3474.2356681530277;4289.914201715948;4314.82740690372;4722.787474563653;5082.669626474304;5097.7960881564695;3904.1996390740114;4084.4281800547947;4544.681872268942;4564.88587785308;4570.382998295221;4708.398075472232;5391.002664507039;5503.972480137613;5520.696361700105;5566.777328906839;3253.3818574470774;3322.4850148227065;3816.122291872857;3823.9617603823885;3862.382018876994;5449.972750136264;5729.656220626775;5788.507976821933;5835.074425439595;5862.677552734598;3547.130952916552;3795.1972812914196;3837.270918525471;3849;3860.6911447084167;3511.367953768295;3914.745541539791;3917.5690674025836;3925.194410571169;3925.9018524537114;4195.14729983608;4528.007676622342;4559.589636932626;4560.749158796298;4595.1728688835665;4996.975015124901;5565.638233514808;5594.13290939905;5604.898163188805;5618.69097199658;1102.0742576236;1216.9026477881926;1255.668990869681;1263.9368031598383;1275.9043071769654;805.0701439836997;812.545543477181;842.4481964313385;860.5912191708888;864.7849771071654;4605.907881842344;4614.769261536977;4872.269159626085;4883.365162528919;4887.118459393159;5442.081701175227;5449.591280653967;5456.127019676912;5473.589480788957;5491.368492623399;4485.686001979895;4620.5379462053525;4687.882802929954;4933.531314525115;4937.851864444073;2784.6355285910163;2827.2083816531403;2844.5306524423654;2844.880563979807;2850.3325554550406;4478.283474644107;4920.827771027997;5438.673679579238;5592.468715472024;5644.604877658607;4747.643926705511;5159.329287192641;5384.703841288689;5561.58288128392;5577.832665020056;4759.452662943737;4979.825706100269;5022.910909790526;5277.076511610529;5597.076482380443;5206.349206349207;5436.646617969819;5456.781728730806;5457.536109430743;5535.750891209825;4475.776211189493;4670.99147366622;4737.739424331695;5048.59611231108;5335.466453354788;4865.7323847188745;4970.657922361014;5570.359408667893;5622.967444137393;5669.489745922887;3382.8646854126546;3485.8605655773486;3563.679268865711;3622.9818850905576;3673.7979411132646;4674;5136.332276804141;5228.816991405282;5410.756515956713;5410.97294513525;5024.522670346311;5200.843974680614;5208.088584497774;5222.843314700565;5225.686458812484;5221.712805795718;5225.294585230945;5250.369955605352;5264.789408423773;5295.285136506523;4243.809028593659;4915.43472500667;5008;5109.645943824815;5121.334226550524;4487.932681009853;4660.603848672901;5250.343707036621;5283.286756287852;5306.52241298285;4543.318502224693;4679.789409476513;4753.524647535219;4838.758062096951;5135.435102138837 06:56:49 INFO - PROCESS | 2281 | |5;yui.html;698.1587187439136;720.762148490998;731.8426538294262;773.9883901741473;781.4881252779431;14020.369083391259;14712.779308310384;14718;14738;14749.926250368746;13350;13471.124376915493;13473.865261347384;13524.188548687061;13531.323433828295;856.9100244474334;858.7080392666477;865.1478293880523;865.5326123893091;878.7934835313728;864.2567392042838;877.8419884420802;879.2043200903161;880.2385936165203;881.3301890563154;928.7028150991686;931.1898648176096;931.6133804471136;931.7391130483476;935.8549424839126;922.5341202692664;926.4256161180044;926.8563372677179;928.1739252065667;933.355984370782;689.1970853955136;689.2211800665266;690.3614156904894;691.7890043536719;691.9238883722786;14288.642783930381;14626.926865365605;14649.853501464955;14658.780118298235;14775.926120369437;13624.93187534056;13930.860691393058;13959.441622335095;13995.440182392693;14016.22910739909;10248.538815753309;10424.218183636181;11505.884941150649;11534.250273732181;11656.475458604384;3637.5816781070253;3687.3178461984617;3701.444783282529;3721.4789929409903;3784.7161462890394;82004;91310.54344728234;97211;97573;101246.98753012449;9992.850107248398;10577.31247468912;10582.201025825629;10636.149108071335;11122.555097795997;17093.914530427268;21354.466138346354;21678.34964951054;21721.782782171977;21894.671579926315;6377.362263773585;7329.303716146957;7345.19202887682;7492.400607951351;7601.315881570575;4028.617281358266;4092.9181416371507;4132.070284186034;4253.76604286761;4307.935380969288;3589.210373717778;3688.0411093115445;3691.520102386726;3692.649198326155;3709.46212799148;665.6937808608094;668.903009063683;681.8125015620744;699.8985147153634;701.0605788243778;474.1522544551013;512.6898226572944;536.3242314683527;538.71178919278;571.9885602287931;9192.17270445663;9629.32594718377;10171.898281017095;10299.794004119876;10478.266521343585;532.3452153850785;568.8066057540456;575.7725698349128;613.4233820208989;683.6000939450378;4508.120916421332;4603.769811509411;4612.492625811158;4629.907401851944;4660.650451216173;86227;89403;90074.54962725144;90440;92256;83542.16457835466;84497.46507604781;86039;87655;88475.55762221148;52459;75321.24678753142;75467;75609.62195188989;76704;8127.471714338549;8734.388592798443;8782.385233033881;8851.291896648381;9024.413413128126;43493.02281586501;44712;44907.77546112249;46800.531994679615;49334;58667.70666146642;62406.75186496336;65783.67108164428;68515.97226041513;71034.93447598393;13010.414531345923;13054.15148015376;13257.204567725967;13371.866281337452;13535;14037.438502459787;14378.424863005363;14446.855531444551;14691.485797997018;14910;12569.497220111092;12893.93553032229;12907.483700652245;13301.933490332487;13360.131591446914;12900.870991289967;13706.862931370559;13825.58523244264;13850;13877.930610346884;9635.470049147365;12181.147319687538;12183.390830458618;12452.439640216031;12556.309402982924;10955.397453140155;11007.779844402907;11155.998443906017;11215;11274.605388811353;10193.757373377844;10341.689749307532;10378.169746420419;10801.135909127093;10905.672829815117 06:56:49 INFO - PROCESS | 2281 | __end_tp_report 06:56:49 INFO - PROCESS | 2281 | __start_cc_report 06:56:49 INFO - PROCESS | 2281 | _x_x_mozilla_cycle_collect,1436 06:56:49 INFO - PROCESS | 2281 | __end_cc_report 06:56:49 INFO - PROCESS | 2281 | __startTimestamp1486479409452__endTimestamp 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | ------- Summary: start ------- 06:56:49 INFO - PROCESS | 2281 | Number of tests: 6 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | [#0] dojo.html Cycles:165 Average:5663.59 Median:5801.54 stddev:657.95 (11.3%) stddev-sans-first:614.45 06:56:49 INFO - PROCESS | 2281 | Values: 2597.8 3298.6 3318.6 3319.8 3356.2 5423.1 5514.4 5545.4 5626.9 5638.9 5541.9 5567.0 5595.6 5605.4 5631.9 5023.9 5067.7 5128.8 5239.4 5241.2 5138.9 5208.8 5220.2 5230.9 5231.6 5016.0 5043.7 5054.3 5055.9 5151.0 5067.6 5096.5 5100.5 5162.6 5165.5 5016.4 5078.9 5111.3 5133.5 5143.6 5382.4 5443.2 5474.6 5565.5 5576.3 6041.6 6063.1 6139.7 6153.4 6155.2 6159.8 6206.9 6210.4 6221.1 6225.2 5462.5 5684.2 5695.9 5709.9 5732.7 6170.0 6189.2 6224.6 6231.6 6257.2 5773.8 5823.4 5830.3 5923.2 6074.4 6103.7 6122.7 6129.6 6133.4 6140.6 6176.4 6178.4 6185.4 6188.1 6190.4 6194.6 6209.8 6212.8 6217.1 6230.1 4772.3 4814.3 4814.7 4907.5 4924.7 4334.4 4383.8 4397.3 4433.9 4484.5 5725.5 5744.2 5747.1 5760.4 5776.9 6216.9 6284.3 6289.3 6301.7 6308.3 5530.8 5576.4 5578.0 5629.3 5741.9 5296.5 5315.7 5317.4 5333.0 5342.8 6269.4 6303.6 6316.5 6319.0 6320.2 6225.5 6230.2 6261.7 6272.4 6276.6 6164.7 6238.9 6255.2 6269.2 6276.5 6138.8 6147.0 6152.0 6161.7 6171.7 5754.5 6034.2 6216.3 6223.7 6241.9 5778.6 5789.9 5935.9 6015.0 6054.3 5896.2 6016.2 6020.3 6054.1 6066.2 5639.4 5673.9 5679.7 5801.5 5860.2 6013.4 6031.8 6068.8 6071.1 6075.2 5888.8 5891.4 5895.5 5895.5 5930.3 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | [#1] ext.html Cycles:180 Average:56896.50 Median:35479.73 stddev:111636.38 (314.6%) stddev-sans-first:111869.13 06:56:49 INFO - PROCESS | 2281 | Values: 456.7 471.2 502.1 505.4 513.8 29696.3 39822.2 41980.0 42050.4 42059.6 34992.0 42669.8 42820.9 42881.8 42909.4 9173.3 9404.5 9419.9 9429.3 9437.3 8016.5 8219.9 11149.6 11424.8 11447.7 11219.9 11300.4 11305.3 11315.6 11323.8 8011.6 11177.4 11254.6 11279.1 11318.5 8024.3 10832.9 11129.8 11173.9 11311.8 40982.4 41314.0 41459.8 41468.8 41492.8 3383.7 3404.6 3920.7 3924.2 4000.3 30090.6 30160.7 30255.4 31329.2 31333.0 2928.1 3021.3 3038.7 3097.3 3198.8 418553.0 425220.0 425747.0 427743.0 428478.0 47816.5 57314.4 57724.4 57764.0 57941.7 48089.0 50490.0 58021.7 59000.1 59196.7 12050.7 14313.8 14763.6 14770.5 14776.9 11317.7 11419.9 11422.8 11423.0 11430.3 14844.7 16975.8 17516.0 17554.9 17622.2 6134.7 7373.6 7450.9 7467.6 7489.6 837.5 853.8 859.6 867.1 883.1 27482.5 27602.0 29115.4 29143.4 29259.7 1004.3 1009.3 1036.4 1038.1 1211.3 13717.2 15285.9 15334.3 15369.3 15402.5 455684.3 457592.0 458623.0 459326.0 462119.0 55963.7 56582.4 56587.0 56604.0 56751.7 1330.8 1381.1 1396.8 1398.2 1402.6 27252.9 28554.3 29028.0 29077.4 29129.9 50048.0 50488.0 50537.7 50641.0 50675.7 325773.4 385521.0 388908.0 391358.0 391728.0 22229.4 25353.7 26596.3 26599.5 26678.3 44302.6 45050.3 45480.5 45558.9 45648.0 33649.3 35057.8 35839.0 37670.7 37763.0 32395.5 35404.9 35512.6 36730.8 36739.8 35446.8 36087.0 36104.0 36306.6 36377.7 30533.2 30730.8 31928.0 32275.7 32403.8 23234.5 24017.2 24034.0 24121.5 24274.8 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | [#2] jquery.html Cycles:180 Average:35556.53 Median:2539.40 stddev:104914.20 (4131.4%) stddev-sans-first:105175.21 06:56:49 INFO - PROCESS | 2281 | Values: 354.0 379.3 379.9 395.8 408.0 14004.9 14222.0 15840.0 16378.9 20723.0 19155.1 19731.9 20175.7 20417.9 20840.6 5631.0 5816.5 5858.0 5870.0 5878.1 3900.7 5455.6 6380.6 6427.2 6459.7 5577.6 5747.4 6364.9 6374.6 6418.1 5923.4 6086.4 6093.0 6096.6 6105.3 5054.3 5293.8 5358.1 5923.1 5943.3 13238.9 13421.8 13437.5 16411.9 23835.5 2488.8 2494.1 2503.8 2575.0 2576.6 9331.3 9791.9 9799.1 10291.4 10298.7 1734.9 1741.1 1761.2 1766.1 1797.1 115432.0 122194.0 122448.0 122479.0 123431.4 11474.3 11578.6 11751.1 11774.9 11940.2 15573.8 15600.5 15602.6 15605.5 15607.4 8099.9 8268.7 8269.4 8302.6 8402.6 6760.8 7838.8 7883.4 8072.4 8113.9 4616.2 4710.5 4718.2 4823.1 4829.5 1719.2 1933.6 1984.5 2000.7 2001.8 710.9 831.1 841.5 856.2 859.4 20660.6 21209.0 21225.7 21343.5 21350.8 718.4 834.0 845.0 847.5 881.4 7881.9 11065.0 11085.2 11114.9 11161.1 602209.0 618854.0 631604.0 641987.0 645199.0 130565.0 132407.0 135253.0 137507.3 142094.8 997.4 1000.8 1015.4 1017.1 1023.9 17731.9 18220.9 18296.6 18393.7 18413.7 40896.0 47180.8 50329.0 52602.5 55573.7 63123.4 77386.5 77548.2 83220.0 85465.6 5536.3 7755.0 7925.2 8004.4 8361.4 9530.7 12078.1 12242.0 12432.8 12550.0 10671.9 10799.8 10817.2 10960.3 10969.6 10281.6 10397.8 10772.0 11010.1 11014.6 10757.3 10862.0 10966.3 11037.8 11080.1 7910.7 8099.4 8155.5 8191.0 8345.7 8017.4 10269.4 10489.9 10918.5 10939.6 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | [#3] mootools.html Cycles:180 Average:5398.17 Median:5088.97 stddev:2973.65 (58.4%) stddev-sans-first:2966.34 06:56:49 INFO - PROCESS | 2281 | Values: 1338.7 1419.7 1427.5 1440.3 1442.7 8129.7 8530.9 8532.1 8538.5 8558.9 8110.1 8384.9 8497.7 8533.9 8550.1 2248.4 2288.2 2289.3 2293.5 2295.9 1973.6 2243.6 2290.3 2298.2 2303.2 2008.0 2040.9 2095.8 2276.9 2283.1 2156.9 2225.8 2246.2 2252.8 2254.4 2221.3 2281.2 2287.7 2288.0 2291.4 8477.7 8531.1 8534.3 8555.9 8558.2 5054.2 5151.8 5154.1 5222.2 5223.9 3288.0 3327.3 3492.6 3559.2 3572.2 1203.8 1226.1 1226.7 1237.0 1256.3 9514.0 10819.8 10964.6 10969.3 10983.2 4146.9 4407.8 4419.0 4485.6 4495.5 8761.7 8771.2 8772.3 8775.8 8778.0 5056.8 5086.2 5091.8 5092.3 5105.4 4582.2 4611.3 4643.6 4754.8 4803.4 3582.1 3624.1 3631.6 3662.7 3719.6 1140.7 1145.5 1145.6 1165.3 1165.6 701.9 726.0 784.0 786.5 862.4 5104.0 5470.4 5750.2 5769.2 5823.6 924.8 984.3 992.8 1005.2 1010.3 3293.9 3624.6 3690.8 3743.6 3794.3 9778.4 10102.7 10116.6 10135.9 10144.0 10047.5 10047.6 10053.7 10055.2 10061.7 7398.6 7699.0 7808.8 7809.8 7877.6 5533.2 5636.2 5639.1 5701.5 5741.7 8917.4 8927.6 8974.1 9267.0 9347.3 9228.7 9403.3 9415.7 9547.6 9550.8 7145.8 7288.2 7290.0 7294.7 7302.8 7141.4 7379.9 7631.0 7850.1 7869.2 6898.7 7115.8 7154.3 7166.9 7244.0 7002.3 7060.6 7072.2 7088.1 7109.0 6311.3 6358.9 6430.4 6439.6 6452.5 4887.7 4921.9 4942.9 5035.1 5150.1 3765.7 4921.0 4994.2 5019.3 5226.2 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | [#4] prototype.html Cycles:180 Average:4324.40 Median:4750.58 stddev:1299.86 (27.4%) stddev-sans-first:1268.90 06:56:49 INFO - PROCESS | 2281 | Values: 354.7 357.6 365.7 375.0 376.4 5118.4 5138.9 5303.3 5375.6 5388.3 4539.8 4755.9 4935.9 5198.6 5264.3 3530.8 3974.4 4001.0 4017.9 4020.3 3715.5 4015.0 4028.6 4041.0 4072.5 3693.1 3706.4 3855.6 3949.2 4008.6 3664.7 3853.1 3966.3 3978.2 3983.9 2931.5 3066.4 3456.9 3459.7 3474.2 4289.9 4314.8 4722.8 5082.7 5097.8 3904.2 4084.4 4544.7 4564.9 4570.4 4708.4 5391.0 5504.0 5520.7 5566.8 3253.4 3322.5 3816.1 3824.0 3862.4 5450.0 5729.7 5788.5 5835.1 5862.7 3547.1 3795.2 3837.3 3849.0 3860.7 3511.4 3914.7 3917.6 3925.2 3925.9 4195.1 4528.0 4559.6 4560.7 4595.2 4997.0 5565.6 5594.1 5604.9 5618.7 1102.1 1216.9 1255.7 1263.9 1275.9 805.1 812.5 842.4 860.6 864.8 4605.9 4614.8 4872.3 4883.4 4887.1 5442.1 5449.6 5456.1 5473.6 5491.4 4485.7 4620.5 4687.9 4933.5 4937.9 2784.6 2827.2 2844.5 2844.9 2850.3 4478.3 4920.8 5438.7 5592.5 5644.6 4747.6 5159.3 5384.7 5561.6 5577.8 4759.5 4979.8 5022.9 5277.1 5597.1 5206.3 5436.6 5456.8 5457.5 5535.8 4475.8 4671.0 4737.7 5048.6 5335.5 4865.7 4970.7 5570.4 5623.0 5669.5 3382.9 3485.9 3563.7 3623.0 3673.8 4674.0 5136.3 5228.8 5410.8 5411.0 5024.5 5200.8 5208.1 5222.8 5225.7 5221.7 5225.3 5250.4 5264.8 5295.3 4243.8 4915.4 5008.0 5109.6 5121.3 4487.9 4660.6 5250.3 5283.3 5306.5 4543.3 4679.8 4753.5 4838.8 5135.4 06:56:49 INFO - PROCESS | 2281 | 06:56:49 INFO - PROCESS | 2281 | [#5] yui.html Cycles:180 Average:19097.20 Median:4555.95 stddev:26739.14 (586.9%) stddev-sans-first:26778.46 06:56:49 INFO - PROCESS | 2281 | Values: 698.2 720.8 731.8 774.0 781.5 14020.4 14712.8 14718.0 14738.0 14749.9 13350.0 13471.1 13473.9 13524.2 13531.3 856.9 858.7 865.1 865.5 878.8 864.3 877.8 879.2 880.2 881.3 928.7 931.2 931.6 931.7 935.9 922.5 926.4 926.9 928.2 933.4 689.2 689.2 690.4 691.8 691.9 14288.6 14626.9 14649.9 14658.8 14775.9 13624.9 13930.9 13959.4 13995.4 14016.2 10248.5 10424.2 11505.9 11534.3 11656.5 3637.6 3687.3 3701.4 3721.5 3784.7 82004.0 91310.5 97211.0 97573.0 101247.0 9992.9 10577.3 10582.2 10636.1 11122.6 17093.9 21354.5 21678.3 21721.8 21894.7 6377.4 7329.3 7345.2 7492.4 7601.3 4028.6 4092.9 4132.1 4253.8 4307.9 3589.2 3688.0 3691.5 3692.6 3709.5 665.7 668.9 681.8 699.9 701.1 474.2 512.7 536.3 538.7 572.0 9192.2 9629.3 10171.9 10299.8 10478.3 532.3 568.8 575.8 613.4 683.6 4508.1 4603.8 4612.5 4629.9 4660.7 86227.0 89403.0 90074.5 90440.0 92256.0 83542.2 84497.5 86039.0 87655.0 88475.6 52459.0 75321.2 75467.0 75609.6 76704.0 8127.5 8734.4 8782.4 8851.3 9024.4 43493.0 44712.0 44907.8 46800.5 49334.0 58667.7 62406.8 65783.7 68516.0 71034.9 13010.4 13054.2 13257.2 13371.9 13535.0 14037.4 14378.4 14446.9 14691.5 14910.0 12569.5 12893.9 12907.5 13301.9 13360.1 12900.9 13706.9 13825.6 13850.0 13877.9 9635.5 12181.1 12183.4 12452.4 12556.3 10955.4 11007.8 11156.0 11215.0 11274.6 10193.8 10341.7 10378.2 10801.1 10905.7 06:56:49 INFO - PROCESS | 2281 | -------- Summary: end -------- 06:56:49 INFO - PROCESS | 2281 | 06:56:50 INFO - TEST-INFO | 2281: exit 0 06:56:50 INFO - TEST-OK | dromaeo_css | took 1118931ms 06:56:50 INFO - TEST-START | kraken 06:56:50 INFO - operating with platform_type : mac_ 06:56:50 INFO - Initialising browser for kraken test... 06:56:50 INFO - TEST-INFO | started process 2283 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpSszj1p/profile http://localhost:49731/getInfo.html) 06:56:52 INFO - PROCESS | 2283 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:56:52 INFO - PROCESS | 2283 | __metrics Screen width/height:1600/1200 06:56:52 INFO - PROCESS | 2283 | colorDepth:24 06:56:52 INFO - PROCESS | 2283 | Browser inner width/height: 1024/689 06:56:52 INFO - PROCESS | 2283 | __metrics 06:56:52 INFO - PROCESS | 2283 | 1486479412546 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:56:52 INFO - PROCESS | 2283 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:56:52 INFO - PROCESS | 2283 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:56:53 INFO - TEST-INFO | 2283: exit 0 06:56:53 INFO - Browser initialized. 06:56:53 INFO - Running cycle 1/1 for kraken test... 06:56:53 INFO - TEST-INFO | started process 2284 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpSszj1p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:57:05 INFO - PROCESS | 2284 | RSS: Main: 236904448 06:57:05 INFO - PROCESS | 2284 | 06:57:08 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49731/tests/kraken/driver.html?testName=audio-beat-detection) 06:57:09 INFO - PROCESS | 2284 | RSS: Main: 289034240 06:57:09 INFO - PROCESS | 2284 | 06:57:11 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49731/tests/kraken/driver.html?testName=audio-dft) 06:57:12 INFO - PROCESS | 2284 | RSS: Main: 290217984 06:57:12 INFO - PROCESS | 2284 | 06:57:15 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49731/tests/kraken/driver.html?testName=audio-fft) 06:57:16 INFO - PROCESS | 2284 | RSS: Main: 381026304 06:57:16 INFO - PROCESS | 2284 | 06:57:18 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49731/tests/kraken/driver.html?testName=audio-oscillator) 06:57:18 INFO - PROCESS | 2284 | RSS: Main: 284041216 06:57:18 INFO - PROCESS | 2284 | 06:57:20 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49731/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:57:21 INFO - PROCESS | 2284 | RSS: Main: 287248384 06:57:21 INFO - PROCESS | 2284 | 06:57:24 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49731/tests/kraken/driver.html?testName=imaging-darkroom) 06:57:24 INFO - PROCESS | 2284 | RSS: Main: 329412608 06:57:24 INFO - PROCESS | 2284 | 06:57:27 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49731/tests/kraken/driver.html?testName=imaging-desaturate) 06:57:28 INFO - PROCESS | 2284 | RSS: Main: 332005376 06:57:28 INFO - PROCESS | 2284 | 06:57:31 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49731/tests/kraken/driver.html?testName=json-parse-financial) 06:57:31 INFO - PROCESS | 2284 | RSS: Main: 328298496 06:57:31 INFO - PROCESS | 2284 | 06:57:33 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49731/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:57:34 INFO - PROCESS | 2284 | RSS: Main: 305758208 06:57:34 INFO - PROCESS | 2284 | 06:57:36 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:57:36 INFO - PROCESS | 2284 | RSS: Main: 312655872 06:57:36 INFO - PROCESS | 2284 | 06:57:38 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:57:39 INFO - PROCESS | 2284 | RSS: Main: 316686336 06:57:39 INFO - PROCESS | 2284 | 06:57:42 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:57:43 INFO - PROCESS | 2284 | RSS: Main: 330670080 06:57:43 INFO - PROCESS | 2284 | 06:57:46 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:57:46 INFO - PROCESS | 2284 | RSS: Main: 324521984 06:57:46 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | Cycle 1(1): loaded http://localhost:49731/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49731/tests/kraken/driver.html?testName=ai-astar) 06:57:48 INFO - PROCESS | 2284 | RSS: Main: 369758208 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | __start_tp_report 06:57:48 INFO - PROCESS | 2284 | _x_x_mozilla_page_load 06:57:48 INFO - PROCESS | 2284 | _x_x_mozilla_page_load_details 06:57:48 INFO - PROCESS | 2284 | |i|pagename|runs| 06:57:48 INFO - PROCESS | 2284 | |0;ai-astar;115;114;114;113;114;115;113;114;113;114 06:57:48 INFO - PROCESS | 2284 | |1;audio-beat-detection;141;112;111;146;111;111;103;111;129;111 06:57:48 INFO - PROCESS | 2284 | |2;audio-dft;183;183;182;184;186;184;181;180;184;184 06:57:48 INFO - PROCESS | 2284 | |3;audio-fft;79;58;59;89;61;60;58;58;74;59 06:57:48 INFO - PROCESS | 2284 | |4;audio-oscillator;84;70;87;71;68;70;87;69;69;84 06:57:48 INFO - PROCESS | 2284 | |5;imaging-gaussian-blur;88;85;88;86;87;86;89;86;88;84 06:57:48 INFO - PROCESS | 2284 | |6;imaging-darkroom;97;98;104;99;97;97;97;98;98;126 06:57:48 INFO - PROCESS | 2284 | |7;imaging-desaturate;87;95;87;70;131;141;91;86;87;86 06:57:48 INFO - PROCESS | 2284 | |8;json-parse-financial;59;58;57;57;59;59;59;58;58;61 06:57:48 INFO - PROCESS | 2284 | |9;json-stringify-tinderbox;67;71;71;74;71;69;70;70;70;70 06:57:48 INFO - PROCESS | 2284 | |10;stanford-crypto-aes;89;87;86;84;87;88;89;89;90;91 06:57:48 INFO - PROCESS | 2284 | |11;stanford-crypto-ccm;141;153;145;126;136;137;139;138;184;156 06:57:48 INFO - PROCESS | 2284 | |12;stanford-crypto-pbkdf2;173;173;173;168;172;172;172;171;170;174 06:57:48 INFO - PROCESS | 2284 | |13;stanford-crypto-sha256-iterative;67;68;68;67;66;69;66;66;65;68 06:57:48 INFO - PROCESS | 2284 | __end_tp_report 06:57:48 INFO - PROCESS | 2284 | __start_cc_report 06:57:48 INFO - PROCESS | 2284 | _x_x_mozilla_cycle_collect,2094 06:57:48 INFO - PROCESS | 2284 | __end_cc_report 06:57:48 INFO - PROCESS | 2284 | __startTimestamp1486479468745__endTimestamp 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | ------- Summary: start ------- 06:57:48 INFO - PROCESS | 2284 | Number of tests: 14 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 06:57:48 INFO - PROCESS | 2284 | Values: 115.0 114.0 114.0 113.0 114.0 115.0 113.0 114.0 113.0 114.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#1] audio-beat-detection Cycles:10 Average:118.60 Median:111.50 stddev:14.67 (13.2%) stddev-sans-first:13.13 06:57:48 INFO - PROCESS | 2284 | Values: 141.0 112.0 111.0 146.0 111.0 111.0 103.0 111.0 129.0 111.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:1.73 (0.9%) stddev-sans-first:1.83 06:57:48 INFO - PROCESS | 2284 | Values: 183.0 183.0 182.0 184.0 186.0 184.0 181.0 180.0 184.0 184.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#3] audio-fft Cycles:10 Average:65.50 Median:60.50 stddev:11.11 (18.4%) stddev-sans-first:10.65 06:57:48 INFO - PROCESS | 2284 | Values: 79.0 58.0 59.0 89.0 61.0 60.0 58.0 58.0 74.0 59.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.50 stddev:8.36 (10.8%) stddev-sans-first:8.34 06:57:48 INFO - PROCESS | 2284 | Values: 84.0 70.0 87.0 71.0 68.0 70.0 87.0 69.0 69.0 84.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:1.57 (1.8%) stddev-sans-first:1.59 06:57:48 INFO - PROCESS | 2284 | Values: 88.0 85.0 88.0 86.0 87.0 86.0 89.0 86.0 88.0 84.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:97.50 stddev:9.00 (9.2%) stddev-sans-first:9.42 06:57:48 INFO - PROCESS | 2284 | Values: 97.0 98.0 104.0 99.0 97.0 97.0 97.0 98.0 98.0 126.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:87.00 stddev:22.10 (25.4%) stddev-sans-first:23.19 06:57:48 INFO - PROCESS | 2284 | Values: 87.0 95.0 87.0 70.0 131.0 141.0 91.0 86.0 87.0 86.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:59.00 stddev:1.18 (2.0%) stddev-sans-first:1.24 06:57:48 INFO - PROCESS | 2284 | Values: 59.0 58.0 57.0 57.0 59.0 59.0 59.0 58.0 58.0 61.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:1.77 (2.5%) stddev-sans-first:1.41 06:57:48 INFO - PROCESS | 2284 | Values: 67.0 71.0 71.0 74.0 71.0 69.0 70.0 70.0 70.0 70.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:89.00 stddev:2.05 (2.3%) stddev-sans-first:2.15 06:57:48 INFO - PROCESS | 2284 | Values: 89.0 87.0 86.0 84.0 87.0 88.0 89.0 89.0 90.0 91.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:143.00 stddev:16.02 (11.2%) stddev-sans-first:16.91 06:57:48 INFO - PROCESS | 2284 | Values: 141.0 153.0 145.0 126.0 136.0 137.0 139.0 138.0 184.0 156.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:172.50 stddev:1.75 (1.0%) stddev-sans-first:1.80 06:57:48 INFO - PROCESS | 2284 | Values: 173.0 173.0 173.0 168.0 172.0 172.0 172.0 171.0 170.0 174.0 06:57:48 INFO - PROCESS | 2284 | 06:57:48 INFO - PROCESS | 2284 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.25 (1.8%) stddev-sans-first:1.32 06:57:48 INFO - PROCESS | 2284 | Values: 67.0 68.0 68.0 67.0 66.0 69.0 66.0 66.0 65.0 68.0 06:57:48 INFO - PROCESS | 2284 | -------- Summary: end -------- 06:57:48 INFO - PROCESS | 2284 | 06:57:49 INFO - TEST-INFO | 2284: exit 0 06:57:49 INFO - TEST-OK | kraken | took 59639ms 06:57:49 INFO - SUITE-END | took 1178s 06:57:49 INFO - Completed test suite (00:19:38) 06:57:49 INFO - javascript benchmark 06:57:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7044.973717871821, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2597.8441293522383, 3298.6206586242574, 3318.5851768528933, 3319.8340082995846, 3356.16095976006, 5423.078076726956, 5514.36584792749, 5545.445455454453, 5626.887462250757, 5638.887222255557, 5541.889162216753, 5567.0, 5595.552355811536, 5605.439456054392, 5631.859203519914, 5023.899522009558, 5067.670601410915, 5128.769205385766, 5239.371275446952, 5241.161414173733, 5138.948610513903, 5208.765605547759, 5220.243064755608, 5230.92153617696, 5231.607629427789, 5016.021875734234, 5043.747812609392, 5054.342935418409, 5055.87360315995, 5150.974245128769, 5067.645264831426, 5096.541311282002, 5100.515451032146, 5162.586993040548, 5165.535101840815, 5016.44819069902, 5078.873028174288, 5111.258867464234, 5133.512316329943, 5143.614228932845, 5382.434844341327, 5443.1835224716215, 5474.644148130359, 5565.526930210937, 5576.302962129734, 6041.6375017499095, 6063.090536419572, 6139.662318572477, 6153.384661533811, 6155.199824022893, 6159.7536098556, 6206.875862482725, 6210.410011048988, 6221.097940798604, 6225.159603453521, 5462.5083742462575, 5684.175794509773, 5695.943040569624, 5709.914351284734, 5732.742026608813, 6170.0, 6189.164462797555, 6224.595401298901, 6231.626102433868, 6257.155284036688, 5773.769049238067, 5823.359430462646, 5830.271216097988, 5923.200367950271, 6074.42292982166, 6103.738726210847, 6122.663253521099, 6129.63387754696, 6133.407859146218, 6140.6315621061985, 6176.3514830943, 6178.41305076017, 6185.381461853869, 6188.1027251048345, 6190.380961903774, 6194.566380353332, 6209.782657606962, 6212.844678883064, 6217.129601855744, 6230.06549017651, 4772.331873537708, 4814.253790662453, 4814.711117332901, 4907.484714105039, 4924.679895806761, 4334.371516130206, 4383.758893260838, 4397.274449715825, 4433.866983990485, 4484.529124441952, 5725.484706376446, 5744.224529688522, 5747.080467125324, 5760.36635970043, 5776.942230577641, 6216.906746398808, 6284.308726040132, 6289.276733175652, 6301.747930082745, 6308.337624549356, 5530.778768849201, 5576.414476479992, 5578.023845826964, 5629.324481062217, 5741.85645358861, 5296.523312901781, 5315.654482458628, 5317.361916569959, 5333.040052790535, 5342.813001544927, 6269.373062693694, 6303.590266632654, 6316.463100636405, 6319.0, 6320.209973753281, 6225.533085018732, 6230.190075290183, 6261.655608941552, 6272.372762723874, 6276.623402595859, 6164.660943648142, 6238.906416403667, 6255.155554000152, 6269.185005949197, 6276.497880169667, 6138.756267604092, 6147.0, 6152.0, 6161.691915404302, 6171.660558669317, 5754.51086657622, 6034.185384972972, 6216.2540495139065, 6223.688815559294, 6241.906371404523, 5778.595498315078, 5789.94210057894, 5935.940640593539, 6014.969925150346, 6054.303755068224, 5896.204012458435, 6016.157737916607, 6020.277566692025, 6054.122152287981, 6066.211392518944, 5639.436056394327, 5673.8865222694485, 5679.744411501575, 5801.535877129735, 5860.238169037998, 6013.3685962975005, 6031.849203769941, 6068.787592434243, 6071.089336599545, 6075.2405949256345, 5888.823335299948, 5891.351951285269, 5895.469407753323, 5895.469407753323, 5930.318013428511], "value": 5618.421718788027, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.6529437627402, 471.2130741661425, 502.1313128288059, 505.3556715188136, 513.7508308470393, 29696.25759356017, 39822.20355592886, 41980.0, 42050.369244461355, 42059.57940420595, 34992.0, 42669.7866510667, 42820.9294767631, 42881.785591072075, 42909.35635965463, 9173.266138708905, 9404.52977351133, 9419.858702119474, 9429.292803039765, 9437.339386242966, 8016.519008859458, 8219.917800821975, 11149.554017839277, 11424.828627570552, 11447.656570302863, 11219.943900280487, 11300.378479183642, 11305.321680699144, 11315.603953861617, 11323.830142547868, 8011.639476223584, 11177.385243811586, 11254.606088786935, 11279.097672186246, 11318.547258109666, 8024.277814996678, 10832.945835270852, 11129.777404451946, 11173.888261117447, 11311.773764524745, 40982.38526422106, 41314.0, 41459.7927010363, 41468.79265603652, 41492.79253603743, 3383.712384447325, 3404.5744281964753, 3920.6863450923856, 3924.1759230561615, 4000.2799496090693, 30090.64592093361, 30160.698393016228, 30255.394892102035, 31329.216769580715, 31333.0, 2928.07765553852, 3021.305099827052, 3038.7113224243662, 3097.2566584019673, 3198.824064676442, 418553.0, 425220.0, 425747.0, 427743.0, 428478.0, 47816.5218347819, 57314.42685573174, 57724.42275577274, 57764.0, 57941.710291448275, 48089.0, 50490.0, 58021.709891451115, 59000.11499827506, 59196.70401647964, 12050.698732531757, 14313.78529322061, 14763.630909227355, 14770.48303309379, 14776.92611536957, 11317.71705707364, 11419.942900285612, 11422.828657570142, 11423.0, 11430.25703329281, 14844.70310593782, 16975.830241697673, 17516.03661798588, 17554.91222543879, 17622.207000685, 6134.723937422737, 7373.5944523050675, 7450.888236676454, 7467.58928258951, 7489.550626962399, 837.5184269045324, 853.7566793463833, 859.6470402025918, 867.128535821508, 883.1389395339493, 27482.45035099327, 27602.033928812394, 29115.41769164605, 29143.417131657672, 29259.7074029257, 1004.2969921055291, 1009.2884516415909, 1036.3781730961364, 1038.1383451735041, 1211.2732360583755, 13717.17696938187, 15285.9235703823, 15334.309956052004, 15369.334829809037, 15402.460913868184, 455684.3294701164, 457592.0, 458623.0, 459326.0, 462119.0, 55963.720181398836, 56582.43417565854, 56587.0, 56604.0, 56751.71624141935, 1330.846952600444, 1381.1160857051495, 1396.8114304568753, 1398.1680899864564, 1402.6423262068108, 27252.863735681196, 28554.286142846595, 29028.0, 29077.41845163127, 29129.85435072811, 50047.99904001973, 50488.0, 50537.74731126321, 50641.0, 50675.746621266655, 325773.3711331428, 385521.0, 388908.0, 391358.0, 391728.0, 22229.444263893532, 25353.746462535877, 26596.335091622863, 26599.468010640066, 26678.333041674112, 44302.556974431136, 45050.324245137, 45480.545194548955, 45558.86102847323, 45648.0, 33649.32701346008, 35057.8247108773, 35839.0, 37670.68152614645, 37763.0, 32395.51406728948, 35404.93785186449, 35512.64487355093, 36730.81634591917, 36739.816300918326, 35446.822765886005, 36087.0, 36104.0, 36306.636933630325, 36377.72677956259, 30533.2447007908, 30730.84634576813, 31928.0, 32275.677243227266, 32403.837980809945, 23234.535309294057, 24017.225100288473, 24034.0, 24121.51756964886, 24274.75725242725], "value": 18984.11545962761, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.03701930748355, 379.33237501996484, 379.8613506070285, 395.82583663188194, 407.9877603671889, 14004.859951400484, 14222.004459687812, 15840.0, 16378.918105409457, 20722.963851807388, 19155.138018789152, 19731.901340493314, 20175.697364539545, 20417.877016764072, 20840.583188336223, 5630.971845140769, 5816.476517113459, 5858.03755741827, 5869.9706501467645, 5878.088896221093, 3900.726949113563, 5455.563554915597, 6380.617162970213, 6427.2287325521, 6459.677016149197, 5577.609567330289, 5747.425257474261, 6364.872702545947, 6374.617522948638, 6418.101465794793, 5923.378045305246, 6086.360932102154, 6093.025115981422, 6096.634201947897, 6105.32841387447, 5054.267131265946, 5293.841184764463, 5358.089124848785, 5923.052311630162, 5943.3165186003735, 13238.933805331008, 13421.798673019912, 13437.462501499927, 16411.91794041022, 23835.52328953411, 2488.813338999564, 2494.0896572751, 2503.7871780898645, 2575.0214918331103, 2576.5748651472486, 9331.253499720005, 9791.853122203172, 9799.11807937282, 10291.382517048925, 10298.691039268833, 1734.9392820736077, 1741.1033317841195, 1761.2250609731677, 1766.1257677449616, 1797.0644289007032, 115432.0, 122194.0, 122447.99999999911, 122478.99999999911, 123431.38284308522, 11474.311541307548, 11578.594749183738, 11751.061074922542, 11774.94112529432, 11940.164188506722, 15573.76639350388, 15600.453984110502, 15602.609934751723, 15605.453809116627, 15607.3757049719, 8099.919000810034, 8268.710595129261, 8269.421140520106, 8302.626381812834, 8402.621882015324, 6760.8168570499965, 7838.843223135619, 7883.448158628956, 8072.394570407243, 8113.8782918256275, 4616.16908956391, 4710.458297295786, 4718.245080787057, 4823.107725070873, 4829.517048295213, 1719.2263481433406, 1933.6422761789115, 1984.4745222929878, 2000.7499062617173, 2001.8098280663314, 710.8578284343153, 831.1107115386599, 841.469873979389, 856.2293935458138, 859.3726579596929, 20660.586788264452, 21209.045592948354, 21225.68161477579, 21343.46641333948, 21350.78649213519, 718.396546900606, 833.954132522705, 844.9873251901226, 847.546562589012, 881.4094556647062, 7881.921180788233, 11065.0, 11085.22403431752, 11114.888851111386, 11161.107111431065, 602209.0, 618854.0, 631604.0, 641987.0, 645199.0, 130565.0, 132407.0, 135253.0, 137507.31246343904, 142094.78137663798, 997.396575072071, 1000.7848312612823, 1015.4313584392763, 1017.1354348803458, 1023.8873723890366, 17731.93608383501, 18220.90889545544, 18296.634067318846, 18393.72409413833, 18413.723794143367, 40896.0, 47180.820479488284, 50329.0, 52602.47397525976, 55573.722131389084, 63123.36876631175, 77386.45227095314, 77548.2245177541, 83220.0, 85465.57267213624, 5536.280283563272, 7754.961225193838, 7925.207479252029, 8004.399670024888, 8361.414700970874, 9530.666426675032, 12078.09414293949, 12241.959433448257, 12432.813507797571, 12550.0, 10671.89328106709, 10799.784004320027, 10817.242793004414, 10960.342379457257, 10969.561217551527, 10281.640142594973, 10397.844032339672, 10772.0, 11010.064144547803, 11014.614488492864, 10757.30077544957, 10862.0, 10966.342019478856, 11037.779244415227, 11080.05819505351, 7910.7231246906085, 8099.392545558989, 8155.469894456842, 8190.959045204736, 8345.733324707116, 8017.358611311198, 10269.435181065113, 10489.947550262505, 10918.454077295944, 10939.56241750321], "value": 9267.166940407467, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.7255612599417, 1419.730251252262, 1427.5431861804227, 1440.2870579063358, 1442.7330943775398, 8129.67481300748, 8530.872036919443, 8532.146785321465, 8538.530380829052, 8558.91441085589, 8110.107888132316, 8384.91615083849, 8497.660093596249, 8533.91466085339, 8550.059493455716, 2248.3592176229777, 2288.2448791898632, 2289.255991802671, 2293.54129174165, 2295.8507696999727, 1973.595412940351, 2243.5849367866995, 2290.347251033456, 2298.2071185440996, 2303.216906251874, 2007.9598408031832, 2040.9183632654676, 2095.7904209578996, 2276.886155692217, 2283.086765293879, 2156.8921553922396, 2225.810806081485, 2246.247507085112, 2252.8085112765434, 2254.357508110197, 2221.3447033125103, 2281.1901774870016, 2287.7025986621634, 2288.0047179476815, 2291.3584196425027, 8477.74566762998, 8531.061583225903, 8534.317254619615, 8555.914440855637, 8558.229759321692, 5054.1660625996665, 5151.793928242866, 5154.12379895415, 5222.164453687392, 5223.895522089537, 3287.96712032879, 3327.2513684421056, 3492.580890293156, 3559.2169722660974, 3572.160542272564, 1203.8194270859353, 1226.0926914083602, 1226.7239871029037, 1236.9505219791288, 1256.3404212788269, 9513.952430237874, 10819.783604327948, 10964.616238431616, 10969.286996345212, 10983.231173817836, 4146.854860079883, 4407.801648925806, 4418.989359841265, 4485.596296333346, 4495.505494395614, 8761.693340733043, 8771.16673915977, 8772.298216142693, 8775.780605484913, 8778.0, 5056.848294551096, 5086.160783470686, 5091.770870310845, 5092.337996060489, 5105.361829771278, 4582.221022426195, 4611.2852507861335, 4643.582077612963, 4754.833580824655, 4803.351547541107, 3582.1402863313137, 3624.130208749881, 3631.5823680276576, 3662.6520480554304, 3719.642330549384, 1140.7262257058246, 1145.484531960621, 1145.5761368293784, 1165.318288801044, 1165.580391059218, 701.9122609673791, 725.9564426134449, 783.9882401763978, 786.4534148766667, 862.3618522293458, 5104.000878107684, 5470.398256191816, 5750.1949727038245, 5769.249997500298, 5823.621464604787, 924.7641851328003, 984.2716389871587, 992.846108853129, 1005.2309982863115, 1010.3211236355695, 3293.9341213175603, 3624.583172935146, 3690.833912473945, 3743.6069212732273, 3794.2790869734663, 9778.364406313567, 10102.69691909244, 10116.645917392856, 10135.949320253352, 10143.985601439797, 10047.547860346302, 10047.598096076075, 10053.69838904834, 10055.245856560641, 10061.69814905554, 7398.593077380688, 7698.961505192438, 7808.804779880548, 7809.843803123906, 7877.606119693993, 5533.170024496197, 5636.1827535008015, 5639.069553523623, 5701.515371193329, 5741.712914354349, 8917.42036767608, 8927.553622318728, 8974.102589740975, 9267.0, 9347.298952578447, 9228.676996305097, 9403.294752893424, 9415.670451534437, 9547.570359333708, 9550.808983820145, 7145.82135446618, 7288.198298187302, 7290.031691443174, 7294.744683936036, 7302.817429564303, 7141.428685705027, 7379.856122301052, 7631.0, 7850.136484986761, 7869.173736757787, 6898.724051037902, 7115.82210444743, 7154.320339567733, 7166.856662866609, 7243.963780181065, 7002.299770022957, 7060.646967651494, 7072.2220555737795, 7088.0785497884945, 7108.964455177691, 6311.337309582428, 6358.90461643085, 6430.421262086435, 6439.647673988515, 6452.483801295979, 4887.731174785279, 4921.926171107365, 4942.92585611209, 5035.093683137071, 5150.124478838681, 3765.661090501868, 4921.0, 4994.225894986283, 5019.32239147725, 5226.216067589892], "value": 4359.20711518754, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.7038223083725, 357.64771699875615, 365.66541614422806, 374.9662530372266, 376.4259504256008, 5118.436971933084, 5138.920826626413, 5303.257543943846, 5375.569954403648, 5388.299521062257, 4539.795709193086, 4755.8811029724275, 4935.901281974358, 5198.610104242188, 5264.263003179558, 3530.7881527108393, 3974.4435778990965, 4001.0, 4017.919641607166, 4020.2763502569524, 3715.4984077149643, 4014.959850401488, 4028.6374226319626, 4041.0, 4072.5316588592245, 3693.1136527233407, 3706.369917114084, 3855.595162507924, 3949.1904159647347, 4008.5769551809126, 3664.6885014773775, 3853.0752619371287, 3966.3455529837543, 3978.1645854370327, 3983.9203215935663, 2931.516299810529, 3066.4173806976605, 3456.9110730120183, 3459.7405194610255, 3474.2356681530277, 4289.914201715948, 4314.82740690372, 4722.787474563653, 5082.669626474304, 5097.7960881564695, 3904.1996390740114, 4084.4281800547947, 4544.681872268942, 4564.88587785308, 4570.382998295221, 4708.398075472232, 5391.002664507039, 5503.972480137613, 5520.696361700105, 5566.777328906839, 3253.3818574470774, 3322.4850148227065, 3816.122291872857, 3823.9617603823885, 3862.382018876994, 5449.972750136264, 5729.656220626775, 5788.507976821933, 5835.074425439595, 5862.677552734598, 3547.130952916552, 3795.1972812914196, 3837.270918525471, 3849.0, 3860.6911447084167, 3511.367953768295, 3914.745541539791, 3917.5690674025836, 3925.194410571169, 3925.9018524537114, 4195.14729983608, 4528.007676622342, 4559.589636932626, 4560.749158796298, 4595.1728688835665, 4996.975015124901, 5565.638233514808, 5594.13290939905, 5604.898163188805, 5618.69097199658, 1102.0742576236, 1216.9026477881926, 1255.668990869681, 1263.9368031598383, 1275.9043071769654, 805.0701439836997, 812.545543477181, 842.4481964313385, 860.5912191708888, 864.7849771071654, 4605.907881842344, 4614.769261536977, 4872.269159626085, 4883.365162528919, 4887.118459393159, 5442.081701175227, 5449.591280653967, 5456.127019676912, 5473.589480788957, 5491.368492623399, 4485.686001979895, 4620.5379462053525, 4687.882802929954, 4933.531314525115, 4937.851864444073, 2784.6355285910163, 2827.2083816531403, 2844.5306524423654, 2844.880563979807, 2850.3325554550406, 4478.283474644107, 4920.827771027997, 5438.673679579238, 5592.468715472024, 5644.604877658607, 4747.643926705511, 5159.329287192641, 5384.703841288689, 5561.58288128392, 5577.832665020056, 4759.452662943737, 4979.825706100269, 5022.910909790526, 5277.076511610529, 5597.076482380443, 5206.349206349207, 5436.646617969819, 5456.781728730806, 5457.536109430743, 5535.750891209825, 4475.776211189493, 4670.99147366622, 4737.739424331695, 5048.59611231108, 5335.466453354788, 4865.7323847188745, 4970.657922361014, 5570.359408667893, 5622.967444137393, 5669.489745922887, 3382.8646854126546, 3485.8605655773486, 3563.679268865711, 3622.9818850905576, 3673.7979411132646, 4674.0, 5136.332276804141, 5228.816991405282, 5410.756515956713, 5410.97294513525, 5024.522670346311, 5200.843974680614, 5208.088584497774, 5222.843314700565, 5225.686458812484, 5221.712805795718, 5225.294585230945, 5250.369955605352, 5264.789408423773, 5295.285136506523, 4243.809028593659, 4915.43472500667, 5008.0, 5109.645943824815, 5121.334226550524, 4487.932681009853, 4660.603848672901, 5250.343707036621, 5283.286756287852, 5306.52241298285, 4543.318502224693, 4679.789409476513, 4753.524647535219, 4838.758062096951, 5135.435102138837], "value": 3926.033102173104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.1587187439136, 720.762148490998, 731.8426538294262, 773.9883901741473, 781.4881252779431, 14020.369083391259, 14712.779308310384, 14718.0, 14738.0, 14749.926250368746, 13350.0, 13471.124376915493, 13473.865261347384, 13524.188548687061, 13531.323433828295, 856.9100244474334, 858.7080392666477, 865.1478293880523, 865.5326123893091, 878.7934835313728, 864.2567392042838, 877.8419884420802, 879.2043200903161, 880.2385936165203, 881.3301890563154, 928.7028150991686, 931.1898648176096, 931.6133804471136, 931.7391130483476, 935.8549424839126, 922.5341202692664, 926.4256161180044, 926.8563372677179, 928.1739252065667, 933.355984370782, 689.1970853955136, 689.2211800665266, 690.3614156904894, 691.7890043536719, 691.9238883722786, 14288.642783930381, 14626.926865365605, 14649.853501464955, 14658.780118298235, 14775.926120369437, 13624.93187534056, 13930.860691393058, 13959.441622335095, 13995.440182392693, 14016.22910739909, 10248.538815753309, 10424.218183636181, 11505.884941150649, 11534.250273732181, 11656.475458604384, 3637.5816781070253, 3687.3178461984617, 3701.444783282529, 3721.4789929409903, 3784.7161462890394, 82004.0, 91310.54344728234, 97211.0, 97573.0, 101246.98753012449, 9992.850107248398, 10577.31247468912, 10582.201025825629, 10636.149108071335, 11122.555097795997, 17093.914530427268, 21354.466138346354, 21678.34964951054, 21721.782782171977, 21894.671579926315, 6377.362263773585, 7329.303716146957, 7345.19202887682, 7492.400607951351, 7601.315881570575, 4028.617281358266, 4092.9181416371507, 4132.070284186034, 4253.76604286761, 4307.935380969288, 3589.210373717778, 3688.0411093115445, 3691.520102386726, 3692.649198326155, 3709.46212799148, 665.6937808608094, 668.903009063683, 681.8125015620744, 699.8985147153634, 701.0605788243778, 474.1522544551013, 512.6898226572944, 536.3242314683527, 538.71178919278, 571.9885602287931, 9192.17270445663, 9629.32594718377, 10171.898281017095, 10299.794004119876, 10478.266521343585, 532.3452153850785, 568.8066057540456, 575.7725698349128, 613.4233820208989, 683.6000939450378, 4508.120916421332, 4603.769811509411, 4612.492625811158, 4629.907401851944, 4660.650451216173, 86227.0, 89403.0, 90074.54962725144, 90440.0, 92256.0, 83542.16457835466, 84497.46507604781, 86039.0, 87655.0, 88475.55762221148, 52459.0, 75321.24678753142, 75467.0, 75609.62195188989, 76704.0, 8127.471714338549, 8734.388592798443, 8782.385233033881, 8851.291896648381, 9024.413413128126, 43493.02281586501, 44712.0, 44907.77546112249, 46800.531994679615, 49334.0, 58667.70666146642, 62406.75186496336, 65783.67108164428, 68515.97226041513, 71034.93447598393, 13010.414531345923, 13054.15148015376, 13257.204567725967, 13371.866281337452, 13535.0, 14037.438502459787, 14378.424863005363, 14446.855531444551, 14691.485797997018, 14910.0, 12569.497220111092, 12893.93553032229, 12907.483700652245, 13301.933490332487, 13360.131591446914, 12900.870991289967, 13706.862931370559, 13825.58523244264, 13850.0, 13877.930610346884, 9635.470049147365, 12181.147319687538, 12183.390830458618, 12452.439640216031, 12556.309402982924, 10955.397453140155, 11007.779844402907, 11155.998443906017, 11215.0, 11274.605388811353, 10193.757373377844, 10341.689749307532, 10378.169746420419, 10801.135909127093, 10905.672829815117], "value": 7226.293900555403, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1442.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 114.0, 115.0, 113.0, 114.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 111.0, 146.0, 111.0, 111.0, 103.0, 111.0, 129.0, 111.0], "value": 118.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 184.0, 186.0, 184.0, 181.0, 180.0, 184.0, 184.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 89.0, 61.0, 60.0, 58.0, 58.0, 74.0, 59.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 87.0, 71.0, 68.0, 70.0, 87.0, 69.0, 69.0, 84.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 87.0, 86.0, 89.0, 86.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 104.0, 99.0, 97.0, 97.0, 97.0, 98.0, 98.0, 126.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 95.0, 87.0, 70.0, 131.0, 141.0, 91.0, 86.0, 87.0, 86.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 57.0, 59.0, 59.0, 59.0, 58.0, 58.0, 61.0], "value": 58.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 71.0, 74.0, 71.0, 69.0, 70.0, 70.0, 70.0, 70.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 86.0, 84.0, 87.0, 88.0, 89.0, 89.0, 90.0, 91.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 153.0, 145.0, 126.0, 136.0, 137.0, 139.0, 138.0, 184.0, 156.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 173.0, 168.0, 172.0, 172.0, 172.0, 171.0, 170.0, 174.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 66.0, 69.0, 66.0, 66.0, 65.0, 68.0], "value": 67.0, "unit": "score"}]}]} 06:57:49 INFO - Return code: 0 06:57:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:57:50 ERROR - # TBPL SUCCESS # 06:57:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:57:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:57:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:57:50 INFO - 2017-02-07 06:57:50.052 screenresolution[2288:26398] starting screenresolution argv=screenresolution get 06:57:50 INFO - 2017-02-07 06:57:50.055 screenresolution[2288:26398] Display 0: 1600x1200x32@60 06:57:50 INFO - 2017-02-07 06:57:50.062 screenresolution[2289:26401] starting screenresolution argv=screenresolution list 06:57:50 INFO - Available Modes on Display 0 06:57:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:57:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:57:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:57:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:57:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:57:50 INFO - Intel Iris: 06:57:50 INFO - Chipset Model: Intel Iris 06:57:50 INFO - Type: GPU 06:57:50 INFO - Bus: Built-In 06:57:50 INFO - VRAM (Dynamic, Max): 1536 MB 06:57:50 INFO - Vendor: Intel (0x8086) 06:57:50 INFO - Device ID: 0x0a2e 06:57:50 INFO - Revision ID: 0x0009 06:57:50 INFO - Displays: 06:57:50 INFO - Display: 06:57:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:57:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:57:50 INFO - Display Serial Number: 1600x1200 60 06:57:50 INFO - Main Display: Yes 06:57:50 INFO - Mirror: Off 06:57:50 INFO - Online: Yes 06:57:50 INFO - Rotation: Supported 06:57:50 INFO - Return code: 0 06:57:50 INFO - Running post-action listener: _resource_record_post_action 06:57:50 INFO - [mozharness: 2017-02-07 14:57:50.308527Z] Finished run-tests step (success) 06:57:50 INFO - Running post-run listener: _resource_record_post_run 06:57:50 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 348160; Write bytes: 553113600; Read time: 4; Write time: 1658 06:57:50 INFO - TinderboxPrint: CPU usage
53.8% 06:57:50 INFO - TinderboxPrint: I/O read bytes / time
348,160 / 4 06:57:50 INFO - TinderboxPrint: I/O write bytes / time
553,113,600 / 1,658 06:57:50 INFO - TinderboxPrint: CPU idle
2,221.5 (46.2%) 06:57:50 INFO - TinderboxPrint: CPU system
960.1 (20.0%) 06:57:50 INFO - TinderboxPrint: CPU user
1,624.1 (33.8%) 06:57:50 INFO - TinderboxPrint: Swap in / out
252,252,160 / 0 06:57:50 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246288896; Write bytes: 242049536; Read time: 17921; Write time: 507 06:57:50 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 245760; Write bytes: 295675392; Read time: 2; Write time: 1099 06:57:50 INFO - Running post-run listener: _upload_blobber_files 06:57:50 INFO - Blob upload gear active. 06:57:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:57:50 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 06:57:50 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'] 06:57:50 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 06:57:51 INFO - (blobuploader) - INFO - Open directory for files ... 06:57:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:57:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:57:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:57:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:57:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:57:52 INFO - (blobuploader) - INFO - Done attempting. 06:57:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:57:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:57:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:57:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:57:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:57:53 INFO - (blobuploader) - INFO - Done attempting. 06:57:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:57:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:57:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:57:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:57:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:57:54 INFO - (blobuploader) - INFO - Done attempting. 06:57:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:57:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:57:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:57:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:57:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:57:56 INFO - (blobuploader) - INFO - Done attempting. 06:57:56 INFO - (blobuploader) - INFO - Iteration through files over. 06:57:56 INFO - Return code: 0 06:57:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:57:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:57:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8abba08e4b53c9f25156eefd5ca5728c4b3daa86de4b725ec924c21defef2e85d412867707e04c8a28c2f1b62eb05c068fb4cc3f3a24e8aa73f9d3a83e6a17ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c6edce70d7c351024043360f4a7309265099ee4aa483d11e830a84735ea8964bba6950ae1778406750afc85225e8743370bf147e6c6ec900a2fa65299d8042a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a91e6e1d1559c15b5825b258600a3a5000e92545b3f0e1ef5df51a228e8aa1045d4c91ea1812995a13f7127dd9aa7f18fc0a9c191c1d11f7530486a69ad04a2d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22fdf53fe2fae31ad925d71cf0b79a29c0b277fa3fb4f564d5c3242c0a8e8d85dfea962da302af79b01ce25ad8237b45bc6f287efadb8bf1a36d87af8827871"} 06:57:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:57:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:57:56 INFO - Contents: 06:57:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8abba08e4b53c9f25156eefd5ca5728c4b3daa86de4b725ec924c21defef2e85d412867707e04c8a28c2f1b62eb05c068fb4cc3f3a24e8aa73f9d3a83e6a17ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c6edce70d7c351024043360f4a7309265099ee4aa483d11e830a84735ea8964bba6950ae1778406750afc85225e8743370bf147e6c6ec900a2fa65299d8042a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a91e6e1d1559c15b5825b258600a3a5000e92545b3f0e1ef5df51a228e8aa1045d4c91ea1812995a13f7127dd9aa7f18fc0a9c191c1d11f7530486a69ad04a2d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22fdf53fe2fae31ad925d71cf0b79a29c0b277fa3fb4f564d5c3242c0a8e8d85dfea962da302af79b01ce25ad8237b45bc6f287efadb8bf1a36d87af8827871"} 06:57:56 INFO - Running post-run listener: copy_logs_to_upload_dir 06:57:56 INFO - Copying logs to upload dir... 06:57:56 INFO - mkdir: /builds/slave/test/build/upload/logs 06:57:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.375963 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-07 06:57:56.300220) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 06:57:56.303251) ========= 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.AgEyICBIc3/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.oATCcgoynY/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8abba08e4b53c9f25156eefd5ca5728c4b3daa86de4b725ec924c21defef2e85d412867707e04c8a28c2f1b62eb05c068fb4cc3f3a24e8aa73f9d3a83e6a17ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c6edce70d7c351024043360f4a7309265099ee4aa483d11e830a84735ea8964bba6950ae1778406750afc85225e8743370bf147e6c6ec900a2fa65299d8042a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a91e6e1d1559c15b5825b258600a3a5000e92545b3f0e1ef5df51a228e8aa1045d4c91ea1812995a13f7127dd9aa7f18fc0a9c191c1d11f7530486a69ad04a2d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22fdf53fe2fae31ad925d71cf0b79a29c0b277fa3fb4f564d5c3242c0a8e8d85dfea962da302af79b01ce25ad8237b45bc6f287efadb8bf1a36d87af8827871"} build_url:https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011096 build_url: 'https://queue.taskcluster.net/v1/task/V-n89V0uQBKpfLQJXz8WKw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8abba08e4b53c9f25156eefd5ca5728c4b3daa86de4b725ec924c21defef2e85d412867707e04c8a28c2f1b62eb05c068fb4cc3f3a24e8aa73f9d3a83e6a17ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c6edce70d7c351024043360f4a7309265099ee4aa483d11e830a84735ea8964bba6950ae1778406750afc85225e8743370bf147e6c6ec900a2fa65299d8042a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a91e6e1d1559c15b5825b258600a3a5000e92545b3f0e1ef5df51a228e8aa1045d4c91ea1812995a13f7127dd9aa7f18fc0a9c191c1d11f7530486a69ad04a2d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c22fdf53fe2fae31ad925d71cf0b79a29c0b277fa3fb4f564d5c3242c0a8e8d85dfea962da302af79b01ce25ad8237b45bc6f287efadb8bf1a36d87af8827871"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 06:57:56.337110) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:57:56.337443) ========= 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.AgEyICBIc3/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.oATCcgoynY/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.005189 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:57:56.386082) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 06:57:56.386442) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 06:57:56.386758) ========= ========= Total master_lag: 0.20 =========