builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0305 starttime: 1490915549.66 results: success (0) buildid: 20170330113306 builduid: f40ac9fd8edb49bc88526c0889a60f4f revision: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.664856) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.665304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.665637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.691835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.692466) ========= 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.LhdrfDVxF9/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.LJY4KygNWn/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-03-30 16:12:29-- 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% 161M=0s 2017-03-30 16:12:29 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131016 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.848588) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.849117) ========= 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.LhdrfDVxF9/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.LJY4KygNWn/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.021259 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:29.899272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 16:12:29.899812) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LhdrfDVxF9/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.LJY4KygNWn/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-03-30 16:12:29,993 truncating revision to first 12 chars 2017-03-30 16:12:29,993 Setting DEBUG logging. 2017-03-30 16:12:29,993 attempt 1/10 2017-03-30 16:12:29,993 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/d20d2cc8ee0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 16:12:31,434 unpacking tar archive at: pine-d20d2cc8ee0e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.710028 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 16:12:31.634997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:31.635319) ========= script_repo_revision: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:31.635715) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:31.635981) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 16:12:31.655666) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-30 16:12:31.656193) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LhdrfDVxF9/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.LJY4KygNWn/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:12:31 INFO - MultiFileLogger online at 20170330 16:12:31 in /builds/slave/test 16:12:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 16:12:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:31 INFO - {'append_to_log': False, 16:12:31 INFO - 'base_work_dir': '/builds/slave/test', 16:12:31 INFO - 'blob_upload_branch': 'Pine', 16:12:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:31 INFO - 'branch': 'Pine', 16:12:31 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:31 INFO - 'config_files': ('talos/mac_config.py',), 16:12:31 INFO - 'default_actions': ('clobber', 16:12:31 INFO - 'read-buildbot-config', 16:12:31 INFO - 'download-and-extract', 16:12:31 INFO - 'populate-webroot', 16:12:31 INFO - 'create-virtualenv', 16:12:31 INFO - 'install', 16:12:31 INFO - 'run-tests'), 16:12:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:31 INFO - 'download_minidump_stackwalk': True, 16:12:31 INFO - 'download_symbols': 'ondemand', 16:12:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:31 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:31 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:31 INFO - 'gecko_profile': False, 16:12:31 INFO - 'gecko_profile_interval': 0, 16:12:31 INFO - 'installer_path': 'installer.exe', 16:12:31 INFO - 'log_level': 'info', 16:12:31 INFO - 'log_name': 'talos', 16:12:31 INFO - 'log_to_console': True, 16:12:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:12:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:12:31 INFO - 'opt_config_files': (), 16:12:31 INFO - 'pip_index': False, 16:12:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:12:31 INFO - 'cmd': ('bash', 16:12:31 INFO - '-c', 16:12:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:12:31 INFO - 'enabled': True, 16:12:31 INFO - 'halt_on_failure': False, 16:12:31 INFO - 'name': 'check_screen_resolution'},), 16:12:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:12:31 INFO - 'cmd': ('bash', 16:12:31 INFO - '-c', 16:12:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:12:31 INFO - 'enabled': True, 16:12:31 INFO - 'halt_on_failure': False, 16:12:31 INFO - 'name': 'check_screen_resolution'},), 16:12:31 INFO - 'run_cmd_checks_enabled': True, 16:12:31 INFO - 'suite': 'dromaeojs', 16:12:31 INFO - 'system_bits': '32', 16:12:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:31 INFO - 'title': 't-yosemite-r7-0305', 16:12:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:31 INFO - 'use_talos_json': True, 16:12:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:31 INFO - 'work_dir': 'build'} 16:12:31 INFO - [mozharness: 2017-03-30 23:12:31.865330Z] Running clobber step. 16:12:31 INFO - Running pre-action listener: _resource_record_pre_action 16:12:31 INFO - Running main action method: clobber 16:12:31 INFO - rmtree: /builds/slave/test/build 16:12:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:33 INFO - Running post-action listener: _resource_record_post_action 16:12:33 INFO - [mozharness: 2017-03-30 23:12:33.985685Z] Finished clobber step (success) 16:12:33 INFO - [mozharness: 2017-03-30 23:12:33.985834Z] Running read-buildbot-config step. 16:12:33 INFO - Running pre-action listener: _resource_record_pre_action 16:12:33 INFO - Running main action method: read_buildbot_config 16:12:33 INFO - Using buildbot properties: 16:12:33 INFO - { 16:12:33 INFO - "project": "", 16:12:33 INFO - "product": "firefox", 16:12:33 INFO - "script_repo_revision": "default", 16:12:33 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 16:12:33 INFO - "repository": "", 16:12:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 16:12:33 INFO - "buildid": "20170330113306", 16:12:33 INFO - "pgo_build": "False", 16:12:33 INFO - "basedir": "/builds/slave/test", 16:12:33 INFO - "buildnumber": 2, 16:12:33 INFO - "slavename": "t-yosemite-r7-0305", 16:12:33 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:12:33 INFO - "platform": "yosemite_r7", 16:12:33 INFO - "scheduler": "tests-pine-macosx64-talos", 16:12:33 INFO - "branch": "pine", 16:12:33 INFO - "repo_path": "projects/pine", 16:12:33 INFO - "revision": "d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3", 16:12:33 INFO - "stage_platform": "macosx64", 16:12:33 INFO - "builduid": "f40ac9fd8edb49bc88526c0889a60f4f", 16:12:33 INFO - "slavebuilddir": "test" 16:12:33 INFO - } 16:12:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:12:33 INFO - Running post-action listener: _resource_record_post_action 16:12:33 INFO - [mozharness: 2017-03-30 23:12:33.989194Z] Finished read-buildbot-config step (success) 16:12:33 INFO - [mozharness: 2017-03-30 23:12:33.989317Z] Running download-and-extract step. 16:12:33 INFO - Running pre-action listener: _resource_record_pre_action 16:12:33 INFO - Running main action method: download_and_extract 16:12:33 INFO - mkdir: /builds/slave/test/build/tests 16:12: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:12:33 INFO - trying https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:12:33 INFO - Downloading https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:12:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:12:35 INFO - Downloaded 1325 bytes. 16:12:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:12:35 INFO - Using the following test package requirements: 16:12:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:12:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:12:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:12:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:12:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'jsshell-mac.zip'], 16:12:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:12:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:12:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:12:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:12:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:12:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:12:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:12:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:12:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:12:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:12:35 INFO - Fetch https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:12:36 INFO - Expected file size: 52508482 16:12:36 INFO - Obtained file size: 52508482 16:12:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:12:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:12:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:12:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:12:55 INFO - Expected file size: 52508482 16:12:55 INFO - Obtained file size: 52508482 16:13:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:13:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:13:10 INFO - Fetch https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:13:11 INFO - Expected file size: 14404152 16:13:11 INFO - Obtained file size: 14404152 16:13:11 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:13:11 INFO - trying https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:13:11 INFO - Downloading https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:13:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:13:14 INFO - Downloaded 75605671 bytes. 16:13:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:13:14 INFO - mkdir: /builds/slave/test/properties 16:13:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:13:14 INFO - Writing to file /builds/slave/test/properties/build_url 16:13:14 INFO - Contents: 16:13:14 INFO - build_url:https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:13:14 INFO - Running post-action listener: _resource_record_post_action 16:13:14 INFO - Running post-action listener: set_extra_try_arguments 16:13:14 INFO - [mozharness: 2017-03-30 23:13:14.935179Z] Finished download-and-extract step (success) 16:13:14 INFO - [mozharness: 2017-03-30 23:13:14.935341Z] Running populate-webroot step. 16:13:14 INFO - Running pre-action listener: _resource_record_pre_action 16:13:14 INFO - Running main action method: populate_webroot 16:13:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:13:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:13:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:13:14 INFO - '--tppagecycles', 16:13:14 INFO - '7'], 16:13:14 INFO - 'tests': ['tsvgm']}}, 16:13:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:13:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:13:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:13:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:13:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:13:14 INFO - 'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:13:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:13:14 INFO - 'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['damp', 'tps']}, 16:13:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:13:14 INFO - 'tests': ['damp', 'tps']}, 16:13:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['dromaeo_dom']}, 16:13:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:13:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['basic_compositor_video']}, 16:13:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:13:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['a11yr', 16:13:14 INFO - 'ts_paint', 16:13:14 INFO - 'tpaint', 16:13:14 INFO - 'sessionrestore', 16:13:14 INFO - 'sessionrestore_no_auto_restore', 16:13:14 INFO - 'tabpaint']}, 16:13:14 INFO - 'other-e10s': {'tests': ['a11yr', 16:13:14 INFO - 'ts_paint', 16:13:14 INFO - 'tpaint', 16:13:14 INFO - 'sessionrestore', 16:13:14 INFO - 'sessionrestore_no_auto_restore', 16:13:14 INFO - 'tabpaint']}, 16:13:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:13:14 INFO - 'ts_paint', 16:13:14 INFO - 'tpaint', 16:13:14 INFO - 'sessionrestore', 16:13:14 INFO - 'sessionrestore_no_auto_restore', 16:13:14 INFO - 'tabpaint']}, 16:13:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:13:14 INFO - 'ts_paint', 16:13:14 INFO - 'tpaint', 16:13:14 INFO - 'sessionrestore', 16:13:14 INFO - 'sessionrestore_no_auto_restore', 16:13:14 INFO - 'tabpaint']}, 16:13:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['a11yr', 16:13:14 INFO - 'ts_paint', 16:13:14 INFO - 'tpaint', 16:13:14 INFO - 'sessionrestore', 16:13:14 INFO - 'sessionrestore_no_auto_restore', 16:13:14 INFO - 'tabpaint']}, 16:13:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['a11yr', 16:13:14 INFO - 'ts_paint', 16:13:14 INFO - 'tpaint', 16:13:14 INFO - 'sessionrestore', 16:13:14 INFO - 'sessionrestore_no_auto_restore', 16:13:14 INFO - 'tabpaint']}, 16:13:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['tsvgx', 16:13:14 INFO - 'tsvgr_opacity', 16:13:14 INFO - 'tart', 16:13:14 INFO - 'tscrollx', 16:13:14 INFO - 'cart', 16:13:14 INFO - 'tsvg_static']}, 16:13:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:13:14 INFO - 'tsvgr_opacity', 16:13:14 INFO - 'tart', 16:13:14 INFO - 'tscrollx', 16:13:14 INFO - 'cart', 16:13:14 INFO - 'tsvg_static']}, 16:13:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:13:14 INFO - 'talos_options': ['--disable-e10s'], 16:13:14 INFO - 'tests': ['tp5o']}, 16:13:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:13:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:13:14 INFO - 'talos_options': ['--disable-e10s', 16:13:14 INFO - '--xperf_path', 16:13:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:14 INFO - 'tests': ['tp5n']}, 16:13:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:13:14 INFO - 'talos_options': ['--xperf_path', 16:13:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:14 INFO - 'tests': ['tp5n']}}, 16:13:14 INFO - 'talos.zip': {'path': '', 16:13:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:13:14 INFO - Running post-action listener: _resource_record_post_action 16:13:14 INFO - [mozharness: 2017-03-30 23:13:14.954100Z] Finished populate-webroot step (success) 16:13:14 INFO - [mozharness: 2017-03-30 23:13:14.954207Z] Running create-virtualenv step. 16:13:14 INFO - Running pre-action listener: _resource_record_pre_action 16:13:14 INFO - Running main action method: create_virtualenv 16:13:14 INFO - Creating virtualenv /builds/slave/test/build/venv 16:13:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:13:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:13:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:13:15 INFO - Using real prefix '/tools/python27' 16:13:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:13:15 INFO - Installing distribute.............................................................................................................................................................................................done. 16:13:18 INFO - Installing pip.................done. 16:13:18 INFO - Return code: 0 16:13:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:13:18 INFO - Reading from file tmpfile_stdout 16:13:18 INFO - Output received: 16:13:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:13:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:18 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 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:18 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:13:18 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:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:18 INFO - 'HOME': '/Users/cltbld', 16:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:18 INFO - 'LOGNAME': 'cltbld', 16:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:18 INFO - 'MOZ_NO_REMOTE': '1', 16:13:18 INFO - 'NO_EM_RESTART': '1', 16:13:18 INFO - 'PAGER': '/bin/cat', 16:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:18 INFO - 'PWD': '/builds/slave/test', 16:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:18 INFO - 'SHELL': '/bin/bash', 16:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:18 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:18 INFO - 'USER': 'cltbld', 16:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:18 INFO - 'XPC_FLAGS': '0x0', 16:13:18 INFO - 'XPC_SERVICE_NAME': '0', 16:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:13:19 INFO - Cleaning up... 16:13:19 INFO - Return code: 0 16:13:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:13: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:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:13:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:19 INFO - 'HOME': '/Users/cltbld', 16:13:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:19 INFO - 'LOGNAME': 'cltbld', 16:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:19 INFO - 'MOZ_NO_REMOTE': '1', 16:13:19 INFO - 'NO_EM_RESTART': '1', 16:13:19 INFO - 'PAGER': '/bin/cat', 16:13:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:19 INFO - 'PWD': '/builds/slave/test', 16:13:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:19 INFO - 'SHELL': '/bin/bash', 16:13:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:19 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:19 INFO - 'USER': 'cltbld', 16:13:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:19 INFO - 'XPC_FLAGS': '0x0', 16:13:19 INFO - 'XPC_SERVICE_NAME': '0', 16:13:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:19 INFO - Downloading/unpacking psutil>=3.1.1 16:13:19 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:13:19 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:13:19 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:13:19 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:13:19 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:13:19 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:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:13:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:23 INFO - Installing collected packages: psutil 16:13:23 INFO - Running setup.py install for psutil 16:13:23 INFO - building 'psutil._psutil_osx' extension 16:13:23 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:13:23 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:13:23 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:13:23 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:13:23 INFO - building 'psutil._psutil_posix' extension 16:13:23 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:13:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:13:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:13:23 INFO - ^ 16:13:23 INFO - 1 warning generated. 16:13:23 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:13:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:24 INFO - Successfully installed psutil 16:13:24 INFO - Cleaning up... 16:13:24 INFO - Return code: 0 16:13:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:24 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 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:24 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:13:24 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:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:24 INFO - 'HOME': '/Users/cltbld', 16:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:24 INFO - 'LOGNAME': 'cltbld', 16:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:24 INFO - 'MOZ_NO_REMOTE': '1', 16:13:24 INFO - 'NO_EM_RESTART': '1', 16:13:24 INFO - 'PAGER': '/bin/cat', 16:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:24 INFO - 'PWD': '/builds/slave/test', 16:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:24 INFO - 'SHELL': '/bin/bash', 16:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:24 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:24 INFO - 'USER': 'cltbld', 16:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:24 INFO - 'XPC_FLAGS': '0x0', 16:13:24 INFO - 'XPC_SERVICE_NAME': '0', 16:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:13:24 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:13:24 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:13:24 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:13:24 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:13:24 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:13:24 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:13:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:13:27 INFO - Installing collected packages: mozsystemmonitor 16:13:27 INFO - Running setup.py install for mozsystemmonitor 16:13:27 INFO - Successfully installed mozsystemmonitor 16:13:27 INFO - Cleaning up... 16:13:27 INFO - Return code: 0 16:13:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:13:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:13:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:28 INFO - 'HOME': '/Users/cltbld', 16:13:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:28 INFO - 'LOGNAME': 'cltbld', 16:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:28 INFO - 'MOZ_NO_REMOTE': '1', 16:13:28 INFO - 'NO_EM_RESTART': '1', 16:13:28 INFO - 'PAGER': '/bin/cat', 16:13:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:28 INFO - 'PWD': '/builds/slave/test', 16:13:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:28 INFO - 'SHELL': '/bin/bash', 16:13:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:28 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:28 INFO - 'USER': 'cltbld', 16:13:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:28 INFO - 'XPC_FLAGS': '0x0', 16:13:28 INFO - 'XPC_SERVICE_NAME': '0', 16:13:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:28 INFO - Downloading/unpacking jsonschema==2.5.1 16:13: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:13: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:13: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:13: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:13: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:13: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:13:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:13:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:13:31 INFO - warning: no files found matching '*.txt' 16:13:31 INFO - no previously-included directories found matching 'build' 16:13:31 INFO - no previously-included directories found matching 'dist' 16:13:31 INFO - no previously-included directories found matching '.git*' 16:13:31 INFO - Installing collected packages: jsonschema, functools32 16:13:31 INFO - Running setup.py install for functools32 16:13:32 INFO - warning: no files found matching '*.txt' 16:13:32 INFO - no previously-included directories found matching 'build' 16:13:32 INFO - no previously-included directories found matching 'dist' 16:13:32 INFO - no previously-included directories found matching '.git*' 16:13:32 INFO - Successfully installed jsonschema functools32 16:13:32 INFO - Cleaning up... 16:13:32 INFO - Return code: 0 16:13:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:13:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:32 INFO - 'HOME': '/Users/cltbld', 16:13:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:32 INFO - 'LOGNAME': 'cltbld', 16:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:32 INFO - 'MOZ_NO_REMOTE': '1', 16:13:32 INFO - 'NO_EM_RESTART': '1', 16:13:32 INFO - 'PAGER': '/bin/cat', 16:13:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:32 INFO - 'PWD': '/builds/slave/test', 16:13:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:32 INFO - 'SHELL': '/bin/bash', 16:13:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:32 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:32 INFO - 'USER': 'cltbld', 16:13:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:32 INFO - 'XPC_FLAGS': '0x0', 16:13:32 INFO - 'XPC_SERVICE_NAME': '0', 16:13:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:13:32 INFO - Cleaning up... 16:13:32 INFO - Return code: 0 16:13:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:13:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:32 INFO - 'HOME': '/Users/cltbld', 16:13:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:32 INFO - 'LOGNAME': 'cltbld', 16:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:32 INFO - 'MOZ_NO_REMOTE': '1', 16:13:32 INFO - 'NO_EM_RESTART': '1', 16:13:32 INFO - 'PAGER': '/bin/cat', 16:13:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:32 INFO - 'PWD': '/builds/slave/test', 16:13:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:32 INFO - 'SHELL': '/bin/bash', 16:13:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:32 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:32 INFO - 'USER': 'cltbld', 16:13:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:32 INFO - 'XPC_FLAGS': '0x0', 16:13:32 INFO - 'XPC_SERVICE_NAME': '0', 16:13:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:32 INFO - Downloading/unpacking blobuploader==1.2.4 16:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:13:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:13:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:13:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:13: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:13: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:13: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:13: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:13: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:13: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:13:36 INFO - Downloading docopt-0.6.1.tar.gz 16:13:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:13:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:13:36 INFO - Running setup.py install for blobuploader 16:13:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:13:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:13:36 INFO - Running setup.py install for requests 16:13:37 INFO - Running setup.py install for docopt 16:13:37 INFO - Successfully installed blobuploader requests docopt 16:13:37 INFO - Cleaning up... 16:13:37 INFO - Return code: 0 16:13:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:37 INFO - 'HOME': '/Users/cltbld', 16:13:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:37 INFO - 'LOGNAME': 'cltbld', 16:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:37 INFO - 'MOZ_NO_REMOTE': '1', 16:13:37 INFO - 'NO_EM_RESTART': '1', 16:13:37 INFO - 'PAGER': '/bin/cat', 16:13:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:37 INFO - 'PWD': '/builds/slave/test', 16:13:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:37 INFO - 'SHELL': '/bin/bash', 16:13:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:37 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:37 INFO - 'USER': 'cltbld', 16:13:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:37 INFO - 'XPC_FLAGS': '0x0', 16:13:37 INFO - 'XPC_SERVICE_NAME': '0', 16:13:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:37 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-PjFXuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:37 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-846pLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:37 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-X7DbUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-0xdcY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Tbjyh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-RMvvx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ppLmMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-wjzqCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-FbUTxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-IuGZeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-dFYwP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-OadXBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ecBcRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-uPnIly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-YRcRfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-G6Ispt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-B7ptbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:13:39 INFO - Running setup.py install for manifestparser 16:13:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:13:39 INFO - Running setup.py install for mozcrash 16:13:39 INFO - Running setup.py install for mozdebug 16:13:39 INFO - Running setup.py install for mozdevice 16:13:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Running setup.py install for mozfile 16:13:40 INFO - Running setup.py install for mozhttpd 16:13:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Running setup.py install for mozinfo 16:13:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Running setup.py install for mozInstall 16:13:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Running setup.py install for mozleak 16:13:40 INFO - Running setup.py install for mozlog 16:13:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:13:40 INFO - Running setup.py install for moznetwork 16:13:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:13:41 INFO - Running setup.py install for mozprocess 16:13:41 INFO - Running setup.py install for mozprofile 16:13:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:13:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:13:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:13:41 INFO - Running setup.py install for mozrunner 16:13:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:13:41 INFO - Running setup.py install for mozscreenshot 16:13:41 INFO - Running setup.py install for moztest 16:13:41 INFO - Running setup.py install for mozversion 16:13:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:13:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:13:42 INFO - Cleaning up... 16:13:42 INFO - Return code: 0 16:13:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:42 INFO - 'HOME': '/Users/cltbld', 16:13:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:42 INFO - 'LOGNAME': 'cltbld', 16:13:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:42 INFO - 'MOZ_NO_REMOTE': '1', 16:13:42 INFO - 'NO_EM_RESTART': '1', 16:13:42 INFO - 'PAGER': '/bin/cat', 16:13:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:42 INFO - 'PWD': '/builds/slave/test', 16:13:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:42 INFO - 'SHELL': '/bin/bash', 16:13:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:42 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:42 INFO - 'USER': 'cltbld', 16:13:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:42 INFO - 'XPC_FLAGS': '0x0', 16:13:42 INFO - 'XPC_SERVICE_NAME': '0', 16:13:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-k7bmOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-8nLjMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-iCwBhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-jiBXyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-LQWiqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:42 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:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-F_oiuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:42 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:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-PPqR0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-OlJEt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-PhmV0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-pT4qKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-4w6kxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-fVorOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-xkoJDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-L4NhDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:43 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:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-JtMtYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:43 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:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-VhOxD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-AXa3bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:13: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:13: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:13: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:13: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:13: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:13: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:13:46 INFO - Downloading blessings-1.6.tar.gz 16:13:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:13:46 INFO - Installing collected packages: blessings 16:13:46 INFO - Running setup.py install for blessings 16:13:46 INFO - Successfully installed blessings 16:13:46 INFO - Cleaning up... 16:13:47 INFO - Return code: 0 16:13:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:13:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:47 INFO - Reading from file tmpfile_stdout 16:13:47 INFO - Current package versions: 16:13:47 INFO - blessings == 1.6 16:13:47 INFO - blobuploader == 1.2.4 16:13:47 INFO - docopt == 0.6.1 16:13:47 INFO - functools32 == 3.2.3-2 16:13:47 INFO - jsonschema == 2.5.1 16:13:47 INFO - manifestparser == 1.1 16:13:47 INFO - mozInstall == 1.12 16:13:47 INFO - mozcrash == 1.0 16:13:47 INFO - mozdebug == 0.1 16:13:47 INFO - mozdevice == 0.49 16:13:47 INFO - mozfile == 1.2 16:13:47 INFO - mozhttpd == 0.7 16:13:47 INFO - mozinfo == 0.9 16:13:47 INFO - mozleak == 0.1 16:13:47 INFO - mozlog == 3.4 16:13:47 INFO - moznetwork == 0.27 16:13:47 INFO - mozprocess == 0.25 16:13:47 INFO - mozprofile == 0.28 16:13:47 INFO - mozrunner == 6.13 16:13:47 INFO - mozscreenshot == 0.1 16:13:47 INFO - mozsystemmonitor == 0.3 16:13:47 INFO - moztest == 0.8 16:13:47 INFO - mozversion == 1.4 16:13:47 INFO - psutil == 3.1.1 16:13:47 INFO - requests == 1.2.3 16:13:47 INFO - wsgiref == 0.1.2 16:13:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:47 INFO - 'HOME': '/Users/cltbld', 16:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:47 INFO - 'LOGNAME': 'cltbld', 16:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:47 INFO - 'MOZ_NO_REMOTE': '1', 16:13:47 INFO - 'NO_EM_RESTART': '1', 16:13:47 INFO - 'PAGER': '/bin/cat', 16:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:47 INFO - 'PWD': '/builds/slave/test', 16:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:47 INFO - 'SHELL': '/bin/bash', 16:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:47 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:47 INFO - 'USER': 'cltbld', 16:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:47 INFO - 'XPC_FLAGS': '0x0', 16:13:47 INFO - 'XPC_SERVICE_NAME': '0', 16:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:13:47 INFO - Cleaning up... 16:13:47 INFO - Return code: 0 16:13:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b165b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b168768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6947b860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, '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.LhdrfDVxF9/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.LJY4KygNWn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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:13:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:47 INFO - 'HOME': '/Users/cltbld', 16:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:47 INFO - 'LOGNAME': 'cltbld', 16:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:47 INFO - 'MOZ_NO_REMOTE': '1', 16:13:47 INFO - 'NO_EM_RESTART': '1', 16:13:47 INFO - 'PAGER': '/bin/cat', 16:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:47 INFO - 'PWD': '/builds/slave/test', 16:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:47 INFO - 'SHELL': '/bin/bash', 16:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:13:47 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:47 INFO - 'USER': 'cltbld', 16:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:47 INFO - 'XPC_FLAGS': '0x0', 16:13:47 INFO - 'XPC_SERVICE_NAME': '0', 16:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:13:47 INFO - Cleaning up... 16:13:47 INFO - Return code: 0 16:13:47 INFO - Running post-action listener: _resource_record_post_action 16:13:47 INFO - Running post-action listener: _start_resource_monitoring 16:13:47 INFO - Starting resource monitoring. 16:13:47 INFO - [mozharness: 2017-03-30 23:13:47.937600Z] Finished create-virtualenv step (success) 16:13:47 INFO - [mozharness: 2017-03-30 23:13:47.938581Z] Running install step. 16:13:47 INFO - Running pre-action listener: _resource_record_pre_action 16:13:47 INFO - Running main action method: install 16:13:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:48 INFO - Reading from file tmpfile_stdout 16:13:48 INFO - Detecting whether we're running mozinstall >=1.0... 16:13:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:13:48 INFO - Reading from file tmpfile_stdout 16:13:48 INFO - Output received: 16:13:48 INFO - Usage: mozinstall [options] installer 16:13:48 INFO - Options: 16:13:48 INFO - -h, --help show this help message and exit 16:13:48 INFO - -d DEST, --destination=DEST 16:13:48 INFO - Directory to install application into. [default: 16:13:48 INFO - "/builds/slave/test"] 16:13:48 INFO - --app=APP Application being installed. [default: firefox] 16:13:48 INFO - mkdir: /builds/slave/test/build/application 16:13:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:14:08 INFO - Reading from file tmpfile_stdout 16:14:08 INFO - Output received: 16:14:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:14:08 INFO - Running post-action listener: _resource_record_post_action 16:14:08 INFO - [mozharness: 2017-03-30 23:14:08.916910Z] Finished install step (success) 16:14:08 INFO - [mozharness: 2017-03-30 23:14:08.917042Z] Running run-tests step. 16:14:08 INFO - Running pre-action listener: _resource_record_pre_action 16:14:08 INFO - Running main action method: run_tests 16:14:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:14:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:14:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:14:08 INFO - 2017-03-30 16:14:08.931 screenresolution[1971:15270] starting screenresolution argv=screenresolution get 16:14:08 INFO - 2017-03-30 16:14:08.934 screenresolution[1971:15270] Display 0: 1600x1200x32@60 16:14:08 INFO - 2017-03-30 16:14:08.940 screenresolution[1972:15273] starting screenresolution argv=screenresolution list 16:14:08 INFO - Available Modes on Display 0 16:14:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:14:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:14:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:14:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:14:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:14:09 INFO - Intel Iris: 16:14:09 INFO - Chipset Model: Intel Iris 16:14:09 INFO - Type: GPU 16:14:09 INFO - Bus: Built-In 16:14:09 INFO - VRAM (Dynamic, Max): 1536 MB 16:14:09 INFO - Vendor: Intel (0x8086) 16:14:09 INFO - Device ID: 0x0a2e 16:14:09 INFO - Revision ID: 0x0009 16:14:09 INFO - Displays: 16:14:09 INFO - Display: 16:14:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:14:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:14:09 INFO - Display Serial Number: 1600x1200 60 16:14:09 INFO - Main Display: Yes 16:14:09 INFO - Mirror: Off 16:14:09 INFO - Online: Yes 16:14:09 INFO - Rotation: Supported 16:14:09 INFO - Return code: 0 16:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:14:09 INFO - Python 2.7.3 16:14:09 INFO - Return code: 0 16:14:09 INFO - grabbing minidump binary from tooltool 16:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:09 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 0x10af43d30>, '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 0x10ae6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af38be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:14:09 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:14:09 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:14:09 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:14:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:14:09 INFO - Return code: 0 16:14:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:14:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:14:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:14:09 INFO - ENV: RUST_BACKTRACE is now 1 16:14:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:14:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0305 --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:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhdrfDVxF9/Render', 16:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:09 INFO - 'HOME': '/Users/cltbld', 16:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:09 INFO - 'LOGNAME': 'cltbld', 16:14:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:14:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:09 INFO - 'MOZ_NO_REMOTE': '1', 16:14:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:14:09 INFO - 'NO_EM_RESTART': '1', 16:14:09 INFO - 'PAGER': '/bin/cat', 16:14:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:09 INFO - 'PWD': '/builds/slave/test', 16:14:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:09 INFO - 'RUST_BACKTRACE': '1', 16:14:09 INFO - 'SHELL': '/bin/bash', 16:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LJY4KygNWn/Listeners', 16:14:09 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:09 INFO - 'USER': 'cltbld', 16:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:09 INFO - 'XPC_FLAGS': '0x0', 16:14:09 INFO - 'XPC_SERVICE_NAME': '0', 16:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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:14:09 INFO - mozversion application_buildid: 20170330113306 16:14:09 INFO - mozversion application_changeset: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 16:14:09 INFO - mozversion application_display_name: Nightly 16:14:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:14:09 INFO - mozversion application_name: Firefox 16:14:09 INFO - mozversion application_remotingname: firefox 16:14:09 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 16:14:09 INFO - mozversion application_vendor: Mozilla 16:14:09 INFO - mozversion application_version: 55.0a1 16:14:09 INFO - mozversion platform_buildid: 20170330113306 16:14:09 INFO - mozversion platform_changeset: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 16:14:09 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 16:14:09 INFO - mozversion platform_version: 55.0a1 16:14:09 INFO - using testdate: 1490915649 16:14:09 INFO - actual date: 1490915649 16:14:09 INFO - starting webserver on 'localhost:49694' 16:14:09 INFO - SUITE-START | Running 2 tests 16:14:09 INFO - TEST-START | dromaeo_css 16:14:09 INFO - operating with platform_type : mac_ 16:14:09 INFO - Initialising browser for dromaeo_css test... 16:14:09 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpqv77zV/profile http://localhost:49694/getInfo.html) 16:14:12 INFO - PID 1982 | __metrics Screen width/height:1600/1200 16:14:12 INFO - PID 1982 | colorDepth:24 16:14:12 INFO - PID 1982 | Browser inner width/height: 1024/689 16:14:12 INFO - PID 1982 | __metrics 16:14:12 ERROR - PID 1982 | 1490915652109 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (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:96:5) 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:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924: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:14:12 INFO - PID 1982 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:14:12 INFO - PID 1982 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:14:12 INFO - TEST-INFO | 1982: exit 0 16:14:12 INFO - Browser initialized. 16:14:12 INFO - Running cycle 1/1 for dromaeo_css test... 16:14:12 INFO - TEST-INFO | started process 1984 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpqv77zV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:25 INFO - PID 1984 | RSS: Main: 253431808 16:14:25 INFO - PID 1984 | 16:17:30 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49694/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49694/tests/dromaeo/cssquery-ext.html) 16:17:32 INFO - PID 1984 | RSS: Main: 430833664 16:17:32 INFO - PID 1984 | 16:19:15 INFO - PID 1984 | ************************* 16:19:15 INFO - PID 1984 | A coding exception was thrown and uncaught in a Task. 16:19:15 INFO - PID 1984 | 16:19:15 ERROR - PID 1984 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:19:15 INFO - PID 1984 | Full stack: 16:19:15 INFO - PID 1984 | ************************* 16:19:15 INFO - PID 1984 | ************************* 16:19:15 INFO - PID 1984 | A coding exception was thrown and uncaught in a Task. 16:19:15 INFO - PID 1984 | 16:19:15 ERROR - PID 1984 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:19:15 INFO - PID 1984 | Full stack: 16:19:15 INFO - PID 1984 | ************************* 16:19:15 INFO - PID 1984 | ************************* 16:19:15 INFO - PID 1984 | A coding exception was thrown and uncaught in a Task. 16:19:15 INFO - PID 1984 | 16:19:15 ERROR - PID 1984 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:19:15 INFO - PID 1984 | Full stack: 16:19:15 INFO - PID 1984 | ************************* 16:19:15 INFO - PID 1984 | ************************* 16:19:15 INFO - PID 1984 | A coding exception was thrown and uncaught in a Task. 16:19:15 INFO - PID 1984 | 16:19:15 ERROR - PID 1984 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:19:15 INFO - PID 1984 | Full stack: 16:19:15 INFO - PID 1984 | ************************* 16:20:35 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49694/tests/dromaeo/cssquery-ext.html (next: http://localhost:49694/tests/dromaeo/cssquery-jquery.html) 16:20:35 INFO - PID 1984 | RSS: Main: 383389696 16:20:35 INFO - PID 1984 | 16:23:38 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49694/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49694/tests/dromaeo/cssquery-mootools.html) 16:23:39 INFO - PID 1984 | RSS: Main: 358477824 16:23:39 INFO - PID 1984 | 16:26:41 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49694/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49694/tests/dromaeo/cssquery-prototype.html) 16:26:42 INFO - PID 1984 | RSS: Main: 366374912 16:26:42 INFO - PID 1984 | 16:29:45 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49694/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49694/tests/dromaeo/cssquery-yui.html) 16:29:46 INFO - PID 1984 | RSS: Main: 349597696 16:29:46 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49694/tests/dromaeo/cssquery-yui.html (next: http://localhost:49694/tests/dromaeo/cssquery-dojo.html) 16:32:50 INFO - PID 1984 | RSS: Main: 489488384 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | __start_tp_report 16:32:50 INFO - PID 1984 | _x_x_mozilla_page_load 16:32:50 INFO - PID 1984 | _x_x_mozilla_page_load_details 16:32:50 INFO - PID 1984 | |i|pagename|runs| 16:32:50 INFO - PID 1984 | |0;dojo.html;3443.5695538057744;3457.688808007279;3472.1493234157633;3482.37317282889;3518.4898189762484;6052.001419765734;6116.113163591277;6125.724342404591;6173.320934697185;6181.629102253863;6117.644716737929;6127.417895299951;6173.043178307369;6178.413050760182;6181.320054793969;5479.75341109651;5521.475459831309;5532.419095994923;5535.308086489189;5555.166724991263;5572.192032155355;5635.830925072234;5648.887022259552;5657.23627310312;5663.971680141593;5393.7572809223675;5462.289902312714;5599.524040456566;5604.523615492688;5668.886622267552;5510.9448905510835;5518;5603.831885043455;5610.523105536035;5614.803481878114;5491.725413729338;5531.695756733378;5540;5572.665640061609;5593.937151941118;6183.103449999725;6204.286507051656;6211.596246243979;6213.906791398133;6241.157443745082;6277.2153480814895;6370.490360771128;6547.116139321179;6549.1158693576235;6564.573302735307;6430.356964303579;6540.542162048659;6579.144711187563;6615.834604134887;6625;6097.146399504073;6255.812325630239;6269.185005949243;6288.119663247149;6304.3695630437505;6760.121184246016;6826.044353790473;6826.4538836892925;6834.624095674686;6835.692393842289;5584.274044374205;6254.781082662085;6360.809175724736;6408.455281301049;6441.227052753699;6596.241432235259;6612.636305003175;6634.502412319095;6640.0703901453835;6648.102506161703;6425.903611445832;6445.1943507061615;6497.285298617148;6523;6527.640979746159;6270.592411493239;6329;6331.746730130835;6500.707468163944;6521.02184672303;5283.920741188886;5286.286351342519;5339.252504649352;5341.519263266325;5354.571634269249;4641.907161856811;4657;4660.743659098782;4672.74299913508;4705.788239529229;6125.84685382869;6207.596506227081;6285.402886725754;6286.968565157236;6310.242770867433;6404.519661025442;6427.517936154713;6521.217453905561;6540.738370465223;6619.404253617197;6247.781327653511;6253.78111766086;6255.468285195719;6278.466330361879;6287.434130928238;5816.098504731774;5817.476427121579;5822.534197264209;5828.154917536934;5859.121131830174;6584.670766461753;6676.632785196666;6699.933000669931;6723.327667233238;6761.290064543349;6578.407943285128;6614.272430032789;6628.204615446178;6638.468922486093;6655.401013908771;6524.412802847766;6569.408753212234;6614.173228346457;6826.8634627308165;6859.725610975505;6339.461145802658;6417.101605775289;6461.224653041477;6489.351064893662;6504.252011018604;6592.307807680125;6610.537262391586;6614.305497922649;6617.834554135992;6625.370589793962;6231.657258850807;6304.842378940563;6336.651484168415;6343.0485427186295;6397.0724244983285;6309.968450157719;6322.336154703874;6339.556231063966;6353.237611486651;6380.138681277968;6208.720607572761;6244.344343843831;6264.968675156595;6274.843128921813;6309.463695585743;6314.463270622048;6349.523785715997;6383.808485745435;6391.58454700443;6399.7440102395385;5160.535551800356;6293.748250070129;6301.936980630135;6306.369363063657;6315.305316415099 16:32:50 INFO - PID 1984 | |1;ext.html;739.3235189801333;796.3748457460895;816.6161903905166;1129.1249281806604;1148.6783700563844;27827.30431739205;37156.88529344117;37881.810590947076;37898.8105059475;37984.81007594958;39507.802460987725;39809.805705828796;39819.00452488682;39865.60134398655;39903.800480997554;8431.325493960483;8588.227059564639;8598.699045533407;8602.741917742478;8609.61256743448;6090.604110732788;6267.780627678032;8396.538190399526;8572.399932004731;8596.01145868227;8476.321894248445;8562;8573.785655358635;8575;8577;8471.110533393998;8545.401821872472;8550.78623034429;8554.101819309008;8564.143585641386;8363.623636936352;8439.789005274917;8441.915580844236;8446.408751387406;8453.788655283666;37142.62857371419;37361.81319093415;37386;37455.25089498222;37471.81264093689;2919.343147791751;2977.642682878047;2990.5258548624543;3001.9849900750573;3022.2746540830262;26274.82128673558;26476.73523264762;26593.468130637473;26704.06535771258;27450;2754.807163498556;2788.261110805638;2793.748562629353;2833.7307955744172;2877.453283876078;301199;302339;302404;302809;305445;46767.532324677;47315.52684473111;47407.52592474031;47478;47481.05037899292;49455.50544494509;49502.75248623734;49506;49581;49597;14279.00046996721;14635.92682036583;14648.487302944346;14652.780208296885;14657.120572765669;10124.949375253076;11902;11905.583304584297;11926;11971;11399.088072954144;11479.19645624815;13167.934160329329;13342.266175360448;13394.531191408254;5866.2960444746905;5944.454441845065;5977.847974729094;5988.221531201003;5993.400659934059;949.4618717677407;961.0917682789697;975.512243878061;984.4831463481729;1002.7242508310127;24226.152084676953;24243.51512969766;24306;24368.512629747307;24406;926.754410081329;969.520087556664;1067.3862529045675;1102.2009043443438;1121.360824330124;9612;12097;12289.56986505468;12298.877011229773;12322.630321090382;440656;443107;443688;443760;444058;54452.91094178174;54865.72567137139;54934.45065549293;54991.45008549943;55080.62298442571;1454.5636309107438;1488.970220595582;1497.9550613481613;1514.0234548716016;1522.1095659039365;23157;23820.761792381854;26016.60975085413;28811.42377152487;28845.278868027628;52884.206736899745;53519.46480535145;53577.464225357246;53644.658883528224;53695.731521342146;462478.375216257;466295;466762;467154;468320;33548.82579109817;34837.8258108718;34875.82562087275;34881.47677784886;34882.47676284807;40883.182336353704;41283.9623717511;41888.790556047024;41964.58035419607;42381.57618423776;28079.43841123125;28919.855400722863;29550.852245738635;29819.701802981694;29992.25019374533;22584.774152258266;27018.99617890085;29963.55054674138;30079.398412032075;30214.848925755232;34859.605615775086;35458.29083418369;35511.11222219465;35544.46683299701;35592.82203588966;29106.981255656796;29689.554656679735;30091.94678186253;30274.24314392158;30609.081727548208;22430;23587.29238122859;24155.396115097265;24324;24393.878030609732 16:32:50 INFO - PID 1984 | |2;jquery.html;385.76468354303887;407.26895223074575;410.90549173690056;424.46941323345806;435.83438293448484;13727;15681.843181568182;22560.66159007616;22609.321720348376;22781.088756449743;22150;22900.083996640155;23244.41888952777;23262.76737232627;23865.164719234832;6170.537209709268;6458.838529036765;6530.542861999662;6539.280679125292;6543.312952140028;5936.7328470218945;5973.864965656533;6173.950428427156;6368.9681551592175;6402.263739669951;3772.4907137536356;3945.7632542047427;3950.841966321358;3951.130751234724;3955.3671412573995;3929.9607003930023;6161.29145148307;6350.523710721668;6422.229332480086;6455.419012288869;5646.463585959327;5716.657000579937;5760.942390576124;5771.971140144273;5780.248567686174;27747.8900843966;28331;28488;28508.7171077302;28599.28501787451;1946.9221231150736;1968.3602829080605;1971.7929617390255;1991.741073660415;2062.113291284747;13638.318084095756;15898.284577194054;16732.498025059267;16786.664266714597;17504.912475437668;1604.855562999324;1645.9670806583922;1646.9094199819006;1704.8550873175795;1709.3162734906014;150978;157539.39684825187;157998;160958.99999999884;162280;12172.634820955209;12448.128630995925;12456.314902680258;12667.683307917376;13060.934695326652;16534.255958481655;16549.089800061112;16556.75164872528;16563.751543726852;16564.83435165633;7687.540606726995;8732.519711415933;8995.370324077383;9037.638494460149;9239.39943903644;8723.869141962876;8738.820259264918;8749.956250218835;8906.287497000354;8918.751374807533;7665.233476652402;7832.177621349791;7841.7255396062;7855.293023627901;7869.331106855867;2095.711137650334;2096.7000459715073;2098.9685154722692;2099.664053751423;2253.42260417705;905.8143080668578;908.5094049213483;924.1590152960773;932.4079209701888;967.5017366056486;19899.900500497406;24599.754002459747;25999.960001599724;26486.735132648813;26640.20079397621;771.8301973565807;808.0868618461101;841.5623875584662;889.644142343068;925.3013974449248;10715.089217416451;11009.2843965142;11021.44892755359;11073.889261107446;11101.38942358178;585641;695126.0487395163;707285;724936.3753181306;725605;145669;151381.24309378382;156397.43602563828;161471;164571.53142702868;1175.2360965372404;1179.6166245970094;1207;1212.89690376319;1213.9150259481928;18443;18631.88208707482;18641.067946602885;18710.719339210194;18868.05659717036;65257;65736.68526629345;66038.67922641424;66481;68848.31151688419;75166;90620.54689726509;91603;93436;94239.52880235555;8515.531645759544;8611.48331100136;8639.09289524616;9021.782059421894;9151.542422878696;13476;14147.151170929777;14158.858411415755;14441.78337324962;14647.974641774974;11600.477978491157;11894.762104757683;12200.511979521074;12224.960878325086;12251.87748122543;9402.38884472507;10358.637447689294;11118.49966751482;11311.264767790068;11668.88331116678;11923.523059077539;12044;12148.088893332859;12332.876671233173;12336.75326493483;7500.662470188746;8739.694110706349;8900.065493123388;8910.509921954355;8996.05541418168;11624.186306958432;11730.70673233176;12105.69735756578;12112.84927931845;12125.454354553889 16:32:50 INFO - PID 1984 | |3;mootools.html;1227.631710486854;1393.7282229965153;1405.7399381114487;1411.760000799864;1418.3333833098443;10507.894921050787;11012.779744405107;11022.559097636085;11037.503312350944;11047.17146214034;10624.78750424991;11029.172812039089;11030.889691103086;11045.889541104587;11068.833967490475;2434.3062227265277;2468.6543883856275;2476.764707352808;2481.9875900620473;2482.019602257107;1498.4081287891277;2140.77521860204;2394.6767186429784;2425.684660994077;2471.456279618481;2123.3417640531407;2360.8229382796358;2450.791682706972;2451.6322551617222;2452.1907770435714;2354.3054798834387;2414.239514552919;2424.175780234729;2425.0663494554547;2425.478522117753;2426.332758491429;2430.1858877276136;2435.659007738918;2435.902563897442;2440.2435245074084;10837.403942783143;10953.014228719454;10966.725831854188;10968.122550195965;10975.780484390347;6116.296625887991;6168.0439531872635;6352.745890164388;6356.4279214870885;6433.806985790434;3797.6012518685416;4037.858674946363;4043.9595604043875;4124.752514849119;4135.6898232632675;1123.9606613768478;1153.8231004375573;1205.2346759807497;1209.6189700244395;1229.4590380232669;15113.697726045528;15324.463643772526;15329.846701532953;15331;15353.462628808049;4834.395700537433;5205.375354957354;5316.122839731478;5328.8401347959625;5344.171653393731;11304.773904521864;11325.093992480583;11348.546058157746;11375.374354410587;11379.829302560469;6233.688315584202;6249.031400132987;6253.874922501615;6264.060390941395;6281.780137695158;5579.525740312038;5658.858528536737;5746.0519014363;5798.478136967608;5832.854178645568;3517.2437925846066;4023.1350259694304;4143.177002119058;4174.332106862948;4220.198162349144;1105.0772604874858;1223.2843786385065;1224.2899118511243;1225.2723659639864;1253;652.4421619515341;723.9322000049884;742.9702811887572;744.2148533297315;786.6312616048035;5870.530357571384;6034.24571928509;6088.299845517822;6162.414570615784;6185.8144255672405;858.9012263589767;864.9697260595975;874.0865795243985;921.801812610292;926.3654396738256;3330.8501117449773;3408.812515311632;3496.0036389628835;3788.1287303920253;3791.0711875590105;10849.837252441219;14153.29233538319;14159.78760318596;14189;14268.42926282937;11355.943220283845;13598;13751.243681597609;14066;14113.364898579382;10098.343607665478;10186.694399168036;10336.5348559315;10347.896521034843;10414.479276036167;6373.3945275198785;6418.743250269937;6487.18910136233;6497.772577959749;6567.199952064257;12547.74904501923;12656.43046062911;12697.047721421115;12864.397968568815;12880.549180778627;12766.744665106831;12847.807282890952;12952.481900723866;13023.413946372246;13045;8087.110417854149;8208.83582328362;8242.505449673039;8335.374846886387;8337.499750015018;9883.752906177404;10152.898471015194;10159.949200254247;10169.03394177552;10177.643782467874;8351.665933362841;8576.656933722581;8674.956625217086;8681.305495560213;8712.477251364939;8487.575621218792;8490.108538603357;8492.235698786892;8567.74296771098;8643.481391116553;7657.617119144132;7856.84286314282;7866;7879.566623835744;7888;4950.257461380677;5710.457506536873;5961.791337303173;5984.850378740566;6039.969800150971;5828.883422331614;5841.561882858718;5968.940310597012;6050.2437195350585;6232.314445410911 16:32:50 INFO - PID 1984 | |4;prototype.html;377.8828563145425;389.4780993468751;402.5591976785421;403.2821577591884;404.54084613963164;5450.482204190601;5864.824055278338;5933.85165370866;5946.61347012445;6151.292601350846;5165.38015438148;5341.4658534146565;5894.705264736767;5974.432428919256;6062.181605483259;3661.6521430464063;3945.855701846461;4769.618430525558;4786.56920877121;4789.832355867546;4348.934765978513;4576.382188404573;4593.862184134465;4773.737444440554;4780.545848144438;4485.215087359699;4513.390692256553;4517.661175411841;4550.408446901898;4550.748544150352;4276.25165596021;4525.434320709911;4558.703684260546;4566.520515345874;4576;3741.9625803741887;3899.3760998240145;3951.3677811550015;3953.525576930758;3954.940675889864;4668.109415686652;4864.878378040542;5391.919121213185;5866.413358664099;5913.970430147821;4219.662427005863;4602.309653551961;5254.264402983586;5268.683878967236;5283.920741188886;5199;6116.296625888035;6394;6424.325445828167;6437.83905402364;3616;3960.8117564730464;4290.141971605692;4295.162443323553;4313.956860431387;5196.181336532221;5651.745671444794;6164.7670465906995;6800.897986530206;6825.453963682893;4127.690423218271;4231.6614670826875;4322.956770432318;4335.869923902288;4374.8906277342685;4207.684423668237;4430.224710675619;4438.112377524508;4453.688241823106;4460.531644177379;5210.583153347723;5234.26720259164;5236.455245702527;5237.764300606482;5240.816571419982;5633.8168984512895;6230.345813689588;6335.271443783931;6426.907425737636;6473.482121430275;1235.6849003504049;1258.1884684378708;1459.5914942080942;1513.8259100203618;1528.9006214596016;845.8942632170979;975.1592047056508;993.2848349188573;998.8052329795765;1008.9445080520642;5110.3356563646485;5199.792008319625;5313.521783039545;5479.589030822624;6154.15375387382;5234.618248696834;5743.770249190069;6524.706388212542;6568.916128838688;6575.934240657628;5790.768369265266;6029.306629737636;6074.42292982166;6107.908381374283;6139.324674285825;3109.502479603298;3208.5668434761474;3480.9477857832153;3481.2515309208643;3488.581370235537;5726;6541.2150541934325;6630.303818099127;6859.038076352506;6952.304769523007;5686.346070201897;6874.793756187322;6888.690008949609;6909.619970901548;6919.619420931796;5984.371640977809;6624.536282460182;6759.155105611799;6942.583444993215;6951.409130223887;5389.353277606712;5592.692401917933;6536.738530458729;6674.43267322283;6679.962611403568;5483.616146869681;6588.209414870054;6596.736130554916;6612.537122401578;6627.834304142435;6029.125776762433;6704.765333213314;6739.8879184935895;6918.9210801194995;6933.861322773617;3645;4399.076193999296;4409.977950110229;4417.712848664826;4417.8734422722;6351.3331100235655;6367.108604795239;6402.519811014099;6566.770163044271;6701.530892837454;5895.823125306248;6054.545909056927;6217.253929528486;6472.514561407819;6480.384363485461;5777.7977770777825;6308.621482711052;6435.970244760816;6445.9033114504255;6481;6005.978983572891;6281.842953926189;6324.588901721373;6336.493080553452;6380.617162970237;5124.538791508782;5340.5994550408095;5423.4034256231;6458.870822583616;6471.967640161769;5583.972080139573;5885.970570147122;6294.874102517832;6311.558190926591;6315.936840631535 16:32:50 INFO - PID 1984 | |5;yui.html;701.9754308599197;703.4618516834619;716.0720033674202;718.2637796258838;719.2771264878797;12444.377781110943;16266.51200463985;16297.022178669273;16297.75553366699;16304.510864674048;15008.174550399724;15105.54683359501;15113;15114.546563603082;16746.58133546662;943.1087622197026;949.8955114937352;967.3953778888194;969.6848524229619;972.0085512776964;988.2785566536432;990.4898977026837;995.5072239241586;996.0537489385077;996.6412091647002;1004.7940172264705;1039.4958445154132;1044.770150566874;1046.074224311486;1048.543883410715;1033.4780935627534;1034.043509753475;1034.849946757716;1036.6838114375112;1038.1953985661098;761.2235519769804;763.0652450747845;763.6525380951654;765.1239330966013;765.7549584133023;13729.519466818614;16014;16363.181840907908;16513.83486165135;17061.82938170615;12061.577844775391;13653.317334133353;14567.49013784523;14714.953387818436;15259.465918692793;11627.186096973215;11649.650510484698;11701.239419437708;11866.169368144234;12283.508659653604;2980.7615390768688;2982.2842517795575;3104.6429660589097;3128.6818942386644;3146.143775172659;112447;115448;117001;117847.2322915157;120291;10019.277445388912;10121.848172277421;10122.949385253027;10255.179585633132;10726.249162558703;20526.92731660325;21109.62787418813;23258.3022509325;23291;23316.067357305856;7804.297613214838;7882.842343153105;7912.841743165104;7913.881291780628;7956.920430795618;3530.646935306449;3596.8741094061584;4213.557576454428;4257.978710106491;4259.148170365939;4018.8794336169963;4149.25313443577;4233.11104668017;4234.745915245096;4236.004538933348;775.4494309040532;778.8052986753312;778.9406407286086;806.2340776262574;811.853866304071;513.7123211001851;525.7239949026791;566.1225101093289;573.0315766354847;576.5589324167015;7891.255622170269;9273.224289490783;9747.220222382193;10211.994962693168;10414.271001029856;518.6032684995981;540.2571464236675;567.8637127089469;577.1804038265673;583.983724065207;4549.408576884984;4606.262997920384;4635.513271106485;4661.673682842236;4678.111158879802;69833;104082;105327.47336263269;105579;107044;93417;93615;95472.52263738775;95991;96395.51802240944;79763;82237.58881205675;82259.5887020561;83414.58292708498;83522;7937.920620793718;8161.306288965505;9414.764630884283;9484.33609647318;9544.761380965532;38827.4469021236;39133.41299880561;39203.80398098105;39513.802430987664;39858.800705996284;40685.186296273314;49198;49692.50922472332;50542;50872.49127508677;11612.361320127475;12216.938915305367;13349;13376.398062087035;13863.237521935987;14964.75528670708;15004.399824007354;15116.470923517623;15149.545513634608;15282.312295946931;11196.944015280198;14074.577762667137;14180.29098545048;14209.50266740659;14398.42406303736;13657.106093930104;13788.241646709126;13931.930340348234;14110.435582576993;14256.714865702836;13500.45998160102;13619.38712757948;13632.591022269347;14106.365213565208;14501.709965800836;10002.749931251778;10145.34055286404;10346.948265258927;10352.016558426938;10527.578896844041;9038.638454461749;10499.68500944973;10731.449801536943;11345.262557933707;11773.17587768848 16:32:50 INFO - PID 1984 | __end_tp_report 16:32:50 INFO - PID 1984 | __start_cc_report 16:32:50 INFO - PID 1984 | _x_x_mozilla_cycle_collect,2006 16:32:50 INFO - PID 1984 | __end_cc_report 16:32:50 INFO - PID 1984 | __startTimestamp1490916770523__endTimestamp 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | ------- Summary: start ------- 16:32:50 INFO - PID 1984 | Number of tests: 6 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | [#0] dojo.html Cycles:165 Average:6085.63 Median:6288.12 stddev:667.89 (10.6%) stddev-sans-first:636.97 16:32:50 INFO - PID 1984 | Values: 3443.6 3457.7 3472.1 3482.4 3518.5 6052.0 6116.1 6125.7 6173.3 6181.6 6117.6 6127.4 6173.0 6178.4 6181.3 5479.8 5521.5 5532.4 5535.3 5555.2 5572.2 5635.8 5648.9 5657.2 5664.0 5393.8 5462.3 5599.5 5604.5 5668.9 5510.9 5518.0 5603.8 5610.5 5614.8 5491.7 5531.7 5540.0 5572.7 5593.9 6183.1 6204.3 6211.6 6213.9 6241.2 6277.2 6370.5 6547.1 6549.1 6564.6 6430.4 6540.5 6579.1 6615.8 6625.0 6097.1 6255.8 6269.2 6288.1 6304.4 6760.1 6826.0 6826.5 6834.6 6835.7 5584.3 6254.8 6360.8 6408.5 6441.2 6596.2 6612.6 6634.5 6640.1 6648.1 6425.9 6445.2 6497.3 6523.0 6527.6 6270.6 6329.0 6331.7 6500.7 6521.0 5283.9 5286.3 5339.3 5341.5 5354.6 4641.9 4657.0 4660.7 4672.7 4705.8 6125.8 6207.6 6285.4 6287.0 6310.2 6404.5 6427.5 6521.2 6540.7 6619.4 6247.8 6253.8 6255.5 6278.5 6287.4 5816.1 5817.5 5822.5 5828.2 5859.1 6584.7 6676.6 6699.9 6723.3 6761.3 6578.4 6614.3 6628.2 6638.5 6655.4 6524.4 6569.4 6614.2 6826.9 6859.7 6339.5 6417.1 6461.2 6489.4 6504.3 6592.3 6610.5 6614.3 6617.8 6625.4 6231.7 6304.8 6336.7 6343.0 6397.1 6310.0 6322.3 6339.6 6353.2 6380.1 6208.7 6244.3 6265.0 6274.8 6309.5 6314.5 6349.5 6383.8 6391.6 6399.7 5160.5 6293.7 6301.9 6306.4 6315.3 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | [#1] ext.html Cycles:180 Average:53839.00 Median:36367.73 stddev:109107.95 (300.0%) stddev-sans-first:109341.19 16:32:50 INFO - PID 1984 | Values: 739.3 796.4 816.6 1129.1 1148.7 27827.3 37156.9 37881.8 37898.8 37984.8 39507.8 39809.8 39819.0 39865.6 39903.8 8431.3 8588.2 8598.7 8602.7 8609.6 6090.6 6267.8 8396.5 8572.4 8596.0 8476.3 8562.0 8573.8 8575.0 8577.0 8471.1 8545.4 8550.8 8554.1 8564.1 8363.6 8439.8 8441.9 8446.4 8453.8 37142.6 37361.8 37386.0 37455.3 37471.8 2919.3 2977.6 2990.5 3002.0 3022.3 26274.8 26476.7 26593.5 26704.1 27450.0 2754.8 2788.3 2793.7 2833.7 2877.5 301199.0 302339.0 302404.0 302809.0 305445.0 46767.5 47315.5 47407.5 47478.0 47481.1 49455.5 49502.8 49506.0 49581.0 49597.0 14279.0 14635.9 14648.5 14652.8 14657.1 10124.9 11902.0 11905.6 11926.0 11971.0 11399.1 11479.2 13167.9 13342.3 13394.5 5866.3 5944.5 5977.8 5988.2 5993.4 949.5 961.1 975.5 984.5 1002.7 24226.2 24243.5 24306.0 24368.5 24406.0 926.8 969.5 1067.4 1102.2 1121.4 9612.0 12097.0 12289.6 12298.9 12322.6 440656.0 443107.0 443688.0 443760.0 444058.0 54452.9 54865.7 54934.5 54991.5 55080.6 1454.6 1489.0 1498.0 1514.0 1522.1 23157.0 23820.8 26016.6 28811.4 28845.3 52884.2 53519.5 53577.5 53644.7 53695.7 462478.4 466295.0 466762.0 467154.0 468320.0 33548.8 34837.8 34875.8 34881.5 34882.5 40883.2 41284.0 41888.8 41964.6 42381.6 28079.4 28919.9 29550.9 29819.7 29992.3 22584.8 27019.0 29963.6 30079.4 30214.8 34859.6 35458.3 35511.1 35544.5 35592.8 29107.0 29689.6 30091.9 30274.2 30609.1 22430.0 23587.3 24155.4 24324.0 24393.9 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | [#2] jquery.html Cycles:180 Average:40796.46 Median:25299.86 stddev:116068.25 (458.8%) stddev-sans-first:116354.19 16:32:50 INFO - PID 1984 | Values: 385.8 407.3 410.9 424.5 435.8 13727.0 15681.8 22560.7 22609.3 22781.1 22150.0 22900.1 23244.4 23262.8 23865.2 6170.5 6458.8 6530.5 6539.3 6543.3 5936.7 5973.9 6174.0 6369.0 6402.3 3772.5 3945.8 3950.8 3951.1 3955.4 3930.0 6161.3 6350.5 6422.2 6455.4 5646.5 5716.7 5760.9 5772.0 5780.2 27747.9 28331.0 28488.0 28508.7 28599.3 1946.9 1968.4 1971.8 1991.7 2062.1 13638.3 15898.3 16732.5 16786.7 17504.9 1604.9 1646.0 1646.9 1704.9 1709.3 150978.0 157539.4 157998.0 160959.0 162280.0 12172.6 12448.1 12456.3 12667.7 13060.9 16534.3 16549.1 16556.8 16563.8 16564.8 7687.5 8732.5 8995.4 9037.6 9239.4 8723.9 8738.8 8750.0 8906.3 8918.8 7665.2 7832.2 7841.7 7855.3 7869.3 2095.7 2096.7 2099.0 2099.7 2253.4 905.8 908.5 924.2 932.4 967.5 19899.9 24599.8 26000.0 26486.7 26640.2 771.8 808.1 841.6 889.6 925.3 10715.1 11009.3 11021.4 11073.9 11101.4 585641.0 695126.0 707285.0 724936.4 725605.0 145669.0 151381.2 156397.4 161471.0 164571.5 1175.2 1179.6 1207.0 1212.9 1213.9 18443.0 18631.9 18641.1 18710.7 18868.1 65257.0 65736.7 66038.7 66481.0 68848.3 75166.0 90620.5 91603.0 93436.0 94239.5 8515.5 8611.5 8639.1 9021.8 9151.5 13476.0 14147.2 14158.9 14441.8 14648.0 11600.5 11894.8 12200.5 12225.0 12251.9 9402.4 10358.6 11118.5 11311.3 11668.9 11923.5 12044.0 12148.1 12332.9 12336.8 7500.7 8739.7 8900.1 8910.5 8996.1 11624.2 11730.7 12105.7 12112.8 12125.5 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | [#3] mootools.html Cycles:180 Average:6727.44 Median:2474.11 stddev:4214.72 (170.4%) stddev-sans-first:4206.28 16:32:50 INFO - PID 1984 | Values: 1227.6 1393.7 1405.7 1411.8 1418.3 10507.9 11012.8 11022.6 11037.5 11047.2 10624.8 11029.2 11030.9 11045.9 11068.8 2434.3 2468.7 2476.8 2482.0 2482.0 1498.4 2140.8 2394.7 2425.7 2471.5 2123.3 2360.8 2450.8 2451.6 2452.2 2354.3 2414.2 2424.2 2425.1 2425.5 2426.3 2430.2 2435.7 2435.9 2440.2 10837.4 10953.0 10966.7 10968.1 10975.8 6116.3 6168.0 6352.7 6356.4 6433.8 3797.6 4037.9 4044.0 4124.8 4135.7 1124.0 1153.8 1205.2 1209.6 1229.5 15113.7 15324.5 15329.8 15331.0 15353.5 4834.4 5205.4 5316.1 5328.8 5344.2 11304.8 11325.1 11348.5 11375.4 11379.8 6233.7 6249.0 6253.9 6264.1 6281.8 5579.5 5658.9 5746.1 5798.5 5832.9 3517.2 4023.1 4143.2 4174.3 4220.2 1105.1 1223.3 1224.3 1225.3 1253.0 652.4 723.9 743.0 744.2 786.6 5870.5 6034.2 6088.3 6162.4 6185.8 858.9 865.0 874.1 921.8 926.4 3330.9 3408.8 3496.0 3788.1 3791.1 10849.8 14153.3 14159.8 14189.0 14268.4 11355.9 13598.0 13751.2 14066.0 14113.4 10098.3 10186.7 10336.5 10347.9 10414.5 6373.4 6418.7 6487.2 6497.8 6567.2 12547.7 12656.4 12697.0 12864.4 12880.5 12766.7 12847.8 12952.5 13023.4 13045.0 8087.1 8208.8 8242.5 8335.4 8337.5 9883.8 10152.9 10159.9 10169.0 10177.6 8351.7 8576.7 8675.0 8681.3 8712.5 8487.6 8490.1 8492.2 8567.7 8643.5 7657.6 7856.8 7866.0 7879.6 7888.0 4950.3 5710.5 5961.8 5984.9 6040.0 5828.9 5841.6 5968.9 6050.2 6232.3 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | [#4] prototype.html Cycles:180 Average:5121.43 Median:5613.25 stddev:1600.70 (28.5%) stddev-sans-first:1565.10 16:32:50 INFO - PID 1984 | Values: 377.9 389.5 402.6 403.3 404.5 5450.5 5864.8 5933.9 5946.6 6151.3 5165.4 5341.5 5894.7 5974.4 6062.2 3661.7 3945.9 4769.6 4786.6 4789.8 4348.9 4576.4 4593.9 4773.7 4780.5 4485.2 4513.4 4517.7 4550.4 4550.7 4276.3 4525.4 4558.7 4566.5 4576.0 3742.0 3899.4 3951.4 3953.5 3954.9 4668.1 4864.9 5391.9 5866.4 5914.0 4219.7 4602.3 5254.3 5268.7 5283.9 5199.0 6116.3 6394.0 6424.3 6437.8 3616.0 3960.8 4290.1 4295.2 4314.0 5196.2 5651.7 6164.8 6800.9 6825.5 4127.7 4231.7 4323.0 4335.9 4374.9 4207.7 4430.2 4438.1 4453.7 4460.5 5210.6 5234.3 5236.5 5237.8 5240.8 5633.8 6230.3 6335.3 6426.9 6473.5 1235.7 1258.2 1459.6 1513.8 1528.9 845.9 975.2 993.3 998.8 1008.9 5110.3 5199.8 5313.5 5479.6 6154.2 5234.6 5743.8 6524.7 6568.9 6575.9 5790.8 6029.3 6074.4 6107.9 6139.3 3109.5 3208.6 3480.9 3481.3 3488.6 5726.0 6541.2 6630.3 6859.0 6952.3 5686.3 6874.8 6888.7 6909.6 6919.6 5984.4 6624.5 6759.2 6942.6 6951.4 5389.4 5592.7 6536.7 6674.4 6680.0 5483.6 6588.2 6596.7 6612.5 6627.8 6029.1 6704.8 6739.9 6918.9 6933.9 3645.0 4399.1 4410.0 4417.7 4417.9 6351.3 6367.1 6402.5 6566.8 6701.5 5895.8 6054.5 6217.3 6472.5 6480.4 5777.8 6308.6 6436.0 6445.9 6481.0 6006.0 6281.8 6324.6 6336.5 6380.6 5124.5 5340.6 5423.4 6458.9 6472.0 5584.0 5886.0 6294.9 6311.6 6315.9 16:32:50 INFO - PID 1984 | 16:32:50 INFO - PID 1984 | [#5] yui.html Cycles:180 Average:20136.93 Median:2981.52 stddev:29771.26 (998.5%) stddev-sans-first:29819.01 16:32:50 INFO - PID 1984 | Values: 702.0 703.5 716.1 718.3 719.3 12444.4 16266.5 16297.0 16297.8 16304.5 15008.2 15105.5 15113.0 15114.5 16746.6 943.1 949.9 967.4 969.7 972.0 988.3 990.5 995.5 996.1 996.6 1004.8 1039.5 1044.8 1046.1 1048.5 1033.5 1034.0 1034.8 1036.7 1038.2 761.2 763.1 763.7 765.1 765.8 13729.5 16014.0 16363.2 16513.8 17061.8 12061.6 13653.3 14567.5 14715.0 15259.5 11627.2 11649.7 11701.2 11866.2 12283.5 2980.8 2982.3 3104.6 3128.7 3146.1 112447.0 115448.0 117001.0 117847.2 120291.0 10019.3 10121.8 10122.9 10255.2 10726.2 20526.9 21109.6 23258.3 23291.0 23316.1 7804.3 7882.8 7912.8 7913.9 7956.9 3530.6 3596.9 4213.6 4258.0 4259.1 4018.9 4149.3 4233.1 4234.7 4236.0 775.4 778.8 778.9 806.2 811.9 513.7 525.7 566.1 573.0 576.6 7891.3 9273.2 9747.2 10212.0 10414.3 518.6 540.3 567.9 577.2 584.0 4549.4 4606.3 4635.5 4661.7 4678.1 69833.0 104082.0 105327.5 105579.0 107044.0 93417.0 93615.0 95472.5 95991.0 96395.5 79763.0 82237.6 82259.6 83414.6 83522.0 7937.9 8161.3 9414.8 9484.3 9544.8 38827.4 39133.4 39203.8 39513.8 39858.8 40685.2 49198.0 49692.5 50542.0 50872.5 11612.4 12216.9 13349.0 13376.4 13863.2 14964.8 15004.4 15116.5 15149.5 15282.3 11196.9 14074.6 14180.3 14209.5 14398.4 13657.1 13788.2 13931.9 14110.4 14256.7 13500.5 13619.4 13632.6 14106.4 14501.7 10002.7 10145.3 10346.9 10352.0 10527.6 9038.6 10499.7 10731.4 11345.3 11773.2 16:32:50 INFO - PID 1984 | -------- Summary: end -------- 16:32:50 INFO - PID 1984 | 16:32:51 INFO - TEST-INFO | 1984: exit 0 16:32:51 INFO - TEST-OK | dromaeo_css | took 1121659ms 16:32:51 INFO - TEST-START | kraken 16:32:51 INFO - operating with platform_type : mac_ 16:32:51 INFO - Initialising browser for kraken test... 16:32:51 INFO - TEST-INFO | started process 1989 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpdGdC43/profile http://localhost:49694/getInfo.html) 16:32:53 INFO - PID 1989 | __metrics Screen width/height:1600/1200 16:32:53 INFO - PID 1989 | colorDepth:24 16:32:53 INFO - PID 1989 | Browser inner width/height: 1024/689 16:32:53 INFO - PID 1989 | __metrics 16:32:53 ERROR - PID 1989 | 1490916773794 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (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:96:5) 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:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924: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:32:53 INFO - PID 1989 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:32:53 INFO - PID 1989 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:54 INFO - TEST-INFO | 1989: exit 0 16:32:54 INFO - Browser initialized. 16:32:54 INFO - Running cycle 1/1 for kraken test... 16:32:54 INFO - TEST-INFO | started process 1990 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpdGdC43/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:06 INFO - PID 1990 | RSS: Main: 250601472 16:33:06 INFO - PID 1990 | 16:33:09 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49694/tests/kraken/driver.html?testName=audio-beat-detection) 16:33:10 INFO - PID 1990 | RSS: Main: 300765184 16:33:10 INFO - PID 1990 | 16:33:13 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49694/tests/kraken/driver.html?testName=audio-dft) 16:33:14 INFO - PID 1990 | RSS: Main: 304152576 16:33:14 INFO - PID 1990 | 16:33:17 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49694/tests/kraken/driver.html?testName=audio-fft) 16:33:18 INFO - PID 1990 | RSS: Main: 394481664 16:33:18 INFO - PID 1990 | 16:33:20 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49694/tests/kraken/driver.html?testName=audio-oscillator) 16:33:21 INFO - PID 1990 | RSS: Main: 297791488 16:33:21 INFO - PID 1990 | 16:33:23 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49694/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:33:23 INFO - PID 1990 | RSS: Main: 299454464 16:33:23 INFO - PID 1990 | 16:33:27 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49694/tests/kraken/driver.html?testName=imaging-darkroom) 16:33:27 INFO - PID 1990 | RSS: Main: 340586496 16:33:27 INFO - PID 1990 | 16:33:31 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49694/tests/kraken/driver.html?testName=imaging-desaturate) 16:33:31 INFO - PID 1990 | RSS: Main: 351965184 16:33:31 INFO - PID 1990 | 16:33:35 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49694/tests/kraken/driver.html?testName=json-parse-financial) 16:33:35 INFO - PID 1990 | RSS: Main: 352899072 16:33:35 INFO - PID 1990 | 16:33:37 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49694/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:33:38 INFO - PID 1990 | RSS: Main: 311021568 16:33:38 INFO - PID 1990 | 16:33:40 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:33:41 INFO - PID 1990 | RSS: Main: 316784640 16:33:41 INFO - PID 1990 | 16:33:43 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:33:44 INFO - PID 1990 | RSS: Main: 319623168 16:33:44 INFO - PID 1990 | 16:33:47 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:33:48 INFO - PID 1990 | RSS: Main: 335208448 16:33:48 INFO - PID 1990 | 16:33:51 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:33:52 INFO - PID 1990 | RSS: Main: 320716800 16:33:52 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | Cycle 1(1): loaded http://localhost:49694/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49694/tests/kraken/driver.html?testName=ai-astar) 16:33:54 INFO - PID 1990 | RSS: Main: 386797568 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | __start_tp_report 16:33:54 INFO - PID 1990 | _x_x_mozilla_page_load 16:33:54 INFO - PID 1990 | _x_x_mozilla_page_load_details 16:33:54 INFO - PID 1990 | |i|pagename|runs| 16:33:54 INFO - PID 1990 | |0;ai-astar;111;126;121;121;125;126;123;123;126;125 16:33:54 INFO - PID 1990 | |1;audio-beat-detection;148;120;124;164;121;121;127;152;142;119 16:33:54 INFO - PID 1990 | |2;audio-dft;220;224;223;221;218;213;222;213;214;215 16:33:54 INFO - PID 1990 | |3;audio-fft;86;63;64;100;67;66;65;65;85;66 16:33:54 INFO - PID 1990 | |4;audio-oscillator;100;77;97;75;78;77;95;75;77;96 16:33:54 INFO - PID 1990 | |5;imaging-gaussian-blur;94;94;94;95;94;95;94;114;95;95 16:33:54 INFO - PID 1990 | |6;imaging-darkroom;114;158;137;103;103;103;104;127;102;102 16:33:54 INFO - PID 1990 | |7;imaging-desaturate;153;164;102;104;99;125;163;102;102;102 16:33:54 INFO - PID 1990 | |8;json-parse-financial;63;63;65;64;64;65;90;99;65;66 16:33:54 INFO - PID 1990 | |9;json-stringify-tinderbox;59;67;62;92;64;71;59;80;90;95 16:33:54 INFO - PID 1990 | |10;stanford-crypto-aes;92;90;93;91;93;89;90;91;89;92 16:33:54 INFO - PID 1990 | |11;stanford-crypto-ccm;155;181;189;155;152;160;172;175;196;173 16:33:54 INFO - PID 1990 | |12;stanford-crypto-pbkdf2;204;205;207;205;214;214;250;254;204;208 16:33:54 INFO - PID 1990 | |13;stanford-crypto-sha256-iterative;74;78;97;103;103;97;75;73;73;75 16:33:54 INFO - PID 1990 | __end_tp_report 16:33:54 INFO - PID 1990 | __start_cc_report 16:33:54 INFO - PID 1990 | _x_x_mozilla_cycle_collect,2211 16:33:54 INFO - PID 1990 | __end_cc_report 16:33:54 INFO - PID 1990 | __startTimestamp1490916834548__endTimestamp 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | ------- Summary: start ------- 16:33:54 INFO - PID 1990 | Number of tests: 14 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#0] ai-astar Cycles:10 Average:122.70 Median:125.00 stddev:4.55 (3.6%) stddev-sans-first:2.06 16:33:54 INFO - PID 1990 | Values: 111.0 126.0 121.0 121.0 125.0 126.0 123.0 123.0 126.0 125.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:134.50 stddev:16.30 (12.1%) stddev-sans-first:16.46 16:33:54 INFO - PID 1990 | Values: 148.0 120.0 124.0 164.0 121.0 121.0 127.0 152.0 142.0 119.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#2] audio-dft Cycles:10 Average:218.30 Median:220.50 stddev:4.27 (1.9%) stddev-sans-first:4.48 16:33:54 INFO - PID 1990 | Values: 220.0 224.0 223.0 221.0 218.0 213.0 222.0 213.0 214.0 215.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#3] audio-fft Cycles:10 Average:72.70 Median:66.00 stddev:12.84 (19.5%) stddev-sans-first:12.69 16:33:54 INFO - PID 1990 | Values: 86.0 63.0 64.0 100.0 67.0 66.0 65.0 65.0 85.0 66.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#4] audio-oscillator Cycles:10 Average:84.70 Median:77.50 stddev:10.70 (13.8%) stddev-sans-first:9.81 16:33:54 INFO - PID 1990 | Values: 100.0 77.0 97.0 75.0 78.0 77.0 95.0 75.0 77.0 96.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:94.50 stddev:6.20 (6.6%) stddev-sans-first:6.52 16:33:54 INFO - PID 1990 | Values: 94.0 94.0 94.0 95.0 94.0 95.0 94.0 114.0 95.0 95.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#6] imaging-darkroom Cycles:10 Average:115.30 Median:109.00 stddev:19.35 (17.7%) stddev-sans-first:20.51 16:33:54 INFO - PID 1990 | Values: 114.0 158.0 137.0 103.0 103.0 103.0 104.0 127.0 102.0 102.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#7] imaging-desaturate Cycles:10 Average:121.60 Median:139.00 stddev:27.62 (19.9%) stddev-sans-first:26.86 16:33:54 INFO - PID 1990 | Values: 153.0 164.0 102.0 104.0 99.0 125.0 163.0 102.0 102.0 102.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#8] json-parse-financial Cycles:10 Average:70.40 Median:65.00 stddev:12.91 (19.9%) stddev-sans-first:13.41 16:33:54 INFO - PID 1990 | Values: 63.0 63.0 65.0 64.0 64.0 65.0 90.0 99.0 65.0 66.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#9] json-stringify-tinderbox Cycles:10 Average:73.90 Median:75.50 stddev:14.18 (18.8%) stddev-sans-first:13.97 16:33:54 INFO - PID 1990 | Values: 59.0 67.0 62.0 92.0 64.0 71.0 59.0 80.0 90.0 95.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:91.50 stddev:1.49 (1.6%) stddev-sans-first:1.54 16:33:54 INFO - PID 1990 | Values: 92.0 90.0 93.0 91.0 93.0 89.0 90.0 91.0 89.0 92.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#11] stanford-crypto-ccm Cycles:10 Average:170.80 Median:174.00 stddev:15.14 (8.7%) stddev-sans-first:14.94 16:33:54 INFO - PID 1990 | Values: 155.0 181.0 189.0 155.0 152.0 160.0 172.0 175.0 196.0 173.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:216.50 Median:211.00 stddev:19.09 (9.0%) stddev-sans-first:19.71 16:33:54 INFO - PID 1990 | Values: 204.0 205.0 207.0 205.0 214.0 214.0 250.0 254.0 204.0 208.0 16:33:54 INFO - PID 1990 | 16:33:54 INFO - PID 1990 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.80 Median:75.00 stddev:13.31 (17.7%) stddev-sans-first:13.53 16:33:54 INFO - PID 1990 | Values: 74.0 78.0 97.0 103.0 103.0 97.0 75.0 73.0 73.0 75.0 16:33:54 INFO - PID 1990 | -------- Summary: end -------- 16:33:54 INFO - PID 1990 | 16:33:55 INFO - TEST-INFO | 1990: exit 0 16:33:55 INFO - TEST-OK | kraken | took 64279ms 16:33:55 INFO - SUITE-END | took 1185s 16:33:55 INFO - Completed test suite (00:19:46) 16:33:55 INFO - javascript benchmark 16:33:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7618.103097886996, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3443.5695538057744, 3457.688808007279, 3472.1493234157633, 3482.37317282889, 3518.4898189762484, 6052.001419765734, 6116.113163591277, 6125.724342404591, 6173.320934697185, 6181.629102253863, 6117.644716737929, 6127.417895299951, 6173.043178307369, 6178.413050760182, 6181.320054793969, 5479.75341109651, 5521.475459831309, 5532.419095994923, 5535.308086489189, 5555.166724991263, 5572.192032155355, 5635.830925072234, 5648.887022259552, 5657.23627310312, 5663.971680141593, 5393.7572809223675, 5462.289902312714, 5599.524040456566, 5604.523615492688, 5668.886622267552, 5510.9448905510835, 5518.0, 5603.831885043455, 5610.523105536035, 5614.803481878114, 5491.725413729338, 5531.695756733378, 5540.0, 5572.665640061609, 5593.937151941118, 6183.103449999725, 6204.286507051656, 6211.596246243979, 6213.906791398133, 6241.157443745082, 6277.2153480814895, 6370.490360771128, 6547.116139321179, 6549.1158693576235, 6564.573302735307, 6430.356964303579, 6540.542162048659, 6579.144711187563, 6615.834604134887, 6625.0, 6097.146399504073, 6255.812325630239, 6269.185005949243, 6288.119663247149, 6304.3695630437505, 6760.121184246016, 6826.044353790473, 6826.4538836892925, 6834.624095674686, 6835.692393842289, 5584.274044374205, 6254.781082662085, 6360.809175724736, 6408.455281301049, 6441.227052753699, 6596.241432235259, 6612.636305003175, 6634.502412319095, 6640.0703901453835, 6648.102506161703, 6425.903611445832, 6445.1943507061615, 6497.285298617148, 6523.0, 6527.640979746159, 6270.592411493239, 6329.0, 6331.746730130835, 6500.707468163944, 6521.02184672303, 5283.920741188886, 5286.286351342519, 5339.252504649352, 5341.519263266325, 5354.571634269249, 4641.907161856811, 4657.0, 4660.743659098782, 4672.74299913508, 4705.788239529229, 6125.84685382869, 6207.596506227081, 6285.402886725754, 6286.968565157236, 6310.242770867433, 6404.519661025442, 6427.517936154713, 6521.217453905561, 6540.738370465223, 6619.404253617197, 6247.781327653511, 6253.78111766086, 6255.468285195719, 6278.466330361879, 6287.434130928238, 5816.098504731774, 5817.476427121579, 5822.534197264209, 5828.154917536934, 5859.121131830174, 6584.670766461753, 6676.632785196666, 6699.933000669931, 6723.327667233238, 6761.290064543349, 6578.407943285128, 6614.272430032789, 6628.204615446178, 6638.468922486093, 6655.401013908771, 6524.412802847766, 6569.408753212234, 6614.173228346457, 6826.8634627308165, 6859.725610975505, 6339.461145802658, 6417.101605775289, 6461.224653041477, 6489.351064893662, 6504.252011018604, 6592.307807680125, 6610.537262391586, 6614.305497922649, 6617.834554135992, 6625.370589793962, 6231.657258850807, 6304.842378940563, 6336.651484168415, 6343.0485427186295, 6397.0724244983285, 6309.968450157719, 6322.336154703874, 6339.556231063966, 6353.237611486651, 6380.138681277968, 6208.720607572761, 6244.344343843831, 6264.968675156595, 6274.843128921813, 6309.463695585743, 6314.463270622048, 6349.523785715997, 6383.808485745435, 6391.58454700443, 6399.7440102395385, 5160.535551800356, 6293.748250070129, 6301.936980630135, 6306.369363063657, 6315.305316415099], "value": 6042.282375259793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.3235189801333, 796.3748457460895, 816.6161903905166, 1129.1249281806604, 1148.6783700563844, 27827.30431739205, 37156.88529344117, 37881.810590947076, 37898.8105059475, 37984.81007594958, 39507.802460987725, 39809.805705828796, 39819.00452488682, 39865.60134398655, 39903.800480997554, 8431.325493960483, 8588.227059564639, 8598.699045533407, 8602.741917742478, 8609.61256743448, 6090.604110732788, 6267.780627678032, 8396.538190399526, 8572.399932004731, 8596.01145868227, 8476.321894248445, 8562.0, 8573.785655358635, 8575.0, 8577.0, 8471.110533393998, 8545.401821872472, 8550.78623034429, 8554.101819309008, 8564.143585641386, 8363.623636936352, 8439.789005274917, 8441.915580844236, 8446.408751387406, 8453.788655283666, 37142.62857371419, 37361.81319093415, 37386.0, 37455.25089498222, 37471.81264093689, 2919.343147791751, 2977.642682878047, 2990.5258548624543, 3001.9849900750573, 3022.2746540830262, 26274.82128673558, 26476.73523264762, 26593.468130637473, 26704.06535771258, 27450.0, 2754.807163498556, 2788.261110805638, 2793.748562629353, 2833.7307955744172, 2877.453283876078, 301199.0, 302339.0, 302404.0, 302809.0, 305445.0, 46767.532324677, 47315.52684473111, 47407.52592474031, 47478.0, 47481.05037899292, 49455.50544494509, 49502.75248623734, 49506.0, 49581.0, 49597.0, 14279.00046996721, 14635.92682036583, 14648.487302944346, 14652.780208296885, 14657.120572765669, 10124.949375253076, 11902.0, 11905.583304584297, 11926.0, 11971.0, 11399.088072954144, 11479.19645624815, 13167.934160329329, 13342.266175360448, 13394.531191408254, 5866.2960444746905, 5944.454441845065, 5977.847974729094, 5988.221531201003, 5993.400659934059, 949.4618717677407, 961.0917682789697, 975.512243878061, 984.4831463481729, 1002.7242508310127, 24226.152084676953, 24243.51512969766, 24306.0, 24368.512629747307, 24406.0, 926.754410081329, 969.520087556664, 1067.3862529045675, 1102.2009043443438, 1121.360824330124, 9612.0, 12097.0, 12289.56986505468, 12298.877011229773, 12322.630321090382, 440656.0, 443107.0, 443688.0, 443760.0, 444058.0, 54452.91094178174, 54865.72567137139, 54934.45065549293, 54991.45008549943, 55080.62298442571, 1454.5636309107438, 1488.970220595582, 1497.9550613481613, 1514.0234548716016, 1522.1095659039365, 23157.0, 23820.761792381854, 26016.60975085413, 28811.42377152487, 28845.278868027628, 52884.206736899745, 53519.46480535145, 53577.464225357246, 53644.658883528224, 53695.731521342146, 462478.375216257, 466295.0, 466762.0, 467154.0, 468320.0, 33548.82579109817, 34837.8258108718, 34875.82562087275, 34881.47677784886, 34882.47676284807, 40883.182336353704, 41283.9623717511, 41888.790556047024, 41964.58035419607, 42381.57618423776, 28079.43841123125, 28919.855400722863, 29550.852245738635, 29819.701802981694, 29992.25019374533, 22584.774152258266, 27018.99617890085, 29963.55054674138, 30079.398412032075, 30214.848925755232, 34859.605615775086, 35458.29083418369, 35511.11222219465, 35544.46683299701, 35592.82203588966, 29106.981255656796, 29689.554656679735, 30091.94678186253, 30274.24314392158, 30609.081727548208, 22430.0, 23587.29238122859, 24155.396115097265, 24324.0, 24393.878030609732], "value": 17674.516219375993, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.76468354303887, 407.26895223074575, 410.90549173690056, 424.46941323345806, 435.83438293448484, 13727.0, 15681.843181568182, 22560.66159007616, 22609.321720348376, 22781.088756449743, 22150.0, 22900.083996640155, 23244.41888952777, 23262.76737232627, 23865.164719234832, 6170.537209709268, 6458.838529036765, 6530.542861999662, 6539.280679125292, 6543.312952140028, 5936.7328470218945, 5973.864965656533, 6173.950428427156, 6368.9681551592175, 6402.263739669951, 3772.4907137536356, 3945.7632542047427, 3950.841966321358, 3951.130751234724, 3955.3671412573995, 3929.9607003930023, 6161.29145148307, 6350.523710721668, 6422.229332480086, 6455.419012288869, 5646.463585959327, 5716.657000579937, 5760.942390576124, 5771.971140144273, 5780.248567686174, 27747.8900843966, 28331.0, 28488.0, 28508.7171077302, 28599.28501787451, 1946.9221231150736, 1968.3602829080605, 1971.7929617390255, 1991.741073660415, 2062.113291284747, 13638.318084095756, 15898.284577194054, 16732.498025059267, 16786.664266714597, 17504.912475437668, 1604.855562999324, 1645.9670806583922, 1646.9094199819006, 1704.8550873175795, 1709.3162734906014, 150978.0, 157539.39684825187, 157998.0, 160958.99999999884, 162280.0, 12172.634820955209, 12448.128630995925, 12456.314902680258, 12667.683307917376, 13060.934695326652, 16534.255958481655, 16549.089800061112, 16556.75164872528, 16563.751543726852, 16564.83435165633, 7687.540606726995, 8732.519711415933, 8995.370324077383, 9037.638494460149, 9239.39943903644, 8723.869141962876, 8738.820259264918, 8749.956250218835, 8906.287497000354, 8918.751374807533, 7665.233476652402, 7832.177621349791, 7841.7255396062, 7855.293023627901, 7869.331106855867, 2095.711137650334, 2096.7000459715073, 2098.9685154722692, 2099.664053751423, 2253.42260417705, 905.8143080668578, 908.5094049213483, 924.1590152960773, 932.4079209701888, 967.5017366056486, 19899.900500497406, 24599.754002459747, 25999.960001599724, 26486.735132648813, 26640.20079397621, 771.8301973565807, 808.0868618461101, 841.5623875584662, 889.644142343068, 925.3013974449248, 10715.089217416451, 11009.2843965142, 11021.44892755359, 11073.889261107446, 11101.38942358178, 585641.0, 695126.0487395163, 707285.0, 724936.3753181306, 725605.0, 145669.0, 151381.24309378382, 156397.43602563828, 161471.0, 164571.53142702868, 1175.2360965372404, 1179.6166245970094, 1207.0, 1212.89690376319, 1213.9150259481928, 18443.0, 18631.88208707482, 18641.067946602885, 18710.719339210194, 18868.05659717036, 65257.0, 65736.68526629345, 66038.67922641424, 66481.0, 68848.31151688419, 75166.0, 90620.54689726509, 91603.0, 93436.0, 94239.52880235555, 8515.531645759544, 8611.48331100136, 8639.09289524616, 9021.782059421894, 9151.542422878696, 13476.0, 14147.151170929777, 14158.858411415755, 14441.78337324962, 14647.974641774974, 11600.477978491157, 11894.762104757683, 12200.511979521074, 12224.960878325086, 12251.87748122543, 9402.38884472507, 10358.637447689294, 11118.49966751482, 11311.264767790068, 11668.88331116678, 11923.523059077539, 12044.0, 12148.088893332859, 12332.876671233173, 12336.75326493483, 7500.662470188746, 8739.694110706349, 8900.065493123388, 8910.509921954355, 8996.05541418168, 11624.186306958432, 11730.70673233176, 12105.69735756578, 12112.84927931845, 12125.454354553889], "value": 10377.057514498849, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.631710486854, 1393.7282229965153, 1405.7399381114487, 1411.760000799864, 1418.3333833098443, 10507.894921050787, 11012.779744405107, 11022.559097636085, 11037.503312350944, 11047.17146214034, 10624.78750424991, 11029.172812039089, 11030.889691103086, 11045.889541104587, 11068.833967490475, 2434.3062227265277, 2468.6543883856275, 2476.764707352808, 2481.9875900620473, 2482.019602257107, 1498.4081287891277, 2140.77521860204, 2394.6767186429784, 2425.684660994077, 2471.456279618481, 2123.3417640531407, 2360.8229382796358, 2450.791682706972, 2451.6322551617222, 2452.1907770435714, 2354.3054798834387, 2414.239514552919, 2424.175780234729, 2425.0663494554547, 2425.478522117753, 2426.332758491429, 2430.1858877276136, 2435.659007738918, 2435.902563897442, 2440.2435245074084, 10837.403942783143, 10953.014228719454, 10966.725831854188, 10968.122550195965, 10975.780484390347, 6116.296625887991, 6168.0439531872635, 6352.745890164388, 6356.4279214870885, 6433.806985790434, 3797.6012518685416, 4037.858674946363, 4043.9595604043875, 4124.752514849119, 4135.6898232632675, 1123.9606613768478, 1153.8231004375573, 1205.2346759807497, 1209.6189700244395, 1229.4590380232669, 15113.697726045528, 15324.463643772526, 15329.846701532953, 15331.0, 15353.462628808049, 4834.395700537433, 5205.375354957354, 5316.122839731478, 5328.8401347959625, 5344.171653393731, 11304.773904521864, 11325.093992480583, 11348.546058157746, 11375.374354410587, 11379.829302560469, 6233.688315584202, 6249.031400132987, 6253.874922501615, 6264.060390941395, 6281.780137695158, 5579.525740312038, 5658.858528536737, 5746.0519014363, 5798.478136967608, 5832.854178645568, 3517.2437925846066, 4023.1350259694304, 4143.177002119058, 4174.332106862948, 4220.198162349144, 1105.0772604874858, 1223.2843786385065, 1224.2899118511243, 1225.2723659639864, 1253.0, 652.4421619515341, 723.9322000049884, 742.9702811887572, 744.2148533297315, 786.6312616048035, 5870.530357571384, 6034.24571928509, 6088.299845517822, 6162.414570615784, 6185.8144255672405, 858.9012263589767, 864.9697260595975, 874.0865795243985, 921.801812610292, 926.3654396738256, 3330.8501117449773, 3408.812515311632, 3496.0036389628835, 3788.1287303920253, 3791.0711875590105, 10849.837252441219, 14153.29233538319, 14159.78760318596, 14189.0, 14268.42926282937, 11355.943220283845, 13598.0, 13751.243681597609, 14066.0, 14113.364898579382, 10098.343607665478, 10186.694399168036, 10336.5348559315, 10347.896521034843, 10414.479276036167, 6373.3945275198785, 6418.743250269937, 6487.18910136233, 6497.772577959749, 6567.199952064257, 12547.74904501923, 12656.43046062911, 12697.047721421115, 12864.397968568815, 12880.549180778627, 12766.744665106831, 12847.807282890952, 12952.481900723866, 13023.413946372246, 13045.0, 8087.110417854149, 8208.83582328362, 8242.505449673039, 8335.374846886387, 8337.499750015018, 9883.752906177404, 10152.898471015194, 10159.949200254247, 10169.03394177552, 10177.643782467874, 8351.665933362841, 8576.656933722581, 8674.956625217086, 8681.305495560213, 8712.477251364939, 8487.575621218792, 8490.108538603357, 8492.235698786892, 8567.74296771098, 8643.481391116553, 7657.617119144132, 7856.84286314282, 7866.0, 7879.566623835744, 7888.0, 4950.257461380677, 5710.457506536873, 5961.791337303173, 5984.850378740566, 6039.969800150971, 5828.883422331614, 5841.561882858718, 5968.940310597012, 6050.2437195350585, 6232.314445410911], "value": 5096.36393386074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.8828563145425, 389.4780993468751, 402.5591976785421, 403.2821577591884, 404.54084613963164, 5450.482204190601, 5864.824055278338, 5933.85165370866, 5946.61347012445, 6151.292601350846, 5165.38015438148, 5341.4658534146565, 5894.705264736767, 5974.432428919256, 6062.181605483259, 3661.6521430464063, 3945.855701846461, 4769.618430525558, 4786.56920877121, 4789.832355867546, 4348.934765978513, 4576.382188404573, 4593.862184134465, 4773.737444440554, 4780.545848144438, 4485.215087359699, 4513.390692256553, 4517.661175411841, 4550.408446901898, 4550.748544150352, 4276.25165596021, 4525.434320709911, 4558.703684260546, 4566.520515345874, 4576.0, 3741.9625803741887, 3899.3760998240145, 3951.3677811550015, 3953.525576930758, 3954.940675889864, 4668.109415686652, 4864.878378040542, 5391.919121213185, 5866.413358664099, 5913.970430147821, 4219.662427005863, 4602.309653551961, 5254.264402983586, 5268.683878967236, 5283.920741188886, 5199.0, 6116.296625888035, 6394.0, 6424.325445828167, 6437.83905402364, 3616.0, 3960.8117564730464, 4290.141971605692, 4295.162443323553, 4313.956860431387, 5196.181336532221, 5651.745671444794, 6164.7670465906995, 6800.897986530206, 6825.453963682893, 4127.690423218271, 4231.6614670826875, 4322.956770432318, 4335.869923902288, 4374.8906277342685, 4207.684423668237, 4430.224710675619, 4438.112377524508, 4453.688241823106, 4460.531644177379, 5210.583153347723, 5234.26720259164, 5236.455245702527, 5237.764300606482, 5240.816571419982, 5633.8168984512895, 6230.345813689588, 6335.271443783931, 6426.907425737636, 6473.482121430275, 1235.6849003504049, 1258.1884684378708, 1459.5914942080942, 1513.8259100203618, 1528.9006214596016, 845.8942632170979, 975.1592047056508, 993.2848349188573, 998.8052329795765, 1008.9445080520642, 5110.3356563646485, 5199.792008319625, 5313.521783039545, 5479.589030822624, 6154.15375387382, 5234.618248696834, 5743.770249190069, 6524.706388212542, 6568.916128838688, 6575.934240657628, 5790.768369265266, 6029.306629737636, 6074.42292982166, 6107.908381374283, 6139.324674285825, 3109.502479603298, 3208.5668434761474, 3480.9477857832153, 3481.2515309208643, 3488.581370235537, 5726.0, 6541.2150541934325, 6630.303818099127, 6859.038076352506, 6952.304769523007, 5686.346070201897, 6874.793756187322, 6888.690008949609, 6909.619970901548, 6919.619420931796, 5984.371640977809, 6624.536282460182, 6759.155105611799, 6942.583444993215, 6951.409130223887, 5389.353277606712, 5592.692401917933, 6536.738530458729, 6674.43267322283, 6679.962611403568, 5483.616146869681, 6588.209414870054, 6596.736130554916, 6612.537122401578, 6627.834304142435, 6029.125776762433, 6704.765333213314, 6739.8879184935895, 6918.9210801194995, 6933.861322773617, 3645.0, 4399.076193999296, 4409.977950110229, 4417.712848664826, 4417.8734422722, 6351.3331100235655, 6367.108604795239, 6402.519811014099, 6566.770163044271, 6701.530892837454, 5895.823125306248, 6054.545909056927, 6217.253929528486, 6472.514561407819, 6480.384363485461, 5777.7977770777825, 6308.621482711052, 6435.970244760816, 6445.9033114504255, 6481.0, 6005.978983572891, 6281.842953926189, 6324.588901721373, 6336.493080553452, 6380.617162970237, 5124.538791508782, 5340.5994550408095, 5423.4034256231, 6458.870822583616, 6471.967640161769, 5583.972080139573, 5885.970570147122, 6294.874102517832, 6311.558190926591, 6315.936840631535], "value": 4623.033750080616, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.9754308599197, 703.4618516834619, 716.0720033674202, 718.2637796258838, 719.2771264878797, 12444.377781110943, 16266.51200463985, 16297.022178669273, 16297.75553366699, 16304.510864674048, 15008.174550399724, 15105.54683359501, 15113.0, 15114.546563603082, 16746.58133546662, 943.1087622197026, 949.8955114937352, 967.3953778888194, 969.6848524229619, 972.0085512776964, 988.2785566536432, 990.4898977026837, 995.5072239241586, 996.0537489385077, 996.6412091647002, 1004.7940172264705, 1039.4958445154132, 1044.770150566874, 1046.074224311486, 1048.543883410715, 1033.4780935627534, 1034.043509753475, 1034.849946757716, 1036.6838114375112, 1038.1953985661098, 761.2235519769804, 763.0652450747845, 763.6525380951654, 765.1239330966013, 765.7549584133023, 13729.519466818614, 16014.0, 16363.181840907908, 16513.83486165135, 17061.82938170615, 12061.577844775391, 13653.317334133353, 14567.49013784523, 14714.953387818436, 15259.465918692793, 11627.186096973215, 11649.650510484698, 11701.239419437708, 11866.169368144234, 12283.508659653604, 2980.7615390768688, 2982.2842517795575, 3104.6429660589097, 3128.6818942386644, 3146.143775172659, 112447.0, 115448.0, 117001.0, 117847.2322915157, 120291.0, 10019.277445388912, 10121.848172277421, 10122.949385253027, 10255.179585633132, 10726.249162558703, 20526.92731660325, 21109.62787418813, 23258.3022509325, 23291.0, 23316.067357305856, 7804.297613214838, 7882.842343153105, 7912.841743165104, 7913.881291780628, 7956.920430795618, 3530.646935306449, 3596.8741094061584, 4213.557576454428, 4257.978710106491, 4259.148170365939, 4018.8794336169963, 4149.25313443577, 4233.11104668017, 4234.745915245096, 4236.004538933348, 775.4494309040532, 778.8052986753312, 778.9406407286086, 806.2340776262574, 811.853866304071, 513.7123211001851, 525.7239949026791, 566.1225101093289, 573.0315766354847, 576.5589324167015, 7891.255622170269, 9273.224289490783, 9747.220222382193, 10211.994962693168, 10414.271001029856, 518.6032684995981, 540.2571464236675, 567.8637127089469, 577.1804038265673, 583.983724065207, 4549.408576884984, 4606.262997920384, 4635.513271106485, 4661.673682842236, 4678.111158879802, 69833.0, 104082.0, 105327.47336263269, 105579.0, 107044.0, 93417.0, 93615.0, 95472.52263738775, 95991.0, 96395.51802240944, 79763.0, 82237.58881205675, 82259.5887020561, 83414.58292708498, 83522.0, 7937.920620793718, 8161.306288965505, 9414.764630884283, 9484.33609647318, 9544.761380965532, 38827.4469021236, 39133.41299880561, 39203.80398098105, 39513.802430987664, 39858.800705996284, 40685.186296273314, 49198.0, 49692.50922472332, 50542.0, 50872.49127508677, 11612.361320127475, 12216.938915305367, 13349.0, 13376.398062087035, 13863.237521935987, 14964.75528670708, 15004.399824007354, 15116.470923517623, 15149.545513634608, 15282.312295946931, 11196.944015280198, 14074.577762667137, 14180.29098545048, 14209.50266740659, 14398.42406303736, 13657.106093930104, 13788.241646709126, 13931.930340348234, 14110.435582576993, 14256.714865702836, 13500.45998160102, 13619.38712757948, 13632.591022269347, 14106.365213565208, 14501.709965800836, 10002.749931251778, 10145.34055286404, 10346.948265258927, 10352.016558426938, 10527.578896844041, 9038.638454461749, 10499.68500944973, 10731.449801536943, 11345.262557933707, 11773.17587768848], "value": 7485.785622007105, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1672.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 126.0, 121.0, 121.0, 125.0, 126.0, 123.0, 123.0, 126.0, 125.0], "value": 122.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 124.0, 164.0, 121.0, 121.0, 127.0, 152.0, 142.0, 119.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 224.0, 223.0, 221.0, 218.0, 213.0, 222.0, 213.0, 214.0, 215.0], "value": 218.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 63.0, 64.0, 100.0, 67.0, 66.0, 65.0, 65.0, 85.0, 66.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 77.0, 97.0, 75.0, 78.0, 77.0, 95.0, 75.0, 77.0, 96.0], "value": 84.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 114.0, 95.0, 95.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 158.0, 137.0, 103.0, 103.0, 103.0, 104.0, 127.0, 102.0, 102.0], "value": 115.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 164.0, 102.0, 104.0, 99.0, 125.0, 163.0, 102.0, 102.0, 102.0], "value": 121.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 65.0, 64.0, 64.0, 65.0, 90.0, 99.0, 65.0, 66.0], "value": 70.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 67.0, 62.0, 92.0, 64.0, 71.0, 59.0, 80.0, 90.0, 95.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 93.0, 91.0, 93.0, 89.0, 90.0, 91.0, 89.0, 92.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 181.0, 189.0, 155.0, 152.0, 160.0, 172.0, 175.0, 196.0, 173.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 205.0, 207.0, 205.0, 214.0, 214.0, 250.0, 254.0, 204.0, 208.0], "value": 216.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 97.0, 103.0, 103.0, 97.0, 75.0, 73.0, 73.0, 75.0], "value": 84.8, "unit": "score"}]}]} 16:33:55 INFO - Return code: 0 16:33:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:33:55 ERROR - # TBPL SUCCESS # 16:33:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:33:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:33:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:33:55 INFO - 2017-03-30 16:33:55.868 screenresolution[1994:17534] starting screenresolution argv=screenresolution get 16:33:55 INFO - 2017-03-30 16:33:55.871 screenresolution[1994:17534] Display 0: 1600x1200x32@60 16:33:55 INFO - 2017-03-30 16:33:55.877 screenresolution[1995:17537] starting screenresolution argv=screenresolution list 16:33:55 INFO - Available Modes on Display 0 16:33:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:33:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:33:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:33:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:33:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:33:56 INFO - Intel Iris: 16:33:56 INFO - Chipset Model: Intel Iris 16:33:56 INFO - Type: GPU 16:33:56 INFO - Bus: Built-In 16:33:56 INFO - VRAM (Dynamic, Max): 1536 MB 16:33:56 INFO - Vendor: Intel (0x8086) 16:33:56 INFO - Device ID: 0x0a2e 16:33:56 INFO - Revision ID: 0x0009 16:33:56 INFO - Displays: 16:33:56 INFO - Display: 16:33:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:33:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:33:56 INFO - Display Serial Number: 1600x1200 60 16:33:56 INFO - Main Display: Yes 16:33:56 INFO - Mirror: Off 16:33:56 INFO - Online: Yes 16:33:56 INFO - Rotation: Supported 16:33:56 INFO - Return code: 0 16:33:56 INFO - Running post-action listener: _resource_record_post_action 16:33:56 INFO - [mozharness: 2017-03-30 23:33:56.116204Z] Finished run-tests step (success) 16:33:56 INFO - Running post-run listener: _resource_record_post_run 16:33:56 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 462848; Write bytes: 515142144; Read time: 7; Write time: 1589 16:33:56 INFO - TinderboxPrint: CPU usage
53.4% 16:33:56 INFO - TinderboxPrint: I/O read bytes / time
462,848 / 7 16:33:56 INFO - TinderboxPrint: I/O write bytes / time
515,142,144 / 1,589 16:33:56 INFO - TinderboxPrint: CPU idle
2,249.8 (46.6%) 16:33:56 INFO - TinderboxPrint: CPU system
965.3 (20.0%) 16:33:56 INFO - TinderboxPrint: CPU user
1,614.7 (33.4%) 16:33:56 INFO - TinderboxPrint: Swap in / out
212,381,696 / 0 16:33:56 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200272384; Write bytes: 196007424; Read time: 15658; Write time: 410 16:33:56 INFO - run-tests - Wall time: 1187s; CPU: 53.0%; Read bytes: 462848; Write bytes: 299305984; Read time: 7; Write time: 1119 16:33:56 INFO - Running post-run listener: _upload_blobber_files 16:33:56 INFO - Blob upload gear active. 16:33:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:33:56 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:33:56 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:33:56 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:33:57 INFO - (blobuploader) - INFO - Open directory for files ... 16:33:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:33:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:33:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:57 INFO - (blobuploader) - INFO - Done attempting. 16:33:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:33:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:33:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:58 INFO - (blobuploader) - INFO - Done attempting. 16:33:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:33:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:33:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:59 INFO - (blobuploader) - INFO - Done attempting. 16:33:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:33:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:34:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:01 INFO - (blobuploader) - INFO - Done attempting. 16:34:01 INFO - (blobuploader) - INFO - Iteration through files over. 16:34:01 INFO - Return code: 0 16:34:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:34:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:34:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/64d945ebcb41ff1f0194ea197bbb30aa2e890d67e9a3a2457c30a8b876c2189d00367dee596fc23748d43957b0a4ee6e05b4fe7244e4d192f9303b92aefef55a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d7d162038450efa78961005aaff8f12fed7e68076d1ae9430b9280a1ede3236ef96c3e756318eff27ca28bb4a485d3bb0fdf7d3200e839f5a0dcc957ea53d5d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a1ccd70d8c9cb67492fd84043706227663ec5f1f83db6f1a20b151bd3dc89d3e8c7c692b463f3da0df6f7d047b71d561c67efb9cac5fc4fe845aea0df01eb848", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/02327af35f8daa9113a02e23e3c390495e3309fb85ecde91d7dc4f59eded57ce0f47e2ddc49040a6d9d3500c962e3e210789f756b6ce25f16b87627212a2a1da"} 16:34:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:34:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:34:01 INFO - Contents: 16:34:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/64d945ebcb41ff1f0194ea197bbb30aa2e890d67e9a3a2457c30a8b876c2189d00367dee596fc23748d43957b0a4ee6e05b4fe7244e4d192f9303b92aefef55a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d7d162038450efa78961005aaff8f12fed7e68076d1ae9430b9280a1ede3236ef96c3e756318eff27ca28bb4a485d3bb0fdf7d3200e839f5a0dcc957ea53d5d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a1ccd70d8c9cb67492fd84043706227663ec5f1f83db6f1a20b151bd3dc89d3e8c7c692b463f3da0df6f7d047b71d561c67efb9cac5fc4fe845aea0df01eb848", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/02327af35f8daa9113a02e23e3c390495e3309fb85ecde91d7dc4f59eded57ce0f47e2ddc49040a6d9d3500c962e3e210789f756b6ce25f16b87627212a2a1da"} 16:34:01 INFO - Running post-run listener: copy_logs_to_upload_dir 16:34:01 INFO - Copying logs to upload dir... 16:34:01 INFO - mkdir: /builds/slave/test/build/upload/logs 16:34:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.266522 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-30 16:34:01.969869) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 16:34:01.973430) ========= 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.LhdrfDVxF9/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.LJY4KygNWn/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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/Pine/sha512/64d945ebcb41ff1f0194ea197bbb30aa2e890d67e9a3a2457c30a8b876c2189d00367dee596fc23748d43957b0a4ee6e05b4fe7244e4d192f9303b92aefef55a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d7d162038450efa78961005aaff8f12fed7e68076d1ae9430b9280a1ede3236ef96c3e756318eff27ca28bb4a485d3bb0fdf7d3200e839f5a0dcc957ea53d5d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a1ccd70d8c9cb67492fd84043706227663ec5f1f83db6f1a20b151bd3dc89d3e8c7c692b463f3da0df6f7d047b71d561c67efb9cac5fc4fe845aea0df01eb848", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/02327af35f8daa9113a02e23e3c390495e3309fb85ecde91d7dc4f59eded57ce0f47e2ddc49040a6d9d3500c962e3e210789f756b6ce25f16b87627212a2a1da"} build_url:https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011491 build_url: 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/64d945ebcb41ff1f0194ea197bbb30aa2e890d67e9a3a2457c30a8b876c2189d00367dee596fc23748d43957b0a4ee6e05b4fe7244e4d192f9303b92aefef55a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d7d162038450efa78961005aaff8f12fed7e68076d1ae9430b9280a1ede3236ef96c3e756318eff27ca28bb4a485d3bb0fdf7d3200e839f5a0dcc957ea53d5d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a1ccd70d8c9cb67492fd84043706227663ec5f1f83db6f1a20b151bd3dc89d3e8c7c692b463f3da0df6f7d047b71d561c67efb9cac5fc4fe845aea0df01eb848", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/02327af35f8daa9113a02e23e3c390495e3309fb85ecde91d7dc4f59eded57ce0f47e2ddc49040a6d9d3500c962e3e210789f756b6ce25f16b87627212a2a1da"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 16:34:02.010420) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:34:02.011116) ========= 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.LhdrfDVxF9/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.LJY4KygNWn/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.004956 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:34:02.061326) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 16:34:02.061785) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 16:34:02.062251) ========= ========= Total master_lag: 0.20 =========