builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0189 starttime: 1475203896.62 results: success (0) buildid: 20160929185419 builduid: e424fb6a233f40439c09a2eec8fa7f1f revision: f6a964152400fa1e5340e9909615ac938610f931 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:36.624170) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:36.624599) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:36.624871) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:36.710901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:36.711164) ========= 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.5biHMDnzAA/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.P8xg1GHTLR/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-29 19:51:36-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.5M=0.001s 2016-09-29 19:51:36 (12.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.234606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:36.967473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:36.967756) ========= 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.5biHMDnzAA/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.P8xg1GHTLR/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.020780 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:37.016403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 19:51:37.016719) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f6a964152400fa1e5340e9909615ac938610f931 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f6a964152400fa1e5340e9909615ac938610f931 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5biHMDnzAA/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.P8xg1GHTLR/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-29 19:51:37,084 truncating revision to first 12 chars 2016-09-29 19:51:37,084 Setting DEBUG logging. 2016-09-29 19:51:37,084 attempt 1/10 2016-09-29 19:51:37,084 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f6a964152400?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 19:51:37,921 unpacking tar archive at: fx-team-f6a964152400/testing/mozharness/ program finished with exit code 0 elapsedTime=1.090762 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 19:51:38.130245) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:38.130761) ========= script_repo_revision: f6a964152400fa1e5340e9909615ac938610f931 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:38.131435) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:38.131737) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 19:51:38.149885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-29 19:51:38.150176) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5biHMDnzAA/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.P8xg1GHTLR/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:51:38 INFO - MultiFileLogger online at 20160929 19:51:38 in /builds/slave/test 19:51:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 19:51:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:51:38 INFO - {'append_to_log': False, 19:51:38 INFO - 'base_work_dir': '/builds/slave/test', 19:51:38 INFO - 'blob_upload_branch': 'Fx-Team', 19:51:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:51:38 INFO - 'branch': 'Fx-Team', 19:51:38 INFO - 'buildbot_json_path': 'buildprops.json', 19:51:38 INFO - 'config_files': ('talos/mac_config.py',), 19:51:38 INFO - 'default_actions': ('clobber', 19:51:38 INFO - 'read-buildbot-config', 19:51:38 INFO - 'download-and-extract', 19:51:38 INFO - 'populate-webroot', 19:51:38 INFO - 'create-virtualenv', 19:51:38 INFO - 'install', 19:51:38 INFO - 'run-tests'), 19:51:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:51:38 INFO - 'download_minidump_stackwalk': True, 19:51:38 INFO - 'download_symbols': 'ondemand', 19:51:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:51:38 INFO - 'tooltool.py': '/tools/tooltool.py', 19:51:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:51:38 INFO - '/tools/misc-python/virtualenv.py')}, 19:51:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:51:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:51:38 INFO - 'installer_path': 'installer.exe', 19:51:38 INFO - 'log_level': 'info', 19:51:38 INFO - 'log_name': 'talos', 19:51:38 INFO - 'log_to_console': True, 19:51:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:51:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:51:38 INFO - 'opt_config_files': (), 19:51:38 INFO - 'pip_index': False, 19:51:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:51:38 INFO - 'cmd': ('bash', 19:51:38 INFO - '-c', 19:51:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:51:38 INFO - 'enabled': True, 19:51:38 INFO - 'halt_on_failure': False, 19:51:38 INFO - 'name': 'check_screen_resolution'},), 19:51:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:51:38 INFO - 'cmd': ('bash', 19:51:38 INFO - '-c', 19:51:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:51:38 INFO - 'enabled': True, 19:51:38 INFO - 'halt_on_failure': False, 19:51:38 INFO - 'name': 'check_screen_resolution'},), 19:51:38 INFO - 'run_cmd_checks_enabled': True, 19:51:38 INFO - 'sps_profile': False, 19:51:38 INFO - 'sps_profile_interval': 0, 19:51:38 INFO - 'suite': 'dromaeojs', 19:51:38 INFO - 'system_bits': '32', 19:51:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:51:38 INFO - 'title': 't-yosemite-r7-0189', 19:51:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:51:38 INFO - 'use_talos_json': True, 19:51:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:51:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:51:38 INFO - 'work_dir': 'build'} 19:51:38 INFO - [mozharness: 2016-09-30 02:51:38.346312Z] Running clobber step. 19:51:38 INFO - Running pre-action listener: _resource_record_pre_action 19:51:38 INFO - Running main action method: clobber 19:51:38 INFO - rmtree: /builds/slave/test/build 19:51:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:51:39 INFO - Running post-action listener: _resource_record_post_action 19:51:39 INFO - [mozharness: 2016-09-30 02:51:39.947169Z] Finished clobber step (success) 19:51:39 INFO - [mozharness: 2016-09-30 02:51:39.947332Z] Running read-buildbot-config step. 19:51:39 INFO - Running pre-action listener: _resource_record_pre_action 19:51:39 INFO - Running main action method: read_buildbot_config 19:51:39 INFO - Using buildbot properties: 19:51:39 INFO - { 19:51:39 INFO - "project": "", 19:51:39 INFO - "product": "firefox", 19:51:39 INFO - "script_repo_revision": "production", 19:51:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 19:51:39 INFO - "repository": "", 19:51:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 19:51:39 INFO - "buildid": "20160929185419", 19:51:39 INFO - "pgo_build": "False", 19:51:39 INFO - "basedir": "/builds/slave/test", 19:51:39 INFO - "buildnumber": 843, 19:51:39 INFO - "slavename": "t-yosemite-r7-0189", 19:51:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:51:39 INFO - "platform": "yosemite_r7", 19:51:39 INFO - "scheduler": "tests-fx-team-macosx64-talos", 19:51:39 INFO - "branch": "fx-team", 19:51:39 INFO - "repo_path": "integration/fx-team", 19:51:39 INFO - "revision": "f6a964152400fa1e5340e9909615ac938610f931", 19:51:39 INFO - "stage_platform": "macosx64", 19:51:39 INFO - "builduid": "e424fb6a233f40439c09a2eec8fa7f1f", 19:51:39 INFO - "slavebuilddir": "test" 19:51:39 INFO - } 19:51:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:51:39 INFO - Running post-action listener: _resource_record_post_action 19:51:39 INFO - [mozharness: 2016-09-30 02:51:39.950743Z] Finished read-buildbot-config step (success) 19:51:39 INFO - [mozharness: 2016-09-30 02:51:39.950835Z] Running download-and-extract step. 19:51:39 INFO - Running pre-action listener: _resource_record_pre_action 19:51:39 INFO - Running main action method: download_and_extract 19:51:39 INFO - mkdir: /builds/slave/test/build/tests 19:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:39 INFO - trying https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:51:39 INFO - Downloading https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:51:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 19:51:41 INFO - Downloaded 1199 bytes. 19:51:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:51:41 INFO - Using the following test package requirements: 19:51:41 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:51:41 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:51:41 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:51:41 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'jsshell-mac.zip'], 19:51:41 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:51:41 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:51:41 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:51:41 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:51:41 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:51:41 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:51:41 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:51:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:51:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:51:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:41 INFO - Fetch https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:51:42 INFO - Http code: 200 19:51:42 INFO - Content-Encoding: None 19:51:42 INFO - Content-Type: application/zip 19:51:42 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 19:51:42 INFO - x-amz-cf-id: fLHIlAZrGPeUX1y6wWyZiZy8o5CbsREBcUGQbXkxRVBdQ7j_kcL94g== 19:51:42 INFO - x-amz-version-id: 2pt3gLa52ukbgiXAWgHSf17O0HjAMuAG 19:51:42 INFO - x-cache: Hit from cloudfront 19:51:42 INFO - Expected file size: 16473331 19:51:42 INFO - Obtained file size: 16473331 19:51:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:51:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:51:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:49 INFO - Fetch https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:51:49 INFO - Http code: 200 19:51:49 INFO - Content-Encoding: None 19:51:49 INFO - Content-Type: application/zip 19:51:49 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 19:51:49 INFO - x-amz-cf-id: yp10tcXrG1odFg96pqsrZ3fDVmIGA2OYYk_1GbHU6ur59xJxNNp_UA== 19:51:49 INFO - x-amz-version-id: 2pt3gLa52ukbgiXAWgHSf17O0HjAMuAG 19:51:49 INFO - x-cache: Hit from cloudfront 19:51:50 INFO - Expected file size: 16473331 19:51:50 INFO - Obtained file size: 16473331 19:51:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:51:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:51:56 INFO - Fetch https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:51:57 INFO - Http code: 200 19:51:57 INFO - Content-Encoding: None 19:51:57 INFO - Content-Type: application/zip 19:51:57 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 19:51:57 INFO - x-amz-cf-id: B2OYgO2_2IX9Sqyj5bKdeJ9cMCbtecNTSyZKqM-zyZybFICSvUgOYw== 19:51:57 INFO - x-amz-version-id: YNtHCXJ3yTQmNvAo2nWv37sGcwbEdRa5 19:51:57 INFO - x-cache: Hit from cloudfront 19:51:57 INFO - Expected file size: 14414067 19:51:57 INFO - Obtained file size: 14414067 19:51:57 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')]} 19:51:57 INFO - trying https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:51:57 INFO - Downloading https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:51:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 19:52:00 INFO - Downloaded 111805084 bytes. 19:52:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:52:00 INFO - mkdir: /builds/slave/test/properties 19:52:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:52:00 INFO - Writing to file /builds/slave/test/properties/build_url 19:52:00 INFO - Contents: 19:52:00 INFO - build_url:https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:52:01 INFO - Running post-action listener: _resource_record_post_action 19:52:01 INFO - Running post-action listener: set_extra_try_arguments 19:52:01 INFO - [mozharness: 2016-09-30 02:52:01.191949Z] Finished download-and-extract step (success) 19:52:01 INFO - [mozharness: 2016-09-30 02:52:01.192068Z] Running populate-webroot step. 19:52:01 INFO - Running pre-action listener: _resource_record_pre_action 19:52:01 INFO - Running main action method: populate_webroot 19:52:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:52:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:52:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:52:01 INFO - '--tppagecycles', 19:52:01 INFO - '7'], 19:52:01 INFO - 'tests': ['tsvgm']}}, 19:52:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:52:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:52:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:52:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:52:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:52:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:52:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['damp', 'tps']}, 19:52:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'tests': ['damp', 'tps']}, 19:52:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['dromaeo_dom']}, 19:52:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:52:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['basic_compositor_video']}, 19:52:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:52:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['a11yr', 19:52:01 INFO - 'ts_paint', 19:52:01 INFO - 'tpaint', 19:52:01 INFO - 'sessionrestore', 19:52:01 INFO - 'sessionrestore_no_auto_restore', 19:52:01 INFO - 'tabpaint']}, 19:52:01 INFO - 'other-e10s': {'tests': ['a11yr', 19:52:01 INFO - 'ts_paint', 19:52:01 INFO - 'tpaint', 19:52:01 INFO - 'sessionrestore', 19:52:01 INFO - 'sessionrestore_no_auto_restore', 19:52:01 INFO - 'tabpaint']}, 19:52:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:52:01 INFO - 'ts_paint', 19:52:01 INFO - 'tpaint', 19:52:01 INFO - 'sessionrestore', 19:52:01 INFO - 'sessionrestore_no_auto_restore', 19:52:01 INFO - 'tabpaint']}, 19:52:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:52:01 INFO - 'ts_paint', 19:52:01 INFO - 'tpaint', 19:52:01 INFO - 'sessionrestore', 19:52:01 INFO - 'sessionrestore_no_auto_restore', 19:52:01 INFO - 'tabpaint']}, 19:52:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['a11yr', 19:52:01 INFO - 'ts_paint', 19:52:01 INFO - 'tpaint', 19:52:01 INFO - 'sessionrestore', 19:52:01 INFO - 'sessionrestore_no_auto_restore', 19:52:01 INFO - 'tabpaint']}, 19:52:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['a11yr', 19:52:01 INFO - 'ts_paint', 19:52:01 INFO - 'tpaint', 19:52:01 INFO - 'sessionrestore', 19:52:01 INFO - 'sessionrestore_no_auto_restore', 19:52:01 INFO - 'tabpaint']}, 19:52:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['tsvgx', 19:52:01 INFO - 'tsvgr_opacity', 19:52:01 INFO - 'tart', 19:52:01 INFO - 'tscrollx', 19:52:01 INFO - 'cart']}, 19:52:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:52:01 INFO - 'tsvgr_opacity', 19:52:01 INFO - 'tart', 19:52:01 INFO - 'tscrollx', 19:52:01 INFO - 'cart']}, 19:52:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'talos_options': ['--disable-e10s'], 19:52:01 INFO - 'tests': ['tp5o']}, 19:52:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'tests': ['tp5o']}, 19:52:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'talos_options': ['--disable-e10s', 19:52:01 INFO - '--xperf_path', 19:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:52:01 INFO - 'tests': ['tp5n']}, 19:52:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:01 INFO - 'talos_options': ['--xperf_path', 19:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:52:01 INFO - 'tests': ['tp5n']}}, 19:52:01 INFO - 'talos.zip': {'path': '', 19:52:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:52:01 INFO - Running post-action listener: _resource_record_post_action 19:52:01 INFO - [mozharness: 2016-09-30 02:52:01.211326Z] Finished populate-webroot step (success) 19:52:01 INFO - [mozharness: 2016-09-30 02:52:01.211414Z] Running create-virtualenv step. 19:52:01 INFO - Running pre-action listener: _resource_record_pre_action 19:52:01 INFO - Running main action method: create_virtualenv 19:52:01 INFO - Creating virtualenv /builds/slave/test/build/venv 19:52:01 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 19:52:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:52:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:52:01 INFO - Using real prefix '/tools/python27' 19:52:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:52:02 INFO - Installing distribute.............................................................................................................................................................................................done. 19:52:04 INFO - Installing pip.................done. 19:52:04 INFO - Return code: 0 19:52:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:52:05 INFO - Reading from file tmpfile_stdout 19:52:05 INFO - Output received: 19:52:05 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) 19:52:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:52:05 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')]} 19:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:05 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')]} 19:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:05 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:05 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 19:52:05 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 19:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:05 INFO - 'HOME': '/Users/cltbld', 19:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:05 INFO - 'LOGNAME': 'cltbld', 19:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:05 INFO - 'MOZ_NO_REMOTE': '1', 19:52:05 INFO - 'NO_EM_RESTART': '1', 19:52:05 INFO - 'PAGER': '/bin/cat', 19:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:05 INFO - 'PWD': '/builds/slave/test', 19:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:05 INFO - 'SHELL': '/bin/bash', 19:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:05 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:05 INFO - 'USER': 'cltbld', 19:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:05 INFO - 'XPC_FLAGS': '0x0', 19:52:05 INFO - 'XPC_SERVICE_NAME': '0', 19:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:05 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 19:52:05 INFO - Cleaning up... 19:52:05 INFO - Return code: 0 19:52:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:52:05 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')]} 19:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:05 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')]} 19:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:05 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:05 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 19:52:05 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 19:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:05 INFO - 'HOME': '/Users/cltbld', 19:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:05 INFO - 'LOGNAME': 'cltbld', 19:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:05 INFO - 'MOZ_NO_REMOTE': '1', 19:52:05 INFO - 'NO_EM_RESTART': '1', 19:52:05 INFO - 'PAGER': '/bin/cat', 19:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:05 INFO - 'PWD': '/builds/slave/test', 19:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:05 INFO - 'SHELL': '/bin/bash', 19:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:05 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:05 INFO - 'USER': 'cltbld', 19:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:05 INFO - 'XPC_FLAGS': '0x0', 19:52:05 INFO - 'XPC_SERVICE_NAME': '0', 19:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:05 INFO - Downloading/unpacking psutil>=3.1.1 19:52:05 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 19:52:05 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 19:52:05 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 19:52:05 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 19:52:05 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 19:52:05 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 19:52:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:52:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:52:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:52:09 INFO - Installing collected packages: psutil 19:52:09 INFO - Running setup.py install for psutil 19:52:09 INFO - building 'psutil._psutil_osx' extension 19:52:09 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 19:52:10 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 19:52:10 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 19:52:10 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 19:52:10 INFO - building 'psutil._psutil_posix' extension 19:52:10 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 19:52:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:52:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:52:10 INFO - ^ 19:52:10 INFO - 1 warning generated. 19:52:10 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 19:52:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:52:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:52:10 INFO - Successfully installed psutil 19:52:10 INFO - Cleaning up... 19:52:10 INFO - Return code: 0 19:52:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:10 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:10 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 19:52:10 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 19:52:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:10 INFO - 'HOME': '/Users/cltbld', 19:52:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:10 INFO - 'LOGNAME': 'cltbld', 19:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:10 INFO - 'MOZ_NO_REMOTE': '1', 19:52:10 INFO - 'NO_EM_RESTART': '1', 19:52:10 INFO - 'PAGER': '/bin/cat', 19:52:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:10 INFO - 'PWD': '/builds/slave/test', 19:52:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:10 INFO - 'SHELL': '/bin/bash', 19:52:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:10 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:10 INFO - 'USER': 'cltbld', 19:52:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:10 INFO - 'XPC_FLAGS': '0x0', 19:52:10 INFO - 'XPC_SERVICE_NAME': '0', 19:52:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:52:10 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 19:52:10 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 19:52:10 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 19:52:10 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 19:52:10 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 19:52:10 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 19:52:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:52:13 INFO - Installing collected packages: mozsystemmonitor 19:52:13 INFO - Running setup.py install for mozsystemmonitor 19:52:13 INFO - Successfully installed mozsystemmonitor 19:52:13 INFO - Cleaning up... 19:52:13 INFO - Return code: 0 19:52:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:13 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:13 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 19:52:13 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 19:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:13 INFO - 'HOME': '/Users/cltbld', 19:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:13 INFO - 'LOGNAME': 'cltbld', 19:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:13 INFO - 'MOZ_NO_REMOTE': '1', 19:52:13 INFO - 'NO_EM_RESTART': '1', 19:52:13 INFO - 'PAGER': '/bin/cat', 19:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:13 INFO - 'PWD': '/builds/slave/test', 19:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:13 INFO - 'SHELL': '/bin/bash', 19:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:13 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:13 INFO - 'USER': 'cltbld', 19:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:13 INFO - 'XPC_FLAGS': '0x0', 19:52:13 INFO - 'XPC_SERVICE_NAME': '0', 19:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:13 INFO - Downloading/unpacking jsonschema==2.5.1 19:52:13 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 19:52:13 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 19:52:13 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 19:52:13 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 19:52:13 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 19:52:13 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 19:52:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:52:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:52:16 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 19:52:16 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 19:52:16 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 19:52:16 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 19:52:16 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 19:52:16 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 19:52:16 INFO - Downloading functools32-3.2.3-2.tar.gz 19:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:52:16 INFO - warning: no files found matching '*.txt' 19:52:16 INFO - no previously-included directories found matching 'build' 19:52:16 INFO - no previously-included directories found matching 'dist' 19:52:16 INFO - no previously-included directories found matching '.git*' 19:52:16 INFO - Installing collected packages: jsonschema, functools32 19:52:16 INFO - Running setup.py install for functools32 19:52:17 INFO - warning: no files found matching '*.txt' 19:52:17 INFO - no previously-included directories found matching 'build' 19:52:17 INFO - no previously-included directories found matching 'dist' 19:52:17 INFO - no previously-included directories found matching '.git*' 19:52:17 INFO - Successfully installed jsonschema functools32 19:52:17 INFO - Cleaning up... 19:52:17 INFO - Return code: 0 19:52:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:52:17 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')]} 19:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:17 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')]} 19:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:17 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:17 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 19:52:17 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 19:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:17 INFO - 'HOME': '/Users/cltbld', 19:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:17 INFO - 'LOGNAME': 'cltbld', 19:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:17 INFO - 'MOZ_NO_REMOTE': '1', 19:52:17 INFO - 'NO_EM_RESTART': '1', 19:52:17 INFO - 'PAGER': '/bin/cat', 19:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:17 INFO - 'PWD': '/builds/slave/test', 19:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:17 INFO - 'SHELL': '/bin/bash', 19:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:17 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:17 INFO - 'USER': 'cltbld', 19:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:17 INFO - 'XPC_FLAGS': '0x0', 19:52:17 INFO - 'XPC_SERVICE_NAME': '0', 19:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:52:17 INFO - Cleaning up... 19:52:17 INFO - Return code: 0 19:52:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:52:17 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')]} 19:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:17 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')]} 19:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:17 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:17 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 19:52:17 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 19:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:17 INFO - 'HOME': '/Users/cltbld', 19:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:17 INFO - 'LOGNAME': 'cltbld', 19:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:17 INFO - 'MOZ_NO_REMOTE': '1', 19:52:17 INFO - 'NO_EM_RESTART': '1', 19:52:17 INFO - 'PAGER': '/bin/cat', 19:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:17 INFO - 'PWD': '/builds/slave/test', 19:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:17 INFO - 'SHELL': '/bin/bash', 19:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:17 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:17 INFO - 'USER': 'cltbld', 19:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:17 INFO - 'XPC_FLAGS': '0x0', 19:52:17 INFO - 'XPC_SERVICE_NAME': '0', 19:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:17 INFO - Downloading/unpacking blobuploader==1.2.4 19:52:17 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 19:52:17 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 19:52:17 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 19:52:17 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 19:52:17 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 19:52:17 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 19:52:20 INFO - Downloading blobuploader-1.2.4.tar.gz 19:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:52:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:52:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:21 INFO - Downloading docopt-0.6.1.tar.gz 19:52:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:52:21 INFO - Installing collected packages: blobuploader, requests, docopt 19:52:21 INFO - Running setup.py install for blobuploader 19:52:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:52:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:52:21 INFO - Running setup.py install for requests 19:52:22 INFO - Running setup.py install for docopt 19:52:22 INFO - Successfully installed blobuploader requests docopt 19:52:22 INFO - Cleaning up... 19:52:22 INFO - Return code: 0 19:52:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:22 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:22 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 19:52:22 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 19:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:22 INFO - 'HOME': '/Users/cltbld', 19:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:22 INFO - 'LOGNAME': 'cltbld', 19:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:22 INFO - 'MOZ_NO_REMOTE': '1', 19:52:22 INFO - 'NO_EM_RESTART': '1', 19:52:22 INFO - 'PAGER': '/bin/cat', 19:52:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:22 INFO - 'PWD': '/builds/slave/test', 19:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:22 INFO - 'SHELL': '/bin/bash', 19:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:22 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:22 INFO - 'USER': 'cltbld', 19:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:22 INFO - 'XPC_FLAGS': '0x0', 19:52:22 INFO - 'XPC_SERVICE_NAME': '0', 19:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:52:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-vTew21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:52:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-R1DXV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:52:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-AI_dya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:52:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-cZiPqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:52:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-b_3EoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-BM1ak8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-jrZXVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-wL3wWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Q6NrqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-ckxf1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-MNMGTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-1VGZVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-keF4s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:52:23 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-qgxcHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:24 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-2cvcxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:52:24 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-pN65YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:52:24 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-65BX61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:52:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:52:24 INFO - Running setup.py install for manifestparser 19:52:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:52:24 INFO - Running setup.py install for mozcrash 19:52:24 INFO - Running setup.py install for mozdebug 19:52:24 INFO - Running setup.py install for mozdevice 19:52:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:52:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:52:24 INFO - Running setup.py install for mozfile 19:52:25 INFO - Running setup.py install for mozhttpd 19:52:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Running setup.py install for mozinfo 19:52:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Running setup.py install for mozInstall 19:52:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Running setup.py install for mozleak 19:52:25 INFO - Running setup.py install for mozlog 19:52:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Running setup.py install for moznetwork 19:52:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:52:25 INFO - Running setup.py install for mozprocess 19:52:26 INFO - Running setup.py install for mozprofile 19:52:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:52:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:52:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:52:26 INFO - Running setup.py install for mozrunner 19:52:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:52:26 INFO - Running setup.py install for mozscreenshot 19:52:26 INFO - Running setup.py install for moztest 19:52:26 INFO - Running setup.py install for mozversion 19:52:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:52:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:52:26 INFO - Cleaning up... 19:52:26 INFO - Return code: 0 19:52:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52:26 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')]} 19:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:26 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')]} 19:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:26 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:26 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 19:52:26 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 19:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:26 INFO - 'HOME': '/Users/cltbld', 19:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:26 INFO - 'LOGNAME': 'cltbld', 19:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:26 INFO - 'MOZ_NO_REMOTE': '1', 19:52:26 INFO - 'NO_EM_RESTART': '1', 19:52:26 INFO - 'PAGER': '/bin/cat', 19:52:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:26 INFO - 'PWD': '/builds/slave/test', 19:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:26 INFO - 'SHELL': '/bin/bash', 19:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:26 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:26 INFO - 'USER': 'cltbld', 19:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:26 INFO - 'XPC_FLAGS': '0x0', 19:52:26 INFO - 'XPC_SERVICE_NAME': '0', 19:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-OyNaHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:52:27 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)) 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-w2WkEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-gbzY8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:52:27 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)) 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-18tbcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-v5k8Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:52:27 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)) 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-bsDCdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:52:27 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)) 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-vi74Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:52:27 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)) 19:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:52:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-t4DrK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:52:28 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)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-_PCV9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:52:28 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)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Lr971g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:52:28 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)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-ipk6zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:52:28 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)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-3FhhOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:52:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-pKay8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:52:28 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)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-YamZ4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:52:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-WjgIjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:28 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)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-_hUxAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:52:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:52:28 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-ulbVkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:52:29 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)) 19:52:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:52:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:52:29 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)) 19:52:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:52:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:52:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:31 INFO - Downloading blessings-1.6.tar.gz 19:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:52:31 INFO - Installing collected packages: blessings 19:52:31 INFO - Running setup.py install for blessings 19:52:31 INFO - Successfully installed blessings 19:52:31 INFO - Cleaning up... 19:52:31 INFO - Return code: 0 19:52:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:52:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:52:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:52:32 INFO - Reading from file tmpfile_stdout 19:52:32 INFO - Current package versions: 19:52:32 INFO - blessings == 1.6 19:52:32 INFO - blobuploader == 1.2.4 19:52:32 INFO - docopt == 0.6.1 19:52:32 INFO - functools32 == 3.2.3-2 19:52:32 INFO - jsonschema == 2.5.1 19:52:32 INFO - manifestparser == 1.1 19:52:32 INFO - mozInstall == 1.12 19:52:32 INFO - mozcrash == 0.17 19:52:32 INFO - mozdebug == 0.1 19:52:32 INFO - mozdevice == 0.48 19:52:32 INFO - mozfile == 1.2 19:52:32 INFO - mozhttpd == 0.7 19:52:32 INFO - mozinfo == 0.9 19:52:32 INFO - mozleak == 0.1 19:52:32 INFO - mozlog == 3.4 19:52:32 INFO - moznetwork == 0.27 19:52:32 INFO - mozprocess == 0.23 19:52:32 INFO - mozprofile == 0.28 19:52:32 INFO - mozrunner == 6.12 19:52:32 INFO - mozscreenshot == 0.1 19:52:32 INFO - mozsystemmonitor == 0.3 19:52:32 INFO - moztest == 0.7 19:52:32 INFO - mozversion == 1.4 19:52:32 INFO - psutil == 3.1.1 19:52:32 INFO - requests == 1.2.3 19:52:32 INFO - wsgiref == 0.1.2 19:52:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52: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')]} 19:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52: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')]} 19:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:32 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 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52:32 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 19:52:32 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 19:52:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:32 INFO - 'HOME': '/Users/cltbld', 19:52:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:32 INFO - 'LOGNAME': 'cltbld', 19:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:32 INFO - 'MOZ_NO_REMOTE': '1', 19:52:32 INFO - 'NO_EM_RESTART': '1', 19:52:32 INFO - 'PAGER': '/bin/cat', 19:52:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:32 INFO - 'PWD': '/builds/slave/test', 19:52:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:32 INFO - 'SHELL': '/bin/bash', 19:52:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:32 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:32 INFO - 'USER': 'cltbld', 19:52:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:32 INFO - 'XPC_FLAGS': '0x0', 19:52:32 INFO - 'XPC_SERVICE_NAME': '0', 19:52:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 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)) 19:52:32 INFO - Cleaning up... 19:52:32 INFO - Return code: 0 19:52:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:52: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')]} 19:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52: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')]} 19:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1018d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10184d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc451cbf780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, '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.5biHMDnzAA/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.P8xg1GHTLR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 19:52: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', 'jsonschema'] in /builds/slave/test/build 19:52: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 jsonschema 19:52:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:52:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:32 INFO - 'HOME': '/Users/cltbld', 19:52:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:32 INFO - 'LOGNAME': 'cltbld', 19:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:32 INFO - 'MOZ_NO_REMOTE': '1', 19:52:32 INFO - 'NO_EM_RESTART': '1', 19:52:32 INFO - 'PAGER': '/bin/cat', 19:52:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:32 INFO - 'PWD': '/builds/slave/test', 19:52:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:32 INFO - 'SHELL': '/bin/bash', 19:52:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:52:32 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:52:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:32 INFO - 'USER': 'cltbld', 19:52:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:32 INFO - 'XPC_FLAGS': '0x0', 19:52:32 INFO - 'XPC_SERVICE_NAME': '0', 19:52:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:52:32 INFO - Cleaning up... 19:52:32 INFO - Return code: 0 19:52:32 INFO - Running post-action listener: _resource_record_post_action 19:52:32 INFO - Running post-action listener: _start_resource_monitoring 19:52:32 INFO - Starting resource monitoring. 19:52:32 INFO - [mozharness: 2016-09-30 02:52:32.723077Z] Finished create-virtualenv step (success) 19:52:32 INFO - [mozharness: 2016-09-30 02:52:32.724118Z] Running install step. 19:52:32 INFO - Running pre-action listener: _resource_record_pre_action 19:52:32 INFO - Running main action method: install 19:52:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:52:32 INFO - Reading from file tmpfile_stdout 19:52:32 INFO - Detecting whether we're running mozinstall >=1.0... 19:52:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:52:33 INFO - Reading from file tmpfile_stdout 19:52:33 INFO - Output received: 19:52:33 INFO - Usage: mozinstall [options] installer 19:52:33 INFO - Options: 19:52:33 INFO - -h, --help show this help message and exit 19:52:33 INFO - -d DEST, --destination=DEST 19:52:33 INFO - Directory to install application into. [default: 19:52:33 INFO - "/builds/slave/test"] 19:52:33 INFO - --app=APP Application being installed. [default: firefox] 19:52:33 INFO - mkdir: /builds/slave/test/build/application 19:52:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:53:07 INFO - Reading from file tmpfile_stdout 19:53:07 INFO - Output received: 19:53:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:53:07 INFO - Running post-action listener: _resource_record_post_action 19:53:07 INFO - [mozharness: 2016-09-30 02:53:07.644205Z] Finished install step (success) 19:53:07 INFO - [mozharness: 2016-09-30 02:53:07.644355Z] Running run-tests step. 19:53:07 INFO - Running pre-action listener: _resource_record_pre_action 19:53:07 INFO - Running main action method: run_tests 19:53:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:53:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:53:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:53:07 INFO - 2016-09-29 19:53:07.658 screenresolution[2488:36343] starting screenresolution argv=screenresolution get 19:53:07 INFO - 2016-09-29 19:53:07.661 screenresolution[2488:36343] Display 0: 1600x1200x32@60 19:53:07 INFO - 2016-09-29 19:53:07.666 screenresolution[2489:36347] starting screenresolution argv=screenresolution list 19:53:07 INFO - Available Modes on Display 0 19:53:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:53:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:53:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:53:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:53:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:53:07 INFO - Intel Iris: 19:53:07 INFO - Chipset Model: Intel Iris 19:53:07 INFO - Type: GPU 19:53:07 INFO - Bus: Built-In 19:53:07 INFO - VRAM (Dynamic, Max): 1536 MB 19:53:07 INFO - Vendor: Intel (0x8086) 19:53:07 INFO - Device ID: 0x0a2e 19:53:07 INFO - Revision ID: 0x0009 19:53:07 INFO - Displays: 19:53:07 INFO - Display: 19:53:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:53:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:53:07 INFO - Display Serial Number: 1600x1200 60 19:53:07 INFO - Main Display: Yes 19:53:07 INFO - Mirror: Off 19:53:07 INFO - Online: Yes 19:53:07 INFO - Rotation: Supported 19:53:07 INFO - Return code: 0 19:53:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:53:07 INFO - Python 2.7.3 19:53:07 INFO - Return code: 0 19:53:07 INFO - grabbing minidump binary from tooltool 19:53:07 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')]} 19:53:07 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 0x1016ebd30>, '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 0x101511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016e3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:53:07 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 19:53:07 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 19:53:07 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 19:53:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:53:08 INFO - Return code: 0 19:53:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:53:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:53:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:53:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:53:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:53:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 19:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0189 --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 19:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5biHMDnzAA/Render', 19:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:53:08 INFO - 'HOME': '/Users/cltbld', 19:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:53:08 INFO - 'LOGNAME': 'cltbld', 19:53:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:53:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:53:08 INFO - 'MOZ_NO_REMOTE': '1', 19:53:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:53:08 INFO - 'NO_EM_RESTART': '1', 19:53:08 INFO - 'PAGER': '/bin/cat', 19:53:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:53:08 INFO - 'PWD': '/builds/slave/test', 19:53:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:53:08 INFO - 'SHELL': '/bin/bash', 19:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8xg1GHTLR/Listeners', 19:53:08 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 19:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:53:08 INFO - 'USER': 'cltbld', 19:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:53:08 INFO - 'XPC_FLAGS': '0x0', 19:53:08 INFO - 'XPC_SERVICE_NAME': '0', 19:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:53:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 19:53:08 INFO - mozversion application_buildid: 20160929185419 19:53:08 INFO - mozversion application_changeset: f6a964152400fa1e5340e9909615ac938610f931 19:53:08 INFO - mozversion application_display_name: Nightly 19:53:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:53:08 INFO - mozversion application_name: Firefox 19:53:08 INFO - mozversion application_remotingname: firefox 19:53:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:53:08 INFO - mozversion application_vendor: Mozilla 19:53:08 INFO - mozversion application_version: 52.0a1 19:53:08 INFO - mozversion platform_buildid: 20160929185419 19:53:08 INFO - mozversion platform_changeset: f6a964152400fa1e5340e9909615ac938610f931 19:53:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:53:08 INFO - mozversion platform_version: 52.0a1 19:53:08 INFO - using testdate: 1475203988 19:53:08 INFO - actual date: 1475203988 19:53:08 INFO - starting webserver on 'localhost:62626' 19:53:08 INFO - SUITE-START | Running 2 tests 19:53:08 INFO - TEST-START | dromaeo_css 19:53:08 INFO - operating with platform_type : mac_ 19:53:08 INFO - Initialising browser for dromaeo_css test... 19:53:08 INFO - TEST-INFO | started process 2499 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpWZ8xaL/profile http://localhost:62626/getInfo.html) 19:53:10 INFO - PROCESS | 2499 | __metrics Screen width/height:1600/1200 19:53:10 INFO - PROCESS | 2499 | colorDepth:24 19:53:10 INFO - PROCESS | 2499 | Browser inner width/height: 1024/689 19:53:10 INFO - PROCESS | 2499 | __metrics 19:53:10 INFO - PROCESS | 2499 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:53:10 INFO - PROCESS | 2499 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:53:11 INFO - TEST-INFO | 2499: exit 0 19:53:11 INFO - Browser initialized. 19:53:11 INFO - Running cycle 1/1 for dromaeo_css test... 19:53:11 INFO - TEST-INFO | started process 2501 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpWZ8xaL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:53:23 INFO - PROCESS | 2501 | RSS: Main: 235851776 19:53:23 INFO - PROCESS | 2501 | 19:54:14 INFO - PROCESS | 2501 | 1475204054256 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:56:26 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:62626/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62626/tests/dromaeo/cssquery-ext.html) 19:56:28 INFO - PROCESS | 2501 | RSS: Main: 384528384 19:56:28 INFO - PROCESS | 2501 | 19:59:31 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:62626/tests/dromaeo/cssquery-ext.html (next: http://localhost:62626/tests/dromaeo/cssquery-jquery.html) 19:59:31 INFO - PROCESS | 2501 | RSS: Main: 370720768 19:59:31 INFO - PROCESS | 2501 | 20:02:34 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:62626/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62626/tests/dromaeo/cssquery-mootools.html) 20:02:35 INFO - PROCESS | 2501 | RSS: Main: 339660800 20:02:35 INFO - PROCESS | 2501 | 20:05:37 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:62626/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62626/tests/dromaeo/cssquery-prototype.html) 20:05:37 INFO - PROCESS | 2501 | RSS: Main: 336887808 20:05:37 INFO - PROCESS | 2501 | 20:08:41 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:62626/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62626/tests/dromaeo/cssquery-yui.html) 20:08:41 INFO - PROCESS | 2501 | RSS: Main: 331026432 20:08:41 INFO - PROCESS | 2501 | 20:11:44 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:62626/tests/dromaeo/cssquery-yui.html (next: http://localhost:62626/tests/dromaeo/cssquery-dojo.html) 20:11:45 INFO - PROCESS | 2501 | RSS: Main: 550776832 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | __start_tp_report 20:11:45 INFO - PROCESS | 2501 | _x_x_mozilla_page_load 20:11:45 INFO - PROCESS | 2501 | _x_x_mozilla_page_load_details 20:11:45 INFO - PROCESS | 2501 | |i|pagename|runs| 20:11:45 INFO - PROCESS | 2501 | |0;dojo.html;3323.8171900545467;3326.6340702522716;3342.84957176927;3353.8658453661856;3387.068556147059;5073.289739436482;5417.783288668448;5687.744051517681;5748.425157484255;5781.797637082697;5678.886422271552;5707.343655479621;5734.655920644754;5764.7982320618785;5797.913031304533;4273.978630106861;5243.659162154467;5289.471052894699;5325.600579956519;5329.733513324338;5297.602679799031;5306.548943339821;5334.27987221724;5335.839924802262;5341.626086173969;5199.298094757216;5326.147816349385;5333.57331413486;5374.543163831079;5393.083175860113;5332.626716129873;5336.199570064483;5364.302640656729;5368.087425137736;5381.327334083239;5147.588192944556;5176.534111929945;5190.584753219733;5206.843794686166;5239.161734122522;5186.974065129651;5460.945390546083;5612.045952188097;5662.745176467069;5795.768169273266;6098.054801505774;6203.7828675996125;6210.596311239755;6220.315765265817;6227.719752611143;5075.847724568269;6270.8118756436925;6279.340669229757;6298.496120310364;6315.179026726542;5649.519790817826;5707.657540547539;5736.971315143398;5762.135679648002;5764.365919748824;6257.4681152102585;6311.274203466568;6346.9682651586445;6349.809505714836;6361.077643741724;5444.564434845203;5650.237217975603;6006.609570377911;6015.218021657157;6031.09533569968;6115.082737589397;6141.692915354214;6184.938150618436;6198.659073750987;6200.3799620037635;6153.384661533901;6221.813345599639;6226.719797609027;6268.310485846553;6282.874342513124;6229.719662615194;6268.310485846553;6296.811095667137;6305.8108256752375;6314;4943.1596628573225;4966.925496117561;4978.103941290602;4985;5002.624803139779;4406.184855801687;4427.867163985085;4457.375967364572;4517.141743068806;4517.141743068806;5379.515843574097;5404.972975135099;5756.856078598069;5892.558058145656;5900.940990590039;6259.311475737665;6290.182276304051;6317.273513545909;6329.588576742497;6330.620162790247;5750.30996280449;5816.1566572846705;5831.854203644943;5841.2990441146485;5846.181534585162;5308.654937429054;5378.91931621026;5386.622936394415;5389.487998640123;5437.13005919051;6294.685265736694;6354.745810167541;6384.61692298445;6386.744530218833;6387.329330420239;6288.685565721604;6325.493960483058;6339.904901426575;6340.619562826245;6357.523185761179;6238.906416403667;6310.463610593151;6367.331430199948;6376.489880809617;6382.585131966407;5662.2639056922335;5956.5532585055425;6138.296437970319;6145.494353883327;6268.247810262797;6253.7185826637;6288.11966324724;6316.62100273985;6328.367163283635;6332.271788744353;6053.273607167168;6114.327423983447;6144.8771024580155;6148.385161483995;6171.382861713792;6057.1519987200945;6097.494431795148;6114.69277300208;6127.295361033538;6137.959273351923;5835.3581106077445;5959.1657167995645;5969.970150149222;5974.2233509643465;5978.551608629283;6144.8771024580155;6164.938350616616;6167.105769663463;6172.598781079216;6186.876262474815;5646.520045796154;5692.5730570208225;5701.201831743642;5887.20522729426;5904.468597826216 20:11:45 INFO - PROCESS | 2501 | |1;ext.html;497.1325037809023;505.6106797765718;505.97470126493675;507.55588859747724;511.439973229314;30767.846160769193;31019.224519386997;32091;32100.839495802546;39589.40615890764;38792.418113728316;41551;41702;41713.58286417135;41782.58217417825;9791.216702663787;9980.001999799999;9983.40099594023;9992.550335234931;10000.699979000641;8793.736187914374;9014.098590140999;9789.265805064613;9925.652602158925;9953.153981911546;11986.160968732192;12301.81547276787;12328.383580820968;12329.691757706085;12347.567835125772;12208.32854193019;12305;12305.938470307681;12314.630561083182;12327.69180770479;8770.868436973451;9005.14451127142;9014.278857691435;11919.165658403916;12022.458989345412;31874.681253187635;32165.35669286601;38871.63949261762;39615.80192099021;39707.448271035246;3212.5823642926503;3224.081136875979;3305.735541156702;3416.7437442191685;3728.459373390843;30127.849360753276;30476;30928;31332.530012049836;31355;3250.9187270318193;3287.4411350070304;3292.226326813197;3376.0715803154085;3394.7793393429347;484656;488748;489102;490206;491143;47482.76258618685;48708.512914871106;55548.72225638846;56011;56034.87930241373;57068.714656427284;58145.709271454216;58322.708386458646;61317;61666.691666542276;15695.68608627821;15841.603959901095;15844.683106337809;15847.52457426279;15866;12308.630741077783;12375.257484550775;12383.876161238453;12391.070669699808;12399.25604463735;11595.536178552764;13547.59357219285;13841.515546955809;13848.09987350819;15214.163221022729;5801;5822.82531524055;6838.93161068383;6861.588304701734;6939.826504337432;788.4993029426356;812.979675508117;824.8968878890139;936.8828896387952;940.2055263302499;27895.860520697268;28810.41542715129;28995.420091598473;29711.702882971327;29883.40233195324;1010.0101900137906;1055.5091882274626;1062.0600768320091;1103.7141729884595;1114.9888501115047;14477.466443372368;14569.490067847575;15148.242587870562;15245.771313430307;15258.389664413522;625499;631108;632354;634282;634984;48664.27003594949;49563.50436495589;55229.17156242659;55824;56044;1327.595083499542;1369.5480491437802;1369.9657508562366;1372.3207012528862;1376.8141300924246;29885.87339595421;30094.548581771727;30097.849510752305;30226.093217203517;30568.694313056585;48353.5164648349;48562.5143748558;48604.75697621489;48682.75658621684;48713.75643121762;544011;565126;666703;671755;818379;24869.626955596043;25137.49725005526;25252.873735631205;25294.873525632254;25302;44737.10525789401;45136.871578210805;45283.09433811371;46367;48686.75656621694;37109.628903711695;37500.06249843776;37534.4314744924;38675.806620966716;38974;37495;38159.1696189448;38185.80907095447;38452.230955381296;38538;36341.81829090943;36902.8154859224;37289.0677733048;37328.81335593413;37667.81166094152;32795.508067378534;33314.5002824953;33700.32599348048;33958.66041339555;34290.48564271588;24974.250772476855;24982.50034999326;25316.620185723765;25389.87305063463;25688 20:11:45 INFO - PROCESS | 2501 | |2;jquery.html;344.2821716720637;362.70802004386456;365;370.6608453265262;387.6743535430238;13915.304234788271;14024.368903399347;15894.20528973551;20184.293549725764;20323.49191270219;16142.273597688103;17762.555936101602;19332.51668708283;19677.90161049193;20086.598268034668;5495.422980587041;6168.414000669929;6171.969140154315;6179.81460556184;6181.969090154543;4191.979040104795;4693.621884935391;6073.271207455112;6098.420650038262;6173.043178307369;4381.43041404617;4424.380586717862;6324.905126423085;6667.899981500257;6667.932940788347;6160.398296442957;6465.709043093072;6634.137562116942;6728.293529179439;6733.225679046899;5701;5701.629394089412;5702.173184888209;5702.714864256812;5702.971485142548;19231.423057308304;19670.016499174984;25506;25664.358391040187;25832.612510812352;2221.2058542793625;2244.450109723128;2858.542633178702;3116.1586371679746;3159.8104113753247;14876;15232.619184520365;15238.161901095435;15933.840661593467;16071.955322903974;1693.9068351240676;1827.7806663200367;1873.4754268804757;1910.2550005497867;2018.8183063524352;111413.22004248407;121861;123534.38232808869;124272;125916.74083259141;9730.221582273402;10637.46812659364;10642.467876606293;10823.729406764895;11178.944105279421;14190.503332383318;14204.218767967654;14215.573532794033;14224.217668028357;14230;8059.758207253792;8403.873941890877;8408.075111737704;8493.787655308543;8520.275776558938;7128.964355178295;8259.967504061991;8351.248387645019;8365.330773538224;8557.058723540405;3925.9607403926166;4260.126674031816;4305.892352691208;4407.823687052482;4459;1713.6487020160837;1746.9301227950739;1803.167511070318;1828.0768212052828;1876.080720446971;711.9466040047017;712.7965849300089;781.0978320040363;913.3743385780762;927.3925578745932;18197;19029.333973310866;19732;21331.46671333198;21387;844.907060223375;865.8786871002068;903.977400564978;908.4004556992353;942.3074040580168;10579.576816927236;11056;11171;11175;11208.943955280172;585847;589376;594285;599955;601185;98662;111208;128677.35661321634;136082.3195884034;141323;962.3215632978734;994.0904072773332;999.8300288950895;1012.7670635753818;1016.3089099412323;14993.400263989319;19041.42875713731;19758.913259770852;19849.503762406057;19858.801411985696;58181;58260.126098107714;58602.413975859694;59487;60084.399156009626;74764.62617686877;75172.62413687896;84591;85595;86956.56521717351;6255.40573645503;7988.760337189894;8059.153788852321;8188.058373287148;8388.328933685412;9271.953640231755;12524.935380492396;12599;12977.675558111123;13448.731025379633;10889.618863339745;10922.781544368909;11437.59968401102;11472.598459054227;11703.2978021319;9471.810563788824;11020.779584408107;11221.270617409842;11268.38023908693;11615.825762613398;10785.352878827296;11885.940570297093;11889.405529723306;12018.519259229533;12036.458359373673;7965.084015338311;7982.6806927724565;8164.591770411337;8251.349730053893;8373.37199710012;8270.793230169053;9020.278377729635;11298.661040168809;11452.198346115692;11589.072874170215 20:11:45 INFO - PROCESS | 2501 | |3;mootools.html;1393.6376542099051;1487.5314276003062;1505.1420690206583;1854.1100271869493;1881.1816859666046;8414.705485308015;8883;8888.555572221396;8896.733098007062;8912.509811960339;7955.960220198891;8170.91829081709;8175.3050990665715;8453.78865528362;8656.047834738189;2307.8038366738806;2345.7067866516686;2346.5424242272766;2346.791402427751;2348.988255058722;2358.575456417844;2379.5716770981217;2394.353524548371;2394.8443351182204;2398.2205783120467;2090.9336238518313;2168.8698678079286;2303.4586872085047;2373.3354660694945;2390.8445951013214;2366.1127077345996;2388.605880029793;2391.1152873436768;2392.3062311929434;2393.8324317297797;2328.2898715891647;2359.197872723282;2360.6223004319227;2364.1725396111397;2370.3955491349598;8725.956370218108;8902.554872256362;8909.020007799138;8910.420822646507;8910.599023043978;5133.6149788765615;5283.577313814885;5284.973575132138;5287.154055351164;5293.682379057269;2813.9718602813914;3427.845746941394;3455.3434847378917;3592.910177245564;3594.245208506217;1062.2882668611971;1069.620284798893;1077.0737166037202;1081.8052750504905;1089.411717672456;11070.778584428346;11114.388708621023;11133.944330278297;11157;11191.552337906556;4366.628836548865;4440.489343725449;4537.818487260539;4569.200389931749;4577.954220457819;9131.81736365269;9132.132447417485;9135;9137.497437640994;9140.54297285133;5256.92114618281;5258.6581872178185;5259.500347466985;5263.34208223972;5263.421023687391;4746.644001699887;4861.51384861511;4922.507749225048;4923;4938.9259161111895;3489.7906125632544;3513.1392808761507;3542.2561262134723;3573.5175751273246;3589.8205089745406;980.2109302011949;1044.9791004179874;1087.2389327470803;1108.036008672444;1113.8941800528937;627.9152314437583;779.380392587894;867.444835305405;902.9774255643662;926.8563372677246;5412.56699464042;5592.384837667853;5794.101914203306;5808.361080281165;5896.557758168155;820.9343252539783;922.3543519536352;929.1823195587839;947.6446332625266;950.933434659581;3304.454764963754;3879.8254078566533;3898.2847547079195;4015.417764424142;4030.3551431770775;10303.948480257552;10579.153667706565;10583.57665693379;10594.152467802558;10595.894041059644;10203.9489802552;10448.164146868385;10532.210084243712;10537.209709271836;10553.577856885791;8115.3913456491;8125.187481251827;8312.83374332522;8330.583470826434;8350.164983501723;5786.537077033911;5805.767769289181;5874.383189765013;5924.9703751480965;5977.163197152315;10069.295149339476;10105.747356316151;10114.342567733074;10119.79760404773;10181.440020798927;9858.457784821903;10246.590136394461;10355.741106472398;10381.84427233577;10421.957804219517;7030.261822508767;7154.964225178841;7161.39128174111;7165.964170179116;7167.784966451015;8194.713185038496;8266.421350505407;8309.376796740147;8352.248297653241;8358.916410836056;7336.193018768038;7408.111026676617;7456.552606843607;7509.249075092447;7534.35957943581;7269.382102521345;7405.148407932941;7406.962965185139;7416.851662966603;7464.850702985801;6846.760363387258;6866.793996180123;6957.930420695729;6968.965155174192;6970.756023539152;4932.457429682804;4944.1100601892;5012.147934851011;5053.494650534917;5222.425533191422;4987.202047672355;5024.773885175103;5070.873228169326;5085.906530096047;5216.895662086813 20:11:45 INFO - PROCESS | 2501 | |4;prototype.html;248.68292926518674;298.9745871600914;308.03431243053024;312.2225658111302;315.13652591898204;5454.563634909207;5472.945270547293;5480.534154596864;5520.515767904444;5529.308836395456;4093.973483125857;5091.134507133787;5159.793608255665;5278.815241466549;5391.514763671269;4092.5907409259135;4114.202342407162;4120.402541631476;4126.649234815044;4152.1695660867945;4091.652209562176;4111.280525907969;4127.29835927893;4128.071183983609;4182.9790851045855;3805.9809700951455;4036.2129384769983;4115.73247738896;4130.359794231884;4169.685824367475;4065;4099.7745124018165;4105.486814148247;4109.609587089251;4124.1957818225455;3104.8292343921075;3443.6728510791436;3517.191046059395;3521.2605352875926;3537.4870643756767;4788.736619485927;5018.7992480300745;5329.413764485904;5381.784728610851;5383.865403364908;4594.402727645418;4643.326717625925;4645.837395691135;4664;4680.3447517347595;4860.590428775686;5573.773769770644;5689.800856969986;5713.685747283897;5758.74085666146;3215.6462789093416;3525.2596954639557;3806.809659517013;3834.69322454203;3842.1547259602844;5431.8940196215435;5603.663780173202;5969.373215812364;5998.670073145975;6076.848078798022;3180.204948762809;3222.3394204188085;3860.9227815443533;3863.4784304118584;3870.3807390817333;3908.511436070491;3926.332523471017;3951.3480275754177;3964.52425708911;3966.8809935701975;3871.7483363581277;4107.815148318333;4556.152404747033;4591.655625828076;4600.81461305877;5193;5670.404607516234;5700.37295897451;5701.462849032224;5710.7430165642645;1033.106362996003;1270.6315168601002;1300.4213125159401;1308.6532069001532;1340.9262490563112;828.3787159630277;843.1610547505271;864.1617630898019;865.5369377383196;867.9739607811775;4330.350447432847;4496.820127194941;4515.458145022552;4526.59260666535;4577.954220457819;4602.3786788783755;4632.305154226893;4684.227102528044;4907.509249075064;4970.378702662168;4132.73137246078;4171.290880550314;4307.741535507879;4480.798364073625;4522.253828118323;2311.260396673082;2569.021853173046;2572.9871350643125;2583.276682528895;2585.6767904012;4311;4429.689921705514;4606.769661516911;5041.6722913010535;5045.747712614355;4278.379634952915;4499.290269697494;4755.762211889391;4942.874563937469;4973.751312434364;4642.837500687528;4657.743824089775;4790.5568160237835;4966.255061740768;5001.4998500149695;4358.4769827620885;4522.5929666328875;4867.637061622751;4870.610351171827;4874.683145595525;4184.225918205142;4390.978045109754;4735.881602959954;4901.9950910062635;5750.367459579526;5177.844664660066;5299.920501192408;6053.9091913622215;6067.848303792441;6115.235595550556;4028.8992775180855;4072.144849581621;4074.7758873262255;4077.8164982575263;4079.8776036718946;5622.578306626938;5904.261967254093;5952.880942381042;5957.940420595739;6007.339192688713;5746.367899530965;5762.4525670061275;5771.422857714195;5780.277465316835;5793.594448388572;5034.622403319692;5528.75120619565;5739.053056245672;5750.712464376848;5767.480926716616;5321.148616221386;5534;5590.804321848878;5595.384507704231;5615.326160860723;4848.369711937426;5042.621803364836;5370.543503802221;5588.133839254922;5730.856728581819;5569.749361278833;5570.693611851225;5572;5603.887922241451;5634.661920284796 20:11:45 INFO - PROCESS | 2501 | |5;yui.html;703.8205257659297;712.3375261007264;768.8270139218677;805.2712295372689;821.6795449774587;15102.697946041086;15554;15566.221688915566;15575.065496070216;15586.922065389657;13898.652533686638;13952.44190232392;13984.16095034296;14013.159210447355;14017.64955876101;653.1345966594258;676.0501495398972;678.5792808458763;803.9839203215932;804.8470790549806;822.1490757066438;830.2237408023517;833.529056083314;835.6448509383519;838.8406202821475;886.756142060932;889.0709208876739;890.0565400675273;890.4212262029667;894.1818236313765;693.7953303775348;884.8451520983866;886.3396769406801;886.6497733395305;888.9422187557788;527.6122050293031;629.2889035390026;642.7493277621732;648.0764910003227;649.4349915573434;14696.971212015163;14709.852901470955;14737.336819843133;14749.410023599043;14758.40966361355;13007.674808129777;13446.260455674934;13490.93254533731;13566.389512471864;13808.861911380958;12340.938295308555;12691.873081269254;12726.10917235794;12738.745225095447;13253.801192982113;4505.662075344362;4532.841350552715;4579.061292435043;4661.953380466186;4677.415323084614;110754.44622776835;112878;113589;113914.99999999917;114002;8763.780905477413;8917.375783695079;10334.586616535405;10519.211059170593;11018.283811552223;21490.78509214888;21675;21761;21792.346229613137;22210.333689989326;7637.618119094023;7689.4232932530285;7694.884576731353;7848.568328741974;7969.003874515685;3632.2735452908996;4183.3515805050265;4188.643965262926;4195.811188496525;4362.17118747437;3118.095752231827;3284.901452956415;3505.386557352453;3520.1023738946406;3522.1546828761425;624.6501958903029;634.5589815078569;642.8007317731518;647.8801421736994;660.9008648702637;609.20194545146;609.8018144103185;621.5114207671891;621.8662987457718;627.5324882962223;7435.293647103516;8721.258693011037;8852.114788521225;9117.179453849185;9214.419358862417;578.8023548534187;583.547750493371;619.6591874469024;649.6232185332495;650.9186351706037;3381.9830900845336;4364.781760911942;4493.438320209973;4560.771961401983;4656.278276867091;95402;124696;155889;163641;167038;77982.61008694921;100154;101364.09759121077;105594.47202763936;112517;63951.680241599424;64903.37741556404;106635.46682266695;106675;107733.46133269284;9329.507278835625;9611.807763844823;9939.602415903282;9960.800783984134;10002.299839011199;59091.70454147702;60122.39877601168;60966.39033609607;61093;63251.36748632455;49587.26444574423;82330.94172645731;82939;85589;89967;13144.633965886233;13338.733225335633;13360.532381366605;13406.664833378853;13737.931310343385;14720.77918831197;14898.77651835245;14952.700945981236;15053.77419338689;15740.291686873888;12375.381230938598;13480.86519134796;13938.860611394162;13954.930225348808;14073.155610663393;13220.867791321964;13293.40719668644;13916;13989;14488.235730720617;13630.863691363356;13632.250226237255;13777.655558611115;13843.584692459242;13873.72252554923;11344.829827552758;11350.886491135314;11410.63331506323;11501.367424791717;11655.941720291345;8102.7894440541;8902.688405905763;9392.624295028123;11650.825237621273;11661.591844285409 20:11:45 INFO - PROCESS | 2501 | __end_tp_report 20:11:45 INFO - PROCESS | 2501 | __start_cc_report 20:11:45 INFO - PROCESS | 2501 | _x_x_mozilla_cycle_collect,1287 20:11:45 INFO - PROCESS | 2501 | __end_cc_report 20:11:45 INFO - PROCESS | 2501 | __startTimestamp1475205105088__endTimestamp 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | ------- Summary: start ------- 20:11:45 INFO - PROCESS | 2501 | Number of tests: 6 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | [#0] dojo.html Cycles:165 Average:5737.73 Median:5892.56 stddev:648.17 (11.0%) stddev-sans-first:621.89 20:11:45 INFO - PROCESS | 2501 | Values: 3323.8 3326.6 3342.8 3353.9 3387.1 5073.3 5417.8 5687.7 5748.4 5781.8 5678.9 5707.3 5734.7 5764.8 5797.9 4274.0 5243.7 5289.5 5325.6 5329.7 5297.6 5306.5 5334.3 5335.8 5341.6 5199.3 5326.1 5333.6 5374.5 5393.1 5332.6 5336.2 5364.3 5368.1 5381.3 5147.6 5176.5 5190.6 5206.8 5239.2 5187.0 5460.9 5612.0 5662.7 5795.8 6098.1 6203.8 6210.6 6220.3 6227.7 5075.8 6270.8 6279.3 6298.5 6315.2 5649.5 5707.7 5737.0 5762.1 5764.4 6257.5 6311.3 6347.0 6349.8 6361.1 5444.6 5650.2 6006.6 6015.2 6031.1 6115.1 6141.7 6184.9 6198.7 6200.4 6153.4 6221.8 6226.7 6268.3 6282.9 6229.7 6268.3 6296.8 6305.8 6314.0 4943.2 4966.9 4978.1 4985.0 5002.6 4406.2 4427.9 4457.4 4517.1 4517.1 5379.5 5405.0 5756.9 5892.6 5900.9 6259.3 6290.2 6317.3 6329.6 6330.6 5750.3 5816.2 5831.9 5841.3 5846.2 5308.7 5378.9 5386.6 5389.5 5437.1 6294.7 6354.7 6384.6 6386.7 6387.3 6288.7 6325.5 6339.9 6340.6 6357.5 6238.9 6310.5 6367.3 6376.5 6382.6 5662.3 5956.6 6138.3 6145.5 6268.2 6253.7 6288.1 6316.6 6328.4 6332.3 6053.3 6114.3 6144.9 6148.4 6171.4 6057.2 6097.5 6114.7 6127.3 6138.0 5835.4 5959.2 5970.0 5974.2 5978.6 6144.9 6164.9 6167.1 6172.6 6186.9 5646.5 5692.6 5701.2 5887.2 5904.5 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | [#1] ext.html Cycles:180 Average:71089.61 Median:20419.04 stddev:160371.92 (785.4%) stddev-sans-first:160734.23 20:11:45 INFO - PROCESS | 2501 | Values: 497.1 505.6 506.0 507.6 511.4 30767.8 31019.2 32091.0 32100.8 39589.4 38792.4 41551.0 41702.0 41713.6 41782.6 9791.2 9980.0 9983.4 9992.6 10000.7 8793.7 9014.1 9789.3 9925.7 9953.2 11986.2 12301.8 12328.4 12329.7 12347.6 12208.3 12305.0 12305.9 12314.6 12327.7 8770.9 9005.1 9014.3 11919.2 12022.5 31874.7 32165.4 38871.6 39615.8 39707.4 3212.6 3224.1 3305.7 3416.7 3728.5 30127.8 30476.0 30928.0 31332.5 31355.0 3250.9 3287.4 3292.2 3376.1 3394.8 484656.0 488748.0 489102.0 490206.0 491143.0 47482.8 48708.5 55548.7 56011.0 56034.9 57068.7 58145.7 58322.7 61317.0 61666.7 15695.7 15841.6 15844.7 15847.5 15866.0 12308.6 12375.3 12383.9 12391.1 12399.3 11595.5 13547.6 13841.5 13848.1 15214.2 5801.0 5822.8 6838.9 6861.6 6939.8 788.5 813.0 824.9 936.9 940.2 27895.9 28810.4 28995.4 29711.7 29883.4 1010.0 1055.5 1062.1 1103.7 1115.0 14477.5 14569.5 15148.2 15245.8 15258.4 625499.0 631108.0 632354.0 634282.0 634984.0 48664.3 49563.5 55229.2 55824.0 56044.0 1327.6 1369.5 1370.0 1372.3 1376.8 29885.9 30094.5 30097.8 30226.1 30568.7 48353.5 48562.5 48604.8 48682.8 48713.8 544011.0 565126.0 666703.0 671755.0 818379.0 24869.6 25137.5 25252.9 25294.9 25302.0 44737.1 45136.9 45283.1 46367.0 48686.8 37109.6 37500.1 37534.4 38675.8 38974.0 37495.0 38159.2 38185.8 38452.2 38538.0 36341.8 36902.8 37289.1 37328.8 37667.8 32795.5 33314.5 33700.3 33958.7 34290.5 24974.3 24982.5 25316.6 25389.9 25688.0 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | [#2] jquery.html Cycles:180 Average:34990.58 Median:1752.05 stddev:99300.20 (5667.7%) stddev-sans-first:99544.69 20:11:45 INFO - PROCESS | 2501 | Values: 344.3 362.7 365.0 370.7 387.7 13915.3 14024.4 15894.2 20184.3 20323.5 16142.3 17762.6 19332.5 19677.9 20086.6 5495.4 6168.4 6172.0 6179.8 6182.0 4192.0 4693.6 6073.3 6098.4 6173.0 4381.4 4424.4 6324.9 6667.9 6667.9 6160.4 6465.7 6634.1 6728.3 6733.2 5701.0 5701.6 5702.2 5702.7 5703.0 19231.4 19670.0 25506.0 25664.4 25832.6 2221.2 2244.5 2858.5 3116.2 3159.8 14876.0 15232.6 15238.2 15933.8 16072.0 1693.9 1827.8 1873.5 1910.3 2018.8 111413.2 121861.0 123534.4 124272.0 125916.7 9730.2 10637.5 10642.5 10823.7 11178.9 14190.5 14204.2 14215.6 14224.2 14230.0 8059.8 8403.9 8408.1 8493.8 8520.3 7129.0 8260.0 8351.2 8365.3 8557.1 3926.0 4260.1 4305.9 4407.8 4459.0 1713.6 1746.9 1803.2 1828.1 1876.1 711.9 712.8 781.1 913.4 927.4 18197.0 19029.3 19732.0 21331.5 21387.0 844.9 865.9 904.0 908.4 942.3 10579.6 11056.0 11171.0 11175.0 11208.9 585847.0 589376.0 594285.0 599955.0 601185.0 98662.0 111208.0 128677.4 136082.3 141323.0 962.3 994.1 999.8 1012.8 1016.3 14993.4 19041.4 19758.9 19849.5 19858.8 58181.0 58260.1 58602.4 59487.0 60084.4 74764.6 75172.6 84591.0 85595.0 86956.6 6255.4 7988.8 8059.2 8188.1 8388.3 9272.0 12524.9 12599.0 12977.7 13448.7 10889.6 10922.8 11437.6 11472.6 11703.3 9471.8 11020.8 11221.3 11268.4 11615.8 10785.4 11885.9 11889.4 12018.5 12036.5 7965.1 7982.7 8164.6 8251.3 8373.4 8270.8 9020.3 11298.7 11452.2 11589.1 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | [#3] mootools.html Cycles:180 Average:5627.23 Median:4999.67 stddev:3154.43 (63.1%) stddev-sans-first:3147.24 20:11:45 INFO - PROCESS | 2501 | Values: 1393.6 1487.5 1505.1 1854.1 1881.2 8414.7 8883.0 8888.6 8896.7 8912.5 7956.0 8170.9 8175.3 8453.8 8656.0 2307.8 2345.7 2346.5 2346.8 2349.0 2358.6 2379.6 2394.4 2394.8 2398.2 2090.9 2168.9 2303.5 2373.3 2390.8 2366.1 2388.6 2391.1 2392.3 2393.8 2328.3 2359.2 2360.6 2364.2 2370.4 8726.0 8902.6 8909.0 8910.4 8910.6 5133.6 5283.6 5285.0 5287.2 5293.7 2814.0 3427.8 3455.3 3592.9 3594.2 1062.3 1069.6 1077.1 1081.8 1089.4 11070.8 11114.4 11133.9 11157.0 11191.6 4366.6 4440.5 4537.8 4569.2 4578.0 9131.8 9132.1 9135.0 9137.5 9140.5 5256.9 5258.7 5259.5 5263.3 5263.4 4746.6 4861.5 4922.5 4923.0 4938.9 3489.8 3513.1 3542.3 3573.5 3589.8 980.2 1045.0 1087.2 1108.0 1113.9 627.9 779.4 867.4 903.0 926.9 5412.6 5592.4 5794.1 5808.4 5896.6 820.9 922.4 929.2 947.6 950.9 3304.5 3879.8 3898.3 4015.4 4030.4 10303.9 10579.2 10583.6 10594.2 10595.9 10203.9 10448.2 10532.2 10537.2 10553.6 8115.4 8125.2 8312.8 8330.6 8350.2 5786.5 5805.8 5874.4 5925.0 5977.2 10069.3 10105.7 10114.3 10119.8 10181.4 9858.5 10246.6 10355.7 10381.8 10422.0 7030.3 7155.0 7161.4 7166.0 7167.8 8194.7 8266.4 8309.4 8352.2 8358.9 7336.2 7408.1 7456.6 7509.2 7534.4 7269.4 7405.1 7407.0 7416.9 7464.9 6846.8 6866.8 6957.9 6969.0 6970.8 4932.5 4944.1 5012.1 5053.5 5222.4 4987.2 5024.8 5070.9 5085.9 5216.9 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | [#4] prototype.html Cycles:180 Average:4396.13 Median:4651.79 stddev:1356.84 (29.2%) stddev-sans-first:1324.46 20:11:45 INFO - PROCESS | 2501 | Values: 248.7 299.0 308.0 312.2 315.1 5454.6 5472.9 5480.5 5520.5 5529.3 4094.0 5091.1 5159.8 5278.8 5391.5 4092.6 4114.2 4120.4 4126.6 4152.2 4091.7 4111.3 4127.3 4128.1 4183.0 3806.0 4036.2 4115.7 4130.4 4169.7 4065.0 4099.8 4105.5 4109.6 4124.2 3104.8 3443.7 3517.2 3521.3 3537.5 4788.7 5018.8 5329.4 5381.8 5383.9 4594.4 4643.3 4645.8 4664.0 4680.3 4860.6 5573.8 5689.8 5713.7 5758.7 3215.6 3525.3 3806.8 3834.7 3842.2 5431.9 5603.7 5969.4 5998.7 6076.8 3180.2 3222.3 3860.9 3863.5 3870.4 3908.5 3926.3 3951.3 3964.5 3966.9 3871.7 4107.8 4556.2 4591.7 4600.8 5193.0 5670.4 5700.4 5701.5 5710.7 1033.1 1270.6 1300.4 1308.7 1340.9 828.4 843.2 864.2 865.5 868.0 4330.4 4496.8 4515.5 4526.6 4578.0 4602.4 4632.3 4684.2 4907.5 4970.4 4132.7 4171.3 4307.7 4480.8 4522.3 2311.3 2569.0 2573.0 2583.3 2585.7 4311.0 4429.7 4606.8 5041.7 5045.7 4278.4 4499.3 4755.8 4942.9 4973.8 4642.8 4657.7 4790.6 4966.3 5001.5 4358.5 4522.6 4867.6 4870.6 4874.7 4184.2 4391.0 4735.9 4902.0 5750.4 5177.8 5299.9 6053.9 6067.8 6115.2 4028.9 4072.1 4074.8 4077.8 4079.9 5622.6 5904.3 5952.9 5957.9 6007.3 5746.4 5762.5 5771.4 5780.3 5793.6 5034.6 5528.8 5739.1 5750.7 5767.5 5321.1 5534.0 5590.8 5595.4 5615.3 4848.4 5042.6 5370.5 5588.1 5730.9 5569.7 5570.7 5572.0 5603.9 5634.7 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - PROCESS | 2501 | [#5] yui.html Cycles:180 Average:22918.00 Median:4192.23 stddev:35947.32 (857.5%) stddev-sans-first:36009.47 20:11:45 INFO - PROCESS | 2501 | Values: 703.8 712.3 768.8 805.3 821.7 15102.7 15554.0 15566.2 15575.1 15586.9 13898.7 13952.4 13984.2 14013.2 14017.6 653.1 676.1 678.6 804.0 804.8 822.1 830.2 833.5 835.6 838.8 886.8 889.1 890.1 890.4 894.2 693.8 884.8 886.3 886.6 888.9 527.6 629.3 642.7 648.1 649.4 14697.0 14709.9 14737.3 14749.4 14758.4 13007.7 13446.3 13490.9 13566.4 13808.9 12340.9 12691.9 12726.1 12738.7 13253.8 4505.7 4532.8 4579.1 4662.0 4677.4 110754.4 112878.0 113589.0 113915.0 114002.0 8763.8 8917.4 10334.6 10519.2 11018.3 21490.8 21675.0 21761.0 21792.3 22210.3 7637.6 7689.4 7694.9 7848.6 7969.0 3632.3 4183.4 4188.6 4195.8 4362.2 3118.1 3284.9 3505.4 3520.1 3522.2 624.7 634.6 642.8 647.9 660.9 609.2 609.8 621.5 621.9 627.5 7435.3 8721.3 8852.1 9117.2 9214.4 578.8 583.5 619.7 649.6 650.9 3382.0 4364.8 4493.4 4560.8 4656.3 95402.0 124696.0 155889.0 163641.0 167038.0 77982.6 100154.0 101364.1 105594.5 112517.0 63951.7 64903.4 106635.5 106675.0 107733.5 9329.5 9611.8 9939.6 9960.8 10002.3 59091.7 60122.4 60966.4 61093.0 63251.4 49587.3 82330.9 82939.0 85589.0 89967.0 13144.6 13338.7 13360.5 13406.7 13737.9 14720.8 14898.8 14952.7 15053.8 15740.3 12375.4 13480.9 13938.9 13954.9 14073.2 13220.9 13293.4 13916.0 13989.0 14488.2 13630.9 13632.3 13777.7 13843.6 13873.7 11344.8 11350.9 11410.6 11501.4 11655.9 8102.8 8902.7 9392.6 11650.8 11661.6 20:11:45 INFO - PROCESS | 2501 | -------- Summary: end -------- 20:11:45 INFO - PROCESS | 2501 | 20:11:45 INFO - TEST-INFO | 2501: exit 0 20:11:45 INFO - TEST-OK | dromaeo_css | took 1117546ms 20:11:45 INFO - TEST-START | kraken 20:11:45 INFO - operating with platform_type : mac_ 20:11:45 INFO - Initialising browser for kraken test... 20:11:45 INFO - TEST-INFO | started process 2504 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpSZyjN7/profile http://localhost:62626/getInfo.html) 20:11:48 INFO - PROCESS | 2504 | __metrics Screen width/height:1600/1200 20:11:48 INFO - PROCESS | 2504 | colorDepth:24 20:11:48 INFO - PROCESS | 2504 | Browser inner width/height: 1024/689 20:11:48 INFO - PROCESS | 2504 | __metrics 20:11:48 INFO - PROCESS | 2504 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:11:48 INFO - PROCESS | 2504 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:11:48 INFO - TEST-INFO | 2504: exit 0 20:11:48 INFO - Browser initialized. 20:11:48 INFO - Running cycle 1/1 for kraken test... 20:11:48 INFO - TEST-INFO | started process 2505 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpSZyjN7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:12:00 INFO - PROCESS | 2505 | RSS: Main: 236158976 20:12:00 INFO - PROCESS | 2505 | 20:12:03 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62626/tests/kraken/driver.html?testName=audio-beat-detection) 20:12:04 INFO - PROCESS | 2505 | RSS: Main: 295563264 20:12:04 INFO - PROCESS | 2505 | 20:12:06 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62626/tests/kraken/driver.html?testName=audio-dft) 20:12:07 INFO - PROCESS | 2505 | RSS: Main: 294780928 20:12:07 INFO - PROCESS | 2505 | 20:12:11 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62626/tests/kraken/driver.html?testName=audio-fft) 20:12:11 INFO - PROCESS | 2505 | RSS: Main: 384593920 20:12:11 INFO - PROCESS | 2505 | 20:12:13 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62626/tests/kraken/driver.html?testName=audio-oscillator) 20:12:14 INFO - PROCESS | 2505 | RSS: Main: 287158272 20:12:14 INFO - PROCESS | 2505 | 20:12:16 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62626/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:12:16 INFO - PROCESS | 2505 | RSS: Main: 291110912 20:12:16 INFO - PROCESS | 2505 | 20:12:19 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62626/tests/kraken/driver.html?testName=imaging-darkroom) 20:12:20 INFO - PROCESS | 2505 | RSS: Main: 328970240 20:12:20 INFO - PROCESS | 2505 | 20:12:22 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62626/tests/kraken/driver.html?testName=imaging-desaturate) 20:12:23 INFO - PROCESS | 2505 | RSS: Main: 328491008 20:12:23 INFO - PROCESS | 2505 | 20:12:26 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62626/tests/kraken/driver.html?testName=json-parse-financial) 20:12:26 INFO - PROCESS | 2505 | RSS: Main: 329392128 20:12:26 INFO - PROCESS | 2505 | 20:12:28 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62626/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:12:29 INFO - PROCESS | 2505 | RSS: Main: 304750592 20:12:29 INFO - PROCESS | 2505 | 20:12:31 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:12:31 INFO - PROCESS | 2505 | RSS: Main: 308002816 20:12:31 INFO - PROCESS | 2505 | 20:12:33 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:12:34 INFO - PROCESS | 2505 | RSS: Main: 312737792 20:12:34 INFO - PROCESS | 2505 | 20:12:37 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:12:38 INFO - PROCESS | 2505 | RSS: Main: 323612672 20:12:38 INFO - PROCESS | 2505 | 20:12:40 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:12:41 INFO - PROCESS | 2505 | RSS: Main: 319991808 20:12:41 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:62626/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62626/tests/kraken/driver.html?testName=ai-astar) 20:12:43 INFO - PROCESS | 2505 | RSS: Main: 363188224 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | __start_tp_report 20:12:43 INFO - PROCESS | 2505 | _x_x_mozilla_page_load 20:12:43 INFO - PROCESS | 2505 | _x_x_mozilla_page_load_details 20:12:43 INFO - PROCESS | 2505 | |i|pagename|runs| 20:12:43 INFO - PROCESS | 2505 | |0;ai-astar;115;113;115;114;114;113;112;113;112;113 20:12:43 INFO - PROCESS | 2505 | |1;audio-beat-detection;148;111;108;147;109;110;108;109;140;106 20:12:43 INFO - PROCESS | 2505 | |2;audio-dft;186;182;184;183;185;185;184;184;184;182 20:12:43 INFO - PROCESS | 2505 | |3;audio-fft;81;60;59;88;63;60;58;60;84;58 20:12:43 INFO - PROCESS | 2505 | |4;audio-oscillator;92;71;91;70;68;69;91;68;69;90 20:12:43 INFO - PROCESS | 2505 | |5;imaging-gaussian-blur;88;85;88;87;88;86;88;85;87;85 20:12:43 INFO - PROCESS | 2505 | |6;imaging-darkroom;93;94;97;93;93;94;92;94;91;118 20:12:43 INFO - PROCESS | 2505 | |7;imaging-desaturate;85;85;86;86;87;85;83;84;86;83 20:12:43 INFO - PROCESS | 2505 | |8;json-parse-financial;56;56;53;53;56;54;55;55;55;55 20:12:43 INFO - PROCESS | 2505 | |9;json-stringify-tinderbox;65;67;81;77;74;70;71;70;71;74 20:12:43 INFO - PROCESS | 2505 | |10;stanford-crypto-aes;87;85;83;90;89;87;87;89;92;85 20:12:43 INFO - PROCESS | 2505 | |11;stanford-crypto-ccm;142;137;141;145;154;142;136;139;175;137 20:12:43 INFO - PROCESS | 2505 | |12;stanford-crypto-pbkdf2;170;169;169;169;170;169;168;170;168;168 20:12:43 INFO - PROCESS | 2505 | |13;stanford-crypto-sha256-iterative;65;67;68;68;67;67;67;70;83;83 20:12:43 INFO - PROCESS | 2505 | __end_tp_report 20:12:43 INFO - PROCESS | 2505 | __start_cc_report 20:12:43 INFO - PROCESS | 2505 | _x_x_mozilla_cycle_collect,1956 20:12:43 INFO - PROCESS | 2505 | __end_cc_report 20:12:43 INFO - PROCESS | 2505 | __startTimestamp1475205163559__endTimestamp 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | ------- Summary: start ------- 20:12:43 INFO - PROCESS | 2505 | Number of tests: 14 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.50 stddev:1.07 (0.9%) stddev-sans-first:0.97 20:12:43 INFO - PROCESS | 2505 | Values: 115.0 113.0 115.0 114.0 114.0 113.0 112.0 113.0 112.0 113.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:110.50 stddev:17.70 (16.0%) stddev-sans-first:15.50 20:12:43 INFO - PROCESS | 2505 | Values: 148.0 111.0 108.0 147.0 109.0 110.0 108.0 109.0 140.0 106.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.29 (0.7%) stddev-sans-first:1.12 20:12:43 INFO - PROCESS | 2505 | Values: 186.0 182.0 184.0 183.0 185.0 185.0 184.0 184.0 184.0 182.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#3] audio-fft Cycles:10 Average:67.10 Median:61.50 stddev:12.09 (19.7%) stddev-sans-first:11.73 20:12:43 INFO - PROCESS | 2505 | Values: 81.0 60.0 59.0 88.0 63.0 60.0 58.0 60.0 84.0 58.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:80.50 stddev:11.32 (14.1%) stddev-sans-first:10.79 20:12:43 INFO - PROCESS | 2505 | Values: 92.0 71.0 91.0 70.0 68.0 69.0 91.0 68.0 69.0 90.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:1.34 (1.5%) stddev-sans-first:1.33 20:12:43 INFO - PROCESS | 2505 | Values: 88.0 85.0 88.0 87.0 88.0 86.0 88.0 85.0 87.0 85.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:93.50 stddev:7.92 (8.5%) stddev-sans-first:8.33 20:12:43 INFO - PROCESS | 2505 | Values: 93.0 94.0 97.0 93.0 93.0 94.0 92.0 94.0 91.0 118.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.50 stddev:1.33 (1.6%) stddev-sans-first:1.41 20:12:43 INFO - PROCESS | 2505 | Values: 85.0 85.0 86.0 86.0 87.0 85.0 83.0 84.0 86.0 83.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.14 (2.1%) stddev-sans-first:1.12 20:12:43 INFO - PROCESS | 2505 | Values: 56.0 56.0 53.0 53.0 56.0 54.0 55.0 55.0 55.0 55.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:4.69 (6.5%) stddev-sans-first:4.24 20:12:43 INFO - PROCESS | 2505 | Values: 65.0 67.0 81.0 77.0 74.0 70.0 71.0 70.0 71.0 74.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:88.00 stddev:2.67 (3.0%) stddev-sans-first:2.83 20:12:43 INFO - PROCESS | 2505 | Values: 87.0 85.0 83.0 90.0 89.0 87.0 87.0 89.0 92.0 85.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:142.00 stddev:11.83 (8.3%) stddev-sans-first:12.50 20:12:43 INFO - PROCESS | 2505 | Values: 142.0 137.0 141.0 145.0 154.0 142.0 136.0 139.0 175.0 137.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 20:12:43 INFO - PROCESS | 2505 | Values: 170.0 169.0 169.0 169.0 170.0 169.0 168.0 170.0 168.0 168.0 20:12:43 INFO - PROCESS | 2505 | 20:12:43 INFO - PROCESS | 2505 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:68.00 stddev:6.70 (9.9%) stddev-sans-first:6.81 20:12:43 INFO - PROCESS | 2505 | Values: 65.0 67.0 68.0 68.0 67.0 67.0 67.0 70.0 83.0 83.0 20:12:43 INFO - PROCESS | 2505 | -------- Summary: end -------- 20:12:43 INFO - PROCESS | 2505 | 20:12:44 INFO - TEST-INFO | 2505: exit 0 20:12:44 INFO - TEST-OK | kraken | took 58616ms 20:12:44 INFO - SUITE-END | took 1176s 20:12:44 INFO - Completed test suite (00:19:36) 20:12:44 INFO - javascript benchmark 20:12:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.8171900545467, 3326.6340702522716, 3342.84957176927, 3353.8658453661856, 3387.068556147059, 5073.289739436482, 5417.783288668448, 5687.744051517681, 5748.425157484255, 5781.797637082697, 5678.886422271552, 5707.343655479621, 5734.655920644754, 5764.7982320618785, 5797.913031304533, 4273.978630106861, 5243.659162154467, 5289.471052894699, 5325.600579956519, 5329.733513324338, 5297.602679799031, 5306.548943339821, 5334.27987221724, 5335.839924802262, 5341.626086173969, 5199.298094757216, 5326.147816349385, 5333.57331413486, 5374.543163831079, 5393.083175860113, 5332.626716129873, 5336.199570064483, 5364.302640656729, 5368.087425137736, 5381.327334083239, 5147.588192944556, 5176.534111929945, 5190.584753219733, 5206.843794686166, 5239.161734122522, 5186.974065129651, 5460.945390546083, 5612.045952188097, 5662.745176467069, 5795.768169273266, 6098.054801505774, 6203.7828675996125, 6210.596311239755, 6220.315765265817, 6227.719752611143, 5075.847724568269, 6270.8118756436925, 6279.340669229757, 6298.496120310364, 6315.179026726542, 5649.519790817826, 5707.657540547539, 5736.971315143398, 5762.135679648002, 5764.365919748824, 6257.4681152102585, 6311.274203466568, 6346.9682651586445, 6349.809505714836, 6361.077643741724, 5444.564434845203, 5650.237217975603, 6006.609570377911, 6015.218021657157, 6031.09533569968, 6115.082737589397, 6141.692915354214, 6184.938150618436, 6198.659073750987, 6200.3799620037635, 6153.384661533901, 6221.813345599639, 6226.719797609027, 6268.310485846553, 6282.874342513124, 6229.719662615194, 6268.310485846553, 6296.811095667137, 6305.8108256752375, 6314.0, 4943.1596628573225, 4966.925496117561, 4978.103941290602, 4985.0, 5002.624803139779, 4406.184855801687, 4427.867163985085, 4457.375967364572, 4517.141743068806, 4517.141743068806, 5379.515843574097, 5404.972975135099, 5756.856078598069, 5892.558058145656, 5900.940990590039, 6259.311475737665, 6290.182276304051, 6317.273513545909, 6329.588576742497, 6330.620162790247, 5750.30996280449, 5816.1566572846705, 5831.854203644943, 5841.2990441146485, 5846.181534585162, 5308.654937429054, 5378.91931621026, 5386.622936394415, 5389.487998640123, 5437.13005919051, 6294.685265736694, 6354.745810167541, 6384.61692298445, 6386.744530218833, 6387.329330420239, 6288.685565721604, 6325.493960483058, 6339.904901426575, 6340.619562826245, 6357.523185761179, 6238.906416403667, 6310.463610593151, 6367.331430199948, 6376.489880809617, 6382.585131966407, 5662.2639056922335, 5956.5532585055425, 6138.296437970319, 6145.494353883327, 6268.247810262797, 6253.7185826637, 6288.11966324724, 6316.62100273985, 6328.367163283635, 6332.271788744353, 6053.273607167168, 6114.327423983447, 6144.8771024580155, 6148.385161483995, 6171.382861713792, 6057.1519987200945, 6097.494431795148, 6114.69277300208, 6127.295361033538, 6137.959273351923, 5835.3581106077445, 5959.1657167995645, 5969.970150149222, 5974.2233509643465, 5978.551608629283, 6144.8771024580155, 6164.938350616616, 6167.105769663463, 6172.598781079216, 6186.876262474815, 5646.520045796154, 5692.5730570208225, 5701.201831743642, 5887.20522729426, 5904.468597826216], "value": 5696.6438901660795, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.1325037809023, 505.6106797765718, 505.97470126493675, 507.55588859747724, 511.439973229314, 30767.846160769193, 31019.224519386997, 32091.0, 32100.839495802546, 39589.40615890764, 38792.418113728316, 41551.0, 41702.0, 41713.58286417135, 41782.58217417825, 9791.216702663787, 9980.001999799999, 9983.40099594023, 9992.550335234931, 10000.699979000641, 8793.736187914374, 9014.098590140999, 9789.265805064613, 9925.652602158925, 9953.153981911546, 11986.160968732192, 12301.81547276787, 12328.383580820968, 12329.691757706085, 12347.567835125772, 12208.32854193019, 12305.0, 12305.938470307681, 12314.630561083182, 12327.69180770479, 8770.868436973451, 9005.14451127142, 9014.278857691435, 11919.165658403916, 12022.458989345412, 31874.681253187635, 32165.35669286601, 38871.63949261762, 39615.80192099021, 39707.448271035246, 3212.5823642926503, 3224.081136875979, 3305.735541156702, 3416.7437442191685, 3728.459373390843, 30127.849360753276, 30476.0, 30928.0, 31332.530012049836, 31355.0, 3250.9187270318193, 3287.4411350070304, 3292.226326813197, 3376.0715803154085, 3394.7793393429347, 484656.0, 488748.0, 489102.0, 490206.0, 491143.0, 47482.76258618685, 48708.512914871106, 55548.72225638846, 56011.0, 56034.87930241373, 57068.714656427284, 58145.709271454216, 58322.708386458646, 61317.0, 61666.691666542276, 15695.68608627821, 15841.603959901095, 15844.683106337809, 15847.52457426279, 15866.0, 12308.630741077783, 12375.257484550775, 12383.876161238453, 12391.070669699808, 12399.25604463735, 11595.536178552764, 13547.59357219285, 13841.515546955809, 13848.09987350819, 15214.163221022729, 5801.0, 5822.82531524055, 6838.93161068383, 6861.588304701734, 6939.826504337432, 788.4993029426356, 812.979675508117, 824.8968878890139, 936.8828896387952, 940.2055263302499, 27895.860520697268, 28810.41542715129, 28995.420091598473, 29711.702882971327, 29883.40233195324, 1010.0101900137906, 1055.5091882274626, 1062.0600768320091, 1103.7141729884595, 1114.9888501115047, 14477.466443372368, 14569.490067847575, 15148.242587870562, 15245.771313430307, 15258.389664413522, 625499.0, 631108.0, 632354.0, 634282.0, 634984.0, 48664.27003594949, 49563.50436495589, 55229.17156242659, 55824.0, 56044.0, 1327.595083499542, 1369.5480491437802, 1369.9657508562366, 1372.3207012528862, 1376.8141300924246, 29885.87339595421, 30094.548581771727, 30097.849510752305, 30226.093217203517, 30568.694313056585, 48353.5164648349, 48562.5143748558, 48604.75697621489, 48682.75658621684, 48713.75643121762, 544011.0, 565126.0, 666703.0, 671755.0, 818379.0, 24869.626955596043, 25137.49725005526, 25252.873735631205, 25294.873525632254, 25302.0, 44737.10525789401, 45136.871578210805, 45283.09433811371, 46367.0, 48686.75656621694, 37109.628903711695, 37500.06249843776, 37534.4314744924, 38675.806620966716, 38974.0, 37495.0, 38159.1696189448, 38185.80907095447, 38452.230955381296, 38538.0, 36341.81829090943, 36902.8154859224, 37289.0677733048, 37328.81335593413, 37667.81166094152, 32795.508067378534, 33314.5002824953, 33700.32599348048, 33958.66041339555, 34290.48564271588, 24974.250772476855, 24982.50034999326, 25316.620185723765, 25389.87305063463, 25688.0], "value": 19555.90392915143, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.2821716720637, 362.70802004386456, 365.0, 370.6608453265262, 387.6743535430238, 13915.304234788271, 14024.368903399347, 15894.20528973551, 20184.293549725764, 20323.49191270219, 16142.273597688103, 17762.555936101602, 19332.51668708283, 19677.90161049193, 20086.598268034668, 5495.422980587041, 6168.414000669929, 6171.969140154315, 6179.81460556184, 6181.969090154543, 4191.979040104795, 4693.621884935391, 6073.271207455112, 6098.420650038262, 6173.043178307369, 4381.43041404617, 4424.380586717862, 6324.905126423085, 6667.899981500257, 6667.932940788347, 6160.398296442957, 6465.709043093072, 6634.137562116942, 6728.293529179439, 6733.225679046899, 5701.0, 5701.629394089412, 5702.173184888209, 5702.714864256812, 5702.971485142548, 19231.423057308304, 19670.016499174984, 25506.0, 25664.358391040187, 25832.612510812352, 2221.2058542793625, 2244.450109723128, 2858.542633178702, 3116.1586371679746, 3159.8104113753247, 14876.0, 15232.619184520365, 15238.161901095435, 15933.840661593467, 16071.955322903974, 1693.9068351240676, 1827.7806663200367, 1873.4754268804757, 1910.2550005497867, 2018.8183063524352, 111413.22004248407, 121861.0, 123534.38232808869, 124272.0, 125916.74083259141, 9730.221582273402, 10637.46812659364, 10642.467876606293, 10823.729406764895, 11178.944105279421, 14190.503332383318, 14204.218767967654, 14215.573532794033, 14224.217668028357, 14230.0, 8059.758207253792, 8403.873941890877, 8408.075111737704, 8493.787655308543, 8520.275776558938, 7128.964355178295, 8259.967504061991, 8351.248387645019, 8365.330773538224, 8557.058723540405, 3925.9607403926166, 4260.126674031816, 4305.892352691208, 4407.823687052482, 4459.0, 1713.6487020160837, 1746.9301227950739, 1803.167511070318, 1828.0768212052828, 1876.080720446971, 711.9466040047017, 712.7965849300089, 781.0978320040363, 913.3743385780762, 927.3925578745932, 18197.0, 19029.333973310866, 19732.0, 21331.46671333198, 21387.0, 844.907060223375, 865.8786871002068, 903.977400564978, 908.4004556992353, 942.3074040580168, 10579.576816927236, 11056.0, 11171.0, 11175.0, 11208.943955280172, 585847.0, 589376.0, 594285.0, 599955.0, 601185.0, 98662.0, 111208.0, 128677.35661321634, 136082.3195884034, 141323.0, 962.3215632978734, 994.0904072773332, 999.8300288950895, 1012.7670635753818, 1016.3089099412323, 14993.400263989319, 19041.42875713731, 19758.913259770852, 19849.503762406057, 19858.801411985696, 58181.0, 58260.126098107714, 58602.413975859694, 59487.0, 60084.399156009626, 74764.62617686877, 75172.62413687896, 84591.0, 85595.0, 86956.56521717351, 6255.40573645503, 7988.760337189894, 8059.153788852321, 8188.058373287148, 8388.328933685412, 9271.953640231755, 12524.935380492396, 12599.0, 12977.675558111123, 13448.731025379633, 10889.618863339745, 10922.781544368909, 11437.59968401102, 11472.598459054227, 11703.2978021319, 9471.810563788824, 11020.779584408107, 11221.270617409842, 11268.38023908693, 11615.825762613398, 10785.352878827296, 11885.940570297093, 11889.405529723306, 12018.519259229533, 12036.458359373673, 7965.084015338311, 7982.6806927724565, 8164.591770411337, 8251.349730053893, 8373.37199710012, 8270.793230169053, 9020.278377729635, 11298.661040168809, 11452.198346115692, 11589.072874170215], "value": 9516.530376217357, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.6376542099051, 1487.5314276003062, 1505.1420690206583, 1854.1100271869493, 1881.1816859666046, 8414.705485308015, 8883.0, 8888.555572221396, 8896.733098007062, 8912.509811960339, 7955.960220198891, 8170.91829081709, 8175.3050990665715, 8453.78865528362, 8656.047834738189, 2307.8038366738806, 2345.7067866516686, 2346.5424242272766, 2346.791402427751, 2348.988255058722, 2358.575456417844, 2379.5716770981217, 2394.353524548371, 2394.8443351182204, 2398.2205783120467, 2090.9336238518313, 2168.8698678079286, 2303.4586872085047, 2373.3354660694945, 2390.8445951013214, 2366.1127077345996, 2388.605880029793, 2391.1152873436768, 2392.3062311929434, 2393.8324317297797, 2328.2898715891647, 2359.197872723282, 2360.6223004319227, 2364.1725396111397, 2370.3955491349598, 8725.956370218108, 8902.554872256362, 8909.020007799138, 8910.420822646507, 8910.599023043978, 5133.6149788765615, 5283.577313814885, 5284.973575132138, 5287.154055351164, 5293.682379057269, 2813.9718602813914, 3427.845746941394, 3455.3434847378917, 3592.910177245564, 3594.245208506217, 1062.2882668611971, 1069.620284798893, 1077.0737166037202, 1081.8052750504905, 1089.411717672456, 11070.778584428346, 11114.388708621023, 11133.944330278297, 11157.0, 11191.552337906556, 4366.628836548865, 4440.489343725449, 4537.818487260539, 4569.200389931749, 4577.954220457819, 9131.81736365269, 9132.132447417485, 9135.0, 9137.497437640994, 9140.54297285133, 5256.92114618281, 5258.6581872178185, 5259.500347466985, 5263.34208223972, 5263.421023687391, 4746.644001699887, 4861.51384861511, 4922.507749225048, 4923.0, 4938.9259161111895, 3489.7906125632544, 3513.1392808761507, 3542.2561262134723, 3573.5175751273246, 3589.8205089745406, 980.2109302011949, 1044.9791004179874, 1087.2389327470803, 1108.036008672444, 1113.8941800528937, 627.9152314437583, 779.380392587894, 867.444835305405, 902.9774255643662, 926.8563372677246, 5412.56699464042, 5592.384837667853, 5794.101914203306, 5808.361080281165, 5896.557758168155, 820.9343252539783, 922.3543519536352, 929.1823195587839, 947.6446332625266, 950.933434659581, 3304.454764963754, 3879.8254078566533, 3898.2847547079195, 4015.417764424142, 4030.3551431770775, 10303.948480257552, 10579.153667706565, 10583.57665693379, 10594.152467802558, 10595.894041059644, 10203.9489802552, 10448.164146868385, 10532.210084243712, 10537.209709271836, 10553.577856885791, 8115.3913456491, 8125.187481251827, 8312.83374332522, 8330.583470826434, 8350.164983501723, 5786.537077033911, 5805.767769289181, 5874.383189765013, 5924.9703751480965, 5977.163197152315, 10069.295149339476, 10105.747356316151, 10114.342567733074, 10119.79760404773, 10181.440020798927, 9858.457784821903, 10246.590136394461, 10355.741106472398, 10381.84427233577, 10421.957804219517, 7030.261822508767, 7154.964225178841, 7161.39128174111, 7165.964170179116, 7167.784966451015, 8194.713185038496, 8266.421350505407, 8309.376796740147, 8352.248297653241, 8358.916410836056, 7336.193018768038, 7408.111026676617, 7456.552606843607, 7509.249075092447, 7534.35957943581, 7269.382102521345, 7405.148407932941, 7406.962965185139, 7416.851662966603, 7464.850702985801, 6846.760363387258, 6866.793996180123, 6957.930420695729, 6968.965155174192, 6970.756023539152, 4932.457429682804, 4944.1100601892, 5012.147934851011, 5053.494650534917, 5222.425533191422, 4987.202047672355, 5024.773885175103, 5070.873228169326, 5085.906530096047, 5216.895662086813], "value": 4501.341009122549, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.68292926518674, 298.9745871600914, 308.03431243053024, 312.2225658111302, 315.13652591898204, 5454.563634909207, 5472.945270547293, 5480.534154596864, 5520.515767904444, 5529.308836395456, 4093.973483125857, 5091.134507133787, 5159.793608255665, 5278.815241466549, 5391.514763671269, 4092.5907409259135, 4114.202342407162, 4120.402541631476, 4126.649234815044, 4152.1695660867945, 4091.652209562176, 4111.280525907969, 4127.29835927893, 4128.071183983609, 4182.9790851045855, 3805.9809700951455, 4036.2129384769983, 4115.73247738896, 4130.359794231884, 4169.685824367475, 4065.0, 4099.7745124018165, 4105.486814148247, 4109.609587089251, 4124.1957818225455, 3104.8292343921075, 3443.6728510791436, 3517.191046059395, 3521.2605352875926, 3537.4870643756767, 4788.736619485927, 5018.7992480300745, 5329.413764485904, 5381.784728610851, 5383.865403364908, 4594.402727645418, 4643.326717625925, 4645.837395691135, 4664.0, 4680.3447517347595, 4860.590428775686, 5573.773769770644, 5689.800856969986, 5713.685747283897, 5758.74085666146, 3215.6462789093416, 3525.2596954639557, 3806.809659517013, 3834.69322454203, 3842.1547259602844, 5431.8940196215435, 5603.663780173202, 5969.373215812364, 5998.670073145975, 6076.848078798022, 3180.204948762809, 3222.3394204188085, 3860.9227815443533, 3863.4784304118584, 3870.3807390817333, 3908.511436070491, 3926.332523471017, 3951.3480275754177, 3964.52425708911, 3966.8809935701975, 3871.7483363581277, 4107.815148318333, 4556.152404747033, 4591.655625828076, 4600.81461305877, 5193.0, 5670.404607516234, 5700.37295897451, 5701.462849032224, 5710.7430165642645, 1033.106362996003, 1270.6315168601002, 1300.4213125159401, 1308.6532069001532, 1340.9262490563112, 828.3787159630277, 843.1610547505271, 864.1617630898019, 865.5369377383196, 867.9739607811775, 4330.350447432847, 4496.820127194941, 4515.458145022552, 4526.59260666535, 4577.954220457819, 4602.3786788783755, 4632.305154226893, 4684.227102528044, 4907.509249075064, 4970.378702662168, 4132.73137246078, 4171.290880550314, 4307.741535507879, 4480.798364073625, 4522.253828118323, 2311.260396673082, 2569.021853173046, 2572.9871350643125, 2583.276682528895, 2585.6767904012, 4311.0, 4429.689921705514, 4606.769661516911, 5041.6722913010535, 5045.747712614355, 4278.379634952915, 4499.290269697494, 4755.762211889391, 4942.874563937469, 4973.751312434364, 4642.837500687528, 4657.743824089775, 4790.5568160237835, 4966.255061740768, 5001.4998500149695, 4358.4769827620885, 4522.5929666328875, 4867.637061622751, 4870.610351171827, 4874.683145595525, 4184.225918205142, 4390.978045109754, 4735.881602959954, 4901.9950910062635, 5750.367459579526, 5177.844664660066, 5299.920501192408, 6053.9091913622215, 6067.848303792441, 6115.235595550556, 4028.8992775180855, 4072.144849581621, 4074.7758873262255, 4077.8164982575263, 4079.8776036718946, 5622.578306626938, 5904.261967254093, 5952.880942381042, 5957.940420595739, 6007.339192688713, 5746.367899530965, 5762.4525670061275, 5771.422857714195, 5780.277465316835, 5793.594448388572, 5034.622403319692, 5528.75120619565, 5739.053056245672, 5750.712464376848, 5767.480926716616, 5321.148616221386, 5534.0, 5590.804321848878, 5595.384507704231, 5615.326160860723, 4848.369711937426, 5042.621803364836, 5370.543503802221, 5588.133839254922, 5730.856728581819, 5569.749361278833, 5570.693611851225, 5572.0, 5603.887922241451, 5634.661920284796], "value": 3960.8158021217578, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.8205257659297, 712.3375261007264, 768.8270139218677, 805.2712295372689, 821.6795449774587, 15102.697946041086, 15554.0, 15566.221688915566, 15575.065496070216, 15586.922065389657, 13898.652533686638, 13952.44190232392, 13984.16095034296, 14013.159210447355, 14017.64955876101, 653.1345966594258, 676.0501495398972, 678.5792808458763, 803.9839203215932, 804.8470790549806, 822.1490757066438, 830.2237408023517, 833.529056083314, 835.6448509383519, 838.8406202821475, 886.756142060932, 889.0709208876739, 890.0565400675273, 890.4212262029667, 894.1818236313765, 693.7953303775348, 884.8451520983866, 886.3396769406801, 886.6497733395305, 888.9422187557788, 527.6122050293031, 629.2889035390026, 642.7493277621732, 648.0764910003227, 649.4349915573434, 14696.971212015163, 14709.852901470955, 14737.336819843133, 14749.410023599043, 14758.40966361355, 13007.674808129777, 13446.260455674934, 13490.93254533731, 13566.389512471864, 13808.861911380958, 12340.938295308555, 12691.873081269254, 12726.10917235794, 12738.745225095447, 13253.801192982113, 4505.662075344362, 4532.841350552715, 4579.061292435043, 4661.953380466186, 4677.415323084614, 110754.44622776835, 112878.0, 113589.0, 113914.99999999917, 114002.0, 8763.780905477413, 8917.375783695079, 10334.586616535405, 10519.211059170593, 11018.283811552223, 21490.78509214888, 21675.0, 21761.0, 21792.346229613137, 22210.333689989326, 7637.618119094023, 7689.4232932530285, 7694.884576731353, 7848.568328741974, 7969.003874515685, 3632.2735452908996, 4183.3515805050265, 4188.643965262926, 4195.811188496525, 4362.17118747437, 3118.095752231827, 3284.901452956415, 3505.386557352453, 3520.1023738946406, 3522.1546828761425, 624.6501958903029, 634.5589815078569, 642.8007317731518, 647.8801421736994, 660.9008648702637, 609.20194545146, 609.8018144103185, 621.5114207671891, 621.8662987457718, 627.5324882962223, 7435.293647103516, 8721.258693011037, 8852.114788521225, 9117.179453849185, 9214.419358862417, 578.8023548534187, 583.547750493371, 619.6591874469024, 649.6232185332495, 650.9186351706037, 3381.9830900845336, 4364.781760911942, 4493.438320209973, 4560.771961401983, 4656.278276867091, 95402.0, 124696.0, 155889.0, 163641.0, 167038.0, 77982.61008694921, 100154.0, 101364.09759121077, 105594.47202763936, 112517.0, 63951.680241599424, 64903.37741556404, 106635.46682266695, 106675.0, 107733.46133269284, 9329.507278835625, 9611.807763844823, 9939.602415903282, 9960.800783984134, 10002.299839011199, 59091.70454147702, 60122.39877601168, 60966.39033609607, 61093.0, 63251.36748632455, 49587.26444574423, 82330.94172645731, 82939.0, 85589.0, 89967.0, 13144.633965886233, 13338.733225335633, 13360.532381366605, 13406.664833378853, 13737.931310343385, 14720.77918831197, 14898.77651835245, 14952.700945981236, 15053.77419338689, 15740.291686873888, 12375.381230938598, 13480.86519134796, 13938.860611394162, 13954.930225348808, 14073.155610663393, 13220.867791321964, 13293.40719668644, 13916.0, 13989.0, 14488.235730720617, 13630.863691363356, 13632.250226237255, 13777.655558611115, 13843.584692459242, 13873.72252554923, 11344.829827552758, 11350.886491135314, 11410.63331506323, 11501.367424791717, 11655.941720291345, 8102.7894440541, 8902.688405905763, 9392.624295028123, 11650.825237621273, 11661.591844285409], "value": 7575.145123242165, "unit": "score"}], "name": "dromaeo_css", "value": 7233.082173776247, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 115.0, 114.0, 114.0, 113.0, 112.0, 113.0, 112.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 108.0, 147.0, 109.0, 110.0, 108.0, 109.0, 140.0, 106.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 184.0, 183.0, 185.0, 185.0, 184.0, 184.0, 184.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 88.0, 63.0, 60.0, 58.0, 60.0, 84.0, 58.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 91.0, 70.0, 68.0, 69.0, 91.0, 68.0, 69.0, 90.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 87.0, 88.0, 86.0, 88.0, 85.0, 87.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 97.0, 93.0, 93.0, 94.0, 92.0, 94.0, 91.0, 118.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 86.0, 87.0, 85.0, 83.0, 84.0, 86.0, 83.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 53.0, 56.0, 54.0, 55.0, 55.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 81.0, 77.0, 74.0, 70.0, 71.0, 70.0, 71.0, 74.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 83.0, 90.0, 89.0, 87.0, 87.0, 89.0, 92.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 137.0, 141.0, 145.0, 154.0, 142.0, 136.0, 139.0, 175.0, 137.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 169.0, 169.0, 170.0, 169.0, 168.0, 170.0, 168.0, 168.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 70.0, 83.0, 83.0], "value": 70.5, "unit": "score"}], "name": "kraken", "value": 1428.0, "alertThreshold": 2.0}]} 20:12:44 INFO - Return code: 0 20:12:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:12:44 INFO - # TBPL SUCCESS # 20:12:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:12:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:12:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:12:44 INFO - 2016-09-29 20:12:44.866 screenresolution[2509:38432] starting screenresolution argv=screenresolution get 20:12:44 INFO - 2016-09-29 20:12:44.868 screenresolution[2509:38432] Display 0: 1600x1200x32@60 20:12:44 INFO - 2016-09-29 20:12:44.874 screenresolution[2510:38435] starting screenresolution argv=screenresolution list 20:12:44 INFO - Available Modes on Display 0 20:12:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:12:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:12:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:12:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:12:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:12:45 INFO - Intel Iris: 20:12:45 INFO - Chipset Model: Intel Iris 20:12:45 INFO - Type: GPU 20:12:45 INFO - Bus: Built-In 20:12:45 INFO - VRAM (Dynamic, Max): 1536 MB 20:12:45 INFO - Vendor: Intel (0x8086) 20:12:45 INFO - Device ID: 0x0a2e 20:12:45 INFO - Revision ID: 0x0009 20:12:45 INFO - Displays: 20:12:45 INFO - Display: 20:12:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:12:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:12:45 INFO - Display Serial Number: 1600x1200 60 20:12:45 INFO - Main Display: Yes 20:12:45 INFO - Mirror: Off 20:12:45 INFO - Online: Yes 20:12:45 INFO - Rotation: Supported 20:12:45 INFO - Return code: 0 20:12:45 INFO - Running post-action listener: _resource_record_post_action 20:12:45 INFO - [mozharness: 2016-09-30 03:12:45.111158Z] Finished run-tests step (success) 20:12:45 INFO - Running post-run listener: _resource_record_post_run 20:12:45 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 507904; Write bytes: 702966272; Read time: 4; Write time: 1881 20:12:45 INFO - TinderboxPrint: CPU usage
53.6% 20:12:45 INFO - TinderboxPrint: I/O read bytes / time
507,904 / 4 20:12:45 INFO - TinderboxPrint: I/O write bytes / time
702,966,272 / 1,881 20:12:45 INFO - TinderboxPrint: CPU idle
2,250.2 (46.4%) 20:12:45 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 20:12:45 INFO - TinderboxPrint: CPU user
1,625.8 (33.6%) 20:12:45 INFO - TinderboxPrint: Swap in / out
407,433,216 / 0 20:12:45 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387226112; Write bytes: 381483008; Read time: 29058; Write time: 645 20:12:45 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 507904; Write bytes: 293024256; Read time: 4; Write time: 1181 20:12:45 INFO - Running post-run listener: _upload_blobber_files 20:12:45 INFO - Blob upload gear active. 20:12:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:12:45 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 20:12:45 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:12:45 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:12:46 INFO - (blobuploader) - INFO - Open directory for files ... 20:12:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:12:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:12:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:46 INFO - (blobuploader) - INFO - Done attempting. 20:12:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:12:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:12:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:47 INFO - (blobuploader) - INFO - Done attempting. 20:12:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:12:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:12:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:50 INFO - (blobuploader) - INFO - Done attempting. 20:12:50 INFO - (blobuploader) - INFO - Iteration through files over. 20:12:50 INFO - Return code: 0 20:12:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:12:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:12:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0bf7befe7c05c49f639ec2852576b901add5084539a821bd0761ef3750f48cd47a2724c8957456282cab31067983b2f40986a6a961e5449d58edccb0d0de986e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62f86c58fba16e935c703c24010a6775c7762c5cc2102a1e202d8f5695ab02ae268cc58f42259584b3422777dd19566d4cd1bd226b864c233e037e41eed9c159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbaecb0796bd25857e10a3378d59ad22e0a247719295af964f37c5d91674ffb0fbd49809337867be30ef1e0acd018cd6cdf67b7e486546e30f4e63cce65ab857"} 20:12:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:12:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:12:50 INFO - Contents: 20:12:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0bf7befe7c05c49f639ec2852576b901add5084539a821bd0761ef3750f48cd47a2724c8957456282cab31067983b2f40986a6a961e5449d58edccb0d0de986e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62f86c58fba16e935c703c24010a6775c7762c5cc2102a1e202d8f5695ab02ae268cc58f42259584b3422777dd19566d4cd1bd226b864c233e037e41eed9c159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbaecb0796bd25857e10a3378d59ad22e0a247719295af964f37c5d91674ffb0fbd49809337867be30ef1e0acd018cd6cdf67b7e486546e30f4e63cce65ab857"} 20:12:50 INFO - Running post-run listener: copy_logs_to_upload_dir 20:12:50 INFO - Copying logs to upload dir... 20:12:50 INFO - mkdir: /builds/slave/test/build/upload/logs 20:12:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.347011 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-29 20:12:50.532262) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 20:12:50.535321) ========= 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.5biHMDnzAA/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.P8xg1GHTLR/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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/Fx-Team/sha512/0bf7befe7c05c49f639ec2852576b901add5084539a821bd0761ef3750f48cd47a2724c8957456282cab31067983b2f40986a6a961e5449d58edccb0d0de986e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62f86c58fba16e935c703c24010a6775c7762c5cc2102a1e202d8f5695ab02ae268cc58f42259584b3422777dd19566d4cd1bd226b864c233e037e41eed9c159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbaecb0796bd25857e10a3378d59ad22e0a247719295af964f37c5d91674ffb0fbd49809337867be30ef1e0acd018cd6cdf67b7e486546e30f4e63cce65ab857"} build_url:https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010978 build_url: 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0bf7befe7c05c49f639ec2852576b901add5084539a821bd0761ef3750f48cd47a2724c8957456282cab31067983b2f40986a6a961e5449d58edccb0d0de986e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62f86c58fba16e935c703c24010a6775c7762c5cc2102a1e202d8f5695ab02ae268cc58f42259584b3422777dd19566d4cd1bd226b864c233e037e41eed9c159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbaecb0796bd25857e10a3378d59ad22e0a247719295af964f37c5d91674ffb0fbd49809337867be30ef1e0acd018cd6cdf67b7e486546e30f4e63cce65ab857"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 20:12:50.566722) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 20:12:50.567017) ========= 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.5biHMDnzAA/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.P8xg1GHTLR/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.005192 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 20:12:50.615900) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 20:12:50.616263) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 20:12:50.616667) ========= ========= Total master_lag: 0.17 =========