builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0251 starttime: 1493073727.01 results: success (0) buildid: 20170424145525 builduid: bd6b75bca751409cbb7e6572b0e4eb40 revision: ca3c6131b6eadf753445b3cd7a54e29b41ab11aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.009503) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.010520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.011260) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.039125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.039812) ========= 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.ulrqkk7TUx/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.T2GInV1RjG/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-24 15:42:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2017-04-24 15:42:07 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136681 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.205984) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.206779) ========= 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.ulrqkk7TUx/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.T2GInV1RjG/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.026302 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:07.260871) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-24 15:42:07.261670) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ca3c6131b6eadf753445b3cd7a54e29b41ab11aa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ca3c6131b6eadf753445b3cd7a54e29b41ab11aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ulrqkk7TUx/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.T2GInV1RjG/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-24 15:42:07,369 truncating revision to first 12 chars 2017-04-24 15:42:07,369 Setting DEBUG logging. 2017-04-24 15:42:07,369 attempt 1/10 2017-04-24 15:42:07,369 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ca3c6131b6ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-24 15:42:09,387 unpacking tar archive at: mozilla-beta-ca3c6131b6ea/testing/mozharness/ program finished with exit code 0 elapsedTime=2.290655 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-24 15:42:09.577813) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:09.578209) ========= script_repo_revision: ca3c6131b6eadf753445b3cd7a54e29b41ab11aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:09.578820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:09.579199) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 15:42:09.600465) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-04-24 15:42:09.600925) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ulrqkk7TUx/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.T2GInV1RjG/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:42:09 INFO - MultiFileLogger online at 20170424 15:42:09 in /builds/slave/test 15:42:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:42:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:42:09 INFO - {'append_to_log': False, 15:42:09 INFO - 'base_work_dir': '/builds/slave/test', 15:42:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:42:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:42:09 INFO - 'branch': 'Mozilla-Beta', 15:42:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:42:09 INFO - 'config_files': ('talos/mac_config.py',), 15:42:09 INFO - 'default_actions': ('clobber', 15:42:09 INFO - 'read-buildbot-config', 15:42:09 INFO - 'download-and-extract', 15:42:09 INFO - 'populate-webroot', 15:42:09 INFO - 'create-virtualenv', 15:42:09 INFO - 'install', 15:42:09 INFO - 'run-tests'), 15:42:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:42:09 INFO - 'download_minidump_stackwalk': True, 15:42:09 INFO - 'download_symbols': 'ondemand', 15:42:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:42:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:42:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:42:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:42:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:42:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:42:09 INFO - 'gecko_profile': False, 15:42:09 INFO - 'gecko_profile_interval': 0, 15:42:09 INFO - 'installer_path': 'installer.exe', 15:42:09 INFO - 'log_level': 'info', 15:42:09 INFO - 'log_name': 'talos', 15:42:09 INFO - 'log_to_console': True, 15:42:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:42:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:42:09 INFO - 'opt_config_files': (), 15:42:09 INFO - 'pip_index': False, 15:42:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:42:09 INFO - 'cmd': ('bash', 15:42:09 INFO - '-c', 15:42:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:42:09 INFO - 'enabled': True, 15:42:09 INFO - 'halt_on_failure': False, 15:42:09 INFO - 'name': 'check_screen_resolution'},), 15:42:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:42:09 INFO - 'cmd': ('bash', 15:42:09 INFO - '-c', 15:42:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:42:09 INFO - 'enabled': True, 15:42:09 INFO - 'halt_on_failure': False, 15:42:09 INFO - 'name': 'check_screen_resolution'},), 15:42:09 INFO - 'run_cmd_checks_enabled': True, 15:42:09 INFO - 'suite': 'dromaeojs-e10s', 15:42:09 INFO - 'system_bits': '32', 15:42:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:42:09 INFO - 'title': 't-yosemite-r7-0251', 15:42:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:42:09 INFO - 'use_talos_json': True, 15:42:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:42:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:42:09 INFO - 'work_dir': 'build'} 15:42:09 INFO - [mozharness: 2017-04-24 22:42:09.817480Z] Running clobber step. 15:42:09 INFO - Running pre-action listener: _resource_record_pre_action 15:42:09 INFO - Running main action method: clobber 15:42:09 INFO - rmtree: /builds/slave/test/build 15:42:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:42:12 INFO - Running post-action listener: _resource_record_post_action 15:42:12 INFO - [mozharness: 2017-04-24 22:42:12.206068Z] Finished clobber step (success) 15:42:12 INFO - [mozharness: 2017-04-24 22:42:12.206253Z] Running read-buildbot-config step. 15:42:12 INFO - Running pre-action listener: _resource_record_pre_action 15:42:12 INFO - Running main action method: read_buildbot_config 15:42:12 INFO - Using buildbot properties: 15:42:12 INFO - { 15:42:12 INFO - "project": "", 15:42:12 INFO - "product": "firefox", 15:42:12 INFO - "script_repo_revision": "production", 15:42:12 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:42:12 INFO - "repository": "", 15:42:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:42:12 INFO - "buildid": "20170424145525", 15:42:12 INFO - "pgo_build": "False", 15:42:12 INFO - "basedir": "/builds/slave/test", 15:42:12 INFO - "buildnumber": 5, 15:42:12 INFO - "slavename": "t-yosemite-r7-0251", 15:42:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:42:12 INFO - "platform": "yosemite_r7", 15:42:12 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:42:12 INFO - "branch": "mozilla-beta", 15:42:12 INFO - "repo_path": "releases/mozilla-beta", 15:42:12 INFO - "revision": "ca3c6131b6eadf753445b3cd7a54e29b41ab11aa", 15:42:12 INFO - "stage_platform": "macosx64", 15:42:12 INFO - "builduid": "bd6b75bca751409cbb7e6572b0e4eb40", 15:42:12 INFO - "slavebuilddir": "test" 15:42:12 INFO - } 15:42:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 15:42:12 INFO - Running post-action listener: _resource_record_post_action 15:42:12 INFO - [mozharness: 2017-04-24 22:42:12.209782Z] Finished read-buildbot-config step (success) 15:42:12 INFO - [mozharness: 2017-04-24 22:42:12.209890Z] Running download-and-extract step. 15:42:12 INFO - Running pre-action listener: _resource_record_pre_action 15:42:12 INFO - Running main action method: download_and_extract 15:42:12 INFO - mkdir: /builds/slave/test/build/tests 15:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:12 INFO - trying https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 15:42:12 INFO - Downloading https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 15:42:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 15:42:13 INFO - Downloaded 1165 bytes. 15:42:13 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 15:42:13 INFO - Using the following test package requirements: 15:42:13 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 15:42:13 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:42:13 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 15:42:13 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:42:13 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 15:42:13 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:42:13 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:42:13 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 15:42:13 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 15:42:13 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:42:13 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 15:42:13 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:42:13 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 15:42:13 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:42:13 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 15:42:13 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:42:13 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 15:42:13 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 15:42:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 15:42:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 15:42:13 INFO - Fetch https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 15:42:15 INFO - Expected file size: 51226133 15:42:15 INFO - Obtained file size: 51226133 15:42:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:42:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 15:42:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 15:42:31 INFO - Fetch https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 15:42:33 INFO - Expected file size: 51226133 15:42:33 INFO - Obtained file size: 51226133 15:42:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 15:42:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 15:42:48 INFO - Fetch https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 15:42:49 INFO - Expected file size: 14404449 15:42:49 INFO - Obtained file size: 14404449 15:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:49 INFO - trying https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:42:49 INFO - Downloading https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 15:42:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 15:42:51 INFO - Downloaded 59532378 bytes. 15:42:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:42:51 INFO - mkdir: /builds/slave/test/properties 15:42:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:42:51 INFO - Writing to file /builds/slave/test/properties/build_url 15:42:51 INFO - Contents: 15:42:51 INFO - build_url:https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:42:52 INFO - Running post-action listener: _resource_record_post_action 15:42:52 INFO - Running post-action listener: set_extra_try_arguments 15:42:52 INFO - [mozharness: 2017-04-24 22:42:52.332960Z] Finished download-and-extract step (success) 15:42:52 INFO - [mozharness: 2017-04-24 22:42:52.333078Z] Running populate-webroot step. 15:42:52 INFO - Running pre-action listener: _resource_record_pre_action 15:42:52 INFO - Running main action method: populate_webroot 15:42:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:42:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:42:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:42:52 INFO - '--tppagecycles', 15:42:52 INFO - '7'], 15:42:52 INFO - 'tests': ['tsvgm']}}, 15:42:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:42:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:42:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:42:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:42:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:42:52 INFO - 'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:42:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:42:52 INFO - 'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['damp', 'tps']}, 15:42:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:42:52 INFO - 'tests': ['damp', 'tps']}, 15:42:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['dromaeo_dom']}, 15:42:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:42:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['basic_compositor_video']}, 15:42:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:42:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['a11yr', 15:42:52 INFO - 'ts_paint', 15:42:52 INFO - 'tpaint', 15:42:52 INFO - 'sessionrestore', 15:42:52 INFO - 'sessionrestore_no_auto_restore', 15:42:52 INFO - 'tabpaint']}, 15:42:52 INFO - 'other-e10s': {'tests': ['a11yr', 15:42:52 INFO - 'ts_paint', 15:42:52 INFO - 'tpaint', 15:42:52 INFO - 'sessionrestore', 15:42:52 INFO - 'sessionrestore_no_auto_restore', 15:42:52 INFO - 'tabpaint']}, 15:42:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:42:52 INFO - 'ts_paint', 15:42:52 INFO - 'tpaint', 15:42:52 INFO - 'sessionrestore', 15:42:52 INFO - 'sessionrestore_no_auto_restore', 15:42:52 INFO - 'tabpaint']}, 15:42:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:42:52 INFO - 'ts_paint', 15:42:52 INFO - 'tpaint', 15:42:52 INFO - 'sessionrestore', 15:42:52 INFO - 'sessionrestore_no_auto_restore', 15:42:52 INFO - 'tabpaint']}, 15:42:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['a11yr', 15:42:52 INFO - 'ts_paint', 15:42:52 INFO - 'tpaint', 15:42:52 INFO - 'sessionrestore', 15:42:52 INFO - 'sessionrestore_no_auto_restore', 15:42:52 INFO - 'tabpaint']}, 15:42:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['a11yr', 15:42:52 INFO - 'ts_paint', 15:42:52 INFO - 'tpaint', 15:42:52 INFO - 'sessionrestore', 15:42:52 INFO - 'sessionrestore_no_auto_restore', 15:42:52 INFO - 'tabpaint']}, 15:42:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['tsvgx', 15:42:52 INFO - 'tsvgr_opacity', 15:42:52 INFO - 'tart', 15:42:52 INFO - 'tscrollx', 15:42:52 INFO - 'cart', 15:42:52 INFO - 'tsvg_static']}, 15:42:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:42:52 INFO - 'tsvgr_opacity', 15:42:52 INFO - 'tart', 15:42:52 INFO - 'tscrollx', 15:42:52 INFO - 'cart', 15:42:52 INFO - 'tsvg_static']}, 15:42:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:42:52 INFO - 'talos_options': ['--disable-e10s'], 15:42:52 INFO - 'tests': ['tp5o']}, 15:42:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:42:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:42:52 INFO - 'talos_options': ['--disable-e10s', 15:42:52 INFO - '--xperf_path', 15:42:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:52 INFO - 'tests': ['tp5n']}, 15:42:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:42:52 INFO - 'talos_options': ['--xperf_path', 15:42:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:52 INFO - 'tests': ['tp5n']}}, 15:42:52 INFO - 'talos.zip': {'path': '', 15:42:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:42:52 INFO - Running post-action listener: _resource_record_post_action 15:42:52 INFO - [mozharness: 2017-04-24 22:42:52.352073Z] Finished populate-webroot step (success) 15:42:52 INFO - [mozharness: 2017-04-24 22:42:52.352206Z] Running create-virtualenv step. 15:42:52 INFO - Running pre-action listener: _resource_record_pre_action 15:42:52 INFO - Running main action method: create_virtualenv 15:42:52 INFO - Creating virtualenv /builds/slave/test/build/venv 15:42:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:42:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:42:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:42:52 INFO - Using real prefix '/tools/python27' 15:42:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:42:53 INFO - Installing distribute.............................................................................................................................................................................................done. 15:42:56 INFO - Installing pip.................done. 15:42:56 INFO - Return code: 0 15:42:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:42:56 INFO - Reading from file tmpfile_stdout 15:42:56 INFO - Output received: 15:42:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:42:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:42:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:42:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:42:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:56 INFO - 'HOME': '/Users/cltbld', 15:42:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:56 INFO - 'LOGNAME': 'cltbld', 15:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:56 INFO - 'MOZ_NO_REMOTE': '1', 15:42:56 INFO - 'NO_EM_RESTART': '1', 15:42:56 INFO - 'PAGER': '/bin/cat', 15:42:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:56 INFO - 'PWD': '/builds/slave/test', 15:42:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:56 INFO - 'SHELL': '/bin/bash', 15:42:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:42:56 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:42:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:56 INFO - 'USER': 'cltbld', 15:42:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:56 INFO - 'XPC_FLAGS': '0x0', 15:42:56 INFO - 'XPC_SERVICE_NAME': '0', 15:42:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:42:56 INFO - Cleaning up... 15:42:56 INFO - Return code: 0 15:42:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:42:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:42:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:42:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:56 INFO - 'HOME': '/Users/cltbld', 15:42:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:56 INFO - 'LOGNAME': 'cltbld', 15:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:56 INFO - 'MOZ_NO_REMOTE': '1', 15:42:56 INFO - 'NO_EM_RESTART': '1', 15:42:56 INFO - 'PAGER': '/bin/cat', 15:42:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:56 INFO - 'PWD': '/builds/slave/test', 15:42:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:56 INFO - 'SHELL': '/bin/bash', 15:42:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:42:56 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:42:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:56 INFO - 'USER': 'cltbld', 15:42:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:56 INFO - 'XPC_FLAGS': '0x0', 15:42:56 INFO - 'XPC_SERVICE_NAME': '0', 15:42:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:56 INFO - Downloading/unpacking psutil>=3.1.1 15:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:43:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:43:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:43:00 INFO - Installing collected packages: psutil 15:43:00 INFO - Running setup.py install for psutil 15:43:00 INFO - building 'psutil._psutil_osx' extension 15:43:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:43:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:43:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:43:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:43:01 INFO - building 'psutil._psutil_posix' extension 15:43:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:43:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:43:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:43:01 INFO - ^ 15:43:01 INFO - 1 warning generated. 15:43:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:43:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:43:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:43:01 INFO - Successfully installed psutil 15:43:01 INFO - Cleaning up... 15:43:01 INFO - Return code: 0 15:43:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:01 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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:01 INFO - 'HOME': '/Users/cltbld', 15:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:01 INFO - 'LOGNAME': 'cltbld', 15:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:01 INFO - 'MOZ_NO_REMOTE': '1', 15:43:01 INFO - 'NO_EM_RESTART': '1', 15:43:01 INFO - 'PAGER': '/bin/cat', 15:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:01 INFO - 'PWD': '/builds/slave/test', 15:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:01 INFO - 'SHELL': '/bin/bash', 15:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:01 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:01 INFO - 'USER': 'cltbld', 15:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:01 INFO - 'XPC_FLAGS': '0x0', 15:43:01 INFO - 'XPC_SERVICE_NAME': '0', 15:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:43:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:43:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:43:04 INFO - Installing collected packages: mozsystemmonitor 15:43:04 INFO - Running setup.py install for mozsystemmonitor 15:43:04 INFO - Successfully installed mozsystemmonitor 15:43:04 INFO - Cleaning up... 15:43:04 INFO - Return code: 0 15:43:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43: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')]} 15:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43: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', '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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:43: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 jsonschema==2.5.1 15:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:05 INFO - 'HOME': '/Users/cltbld', 15:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:05 INFO - 'LOGNAME': 'cltbld', 15:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:05 INFO - 'MOZ_NO_REMOTE': '1', 15:43:05 INFO - 'NO_EM_RESTART': '1', 15:43:05 INFO - 'PAGER': '/bin/cat', 15:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:05 INFO - 'PWD': '/builds/slave/test', 15:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:05 INFO - 'SHELL': '/bin/bash', 15:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:05 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:05 INFO - 'USER': 'cltbld', 15:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:05 INFO - 'XPC_FLAGS': '0x0', 15:43:05 INFO - 'XPC_SERVICE_NAME': '0', 15:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:05 INFO - Downloading/unpacking jsonschema==2.5.1 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:43:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:09 INFO - Downloading functools32-3.2.3-2.tar.gz 15:43:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:43:09 INFO - warning: no files found matching '*.txt' 15:43:09 INFO - no previously-included directories found matching 'build' 15:43:09 INFO - no previously-included directories found matching 'dist' 15:43:09 INFO - no previously-included directories found matching '.git*' 15:43:09 INFO - Installing collected packages: jsonschema, functools32 15:43:09 INFO - Running setup.py install for functools32 15:43:09 INFO - warning: no files found matching '*.txt' 15:43:09 INFO - no previously-included directories found matching 'build' 15:43:09 INFO - no previously-included directories found matching 'dist' 15:43:09 INFO - no previously-included directories found matching '.git*' 15:43:09 INFO - Successfully installed jsonschema functools32 15:43:09 INFO - Cleaning up... 15:43:09 INFO - Return code: 0 15:43:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:09 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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:09 INFO - 'HOME': '/Users/cltbld', 15:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:09 INFO - 'LOGNAME': 'cltbld', 15:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:09 INFO - 'MOZ_NO_REMOTE': '1', 15:43:09 INFO - 'NO_EM_RESTART': '1', 15:43:09 INFO - 'PAGER': '/bin/cat', 15:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:09 INFO - 'PWD': '/builds/slave/test', 15:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:09 INFO - 'SHELL': '/bin/bash', 15:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:09 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:09 INFO - 'USER': 'cltbld', 15:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:09 INFO - 'XPC_FLAGS': '0x0', 15:43:09 INFO - 'XPC_SERVICE_NAME': '0', 15:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:43:09 INFO - Cleaning up... 15:43:09 INFO - Return code: 0 15:43:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:09 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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:09 INFO - 'HOME': '/Users/cltbld', 15:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:09 INFO - 'LOGNAME': 'cltbld', 15:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:09 INFO - 'MOZ_NO_REMOTE': '1', 15:43:09 INFO - 'NO_EM_RESTART': '1', 15:43:09 INFO - 'PAGER': '/bin/cat', 15:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:09 INFO - 'PWD': '/builds/slave/test', 15:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:09 INFO - 'SHELL': '/bin/bash', 15:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:09 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:09 INFO - 'USER': 'cltbld', 15:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:09 INFO - 'XPC_FLAGS': '0x0', 15:43:09 INFO - 'XPC_SERVICE_NAME': '0', 15:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:10 INFO - Downloading/unpacking blobuploader==1.2.4 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43:12 INFO - Downloading blobuploader-1.2.4.tar.gz 15:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:43:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:43:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43:13 INFO - Downloading docopt-0.6.1.tar.gz 15:43:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:43:14 INFO - Installing collected packages: blobuploader, requests, docopt 15:43:14 INFO - Running setup.py install for blobuploader 15:43:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:43:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:43:14 INFO - Running setup.py install for requests 15:43:14 INFO - Running setup.py install for docopt 15:43:14 INFO - Successfully installed blobuploader requests docopt 15:43:14 INFO - Cleaning up... 15:43:14 INFO - Return code: 0 15:43:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:14 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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:43:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:14 INFO - 'HOME': '/Users/cltbld', 15:43:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:14 INFO - 'LOGNAME': 'cltbld', 15:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:14 INFO - 'MOZ_NO_REMOTE': '1', 15:43:14 INFO - 'NO_EM_RESTART': '1', 15:43:14 INFO - 'PAGER': '/bin/cat', 15:43:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:14 INFO - 'PWD': '/builds/slave/test', 15:43:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:14 INFO - 'SHELL': '/bin/bash', 15:43:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:14 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:14 INFO - 'USER': 'cltbld', 15:43:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:14 INFO - 'XPC_FLAGS': '0x0', 15:43:14 INFO - 'XPC_SERVICE_NAME': '0', 15:43:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ib9yZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-70n3kO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-q5jbgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-v4wgsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-BQWAEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-YNaTfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-enbS3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-69JuJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:43:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-zeYJAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-3Ng84k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-t7qdLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-43XTlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-S4JzpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-D1rBht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-o77P5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-yLKX6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:43:16 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-XyynCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:43:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:43:16 INFO - Running setup.py install for manifestparser 15:43:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:43:17 INFO - Running setup.py install for mozcrash 15:43:17 INFO - Running setup.py install for mozdebug 15:43:17 INFO - Running setup.py install for mozdevice 15:43:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:43:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:43:17 INFO - Running setup.py install for mozfile 15:43:17 INFO - Running setup.py install for mozhttpd 15:43:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:43:17 INFO - Running setup.py install for mozinfo 15:43:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:43:17 INFO - Running setup.py install for mozInstall 15:43:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Running setup.py install for mozleak 15:43:18 INFO - Running setup.py install for mozlog 15:43:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Running setup.py install for moznetwork 15:43:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Running setup.py install for mozprocess 15:43:18 INFO - Running setup.py install for mozprofile 15:43:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:43:18 INFO - Running setup.py install for mozrunner 15:43:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:43:19 INFO - Running setup.py install for mozscreenshot 15:43:19 INFO - Running setup.py install for moztest 15:43:19 INFO - Running setup.py install for mozversion 15:43:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:43:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:43:19 INFO - Cleaning up... 15:43:19 INFO - Return code: 0 15:43:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:19 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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:43:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:19 INFO - 'HOME': '/Users/cltbld', 15:43:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:19 INFO - 'LOGNAME': 'cltbld', 15:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:19 INFO - 'MOZ_NO_REMOTE': '1', 15:43:19 INFO - 'NO_EM_RESTART': '1', 15:43:19 INFO - 'PAGER': '/bin/cat', 15:43:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:19 INFO - 'PWD': '/builds/slave/test', 15:43:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:19 INFO - 'SHELL': '/bin/bash', 15:43:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:19 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:19 INFO - 'USER': 'cltbld', 15:43:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:19 INFO - 'XPC_FLAGS': '0x0', 15:43:19 INFO - 'XPC_SERVICE_NAME': '0', 15:43:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:43:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-n99G8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:43:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:43:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-QnADcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:43:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:43:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-2ZvWB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-VxQpKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-1d6N6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-XiSZox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-E83FSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-6dFcOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-DWFBec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-im7E1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-CseGo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:43:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Zbo1g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:43:21 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jo0V73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:43:21 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-x5c26C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:21 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-xbd90x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:43:21 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Hleb6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:43:21 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-qZhZ0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:43:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:24 INFO - Downloading blessings-1.6.tar.gz 15:43:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:43:24 INFO - Installing collected packages: blessings 15:43:24 INFO - Running setup.py install for blessings 15:43:24 INFO - Successfully installed blessings 15:43:24 INFO - Cleaning up... 15:43:24 INFO - Return code: 0 15:43:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:43:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:24 INFO - Reading from file tmpfile_stdout 15:43:24 INFO - Current package versions: 15:43:24 INFO - blessings == 1.6 15:43:24 INFO - blobuploader == 1.2.4 15:43:24 INFO - docopt == 0.6.1 15:43:24 INFO - functools32 == 3.2.3-2 15:43:24 INFO - jsonschema == 2.5.1 15:43:24 INFO - manifestparser == 1.1 15:43:24 INFO - mozInstall == 1.12 15:43:24 INFO - mozcrash == 1.0 15:43:24 INFO - mozdebug == 0.1 15:43:24 INFO - mozdevice == 0.49 15:43:24 INFO - mozfile == 1.2 15:43:24 INFO - mozhttpd == 0.7 15:43:24 INFO - mozinfo == 0.9 15:43:24 INFO - mozleak == 0.1 15:43:24 INFO - mozlog == 3.4 15:43:24 INFO - moznetwork == 0.27 15:43:24 INFO - mozprocess == 0.25 15:43:24 INFO - mozprofile == 0.28 15:43:24 INFO - mozrunner == 6.13 15:43:24 INFO - mozscreenshot == 0.1 15:43:24 INFO - mozsystemmonitor == 0.3 15:43:24 INFO - moztest == 0.8 15:43:24 INFO - mozversion == 1.4 15:43:24 INFO - psutil == 3.1.1 15:43:24 INFO - requests == 1.2.3 15:43:24 INFO - wsgiref == 0.1.2 15:43:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:24 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 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:24 INFO - 'HOME': '/Users/cltbld', 15:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:24 INFO - 'LOGNAME': 'cltbld', 15:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:24 INFO - 'MOZ_NO_REMOTE': '1', 15:43:24 INFO - 'NO_EM_RESTART': '1', 15:43:24 INFO - 'PAGER': '/bin/cat', 15:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:24 INFO - 'PWD': '/builds/slave/test', 15:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:24 INFO - 'SHELL': '/bin/bash', 15:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:24 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:24 INFO - 'USER': 'cltbld', 15:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:24 INFO - 'XPC_FLAGS': '0x0', 15:43:24 INFO - 'XPC_SERVICE_NAME': '0', 15:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:43:25 INFO - Cleaning up... 15:43:25 INFO - Return code: 0 15:43:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1a8cd1f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, '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.ulrqkk7TUx/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.T2GInV1RjG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:43:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:43:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:25 INFO - 'HOME': '/Users/cltbld', 15:43:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:25 INFO - 'LOGNAME': 'cltbld', 15:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:25 INFO - 'MOZ_NO_REMOTE': '1', 15:43:25 INFO - 'NO_EM_RESTART': '1', 15:43:25 INFO - 'PAGER': '/bin/cat', 15:43:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:25 INFO - 'PWD': '/builds/slave/test', 15:43:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:25 INFO - 'SHELL': '/bin/bash', 15:43:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:25 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:25 INFO - 'USER': 'cltbld', 15:43:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:25 INFO - 'XPC_FLAGS': '0x0', 15:43:25 INFO - 'XPC_SERVICE_NAME': '0', 15:43:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:43:25 INFO - Cleaning up... 15:43:25 INFO - Return code: 0 15:43:25 INFO - Running post-action listener: _resource_record_post_action 15:43:25 INFO - Running post-action listener: _start_resource_monitoring 15:43:25 INFO - Starting resource monitoring. 15:43:25 INFO - [mozharness: 2017-04-24 22:43:25.394796Z] Finished create-virtualenv step (success) 15:43:25 INFO - [mozharness: 2017-04-24 22:43:25.395708Z] Running install step. 15:43:25 INFO - Running pre-action listener: _resource_record_pre_action 15:43:25 INFO - Running main action method: install 15:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:25 INFO - Reading from file tmpfile_stdout 15:43:25 INFO - Detecting whether we're running mozinstall >=1.0... 15:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:43:25 INFO - Reading from file tmpfile_stdout 15:43:25 INFO - Output received: 15:43:25 INFO - Usage: mozinstall [options] installer 15:43:25 INFO - Options: 15:43:25 INFO - -h, --help show this help message and exit 15:43:25 INFO - -d DEST, --destination=DEST 15:43:25 INFO - Directory to install application into. [default: 15:43:25 INFO - "/builds/slave/test"] 15:43:25 INFO - --app=APP Application being installed. [default: firefox] 15:43:25 INFO - mkdir: /builds/slave/test/build/application 15:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:43:40 INFO - Reading from file tmpfile_stdout 15:43:40 INFO - Output received: 15:43:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:43:40 INFO - Running post-action listener: _resource_record_post_action 15:43:40 INFO - [mozharness: 2017-04-24 22:43:40.571708Z] Finished install step (success) 15:43:40 INFO - [mozharness: 2017-04-24 22:43:40.571832Z] Running run-tests step. 15:43:40 INFO - Running pre-action listener: _resource_record_pre_action 15:43:40 INFO - Running main action method: run_tests 15:43:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:43:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:43:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:43:40 INFO - 2017-04-24 15:43:40.586 screenresolution[1626:7525] starting screenresolution argv=screenresolution get 15:43:40 INFO - 2017-04-24 15:43:40.589 screenresolution[1626:7525] Display 0: 1600x1200x32@60 15:43:40 INFO - 2017-04-24 15:43:40.594 screenresolution[1627:7527] starting screenresolution argv=screenresolution list 15:43:40 INFO - Available Modes on Display 0 15:43:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:43:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:43:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:43:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:43:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:43:40 INFO - Intel Iris: 15:43:40 INFO - Chipset Model: Intel Iris 15:43:40 INFO - Type: GPU 15:43:40 INFO - Bus: Built-In 15:43:40 INFO - VRAM (Dynamic, Max): 1536 MB 15:43:40 INFO - Vendor: Intel (0x8086) 15:43:40 INFO - Device ID: 0x0a2e 15:43:40 INFO - Revision ID: 0x0009 15:43:40 INFO - Displays: 15:43:40 INFO - Display: 15:43:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:43:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:43:40 INFO - Display Serial Number: 1600x1200 60 15:43:40 INFO - Main Display: Yes 15:43:40 INFO - Mirror: Off 15:43:40 INFO - Online: Yes 15:43:40 INFO - Rotation: Supported 15:43:40 INFO - Return code: 0 15:43:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:43:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:43:40 INFO - Python 2.7.3 15:43:40 INFO - Return code: 0 15:43:40 INFO - grabbing minidump binary from tooltool 15:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:40 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 0x106be2d30>, '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 0x106b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bd7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:43:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:43:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:43:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:43:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:43:40 INFO - Return code: 0 15:43:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:43:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:43:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:43:40 INFO - ENV: RUST_BACKTRACE is now 1 15:43:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:43:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:43:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:43:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0251 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:43:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulrqkk7TUx/Render', 15:43:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:40 INFO - 'HOME': '/Users/cltbld', 15:43:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:40 INFO - 'LOGNAME': 'cltbld', 15:43:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:43:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:40 INFO - 'MOZ_NO_REMOTE': '1', 15:43:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:43:40 INFO - 'NO_EM_RESTART': '1', 15:43:40 INFO - 'PAGER': '/bin/cat', 15:43:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:40 INFO - 'PWD': '/builds/slave/test', 15:43:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:43:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:40 INFO - 'RUST_BACKTRACE': '1', 15:43:40 INFO - 'SHELL': '/bin/bash', 15:43:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T2GInV1RjG/Listeners', 15:43:40 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 15:43:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:40 INFO - 'USER': 'cltbld', 15:43:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:40 INFO - 'XPC_FLAGS': '0x0', 15:43:40 INFO - 'XPC_SERVICE_NAME': '0', 15:43:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:43:41 INFO - mozversion application_buildid: 20170424145525 15:43:41 INFO - mozversion application_changeset: ca3c6131b6eadf753445b3cd7a54e29b41ab11aa 15:43:41 INFO - mozversion application_display_name: Firefox 15:43:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:43:41 INFO - mozversion application_name: Firefox 15:43:41 INFO - mozversion application_remotingname: firefox 15:43:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:43:41 INFO - mozversion application_vendor: Mozilla 15:43:41 INFO - mozversion application_version: 54.0 15:43:41 INFO - mozversion platform_buildid: 20170424145525 15:43:41 INFO - mozversion platform_changeset: ca3c6131b6eadf753445b3cd7a54e29b41ab11aa 15:43:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:43:41 INFO - mozversion platform_version: 54.0 15:43:41 INFO - using testdate: 1493073821 15:43:41 INFO - actual date: 1493073821 15:43:41 INFO - starting webserver on 'localhost:49249' 15:43:41 INFO - SUITE-START | Running 2 tests 15:43:41 INFO - TEST-START | dromaeo_css 15:43:41 INFO - operating with platform_type : mac_ 15:43:41 INFO - Initialising browser for dromaeo_css test... 15:43:41 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp6ukaRW/profile http://localhost:49249/getInfo.html) 15:43:44 INFO - PROCESS | 1637 | 2017-04-24 15:43:44.166 plugin-container[1640:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 15:43:44 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:43:44 INFO - PROCESS | 1637 | 2017-04-24 15:43:44.167 plugin-container[1640:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 15:43:44 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:43:44 INFO - PROCESS | 1637 | 2017-04-24 15:43:44.167 plugin-container[1640:7669] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:43:44 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 15:43:44 INFO - PROCESS | 1637 | colorDepth:24 15:43:44 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 15:43:44 INFO - PROCESS | 1637 | __metrics 15:43:44 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 15:43:44 INFO - TEST-INFO | 1637: exit 0 15:43:44 INFO - Browser initialized. 15:43:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:43:44 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp6ukaRW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:47 INFO - PROCESS | 1641 | 2017-04-24 15:43:47.312 plugin-container[1642:7801] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 15:43:47 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:43:47 INFO - PROCESS | 1641 | 2017-04-24 15:43:47.313 plugin-container[1642:7801] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.CFPasteboardClient' 15:43:47 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:43:47 INFO - PROCESS | 1641 | 2017-04-24 15:43:47.313 plugin-container[1642:7801] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:43:57 INFO - PROCESS | 1641 | RSS: Main: 174166016 15:43:57 INFO - PROCESS | 1641 | 15:46:59 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 15:47:00 INFO - PROCESS | 1641 | RSS: Main: 191279104 15:47:00 INFO - PROCESS | 1641 | 15:50:02 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 15:50:03 INFO - PROCESS | 1641 | RSS: Main: 202665984 15:50:03 INFO - PROCESS | 1641 | 15:53:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 15:53:05 INFO - PROCESS | 1641 | RSS: Main: 205295616 15:53:05 INFO - PROCESS | 1641 | 15:56:07 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 15:56:08 INFO - PROCESS | 1641 | RSS: Main: 208375808 15:56:08 INFO - PROCESS | 1641 | 15:59:11 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 15:59:11 INFO - PROCESS | 1641 | RSS: Main: 210833408 15:59:11 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 16:02:14 INFO - PROCESS | 1641 | RSS: Main: 208654336 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | __start_tp_report 16:02:14 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 16:02:14 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 16:02:14 INFO - PROCESS | 1641 | |i|pagename|runs| 16:02:14 INFO - PROCESS | 1641 | |0;dojo.html;2611.569091099969;2679.464107178565;2907.95638065429;3064.325848313372;3598.334308152992;5996.61022033568;6061.727222274997;6065.787697430591;6076.14933909252;6097;6032.728527216274;6038.39616038396;6099.878002439948;6116.9388306116925;6135.754569817202;5518.061929471999;5545.833624991236;5566.332040155166;5587.525060369853;5604.495595396414;5544.251526043993;5556.91664625031;5586.55307575395;5596;5609.0184217761935;5368.651037682557;5396.082665946778;5443.401225865152;5583.860403489925;5614.326280846304;5526.41972592878;5534.944650553503;5537.640053396557;5545.085060964936;5578.581606379497;5411.945880541183;5546.140348246028;5546.833594992117;5551.916721249185;5627.54979601635;5819.883602327972;6098.207233059718;6110.175126357929;6176.641138949424;6227.190465239535;6251.374862513758;6270.435660790551;6278;6290.119383286344;6304.842378940517;6493.902591461132;6516.380943810331;6519.543631945766;6528.183977002875;6529.31442198567;6144.846378840475;6149.446549810494;6165.321814600436;6194.225721784777;6201;6358.523110766711;6418.935810641927;6499.317571655003;6690.799276021728;6701.731930722814;6207.906881396782;6250.1874756281395;6274.466670333073;6283.6858157091965;6307.085472606446;6448.742050318028;6476.31998640136;6483.416492515602;6489.513286503531;6521.0544471051435;6206.968965155145;6343.55595108338;6348.016057511093;6360.236771587347;6368.713407896656;5087.516685914832;5114.87212819675;5345.915093113689;5753.252077229933;6294.716737746812;4994.200927851599;5041.269015992734;5091.745412729349;5153.793848246029;5155.407128180233;4572.97713511437;4576.816927322936;4584.33527138563;4630.884227894329;4631.397918270671;6018.668973206516;6068.726907289182;6163.8150855474405;6199.814005579839;6272.3100458949475;6337.841553961096;6356.4279214870885;6374.139491168633;6408.967955160288;6451.612903225821;6211.223597050369;6224.439800417984;6226.034964580497;6234.127222188898;6257.468115210167;4930.457649658535;5715.571332150105;5720.313562372542;5729.968605651023;5856.882862342814;6447.580907241015;6527.5430719849155;6658.6337751424135;6673.666316684244;6731.596104233761;6547.148870646785;6556.803295901131;6573.276939536551;6585.6377899216;6594.901076483952;6696.933030669631;6716.5634233774645;6784.423324017514;6806.591604503746;6812.761553345609;6192.318844926965;6197.628142311476;6307.842303942438;6450.774222902176;6509.251436084911;5640.858978525405;6438.774642887476;6466.256380516301;6469.7412103515335;6499.772507962199;6138.600990935575;6162.691865406801;6280.309165991828;6315.5894866833505;6318.526110541636;6128.594790794166;6161.168242287234;6212.502999760099;6242.219722534684;6282.057691346335;5740.13897915316;6116.052011938156;6125.449118208347;6145.8156255312415;6213.720382582885;6287.874242515032;6292.968535157295;6300;6304.590201636879;6341.714622842075;5992.760289588367;6053.152558641882;6130.000698177663;6164.506839452923;6218.533609979179 16:02:14 INFO - PROCESS | 1641 | |1;ext.html;677.7153595489893;701.7122979578373;759.0853022108358;844.7634662294556;969.2391472693937;38895.80552097239;39295.803520982394;40745.59254407451;40777.59222407775;40824;38556.614433855655;38840.805795970984;38859.61140388595;38895.41656875149;39068.80465597675;8244.134365891585;11158.721031974224;11392.829107563393;11547.30716157029;11595.188336816425;11166.050885674687;11234.662960111209;11252.887471125307;11270.774584508305;11303.208775385727;11247.718807029807;11276.943615281913;11283.097352211844;11285.887141128607;11353.602623908166;9503.667371642028;11217.158713096509;11248.831267530994;11254.043406310433;11257.60598379061;9969.850452243221;10471.79574348952;11020.779584408267;11069.50187241576;11073.833892491619;30373.69626303731;33825.15437114092;34512.82743586291;34619.4807077894;34681.82659086714;3278.934421311584;3806.257779732953;3823.9617603823885;3909.2376986487648;3991.2017596480528;25419.237422877344;25696;26368.472630547283;26879.790409431425;27246.5913011305;3181.316017056344;3204.3911656785367;3222.133103605345;3235.4176248275303;3241.7892836965757;385459;386156;386782;387953;388066;45302;45587.316190257174;45695.77152114218;45871.85320367017;45888.77055614701;46529.83675408142;46604.5339546607;46680.7665961668;46762.532374675815;46920;14078.577642670736;14434.422623094959;14463.710725785426;14518.911081668917;14530.636734081732;12476.064295177897;12481.75036499265;12482.563110291278;12494.312812795202;12509.374531273399;11558.94220528892;11958.58144964922;11959.40202989847;11962.82055769164;12022.759544809054;5786.189933409326;5794.55136215946;5840.2115714378015;5861.472467477947;5867.266591676041;1017.7455636090978;1163.8545181852269;1187.1155988788407;1203.1171217045296;1223.1315765806378;23628.763712363;26051.264617214918;26389.208323750318;27011.324716882234;27679.86160069227;1278.9488420463076;1288.2013151845915;1290.5870121561197;1311.8622544632867;1368.6783605852618;13726.245056521788;14013.859861401255;14108.15351078918;14272;14309.713805723828;450666;506401;507801;509764.90235097916;510235;47029.29456058162;47685.284720729214;57666.7116664414;57978;58328.41671583315;1471.0658731705444;1490.508132316333;1504.5260742865962;1516.2494565190302;1518.6658935034495;26739.197824065308;29276.853615731787;29527.704722952494;30301.696983029888;30499.2375190622;38849;45689.77155114203;45761.313580296985;45856.7707161462;45962;406978;462927;463739;464710;466857;31647.52528712117;32113.715451381682;32301.030969070966;32409.51385729263;32425.027249182564;44561.33158002698;45583.54416455793;46283.305750414445;46321.76839115783;46628.76685616686;30830.075097747103;30966.69033309638;31429.528557071204;31486.370272593962;31729.524057139624;28179.450130243033;30600.846995764878;30723.539146913263;30725.385492290476;31652;31470;31501.897433589715;33997.49003764896;37537.31082101257;37600.623993760804;28033.43933121367;31240.750369985853;31368;31452.89914852969;31611.84194079015;21364.893175534024;23555.822208889145;24539;24729.1344802931;25160.49679006373 16:02:14 INFO - PROCESS | 1641 | |2;jquery.html;423.9470066241716;433.1288832244702;435.880132963435;439.6219251443758;446.9441319835021;20194.293199737993;21162.576748465042;22393.440163995907;22584.322470325915;22693.77306226937;20376.083076261566;20392.388228353135;22404.55190896181;22851.085956561717;22975.19586814462;6547.967260163692;6603;6850.451963842881;6858.3141685831515;6902.5858448493;6069.817905462844;6797.490188235877;6829.965850170742;6831.897521537181;6858.897116543256;6506.154199954023;6771.052052712625;6809.6595170241535;6853.17761868574;6854.520183587151;6485.351464853524;6768.255491895888;6799.898001529981;6851.972204169365;6873.4844886633455;6572.835679108012;6594.835129121762;6609.438197753197;6610.0745895574655;6615.669216539202;25875.96496140152;26032.609510857354;26587.46825063488;26831.463370732476;27288.590671139948;3035.423269578795;3035.4384438878656;3068.435098099963;3084.5373194020854;3089.7373723233327;16246.43137490194;16786.83213167865;16869.915650421794;16978.23597938096;17422;1990.8506861985263;2024.342088821139;2057.3930690446355;2073.844461665381;2084.770675225724;131585.68414315925;140624.29687851496;150453;172216;181401;19461;20914.686279705817;20993.475163121046;21061.473463163544;21183.8940805295;31344.05967820969;31374;31394;31401.37197256042;31492.89774857869;10496.26526143178;10531.789364212826;10803.83794243087;10840.024397804078;10893.727656808644;8969.461832290084;8983.371164018456;9012.459252444874;9130.634774609074;9150.542472876463;9172.403793753385;9449.055094490495;9478.620855165716;9486.857697134548;9523.619055237712;3566.8394922228563;4359.607635312837;4378.693491455568;4396.208682437128;4426.15902978433;1054.1250761867632;1060.612876300145;1074.9677509674723;1075.0700643942932;1116.2855772305732;19807;21471.14115435396;21551.8922405387;22511.774882251295;23512.529749404915;946.8295706772709;952.6760901293455;979.6767066868059;981.7545613596601;994.1500017485091;6216.502679785606;6819.863602727918;6854.2117656469145;6961.129858767654;7158.4273258140265;790288;798139;801804;812070;821715;121348;161670;161777.19111404603;168301;170007.14996425185;1294.3592921503728;1316.6181807275975;1336.6858788184768;1342.8187194728587;1345.9259740714356;12324.568640097912;12347.567835125728;12480.875191247971;12604.432800523815;12738.87261127377;61230.77538449117;62206.68896655488;62440;63073;63669.3633063682;93983;94314;94437.5278123605;94775.52612236894;96241.03758962321;9980.950095249767;11157.553697851996;11444.313341199555;11672.416379181177;11710.531578737096;12491.188072775241;12524.686882828002;12610.684732881751;12708.428120734774;12817.679558010752;9194;11116.61091861781;11212.327260364404;11360.375179365214;11386.316820990767;10246.897531024595;10315.845262321222;10352.120069794151;10398.376097434179;10560.577576896838;11301.095912327159;11535.596254131066;11585.246958947642;11634.767304653691;11716.531338746696;11254.324740515594;11445.084393248353;11451.599194028502;11469.598564050219;11593.072554195813;9503.857442138511;10135.290529662852;10362.378257304586;10817.567297308335;11228.270162439416 16:02:14 INFO - PROCESS | 1641 | |3;mootools.html;1612.8870979031472;1638.6722655468902;1639.5737108351825;1656.089150966968;1656.0891509669686;9759.902400976005;10028.648997285096;10263.076323130917;10795.514201860915;10809.783804323919;11016.889831101687;11308.982191602774;11331.943340283287;11334.773304533905;11550.422478876064;2454.4477492564165;2503.4242124311327;2503.812214083942;2504.9373765655823;2517.987410062947;2460.8646524441147;2480.4790993891215;2499.4376265340243;2512.7612876776675;2520.117958714454;2473.109680515013;2485.6768620079365;2498.025769949721;2503.4116982509186;2506.774390304872;2318.3160967514623;2324.2562380038394;2325.534893021394;2331.440454290975;2339.7660233976635;2222.8777417242045;2237.6084185267514;2264.8754318512474;2384.9642255366184;2406.121765555577;10791.6222932197;10942.069924056465;10996.0653344465;11004.119670426428;11047;6027.427394397525;6143.907841382383;6173.598716083485;6180.690965451709;6184.041473571604;3573.4103872860946;3689.5572531296416;3696.1129328961124;3768.0391500167284;3769.4722738816313;1288.2270637617448;1913.8181872722068;1948.814862588052;1985.831095136199;2031.116464338003;15797.763033554505;15824.208789560476;15896.443624473204;15921.124338161397;15938.760918586231;4736.313234580966;4789.30555069513;4839;4845.733484658307;4852.539008794159;12004.099692522921;12015.819762703566;12017.759644807229;12023.458944347525;12058.035357171406;5871.530277577784;5912.260967379078;5937.70311484424;5948.256467941507;5979.073243647242;5264;5395.136778115482;5466.234727138124;5622.943770562324;5740.56945729072;3947.6626065655355;4042.939355909664;4066.9796651017145;4107.383892416162;4110.794460276974;1081.7079388565044;1085.6417382263835;1086.1148164246115;1095.8575385199872;1100.2518287564533;960.5917485068818;970.7864269860619;990.1830989433746;1002.7493126718321;1019.7603563162651;6744.966275168593;6767.289434609295;6951.548149370275;6973.3375329343635;6987.615681137586;751.8158051277362;888.7955770172896;951.357833462424;970.9951450242845;974;4066.4567227792018;4449.911001780011;4496.797644105957;4510.616597589242;4576.656750743708;13608.319584020759;13674.453021879212;13720.245386503839;14261.572152835432;14383.424663013571;11785.88214117848;14112.364943577564;14359.210243436712;14375.353109110116;14386.28068596566;10491.947540262403;10504;10709.571617135383;10760.838587421194;10779.514921828537;6860.279670634512;6965.582065076112;7118.5017048806085;7130.821729456805;7176.96411517939;10970.780584388427;12402.00783937301;12953.54662586805;12992.610221693365;13013.544525941925;11682.70793230176;11901.226420282654;12046.457909393923;13349.998750093977;13352;9386.5776040077;9468;9491.288153388661;9550.95224523873;9559.904400955902;9770.658026969022;10413.271071024956;10500.684979450629;10513.9474302628;10514.421706805893;8751.91248087511;8754.737357879274;8791.208791208823;8813.074627164055;8832.425892316978;8601.22588966996;8624.00823905259;8693.261072808882;8702.782430439289;8873.822523549621;7805.297523222937;7946.245106714853;7950;7961.880571791312;7967.32277756374;5832.883342333214;5890.852728681817;5968.731407086779;6004.519638429003;6084.026555751058;5994.730237139252;6113.23584551931;6219.222597175353;6261.624302541862;6269.529785266032 16:02:14 INFO - PROCESS | 1641 | |4;prototype.html;368.5650931900358;370.99258014839705;382.3156549775901;384.8499085356713;395.2529718831409;4384.451943507065;5189.313473121232;5229.477052294774;5276.393214780297;5330.254918716864;4660.766961651921;5115.6930584164975;5157.896842063165;5174.66364686296;5233.398159211692;3832.9808350958347;4376.518582955872;4389.999999999992;4414.448193975753;4460.085682435093;4400.867973960786;4429.5348988356245;4446.310821822606;4451;4456.442944631921;3882.825272862714;4424.8008839602135;4460.308652158904;4463.794775410637;4478.328250762379;3432.7470473277076;4124.05146816234;4304.849330273441;4312.482502099768;4354.455693038371;4016.7389119707127;4042.858499952517;4061.562423558634;4065.3698676705158;4094.8566800161852;4520.367148599199;4705.894114883;5070.478856343082;5161.303224064741;5167.664101833407;4580.404547408849;4761;4923.384576927884;5053.974730126326;5059.595232381401;4700.529947005306;5026.623003274769;5482.533984611313;5624.706317546947;5629.915551266734;3865.4781604483596;4397.516273209944;4561.840335588238;4585.220512512881;4588.059447813191;5661.433856614347;5954.761809527614;5973.342932277446;6053.152558641794;6054.545909056838;4173.874783756492;4626.629869610423;4655.3947986761505;4672.299155126758;4705.494241842646;4277.144571085795;4457.420535330386;4469.687121901501;4615.446146462446;4818.10864989978;4544.590986811203;4973.875653108701;4991.950080499221;5074.82238121664;5109.744512774347;5121.513456221653;5606.271184746039;5608.747606357723;5668.376478587352;5719.910264161144;1386.7449957788217;1609.0828227909979;1621.5864954436543;1635.6565121324418;1638.811536673274;1109.8834622364698;1178.9882101178878;1200.7418405042959;1217.4156404925686;1238.102375777554;4875.146849301358;5162.7542019221755;5283.630145889828;5381.569474442035;5391.973040134774;5201.583873290203;5240.868978275497;5242.475752424803;5857.88284234313;5907.7046147692445;4514.929847703527;4564.246899261585;4890.58430033444;5352.598555108305;5449.727513624303;3031.818090914552;3040.403788011312;3042.3763128558594;3067.371188906269;3082.892098776532;5480.534154596904;5601.971990140105;5744.827655170351;6118.969405152946;6132.834761395321;4687.718736875799;5237.978594174176;5443.7822487100075;5597.608167428322;6018.291768704101;5369.201317558662;5815.912761308584;5866.5893387462465;6001.489873360726;6034.999999999912;5387.138057910715;5823.330317013429;5839.386864379179;5839.445252700986;5868.471837534642;4922.778474968563;5344.652597581145;5465.398806131243;5731.160122717873;5773.682447465262;5172.646445867046;5620.494155526022;5953.469775345374;6061.212042434456;6114.104627565469;4015.759054456742;4360.993942786354;4464.285714285698;4575.506349039715;4591.288350305575;4958.57852082577;5027.974860125676;5303.814366497309;5375.946240537544;5809.796657116981;5120.543633047752;5461.726913654381;5650.943490565041;5692;5724.254102498778;4527.615152712056;4831.830885918976;5299.800582758112;5654.123610840326;5687.943120568907;4952.959878425571;5426.891462170814;5473.781048758005;5509.3664228612615;5538.6399884007415;5080.898382032413;5178.97410512945;5324.361076670824;5650.237217975685;5728.942710572841;4868.612445453029;5277.868053298698;5455.5908306876345;5545.944540554704;5575.693336866511 16:02:14 INFO - PROCESS | 1641 | |5;yui.html;683.43616516374;684.127737135153;754.8226166850789;762.9351505122065;909.8635204719292;15893.68212635748;16688.666226675457;17227.569310767223;17464.301427942897;17538.386156484492;14520.709585808278;14669.633259168526;14708.117512949231;15069.698606027872;15947.920260398681;997.5012493753142;1026.7535791410082;1027.850961610566;1027.994860025699;1029.9948500257487;1003.0922015575916;1024.6106479537766;1051.3481641382355;1061.5117046158775;1062.5271754069443;1078.7626722121122;1084.7342401111698;1085.6200329884557;1086.8532748078987;1088.0697004061492;1076.4832880217461;1081.4484612847425;1082.4804094034907;1083.707399002273;1085.2511766880866;793.9444238903279;802.2659266770897;803.8995125609299;804.8631732605471;805.2832978649008;14891.925540372336;17138.57153571158;17141.48575542723;17248.223829927683;18042.729359059624;11934.104942129288;13718.862811371859;14635.63410914725;14718.116912985255;14847.331870065873;11986.460609272604;12077.45651445687;12218.511259549607;12342.13605047647;12732.427040783094;3326.6673332666783;3869.129445874684;3922.4704664870164;3927.1163988102735;4038.5557588665224;120453.39773301165;123615;123838;124532;126976;11323.263987840928;11346.092312614972;11507.654770356903;11534.192606517463;11650.88349116515;23520.64719029216;23538;23785.04859805623;23798.286051418487;24138.758612414003;7882.330001949898;7958.3235424989525;8200.179982001751;8418.705345312885;8451.577421129043;4539.16025535277;4593.104344652827;4794.113089078532;4879.585235254972;4893.828715994923;3119.173419043955;3639.4358874373984;3734.626537346298;3742.3076730804887;3753.962460375416;588.3498733899065;594.2809200866999;607.4229481992126;609.3479976425272;610.7709608896664;558.8794467093452;596.832886791699;603.8490377405649;664.9601023938483;735.2023054985294;8836.55817209137;10503.212259080447;10721;11072.612458564072;11217;561.7135261016911;606.6178307666142;611.6788685939918;633.0567454492865;756.1493320014984;4831.492693267227;5316.362036555561;5364.383095943938;5388.91916621251;5401.162819762943;82164;106105;108226;109131.36302955462;110013;59336.703316483145;62301.065484017774;80262.19737802664;90619.09380906107;90913.54543227374;75776.24223757692;77900;78150.82773758398;78273.60863195648;79239;10713.517891695048;11134.665960021212;11145.162734699377;11279.605213817476;11484.655460336204;41885.37171942362;44549;44991.7750411259;47579.76210118927;48857.51142488672;52984.73507632592;67928.6603566979;69085;70079.64960175166;70354;13602.727945441233;13721.45114195421;13878;14006.719865602834;14426.350814213176;14701.117932924059;14717.55847324582;14811.333489993192;15107.32017059257;15740.97683650559;13747.312634368041;13914.234717090252;14070.78893816614;14091.859081409464;14590.85409145895;13440.46238150463;13926.860731392557;13970.161790292615;14126.576202713935;14157.36291866848;13089.345532723135;13738.10702304347;13780.793288100485;13796.655083622989;14015.50945716895;9970.451625160686;10160.796784064129;10711.732206694895;10997.12023038171;11020.478914394544;8191.713290034821;10127.898721012696;10447.87279116704;10544.15646748243;10743.818120230439 16:02:14 INFO - PROCESS | 1641 | __end_tp_report 16:02:14 INFO - PROCESS | 1641 | __start_cc_report 16:02:14 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,388 16:02:14 INFO - PROCESS | 1641 | __end_cc_report 16:02:14 INFO - PROCESS | 1641 | __startTimestamp1493074934900__endTimestamp 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | ------- Summary: start ------- 16:02:14 INFO - PROCESS | 1641 | Number of tests: 6 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5956.15 Median:6192.32 stddev:721.32 (11.6%) stddev-sans-first:674.13 16:02:14 INFO - PROCESS | 1641 | Values: 2611.6 2679.5 2908.0 3064.3 3598.3 5996.6 6061.7 6065.8 6076.1 6097.0 6032.7 6038.4 6099.9 6116.9 6135.8 5518.1 5545.8 5566.3 5587.5 5604.5 5544.3 5556.9 5586.6 5596.0 5609.0 5368.7 5396.1 5443.4 5583.9 5614.3 5526.4 5534.9 5537.6 5545.1 5578.6 5411.9 5546.1 5546.8 5551.9 5627.5 5819.9 6098.2 6110.2 6176.6 6227.2 6251.4 6270.4 6278.0 6290.1 6304.8 6493.9 6516.4 6519.5 6528.2 6529.3 6144.8 6149.4 6165.3 6194.2 6201.0 6358.5 6418.9 6499.3 6690.8 6701.7 6207.9 6250.2 6274.5 6283.7 6307.1 6448.7 6476.3 6483.4 6489.5 6521.1 6207.0 6343.6 6348.0 6360.2 6368.7 5087.5 5114.9 5345.9 5753.3 6294.7 4994.2 5041.3 5091.7 5153.8 5155.4 4573.0 4576.8 4584.3 4630.9 4631.4 6018.7 6068.7 6163.8 6199.8 6272.3 6337.8 6356.4 6374.1 6409.0 6451.6 6211.2 6224.4 6226.0 6234.1 6257.5 4930.5 5715.6 5720.3 5730.0 5856.9 6447.6 6527.5 6658.6 6673.7 6731.6 6547.1 6556.8 6573.3 6585.6 6594.9 6696.9 6716.6 6784.4 6806.6 6812.8 6192.3 6197.6 6307.8 6450.8 6509.3 5640.9 6438.8 6466.3 6469.7 6499.8 6138.6 6162.7 6280.3 6315.6 6318.5 6128.6 6161.2 6212.5 6242.2 6282.1 5740.1 6116.1 6125.4 6145.8 6213.7 6287.9 6293.0 6300.0 6304.6 6341.7 5992.8 6053.2 6130.0 6164.5 6218.5 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:57662.72 Median:31494.13 stddev:119128.53 (378.3%) stddev-sans-first:119385.89 16:02:14 INFO - PROCESS | 1641 | Values: 677.7 701.7 759.1 844.8 969.2 38895.8 39295.8 40745.6 40777.6 40824.0 38556.6 38840.8 38859.6 38895.4 39068.8 8244.1 11158.7 11392.8 11547.3 11595.2 11166.1 11234.7 11252.9 11270.8 11303.2 11247.7 11276.9 11283.1 11285.9 11353.6 9503.7 11217.2 11248.8 11254.0 11257.6 9969.9 10471.8 11020.8 11069.5 11073.8 30373.7 33825.2 34512.8 34619.5 34681.8 3278.9 3806.3 3824.0 3909.2 3991.2 25419.2 25696.0 26368.5 26879.8 27246.6 3181.3 3204.4 3222.1 3235.4 3241.8 385459.0 386156.0 386782.0 387953.0 388066.0 45302.0 45587.3 45695.8 45871.9 45888.8 46529.8 46604.5 46680.8 46762.5 46920.0 14078.6 14434.4 14463.7 14518.9 14530.6 12476.1 12481.8 12482.6 12494.3 12509.4 11558.9 11958.6 11959.4 11962.8 12022.8 5786.2 5794.6 5840.2 5861.5 5867.3 1017.7 1163.9 1187.1 1203.1 1223.1 23628.8 26051.3 26389.2 27011.3 27679.9 1278.9 1288.2 1290.6 1311.9 1368.7 13726.2 14013.9 14108.2 14272.0 14309.7 450666.0 506401.0 507801.0 509764.9 510235.0 47029.3 47685.3 57666.7 57978.0 58328.4 1471.1 1490.5 1504.5 1516.2 1518.7 26739.2 29276.9 29527.7 30301.7 30499.2 38849.0 45689.8 45761.3 45856.8 45962.0 406978.0 462927.0 463739.0 464710.0 466857.0 31647.5 32113.7 32301.0 32409.5 32425.0 44561.3 45583.5 46283.3 46321.8 46628.8 30830.1 30966.7 31429.5 31486.4 31729.5 28179.5 30600.8 30723.5 30725.4 31652.0 31470.0 31501.9 33997.5 37537.3 37600.6 28033.4 31240.8 31368.0 31452.9 31611.8 21364.9 23555.8 24539.0 24729.1 25160.5 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:44679.83 Median:23243.86 stddev:134083.61 (576.9%) stddev-sans-first:134418.57 16:02:14 INFO - PROCESS | 1641 | Values: 423.9 433.1 435.9 439.6 446.9 20194.3 21162.6 22393.4 22584.3 22693.8 20376.1 20392.4 22404.6 22851.1 22975.2 6548.0 6603.0 6850.5 6858.3 6902.6 6069.8 6797.5 6830.0 6831.9 6858.9 6506.2 6771.1 6809.7 6853.2 6854.5 6485.4 6768.3 6799.9 6852.0 6873.5 6572.8 6594.8 6609.4 6610.1 6615.7 25876.0 26032.6 26587.5 26831.5 27288.6 3035.4 3035.4 3068.4 3084.5 3089.7 16246.4 16786.8 16869.9 16978.2 17422.0 1990.9 2024.3 2057.4 2073.8 2084.8 131585.7 140624.3 150453.0 172216.0 181401.0 19461.0 20914.7 20993.5 21061.5 21183.9 31344.1 31374.0 31394.0 31401.4 31492.9 10496.3 10531.8 10803.8 10840.0 10893.7 8969.5 8983.4 9012.5 9130.6 9150.5 9172.4 9449.1 9478.6 9486.9 9523.6 3566.8 4359.6 4378.7 4396.2 4426.2 1054.1 1060.6 1075.0 1075.1 1116.3 19807.0 21471.1 21551.9 22511.8 23512.5 946.8 952.7 979.7 981.8 994.2 6216.5 6819.9 6854.2 6961.1 7158.4 790288.0 798139.0 801804.0 812070.0 821715.0 121348.0 161670.0 161777.2 168301.0 170007.1 1294.4 1316.6 1336.7 1342.8 1345.9 12324.6 12347.6 12480.9 12604.4 12738.9 61230.8 62206.7 62440.0 63073.0 63669.4 93983.0 94314.0 94437.5 94775.5 96241.0 9981.0 11157.6 11444.3 11672.4 11710.5 12491.2 12524.7 12610.7 12708.4 12817.7 9194.0 11116.6 11212.3 11360.4 11386.3 10246.9 10315.8 10352.1 10398.4 10560.6 11301.1 11535.6 11585.2 11634.8 11716.5 11254.3 11445.1 11451.6 11469.6 11593.1 9503.9 10135.3 10362.4 10817.6 11228.3 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6895.98 Median:2505.86 stddev:4257.05 (169.9%) stddev-sans-first:4250.49 16:02:14 INFO - PROCESS | 1641 | Values: 1612.9 1638.7 1639.6 1656.1 1656.1 9759.9 10028.6 10263.1 10795.5 10809.8 11016.9 11309.0 11331.9 11334.8 11550.4 2454.4 2503.4 2503.8 2504.9 2518.0 2460.9 2480.5 2499.4 2512.8 2520.1 2473.1 2485.7 2498.0 2503.4 2506.8 2318.3 2324.3 2325.5 2331.4 2339.8 2222.9 2237.6 2264.9 2385.0 2406.1 10791.6 10942.1 10996.1 11004.1 11047.0 6027.4 6143.9 6173.6 6180.7 6184.0 3573.4 3689.6 3696.1 3768.0 3769.5 1288.2 1913.8 1948.8 1985.8 2031.1 15797.8 15824.2 15896.4 15921.1 15938.8 4736.3 4789.3 4839.0 4845.7 4852.5 12004.1 12015.8 12017.8 12023.5 12058.0 5871.5 5912.3 5937.7 5948.3 5979.1 5264.0 5395.1 5466.2 5622.9 5740.6 3947.7 4042.9 4067.0 4107.4 4110.8 1081.7 1085.6 1086.1 1095.9 1100.3 960.6 970.8 990.2 1002.7 1019.8 6745.0 6767.3 6951.5 6973.3 6987.6 751.8 888.8 951.4 971.0 974.0 4066.5 4449.9 4496.8 4510.6 4576.7 13608.3 13674.5 13720.2 14261.6 14383.4 11785.9 14112.4 14359.2 14375.4 14386.3 10491.9 10504.0 10709.6 10760.8 10779.5 6860.3 6965.6 7118.5 7130.8 7177.0 10970.8 12402.0 12953.5 12992.6 13013.5 11682.7 11901.2 12046.5 13350.0 13352.0 9386.6 9468.0 9491.3 9551.0 9559.9 9770.7 10413.3 10500.7 10513.9 10514.4 8751.9 8754.7 8791.2 8813.1 8832.4 8601.2 8624.0 8693.3 8702.8 8873.8 7805.3 7946.2 7950.0 7961.9 7967.3 5832.9 5890.9 5968.7 6004.5 6084.0 5994.7 6113.2 6219.2 6261.6 6269.5 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4689.45 Median:5065.04 stddev:1290.11 (25.5%) stddev-sans-first:1252.30 16:02:14 INFO - PROCESS | 1641 | Values: 368.6 371.0 382.3 384.8 395.3 4384.5 5189.3 5229.5 5276.4 5330.3 4660.8 5115.7 5157.9 5174.7 5233.4 3833.0 4376.5 4390.0 4414.4 4460.1 4400.9 4429.5 4446.3 4451.0 4456.4 3882.8 4424.8 4460.3 4463.8 4478.3 3432.7 4124.1 4304.8 4312.5 4354.5 4016.7 4042.9 4061.6 4065.4 4094.9 4520.4 4705.9 5070.5 5161.3 5167.7 4580.4 4761.0 4923.4 5054.0 5059.6 4700.5 5026.6 5482.5 5624.7 5629.9 3865.5 4397.5 4561.8 4585.2 4588.1 5661.4 5954.8 5973.3 6053.2 6054.5 4173.9 4626.6 4655.4 4672.3 4705.5 4277.1 4457.4 4469.7 4615.4 4818.1 4544.6 4973.9 4992.0 5074.8 5109.7 5121.5 5606.3 5608.7 5668.4 5719.9 1386.7 1609.1 1621.6 1635.7 1638.8 1109.9 1179.0 1200.7 1217.4 1238.1 4875.1 5162.8 5283.6 5381.6 5392.0 5201.6 5240.9 5242.5 5857.9 5907.7 4514.9 4564.2 4890.6 5352.6 5449.7 3031.8 3040.4 3042.4 3067.4 3082.9 5480.5 5602.0 5744.8 6119.0 6132.8 4687.7 5238.0 5443.8 5597.6 6018.3 5369.2 5815.9 5866.6 6001.5 6035.0 5387.1 5823.3 5839.4 5839.4 5868.5 4922.8 5344.7 5465.4 5731.2 5773.7 5172.6 5620.5 5953.5 6061.2 6114.1 4015.8 4361.0 4464.3 4575.5 4591.3 4958.6 5028.0 5303.8 5375.9 5809.8 5120.5 5461.7 5650.9 5692.0 5724.3 4527.6 4831.8 5299.8 5654.1 5687.9 4953.0 5426.9 5473.8 5509.4 5538.6 5080.9 5179.0 5324.4 5650.2 5728.9 4868.6 5277.9 5455.6 5545.9 5575.7 16:02:14 INFO - PROCESS | 1641 | 16:02:14 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20823.00 Median:15920.80 stddev:30154.61 (189.4%) stddev-sans-first:30201.28 16:02:14 INFO - PROCESS | 1641 | Values: 683.4 684.1 754.8 762.9 909.9 15893.7 16688.7 17227.6 17464.3 17538.4 14520.7 14669.6 14708.1 15069.7 15947.9 997.5 1026.8 1027.9 1028.0 1030.0 1003.1 1024.6 1051.3 1061.5 1062.5 1078.8 1084.7 1085.6 1086.9 1088.1 1076.5 1081.4 1082.5 1083.7 1085.3 793.9 802.3 803.9 804.9 805.3 14891.9 17138.6 17141.5 17248.2 18042.7 11934.1 13718.9 14635.6 14718.1 14847.3 11986.5 12077.5 12218.5 12342.1 12732.4 3326.7 3869.1 3922.5 3927.1 4038.6 120453.4 123615.0 123838.0 124532.0 126976.0 11323.3 11346.1 11507.7 11534.2 11650.9 23520.6 23538.0 23785.0 23798.3 24138.8 7882.3 7958.3 8200.2 8418.7 8451.6 4539.2 4593.1 4794.1 4879.6 4893.8 3119.2 3639.4 3734.6 3742.3 3754.0 588.3 594.3 607.4 609.3 610.8 558.9 596.8 603.8 665.0 735.2 8836.6 10503.2 10721.0 11072.6 11217.0 561.7 606.6 611.7 633.1 756.1 4831.5 5316.4 5364.4 5388.9 5401.2 82164.0 106105.0 108226.0 109131.4 110013.0 59336.7 62301.1 80262.2 90619.1 90913.5 75776.2 77900.0 78150.8 78273.6 79239.0 10713.5 11134.7 11145.2 11279.6 11484.7 41885.4 44549.0 44991.8 47579.8 48857.5 52984.7 67928.7 69085.0 70079.6 70354.0 13602.7 13721.5 13878.0 14006.7 14426.4 14701.1 14717.6 14811.3 15107.3 15741.0 13747.3 13914.2 14070.8 14091.9 14590.9 13440.5 13926.9 13970.2 14126.6 14157.4 13089.3 13738.1 13780.8 13796.7 14015.5 9970.5 10160.8 10711.7 10997.1 11020.5 8191.7 10127.9 10447.9 10544.2 10743.8 16:02:14 INFO - PROCESS | 1641 | -------- Summary: end -------- 16:02:14 INFO - PROCESS | 1641 | 16:02:15 INFO - TEST-INFO | 1641: exit 0 16:02:15 INFO - TEST-OK | dromaeo_css | took 1114306ms 16:02:15 INFO - TEST-START | kraken 16:02:15 INFO - operating with platform_type : mac_ 16:02:15 INFO - Initialising browser for kraken test... 16:02:15 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpREEN_S/profile http://localhost:49249/getInfo.html) 16:02:17 INFO - PROCESS | 1653 | 2017-04-24 16:02:17.990 plugin-container[1655:10459] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 16:02:17 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:02:17 INFO - PROCESS | 1653 | 2017-04-24 16:02:17.990 plugin-container[1655:10459] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:02:17 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:02:17 INFO - PROCESS | 1653 | 2017-04-24 16:02:17.990 plugin-container[1655:10459] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:02:18 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 16:02:18 INFO - PROCESS | 1653 | colorDepth:24 16:02:18 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 16:02:18 INFO - PROCESS | 1653 | __metrics 16:02:18 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 16:02:18 INFO - TEST-INFO | 1653: exit 0 16:02:18 INFO - Browser initialized. 16:02:18 INFO - Running cycle 1/1 for kraken test... 16:02:18 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpREEN_S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:21 INFO - PROCESS | 1656 | 2017-04-24 16:02:21.136 plugin-container[1657:10583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 16:02:21 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:02:21 INFO - PROCESS | 1656 | 2017-04-24 16:02:21.136 plugin-container[1657:10583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 16:02:21 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:02:21 INFO - PROCESS | 1656 | 2017-04-24 16:02:21.136 plugin-container[1657:10583] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:02:30 INFO - PROCESS | 1656 | RSS: Main: 174624768 16:02:30 INFO - PROCESS | 1656 | 16:02:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 16:02:34 INFO - PROCESS | 1656 | RSS: Main: 188153856 16:02:34 INFO - PROCESS | 1656 | 16:02:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 16:02:37 INFO - PROCESS | 1656 | RSS: Main: 196079616 16:02:37 INFO - PROCESS | 1656 | 16:02:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 16:02:42 INFO - PROCESS | 1656 | RSS: Main: 197664768 16:02:42 INFO - PROCESS | 1656 | 16:02:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 16:02:44 INFO - PROCESS | 1656 | RSS: Main: 200417280 16:02:44 INFO - PROCESS | 1656 | 16:02:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:02:47 INFO - PROCESS | 1656 | RSS: Main: 201080832 16:02:47 INFO - PROCESS | 1656 | 16:02:50 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 16:02:50 INFO - PROCESS | 1656 | RSS: Main: 204869632 16:02:50 INFO - PROCESS | 1656 | 16:02:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 16:02:54 INFO - PROCESS | 1656 | RSS: Main: 204496896 16:02:54 INFO - PROCESS | 1656 | 16:02:57 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 16:02:58 INFO - PROCESS | 1656 | RSS: Main: 204648448 16:02:58 INFO - PROCESS | 1656 | 16:03:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:03:00 INFO - PROCESS | 1656 | RSS: Main: 204410880 16:03:00 INFO - PROCESS | 1656 | 16:03:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:03:03 INFO - PROCESS | 1656 | RSS: Main: 204394496 16:03:03 INFO - PROCESS | 1656 | 16:03:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:03:05 INFO - PROCESS | 1656 | RSS: Main: 204435456 16:03:05 INFO - PROCESS | 1656 | 16:03:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:03:09 INFO - PROCESS | 1656 | RSS: Main: 204754944 16:03:09 INFO - PROCESS | 1656 | 16:03:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:03:13 INFO - PROCESS | 1656 | RSS: Main: 204894208 16:03:13 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 16:03:14 INFO - PROCESS | 1656 | RSS: Main: 207093760 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | __start_tp_report 16:03:14 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 16:03:14 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 16:03:14 INFO - PROCESS | 1656 | |i|pagename|runs| 16:03:14 INFO - PROCESS | 1656 | |0;ai-astar;109;121;120;122;121;121;121;123;120;122 16:03:14 INFO - PROCESS | 1656 | |1;audio-beat-detection;142;136;135;170;110;110;118;134;125;134 16:03:14 INFO - PROCESS | 1656 | |2;audio-dft;204;193;185;265;204;272;273;198;180;264 16:03:14 INFO - PROCESS | 1656 | |3;audio-fft;82;61;59;87;62;59;63;79;67;76 16:03:14 INFO - PROCESS | 1656 | |4;audio-oscillator;88;70;78;85;100;98;96;72;70;84 16:03:14 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;91;92;91;78;127;90;90;91;92;94 16:03:14 INFO - PROCESS | 1656 | |6;imaging-darkroom;143;98;102;97;103;145;147;126;97;97 16:03:14 INFO - PROCESS | 1656 | |7;imaging-desaturate;90;96;93;87;93;93;90;90;93;118 16:03:14 INFO - PROCESS | 1656 | |8;json-parse-financial;66;65;66;70;68;71;67;68;76;95 16:03:14 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;60;61;63;67;61;61;60;74;68;59 16:03:14 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;96;98;82;85;83;81;83;85;81;83 16:03:14 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;146;138;140;129;144;140;147;151;201;145 16:03:14 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;166;164;166;163;163;160;164;167;159;159 16:03:14 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;66;66;64;64;63;66;64;63;80;81 16:03:14 INFO - PROCESS | 1656 | __end_tp_report 16:03:14 INFO - PROCESS | 1656 | __start_cc_report 16:03:14 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,976 16:03:14 INFO - PROCESS | 1656 | __end_cc_report 16:03:14 INFO - PROCESS | 1656 | __startTimestamp1493074994961__endTimestamp 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | ------- Summary: start ------- 16:03:14 INFO - PROCESS | 1656 | Number of tests: 14 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:120.00 Median:121.00 stddev:3.97 (3.3%) stddev-sans-first:0.97 16:03:14 INFO - PROCESS | 1656 | Values: 109.0 121.0 120.0 122.0 121.0 121.0 121.0 123.0 120.0 122.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:134.50 stddev:17.60 (13.1%) stddev-sans-first:18.24 16:03:14 INFO - PROCESS | 1656 | Values: 142.0 136.0 135.0 170.0 110.0 110.0 118.0 134.0 125.0 134.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:223.80 Median:234.00 stddev:39.27 (16.8%) stddev-sans-first:40.99 16:03:14 INFO - PROCESS | 1656 | Values: 204.0 193.0 185.0 265.0 204.0 272.0 273.0 198.0 180.0 264.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:69.50 Median:71.50 stddev:10.50 (14.7%) stddev-sans-first:10.12 16:03:14 INFO - PROCESS | 1656 | Values: 82.0 61.0 59.0 87.0 62.0 59.0 63.0 79.0 67.0 76.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:84.50 stddev:11.47 (13.6%) stddev-sans-first:12.08 16:03:14 INFO - PROCESS | 1656 | Values: 88.0 70.0 78.0 85.0 100.0 98.0 96.0 72.0 70.0 84.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:91.00 stddev:12.52 (13.8%) stddev-sans-first:13.24 16:03:14 INFO - PROCESS | 1656 | Values: 91.0 92.0 91.0 78.0 127.0 90.0 90.0 91.0 92.0 94.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:115.50 Median:122.00 stddev:22.11 (18.1%) stddev-sans-first:21.10 16:03:14 INFO - PROCESS | 1656 | Values: 143.0 98.0 102.0 97.0 103.0 145.0 147.0 126.0 97.0 97.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:93.00 stddev:8.69 (9.3%) stddev-sans-first:9.08 16:03:14 INFO - PROCESS | 1656 | Values: 90.0 96.0 93.0 87.0 93.0 93.0 90.0 90.0 93.0 118.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:71.20 Median:69.00 stddev:8.95 (13.0%) stddev-sans-first:9.30 16:03:14 INFO - PROCESS | 1656 | Values: 66.0 65.0 66.0 70.0 68.0 71.0 67.0 68.0 76.0 95.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:62.00 stddev:4.79 (7.7%) stddev-sans-first:4.92 16:03:14 INFO - PROCESS | 1656 | Values: 60.0 61.0 63.0 67.0 61.0 61.0 60.0 74.0 68.0 59.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:84.00 stddev:6.13 (7.3%) stddev-sans-first:5.25 16:03:14 INFO - PROCESS | 1656 | Values: 96.0 98.0 82.0 85.0 83.0 81.0 83.0 85.0 81.0 83.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:145.50 stddev:19.54 (13.4%) stddev-sans-first:20.71 16:03:14 INFO - PROCESS | 1656 | Values: 146.0 138.0 140.0 129.0 144.0 140.0 147.0 151.0 201.0 145.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:164.00 stddev:2.92 (1.8%) stddev-sans-first:2.91 16:03:14 INFO - PROCESS | 1656 | Values: 166.0 164.0 166.0 163.0 163.0 160.0 164.0 167.0 159.0 159.0 16:03:14 INFO - PROCESS | 1656 | 16:03:14 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:66.00 stddev:6.85 (10.4%) stddev-sans-first:7.24 16:03:14 INFO - PROCESS | 1656 | Values: 66.0 66.0 64.0 64.0 63.0 66.0 64.0 63.0 80.0 81.0 16:03:14 INFO - PROCESS | 1656 | -------- Summary: end -------- 16:03:14 INFO - PROCESS | 1656 | 16:03:15 INFO - TEST-INFO | 1656: exit 0 16:03:15 INFO - TEST-OK | kraken | took 60391ms 16:03:15 INFO - SUITE-END | took 1174s 16:03:16 INFO - Completed test suite (00:19:34) 16:03:16 INFO - javascript benchmark 16:03:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7830.780940663879, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2611.569091099969, 2679.464107178565, 2907.95638065429, 3064.325848313372, 3598.334308152992, 5996.61022033568, 6061.727222274997, 6065.787697430591, 6076.14933909252, 6097.0, 6032.728527216274, 6038.39616038396, 6099.878002439948, 6116.9388306116925, 6135.754569817202, 5518.061929471999, 5545.833624991236, 5566.332040155166, 5587.525060369853, 5604.495595396414, 5544.251526043993, 5556.91664625031, 5586.55307575395, 5596.0, 5609.0184217761935, 5368.651037682557, 5396.082665946778, 5443.401225865152, 5583.860403489925, 5614.326280846304, 5526.41972592878, 5534.944650553503, 5537.640053396557, 5545.085060964936, 5578.581606379497, 5411.945880541183, 5546.140348246028, 5546.833594992117, 5551.916721249185, 5627.54979601635, 5819.883602327972, 6098.207233059718, 6110.175126357929, 6176.641138949424, 6227.190465239535, 6251.374862513758, 6270.435660790551, 6278.0, 6290.119383286344, 6304.842378940517, 6493.902591461132, 6516.380943810331, 6519.543631945766, 6528.183977002875, 6529.31442198567, 6144.846378840475, 6149.446549810494, 6165.321814600436, 6194.225721784777, 6201.0, 6358.523110766711, 6418.935810641927, 6499.317571655003, 6690.799276021728, 6701.731930722814, 6207.906881396782, 6250.1874756281395, 6274.466670333073, 6283.6858157091965, 6307.085472606446, 6448.742050318028, 6476.31998640136, 6483.416492515602, 6489.513286503531, 6521.0544471051435, 6206.968965155145, 6343.55595108338, 6348.016057511093, 6360.236771587347, 6368.713407896656, 5087.516685914832, 5114.87212819675, 5345.915093113689, 5753.252077229933, 6294.716737746812, 4994.200927851599, 5041.269015992734, 5091.745412729349, 5153.793848246029, 5155.407128180233, 4572.97713511437, 4576.816927322936, 4584.33527138563, 4630.884227894329, 4631.397918270671, 6018.668973206516, 6068.726907289182, 6163.8150855474405, 6199.814005579839, 6272.3100458949475, 6337.841553961096, 6356.4279214870885, 6374.139491168633, 6408.967955160288, 6451.612903225821, 6211.223597050369, 6224.439800417984, 6226.034964580497, 6234.127222188898, 6257.468115210167, 4930.457649658535, 5715.571332150105, 5720.313562372542, 5729.968605651023, 5856.882862342814, 6447.580907241015, 6527.5430719849155, 6658.6337751424135, 6673.666316684244, 6731.596104233761, 6547.148870646785, 6556.803295901131, 6573.276939536551, 6585.6377899216, 6594.901076483952, 6696.933030669631, 6716.5634233774645, 6784.423324017514, 6806.591604503746, 6812.761553345609, 6192.318844926965, 6197.628142311476, 6307.842303942438, 6450.774222902176, 6509.251436084911, 5640.858978525405, 6438.774642887476, 6466.256380516301, 6469.7412103515335, 6499.772507962199, 6138.600990935575, 6162.691865406801, 6280.309165991828, 6315.5894866833505, 6318.526110541636, 6128.594790794166, 6161.168242287234, 6212.502999760099, 6242.219722534684, 6282.057691346335, 5740.13897915316, 6116.052011938156, 6125.449118208347, 6145.8156255312415, 6213.720382582885, 6287.874242515032, 6292.968535157295, 6300.0, 6304.590201636879, 6341.714622842075, 5992.760289588367, 6053.152558641882, 6130.000698177663, 6164.506839452923, 6218.533609979179], "value": 5901.708807670036, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.7153595489893, 701.7122979578373, 759.0853022108358, 844.7634662294556, 969.2391472693937, 38895.80552097239, 39295.803520982394, 40745.59254407451, 40777.59222407775, 40824.0, 38556.614433855655, 38840.805795970984, 38859.61140388595, 38895.41656875149, 39068.80465597675, 8244.134365891585, 11158.721031974224, 11392.829107563393, 11547.30716157029, 11595.188336816425, 11166.050885674687, 11234.662960111209, 11252.887471125307, 11270.774584508305, 11303.208775385727, 11247.718807029807, 11276.943615281913, 11283.097352211844, 11285.887141128607, 11353.602623908166, 9503.667371642028, 11217.158713096509, 11248.831267530994, 11254.043406310433, 11257.60598379061, 9969.850452243221, 10471.79574348952, 11020.779584408267, 11069.50187241576, 11073.833892491619, 30373.69626303731, 33825.15437114092, 34512.82743586291, 34619.4807077894, 34681.82659086714, 3278.934421311584, 3806.257779732953, 3823.9617603823885, 3909.2376986487648, 3991.2017596480528, 25419.237422877344, 25696.0, 26368.472630547283, 26879.790409431425, 27246.5913011305, 3181.316017056344, 3204.3911656785367, 3222.133103605345, 3235.4176248275303, 3241.7892836965757, 385459.0, 386156.0, 386782.0, 387953.0, 388066.0, 45302.0, 45587.316190257174, 45695.77152114218, 45871.85320367017, 45888.77055614701, 46529.83675408142, 46604.5339546607, 46680.7665961668, 46762.532374675815, 46920.0, 14078.577642670736, 14434.422623094959, 14463.710725785426, 14518.911081668917, 14530.636734081732, 12476.064295177897, 12481.75036499265, 12482.563110291278, 12494.312812795202, 12509.374531273399, 11558.94220528892, 11958.58144964922, 11959.40202989847, 11962.82055769164, 12022.759544809054, 5786.189933409326, 5794.55136215946, 5840.2115714378015, 5861.472467477947, 5867.266591676041, 1017.7455636090978, 1163.8545181852269, 1187.1155988788407, 1203.1171217045296, 1223.1315765806378, 23628.763712363, 26051.264617214918, 26389.208323750318, 27011.324716882234, 27679.86160069227, 1278.9488420463076, 1288.2013151845915, 1290.5870121561197, 1311.8622544632867, 1368.6783605852618, 13726.245056521788, 14013.859861401255, 14108.15351078918, 14272.0, 14309.713805723828, 450666.0, 506401.0, 507801.0, 509764.90235097916, 510235.0, 47029.29456058162, 47685.284720729214, 57666.7116664414, 57978.0, 58328.41671583315, 1471.0658731705444, 1490.508132316333, 1504.5260742865962, 1516.2494565190302, 1518.6658935034495, 26739.197824065308, 29276.853615731787, 29527.704722952494, 30301.696983029888, 30499.2375190622, 38849.0, 45689.77155114203, 45761.313580296985, 45856.7707161462, 45962.0, 406978.0, 462927.0, 463739.0, 464710.0, 466857.0, 31647.52528712117, 32113.715451381682, 32301.030969070966, 32409.51385729263, 32425.027249182564, 44561.33158002698, 45583.54416455793, 46283.305750414445, 46321.76839115783, 46628.76685616686, 30830.075097747103, 30966.69033309638, 31429.528557071204, 31486.370272593962, 31729.524057139624, 28179.450130243033, 30600.846995764878, 30723.539146913263, 30725.385492290476, 31652.0, 31470.0, 31501.897433589715, 33997.49003764896, 37537.31082101257, 37600.623993760804, 28033.43933121367, 31240.750369985853, 31368.0, 31452.89914852969, 31611.84194079015, 21364.893175534024, 23555.822208889145, 24539.0, 24729.1344802931, 25160.49679006373], "value": 18966.4162872506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.9470066241716, 433.1288832244702, 435.880132963435, 439.6219251443758, 446.9441319835021, 20194.293199737993, 21162.576748465042, 22393.440163995907, 22584.322470325915, 22693.77306226937, 20376.083076261566, 20392.388228353135, 22404.55190896181, 22851.085956561717, 22975.19586814462, 6547.967260163692, 6603.0, 6850.451963842881, 6858.3141685831515, 6902.5858448493, 6069.817905462844, 6797.490188235877, 6829.965850170742, 6831.897521537181, 6858.897116543256, 6506.154199954023, 6771.052052712625, 6809.6595170241535, 6853.17761868574, 6854.520183587151, 6485.351464853524, 6768.255491895888, 6799.898001529981, 6851.972204169365, 6873.4844886633455, 6572.835679108012, 6594.835129121762, 6609.438197753197, 6610.0745895574655, 6615.669216539202, 25875.96496140152, 26032.609510857354, 26587.46825063488, 26831.463370732476, 27288.590671139948, 3035.423269578795, 3035.4384438878656, 3068.435098099963, 3084.5373194020854, 3089.7373723233327, 16246.43137490194, 16786.83213167865, 16869.915650421794, 16978.23597938096, 17422.0, 1990.8506861985263, 2024.342088821139, 2057.3930690446355, 2073.844461665381, 2084.770675225724, 131585.68414315925, 140624.29687851496, 150453.0, 172216.0, 181401.0, 19461.0, 20914.686279705817, 20993.475163121046, 21061.473463163544, 21183.8940805295, 31344.05967820969, 31374.0, 31394.0, 31401.37197256042, 31492.89774857869, 10496.26526143178, 10531.789364212826, 10803.83794243087, 10840.024397804078, 10893.727656808644, 8969.461832290084, 8983.371164018456, 9012.459252444874, 9130.634774609074, 9150.542472876463, 9172.403793753385, 9449.055094490495, 9478.620855165716, 9486.857697134548, 9523.619055237712, 3566.8394922228563, 4359.607635312837, 4378.693491455568, 4396.208682437128, 4426.15902978433, 1054.1250761867632, 1060.612876300145, 1074.9677509674723, 1075.0700643942932, 1116.2855772305732, 19807.0, 21471.14115435396, 21551.8922405387, 22511.774882251295, 23512.529749404915, 946.8295706772709, 952.6760901293455, 979.6767066868059, 981.7545613596601, 994.1500017485091, 6216.502679785606, 6819.863602727918, 6854.2117656469145, 6961.129858767654, 7158.4273258140265, 790288.0, 798139.0, 801804.0, 812070.0, 821715.0, 121348.0, 161670.0, 161777.19111404603, 168301.0, 170007.14996425185, 1294.3592921503728, 1316.6181807275975, 1336.6858788184768, 1342.8187194728587, 1345.9259740714356, 12324.568640097912, 12347.567835125728, 12480.875191247971, 12604.432800523815, 12738.87261127377, 61230.77538449117, 62206.68896655488, 62440.0, 63073.0, 63669.3633063682, 93983.0, 94314.0, 94437.5278123605, 94775.52612236894, 96241.03758962321, 9980.950095249767, 11157.553697851996, 11444.313341199555, 11672.416379181177, 11710.531578737096, 12491.188072775241, 12524.686882828002, 12610.684732881751, 12708.428120734774, 12817.679558010752, 9194.0, 11116.61091861781, 11212.327260364404, 11360.375179365214, 11386.316820990767, 10246.897531024595, 10315.845262321222, 10352.120069794151, 10398.376097434179, 10560.577576896838, 11301.095912327159, 11535.596254131066, 11585.246958947642, 11634.767304653691, 11716.531338746696, 11254.324740515594, 11445.084393248353, 11451.599194028502, 11469.598564050219, 11593.072554195813, 9503.857442138511, 10135.290529662852, 10362.378257304586, 10817.567297308335, 11228.270162439416], "value": 11447.625413220523, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1612.8870979031472, 1638.6722655468902, 1639.5737108351825, 1656.089150966968, 1656.0891509669686, 9759.902400976005, 10028.648997285096, 10263.076323130917, 10795.514201860915, 10809.783804323919, 11016.889831101687, 11308.982191602774, 11331.943340283287, 11334.773304533905, 11550.422478876064, 2454.4477492564165, 2503.4242124311327, 2503.812214083942, 2504.9373765655823, 2517.987410062947, 2460.8646524441147, 2480.4790993891215, 2499.4376265340243, 2512.7612876776675, 2520.117958714454, 2473.109680515013, 2485.6768620079365, 2498.025769949721, 2503.4116982509186, 2506.774390304872, 2318.3160967514623, 2324.2562380038394, 2325.534893021394, 2331.440454290975, 2339.7660233976635, 2222.8777417242045, 2237.6084185267514, 2264.8754318512474, 2384.9642255366184, 2406.121765555577, 10791.6222932197, 10942.069924056465, 10996.0653344465, 11004.119670426428, 11047.0, 6027.427394397525, 6143.907841382383, 6173.598716083485, 6180.690965451709, 6184.041473571604, 3573.4103872860946, 3689.5572531296416, 3696.1129328961124, 3768.0391500167284, 3769.4722738816313, 1288.2270637617448, 1913.8181872722068, 1948.814862588052, 1985.831095136199, 2031.116464338003, 15797.763033554505, 15824.208789560476, 15896.443624473204, 15921.124338161397, 15938.760918586231, 4736.313234580966, 4789.30555069513, 4839.0, 4845.733484658307, 4852.539008794159, 12004.099692522921, 12015.819762703566, 12017.759644807229, 12023.458944347525, 12058.035357171406, 5871.530277577784, 5912.260967379078, 5937.70311484424, 5948.256467941507, 5979.073243647242, 5264.0, 5395.136778115482, 5466.234727138124, 5622.943770562324, 5740.56945729072, 3947.6626065655355, 4042.939355909664, 4066.9796651017145, 4107.383892416162, 4110.794460276974, 1081.7079388565044, 1085.6417382263835, 1086.1148164246115, 1095.8575385199872, 1100.2518287564533, 960.5917485068818, 970.7864269860619, 990.1830989433746, 1002.7493126718321, 1019.7603563162651, 6744.966275168593, 6767.289434609295, 6951.548149370275, 6973.3375329343635, 6987.615681137586, 751.8158051277362, 888.7955770172896, 951.357833462424, 970.9951450242845, 974.0, 4066.4567227792018, 4449.911001780011, 4496.797644105957, 4510.616597589242, 4576.656750743708, 13608.319584020759, 13674.453021879212, 13720.245386503839, 14261.572152835432, 14383.424663013571, 11785.88214117848, 14112.364943577564, 14359.210243436712, 14375.353109110116, 14386.28068596566, 10491.947540262403, 10504.0, 10709.571617135383, 10760.838587421194, 10779.514921828537, 6860.279670634512, 6965.582065076112, 7118.5017048806085, 7130.821729456805, 7176.96411517939, 10970.780584388427, 12402.00783937301, 12953.54662586805, 12992.610221693365, 13013.544525941925, 11682.70793230176, 11901.226420282654, 12046.457909393923, 13349.998750093977, 13352.0, 9386.5776040077, 9468.0, 9491.288153388661, 9550.95224523873, 9559.904400955902, 9770.658026969022, 10413.271071024956, 10500.684979450629, 10513.9474302628, 10514.421706805893, 8751.91248087511, 8754.737357879274, 8791.208791208823, 8813.074627164055, 8832.425892316978, 8601.22588966996, 8624.00823905259, 8693.261072808882, 8702.782430439289, 8873.822523549621, 7805.297523222937, 7946.245106714853, 7950.0, 7961.880571791312, 7967.32277756374, 5832.883342333214, 5890.852728681817, 5968.731407086779, 6004.519638429003, 6084.026555751058, 5994.730237139252, 6113.23584551931, 6219.222597175353, 6261.624302541862, 6269.529785266032], "value": 5313.100925514867, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.5650931900358, 370.99258014839705, 382.3156549775901, 384.8499085356713, 395.2529718831409, 4384.451943507065, 5189.313473121232, 5229.477052294774, 5276.393214780297, 5330.254918716864, 4660.766961651921, 5115.6930584164975, 5157.896842063165, 5174.66364686296, 5233.398159211692, 3832.9808350958347, 4376.518582955872, 4389.999999999992, 4414.448193975753, 4460.085682435093, 4400.867973960786, 4429.5348988356245, 4446.310821822606, 4451.0, 4456.442944631921, 3882.825272862714, 4424.8008839602135, 4460.308652158904, 4463.794775410637, 4478.328250762379, 3432.7470473277076, 4124.05146816234, 4304.849330273441, 4312.482502099768, 4354.455693038371, 4016.7389119707127, 4042.858499952517, 4061.562423558634, 4065.3698676705158, 4094.8566800161852, 4520.367148599199, 4705.894114883, 5070.478856343082, 5161.303224064741, 5167.664101833407, 4580.404547408849, 4761.0, 4923.384576927884, 5053.974730126326, 5059.595232381401, 4700.529947005306, 5026.623003274769, 5482.533984611313, 5624.706317546947, 5629.915551266734, 3865.4781604483596, 4397.516273209944, 4561.840335588238, 4585.220512512881, 4588.059447813191, 5661.433856614347, 5954.761809527614, 5973.342932277446, 6053.152558641794, 6054.545909056838, 4173.874783756492, 4626.629869610423, 4655.3947986761505, 4672.299155126758, 4705.494241842646, 4277.144571085795, 4457.420535330386, 4469.687121901501, 4615.446146462446, 4818.10864989978, 4544.590986811203, 4973.875653108701, 4991.950080499221, 5074.82238121664, 5109.744512774347, 5121.513456221653, 5606.271184746039, 5608.747606357723, 5668.376478587352, 5719.910264161144, 1386.7449957788217, 1609.0828227909979, 1621.5864954436543, 1635.6565121324418, 1638.811536673274, 1109.8834622364698, 1178.9882101178878, 1200.7418405042959, 1217.4156404925686, 1238.102375777554, 4875.146849301358, 5162.7542019221755, 5283.630145889828, 5381.569474442035, 5391.973040134774, 5201.583873290203, 5240.868978275497, 5242.475752424803, 5857.88284234313, 5907.7046147692445, 4514.929847703527, 4564.246899261585, 4890.58430033444, 5352.598555108305, 5449.727513624303, 3031.818090914552, 3040.403788011312, 3042.3763128558594, 3067.371188906269, 3082.892098776532, 5480.534154596904, 5601.971990140105, 5744.827655170351, 6118.969405152946, 6132.834761395321, 4687.718736875799, 5237.978594174176, 5443.7822487100075, 5597.608167428322, 6018.291768704101, 5369.201317558662, 5815.912761308584, 5866.5893387462465, 6001.489873360726, 6034.999999999912, 5387.138057910715, 5823.330317013429, 5839.386864379179, 5839.445252700986, 5868.471837534642, 4922.778474968563, 5344.652597581145, 5465.398806131243, 5731.160122717873, 5773.682447465262, 5172.646445867046, 5620.494155526022, 5953.469775345374, 6061.212042434456, 6114.104627565469, 4015.759054456742, 4360.993942786354, 4464.285714285698, 4575.506349039715, 4591.288350305575, 4958.57852082577, 5027.974860125676, 5303.814366497309, 5375.946240537544, 5809.796657116981, 5120.543633047752, 5461.726913654381, 5650.943490565041, 5692.0, 5724.254102498778, 4527.615152712056, 4831.830885918976, 5299.800582758112, 5654.123610840326, 5687.943120568907, 4952.959878425571, 5426.891462170814, 5473.781048758005, 5509.3664228612615, 5538.6399884007415, 5080.898382032413, 5178.97410512945, 5324.361076670824, 5650.237217975685, 5728.942710572841, 4868.612445453029, 5277.868053298698, 5455.5908306876345, 5545.944540554704, 5575.693336866511], "value": 4313.139879050601, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.43616516374, 684.127737135153, 754.8226166850789, 762.9351505122065, 909.8635204719292, 15893.68212635748, 16688.666226675457, 17227.569310767223, 17464.301427942897, 17538.386156484492, 14520.709585808278, 14669.633259168526, 14708.117512949231, 15069.698606027872, 15947.920260398681, 997.5012493753142, 1026.7535791410082, 1027.850961610566, 1027.994860025699, 1029.9948500257487, 1003.0922015575916, 1024.6106479537766, 1051.3481641382355, 1061.5117046158775, 1062.5271754069443, 1078.7626722121122, 1084.7342401111698, 1085.6200329884557, 1086.8532748078987, 1088.0697004061492, 1076.4832880217461, 1081.4484612847425, 1082.4804094034907, 1083.707399002273, 1085.2511766880866, 793.9444238903279, 802.2659266770897, 803.8995125609299, 804.8631732605471, 805.2832978649008, 14891.925540372336, 17138.57153571158, 17141.48575542723, 17248.223829927683, 18042.729359059624, 11934.104942129288, 13718.862811371859, 14635.63410914725, 14718.116912985255, 14847.331870065873, 11986.460609272604, 12077.45651445687, 12218.511259549607, 12342.13605047647, 12732.427040783094, 3326.6673332666783, 3869.129445874684, 3922.4704664870164, 3927.1163988102735, 4038.5557588665224, 120453.39773301165, 123615.0, 123838.0, 124532.0, 126976.0, 11323.263987840928, 11346.092312614972, 11507.654770356903, 11534.192606517463, 11650.88349116515, 23520.64719029216, 23538.0, 23785.04859805623, 23798.286051418487, 24138.758612414003, 7882.330001949898, 7958.3235424989525, 8200.179982001751, 8418.705345312885, 8451.577421129043, 4539.16025535277, 4593.104344652827, 4794.113089078532, 4879.585235254972, 4893.828715994923, 3119.173419043955, 3639.4358874373984, 3734.626537346298, 3742.3076730804887, 3753.962460375416, 588.3498733899065, 594.2809200866999, 607.4229481992126, 609.3479976425272, 610.7709608896664, 558.8794467093452, 596.832886791699, 603.8490377405649, 664.9601023938483, 735.2023054985294, 8836.55817209137, 10503.212259080447, 10721.0, 11072.612458564072, 11217.0, 561.7135261016911, 606.6178307666142, 611.6788685939918, 633.0567454492865, 756.1493320014984, 4831.492693267227, 5316.362036555561, 5364.383095943938, 5388.91916621251, 5401.162819762943, 82164.0, 106105.0, 108226.0, 109131.36302955462, 110013.0, 59336.703316483145, 62301.065484017774, 80262.19737802664, 90619.09380906107, 90913.54543227374, 75776.24223757692, 77900.0, 78150.82773758398, 78273.60863195648, 79239.0, 10713.517891695048, 11134.665960021212, 11145.162734699377, 11279.605213817476, 11484.655460336204, 41885.37171942362, 44549.0, 44991.7750411259, 47579.76210118927, 48857.51142488672, 52984.73507632592, 67928.6603566979, 69085.0, 70079.64960175166, 70354.0, 13602.727945441233, 13721.45114195421, 13878.0, 14006.719865602834, 14426.350814213176, 14701.117932924059, 14717.55847324582, 14811.333489993192, 15107.32017059257, 15740.97683650559, 13747.312634368041, 13914.234717090252, 14070.78893816614, 14091.859081409464, 14590.85409145895, 13440.46238150463, 13926.860731392557, 13970.161790292615, 14126.576202713935, 14157.36291866848, 13089.345532723135, 13738.10702304347, 13780.793288100485, 13796.655083622989, 14015.50945716895, 9970.451625160686, 10160.796784064129, 10711.732206694895, 10997.12023038171, 11020.478914394544, 8191.713290034821, 10127.898721012696, 10447.87279116704, 10544.15646748243, 10743.818120230439], "value": 7851.831654965748, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 120.0, 122.0, 121.0, 121.0, 121.0, 123.0, 120.0, 122.0], "value": 120.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 136.0, 135.0, 170.0, 110.0, 110.0, 118.0, 134.0, 125.0, 134.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 193.0, 185.0, 265.0, 204.0, 272.0, 273.0, 198.0, 180.0, 264.0], "value": 223.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 59.0, 87.0, 62.0, 59.0, 63.0, 79.0, 67.0, 76.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 78.0, 85.0, 100.0, 98.0, 96.0, 72.0, 70.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 78.0, 127.0, 90.0, 90.0, 91.0, 92.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 98.0, 102.0, 97.0, 103.0, 145.0, 147.0, 126.0, 97.0, 97.0], "value": 115.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 96.0, 93.0, 87.0, 93.0, 93.0, 90.0, 90.0, 93.0, 118.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 70.0, 68.0, 71.0, 67.0, 68.0, 76.0, 95.0], "value": 71.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 63.0, 67.0, 61.0, 61.0, 60.0, 74.0, 68.0, 59.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 82.0, 85.0, 83.0, 81.0, 83.0, 85.0, 81.0, 83.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 138.0, 140.0, 129.0, 144.0, 140.0, 147.0, 151.0, 201.0, 145.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 166.0, 163.0, 163.0, 160.0, 164.0, 167.0, 159.0, 159.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 64.0, 63.0, 66.0, 64.0, 63.0, 80.0, 81.0], "value": 67.7, "unit": "score"}]}]} 16:03:16 INFO - Return code: 0 16:03:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:03:16 ERROR - # TBPL SUCCESS # 16:03:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:03:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:03:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:03:16 INFO - 2017-04-24 16:03:16.275 screenresolution[1661:10967] starting screenresolution argv=screenresolution get 16:03:16 INFO - 2017-04-24 16:03:16.278 screenresolution[1661:10967] Display 0: 1600x1200x32@60 16:03:16 INFO - 2017-04-24 16:03:16.285 screenresolution[1662:10970] starting screenresolution argv=screenresolution list 16:03:16 INFO - Available Modes on Display 0 16:03:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:03:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:03:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:03:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:03:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:03:16 INFO - Intel Iris: 16:03:16 INFO - Chipset Model: Intel Iris 16:03:16 INFO - Type: GPU 16:03:16 INFO - Bus: Built-In 16:03:16 INFO - VRAM (Dynamic, Max): 1536 MB 16:03:16 INFO - Vendor: Intel (0x8086) 16:03:16 INFO - Device ID: 0x0a2e 16:03:16 INFO - Revision ID: 0x0009 16:03:16 INFO - Displays: 16:03:16 INFO - Display: 16:03:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:03:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:03:16 INFO - Display Serial Number: 1600x1200 60 16:03:16 INFO - Main Display: Yes 16:03:16 INFO - Mirror: Off 16:03:16 INFO - Online: Yes 16:03:16 INFO - Rotation: Supported 16:03:16 INFO - Return code: 0 16:03:16 INFO - Running post-action listener: _resource_record_post_action 16:03:16 INFO - [mozharness: 2017-04-24 23:03:16.529838Z] Finished run-tests step (success) 16:03:16 INFO - Running post-run listener: _resource_record_post_run 16:03:16 INFO - Total resource usage - Wall time: 1190s; CPU: 55.0%; Read bytes: 50912256; Write bytes: 434823680; Read time: 390; Write time: 1514 16:03:16 INFO - TinderboxPrint: CPU usage
55.0% 16:03:16 INFO - TinderboxPrint: I/O read bytes / time
50,912,256 / 390 16:03:16 INFO - TinderboxPrint: I/O write bytes / time
434,823,680 / 1,514 16:03:16 INFO - TinderboxPrint: CPU idle
2,141.6 (45.0%) 16:03:16 INFO - TinderboxPrint: CPU system
965.0 (20.3%) 16:03:16 INFO - TinderboxPrint: CPU user
1,655.1 (34.8%) 16:03:16 INFO - TinderboxPrint: Swap in / out
166,182,912 / 0 16:03:16 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121637376; Write bytes: 115795968; Read time: 11066; Write time: 269 16:03:16 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 46980096; Write bytes: 308820480; Read time: 364; Write time: 1210 16:03:17 INFO - Running post-run listener: _upload_blobber_files 16:03:17 INFO - Blob upload gear active. 16:03:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:03:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:03:17 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:03:17 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:03:17 INFO - (blobuploader) - INFO - Open directory for files ... 16:03:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:03:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:03:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:18 INFO - (blobuploader) - INFO - Done attempting. 16:03:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:03:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:03:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:19 INFO - (blobuploader) - INFO - Done attempting. 16:03:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:03:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:03:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:20 INFO - (blobuploader) - INFO - Done attempting. 16:03:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:03:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:03:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:22 INFO - (blobuploader) - INFO - Done attempting. 16:03:22 INFO - (blobuploader) - INFO - Iteration through files over. 16:03:22 INFO - Return code: 0 16:03:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:03:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:03:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d93e6fd2f8aa8f621c19ac1343ed92accc0554826b8fb771428e97f575a0b0c05b1f0d604c9c401f3c8d382b87f91ea1b6699917d0858798cb835bade83ebda0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0105dd6846d0d434d04663820a707dcc6d7de97dc671b34ec56eb322435d46c9223f486e6fdef412ecb36310fcb325346d5ad8e235421a4291b4d38ac4734a7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/568c24b040bdef0745822ee5659468ccbeb06b990cce575cfd4da91c508ae19b96877fe33745951688853635ad7f6fc9b1b0be3abacf76ec9ef32cbd9bf80983", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ae463beb8744d974e8e09543248c91a8f470e19071980f897a86c294f89dd7a3dde2cf0b09e0db202548389bc038d3513079841b7417463019969ea6b6d40939"} 16:03:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:03:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:03:22 INFO - Contents: 16:03:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d93e6fd2f8aa8f621c19ac1343ed92accc0554826b8fb771428e97f575a0b0c05b1f0d604c9c401f3c8d382b87f91ea1b6699917d0858798cb835bade83ebda0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0105dd6846d0d434d04663820a707dcc6d7de97dc671b34ec56eb322435d46c9223f486e6fdef412ecb36310fcb325346d5ad8e235421a4291b4d38ac4734a7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/568c24b040bdef0745822ee5659468ccbeb06b990cce575cfd4da91c508ae19b96877fe33745951688853635ad7f6fc9b1b0be3abacf76ec9ef32cbd9bf80983", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ae463beb8744d974e8e09543248c91a8f470e19071980f897a86c294f89dd7a3dde2cf0b09e0db202548389bc038d3513079841b7417463019969ea6b6d40939"} 16:03:22 INFO - Running post-run listener: copy_logs_to_upload_dir 16:03:22 INFO - Copying logs to upload dir... 16:03:22 INFO - mkdir: /builds/slave/test/build/upload/logs 16:03:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.036292 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-04-24 16:03:22.681027) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 16:03:22.684600) ========= 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.ulrqkk7TUx/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.T2GInV1RjG/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d93e6fd2f8aa8f621c19ac1343ed92accc0554826b8fb771428e97f575a0b0c05b1f0d604c9c401f3c8d382b87f91ea1b6699917d0858798cb835bade83ebda0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0105dd6846d0d434d04663820a707dcc6d7de97dc671b34ec56eb322435d46c9223f486e6fdef412ecb36310fcb325346d5ad8e235421a4291b4d38ac4734a7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/568c24b040bdef0745822ee5659468ccbeb06b990cce575cfd4da91c508ae19b96877fe33745951688853635ad7f6fc9b1b0be3abacf76ec9ef32cbd9bf80983", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ae463beb8744d974e8e09543248c91a8f470e19071980f897a86c294f89dd7a3dde2cf0b09e0db202548389bc038d3513079841b7417463019969ea6b6d40939"} build_url:https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/DNILcTpKSq65SxAuHmLNpw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d93e6fd2f8aa8f621c19ac1343ed92accc0554826b8fb771428e97f575a0b0c05b1f0d604c9c401f3c8d382b87f91ea1b6699917d0858798cb835bade83ebda0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0105dd6846d0d434d04663820a707dcc6d7de97dc671b34ec56eb322435d46c9223f486e6fdef412ecb36310fcb325346d5ad8e235421a4291b4d38ac4734a7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/568c24b040bdef0745822ee5659468ccbeb06b990cce575cfd4da91c508ae19b96877fe33745951688853635ad7f6fc9b1b0be3abacf76ec9ef32cbd9bf80983", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ae463beb8744d974e8e09543248c91a8f470e19071980f897a86c294f89dd7a3dde2cf0b09e0db202548389bc038d3513079841b7417463019969ea6b6d40939"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 16:03:22.724646) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 16:03:22.724978) ========= 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.ulrqkk7TUx/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.T2GInV1RjG/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.004961 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 16:03:22.776767) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 16:03:22.777497) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 16:03:22.778130) ========= ========= Total master_lag: 0.20 =========