builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0350 starttime: 1485908816.57 results: success (0) buildid: 20170131151208 builduid: 38ca0a03598a40a9960ddb2436b57e9e revision: c22bc80b4d44f8b710c154eed2b52a73a167b037 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.567706) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.568421) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.568916) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.595910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.596301) ========= 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.xxeEgz7NFy/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.F46QCNqFRK/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-31 16:26:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2017-01-31 16:26:56 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.750115) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.750458) ========= 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.xxeEgz7NFy/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.F46QCNqFRK/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.025901 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:56.799890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 16:26:56.800480) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c22bc80b4d44f8b710c154eed2b52a73a167b037 --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 c22bc80b4d44f8b710c154eed2b52a73a167b037 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xxeEgz7NFy/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.F46QCNqFRK/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-31 16:26:56,909 truncating revision to first 12 chars 2017-01-31 16:26:56,909 Setting DEBUG logging. 2017-01-31 16:26:56,910 attempt 1/10 2017-01-31 16:26:56,910 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c22bc80b4d44?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 16:26:57,900 unpacking tar archive at: mozilla-central-c22bc80b4d44/testing/mozharness/ program finished with exit code 0 elapsedTime=1.269418 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 16:26:58.094515) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:58.094861) ========= script_repo_revision: c22bc80b4d44f8b710c154eed2b52a73a167b037 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:58.095241) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:58.095503) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 16:26:58.115192) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-31 16:26:58.115621) ========= /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.xxeEgz7NFy/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.F46QCNqFRK/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:26:58 INFO - MultiFileLogger online at 20170131 16:26:58 in /builds/slave/test 16:26:58 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 16:26:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:26:58 INFO - {'append_to_log': False, 16:26:58 INFO - 'base_work_dir': '/builds/slave/test', 16:26:58 INFO - 'blob_upload_branch': 'Firefox', 16:26:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:26:58 INFO - 'branch': 'Firefox', 16:26:58 INFO - 'buildbot_json_path': 'buildprops.json', 16:26:58 INFO - 'config_files': ('talos/mac_config.py',), 16:26:58 INFO - 'default_actions': ('clobber', 16:26:58 INFO - 'read-buildbot-config', 16:26:58 INFO - 'download-and-extract', 16:26:58 INFO - 'populate-webroot', 16:26:58 INFO - 'create-virtualenv', 16:26:58 INFO - 'install', 16:26:58 INFO - 'run-tests'), 16:26:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:26:58 INFO - 'download_minidump_stackwalk': True, 16:26:58 INFO - 'download_symbols': 'ondemand', 16:26:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:26:58 INFO - 'tooltool.py': '/tools/tooltool.py', 16:26:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:26:58 INFO - '/tools/misc-python/virtualenv.py')}, 16:26:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:26:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:26:58 INFO - 'installer_path': 'installer.exe', 16:26:58 INFO - 'log_level': 'info', 16:26:58 INFO - 'log_name': 'talos', 16:26:58 INFO - 'log_to_console': True, 16:26:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:26:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:26:58 INFO - 'opt_config_files': (), 16:26:58 INFO - 'pip_index': False, 16:26:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:58 INFO - 'cmd': ('bash', 16:26:58 INFO - '-c', 16:26:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:58 INFO - 'enabled': True, 16:26:58 INFO - 'halt_on_failure': False, 16:26:58 INFO - 'name': 'check_screen_resolution'},), 16:26:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:58 INFO - 'cmd': ('bash', 16:26:58 INFO - '-c', 16:26:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:58 INFO - 'enabled': True, 16:26:58 INFO - 'halt_on_failure': False, 16:26:58 INFO - 'name': 'check_screen_resolution'},), 16:26:58 INFO - 'run_cmd_checks_enabled': True, 16:26:58 INFO - 'sps_profile': False, 16:26:58 INFO - 'sps_profile_interval': 0, 16:26:58 INFO - 'suite': 'dromaeojs', 16:26:58 INFO - 'system_bits': '32', 16:26:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:26:58 INFO - 'title': 't-yosemite-r7-0350', 16:26:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:26:58 INFO - 'use_talos_json': True, 16:26:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:26:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:26:58 INFO - 'work_dir': 'build'} 16:26:58 INFO - [mozharness: 2017-02-01 00:26:58.331255Z] Running clobber step. 16:26:58 INFO - Running pre-action listener: _resource_record_pre_action 16:26:58 INFO - Running main action method: clobber 16:26:58 INFO - rmtree: /builds/slave/test/build 16:26:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:27:00 INFO - Running post-action listener: _resource_record_post_action 16:27:00 INFO - [mozharness: 2017-02-01 00:27:00.944113Z] Finished clobber step (success) 16:27:00 INFO - [mozharness: 2017-02-01 00:27:00.944260Z] Running read-buildbot-config step. 16:27:00 INFO - Running pre-action listener: _resource_record_pre_action 16:27:00 INFO - Running main action method: read_buildbot_config 16:27:00 INFO - Using buildbot properties: 16:27:00 INFO - { 16:27:00 INFO - "project": "", 16:27:00 INFO - "product": "firefox", 16:27:00 INFO - "script_repo_revision": "production", 16:27:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:27:00 INFO - "repository": "", 16:27:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:27:00 INFO - "buildid": "20170131151208", 16:27:00 INFO - "pgo_build": "False", 16:27:00 INFO - "basedir": "/builds/slave/test", 16:27:00 INFO - "buildnumber": 48, 16:27:00 INFO - "slavename": "t-yosemite-r7-0350", 16:27:00 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:27:00 INFO - "platform": "yosemite_r7", 16:27:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:27:00 INFO - "branch": "mozilla-central", 16:27:00 INFO - "repo_path": "mozilla-central", 16:27:00 INFO - "revision": "c22bc80b4d44f8b710c154eed2b52a73a167b037", 16:27:00 INFO - "stage_platform": "macosx64", 16:27:00 INFO - "builduid": "38ca0a03598a40a9960ddb2436b57e9e", 16:27:00 INFO - "slavebuilddir": "test" 16:27:00 INFO - } 16:27:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 16:27:00 INFO - Running post-action listener: _resource_record_post_action 16:27:00 INFO - [mozharness: 2017-02-01 00:27:00.947773Z] Finished read-buildbot-config step (success) 16:27:00 INFO - [mozharness: 2017-02-01 00:27:00.947898Z] Running download-and-extract step. 16:27:00 INFO - Running pre-action listener: _resource_record_pre_action 16:27:00 INFO - Running main action method: download_and_extract 16:27:00 INFO - mkdir: /builds/slave/test/build/tests 16:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:00 INFO - trying https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 16:27:00 INFO - Downloading https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/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 16:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/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 16:27:02 INFO - Downloaded 1199 bytes. 16:27:02 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 16:27:02 INFO - Using the following test package requirements: 16:27:02 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 16:27:02 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 16:27:02 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 16:27:02 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'jsshell-mac.zip'], 16:27:02 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 16:27:02 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 16:27:02 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 16:27:02 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 16:27:02 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 16:27:02 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:27:02 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 16:27:02 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:27:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 16:27:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:27:02 INFO - Fetch https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 16:27:05 INFO - Expected file size: 16371951 16:27:05 INFO - Obtained file size: 16371951 16:27:11 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 16:27:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 16:27:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:27:11 INFO - Fetch https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 16:27:13 INFO - Expected file size: 16371951 16:27:13 INFO - Obtained file size: 16371951 16:27:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 16:27:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:27:19 INFO - Fetch https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 16:27:21 INFO - Expected file size: 14403454 16:27:21 INFO - Obtained file size: 14403454 16:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:21 INFO - trying https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 16:27:21 INFO - Downloading https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 16:27:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/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 16:27:23 INFO - Downloaded 70201193 bytes. 16:27:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 16:27:23 INFO - mkdir: /builds/slave/test/properties 16:27:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:23 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:23 INFO - Contents: 16:27:23 INFO - build_url:https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 16:27:24 INFO - Running post-action listener: _resource_record_post_action 16:27:24 INFO - Running post-action listener: set_extra_try_arguments 16:27:24 INFO - [mozharness: 2017-02-01 00:27:24.117284Z] Finished download-and-extract step (success) 16:27:24 INFO - [mozharness: 2017-02-01 00:27:24.117435Z] Running populate-webroot step. 16:27:24 INFO - Running pre-action listener: _resource_record_pre_action 16:27:24 INFO - Running main action method: populate_webroot 16:27:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:24 INFO - '--tppagecycles', 16:27:24 INFO - '7'], 16:27:24 INFO - 'tests': ['tsvgm']}}, 16:27:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:27:24 INFO - 'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:27:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:27:24 INFO - 'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['damp', 'tps']}, 16:27:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:27:24 INFO - 'tests': ['damp', 'tps']}, 16:27:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['dromaeo_dom']}, 16:27:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['basic_compositor_video']}, 16:27:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['a11yr', 16:27:24 INFO - 'ts_paint', 16:27:24 INFO - 'tpaint', 16:27:24 INFO - 'sessionrestore', 16:27:24 INFO - 'sessionrestore_no_auto_restore', 16:27:24 INFO - 'tabpaint']}, 16:27:24 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:24 INFO - 'ts_paint', 16:27:24 INFO - 'tpaint', 16:27:24 INFO - 'sessionrestore', 16:27:24 INFO - 'sessionrestore_no_auto_restore', 16:27:24 INFO - 'tabpaint']}, 16:27:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:24 INFO - 'ts_paint', 16:27:24 INFO - 'tpaint', 16:27:24 INFO - 'sessionrestore', 16:27:24 INFO - 'sessionrestore_no_auto_restore', 16:27:24 INFO - 'tabpaint']}, 16:27:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:24 INFO - 'ts_paint', 16:27:24 INFO - 'tpaint', 16:27:24 INFO - 'sessionrestore', 16:27:24 INFO - 'sessionrestore_no_auto_restore', 16:27:24 INFO - 'tabpaint']}, 16:27:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['a11yr', 16:27:24 INFO - 'ts_paint', 16:27:24 INFO - 'tpaint', 16:27:24 INFO - 'sessionrestore', 16:27:24 INFO - 'sessionrestore_no_auto_restore', 16:27:24 INFO - 'tabpaint']}, 16:27:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['a11yr', 16:27:24 INFO - 'ts_paint', 16:27:24 INFO - 'tpaint', 16:27:24 INFO - 'sessionrestore', 16:27:24 INFO - 'sessionrestore_no_auto_restore', 16:27:24 INFO - 'tabpaint']}, 16:27:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['tsvgx', 16:27:24 INFO - 'tsvgr_opacity', 16:27:24 INFO - 'tart', 16:27:24 INFO - 'tscrollx', 16:27:24 INFO - 'cart', 16:27:24 INFO - 'tsvg_static']}, 16:27:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:24 INFO - 'tsvgr_opacity', 16:27:24 INFO - 'tart', 16:27:24 INFO - 'tscrollx', 16:27:24 INFO - 'cart', 16:27:24 INFO - 'tsvg_static']}, 16:27:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:27:24 INFO - 'talos_options': ['--disable-e10s'], 16:27:24 INFO - 'tests': ['tp5o']}, 16:27:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:27:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:27:24 INFO - 'talos_options': ['--disable-e10s', 16:27:24 INFO - '--xperf_path', 16:27:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:24 INFO - 'tests': ['tp5n']}, 16:27:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:27:24 INFO - 'talos_options': ['--xperf_path', 16:27:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:24 INFO - 'tests': ['tp5n']}}, 16:27:24 INFO - 'talos.zip': {'path': '', 16:27:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:24 INFO - Running post-action listener: _resource_record_post_action 16:27:24 INFO - [mozharness: 2017-02-01 00:27:24.136032Z] Finished populate-webroot step (success) 16:27:24 INFO - [mozharness: 2017-02-01 00:27:24.136147Z] Running create-virtualenv step. 16:27:24 INFO - Running pre-action listener: _resource_record_pre_action 16:27:24 INFO - Running main action method: create_virtualenv 16:27:24 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:24 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 16:27:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:24 INFO - Using real prefix '/tools/python27' 16:27:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:25 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:27 INFO - Installing pip.................done. 16:27:27 INFO - Return code: 0 16:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:27:28 INFO - Reading from file tmpfile_stdout 16:27:28 INFO - Output received: 16:27:28 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) 16:27:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27: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', 'pip>=1.5'] in /builds/slave/test/build 16:27: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 pip>=1.5 16:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:28 INFO - 'HOME': '/Users/cltbld', 16:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:28 INFO - 'LOGNAME': 'cltbld', 16:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:28 INFO - 'MOZ_NO_REMOTE': '1', 16:27:28 INFO - 'NO_EM_RESTART': '1', 16:27:28 INFO - 'PAGER': '/bin/cat', 16:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:28 INFO - 'PWD': '/builds/slave/test', 16:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:28 INFO - 'SHELL': '/bin/bash', 16:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:28 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:28 INFO - 'USER': 'cltbld', 16:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:28 INFO - 'XPC_FLAGS': '0x0', 16:27:28 INFO - 'XPC_SERVICE_NAME': '0', 16:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:28 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 16:27:28 INFO - Cleaning up... 16:27:28 INFO - Return code: 0 16:27:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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', '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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:27: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 psutil>=3.1.1 16:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:28 INFO - 'HOME': '/Users/cltbld', 16:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:28 INFO - 'LOGNAME': 'cltbld', 16:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:28 INFO - 'MOZ_NO_REMOTE': '1', 16:27:28 INFO - 'NO_EM_RESTART': '1', 16:27:28 INFO - 'PAGER': '/bin/cat', 16:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:28 INFO - 'PWD': '/builds/slave/test', 16:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:28 INFO - 'SHELL': '/bin/bash', 16:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:28 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:28 INFO - 'USER': 'cltbld', 16:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:28 INFO - 'XPC_FLAGS': '0x0', 16:27:28 INFO - 'XPC_SERVICE_NAME': '0', 16:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:28 INFO - Downloading/unpacking psutil>=3.1.1 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:32 INFO - Installing collected packages: psutil 16:27:32 INFO - Running setup.py install for psutil 16:27:32 INFO - building 'psutil._psutil_osx' extension 16:27:32 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 16:27:32 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 16:27:32 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 16:27:32 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 16:27:33 INFO - building 'psutil._psutil_posix' extension 16:27:33 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 16:27:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:27:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:27:33 INFO - ^ 16:27:33 INFO - 1 warning generated. 16:27:33 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 16:27:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:33 INFO - Successfully installed psutil 16:27:33 INFO - Cleaning up... 16:27:33 INFO - Return code: 0 16:27:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:33 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:33 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 16:27:33 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 16:27:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:33 INFO - 'HOME': '/Users/cltbld', 16:27:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:33 INFO - 'LOGNAME': 'cltbld', 16:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:33 INFO - 'MOZ_NO_REMOTE': '1', 16:27:33 INFO - 'NO_EM_RESTART': '1', 16:27:33 INFO - 'PAGER': '/bin/cat', 16:27:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:33 INFO - 'PWD': '/builds/slave/test', 16:27:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:33 INFO - 'SHELL': '/bin/bash', 16:27:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:33 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:33 INFO - 'USER': 'cltbld', 16:27:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:33 INFO - 'XPC_FLAGS': '0x0', 16:27:33 INFO - 'XPC_SERVICE_NAME': '0', 16:27:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:36 INFO - Installing collected packages: mozsystemmonitor 16:27:36 INFO - Running setup.py install for mozsystemmonitor 16:27:36 INFO - Successfully installed mozsystemmonitor 16:27:36 INFO - Cleaning up... 16:27:36 INFO - Return code: 0 16:27:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:36 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')]} 16:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:36 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')]} 16:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:36 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:36 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 16:27:36 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 16:27:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:36 INFO - 'HOME': '/Users/cltbld', 16:27:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:36 INFO - 'LOGNAME': 'cltbld', 16:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:36 INFO - 'MOZ_NO_REMOTE': '1', 16:27:36 INFO - 'NO_EM_RESTART': '1', 16:27:36 INFO - 'PAGER': '/bin/cat', 16:27:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:36 INFO - 'PWD': '/builds/slave/test', 16:27:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:36 INFO - 'SHELL': '/bin/bash', 16:27:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:36 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:36 INFO - 'USER': 'cltbld', 16:27:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:36 INFO - 'XPC_FLAGS': '0x0', 16:27:36 INFO - 'XPC_SERVICE_NAME': '0', 16:27:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:36 INFO - Downloading/unpacking jsonschema==2.5.1 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:39 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 16:27:39 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 16:27:39 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 16:27:39 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 16:27:39 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 16:27:39 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 16:27:39 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:39 INFO - warning: no files found matching '*.txt' 16:27:39 INFO - no previously-included directories found matching 'build' 16:27:39 INFO - no previously-included directories found matching 'dist' 16:27:39 INFO - no previously-included directories found matching '.git*' 16:27:39 INFO - Installing collected packages: jsonschema, functools32 16:27:39 INFO - Running setup.py install for functools32 16:27:39 INFO - warning: no files found matching '*.txt' 16:27:39 INFO - no previously-included directories found matching 'build' 16:27:39 INFO - no previously-included directories found matching 'dist' 16:27:39 INFO - no previously-included directories found matching '.git*' 16:27:40 INFO - Successfully installed jsonschema functools32 16:27:40 INFO - Cleaning up... 16:27:40 INFO - Return code: 0 16:27:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:40 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')]} 16:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 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')]} 16:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:40 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 16:27:40 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 16:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:40 INFO - 'HOME': '/Users/cltbld', 16:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:40 INFO - 'LOGNAME': 'cltbld', 16:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:40 INFO - 'MOZ_NO_REMOTE': '1', 16:27:40 INFO - 'NO_EM_RESTART': '1', 16:27:40 INFO - 'PAGER': '/bin/cat', 16:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:40 INFO - 'PWD': '/builds/slave/test', 16:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:40 INFO - 'SHELL': '/bin/bash', 16:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:40 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:40 INFO - 'USER': 'cltbld', 16:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:40 INFO - 'XPC_FLAGS': '0x0', 16:27:40 INFO - 'XPC_SERVICE_NAME': '0', 16:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:40 INFO - Cleaning up... 16:27:40 INFO - Return code: 0 16:27:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:40 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')]} 16:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 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')]} 16:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:40 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 16:27:40 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 16:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:40 INFO - 'HOME': '/Users/cltbld', 16:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:40 INFO - 'LOGNAME': 'cltbld', 16:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:40 INFO - 'MOZ_NO_REMOTE': '1', 16:27:40 INFO - 'NO_EM_RESTART': '1', 16:27:40 INFO - 'PAGER': '/bin/cat', 16:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:40 INFO - 'PWD': '/builds/slave/test', 16:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:40 INFO - 'SHELL': '/bin/bash', 16:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:40 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:40 INFO - 'USER': 'cltbld', 16:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:40 INFO - 'XPC_FLAGS': '0x0', 16:27:40 INFO - 'XPC_SERVICE_NAME': '0', 16:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:40 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:40 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 16:27:40 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 16:27:40 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 16:27:40 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 16:27:40 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 16:27:40 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 16:27:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:43 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 16:27:43 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 16:27:43 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 16:27:43 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 16:27:43 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 16:27:43 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 16:27:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:27:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27:44 INFO - Downloading docopt-0.6.1.tar.gz 16:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:44 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:44 INFO - Running setup.py install for blobuploader 16:27:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:27:44 INFO - Running setup.py install for requests 16:27:45 INFO - Running setup.py install for docopt 16:27:45 INFO - Successfully installed blobuploader requests docopt 16:27:45 INFO - Cleaning up... 16:27:45 INFO - Return code: 0 16:27:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:45 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:45 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 16:27:45 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 16:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:45 INFO - 'HOME': '/Users/cltbld', 16:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:45 INFO - 'LOGNAME': 'cltbld', 16:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:45 INFO - 'MOZ_NO_REMOTE': '1', 16:27:45 INFO - 'NO_EM_RESTART': '1', 16:27:45 INFO - 'PAGER': '/bin/cat', 16:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:45 INFO - 'PWD': '/builds/slave/test', 16:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:45 INFO - 'SHELL': '/bin/bash', 16:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:45 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:45 INFO - 'USER': 'cltbld', 16:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:45 INFO - 'XPC_FLAGS': '0x0', 16:27:45 INFO - 'XPC_SERVICE_NAME': '0', 16:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:45 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-KVqBRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:45 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-FzJSrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-hZNmG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-fmvzJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-s7c38U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-5DHDC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-EZP6BJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-0GcG0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-8ToWwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-rnfLgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-zQL_sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:46 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-rgBvnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:47 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-s7nZxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:47 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-xObnOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:47 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-GZDPQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:47 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-csIK13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:47 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-Ak78ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:47 INFO - Running setup.py install for manifestparser 16:27:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:27:47 INFO - Running setup.py install for mozcrash 16:27:47 INFO - Running setup.py install for mozdebug 16:27:47 INFO - Running setup.py install for mozdevice 16:27:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Running setup.py install for mozfile 16:27:48 INFO - Running setup.py install for mozhttpd 16:27:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Running setup.py install for mozinfo 16:27:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Running setup.py install for mozInstall 16:27:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:27:48 INFO - Running setup.py install for mozleak 16:27:48 INFO - Running setup.py install for mozlog 16:27:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:27:49 INFO - Running setup.py install for moznetwork 16:27:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:27:49 INFO - Running setup.py install for mozprocess 16:27:49 INFO - Running setup.py install for mozprofile 16:27:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:27:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:27:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:27:49 INFO - Running setup.py install for mozrunner 16:27:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:27:49 INFO - Running setup.py install for mozscreenshot 16:27:49 INFO - Running setup.py install for moztest 16:27:49 INFO - Running setup.py install for mozversion 16:27:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:27:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:27:50 INFO - Cleaning up... 16:27:50 INFO - Return code: 0 16:27:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:50 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:50 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 16:27:50 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 16:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:50 INFO - 'HOME': '/Users/cltbld', 16:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:50 INFO - 'LOGNAME': 'cltbld', 16:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:50 INFO - 'MOZ_NO_REMOTE': '1', 16:27:50 INFO - 'NO_EM_RESTART': '1', 16:27:50 INFO - 'PAGER': '/bin/cat', 16:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:50 INFO - 'PWD': '/builds/slave/test', 16:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:50 INFO - 'SHELL': '/bin/bash', 16:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:50 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:50 INFO - 'USER': 'cltbld', 16:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:50 INFO - 'XPC_FLAGS': '0x0', 16:27:50 INFO - 'XPC_SERVICE_NAME': '0', 16:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:50 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-gcZdDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:50 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)) 16:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:50 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-tJuR3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:50 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)) 16:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:50 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-sf5Doz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:50 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)) 16:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:50 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-d7ZQ7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:50 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)) 16:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:50 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-kzSagU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:50 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)) 16:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:50 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-QZFd28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-tQeaqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-C2xJGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-XZ3IJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-xrsNiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-DGfdTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-3ghbmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-DU3hYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-UIy0gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-b0SKPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:51 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)) 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:51 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ddTMgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:52 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)) 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:52 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-yenIcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:52 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)) 16:27:52 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)) 16:27:52 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)) 16:27:52 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)) 16:27:52 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)) 16:27:52 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)) 16:27:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:27:52 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 16:27:52 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 16:27:52 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 16:27:52 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 16:27:52 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 16:27:52 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 16:27:54 INFO - Downloading blessings-1.6.tar.gz 16:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:27:54 INFO - Installing collected packages: blessings 16:27:54 INFO - Running setup.py install for blessings 16:27:55 INFO - Successfully installed blessings 16:27:55 INFO - Cleaning up... 16:27:55 INFO - Return code: 0 16:27:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:27:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:55 INFO - Reading from file tmpfile_stdout 16:27:55 INFO - Current package versions: 16:27:55 INFO - blessings == 1.6 16:27:55 INFO - blobuploader == 1.2.4 16:27:55 INFO - docopt == 0.6.1 16:27:55 INFO - functools32 == 3.2.3-2 16:27:55 INFO - jsonschema == 2.5.1 16:27:55 INFO - manifestparser == 1.1 16:27:55 INFO - mozInstall == 1.12 16:27:55 INFO - mozcrash == 1.0 16:27:55 INFO - mozdebug == 0.1 16:27:55 INFO - mozdevice == 0.49 16:27:55 INFO - mozfile == 1.2 16:27:55 INFO - mozhttpd == 0.7 16:27:55 INFO - mozinfo == 0.9 16:27:55 INFO - mozleak == 0.1 16:27:55 INFO - mozlog == 3.4 16:27:55 INFO - moznetwork == 0.27 16:27:55 INFO - mozprocess == 0.24 16:27:55 INFO - mozprofile == 0.28 16:27:55 INFO - mozrunner == 6.13 16:27:55 INFO - mozscreenshot == 0.1 16:27:55 INFO - mozsystemmonitor == 0.3 16:27:55 INFO - moztest == 0.8 16:27:55 INFO - mozversion == 1.4 16:27:55 INFO - psutil == 3.1.1 16:27:55 INFO - requests == 1.2.3 16:27:55 INFO - wsgiref == 0.1.2 16:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:56 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:56 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 16:27:56 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 16:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:56 INFO - 'HOME': '/Users/cltbld', 16:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:56 INFO - 'LOGNAME': 'cltbld', 16:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:56 INFO - 'MOZ_NO_REMOTE': '1', 16:27:56 INFO - 'NO_EM_RESTART': '1', 16:27:56 INFO - 'PAGER': '/bin/cat', 16:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:56 INFO - 'PWD': '/builds/slave/test', 16:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:56 INFO - 'SHELL': '/bin/bash', 16:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:56 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:56 INFO - 'USER': 'cltbld', 16:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:56 INFO - 'XPC_FLAGS': '0x0', 16:27:56 INFO - 'XPC_SERVICE_NAME': '0', 16:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 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)) 16:27:56 INFO - Cleaning up... 16:27:56 INFO - Return code: 0 16:27:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:56 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 0x102b59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff938c46780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, '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.xxeEgz7NFy/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.F46QCNqFRK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 16:27:56 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 16:27:56 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 16:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:56 INFO - 'HOME': '/Users/cltbld', 16:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:56 INFO - 'LOGNAME': 'cltbld', 16:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:56 INFO - 'MOZ_NO_REMOTE': '1', 16:27:56 INFO - 'NO_EM_RESTART': '1', 16:27:56 INFO - 'PAGER': '/bin/cat', 16:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:56 INFO - 'PWD': '/builds/slave/test', 16:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:56 INFO - 'SHELL': '/bin/bash', 16:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:27:56 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:56 INFO - 'USER': 'cltbld', 16:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:56 INFO - 'XPC_FLAGS': '0x0', 16:27:56 INFO - 'XPC_SERVICE_NAME': '0', 16:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:27:57 INFO - Cleaning up... 16:27:57 INFO - Return code: 0 16:27:57 INFO - Running post-action listener: _resource_record_post_action 16:27:57 INFO - Running post-action listener: _start_resource_monitoring 16:27:57 INFO - Starting resource monitoring. 16:27:57 INFO - [mozharness: 2017-02-01 00:27:57.108523Z] Finished create-virtualenv step (success) 16:27:57 INFO - [mozharness: 2017-02-01 00:27:57.109466Z] Running install step. 16:27:57 INFO - Running pre-action listener: _resource_record_pre_action 16:27:57 INFO - Running main action method: install 16:27:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:57 INFO - Reading from file tmpfile_stdout 16:27:57 INFO - Detecting whether we're running mozinstall >=1.0... 16:27:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:27:57 INFO - Reading from file tmpfile_stdout 16:27:57 INFO - Output received: 16:27:57 INFO - Usage: mozinstall [options] installer 16:27:57 INFO - Options: 16:27:57 INFO - -h, --help show this help message and exit 16:27:57 INFO - -d DEST, --destination=DEST 16:27:57 INFO - Directory to install application into. [default: 16:27:57 INFO - "/builds/slave/test"] 16:27:57 INFO - --app=APP Application being installed. [default: firefox] 16:27:57 INFO - mkdir: /builds/slave/test/build/application 16:27:57 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'] 16:27:57 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 16:28:19 INFO - Reading from file tmpfile_stdout 16:28:19 INFO - Output received: 16:28:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:28:19 INFO - Running post-action listener: _resource_record_post_action 16:28:19 INFO - [mozharness: 2017-02-01 00:28:19.411327Z] Finished install step (success) 16:28:19 INFO - [mozharness: 2017-02-01 00:28:19.411474Z] Running run-tests step. 16:28:19 INFO - Running pre-action listener: _resource_record_pre_action 16:28:19 INFO - Running main action method: run_tests 16:28:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:28:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:28:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:28:19 INFO - 2017-01-31 16:28:19.425 screenresolution[1622:7278] starting screenresolution argv=screenresolution get 16:28:19 INFO - 2017-01-31 16:28:19.428 screenresolution[1622:7278] Display 0: 1600x1200x32@60 16:28:19 INFO - 2017-01-31 16:28:19.434 screenresolution[1623:7280] starting screenresolution argv=screenresolution list 16:28:19 INFO - Available Modes on Display 0 16:28:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:28:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:28:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:28:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:28:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:28:19 INFO - Intel Iris: 16:28:19 INFO - Chipset Model: Intel Iris 16:28:19 INFO - Type: GPU 16:28:19 INFO - Bus: Built-In 16:28:19 INFO - VRAM (Dynamic, Max): 1536 MB 16:28:19 INFO - Vendor: Intel (0x8086) 16:28:19 INFO - Device ID: 0x0a2e 16:28:19 INFO - Revision ID: 0x0009 16:28:19 INFO - Displays: 16:28:19 INFO - Display: 16:28:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:28:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:28:19 INFO - Display Serial Number: 1600x1200 60 16:28:19 INFO - Main Display: Yes 16:28:19 INFO - Mirror: Off 16:28:19 INFO - Online: Yes 16:28:19 INFO - Rotation: Supported 16:28:19 INFO - Return code: 0 16:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:19 INFO - Python 2.7.3 16:28:19 INFO - Return code: 0 16:28:19 INFO - grabbing minidump binary from tooltool 16:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:19 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 0x102937d30>, '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 0x10285edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10292cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:19 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 16:28:19 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 16:28:19 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 16:28:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:19 INFO - Return code: 0 16:28:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:28:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:28:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:19 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/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 16:28:19 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/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0350 --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 16:28:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeEgz7NFy/Render', 16:28:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:19 INFO - 'HOME': '/Users/cltbld', 16:28:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:19 INFO - 'LOGNAME': 'cltbld', 16:28:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:19 INFO - 'MOZ_NO_REMOTE': '1', 16:28:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:19 INFO - 'NO_EM_RESTART': '1', 16:28:19 INFO - 'PAGER': '/bin/cat', 16:28:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:19 INFO - 'PWD': '/builds/slave/test', 16:28:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:19 INFO - 'SHELL': '/bin/bash', 16:28:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F46QCNqFRK/Listeners', 16:28:19 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 16:28:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:19 INFO - 'USER': 'cltbld', 16:28:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:19 INFO - 'XPC_FLAGS': '0x0', 16:28:19 INFO - 'XPC_SERVICE_NAME': '0', 16:28:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:19 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/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 16:28:20 INFO - mozversion application_buildid: 20170131151208 16:28:20 INFO - mozversion application_changeset: c22bc80b4d44f8b710c154eed2b52a73a167b037 16:28:20 INFO - mozversion application_display_name: Nightly 16:28:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:20 INFO - mozversion application_name: Firefox 16:28:20 INFO - mozversion application_remotingname: firefox 16:28:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:28:20 INFO - mozversion application_vendor: Mozilla 16:28:20 INFO - mozversion application_version: 54.0a1 16:28:20 INFO - mozversion platform_buildid: 20170131151208 16:28:20 INFO - mozversion platform_changeset: c22bc80b4d44f8b710c154eed2b52a73a167b037 16:28:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:28:20 INFO - mozversion platform_version: 54.0a1 16:28:20 INFO - using testdate: 1485908900 16:28:20 INFO - actual date: 1485908900 16:28:20 INFO - starting webserver on 'localhost:49240' 16:28:20 INFO - SUITE-START | Running 2 tests 16:28:20 INFO - TEST-START | dromaeo_css 16:28:20 INFO - operating with platform_type : mac_ 16:28:20 INFO - Initialising browser for dromaeo_css test... 16:28:20 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpXOZcU7/profile http://localhost:49240/getInfo.html) 16:28:22 INFO - PROCESS | 1633 | 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] 16:28:22 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 16:28:22 INFO - PROCESS | 1633 | colorDepth:24 16:28:22 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 16:28:22 INFO - PROCESS | 1633 | __metrics 16:28:22 INFO - PROCESS | 1633 | 1485908902394 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 16:28:22 INFO - PROCESS | 1633 | 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" 16:28:22 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:23 INFO - TEST-INFO | 1633: exit 0 16:28:23 INFO - Browser initialized. 16:28:23 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:23 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpXOZcU7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:35 INFO - PROCESS | 1635 | RSS: Main: 237236224 16:28:35 INFO - PROCESS | 1635 | 16:31:38 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 16:31:40 INFO - PROCESS | 1635 | RSS: Main: 442175488 16:31:40 INFO - PROCESS | 1635 | 16:33:25 INFO - PROCESS | 1635 | ************************* 16:33:25 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 16:33:25 INFO - PROCESS | 1635 | 16:33:25 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:33:25 INFO - PROCESS | 1635 | Full stack: 16:33:25 INFO - PROCESS | 1635 | ************************* 16:34:44 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 16:34:44 INFO - PROCESS | 1635 | RSS: Main: 371003392 16:34:44 INFO - PROCESS | 1635 | 16:37:47 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 16:37:47 INFO - PROCESS | 1635 | RSS: Main: 327962624 16:37:47 INFO - PROCESS | 1635 | 16:40:50 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 16:40:50 INFO - PROCESS | 1635 | RSS: Main: 366403584 16:40:50 INFO - PROCESS | 1635 | 16:43:54 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 16:43:54 INFO - PROCESS | 1635 | RSS: Main: 334323712 16:43:54 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 16:46:58 INFO - PROCESS | 1635 | RSS: Main: 406581248 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | __start_tp_report 16:46:58 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 16:46:58 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 16:46:58 INFO - PROCESS | 1635 | |i|pagename|runs| 16:46:58 INFO - PROCESS | 1635 | |0;dojo.html;3308.139883630256;3332.966670333296;3339.0483712142036;3359.344927739092;3396.252824378636;5539.750711217995;5578.24693666355;5610.803621873235;5651.2653355063885;5651.773929042838;5613.158026296058;5666.291713535808;5737.971310143454;5746.942530574693;5755.65466072036;4233.83064677414;5101.693898366091;5106.69359838409;5167.095758242312;5185.818496352628;5184.7148406837605;5251.684898906059;5259.815906443275;5289.920651190235;5335.5198032177095;5241.9213711794355;5267.26258323835;5271.578273738091;5276.182191760283;5281.155015197569;4676.3453116563705;5004.649674522785;5043.52086551777;5055.620828437882;5152.639315247934;4453.153900758845;5150.562202212816;5175.249588809639;5178.637495375326;5230.8692282692855;5251.55361794248;5646.237757902672;5648.43515648436;5684.488396044376;5686.289213848269;6093.299270583895;6127.387261273881;6136.7852125175405;6249.343818899041;6276.246850377984;6185.783497577609;6213.068039794067;6294.307626161119;6323.620582765049;6334.778282760128;5760.53915686744;5807.128930660392;5809.68046757424;5826.854328641818;5857.062869940832;6168.0439531872635;6304.747810087545;6311.526635502356;6318.241810982711;6319.494440444753;5903.616264942765;6071.605345652519;6079.513638908877;6136.8772624547255;6165.32181460039;6141.570090093651;6247.687615619201;6252.562320637603;6255.249370075529;6256;6077.270727512727;6102.193115296201;6107.15847211733;6155.261368635703;6172.105044768484;6216.44052035319;6226.346233645492;6230.252369715572;6235.657038862906;6236.126942228092;4884.389451318585;4932.334134891744;4933.038057578738;4937.086638971802;4987.002599480046;3637.2725454908596;4340.8697739067875;4391.626711729538;4406.559344065632;4427.5572442755465;5669.31968163823;5685.1756495307945;5697.0599851024945;5698.287714035746;5713.400092990176;5441.347038355423;6186.1648677428875;6211.59624624407;6215.96892015537;6220.533459990519;5626.774929002876;5666.9716651416475;5695.800646977336;5710.3433105193435;5743.483086522233;5420.728963551806;5422.05114105038;5428.538574221236;5430.049741295257;5433.701146436984;6242.625442473466;6264.34224406449;6291.370862913764;6310.810675679737;6334.968325158345;6234.750609975732;6289.40250676185;6295.905561416491;6307.085472606538;6328;6296.150019747459;6298.08677741734;6302.243730752339;6302.306746257999;6336.619802811846;5986.16193732869;6160.353162918008;6188.288346840171;6204.255489341308;6216;6122.051082082285;6191.102290167811;6195.96902015487;6236.781712640217;6286.151369565233;6122.571420000735;6131.141640170291;6133.938660613337;6145.754169833157;6151;5993.3706960768495;6096.603720758137;6109.784727033366;6114.38856114385;6114.960456722457;5796.536277097738;5845.736941837713;5856.853578660568;5891.027980383189;5895.115732640138;6000.8499787503915;6019.428154325332;6029.035354343284;6031.969840150771;6033.668148251888;5816.82549523515;5865.237519122486;5872.236609240942;5876.676782776817;5890.145928840208 16:46:58 INFO - PROCESS | 1635 | |1;ext.html;450.7430764464255;474.07318691957204;477.4032459425718;493.21332474702837;519.0786354221257;30848.228794280127;32601.67398326016;32644.347113057724;37750;39666.40500392489;40833.183336333255;41293.587064129424;41367.58632413675;41415.792921035354;41455.58544414555;9057.592408341625;9285.303602229826;9319.813603727922;9321.673741419052;9322.161005509503;8382.622781974795;8568.700095496659;9227.630894764201;9349.579268932881;10979.451027448635;11089.83365249522;11320.830187547193;11344.546218151265;11345.773084538345;11347.546098156065;11307.378094204816;11315.32108073514;11319.20765546412;11359.829602555968;11387;7112.751053713147;8520.062793092755;10768.569257229701;11013.83479247812;11119.444027798658;38917;39200.803995980124;39240.60759392398;39243;39311.21377572461;3986.86045988389;3995.98002009991;3999.140184860269;4051.1290072634524;4077.3894311746694;29118;29390.265243369086;29874.253143671584;30056.849715751283;30243.69756302453;3005.1435340927733;3181.936361272785;3217.6621454747146;3273.6562660920736;3273.705366517001;458326;458791.9999999967;461960;463194;466521;63589.682051590375;63979.36020639827;63983.04025439622;64065.35934640594;64198.67900660467;58463;66761.33238667648;66998;67011;67015.32984670092;15615.453459128876;15889.364425423084;15976;16127.435539756052;16205;12421.37893105341;12495.75008499825;12507.874921250854;12532.624021279376;12537.811932821014;14409.711805764036;14582.416703331748;15945.760813587805;16105.677886442205;16114.355425783071;6332.556721029484;6846.349596788297;6919.792406227822;6940.201876784235;6962.477814163958;857.2627540315324;871.1158174452935;880.9515476648846;881.6737807011318;900.4597241654955;23418.765812342;27540.44919101647;27724.445511090067;28757.71242287592;29839.403211935638;1035.295998720877;1036.3056751976194;1072.4905669806778;1076.2842709598185;1131.1742428027585;11129.77572467028;12580.30808305552;14717.852821471863;15265.389384424721;15548.844511554966;580441;585210;585728;585736;586973;60520.71147904867;60938;61082.083768743505;61148;61275.38724612786;1360.4014233737123;1410.9365078571489;1425.3728359521776;1425.736238795826;1426.2369632246698;27212.863935680194;28073.929573820355;28252.15243542697;28738;28748;46602.53397465982;46649.06701865876;46898;46989.06021879612;47219.52780472151;494728;497788;499125;499445;501277;24738.010479580615;25060.498790024463;25189.87405062963;25207.621885672095;25229.87385063063;38783.806080969414;42115;42753;47373.578792636275;47597.28604071011;38790.80604596959;39030.41454378243;39359.212815742954;40520;40735.796321018206;38594.2281154381;39880.800595996836;40208.994775129686;40630.59369406268;40660.18679626332;30884.382312354075;36897.81551092227;37043.073108302546;37168.44247336346;37704;33354.4996825043;34174.82912585421;34289.31421371608;34765.82617086898;34890.30219395547;21328.253511127037;23292.534149317256;24306.75693243045;24807;24988.875055624605 16:46:58 INFO - PROCESS | 1635 | |2;jquery.html;395.11691369788537;395.466120737005;397.14019148543406;408.58936768547596;423.52988183116736;10707.625233116823;20166.798332016675;20761.792382076175;21523.67714484284;21531;13378.531751388678;18068.819311806878;20271.49321266969;20795.58408831826;21456.249031283907;5682.6306290091115;5811.4479124483105;5860.501857342109;5943.049112142036;5945.137954996523;5665.1785491103765;5836.649801011953;5837.328707198684;5862.091375836731;5947.672877991708;5842.152887831283;5852.180694702745;5859.531237501011;5860.238169038019;5861.501772349356;5602.971985140048;5831.271091113611;5833.679147646878;5853.385394533598;5890.764369425217;5152.793888244466;5161.974190129063;5163.8192663256605;5166.250893620441;5187.740612969336;25965;26236.60645090325;26322.94708211669;26722.5991610126;26988.865055674793;2634.2755742170866;2721.621661846209;2721.8366897986184;2724.768394686454;2727.6454060971946;15417.922910385376;15579.065256084557;15585.415354468423;15679.372825086983;15909;1816.4823025437686;1874.4845167578887;1881.7459642948163;1899.943001709951;1905.4569447707338;130162;133315.33342333324;134460.3276983609;136299.31850340785;137096;9403.952980235055;11287.717807054889;11299.717507062225;11500.309981401142;11588.420578971018;14401.63995900111;14409.85590144106;14414.927925360305;14435.566932992027;14458.927705361617;7594;7971.960140199378;7985.64064617082;8262.793430164174;8554.743357699279;7043.696916070543;8138.796530086795;8484.660613575512;8535.658573656985;8714.9977752558;5725.5133313667975;7157.821054473679;7164.820879478055;7208.675609597476;7230.385417239489;1905.8284754371896;1939.9718149380851;1942.5435022769639;1951.8048195180652;1953.247999520195;337.73825285404007;389.2817751248941;1014.183582216323;1036.0778906772978;1066.858461446245;11504.871598754256;11665.300081995107;11967.940160299142;12069;12101.757964840654;403.2177575503514;404.41965779107073;421.33849855726214;421.58684489200755;439.7515403796908;5990.921634105903;6009.789657361971;6169.9383006170265;6203.286622038433;6228.252609686867;517974;582390;655228.7532684387;762550;769672;131245.34377328242;139743;149187;155564.2221788884;166710;827.7475370012204;982.6511588386163;1022.197574903697;1026.9486525673688;1027.111548510548;15087.924560377129;18481.445556633324;18661;19990.600187995868;20335.694964575247;58066.709666451396;60577.09134363076;61553;62000;62203.444913877516;58377.416225837194;66730.66634666796;70153.64923175352;88336;89333;7217.711291548279;7356.66894989716;7567.470277080552;8245.95877020611;8265.710700125466;7749.573773442515;11779;13095.345232738135;13323.467061317438;13774.793378099537;11183.440827958731;11407.885921140682;11755.823662645238;11806.232594881305;11839.408029598659;10981.066609337977;11215.66353009411;11469.369184694922;11584;11665.475053622438;11259.887401126212;11467.426628668367;11508.88491115078;11967.521299147937;12046.457909393923;7577.280158384945;7732.110807257238;7748.651310691145;7768.145503994443;7811.531308121531;5726.627769195155;8606.26846718036;9413.623455061721;9655.613775448903;10834.212464420085 16:46:58 INFO - PROCESS | 1635 | |3;mootools.html;1403.3404299979013;1410.5063227870246;1420.8081908942297;1426.0801782850056;1426.4365575597635;7907.802304942379;8326.625301861415;8327.167283271678;8335.416520843528;8346.332293416528;7378.151512576042;7620.123685776138;7641.3122818946285;7910.723124690637;8132.186781321865;2218.223621732393;2260.9886950565224;2267.4218074391006;2269.7389800172946;2273.6475846243857;2259.2318611672026;2298.0807676929194;2299.8735069571167;2301.5742087713743;2306.780855818703;1973.0627951722888;2062.865913715611;2189.824814014875;2257.8532395394245;2284.668723035159;2259.2092767531317;2270.0465804362207;2270.2962081754545;2271.943201419961;2272.625016872214;2199.802017818388;2263.207877242952;2266.6033444147374;2268.7390950040626;2268.875211863347;8201.876971845428;8263.669453221864;8267.958660206661;8282.461639993382;8294.8341033179;4984.102861484931;5139.665921715114;5142.408623008327;5144.588432925357;5147.6911385317;3094.6428830466593;3198.040587823644;3378.3750006248924;3434.553508043963;3543.3976224041717;1170.5902933973127;1218.1351240619172;1236.190295356543;1256.064232147047;1267.1889990406055;10696.999999999922;10707.78584428315;10716.892831071746;10717.142628589694;10722.571097156104;4493.551014696998;4578.748168850745;4610.008848097675;4651.813927442932;4662.906741865144;9414.29392795543;9438.858417123749;9439.528023598792;9441.527923603793;9465.148136667594;5221.477852214824;5226.608004399685;5229.6339256251695;5241.449647787003;5243.8164664236565;4214.915701685949;4255.595718406808;4640.443146822336;4670.159371313196;4743.976280118646;3685.4287585424304;3741.5884252732176;3787.753796003251;3840.807959602009;3846.2692088503095;1236.5857437758452;1245.1781823996118;1247.9376031198585;1258.8552316483538;1265.487477571585;668.9230738465042;757.9734709285149;773.083895583735;781.6482582837746;846.8687353460224;5054.595632349403;5201.420740018578;5317.282166907496;5345.1447768356875;5556;871.3639043498282;883.7348795361365;929.7396728915915;949.4730424614268;971.9454391984634;3603.135247540624;3613.512175856226;3640.7633503822167;3732.496113024707;3830.942535861964;8821.558922053871;9557.856632150524;9855.014498550088;9863.457509836884;9872.210223182128;9162.86255706165;9650.758731031781;9807.26445516589;9807.313488055768;9822.656207032862;7386.298301661333;7573.697052117964;7573.886391704129;7595.848083038419;7628.656710448043;5311.973440132774;5425.674459532441;5433.565314774887;5437.782488700566;5630.887382252414;9060.320475964198;9145.451272923647;9178.495182764746;9187.862182067409;9211.124943130391;8834.204921557091;9012.008679045168;9264.305177111879;9335.159835614828;9348.25213982866;6596.175478065242;6721.462283017249;6732.629705366252;6739.898901516383;6740.056392105012;7579.393648507996;7654.387648988179;7748.65131069092;7771.261730135628;7775.6112194388925;6882.449404047564;7009.264027277062;7038.331358520933;7040.823979400556;7065.823354415975;6811.795646130624;6907.551009184387;6962.164540255202;6964.756233531802;6968;6107.175531303217;6246.968765156145;6248.156498872776;6263.248410190807;6265.122882796503;4717.262793336224;4869.926951095666;4918.508149185053;4919.0407870464915;4995.275685025578;4831.758412079453;4885.047415753894;4982.55157035854;5105.795768169231;5114.71869047206 16:46:58 INFO - PROCESS | 1635 | |4;prototype.html;349.28222502756824;359.60802725029714;369.45505379565134;373.4472979989617;376.65159727252285;5355.544778693811;5373.247745315649;5385.21914322423;5412.917416516692;5444.9707216659635;4485.52901945296;4901.877453063676;5299.152135658295;5326;5332.680039197651;3494.6505349465106;3817.3701339278987;3895.6883449323986;4175.415441838145;4175.853845115422;3709.071282932873;3776.1126135358168;4091.486150124458;4152.771597562133;4187.7277976931555;3446.2418267981;3821.7515861469055;4121.897832105617;4140.689448291375;4184.263530634783;3521.1021189596618;3964.940525892114;3983.24318379507;4149.356849688304;4161.292580261363;3194.552762613236;3456.1164165529844;3694.7598406103784;3703.759255648374;3730.8321125549414;4319.747273290742;4983.90032199354;5071.746412679351;5098.286239926413;5222.242774797671;4704.106219818258;4748.287756836467;4761.785719642589;4779.665423420362;4791.305260737174;4829.6860704054125;5255.185446255837;5554.305711786027;5573.637713548606;5644.181593668936;4075.735077219971;4077.7961101944784;4082.6733861291027;4094.979525102385;4121.5878412158845;4918.778654960535;5082.603547931013;5563.638363506359;5666;5916.585838991272;3586.7130629549574;3902.8776978417154;4121.753644263883;4121.855735049319;4156.129741616285;3348.5646865907274;3447.5690538682666;4205.810738516774;4235.639970602533;4237.745735255895;3974.3839704845795;3990.3216453203027;3994.0613955720382;4740.881477963078;4793.60985314262;5012.624053196025;5049.293098966147;5616.269884914935;5647.842192350558;5648.9045442055;1066.4401189375485;1174.5595401724354;1217.933013684256;1274.5857596281246;1283.2172374851334;792.659156562672;808.8948436703191;832.5171400587644;868.5006121480175;876.3252295732251;4477.388140269565;4648.604868586141;4979.041661878815;4988.578255197324;5062.367204099488;4880.902381952341;5293.285406470154;5323.30796996388;5517.641353312022;5554.888902221933;4690.835820746326;4751.881202969884;4859.1010663027455;4893.556400861754;5041.470645582234;2444.8655323956996;2460.6801115855183;2684.852333121697;2846.672632647231;2879.5680647902564;5270;5545.944540554543;5626.2967129108865;5635.323761148688;5768.480836724715;4881.51184881509;4910.386201724785;5102.708752854388;5162.406323272797;5691.211306459805;5233.527315558851;5316.335458067664;5712.971894977306;5721.456461636138;5742.971285143548;4326.805293761725;4736.4316282046375;4942.382202224722;5110.189395832203;5564.248826408545;4682.742449165328;4982.377202849644;5045.823396181116;5419.883566948941;5482.533984611353;4776.42682878043;4996.550310472075;5091.141733267256;5705.465698272669;5761.913571296518;3791.0901383668274;3809.933218698769;3811.294910441577;3847.3074846526774;3849.5380554332537;5020.945601423742;5113.937202169899;5668.093105103164;5708.80019199326;5726.169705392612;4690.296455531698;5272.1029447468645;5426.593005524523;5516.889662206813;5554.666719996651;4643.86068417948;4832.830850920201;5350.545203657733;5512.283403157564;5548.3619383769765;4210.884115709401;4581.419410303573;5388.54197393226;5427.019138014675;5448.210009548672;4454.376387305844;5537.224788529528;5559.471850174227;5592.776288948559;5620.550355971431;4585.862424127282;5485.862853428536;5492.116638764521;5495.945040549544;5514.097636315465 16:46:58 INFO - PROCESS | 1635 | |5;yui.html;694.7811439396587;713.3401603516747;727.4035291061329;774.9615515209622;847.5080290234328;12361.814572781415;15443.613909652264;15760.211989400541;16284;16308.347666093372;14574.05268657539;14737.926310368433;14751.754613984906;14763.335649895753;14804.925975370135;853.9573021348923;882.5807741322884;884.8274586455643;885.5173930208052;888.1473785166248;697.9546329488593;715.8031541326151;837.8408102460544;870.7648934787635;873.9126087391273;918.8897332320098;923.6443969071888;924.3344791749929;924.8057907839362;925.6112432778249;916.1571354354011;917.4678686361895;918.3433844800933;919.0763282900675;919.2554031234688;534.794104269855;547.728874207266;557.1892895836548;688.1088989758275;694.6978064541889;12565.371731413485;12632.368381580884;14747.262636868221;15252.237388130548;15326.923365383213;12328.753424931541;14313.85686143146;14364;14491.855081449155;14903.254837258093;11593.304401735839;11706.824397634042;11802.645920622395;11850.358311688953;12068.517259309618;3713.3316003119426;3793.848246070154;3890.5720370759195;3927.155661532784;3928.823202955874;95433.5228323861;97480.51259743726;101430.49284753602;101908.49045754797;102447.4877625607;10318.122959548373;11031.007209351197;11040.392778397265;11387.20289579738;11678.474468648932;19962.308857418495;21215.15139394438;22049.448763780714;22086.447838803837;22519.64882107046;7631.847363052708;7706.499077559941;7792.220777922275;7817.921820781833;7974.880376794353;4289.485261768546;4312.482502099768;4344.54382289855;4403.119376124724;4532.388127602731;3548.3258180945536;3611.7832930024283;3634.527511423498;3642.2897534980425;3655.634436556323;656.7734131724643;663.2571519897746;666.4901350466799;676.0940339944498;677.579900461707;645.0195702532125;655.2890114226118;675.6081472745796;687.9690413931385;692.6190595172735;9415.63473296382;9801.50992450389;9901.306908516479;10058.094771470604;10063.446510441849;523.481753064471;552.4116815591383;589.1457386789087;600.4265926040621;666.2038863558098;4609.990627278047;4947.942587542037;4959.328129592089;4964.461126861792;4969.418581002334;103859.96140038654;104918;105585;105711.9428805702;106258;90487;91273.54363228142;91771.5411422952;92965;95165.52417237869;63045;79789.20210797933;83051.58474207591;83114.58442707869;83927;9227.584758685742;9668.274879384215;9682.177014953526;9693.6122555097;9752.12230899194;47197.528024719315;47886;48620.513794861596;48936;49836;60487.79024419575;63183.097324342685;64771.67614161899;66144.33855661382;66475.67048658904;9241.630334786532;11192.720181995515;11388.658340249805;11432.714182145513;11543.538258469904;12836.486540538273;12847.229166250056;12926.289054102115;13149.671258218621;13171.209727416386;11753.353565553634;11845.348505832262;12002.21985570935;12277.447514861675;12364.072694547765;11673.883261167279;11854.762904742029;11870.465829037887;11925.761484770082;11973.461194246454;11874.643760687193;11889.762204755683;11929.701757456132;11933.224340417844;11981;10207.642732504326;10342.948285258526;10483.37099774016;10527.420991845522;10803.459827008775;9459.38513996588;9826.263030272616;9841.557129929028;9850.261230407605;9872.950635246778 16:46:58 INFO - PROCESS | 1635 | __end_tp_report 16:46:58 INFO - PROCESS | 1635 | __start_cc_report 16:46:58 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,2459 16:46:58 INFO - PROCESS | 1635 | __end_cc_report 16:46:58 INFO - PROCESS | 1635 | __startTimestamp1485910018452__endTimestamp 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | ------- Summary: start ------- 16:46:58 INFO - PROCESS | 1635 | Number of tests: 6 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5703.15 Median:5876.68 stddev:668.63 (11.4%) stddev-sans-first:643.74 16:46:58 INFO - PROCESS | 1635 | Values: 3308.1 3333.0 3339.0 3359.3 3396.3 5539.8 5578.2 5610.8 5651.3 5651.8 5613.2 5666.3 5738.0 5746.9 5755.7 4233.8 5101.7 5106.7 5167.1 5185.8 5184.7 5251.7 5259.8 5289.9 5335.5 5241.9 5267.3 5271.6 5276.2 5281.2 4676.3 5004.6 5043.5 5055.6 5152.6 4453.2 5150.6 5175.2 5178.6 5230.9 5251.6 5646.2 5648.4 5684.5 5686.3 6093.3 6127.4 6136.8 6249.3 6276.2 6185.8 6213.1 6294.3 6323.6 6334.8 5760.5 5807.1 5809.7 5826.9 5857.1 6168.0 6304.7 6311.5 6318.2 6319.5 5903.6 6071.6 6079.5 6136.9 6165.3 6141.6 6247.7 6252.6 6255.2 6256.0 6077.3 6102.2 6107.2 6155.3 6172.1 6216.4 6226.3 6230.3 6235.7 6236.1 4884.4 4932.3 4933.0 4937.1 4987.0 3637.3 4340.9 4391.6 4406.6 4427.6 5669.3 5685.2 5697.1 5698.3 5713.4 5441.3 6186.2 6211.6 6216.0 6220.5 5626.8 5667.0 5695.8 5710.3 5743.5 5420.7 5422.1 5428.5 5430.0 5433.7 6242.6 6264.3 6291.4 6310.8 6335.0 6234.8 6289.4 6295.9 6307.1 6328.0 6296.2 6298.1 6302.2 6302.3 6336.6 5986.2 6160.4 6188.3 6204.3 6216.0 6122.1 6191.1 6196.0 6236.8 6286.2 6122.6 6131.1 6133.9 6145.8 6151.0 5993.4 6096.6 6109.8 6114.4 6115.0 5796.5 5845.7 5856.9 5891.0 5895.1 6000.8 6019.4 6029.0 6032.0 6033.7 5816.8 5865.2 5872.2 5876.7 5890.1 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:65323.50 Median:38853.90 stddev:137971.69 (355.1%) stddev-sans-first:138272.76 16:46:58 INFO - PROCESS | 1635 | Values: 450.7 474.1 477.4 493.2 519.1 30848.2 32601.7 32644.3 37750.0 39666.4 40833.2 41293.6 41367.6 41415.8 41455.6 9057.6 9285.3 9319.8 9321.7 9322.2 8382.6 8568.7 9227.6 9349.6 10979.5 11089.8 11320.8 11344.5 11345.8 11347.5 11307.4 11315.3 11319.2 11359.8 11387.0 7112.8 8520.1 10768.6 11013.8 11119.4 38917.0 39200.8 39240.6 39243.0 39311.2 3986.9 3996.0 3999.1 4051.1 4077.4 29118.0 29390.3 29874.3 30056.8 30243.7 3005.1 3181.9 3217.7 3273.7 3273.7 458326.0 458792.0 461960.0 463194.0 466521.0 63589.7 63979.4 63983.0 64065.4 64198.7 58463.0 66761.3 66998.0 67011.0 67015.3 15615.5 15889.4 15976.0 16127.4 16205.0 12421.4 12495.8 12507.9 12532.6 12537.8 14409.7 14582.4 15945.8 16105.7 16114.4 6332.6 6846.3 6919.8 6940.2 6962.5 857.3 871.1 881.0 881.7 900.5 23418.8 27540.4 27724.4 28757.7 29839.4 1035.3 1036.3 1072.5 1076.3 1131.2 11129.8 12580.3 14717.9 15265.4 15548.8 580441.0 585210.0 585728.0 585736.0 586973.0 60520.7 60938.0 61082.1 61148.0 61275.4 1360.4 1410.9 1425.4 1425.7 1426.2 27212.9 28073.9 28252.2 28738.0 28748.0 46602.5 46649.1 46898.0 46989.1 47219.5 494728.0 497788.0 499125.0 499445.0 501277.0 24738.0 25060.5 25189.9 25207.6 25229.9 38783.8 42115.0 42753.0 47373.6 47597.3 38790.8 39030.4 39359.2 40520.0 40735.8 38594.2 39880.8 40209.0 40630.6 40660.2 30884.4 36897.8 37043.1 37168.4 37704.0 33354.5 34174.8 34289.3 34765.8 34890.3 21328.3 23292.5 24306.8 24807.0 24988.9 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:37476.68 Median:363.51 stddev:111474.87 (30666.2%) stddev-sans-first:111752.81 16:46:58 INFO - PROCESS | 1635 | Values: 395.1 395.5 397.1 408.6 423.5 10707.6 20166.8 20761.8 21523.7 21531.0 13378.5 18068.8 20271.5 20795.6 21456.2 5682.6 5811.4 5860.5 5943.0 5945.1 5665.2 5836.6 5837.3 5862.1 5947.7 5842.2 5852.2 5859.5 5860.2 5861.5 5603.0 5831.3 5833.7 5853.4 5890.8 5152.8 5162.0 5163.8 5166.3 5187.7 25965.0 26236.6 26322.9 26722.6 26988.9 2634.3 2721.6 2721.8 2724.8 2727.6 15417.9 15579.1 15585.4 15679.4 15909.0 1816.5 1874.5 1881.7 1899.9 1905.5 130162.0 133315.3 134460.3 136299.3 137096.0 9404.0 11287.7 11299.7 11500.3 11588.4 14401.6 14409.9 14414.9 14435.6 14458.9 7594.0 7972.0 7985.6 8262.8 8554.7 7043.7 8138.8 8484.7 8535.7 8715.0 5725.5 7157.8 7164.8 7208.7 7230.4 1905.8 1940.0 1942.5 1951.8 1953.2 337.7 389.3 1014.2 1036.1 1066.9 11504.9 11665.3 11967.9 12069.0 12101.8 403.2 404.4 421.3 421.6 439.8 5990.9 6009.8 6169.9 6203.3 6228.3 517974.0 582390.0 655228.8 762550.0 769672.0 131245.3 139743.0 149187.0 155564.2 166710.0 827.7 982.7 1022.2 1026.9 1027.1 15087.9 18481.4 18661.0 19990.6 20335.7 58066.7 60577.1 61553.0 62000.0 62203.4 58377.4 66730.7 70153.6 88336.0 89333.0 7217.7 7356.7 7567.5 8246.0 8265.7 7749.6 11779.0 13095.3 13323.5 13774.8 11183.4 11407.9 11755.8 11806.2 11839.4 10981.1 11215.7 11469.4 11584.0 11665.5 11259.9 11467.4 11508.9 11967.5 12046.5 7577.3 7732.1 7748.7 7768.1 7811.5 5726.6 8606.3 9413.6 9655.6 10834.2 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5326.24 Median:5242.63 stddev:2901.78 (55.3%) stddev-sans-first:2894.94 16:46:58 INFO - PROCESS | 1635 | Values: 1403.3 1410.5 1420.8 1426.1 1426.4 7907.8 8326.6 8327.2 8335.4 8346.3 7378.2 7620.1 7641.3 7910.7 8132.2 2218.2 2261.0 2267.4 2269.7 2273.6 2259.2 2298.1 2299.9 2301.6 2306.8 1973.1 2062.9 2189.8 2257.9 2284.7 2259.2 2270.0 2270.3 2271.9 2272.6 2199.8 2263.2 2266.6 2268.7 2268.9 8201.9 8263.7 8268.0 8282.5 8294.8 4984.1 5139.7 5142.4 5144.6 5147.7 3094.6 3198.0 3378.4 3434.6 3543.4 1170.6 1218.1 1236.2 1256.1 1267.2 10697.0 10707.8 10716.9 10717.1 10722.6 4493.6 4578.7 4610.0 4651.8 4662.9 9414.3 9438.9 9439.5 9441.5 9465.1 5221.5 5226.6 5229.6 5241.4 5243.8 4214.9 4255.6 4640.4 4670.2 4744.0 3685.4 3741.6 3787.8 3840.8 3846.3 1236.6 1245.2 1247.9 1258.9 1265.5 668.9 758.0 773.1 781.6 846.9 5054.6 5201.4 5317.3 5345.1 5556.0 871.4 883.7 929.7 949.5 971.9 3603.1 3613.5 3640.8 3732.5 3830.9 8821.6 9557.9 9855.0 9863.5 9872.2 9162.9 9650.8 9807.3 9807.3 9822.7 7386.3 7573.7 7573.9 7595.8 7628.7 5312.0 5425.7 5433.6 5437.8 5630.9 9060.3 9145.5 9178.5 9187.9 9211.1 8834.2 9012.0 9264.3 9335.2 9348.3 6596.2 6721.5 6732.6 6739.9 6740.1 7579.4 7654.4 7748.7 7771.3 7775.6 6882.4 7009.3 7038.3 7040.8 7065.8 6811.8 6907.6 6962.2 6964.8 6968.0 6107.2 6247.0 6248.2 6263.2 6265.1 4717.3 4869.9 4918.5 4919.0 4995.3 4831.8 4885.0 4982.6 5105.8 5114.7 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4417.66 Median:4887.53 stddev:1325.93 (27.1%) stddev-sans-first:1294.01 16:46:58 INFO - PROCESS | 1635 | Values: 349.3 359.6 369.5 373.4 376.7 5355.5 5373.2 5385.2 5412.9 5445.0 4485.5 4901.9 5299.2 5326.0 5332.7 3494.7 3817.4 3895.7 4175.4 4175.9 3709.1 3776.1 4091.5 4152.8 4187.7 3446.2 3821.8 4121.9 4140.7 4184.3 3521.1 3964.9 3983.2 4149.4 4161.3 3194.6 3456.1 3694.8 3703.8 3730.8 4319.7 4983.9 5071.7 5098.3 5222.2 4704.1 4748.3 4761.8 4779.7 4791.3 4829.7 5255.2 5554.3 5573.6 5644.2 4075.7 4077.8 4082.7 4095.0 4121.6 4918.8 5082.6 5563.6 5666.0 5916.6 3586.7 3902.9 4121.8 4121.9 4156.1 3348.6 3447.6 4205.8 4235.6 4237.7 3974.4 3990.3 3994.1 4740.9 4793.6 5012.6 5049.3 5616.3 5647.8 5648.9 1066.4 1174.6 1217.9 1274.6 1283.2 792.7 808.9 832.5 868.5 876.3 4477.4 4648.6 4979.0 4988.6 5062.4 4880.9 5293.3 5323.3 5517.6 5554.9 4690.8 4751.9 4859.1 4893.6 5041.5 2444.9 2460.7 2684.9 2846.7 2879.6 5270.0 5545.9 5626.3 5635.3 5768.5 4881.5 4910.4 5102.7 5162.4 5691.2 5233.5 5316.3 5713.0 5721.5 5743.0 4326.8 4736.4 4942.4 5110.2 5564.2 4682.7 4982.4 5045.8 5419.9 5482.5 4776.4 4996.6 5091.1 5705.5 5761.9 3791.1 3809.9 3811.3 3847.3 3849.5 5020.9 5113.9 5668.1 5708.8 5726.2 4690.3 5272.1 5426.6 5516.9 5554.7 4643.9 4832.8 5350.5 5512.3 5548.4 4210.9 4581.4 5388.5 5427.0 5448.2 4454.4 5537.2 5559.5 5592.8 5620.6 4585.9 5485.9 5492.1 5495.9 5514.1 16:46:58 INFO - PROCESS | 1635 | 16:46:58 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20056.43 Median:47541.76 stddev:29250.88 (61.5%) stddev-sans-first:29296.81 16:46:58 INFO - PROCESS | 1635 | Values: 694.8 713.3 727.4 775.0 847.5 12361.8 15443.6 15760.2 16284.0 16308.3 14574.1 14737.9 14751.8 14763.3 14804.9 854.0 882.6 884.8 885.5 888.1 698.0 715.8 837.8 870.8 873.9 918.9 923.6 924.3 924.8 925.6 916.2 917.5 918.3 919.1 919.3 534.8 547.7 557.2 688.1 694.7 12565.4 12632.4 14747.3 15252.2 15326.9 12328.8 14313.9 14364.0 14491.9 14903.3 11593.3 11706.8 11802.6 11850.4 12068.5 3713.3 3793.8 3890.6 3927.2 3928.8 95433.5 97480.5 101430.5 101908.5 102447.5 10318.1 11031.0 11040.4 11387.2 11678.5 19962.3 21215.2 22049.4 22086.4 22519.6 7631.8 7706.5 7792.2 7817.9 7974.9 4289.5 4312.5 4344.5 4403.1 4532.4 3548.3 3611.8 3634.5 3642.3 3655.6 656.8 663.3 666.5 676.1 677.6 645.0 655.3 675.6 688.0 692.6 9415.6 9801.5 9901.3 10058.1 10063.4 523.5 552.4 589.1 600.4 666.2 4610.0 4947.9 4959.3 4964.5 4969.4 103860.0 104918.0 105585.0 105711.9 106258.0 90487.0 91273.5 91771.5 92965.0 95165.5 63045.0 79789.2 83051.6 83114.6 83927.0 9227.6 9668.3 9682.2 9693.6 9752.1 47197.5 47886.0 48620.5 48936.0 49836.0 60487.8 63183.1 64771.7 66144.3 66475.7 9241.6 11192.7 11388.7 11432.7 11543.5 12836.5 12847.2 12926.3 13149.7 13171.2 11753.4 11845.3 12002.2 12277.4 12364.1 11673.9 11854.8 11870.5 11925.8 11973.5 11874.6 11889.8 11929.7 11933.2 11981.0 10207.6 10342.9 10483.4 10527.4 10803.5 9459.4 9826.3 9841.6 9850.3 9873.0 16:46:58 INFO - PROCESS | 1635 | -------- Summary: end -------- 16:46:58 INFO - PROCESS | 1635 | 16:46:59 INFO - TEST-INFO | 1635: exit 0 16:46:59 INFO - TEST-OK | dromaeo_css | took 1119113ms 16:46:59 INFO - TEST-START | kraken 16:46:59 INFO - operating with platform_type : mac_ 16:46:59 INFO - Initialising browser for kraken test... 16:46:59 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpx0f6AL/profile http://localhost:49240/getInfo.html) 16:47:01 INFO - PROCESS | 1646 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 16:47:01 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 16:47:01 INFO - PROCESS | 1646 | colorDepth:24 16:47:01 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 16:47:01 INFO - PROCESS | 1646 | __metrics 16:47:01 INFO - PROCESS | 1646 | 1485910021606 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 16:47:01 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:47:01 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:47:02 INFO - TEST-INFO | 1646: exit 0 16:47:02 INFO - Browser initialized. 16:47:02 INFO - Running cycle 1/1 for kraken test... 16:47:02 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpx0f6AL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:14 INFO - PROCESS | 1647 | RSS: Main: 237379584 16:47:14 INFO - PROCESS | 1647 | 16:47:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:18 INFO - PROCESS | 1647 | RSS: Main: 289394688 16:47:18 INFO - PROCESS | 1647 | 16:47:20 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 16:47:21 INFO - PROCESS | 1647 | RSS: Main: 290603008 16:47:21 INFO - PROCESS | 1647 | 16:47:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 16:47:25 INFO - PROCESS | 1647 | RSS: Main: 382910464 16:47:25 INFO - PROCESS | 1647 | 16:47:27 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 16:47:27 INFO - PROCESS | 1647 | RSS: Main: 284467200 16:47:27 INFO - PROCESS | 1647 | 16:47:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:30 INFO - PROCESS | 1647 | RSS: Main: 287625216 16:47:30 INFO - PROCESS | 1647 | 16:47:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:34 INFO - PROCESS | 1647 | RSS: Main: 330149888 16:47:34 INFO - PROCESS | 1647 | 16:47:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:37 INFO - PROCESS | 1647 | RSS: Main: 331321344 16:47:37 INFO - PROCESS | 1647 | 16:47:40 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 16:47:40 INFO - PROCESS | 1647 | RSS: Main: 330739712 16:47:40 INFO - PROCESS | 1647 | 16:47:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:43 INFO - PROCESS | 1647 | RSS: Main: 305676288 16:47:43 INFO - PROCESS | 1647 | 16:47:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:45 INFO - PROCESS | 1647 | RSS: Main: 312770560 16:47:45 INFO - PROCESS | 1647 | 16:47:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:48 INFO - PROCESS | 1647 | RSS: Main: 312811520 16:47:48 INFO - PROCESS | 1647 | 16:47:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:52 INFO - PROCESS | 1647 | RSS: Main: 330010624 16:47:52 INFO - PROCESS | 1647 | 16:47:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:55 INFO - PROCESS | 1647 | RSS: Main: 323911680 16:47:55 INFO - PROCESS | 1647 | 16:47:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 16:47:58 INFO - PROCESS | 1647 | RSS: Main: 368635904 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | __start_tp_report 16:47:58 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 16:47:58 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 16:47:58 INFO - PROCESS | 1647 | |i|pagename|runs| 16:47:58 INFO - PROCESS | 1647 | |0;ai-astar;113;113;114;115;114;113;114;115;114;115 16:47:58 INFO - PROCESS | 1647 | |1;audio-beat-detection;132;109;110;144;110;112;112;135;162;141 16:47:58 INFO - PROCESS | 1647 | |2;audio-dft;186;182;181;183;184;186;181;180;182;182 16:47:58 INFO - PROCESS | 1647 | |3;audio-fft;78;59;58;88;62;58;58;58;73;58 16:47:58 INFO - PROCESS | 1647 | |4;audio-oscillator;89;71;86;75;95;101;119;67;70;82 16:47:58 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;86;85;87;83;87;83;84;86;87;84 16:47:58 INFO - PROCESS | 1647 | |6;imaging-darkroom;93;94;93;94;93;93;93;97;94;93 16:47:58 INFO - PROCESS | 1647 | |7;imaging-desaturate;86;85;84;86;86;87;88;87;85;87 16:47:58 INFO - PROCESS | 1647 | |8;json-parse-financial;61;58;59;58;60;60;59;61;61;59 16:47:58 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;66;70;69;81;75;73;71;71;71;71 16:47:58 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;101;75;88;95;107;109;106;85;85;86 16:47:58 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;139;144;151;148;138;142;151;145;202;147 16:47:58 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;168;167;169;174;174;173;181;173;173;174 16:47:58 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;66;67;67;70;79;86;61;59;81;82 16:47:58 INFO - PROCESS | 1647 | __end_tp_report 16:47:58 INFO - PROCESS | 1647 | __start_cc_report 16:47:58 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1979 16:47:58 INFO - PROCESS | 1647 | __end_cc_report 16:47:58 INFO - PROCESS | 1647 | __startTimestamp1485910078025__endTimestamp 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | ------- Summary: start ------- 16:47:58 INFO - PROCESS | 1647 | Number of tests: 14 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 16:47:58 INFO - PROCESS | 1647 | Values: 113.0 113.0 114.0 115.0 114.0 113.0 114.0 115.0 114.0 115.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:133.50 stddev:18.71 (14.0%) stddev-sans-first:19.74 16:47:58 INFO - PROCESS | 1647 | Values: 132.0 109.0 110.0 144.0 110.0 112.0 112.0 135.0 162.0 141.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:182.70 Median:182.50 stddev:2.06 (1.1%) stddev-sans-first:1.80 16:47:58 INFO - PROCESS | 1647 | Values: 186.0 182.0 181.0 183.0 184.0 186.0 181.0 180.0 182.0 182.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:65.00 Median:60.50 stddev:10.81 (17.9%) stddev-sans-first:10.39 16:47:58 INFO - PROCESS | 1647 | Values: 78.0 59.0 58.0 88.0 62.0 58.0 58.0 58.0 73.0 58.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:78.50 stddev:16.26 (20.7%) stddev-sans-first:17.20 16:47:58 INFO - PROCESS | 1647 | Values: 89.0 71.0 86.0 75.0 95.0 101.0 119.0 67.0 70.0 82.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.62 (1.9%) stddev-sans-first:1.69 16:47:58 INFO - PROCESS | 1647 | Values: 86.0 85.0 87.0 83.0 87.0 83.0 84.0 86.0 87.0 84.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.50 stddev:1.25 (1.3%) stddev-sans-first:1.30 16:47:58 INFO - PROCESS | 1647 | Values: 93.0 94.0 93.0 94.0 93.0 93.0 93.0 97.0 94.0 93.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.50 stddev:1.20 (1.4%) stddev-sans-first:1.27 16:47:58 INFO - PROCESS | 1647 | Values: 86.0 85.0 84.0 86.0 86.0 87.0 88.0 87.0 85.0 87.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:60.00 stddev:1.17 (2.0%) stddev-sans-first:1.13 16:47:58 INFO - PROCESS | 1647 | Values: 61.0 58.0 59.0 58.0 60.0 60.0 59.0 61.0 61.0 59.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.00 stddev:3.99 (5.6%) stddev-sans-first:3.64 16:47:58 INFO - PROCESS | 1647 | Values: 66.0 70.0 69.0 81.0 75.0 73.0 71.0 71.0 71.0 71.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:85.00 stddev:11.60 (13.6%) stddev-sans-first:11.99 16:47:58 INFO - PROCESS | 1647 | Values: 101.0 75.0 88.0 95.0 107.0 109.0 106.0 85.0 85.0 86.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:147.50 stddev:18.57 (12.6%) stddev-sans-first:19.21 16:47:58 INFO - PROCESS | 1647 | Values: 139.0 144.0 151.0 148.0 138.0 142.0 151.0 145.0 202.0 147.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.50 stddev:3.98 (2.3%) stddev-sans-first:3.86 16:47:58 INFO - PROCESS | 1647 | Values: 168.0 167.0 169.0 174.0 174.0 173.0 181.0 173.0 173.0 174.0 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:74.50 stddev:9.46 (12.7%) stddev-sans-first:9.80 16:47:58 INFO - PROCESS | 1647 | Values: 66.0 67.0 67.0 70.0 79.0 86.0 61.0 59.0 81.0 82.0 16:47:58 INFO - PROCESS | 1647 | -------- Summary: end -------- 16:47:58 INFO - PROCESS | 1647 | 16:47:58 INFO - TEST-INFO | 1647: exit 0 16:47:58 INFO - TEST-OK | kraken | took 59777ms 16:47:58 INFO - SUITE-END | took 1178s 16:47:59 INFO - Completed test suite (00:19:39) 16:47:59 INFO - javascript benchmark 16:47:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7108.41998593845, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.139883630256, 3332.966670333296, 3339.0483712142036, 3359.344927739092, 3396.252824378636, 5539.750711217995, 5578.24693666355, 5610.803621873235, 5651.2653355063885, 5651.773929042838, 5613.158026296058, 5666.291713535808, 5737.971310143454, 5746.942530574693, 5755.65466072036, 4233.83064677414, 5101.693898366091, 5106.69359838409, 5167.095758242312, 5185.818496352628, 5184.7148406837605, 5251.684898906059, 5259.815906443275, 5289.920651190235, 5335.5198032177095, 5241.9213711794355, 5267.26258323835, 5271.578273738091, 5276.182191760283, 5281.155015197569, 4676.3453116563705, 5004.649674522785, 5043.52086551777, 5055.620828437882, 5152.639315247934, 4453.153900758845, 5150.562202212816, 5175.249588809639, 5178.637495375326, 5230.8692282692855, 5251.55361794248, 5646.237757902672, 5648.43515648436, 5684.488396044376, 5686.289213848269, 6093.299270583895, 6127.387261273881, 6136.7852125175405, 6249.343818899041, 6276.246850377984, 6185.783497577609, 6213.068039794067, 6294.307626161119, 6323.620582765049, 6334.778282760128, 5760.53915686744, 5807.128930660392, 5809.68046757424, 5826.854328641818, 5857.062869940832, 6168.0439531872635, 6304.747810087545, 6311.526635502356, 6318.241810982711, 6319.494440444753, 5903.616264942765, 6071.605345652519, 6079.513638908877, 6136.8772624547255, 6165.32181460039, 6141.570090093651, 6247.687615619201, 6252.562320637603, 6255.249370075529, 6256.0, 6077.270727512727, 6102.193115296201, 6107.15847211733, 6155.261368635703, 6172.105044768484, 6216.44052035319, 6226.346233645492, 6230.252369715572, 6235.657038862906, 6236.126942228092, 4884.389451318585, 4932.334134891744, 4933.038057578738, 4937.086638971802, 4987.002599480046, 3637.2725454908596, 4340.8697739067875, 4391.626711729538, 4406.559344065632, 4427.5572442755465, 5669.31968163823, 5685.1756495307945, 5697.0599851024945, 5698.287714035746, 5713.400092990176, 5441.347038355423, 6186.1648677428875, 6211.59624624407, 6215.96892015537, 6220.533459990519, 5626.774929002876, 5666.9716651416475, 5695.800646977336, 5710.3433105193435, 5743.483086522233, 5420.728963551806, 5422.05114105038, 5428.538574221236, 5430.049741295257, 5433.701146436984, 6242.625442473466, 6264.34224406449, 6291.370862913764, 6310.810675679737, 6334.968325158345, 6234.750609975732, 6289.40250676185, 6295.905561416491, 6307.085472606538, 6328.0, 6296.150019747459, 6298.08677741734, 6302.243730752339, 6302.306746257999, 6336.619802811846, 5986.16193732869, 6160.353162918008, 6188.288346840171, 6204.255489341308, 6216.0, 6122.051082082285, 6191.102290167811, 6195.96902015487, 6236.781712640217, 6286.151369565233, 6122.571420000735, 6131.141640170291, 6133.938660613337, 6145.754169833157, 6151.0, 5993.3706960768495, 6096.603720758137, 6109.784727033366, 6114.38856114385, 6114.960456722457, 5796.536277097738, 5845.736941837713, 5856.853578660568, 5891.027980383189, 5895.115732640138, 6000.8499787503915, 6019.428154325332, 6029.035354343284, 6031.969840150771, 6033.668148251888, 5816.82549523515, 5865.237519122486, 5872.236609240942, 5876.676782776817, 5890.145928840208], "value": 5658.552511389831, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.7430764464255, 474.07318691957204, 477.4032459425718, 493.21332474702837, 519.0786354221257, 30848.228794280127, 32601.67398326016, 32644.347113057724, 37750.0, 39666.40500392489, 40833.183336333255, 41293.587064129424, 41367.58632413675, 41415.792921035354, 41455.58544414555, 9057.592408341625, 9285.303602229826, 9319.813603727922, 9321.673741419052, 9322.161005509503, 8382.622781974795, 8568.700095496659, 9227.630894764201, 9349.579268932881, 10979.451027448635, 11089.83365249522, 11320.830187547193, 11344.546218151265, 11345.773084538345, 11347.546098156065, 11307.378094204816, 11315.32108073514, 11319.20765546412, 11359.829602555968, 11387.0, 7112.751053713147, 8520.062793092755, 10768.569257229701, 11013.83479247812, 11119.444027798658, 38917.0, 39200.803995980124, 39240.60759392398, 39243.0, 39311.21377572461, 3986.86045988389, 3995.98002009991, 3999.140184860269, 4051.1290072634524, 4077.3894311746694, 29118.0, 29390.265243369086, 29874.253143671584, 30056.849715751283, 30243.69756302453, 3005.1435340927733, 3181.936361272785, 3217.6621454747146, 3273.6562660920736, 3273.705366517001, 458326.0, 458791.9999999967, 461960.0, 463194.0, 466521.0, 63589.682051590375, 63979.36020639827, 63983.04025439622, 64065.35934640594, 64198.67900660467, 58463.0, 66761.33238667648, 66998.0, 67011.0, 67015.32984670092, 15615.453459128876, 15889.364425423084, 15976.0, 16127.435539756052, 16205.0, 12421.37893105341, 12495.75008499825, 12507.874921250854, 12532.624021279376, 12537.811932821014, 14409.711805764036, 14582.416703331748, 15945.760813587805, 16105.677886442205, 16114.355425783071, 6332.556721029484, 6846.349596788297, 6919.792406227822, 6940.201876784235, 6962.477814163958, 857.2627540315324, 871.1158174452935, 880.9515476648846, 881.6737807011318, 900.4597241654955, 23418.765812342, 27540.44919101647, 27724.445511090067, 28757.71242287592, 29839.403211935638, 1035.295998720877, 1036.3056751976194, 1072.4905669806778, 1076.2842709598185, 1131.1742428027585, 11129.77572467028, 12580.30808305552, 14717.852821471863, 15265.389384424721, 15548.844511554966, 580441.0, 585210.0, 585728.0, 585736.0, 586973.0, 60520.71147904867, 60938.0, 61082.083768743505, 61148.0, 61275.38724612786, 1360.4014233737123, 1410.9365078571489, 1425.3728359521776, 1425.736238795826, 1426.2369632246698, 27212.863935680194, 28073.929573820355, 28252.15243542697, 28738.0, 28748.0, 46602.53397465982, 46649.06701865876, 46898.0, 46989.06021879612, 47219.52780472151, 494728.0, 497788.0, 499125.0, 499445.0, 501277.0, 24738.010479580615, 25060.498790024463, 25189.87405062963, 25207.621885672095, 25229.87385063063, 38783.806080969414, 42115.0, 42753.0, 47373.578792636275, 47597.28604071011, 38790.80604596959, 39030.41454378243, 39359.212815742954, 40520.0, 40735.796321018206, 38594.2281154381, 39880.800595996836, 40208.994775129686, 40630.59369406268, 40660.18679626332, 30884.382312354075, 36897.81551092227, 37043.073108302546, 37168.44247336346, 37704.0, 33354.4996825043, 34174.82912585421, 34289.31421371608, 34765.82617086898, 34890.30219395547, 21328.253511127037, 23292.534149317256, 24306.75693243045, 24807.0, 24988.875055624605], "value": 19489.416354876194, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.11691369788537, 395.466120737005, 397.14019148543406, 408.58936768547596, 423.52988183116736, 10707.625233116823, 20166.798332016675, 20761.792382076175, 21523.67714484284, 21531.0, 13378.531751388678, 18068.819311806878, 20271.49321266969, 20795.58408831826, 21456.249031283907, 5682.6306290091115, 5811.4479124483105, 5860.501857342109, 5943.049112142036, 5945.137954996523, 5665.1785491103765, 5836.649801011953, 5837.328707198684, 5862.091375836731, 5947.672877991708, 5842.152887831283, 5852.180694702745, 5859.531237501011, 5860.238169038019, 5861.501772349356, 5602.971985140048, 5831.271091113611, 5833.679147646878, 5853.385394533598, 5890.764369425217, 5152.793888244466, 5161.974190129063, 5163.8192663256605, 5166.250893620441, 5187.740612969336, 25965.0, 26236.60645090325, 26322.94708211669, 26722.5991610126, 26988.865055674793, 2634.2755742170866, 2721.621661846209, 2721.8366897986184, 2724.768394686454, 2727.6454060971946, 15417.922910385376, 15579.065256084557, 15585.415354468423, 15679.372825086983, 15909.0, 1816.4823025437686, 1874.4845167578887, 1881.7459642948163, 1899.943001709951, 1905.4569447707338, 130162.0, 133315.33342333324, 134460.3276983609, 136299.31850340785, 137096.0, 9403.952980235055, 11287.717807054889, 11299.717507062225, 11500.309981401142, 11588.420578971018, 14401.63995900111, 14409.85590144106, 14414.927925360305, 14435.566932992027, 14458.927705361617, 7594.0, 7971.960140199378, 7985.64064617082, 8262.793430164174, 8554.743357699279, 7043.696916070543, 8138.796530086795, 8484.660613575512, 8535.658573656985, 8714.9977752558, 5725.5133313667975, 7157.821054473679, 7164.820879478055, 7208.675609597476, 7230.385417239489, 1905.8284754371896, 1939.9718149380851, 1942.5435022769639, 1951.8048195180652, 1953.247999520195, 337.73825285404007, 389.2817751248941, 1014.183582216323, 1036.0778906772978, 1066.858461446245, 11504.871598754256, 11665.300081995107, 11967.940160299142, 12069.0, 12101.757964840654, 403.2177575503514, 404.41965779107073, 421.33849855726214, 421.58684489200755, 439.7515403796908, 5990.921634105903, 6009.789657361971, 6169.9383006170265, 6203.286622038433, 6228.252609686867, 517974.0, 582390.0, 655228.7532684387, 762550.0, 769672.0, 131245.34377328242, 139743.0, 149187.0, 155564.2221788884, 166710.0, 827.7475370012204, 982.6511588386163, 1022.197574903697, 1026.9486525673688, 1027.111548510548, 15087.924560377129, 18481.445556633324, 18661.0, 19990.600187995868, 20335.694964575247, 58066.709666451396, 60577.09134363076, 61553.0, 62000.0, 62203.444913877516, 58377.416225837194, 66730.66634666796, 70153.64923175352, 88336.0, 89333.0, 7217.711291548279, 7356.66894989716, 7567.470277080552, 8245.95877020611, 8265.710700125466, 7749.573773442515, 11779.0, 13095.345232738135, 13323.467061317438, 13774.793378099537, 11183.440827958731, 11407.885921140682, 11755.823662645238, 11806.232594881305, 11839.408029598659, 10981.066609337977, 11215.66353009411, 11469.369184694922, 11584.0, 11665.475053622438, 11259.887401126212, 11467.426628668367, 11508.88491115078, 11967.521299147937, 12046.457909393923, 7577.280158384945, 7732.110807257238, 7748.651310691145, 7768.145503994443, 7811.531308121531, 5726.627769195155, 8606.26846718036, 9413.623455061721, 9655.613775448903, 10834.212464420085], "value": 9258.03727965304, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.3404299979013, 1410.5063227870246, 1420.8081908942297, 1426.0801782850056, 1426.4365575597635, 7907.802304942379, 8326.625301861415, 8327.167283271678, 8335.416520843528, 8346.332293416528, 7378.151512576042, 7620.123685776138, 7641.3122818946285, 7910.723124690637, 8132.186781321865, 2218.223621732393, 2260.9886950565224, 2267.4218074391006, 2269.7389800172946, 2273.6475846243857, 2259.2318611672026, 2298.0807676929194, 2299.8735069571167, 2301.5742087713743, 2306.780855818703, 1973.0627951722888, 2062.865913715611, 2189.824814014875, 2257.8532395394245, 2284.668723035159, 2259.2092767531317, 2270.0465804362207, 2270.2962081754545, 2271.943201419961, 2272.625016872214, 2199.802017818388, 2263.207877242952, 2266.6033444147374, 2268.7390950040626, 2268.875211863347, 8201.876971845428, 8263.669453221864, 8267.958660206661, 8282.461639993382, 8294.8341033179, 4984.102861484931, 5139.665921715114, 5142.408623008327, 5144.588432925357, 5147.6911385317, 3094.6428830466593, 3198.040587823644, 3378.3750006248924, 3434.553508043963, 3543.3976224041717, 1170.5902933973127, 1218.1351240619172, 1236.190295356543, 1256.064232147047, 1267.1889990406055, 10696.999999999922, 10707.78584428315, 10716.892831071746, 10717.142628589694, 10722.571097156104, 4493.551014696998, 4578.748168850745, 4610.008848097675, 4651.813927442932, 4662.906741865144, 9414.29392795543, 9438.858417123749, 9439.528023598792, 9441.527923603793, 9465.148136667594, 5221.477852214824, 5226.608004399685, 5229.6339256251695, 5241.449647787003, 5243.8164664236565, 4214.915701685949, 4255.595718406808, 4640.443146822336, 4670.159371313196, 4743.976280118646, 3685.4287585424304, 3741.5884252732176, 3787.753796003251, 3840.807959602009, 3846.2692088503095, 1236.5857437758452, 1245.1781823996118, 1247.9376031198585, 1258.8552316483538, 1265.487477571585, 668.9230738465042, 757.9734709285149, 773.083895583735, 781.6482582837746, 846.8687353460224, 5054.595632349403, 5201.420740018578, 5317.282166907496, 5345.1447768356875, 5556.0, 871.3639043498282, 883.7348795361365, 929.7396728915915, 949.4730424614268, 971.9454391984634, 3603.135247540624, 3613.512175856226, 3640.7633503822167, 3732.496113024707, 3830.942535861964, 8821.558922053871, 9557.856632150524, 9855.014498550088, 9863.457509836884, 9872.210223182128, 9162.86255706165, 9650.758731031781, 9807.26445516589, 9807.313488055768, 9822.656207032862, 7386.298301661333, 7573.697052117964, 7573.886391704129, 7595.848083038419, 7628.656710448043, 5311.973440132774, 5425.674459532441, 5433.565314774887, 5437.782488700566, 5630.887382252414, 9060.320475964198, 9145.451272923647, 9178.495182764746, 9187.862182067409, 9211.124943130391, 8834.204921557091, 9012.008679045168, 9264.305177111879, 9335.159835614828, 9348.25213982866, 6596.175478065242, 6721.462283017249, 6732.629705366252, 6739.898901516383, 6740.056392105012, 7579.393648507996, 7654.387648988179, 7748.65131069092, 7771.261730135628, 7775.6112194388925, 6882.449404047564, 7009.264027277062, 7038.331358520933, 7040.823979400556, 7065.823354415975, 6811.795646130624, 6907.551009184387, 6962.164540255202, 6964.756233531802, 6968.0, 6107.175531303217, 6246.968765156145, 6248.156498872776, 6263.248410190807, 6265.122882796503, 4717.262793336224, 4869.926951095666, 4918.508149185053, 4919.0407870464915, 4995.275685025578, 4831.758412079453, 4885.047415753894, 4982.55157035854, 5105.795768169231, 5114.71869047206], "value": 4323.1815342711025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.28222502756824, 359.60802725029714, 369.45505379565134, 373.4472979989617, 376.65159727252285, 5355.544778693811, 5373.247745315649, 5385.21914322423, 5412.917416516692, 5444.9707216659635, 4485.52901945296, 4901.877453063676, 5299.152135658295, 5326.0, 5332.680039197651, 3494.6505349465106, 3817.3701339278987, 3895.6883449323986, 4175.415441838145, 4175.853845115422, 3709.071282932873, 3776.1126135358168, 4091.486150124458, 4152.771597562133, 4187.7277976931555, 3446.2418267981, 3821.7515861469055, 4121.897832105617, 4140.689448291375, 4184.263530634783, 3521.1021189596618, 3964.940525892114, 3983.24318379507, 4149.356849688304, 4161.292580261363, 3194.552762613236, 3456.1164165529844, 3694.7598406103784, 3703.759255648374, 3730.8321125549414, 4319.747273290742, 4983.90032199354, 5071.746412679351, 5098.286239926413, 5222.242774797671, 4704.106219818258, 4748.287756836467, 4761.785719642589, 4779.665423420362, 4791.305260737174, 4829.6860704054125, 5255.185446255837, 5554.305711786027, 5573.637713548606, 5644.181593668936, 4075.735077219971, 4077.7961101944784, 4082.6733861291027, 4094.979525102385, 4121.5878412158845, 4918.778654960535, 5082.603547931013, 5563.638363506359, 5666.0, 5916.585838991272, 3586.7130629549574, 3902.8776978417154, 4121.753644263883, 4121.855735049319, 4156.129741616285, 3348.5646865907274, 3447.5690538682666, 4205.810738516774, 4235.639970602533, 4237.745735255895, 3974.3839704845795, 3990.3216453203027, 3994.0613955720382, 4740.881477963078, 4793.60985314262, 5012.624053196025, 5049.293098966147, 5616.269884914935, 5647.842192350558, 5648.9045442055, 1066.4401189375485, 1174.5595401724354, 1217.933013684256, 1274.5857596281246, 1283.2172374851334, 792.659156562672, 808.8948436703191, 832.5171400587644, 868.5006121480175, 876.3252295732251, 4477.388140269565, 4648.604868586141, 4979.041661878815, 4988.578255197324, 5062.367204099488, 4880.902381952341, 5293.285406470154, 5323.30796996388, 5517.641353312022, 5554.888902221933, 4690.835820746326, 4751.881202969884, 4859.1010663027455, 4893.556400861754, 5041.470645582234, 2444.8655323956996, 2460.6801115855183, 2684.852333121697, 2846.672632647231, 2879.5680647902564, 5270.0, 5545.944540554543, 5626.2967129108865, 5635.323761148688, 5768.480836724715, 4881.51184881509, 4910.386201724785, 5102.708752854388, 5162.406323272797, 5691.211306459805, 5233.527315558851, 5316.335458067664, 5712.971894977306, 5721.456461636138, 5742.971285143548, 4326.805293761725, 4736.4316282046375, 4942.382202224722, 5110.189395832203, 5564.248826408545, 4682.742449165328, 4982.377202849644, 5045.823396181116, 5419.883566948941, 5482.533984611353, 4776.42682878043, 4996.550310472075, 5091.141733267256, 5705.465698272669, 5761.913571296518, 3791.0901383668274, 3809.933218698769, 3811.294910441577, 3847.3074846526774, 3849.5380554332537, 5020.945601423742, 5113.937202169899, 5668.093105103164, 5708.80019199326, 5726.169705392612, 4690.296455531698, 5272.1029447468645, 5426.593005524523, 5516.889662206813, 5554.666719996651, 4643.86068417948, 4832.830850920201, 5350.545203657733, 5512.283403157564, 5548.3619383769765, 4210.884115709401, 4581.419410303573, 5388.54197393226, 5427.019138014675, 5448.210009548672, 4454.376387305844, 5537.224788529528, 5559.471850174227, 5592.776288948559, 5620.550355971431, 4585.862424127282, 5485.862853428536, 5492.116638764521, 5495.945040549544, 5514.097636315465], "value": 4006.630983999463, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.7811439396587, 713.3401603516747, 727.4035291061329, 774.9615515209622, 847.5080290234328, 12361.814572781415, 15443.613909652264, 15760.211989400541, 16284.0, 16308.347666093372, 14574.05268657539, 14737.926310368433, 14751.754613984906, 14763.335649895753, 14804.925975370135, 853.9573021348923, 882.5807741322884, 884.8274586455643, 885.5173930208052, 888.1473785166248, 697.9546329488593, 715.8031541326151, 837.8408102460544, 870.7648934787635, 873.9126087391273, 918.8897332320098, 923.6443969071888, 924.3344791749929, 924.8057907839362, 925.6112432778249, 916.1571354354011, 917.4678686361895, 918.3433844800933, 919.0763282900675, 919.2554031234688, 534.794104269855, 547.728874207266, 557.1892895836548, 688.1088989758275, 694.6978064541889, 12565.371731413485, 12632.368381580884, 14747.262636868221, 15252.237388130548, 15326.923365383213, 12328.753424931541, 14313.85686143146, 14364.0, 14491.855081449155, 14903.254837258093, 11593.304401735839, 11706.824397634042, 11802.645920622395, 11850.358311688953, 12068.517259309618, 3713.3316003119426, 3793.848246070154, 3890.5720370759195, 3927.155661532784, 3928.823202955874, 95433.5228323861, 97480.51259743726, 101430.49284753602, 101908.49045754797, 102447.4877625607, 10318.122959548373, 11031.007209351197, 11040.392778397265, 11387.20289579738, 11678.474468648932, 19962.308857418495, 21215.15139394438, 22049.448763780714, 22086.447838803837, 22519.64882107046, 7631.847363052708, 7706.499077559941, 7792.220777922275, 7817.921820781833, 7974.880376794353, 4289.485261768546, 4312.482502099768, 4344.54382289855, 4403.119376124724, 4532.388127602731, 3548.3258180945536, 3611.7832930024283, 3634.527511423498, 3642.2897534980425, 3655.634436556323, 656.7734131724643, 663.2571519897746, 666.4901350466799, 676.0940339944498, 677.579900461707, 645.0195702532125, 655.2890114226118, 675.6081472745796, 687.9690413931385, 692.6190595172735, 9415.63473296382, 9801.50992450389, 9901.306908516479, 10058.094771470604, 10063.446510441849, 523.481753064471, 552.4116815591383, 589.1457386789087, 600.4265926040621, 666.2038863558098, 4609.990627278047, 4947.942587542037, 4959.328129592089, 4964.461126861792, 4969.418581002334, 103859.96140038654, 104918.0, 105585.0, 105711.9428805702, 106258.0, 90487.0, 91273.54363228142, 91771.5411422952, 92965.0, 95165.52417237869, 63045.0, 79789.20210797933, 83051.58474207591, 83114.58442707869, 83927.0, 9227.584758685742, 9668.274879384215, 9682.177014953526, 9693.6122555097, 9752.12230899194, 47197.528024719315, 47886.0, 48620.513794861596, 48936.0, 49836.0, 60487.79024419575, 63183.097324342685, 64771.67614161899, 66144.33855661382, 66475.67048658904, 9241.630334786532, 11192.720181995515, 11388.658340249805, 11432.714182145513, 11543.538258469904, 12836.486540538273, 12847.229166250056, 12926.289054102115, 13149.671258218621, 13171.209727416386, 11753.353565553634, 11845.348505832262, 12002.21985570935, 12277.447514861675, 12364.072694547765, 11673.883261167279, 11854.762904742029, 11870.465829037887, 11925.761484770082, 11973.461194246454, 11874.643760687193, 11889.762204755683, 11929.701757456132, 11933.224340417844, 11981.0, 10207.642732504326, 10342.948285258526, 10483.37099774016, 10527.420991845522, 10803.459827008775, 9459.38513996588, 9826.263030272616, 9841.557129929028, 9850.261230407605, 9872.950635246778], "value": 7294.319856506747, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 115.0, 114.0, 113.0, 114.0, 115.0, 114.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 109.0, 110.0, 144.0, 110.0, 112.0, 112.0, 135.0, 162.0, 141.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 181.0, 183.0, 184.0, 186.0, 181.0, 180.0, 182.0, 182.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 58.0, 88.0, 62.0, 58.0, 58.0, 58.0, 73.0, 58.0], "value": 65.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 75.0, 95.0, 101.0, 119.0, 67.0, 70.0, 82.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 83.0, 87.0, 83.0, 84.0, 86.0, 87.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 93.0, 93.0, 93.0, 97.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 86.0, 86.0, 87.0, 88.0, 87.0, 85.0, 87.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 59.0, 58.0, 60.0, 60.0, 59.0, 61.0, 61.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 69.0, 81.0, 75.0, 73.0, 71.0, 71.0, 71.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 75.0, 88.0, 95.0, 107.0, 109.0, 106.0, 85.0, 85.0, 86.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 144.0, 151.0, 148.0, 138.0, 142.0, 151.0, 145.0, 202.0, 147.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 169.0, 174.0, 174.0, 173.0, 181.0, 173.0, 173.0, 174.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 70.0, 79.0, 86.0, 61.0, 59.0, 81.0, 82.0], "value": 71.8, "unit": "score"}]}]} 16:47:59 INFO - Return code: 0 16:47:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:59 ERROR - # TBPL SUCCESS # 16:47:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:47:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:47:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:47:59 INFO - 2017-01-31 16:47:59.294 screenresolution[1651:9646] starting screenresolution argv=screenresolution get 16:47:59 INFO - 2017-01-31 16:47:59.297 screenresolution[1651:9646] Display 0: 1600x1200x32@60 16:47:59 INFO - 2017-01-31 16:47:59.304 screenresolution[1652:9649] starting screenresolution argv=screenresolution list 16:47:59 INFO - Available Modes on Display 0 16:47:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:47:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:47:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:47:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:47:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:47:59 INFO - Intel Iris: 16:47:59 INFO - Chipset Model: Intel Iris 16:47:59 INFO - Type: GPU 16:47:59 INFO - Bus: Built-In 16:47:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:47:59 INFO - Vendor: Intel (0x8086) 16:47:59 INFO - Device ID: 0x0a2e 16:47:59 INFO - Revision ID: 0x0009 16:47:59 INFO - Displays: 16:47:59 INFO - Display: 16:47:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:47:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:47:59 INFO - Display Serial Number: 1600x1200 60 16:47:59 INFO - Main Display: Yes 16:47:59 INFO - Mirror: Off 16:47:59 INFO - Online: Yes 16:47:59 INFO - Rotation: Supported 16:47:59 INFO - Return code: 0 16:47:59 INFO - Running post-action listener: _resource_record_post_action 16:47:59 INFO - [mozharness: 2017-02-01 00:47:59.545700Z] Finished run-tests step (success) 16:47:59 INFO - Running post-run listener: _resource_record_post_run 16:47:59 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 36922368; Write bytes: 551746560; Read time: 243; Write time: 1657 16:47:59 INFO - TinderboxPrint: CPU usage
53.6% 16:47:59 INFO - TinderboxPrint: I/O read bytes / time
36,922,368 / 243 16:47:59 INFO - TinderboxPrint: I/O write bytes / time
551,746,560 / 1,657 16:47:59 INFO - TinderboxPrint: CPU idle
2,228.8 (46.4%) 16:47:59 INFO - TinderboxPrint: CPU system
963.4 (20.0%) 16:47:59 INFO - TinderboxPrint: CPU user
1,613.5 (33.6%) 16:47:59 INFO - TinderboxPrint: Swap in / out
288,665,600 / 0 16:47:59 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 248769024; Write bytes: 242272256; Read time: 17783; Write time: 523 16:47:59 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34759680; Write bytes: 296141824; Read time: 223; Write time: 1093 16:48:00 INFO - Running post-run listener: _upload_blobber_files 16:48:00 INFO - Blob upload gear active. 16:48:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:48:00 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 16:48:00 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'] 16:48:00 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 16:48:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:01 INFO - (blobuploader) - INFO - Done attempting. 16:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:02 INFO - (blobuploader) - INFO - Done attempting. 16:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:48:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:03 INFO - (blobuploader) - INFO - Done attempting. 16:48:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:48:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:05 INFO - (blobuploader) - INFO - Done attempting. 16:48:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:05 INFO - Return code: 0 16:48:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6eafd741a7d14d531573b5890ae6fcfea61ab4d609976206466e2dfbce0236b6213650ff1158a82839df85a909a39bda01da6d112cf58883319f398c7ea8b1ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45221b68f1ec9156720529039c2d071f70fa7c42d1ca1ffe6b5a8809d0e17d92f3e57c6a290a0089823f8334ba8ade9401deee2e5cf86f2bc67bc31395a70e0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5455550f175affb4f765b47028eccbc1197b292f482bebee641721b11bbbe9c06e8009e182d106144f288f18138ccdf9fb9ced1720dac376596d71100eca6f61", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8adec9d7e1a5dcc673289d9bd5760f642be8a8687ae9d9381eb1ba912497806d8d9dc6b4668e4cab70a9f86cf1b3a1cc595c18382e64d8136672cdac99ec429"} 16:48:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:05 INFO - Contents: 16:48:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6eafd741a7d14d531573b5890ae6fcfea61ab4d609976206466e2dfbce0236b6213650ff1158a82839df85a909a39bda01da6d112cf58883319f398c7ea8b1ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45221b68f1ec9156720529039c2d071f70fa7c42d1ca1ffe6b5a8809d0e17d92f3e57c6a290a0089823f8334ba8ade9401deee2e5cf86f2bc67bc31395a70e0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5455550f175affb4f765b47028eccbc1197b292f482bebee641721b11bbbe9c06e8009e182d106144f288f18138ccdf9fb9ced1720dac376596d71100eca6f61", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8adec9d7e1a5dcc673289d9bd5760f642be8a8687ae9d9381eb1ba912497806d8d9dc6b4668e4cab70a9f86cf1b3a1cc595c18382e64d8136672cdac99ec429"} 16:48:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:05 INFO - Copying logs to upload dir... 16:48:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.717016 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-31 16:48:05.872159) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 16:48:05.875284) ========= 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.xxeEgz7NFy/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.F46QCNqFRK/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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/6eafd741a7d14d531573b5890ae6fcfea61ab4d609976206466e2dfbce0236b6213650ff1158a82839df85a909a39bda01da6d112cf58883319f398c7ea8b1ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45221b68f1ec9156720529039c2d071f70fa7c42d1ca1ffe6b5a8809d0e17d92f3e57c6a290a0089823f8334ba8ade9401deee2e5cf86f2bc67bc31395a70e0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5455550f175affb4f765b47028eccbc1197b292f482bebee641721b11bbbe9c06e8009e182d106144f288f18138ccdf9fb9ced1720dac376596d71100eca6f61", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8adec9d7e1a5dcc673289d9bd5760f642be8a8687ae9d9381eb1ba912497806d8d9dc6b4668e4cab70a9f86cf1b3a1cc595c18382e64d8136672cdac99ec429"} build_url:https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/VOQ6mFnhSiexMFoM2uEATw/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/6eafd741a7d14d531573b5890ae6fcfea61ab4d609976206466e2dfbce0236b6213650ff1158a82839df85a909a39bda01da6d112cf58883319f398c7ea8b1ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45221b68f1ec9156720529039c2d071f70fa7c42d1ca1ffe6b5a8809d0e17d92f3e57c6a290a0089823f8334ba8ade9401deee2e5cf86f2bc67bc31395a70e0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5455550f175affb4f765b47028eccbc1197b292f482bebee641721b11bbbe9c06e8009e182d106144f288f18138ccdf9fb9ced1720dac376596d71100eca6f61", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8adec9d7e1a5dcc673289d9bd5760f642be8a8687ae9d9381eb1ba912497806d8d9dc6b4668e4cab70a9f86cf1b3a1cc595c18382e64d8136672cdac99ec429"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 16:48:05.910512) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 16:48:05.910915) ========= 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.xxeEgz7NFy/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.F46QCNqFRK/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 16:48:05.960448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 16:48:05.961198) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 16:48:05.961954) ========= ========= Total master_lag: 0.18 =========