builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0013 starttime: 1480911766.44 results: success (0) buildid: 20161204191338 builduid: e78c2c19b4d64468a4c28dec6d7eb821 revision: 52cc3c8b17c96e2a008fedaec7149427d1b31530 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.438804) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.439369) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.439660) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.466272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.466641) ========= 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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-04 20:22:46-- 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% 151M=0s 2016-12-04 20:22:46 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.162614 ========= master_lag: 0.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.879326) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.879642) ========= 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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.026782 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:46.930956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-04 20:22:46.931282) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 52cc3c8b17c96e2a008fedaec7149427d1b31530 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 52cc3c8b17c96e2a008fedaec7149427d1b31530 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OwVjCS9sGS/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.fuaeNF7QEr/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-04 20:22:47,023 truncating revision to first 12 chars 2016-12-04 20:22:47,023 Setting DEBUG logging. 2016-12-04 20:22:47,023 attempt 1/10 2016-12-04 20:22:47,024 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/52cc3c8b17c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-04 20:22:47,992 unpacking tar archive at: mozilla-central-52cc3c8b17c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.237800 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-04 20:22:48.191406) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:48.191717) ========= script_repo_revision: 52cc3c8b17c96e2a008fedaec7149427d1b31530 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:48.192184) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:48.192458) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 20:22:48.212201) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-04 20:22:48.212539) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OwVjCS9sGS/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.fuaeNF7QEr/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:22:48 INFO - MultiFileLogger online at 20161204 20:22:48 in /builds/slave/test 20:22:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:22:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:22:48 INFO - {'append_to_log': False, 20:22:48 INFO - 'base_work_dir': '/builds/slave/test', 20:22:48 INFO - 'blob_upload_branch': 'Firefox', 20:22:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:22:48 INFO - 'branch': 'Firefox', 20:22:48 INFO - 'buildbot_json_path': 'buildprops.json', 20:22:48 INFO - 'config_files': ('talos/mac_config.py',), 20:22:48 INFO - 'default_actions': ('clobber', 20:22:48 INFO - 'read-buildbot-config', 20:22:48 INFO - 'download-and-extract', 20:22:48 INFO - 'populate-webroot', 20:22:48 INFO - 'create-virtualenv', 20:22:48 INFO - 'install', 20:22:48 INFO - 'run-tests'), 20:22:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:22:48 INFO - 'download_minidump_stackwalk': True, 20:22:48 INFO - 'download_symbols': 'ondemand', 20:22:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:22:48 INFO - 'tooltool.py': '/tools/tooltool.py', 20:22:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:22:48 INFO - '/tools/misc-python/virtualenv.py')}, 20:22:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:22:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:22:48 INFO - 'installer_path': 'installer.exe', 20:22:48 INFO - 'log_level': 'info', 20:22:48 INFO - 'log_name': 'talos', 20:22:48 INFO - 'log_to_console': True, 20:22:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:22:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:22:48 INFO - 'opt_config_files': (), 20:22:48 INFO - 'pip_index': False, 20:22:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:22:48 INFO - 'cmd': ('bash', 20:22:48 INFO - '-c', 20:22:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:22:48 INFO - 'enabled': True, 20:22:48 INFO - 'halt_on_failure': False, 20:22:48 INFO - 'name': 'check_screen_resolution'},), 20:22:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:22:48 INFO - 'cmd': ('bash', 20:22:48 INFO - '-c', 20:22:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:22:48 INFO - 'enabled': True, 20:22:48 INFO - 'halt_on_failure': False, 20:22:48 INFO - 'name': 'check_screen_resolution'},), 20:22:48 INFO - 'run_cmd_checks_enabled': True, 20:22:48 INFO - 'sps_profile': False, 20:22:48 INFO - 'sps_profile_interval': 0, 20:22:48 INFO - 'suite': 'dromaeojs-e10s', 20:22:48 INFO - 'system_bits': '32', 20:22:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:22:48 INFO - 'title': 't-yosemite-r7-0013', 20:22:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:22:48 INFO - 'use_talos_json': True, 20:22:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:22:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:22:48 INFO - 'work_dir': 'build'} 20:22:48 INFO - [mozharness: 2016-12-05 04:22:48.414000Z] Running clobber step. 20:22:48 INFO - Running pre-action listener: _resource_record_pre_action 20:22:48 INFO - Running main action method: clobber 20:22:48 INFO - rmtree: /builds/slave/test/build 20:22:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:22:50 INFO - Running post-action listener: _resource_record_post_action 20:22:50 INFO - [mozharness: 2016-12-05 04:22:50.906295Z] Finished clobber step (success) 20:22:50 INFO - [mozharness: 2016-12-05 04:22:50.906424Z] Running read-buildbot-config step. 20:22:50 INFO - Running pre-action listener: _resource_record_pre_action 20:22:50 INFO - Running main action method: read_buildbot_config 20:22:50 INFO - Using buildbot properties: 20:22:50 INFO - { 20:22:50 INFO - "project": "", 20:22:50 INFO - "product": "firefox", 20:22:50 INFO - "script_repo_revision": "production", 20:22:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:22:50 INFO - "repository": "", 20:22:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:22:50 INFO - "buildid": "20161204191338", 20:22:50 INFO - "pgo_build": "False", 20:22:50 INFO - "basedir": "/builds/slave/test", 20:22:50 INFO - "buildnumber": 12, 20:22:50 INFO - "slavename": "t-yosemite-r7-0013", 20:22:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 20:22:50 INFO - "platform": "yosemite_r7", 20:22:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:22:50 INFO - "branch": "mozilla-central", 20:22:50 INFO - "repo_path": "mozilla-central", 20:22:50 INFO - "revision": "52cc3c8b17c96e2a008fedaec7149427d1b31530", 20:22:50 INFO - "stage_platform": "macosx64", 20:22:50 INFO - "builduid": "e78c2c19b4d64468a4c28dec6d7eb821", 20:22:50 INFO - "slavebuilddir": "test" 20:22:50 INFO - } 20:22:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:22:50 INFO - Running post-action listener: _resource_record_post_action 20:22:50 INFO - [mozharness: 2016-12-05 04:22:50.909755Z] Finished read-buildbot-config step (success) 20:22:50 INFO - [mozharness: 2016-12-05 04:22:50.909851Z] Running download-and-extract step. 20:22:50 INFO - Running pre-action listener: _resource_record_pre_action 20:22:50 INFO - Running main action method: download_and_extract 20:22:50 INFO - mkdir: /builds/slave/test/build/tests 20:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:50 INFO - trying https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:22:50 INFO - Downloading https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 20:22:52 INFO - Downloaded 1199 bytes. 20:22:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:22:52 INFO - Using the following test package requirements: 20:22:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:22:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:22:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:22:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'jsshell-mac.zip'], 20:22:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:22:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:22:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:22:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:22:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:22:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:22:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:22:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:22:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:22:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:22:52 INFO - Fetch https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:22:53 INFO - Expected file size: 16283760 20:22:53 INFO - Obtained file size: 16283760 20:22:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:22:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:22:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:22:59 INFO - Fetch https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:23:00 INFO - Expected file size: 16283760 20:23:00 INFO - Obtained file size: 16283760 20:23:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:23:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:23:07 INFO - Fetch https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:23:07 INFO - Expected file size: 14409734 20:23:07 INFO - Obtained file size: 14409734 20:23:08 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')]} 20:23:08 INFO - trying https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:23:08 INFO - Downloading https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:23:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 20:23:11 INFO - Downloaded 107534284 bytes. 20:23:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:23:11 INFO - mkdir: /builds/slave/test/properties 20:23:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:23:11 INFO - Writing to file /builds/slave/test/properties/build_url 20:23:11 INFO - Contents: 20:23:11 INFO - build_url:https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:23:11 INFO - Running post-action listener: _resource_record_post_action 20:23:11 INFO - Running post-action listener: set_extra_try_arguments 20:23:11 INFO - [mozharness: 2016-12-05 04:23:11.679802Z] Finished download-and-extract step (success) 20:23:11 INFO - [mozharness: 2016-12-05 04:23:11.679929Z] Running populate-webroot step. 20:23:11 INFO - Running pre-action listener: _resource_record_pre_action 20:23:11 INFO - Running main action method: populate_webroot 20:23:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:23:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:23:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:23:11 INFO - '--tppagecycles', 20:23:11 INFO - '7'], 20:23:11 INFO - 'tests': ['tsvgm']}}, 20:23:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:23:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:23:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:23:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:23:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:23:11 INFO - 'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:23:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:23:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:23:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:23:11 INFO - 'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['damp', 'tps']}, 20:23:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:23:11 INFO - 'tests': ['damp', 'tps']}, 20:23:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['dromaeo_dom']}, 20:23:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:23:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['basic_compositor_video']}, 20:23:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:23:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['a11yr', 20:23:11 INFO - 'ts_paint', 20:23:11 INFO - 'tpaint', 20:23:11 INFO - 'sessionrestore', 20:23:11 INFO - 'sessionrestore_no_auto_restore', 20:23:11 INFO - 'tabpaint']}, 20:23:11 INFO - 'other-e10s': {'tests': ['a11yr', 20:23:11 INFO - 'ts_paint', 20:23:11 INFO - 'tpaint', 20:23:11 INFO - 'sessionrestore', 20:23:11 INFO - 'sessionrestore_no_auto_restore', 20:23:11 INFO - 'tabpaint']}, 20:23:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:23:11 INFO - 'ts_paint', 20:23:11 INFO - 'tpaint', 20:23:11 INFO - 'sessionrestore', 20:23:11 INFO - 'sessionrestore_no_auto_restore', 20:23:11 INFO - 'tabpaint']}, 20:23:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:23:11 INFO - 'ts_paint', 20:23:11 INFO - 'tpaint', 20:23:11 INFO - 'sessionrestore', 20:23:11 INFO - 'sessionrestore_no_auto_restore', 20:23:11 INFO - 'tabpaint']}, 20:23:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['a11yr', 20:23:11 INFO - 'ts_paint', 20:23:11 INFO - 'tpaint', 20:23:11 INFO - 'sessionrestore', 20:23:11 INFO - 'sessionrestore_no_auto_restore', 20:23:11 INFO - 'tabpaint']}, 20:23:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['a11yr', 20:23:11 INFO - 'ts_paint', 20:23:11 INFO - 'tpaint', 20:23:11 INFO - 'sessionrestore', 20:23:11 INFO - 'sessionrestore_no_auto_restore', 20:23:11 INFO - 'tabpaint']}, 20:23:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['tsvgx', 20:23:11 INFO - 'tsvgr_opacity', 20:23:11 INFO - 'tart', 20:23:11 INFO - 'tscrollx', 20:23:11 INFO - 'cart', 20:23:11 INFO - 'tsvg_static']}, 20:23:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:23:11 INFO - 'tsvgr_opacity', 20:23:11 INFO - 'tart', 20:23:11 INFO - 'tscrollx', 20:23:11 INFO - 'cart', 20:23:11 INFO - 'tsvg_static']}, 20:23:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:23:11 INFO - 'talos_options': ['--disable-e10s'], 20:23:11 INFO - 'tests': ['tp5o']}, 20:23:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:23:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:23:11 INFO - 'talos_options': ['--disable-e10s', 20:23:11 INFO - '--xperf_path', 20:23:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:23:11 INFO - 'tests': ['tp5n']}, 20:23:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:23:11 INFO - 'talos_options': ['--xperf_path', 20:23:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:23:11 INFO - 'tests': ['tp5n']}}, 20:23:11 INFO - 'talos.zip': {'path': '', 20:23:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:23:11 INFO - Running post-action listener: _resource_record_post_action 20:23:11 INFO - [mozharness: 2016-12-05 04:23:11.698486Z] Finished populate-webroot step (success) 20:23:11 INFO - [mozharness: 2016-12-05 04:23:11.698605Z] Running create-virtualenv step. 20:23:11 INFO - Running pre-action listener: _resource_record_pre_action 20:23:11 INFO - Running main action method: create_virtualenv 20:23:11 INFO - Creating virtualenv /builds/slave/test/build/venv 20:23:11 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 20:23:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:23:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:23:11 INFO - Using real prefix '/tools/python27' 20:23:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:23:12 INFO - Installing distribute.............................................................................................................................................................................................done. 20:23:15 INFO - Installing pip.................done. 20:23:15 INFO - Return code: 0 20:23:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:23:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:23:15 INFO - Reading from file tmpfile_stdout 20:23:15 INFO - Output received: 20:23:15 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) 20:23:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:23:15 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')]} 20:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:16 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')]} 20:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:16 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:16 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 20:23:16 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 20:23:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:16 INFO - 'HOME': '/Users/cltbld', 20:23:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:16 INFO - 'LOGNAME': 'cltbld', 20:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:16 INFO - 'MOZ_NO_REMOTE': '1', 20:23:16 INFO - 'NO_EM_RESTART': '1', 20:23:16 INFO - 'PAGER': '/bin/cat', 20:23:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:16 INFO - 'PWD': '/builds/slave/test', 20:23:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:16 INFO - 'SHELL': '/bin/bash', 20:23:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:16 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:16 INFO - 'USER': 'cltbld', 20:23:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:16 INFO - 'XPC_FLAGS': '0x0', 20:23:16 INFO - 'XPC_SERVICE_NAME': '0', 20:23:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:16 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 20:23:16 INFO - Cleaning up... 20:23:16 INFO - Return code: 0 20:23:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:23:16 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')]} 20:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:16 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')]} 20:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:17 INFO - 'HOME': '/Users/cltbld', 20:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:17 INFO - 'LOGNAME': 'cltbld', 20:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:17 INFO - 'MOZ_NO_REMOTE': '1', 20:23:17 INFO - 'NO_EM_RESTART': '1', 20:23:17 INFO - 'PAGER': '/bin/cat', 20:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:17 INFO - 'PWD': '/builds/slave/test', 20:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:17 INFO - 'SHELL': '/bin/bash', 20:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:17 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:17 INFO - 'USER': 'cltbld', 20:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:17 INFO - 'XPC_FLAGS': '0x0', 20:23:17 INFO - 'XPC_SERVICE_NAME': '0', 20:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:17 INFO - Downloading/unpacking psutil>=3.1.1 20:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:23:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:21 INFO - Installing collected packages: psutil 20:23:21 INFO - Running setup.py install for psutil 20:23:21 INFO - building 'psutil._psutil_osx' extension 20:23:21 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 20:23:21 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 20:23:21 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 20:23:22 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 20:23:22 INFO - building 'psutil._psutil_posix' extension 20:23:22 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 20:23:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:23:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:23:22 INFO - ^ 20:23:22 INFO - 1 warning generated. 20:23:22 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 20:23:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:22 INFO - Successfully installed psutil 20:23:22 INFO - Cleaning up... 20:23:22 INFO - Return code: 0 20:23:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:22 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:22 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 20:23:22 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 20:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:22 INFO - 'HOME': '/Users/cltbld', 20:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:22 INFO - 'LOGNAME': 'cltbld', 20:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:22 INFO - 'MOZ_NO_REMOTE': '1', 20:23:22 INFO - 'NO_EM_RESTART': '1', 20:23:22 INFO - 'PAGER': '/bin/cat', 20:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:22 INFO - 'PWD': '/builds/slave/test', 20:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:22 INFO - 'SHELL': '/bin/bash', 20:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:22 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:22 INFO - 'USER': 'cltbld', 20:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:22 INFO - 'XPC_FLAGS': '0x0', 20:23:22 INFO - 'XPC_SERVICE_NAME': '0', 20:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:23:22 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 20:23:22 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 20:23:22 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 20:23:22 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 20:23:22 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 20:23:22 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 20:23:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:23:25 INFO - Installing collected packages: mozsystemmonitor 20:23:25 INFO - Running setup.py install for mozsystemmonitor 20:23:25 INFO - Successfully installed mozsystemmonitor 20:23:25 INFO - Cleaning up... 20:23:25 INFO - Return code: 0 20:23:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:23: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')]} 20:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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')]} 20:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23: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==2.5.1'] in /builds/slave/test/build 20:23: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==2.5.1 20:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:25 INFO - 'HOME': '/Users/cltbld', 20:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:25 INFO - 'LOGNAME': 'cltbld', 20:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:25 INFO - 'MOZ_NO_REMOTE': '1', 20:23:25 INFO - 'NO_EM_RESTART': '1', 20:23:25 INFO - 'PAGER': '/bin/cat', 20:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:25 INFO - 'PWD': '/builds/slave/test', 20:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:25 INFO - 'SHELL': '/bin/bash', 20:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:25 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:25 INFO - 'USER': 'cltbld', 20:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:25 INFO - 'XPC_FLAGS': '0x0', 20:23:25 INFO - 'XPC_SERVICE_NAME': '0', 20:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:25 INFO - Downloading/unpacking jsonschema==2.5.1 20:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:23:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:28 INFO - Downloading functools32-3.2.3-2.tar.gz 20:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:23:28 INFO - warning: no files found matching '*.txt' 20:23:28 INFO - no previously-included directories found matching 'build' 20:23:28 INFO - no previously-included directories found matching 'dist' 20:23:28 INFO - no previously-included directories found matching '.git*' 20:23:28 INFO - Installing collected packages: jsonschema, functools32 20:23:28 INFO - Running setup.py install for functools32 20:23:29 INFO - warning: no files found matching '*.txt' 20:23:29 INFO - no previously-included directories found matching 'build' 20:23:29 INFO - no previously-included directories found matching 'dist' 20:23:29 INFO - no previously-included directories found matching '.git*' 20:23:29 INFO - Successfully installed jsonschema functools32 20:23:29 INFO - Cleaning up... 20:23:29 INFO - Return code: 0 20:23:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:23:29 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')]} 20:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:29 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')]} 20:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:29 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:29 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 20:23:29 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 20:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:29 INFO - 'HOME': '/Users/cltbld', 20:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:29 INFO - 'LOGNAME': 'cltbld', 20:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:29 INFO - 'MOZ_NO_REMOTE': '1', 20:23:29 INFO - 'NO_EM_RESTART': '1', 20:23:29 INFO - 'PAGER': '/bin/cat', 20:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:29 INFO - 'PWD': '/builds/slave/test', 20:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:29 INFO - 'SHELL': '/bin/bash', 20:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:29 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:29 INFO - 'USER': 'cltbld', 20:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:29 INFO - 'XPC_FLAGS': '0x0', 20:23:29 INFO - 'XPC_SERVICE_NAME': '0', 20:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:23:29 INFO - Cleaning up... 20:23:29 INFO - Return code: 0 20:23:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:23:29 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')]} 20:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:29 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')]} 20:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:29 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:29 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 20:23:29 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 20:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:29 INFO - 'HOME': '/Users/cltbld', 20:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:29 INFO - 'LOGNAME': 'cltbld', 20:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:29 INFO - 'MOZ_NO_REMOTE': '1', 20:23:29 INFO - 'NO_EM_RESTART': '1', 20:23:29 INFO - 'PAGER': '/bin/cat', 20:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:29 INFO - 'PWD': '/builds/slave/test', 20:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:29 INFO - 'SHELL': '/bin/bash', 20:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:29 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:29 INFO - 'USER': 'cltbld', 20:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:29 INFO - 'XPC_FLAGS': '0x0', 20:23:29 INFO - 'XPC_SERVICE_NAME': '0', 20:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:29 INFO - Downloading/unpacking blobuploader==1.2.4 20:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:32 INFO - Downloading blobuploader-1.2.4.tar.gz 20:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:23:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:23:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:33 INFO - Downloading docopt-0.6.1.tar.gz 20:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:23:33 INFO - Installing collected packages: blobuploader, requests, docopt 20:23:33 INFO - Running setup.py install for blobuploader 20:23:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:23:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:23:33 INFO - Running setup.py install for requests 20:23:34 INFO - Running setup.py install for docopt 20:23:34 INFO - Successfully installed blobuploader requests docopt 20:23:34 INFO - Cleaning up... 20:23:34 INFO - Return code: 0 20:23:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:34 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')]} 20:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:34 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')]} 20:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:34 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:34 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 20:23:34 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 20:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:34 INFO - 'HOME': '/Users/cltbld', 20:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:34 INFO - 'LOGNAME': 'cltbld', 20:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:34 INFO - 'MOZ_NO_REMOTE': '1', 20:23:34 INFO - 'NO_EM_RESTART': '1', 20:23:34 INFO - 'PAGER': '/bin/cat', 20:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:34 INFO - 'PWD': '/builds/slave/test', 20:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:34 INFO - 'SHELL': '/bin/bash', 20:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:34 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:34 INFO - 'USER': 'cltbld', 20:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:34 INFO - 'XPC_FLAGS': '0x0', 20:23:34 INFO - 'XPC_SERVICE_NAME': '0', 20:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:23:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-qXIPrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:23:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-64y4LO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:23:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Qng1zX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:23:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-1g9ZZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-MUt5jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Qm6o3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-4vXt_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Zhki8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-um7sAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ymFfgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-OjYn8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-cMnVPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:23:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-RJzRlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:23:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-QTb4Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-52S7rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:23:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-hK6k5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:23:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-1yyffa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:23:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:23:36 INFO - Running setup.py install for manifestparser 20:23:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:23:36 INFO - Running setup.py install for mozcrash 20:23:36 INFO - Running setup.py install for mozdebug 20:23:36 INFO - Running setup.py install for mozdevice 20:23:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Running setup.py install for mozfile 20:23:37 INFO - Running setup.py install for mozhttpd 20:23:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Running setup.py install for mozinfo 20:23:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Running setup.py install for mozInstall 20:23:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Running setup.py install for mozleak 20:23:37 INFO - Running setup.py install for mozlog 20:23:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:23:37 INFO - Running setup.py install for moznetwork 20:23:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:23:38 INFO - Running setup.py install for mozprocess 20:23:38 INFO - Running setup.py install for mozprofile 20:23:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:23:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:23:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:23:38 INFO - Running setup.py install for mozrunner 20:23:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:23:38 INFO - Running setup.py install for mozscreenshot 20:23:38 INFO - Running setup.py install for moztest 20:23:38 INFO - Running setup.py install for mozversion 20:23:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:23:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:23:39 INFO - Cleaning up... 20:23:39 INFO - Return code: 0 20:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:39 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:39 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 20:23:39 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 20:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:39 INFO - 'HOME': '/Users/cltbld', 20:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:39 INFO - 'LOGNAME': 'cltbld', 20:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:39 INFO - 'MOZ_NO_REMOTE': '1', 20:23:39 INFO - 'NO_EM_RESTART': '1', 20:23:39 INFO - 'PAGER': '/bin/cat', 20:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:39 INFO - 'PWD': '/builds/slave/test', 20:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:39 INFO - 'SHELL': '/bin/bash', 20:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:39 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:39 INFO - 'USER': 'cltbld', 20:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:39 INFO - 'XPC_FLAGS': '0x0', 20:23:39 INFO - 'XPC_SERVICE_NAME': '0', 20:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:23:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-8J08H9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:23:39 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)) 20:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:23:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-mbJGsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:23:39 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)) 20:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:23:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-SCYjo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:23:39 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)) 20:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:23:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Ux1Yt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:23:39 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)) 20:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:23:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-TaptkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:23:39 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)) 20:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:23:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-LWiW_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:23:39 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)) 20:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:23:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-rFNo05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Z1tV9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-JffKYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Ig3tPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-grHN5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-jSuwBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-hsN2gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-AZCePh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-3yOXpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:40 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)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-k3iX5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:23:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-jfhENV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:23:41 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)) 20:23:41 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)) 20:23:41 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)) 20:23:41 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)) 20:23:41 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)) 20:23:41 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)) 20:23:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:23:41 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 20:23:41 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 20:23:41 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 20:23:41 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 20:23:41 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 20:23:41 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 20:23:43 INFO - Downloading blessings-1.6.tar.gz 20:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:23:43 INFO - Installing collected packages: blessings 20:23:43 INFO - Running setup.py install for blessings 20:23:43 INFO - Successfully installed blessings 20:23:43 INFO - Cleaning up... 20:23:44 INFO - Return code: 0 20:23:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:23:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:44 INFO - Reading from file tmpfile_stdout 20:23:44 INFO - Current package versions: 20:23:44 INFO - blessings == 1.6 20:23:44 INFO - blobuploader == 1.2.4 20:23:44 INFO - docopt == 0.6.1 20:23:44 INFO - functools32 == 3.2.3-2 20:23:44 INFO - jsonschema == 2.5.1 20:23:44 INFO - manifestparser == 1.1 20:23:44 INFO - mozInstall == 1.12 20:23:44 INFO - mozcrash == 1.0 20:23:44 INFO - mozdebug == 0.1 20:23:44 INFO - mozdevice == 0.49 20:23:44 INFO - mozfile == 1.2 20:23:44 INFO - mozhttpd == 0.7 20:23:44 INFO - mozinfo == 0.9 20:23:44 INFO - mozleak == 0.1 20:23:44 INFO - mozlog == 3.4 20:23:44 INFO - moznetwork == 0.27 20:23:44 INFO - mozprocess == 0.23 20:23:44 INFO - mozprofile == 0.28 20:23:44 INFO - mozrunner == 6.13 20:23:44 INFO - mozscreenshot == 0.1 20:23:44 INFO - mozsystemmonitor == 0.3 20:23:44 INFO - moztest == 0.7 20:23:44 INFO - mozversion == 1.4 20:23:44 INFO - psutil == 3.1.1 20:23:44 INFO - requests == 1.2.3 20:23:44 INFO - wsgiref == 0.1.2 20:23:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:44 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')]} 20:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:44 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')]} 20:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:44 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:44 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 20:23:44 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 20:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:44 INFO - 'HOME': '/Users/cltbld', 20:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:44 INFO - 'LOGNAME': 'cltbld', 20:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:44 INFO - 'MOZ_NO_REMOTE': '1', 20:23:44 INFO - 'NO_EM_RESTART': '1', 20:23:44 INFO - 'PAGER': '/bin/cat', 20:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:44 INFO - 'PWD': '/builds/slave/test', 20:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:44 INFO - 'SHELL': '/bin/bash', 20:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:44 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:44 INFO - 'USER': 'cltbld', 20:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:44 INFO - 'XPC_FLAGS': '0x0', 20:23:44 INFO - 'XPC_SERVICE_NAME': '0', 20:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 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)) 20:23:44 INFO - Cleaning up... 20:23:44 INFO - Return code: 0 20:23:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:23:44 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')]} 20:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:44 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')]} 20:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:44 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 0x10109d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101013570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb490579f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, '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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 20:23:44 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 20:23:44 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 20:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:44 INFO - 'HOME': '/Users/cltbld', 20:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:44 INFO - 'LOGNAME': 'cltbld', 20:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:44 INFO - 'MOZ_NO_REMOTE': '1', 20:23:44 INFO - 'NO_EM_RESTART': '1', 20:23:44 INFO - 'PAGER': '/bin/cat', 20:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:44 INFO - 'PWD': '/builds/slave/test', 20:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:44 INFO - 'SHELL': '/bin/bash', 20:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:23:44 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:44 INFO - 'USER': 'cltbld', 20:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:44 INFO - 'XPC_FLAGS': '0x0', 20:23:44 INFO - 'XPC_SERVICE_NAME': '0', 20:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:23:44 INFO - Cleaning up... 20:23:44 INFO - Return code: 0 20:23:44 INFO - Running post-action listener: _resource_record_post_action 20:23:44 INFO - Running post-action listener: _start_resource_monitoring 20:23:44 INFO - Starting resource monitoring. 20:23:44 INFO - [mozharness: 2016-12-05 04:23:44.913163Z] Finished create-virtualenv step (success) 20:23:44 INFO - [mozharness: 2016-12-05 04:23:44.914137Z] Running install step. 20:23:44 INFO - Running pre-action listener: _resource_record_pre_action 20:23:44 INFO - Running main action method: install 20:23:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:45 INFO - Reading from file tmpfile_stdout 20:23:45 INFO - Detecting whether we're running mozinstall >=1.0... 20:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:23:45 INFO - Reading from file tmpfile_stdout 20:23:45 INFO - Output received: 20:23:45 INFO - Usage: mozinstall [options] installer 20:23:45 INFO - Options: 20:23:45 INFO - -h, --help show this help message and exit 20:23:45 INFO - -d DEST, --destination=DEST 20:23:45 INFO - Directory to install application into. [default: 20:23:45 INFO - "/builds/slave/test"] 20:23:45 INFO - --app=APP Application being installed. [default: firefox] 20:23:45 INFO - mkdir: /builds/slave/test/build/application 20:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:24:17 INFO - Reading from file tmpfile_stdout 20:24:17 INFO - Output received: 20:24:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:24:17 INFO - Running post-action listener: _resource_record_post_action 20:24:17 INFO - [mozharness: 2016-12-05 04:24:17.749364Z] Finished install step (success) 20:24:17 INFO - [mozharness: 2016-12-05 04:24:17.749483Z] Running run-tests step. 20:24:17 INFO - Running pre-action listener: _resource_record_pre_action 20:24:17 INFO - Running main action method: run_tests 20:24:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:24:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:24:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:24:17 INFO - 2016-12-04 20:24:17.764 screenresolution[1635:7600] starting screenresolution argv=screenresolution get 20:24:17 INFO - 2016-12-04 20:24:17.766 screenresolution[1635:7600] Display 0: 1600x1200x32@60 20:24:17 INFO - 2016-12-04 20:24:17.772 screenresolution[1636:7602] starting screenresolution argv=screenresolution list 20:24:17 INFO - Available Modes on Display 0 20:24:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:24:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:24:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:24:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:24:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:24:17 INFO - Intel Iris: 20:24:17 INFO - Chipset Model: Intel Iris 20:24:17 INFO - Type: GPU 20:24:17 INFO - Bus: Built-In 20:24:17 INFO - VRAM (Dynamic, Max): 1536 MB 20:24:17 INFO - Vendor: Intel (0x8086) 20:24:17 INFO - Device ID: 0x0a2e 20:24:17 INFO - Revision ID: 0x0009 20:24:17 INFO - Displays: 20:24:17 INFO - Display: 20:24:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:24:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:24:17 INFO - Display Serial Number: 1600x1200 60 20:24:17 INFO - Main Display: Yes 20:24:17 INFO - Mirror: Off 20:24:17 INFO - Online: Yes 20:24:17 INFO - Rotation: Supported 20:24:18 INFO - Return code: 0 20:24:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:24:18 INFO - Python 2.7.3 20:24:18 INFO - Return code: 0 20:24:18 INFO - grabbing minidump binary from tooltool 20:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:24:18 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 0x100eb2d30>, '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 0x100d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100eaab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:24:18 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 20:24:18 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 20:24:18 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 20:24:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:24:18 INFO - Return code: 0 20:24:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:24:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:24:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:24:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:24:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:24:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 20:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0013 --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 20:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OwVjCS9sGS/Render', 20:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:24:18 INFO - 'HOME': '/Users/cltbld', 20:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:24:18 INFO - 'LOGNAME': 'cltbld', 20:24:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:24:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:18 INFO - 'MOZ_NO_REMOTE': '1', 20:24:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:24:18 INFO - 'NO_EM_RESTART': '1', 20:24:18 INFO - 'PAGER': '/bin/cat', 20:24:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:18 INFO - 'PWD': '/builds/slave/test', 20:24:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:24:18 INFO - 'SHELL': '/bin/bash', 20:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuaeNF7QEr/Listeners', 20:24:18 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 20:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:24:18 INFO - 'USER': 'cltbld', 20:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:18 INFO - 'XPC_FLAGS': '0x0', 20:24:18 INFO - 'XPC_SERVICE_NAME': '0', 20:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:24:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 20:24:18 INFO - mozversion application_buildid: 20161204191338 20:24:18 INFO - mozversion application_changeset: 52cc3c8b17c96e2a008fedaec7149427d1b31530 20:24:18 INFO - mozversion application_display_name: Nightly 20:24:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:24:18 INFO - mozversion application_name: Firefox 20:24:18 INFO - mozversion application_remotingname: firefox 20:24:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:24:18 INFO - mozversion application_vendor: Mozilla 20:24:18 INFO - mozversion application_version: 53.0a1 20:24:18 INFO - mozversion platform_buildid: 20161204191338 20:24:18 INFO - mozversion platform_changeset: 52cc3c8b17c96e2a008fedaec7149427d1b31530 20:24:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:24:18 INFO - mozversion platform_version: 53.0a1 20:24:18 INFO - using testdate: 1480911858 20:24:18 INFO - actual date: 1480911858 20:24:18 INFO - starting webserver on 'localhost:49243' 20:24:18 INFO - SUITE-START | Running 2 tests 20:24:18 INFO - TEST-START | dromaeo_css 20:24:18 INFO - operating with platform_type : mac_ 20:24:18 INFO - Initialising browser for dromaeo_css test... 20:24:18 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpmAMlE1/profile http://localhost:49243/getInfo.html) 20:24:21 INFO - PROCESS | 1646 | 2016-12-04 20:24:21.717 plugin-container[1648:7778] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c3b, name = 'com.apple.tsm.portname' 20:24:21 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:21 INFO - PROCESS | 1646 | 2016-12-04 20:24:21.719 plugin-container[1648:7778] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 20:24:21 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:21 INFO - PROCESS | 1646 | 2016-12-04 20:24:21.719 plugin-container[1648:7778] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:24:21 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 20:24:21 INFO - PROCESS | 1646 | colorDepth:24 20:24:21 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 20:24:21 INFO - PROCESS | 1646 | __metrics 20:24:21 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 20:24:22 INFO - TEST-INFO | 1646: exit 0 20:24:22 INFO - Browser initialized. 20:24:22 INFO - Running cycle 1/1 for dromaeo_css test... 20:24:22 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpmAMlE1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:25 INFO - PROCESS | 1649 | 2016-12-04 20:24:25.488 plugin-container[1650:7902] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:24:25 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:25 INFO - PROCESS | 1649 | 2016-12-04 20:24:25.489 plugin-container[1650:7902] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1f, name = 'com.apple.CFPasteboardClient' 20:24:25 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:25 INFO - PROCESS | 1649 | 2016-12-04 20:24:25.489 plugin-container[1650:7902] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:24:35 INFO - PROCESS | 1649 | RSS: Main: 241815552 20:24:35 INFO - PROCESS | 1649 | 20:25:24 INFO - PROCESS | 1649 | 1480911924849 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:27:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 20:27:40 INFO - PROCESS | 1649 | RSS: Main: 248688640 20:27:40 INFO - PROCESS | 1649 | 20:30:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 20:30:43 INFO - PROCESS | 1649 | RSS: Main: 262324224 20:30:43 INFO - PROCESS | 1649 | 20:33:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 20:33:46 INFO - PROCESS | 1649 | RSS: Main: 267984896 20:33:46 INFO - PROCESS | 1649 | 20:36:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 20:36:48 INFO - PROCESS | 1649 | RSS: Main: 266674176 20:36:48 INFO - PROCESS | 1649 | 20:39:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 20:39:52 INFO - PROCESS | 1649 | RSS: Main: 271302656 20:39:52 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 20:42:55 INFO - PROCESS | 1649 | RSS: Main: 274833408 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | __start_tp_report 20:42:55 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 20:42:55 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 20:42:55 INFO - PROCESS | 1649 | |i|pagename|runs| 20:42:55 INFO - PROCESS | 1649 | |0;dojo.html;3341.7159541438978;3395.202127500037;3424.195314101186;3456.8962931112073;3465.6880880720737;5166.741662916853;5659.094544872821;5819.738111784964;5846.707664616768;5889.8822023559505;5728.16941543476;5767.077267637179;5856.648601083927;5868.911966320498;5894.263217097863;5184.11869982104;5268.0780863348955;5317.12267475866;5324.840254792362;5329.467053294659;5279.894402111955;5386.380566234874;5395;5402.324709411324;5444.128939369702;5326.2543243945875;5361.115415956362;5411.729413529328;5416.295881535415;5419.972900135494;5171.948280517185;5208.947910520922;5227.895442091175;5283.920741188886;5286.762095705702;5014.197728363444;5052.292679024939;5148.459411761749;5167.534921857051;5223.425423203445;5450.264214331054;5582.134769110794;5583.3858275590055;5589.524890384322;5607.887842243133;6093.482054025413;6133.865234931552;6140.907886381708;6162.8767424651705;6177.722002509898;6114.66235542843;6232.91470721991;6247.781327653556;6269.279032911228;6272.372762723691;5960.761569537257;5968.910466343008;5990.191324171268;6005.459508644243;6019.067044608137;6332.936670633235;6345;6360.268569114519;6378.77674281398;6428.260750013808;5138.665986710852;6105.236845394325;6110.801781737226;6270.780522681775;6285.057241413733;5061.519155680205;5513.145462453326;6297.370262973757;6305.4009869062365;6316.905246421307;5925.703714814242;6143.6928153593035;6179.887620228402;6187.195664563578;6210.844728881723;6224.097505861625;6225.346338634468;6236.937630623726;6280.152179455715;6303.558750887394;4782.589136204828;4832.806687732451;4934.5312195341385;5035.823746168867;5061.44324124346;4160.54234034256;4180.038591123997;4467.039586488921;4516.774161291923;4542.613877820422;5844.912326315109;5858.179854820329;5937.554683398676;6096.268447786294;6134.35589263121;6256.593321434092;6309.558330916883;6314.778982735582;6316.652584107826;6323.114763933053;5989.9101513477335;6004.819855404345;6026.6986650667295;6044.24446944132;6064.878702426015;5207.635465517377;5326.46735326472;5347.117725575315;5385.111456609616;5423.511883930465;6218.347073557302;6240.500759939194;6321.620702757849;6343.302236754045;6344.080108384258;6293.4335909766505;6306.842328941813;6314.116023756769;6319.494440444661;6325.335839736761;6201.162843016135;6279.466245369012;6322.241331040304;6330.968345158245;6331.145295385064;5295.338082739658;6185.41238582334;6217.533684973555;6221.626702397871;6221.844453888689;6103.908441373294;6276.780312689034;6283.528735344776;6284.717187726472;6295.150154729053;5680.318361796611;6029.638221706711;6164.4143806338325;6174.722137503733;6177.96911015442;6087.995480745627;6120.204373431604;6138.3861613840045;6140.718981675666;6218.129461875251;5960.195373624505;5983.760649574142;6088.8782224356155;6113.99119145336;6125.540584456273;5787.392323806108;5791.884162316645;5797.391273916178;5840.620359676607;5912.615679980788;5912.793052243151;6015.1278064681255;6016.947034268898;6063.120847477179;6137.294211165773 20:42:55 INFO - PROCESS | 1649 | |1;ext.html;469.22343521471964;499.5853441643436;547.945205479452;552.2213678713013;566.8945556165473;30833.075007749776;36713.449298260544;36793.26413471729;36797.81601091991;36834;37327.813360933156;38025.23949521008;38060.80969595155;38061.80969095157;38124.85625431235;8310.33517318616;8561.1866872647;8961.775955601097;9282.025387334334;9302.627894884197;10978.176636752236;11317.943410282936;11366;11379.374134422604;11474.426278686075;11238.100951923827;11289.774204515905;11294.435278236097;11295.830562541569;11318.603848865292;11202.215844890861;11225.270357426823;11275.210735248536;11279.37963412012;11290.153238507144;10588;10710.928907109226;11079.169062320278;11119.166062545342;11142.721431964184;33312.83343583291;36466.63533364659;36733.44899826505;36823.631763682286;36923.4461483078;3194.824384497106;3273.9345213095844;3283.6387997320276;3284.967150328514;3365.5456513370627;28068.438631227466;28440.857795711097;28671;28699.995500157394;28975.275618109506;3181.984090079558;3194.7284480819158;3200.3439294944483;3294.407006738786;3297.3899828531557;444179;452688;453581.9999999967;454153;454169;54762;54979;55013;55085.449145508835;55087;56829.71585142048;57073;57124;57264.713676432184;57356;14842.332095055748;15328.156951367559;15337.386504539694;15355.309011094527;15359;9175.128362805523;10295.021972912562;10899.1280697544;11262.718432039264;12072.517099316105;15107.39570417171;15116.168610726296;15155.545333640008;15223.771643425358;15378.769318460232;5373;5400.378956420062;6046.27444706638;6541.182352205974;6553.574017688835;824.4805772363372;941.4116177389132;952.3333666433524;970.7670159161892;972.6401231544373;27661.03186388507;27890.163295101178;28510.71489285122;28546.71453285482;28688.13935581936;1005.261133067195;1050.3855244681795;1053.9367637941748;1066.4880857188593;1090.3566895531674;12032.338221397733;12669.430990626895;13557.932210338886;14691.412343506354;15206.543803685907;607327;607670;607956;609457;612256;52273.73863130736;52397.214041789404;52423.21365179525;52538.21192682113;52639;1329.0563699773077;1340.215973655403;1349.8785109340206;1354.3837553912947;1372.9588112356646;27653;28439.146825595264;28806.567901481074;28902.855485722437;29156.854215728785;45743.62769116799;45942.540574595165;46055.53944460512;46148.30777538407;46340.768296158305;552000;555517;555811;556756;557739;31642.208944776565;32634.020979370656;36609.81695091614;37962.0509487265;38017.2396552073;42267.7886610565;43092.78453607712;43117.3532397008;44935.77532112318;45015;35261;35512.46731298981;35861.64138358687;35987.46018809667;36776;30337.938172163867;31232.531512026882;35884.92345229543;36326.09184770402;37081.88754337374;34898;34978.825105874304;35765;35895.461568075974;36336;31339.843300783352;32410.675893240765;32468.350632986734;32497.675023249463;32546;20499.38501844947;23818.88090559536;24237.15169969113;24747.87626061858;25739 20:42:55 INFO - PROCESS | 1649 | |2;jquery.html;318.49995507053933;332.36352385182374;379.32290860813447;388.18674876134526;400.82163437270407;18758.718619220705;19639.11623976921;19917.701234481458;20438.69341959872;20663;19294.807051929478;19694.704579431283;19790.40628781135;20249;20320.491987700316;4036.656884164835;5560.499555040035;5821;5832.270966129234;5854;6263.060540918876;6463.644499552523;6463.7091330890225;6489.415952564268;6492.350764923517;6176.227971503562;6332;6430.067640192169;6432.45324147448;6455.354464553554;5352.545033672142;6417.486601071903;6423.967880160616;6458.354164583528;6473.482121430275;4252.574742525753;4936.555709986083;5378.623496355257;5410.945890541084;5859.619124756877;24073.63889541658;24150.79246037691;24520;24712.629310560354;24864.627030594555;2177.157317334905;2250.189931624614;2267.5691618592577;2349.9765002349927;3008.0524634740204;14808.967006777368;14848.851511484963;15126.319315630823;15469.5359139226;15474;1764.9647007059916;1862.37610400516;1865.580244444989;1959.1086055844557;1959.9024546254;116721;119364;123150;123914.38042809819;124435;9109.863352049724;10508.474576271155;10630.73423164427;10635.20235982289;10968.341899486057;13458.394372253271;13509.864901351057;13524.864751352361;13532.86467135316;13573.52492662752;8361.916380836114;8409;8470.49177049379;8546.145385461528;8675;7075.469339799535;8413.540495078065;8509.914900851036;8511.233988941025;8641.56792160402;5066.873328166826;6343.111964324999;6537.771178008747;6693.799186024427;6701.932980670228;1554.331637395908;1608.4290077022722;1816.9000704961354;1826.8081851405677;1855.7587513623143;859.6002858670748;921.3918813583138;922.7877588154762;934.5560858592114;1030.1964467715195;20562.69155962662;20890.582188356148;21620.78379216219;21622.567548648938;21783.782162178493;902.977425564353;936.1995493852794;943.3302355327656;961.9086186812242;966.1449617088927;11276.774464510663;11535.71160720972;11587.304761714324;11605.6518304451;11678.474468648932;564042;570510;574214;585492;586022;113614.80312758971;116191.2571311431;118820;130320;139468;955.5126885288553;986.1814693804125;1014.8224060789332;1015.0458568945315;1017.9592816287413;18805.24779008879;18997.90501047486;19076.33232836844;19128.426147215607;19138;54821.45178548164;54842;56016;56115;57036.85926281534;79889;81723;81944;82329;84966.87582810479;6594.8351291218105;6958.686859091252;6961.8955715665315;7405.740799072007;8075.676972921017;8786.472811631322;10353.4305613192;11989.880101198876;12800.61598152057;13223.3388330585;9320.440773553608;9799.481080432544;10710;11148.665540033811;11394.487248074023;9356.859647105162;9915.206783457163;10484.528196231035;10952.726181845517;11184.496697648463;10958.561657533928;11132.38771867555;11147.777044459228;11152.330860148417;11597.362145082101;6071.574989750675;7507.887381689388;7581.6209189541405;7610.9238907610215;7708.535378278109;10656.680299591024;11044.33733975964;11065.612703555336;11214.7757044857;11302.4913878874 20:42:55 INFO - PROCESS | 1649 | |3;mootools.html;1429.563982985189;1457.548160070378;1491.962700932477;1743.3026789284293;1792.7221280701497;7485.73799917003;7722.07335119785;8048.517088974659;8472.364572657045;8498.6175622097;8027.19728027197;8282.171782821715;8300.460470069456;8428.873566896502;8453.281471074966;2263.10607310112;2316.9652455213186;2316.976830231693;2326.604477238865;2330.7669233076726;2315.780000899912;2328.719204437561;2362.2204672458133;2366.420227044368;2366.479374537599;2298.8620682759006;2303.0787684925995;2323.9186628468005;2325.244295603927;2330.1960823515947;2288.000158819967;2288.0275882749847;2307.457747429361;2308.203669733939;2323.2449453927456;2267.6598510223434;2284.4517315844246;2288.2448791898632;2291.140822191678;2292.7821856923565;7948.324392426651;8030.879536806952;8033.196680331978;8380.916190838074;8490;5057.393112826484;5198.818041368534;5202.765875535572;5207.1668533034535;5214.60890433219;3173.5239714043078;3246.4481038223557;3501.439769636846;3504.667056629616;3510.613832478451;999.8900120986685;1029.3566520924421;1041.8958104189596;1055.5619417941546;1055.941923194224;10934;10966.670999870017;11023.338599683964;11104.722381940355;11106.944465277622;4470.485894122153;4612.215923293048;4715.104130215248;4727.527247275245;4729.858104256878;7987.720429784929;9457.716268511957;9466.337356384987;9476;9483.620655173716;5137.383513978347;5190.662606930538;5201.219817027476;5211.114110601206;5220.086484865134;3915.7846318452757;4216.030313028021;4233.97883010583;4751.691140075884;4765.547273009059;3755.7183211258716;3762.811859407019;3842.269968705937;3891;3900.3369427197445;1030.7474668706066;1260.420206704926;1263.122130119558;1271.4786937355639;1274.5921305182253;716.5199316457986;886.6807949138305;889.6352495476953;892.9866052009226;900.8648702694649;5546.611737178358;5632.577556683265;5665.801696940587;5721.742521586539;5823.854403639943;941;944.2823454174877;976.2873102635116;987.3088837813416;1013.5439052426437;3742.700583953277;3772.679322257639;3776.0937375029794;3839.9232015359535;3862.4206369044305;9996;10189.337693049929;10325.690229293134;10328.431936243429;10365.689029329133;10250.692479225487;10273.537690803932;10276.486175691185;10286.022827831344;10319.432431216355;6128.866159760459;6449.710057988421;7593.354564862048;7827;8002.319802816709;4476.373307736984;4922.92615610759;5473.8357849264585;5531;5607.962526932367;9430.43417394931;9489.76275593088;9505.95247023788;9522.904770952202;9588.472634004918;9497.097775711296;9579.952100239454;9640.228781697588;9640.325177237526;9737.22102231834;6261.186045814015;6496.740130394731;6654;6712.83217919556;6866.038754574263;7877.172896845748;8079.1516890725625;8085.39359548048;8092.838143236984;8097.878531822145;7044.682989265598;7105.50261481712;7139.678714457966;7193.532420392867;7298.781036568911;6333.398327158912;6877.449804015767;7002.704499667578;7029.121359830021;7102.538335008208;6651.16860392451;6681.365270299314;6749.932500675127;6793.898091528532;6802.795916122524;4788.04239152164;4823.807047718052;4831.710097394168;4858.174110401311;4922.778474968563;4655.301704744315;4666.510016448224;4763.857084287477;4826.0589185108565;4954.207326827835 20:42:55 INFO - PROCESS | 1649 | |4;prototype.html;231.54270316125653;298.8431073686314;303.7342325465219;305.67598345753504;308.64968261023733;4733.124371991175;5086.880886205038;5505.073224371473;5530.308711411074;5591.3569939456975;5269.446708095652;5423.511883930455;5435.402105768362;5460.781568737246;5473.644213126154;3452.361313157068;4214.136102099063;4214.831406743727;4223;4223.049813791903;3609.3864043112603;4024.2152780207725;4230.280852255109;4238.618524332809;4243.872683819491;3546.350946809733;4144.129732756125;4149.564295748948;4155.31437312843;4165.604267594574;3813.2649644411863;3948.7630742155275;4139.56534563871;4146.072076484419;4172.603602657743;2806.214260007201;2988.5965394671657;3608;3628.727845411605;3681.834317455721;5138.537531622134;5289.497497737709;5316.255724198615;5335.586069691535;5341.985022845647;4278.187144442546;4458.219811532968;4723.669343145981;4734.242521196592;4742.928856067162;4471.910561788779;4756.595689366408;5101.984716037145;5741.684207368593;5782.45066718661;3168.825714585697;3653.3971894637352;3823.9808800955816;3952.8616498422703;4000.879973600797;5206.37523497179;5822.796202132948;6023.126646636213;6080;6088.529538163204;3598.3882739934274;3630.565926459034;3900.4344370066183;4037.5760545142925;4092.8158232878995;3768.397056471006;3883.126296583246;4126.236646220459;4132.586741325843;4193.094079926494;4272.636825869836;4426.513083560806;4512.427916087799;4601.700889442175;4769.642276829251;4849.642100211946;5623.775048997994;5874.650139944057;5884.176215329857;5890.970545147246;1132.841402203692;1245.2528482910182;1247.7691627049026;1307.2613973105356;1315.1911574381686;713.7180813578607;879.3932186791094;894.2398960883197;900.1988230474883;901.5131828812367;4826.638002149853;4922.458529561745;5056;5261.76322065508;5295.099833028395;4823.493533178966;5337.879045400509;5401.621886467988;5501.779928802803;5815.41845815415;4734.715917044989;4959.578435832995;5186.740662966837;5239.790408383698;5248.475152484721;2574.9742502574736;2877.2950627096075;2892.2191008428026;2995.221242476972;3011.2170835582906;4876.878078048077;5200.7919683213;5211.061431373882;5729.054705973468;5731.254936858182;5243;5537.418571050062;5840.970795145997;5842.620229685057;5856.45684360964;5300.92048619271;5389.7035663038905;5515.227868098389;5689.573282003784;5791;5668.829935101953;6028.186194863637;6081.726322315595;6090.390960903874;6142.754289828356;6018.51851851842;6020.849478763066;6021.819345419469;6032.853757786007;6049.667268300285;4512.481064677604;4695.671303008757;6022.193341997471;6102.4812890902995;6158.983767678283;3355.3558756208795;3425.2635683328203;3441.4513468938576;3664.5602527696847;4015.738976966488;5247.239150323258;5280.471952804689;5546.696526316393;6089.421504957022;6103.108036508696;4848.733319667452;5660.801871934463;5775.249217601685;5783.971080144573;5906.645601263938;5818.8254352369495;5866.090755932837;5875.6474611523445;5894.174815525744;5934.7922822701;4430.911381772411;5514.944850551443;5656.85857853557;5753.338366087954;5795.247896724645;4977.850664480072;5827.650340979385;5864.530837533072;5877.265341832271;5909.4293982117915;4816.4220293564995;5379.838604841861;5587.8882422352135;5643;5762.567807414544 20:42:55 INFO - PROCESS | 1649 | |5;yui.html;618.7679620142446;709.4147328454027;718.3642476408379;726.8437285983513;727.2400341642982;14533.418663253471;15310.38758449662;15496.535103946871;16627.168641567903;17202.483925482255;15208.543743687707;16015.35938562459;16290.918545407256;16292.26684799184;16315.755263671024;806.5160903457922;838.9177960431067;840.604915689628;840.8318336332727;845.2519520224585;645.8805121052596;673.7237732529663;783.1776634533745;810.8013536683521;828.5981299069946;862.6937437209795;867.0635713429513;880.6609455359701;882.272125496468;883.571467838098;867.8784970104191;873.9563021848882;874.1302404107921;874.7069731639908;876.1720174435161;649.8505343770912;649.8895187818082;650.2554575011585;652.5301782716436;655.0665301944757;15006.099634021994;15344.386224551004;15346.923265383713;15499.767503487457;15542.844571554253;13892.93053534726;13910.51313204043;13925.164490130523;14226.573202803933;14283.78574321386;11850.822237666442;11974;12235.449404776806;12246.387680615933;12415.193012454161;3633.7819730816236;3821.445890345912;3834.3098242316373;3851.5378154621626;3866.4586957825927;110205;116426;117087.82912170855;117830.23254651188;121251.3937430316;9771;10257.230707696952;10356.275060745675;10541.31481453703;10693.358398496115;21424.78575214259;21430.678539821914;21448.78551214499;21458.892705536684;21463.46341341479;7557.35762460197;7596.164421913585;7656.80857978555;7736.419768517384;7768.262015108556;3390.694837464591;3659.3779057560278;3989.960100399017;4143.943314358519;4438.822447102079;2934.6918573549897;3413.2490852012515;3487.773294735834;3523.3657941570764;3525.1715846775987;602.6625089949555;622.107276058857;627.3193584960278;635.8887194740994;641.112059797175;564.8954943335497;570.3061906138761;607.9785959587934;634.403660559073;647.0972992675191;7928.197456993268;8247.463914845515;8676.522791246542;8736.519491427905;9169.403988740843;460.8225833054231;578.6932925549465;587.1281147495965;621.3258614403333;652.2987788127782;4283.357496375568;4284.421603083544;4285.421468101828;4333.285007973712;4406.867793966187;78636.60681696555;106139;107622;108307.45846270719;109261;93205.53397232971;94862.05137948532;95499.52250238704;95933.12200633992;96621.51689241649;78893;79242;79796.20203798004;80321.59839200767;81879.18120818716;7799.961000194962;8439.873401898854;8487.023992240971;8567.614457349559;8638.740837774876;43618.90952726207;53072.46927530675;54201;54417.45582544124;54618.45381546134;61480.69259653673;67222.66388668025;74680.62659686667;77247.61376193083;79165.20834791579;9152.725418237464;9716.651379558189;10307.690769276935;10588.947055264674;10816.4050977197;11932.463039163085;12068.818967715315;12244.877551224374;12369.196002259825;12440.813387799371;9455.763605909908;11710.00464960488;11819.172657913863;12054.51781928713;12109.970652494285;10084.394936303845;10898.945505272422;11639.64682994955;11714.414279286171;12285.078619103782;11235.943820280847;11472.713182170513;11490.540378385105;11528.250663707167;11970.22193557416;9621.90378096238;9625.663101791404;9703.466309353053;9747.463889486145;10061.798764024825;8419.789505262417;8953.59708813118;9653.276004299845;9839.360441571274;9983.800323993624 20:42:55 INFO - PROCESS | 1649 | __end_tp_report 20:42:55 INFO - PROCESS | 1649 | __start_cc_report 20:42:55 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,361 20:42:55 INFO - PROCESS | 1649 | __end_cc_report 20:42:55 INFO - PROCESS | 1649 | __startTimestamp1480912975447__endTimestamp 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | ------- Summary: start ------- 20:42:55 INFO - PROCESS | 1649 | Number of tests: 6 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5760.54 Median:6005.46 stddev:643.21 (10.7%) stddev-sans-first:616.56 20:42:55 INFO - PROCESS | 1649 | Values: 3341.7 3395.2 3424.2 3456.9 3465.7 5166.7 5659.1 5819.7 5846.7 5889.9 5728.2 5767.1 5856.6 5868.9 5894.3 5184.1 5268.1 5317.1 5324.8 5329.5 5279.9 5386.4 5395.0 5402.3 5444.1 5326.3 5361.1 5411.7 5416.3 5420.0 5171.9 5208.9 5227.9 5283.9 5286.8 5014.2 5052.3 5148.5 5167.5 5223.4 5450.3 5582.1 5583.4 5589.5 5607.9 6093.5 6133.9 6140.9 6162.9 6177.7 6114.7 6232.9 6247.8 6269.3 6272.4 5960.8 5968.9 5990.2 6005.5 6019.1 6332.9 6345.0 6360.3 6378.8 6428.3 5138.7 6105.2 6110.8 6270.8 6285.1 5061.5 5513.1 6297.4 6305.4 6316.9 5925.7 6143.7 6179.9 6187.2 6210.8 6224.1 6225.3 6236.9 6280.2 6303.6 4782.6 4832.8 4934.5 5035.8 5061.4 4160.5 4180.0 4467.0 4516.8 4542.6 5844.9 5858.2 5937.6 6096.3 6134.4 6256.6 6309.6 6314.8 6316.7 6323.1 5989.9 6004.8 6026.7 6044.2 6064.9 5207.6 5326.5 5347.1 5385.1 5423.5 6218.3 6240.5 6321.6 6343.3 6344.1 6293.4 6306.8 6314.1 6319.5 6325.3 6201.2 6279.5 6322.2 6331.0 6331.1 5295.3 6185.4 6217.5 6221.6 6221.8 6103.9 6276.8 6283.5 6284.7 6295.2 5680.3 6029.6 6164.4 6174.7 6178.0 6088.0 6120.2 6138.4 6140.7 6218.1 5960.2 5983.8 6088.9 6114.0 6125.5 5787.4 5791.9 5797.4 5840.6 5912.6 5912.8 6015.1 6016.9 6063.1 6137.3 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:66317.55 Median:34938.41 stddev:145005.04 (415.0%) stddev-sans-first:145327.53 20:42:55 INFO - PROCESS | 1649 | Values: 469.2 499.6 547.9 552.2 566.9 30833.1 36713.4 36793.3 36797.8 36834.0 37327.8 38025.2 38060.8 38061.8 38124.9 8310.3 8561.2 8961.8 9282.0 9302.6 10978.2 11317.9 11366.0 11379.4 11474.4 11238.1 11289.8 11294.4 11295.8 11318.6 11202.2 11225.3 11275.2 11279.4 11290.2 10588.0 10710.9 11079.2 11119.2 11142.7 33312.8 36466.6 36733.4 36823.6 36923.4 3194.8 3273.9 3283.6 3285.0 3365.5 28068.4 28440.9 28671.0 28700.0 28975.3 3182.0 3194.7 3200.3 3294.4 3297.4 444179.0 452688.0 453582.0 454153.0 454169.0 54762.0 54979.0 55013.0 55085.4 55087.0 56829.7 57073.0 57124.0 57264.7 57356.0 14842.3 15328.2 15337.4 15355.3 15359.0 9175.1 10295.0 10899.1 11262.7 12072.5 15107.4 15116.2 15155.5 15223.8 15378.8 5373.0 5400.4 6046.3 6541.2 6553.6 824.5 941.4 952.3 970.8 972.6 27661.0 27890.2 28510.7 28546.7 28688.1 1005.3 1050.4 1053.9 1066.5 1090.4 12032.3 12669.4 13557.9 14691.4 15206.5 607327.0 607670.0 607956.0 609457.0 612256.0 52273.7 52397.2 52423.2 52538.2 52639.0 1329.1 1340.2 1349.9 1354.4 1373.0 27653.0 28439.1 28806.6 28902.9 29156.9 45743.6 45942.5 46055.5 46148.3 46340.8 552000.0 555517.0 555811.0 556756.0 557739.0 31642.2 32634.0 36609.8 37962.1 38017.2 42267.8 43092.8 43117.4 44935.8 45015.0 35261.0 35512.5 35861.6 35987.5 36776.0 30337.9 31232.5 35884.9 36326.1 37081.9 34898.0 34978.8 35765.0 35895.5 36336.0 31339.8 32410.7 32468.4 32497.7 32546.0 20499.4 23818.9 24237.2 24747.9 25739.0 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34589.17 Median:24788.63 stddev:96418.08 (389.0%) stddev-sans-first:96654.23 20:42:55 INFO - PROCESS | 1649 | Values: 318.5 332.4 379.3 388.2 400.8 18758.7 19639.1 19917.7 20438.7 20663.0 19294.8 19694.7 19790.4 20249.0 20320.5 4036.7 5560.5 5821.0 5832.3 5854.0 6263.1 6463.6 6463.7 6489.4 6492.4 6176.2 6332.0 6430.1 6432.5 6455.4 5352.5 6417.5 6424.0 6458.4 6473.5 4252.6 4936.6 5378.6 5410.9 5859.6 24073.6 24150.8 24520.0 24712.6 24864.6 2177.2 2250.2 2267.6 2350.0 3008.1 14809.0 14848.9 15126.3 15469.5 15474.0 1765.0 1862.4 1865.6 1959.1 1959.9 116721.0 119364.0 123150.0 123914.4 124435.0 9109.9 10508.5 10630.7 10635.2 10968.3 13458.4 13509.9 13524.9 13532.9 13573.5 8361.9 8409.0 8470.5 8546.1 8675.0 7075.5 8413.5 8509.9 8511.2 8641.6 5066.9 6343.1 6537.8 6693.8 6701.9 1554.3 1608.4 1816.9 1826.8 1855.8 859.6 921.4 922.8 934.6 1030.2 20562.7 20890.6 21620.8 21622.6 21783.8 903.0 936.2 943.3 961.9 966.1 11276.8 11535.7 11587.3 11605.7 11678.5 564042.0 570510.0 574214.0 585492.0 586022.0 113614.8 116191.3 118820.0 130320.0 139468.0 955.5 986.2 1014.8 1015.0 1018.0 18805.2 18997.9 19076.3 19128.4 19138.0 54821.5 54842.0 56016.0 56115.0 57036.9 79889.0 81723.0 81944.0 82329.0 84966.9 6594.8 6958.7 6961.9 7405.7 8075.7 8786.5 10353.4 11989.9 12800.6 13223.3 9320.4 9799.5 10710.0 11148.7 11394.5 9356.9 9915.2 10484.5 10952.7 11184.5 10958.6 11132.4 11147.8 11152.3 11597.4 6071.6 7507.9 7581.6 7610.9 7708.5 10656.7 11044.3 11065.6 11214.8 11302.5 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5420.01 Median:5005.80 stddev:2998.90 (59.9%) stddev-sans-first:2992.32 20:42:55 INFO - PROCESS | 1649 | Values: 1429.6 1457.5 1492.0 1743.3 1792.7 7485.7 7722.1 8048.5 8472.4 8498.6 8027.2 8282.2 8300.5 8428.9 8453.3 2263.1 2317.0 2317.0 2326.6 2330.8 2315.8 2328.7 2362.2 2366.4 2366.5 2298.9 2303.1 2323.9 2325.2 2330.2 2288.0 2288.0 2307.5 2308.2 2323.2 2267.7 2284.5 2288.2 2291.1 2292.8 7948.3 8030.9 8033.2 8380.9 8490.0 5057.4 5198.8 5202.8 5207.2 5214.6 3173.5 3246.4 3501.4 3504.7 3510.6 999.9 1029.4 1041.9 1055.6 1055.9 10934.0 10966.7 11023.3 11104.7 11106.9 4470.5 4612.2 4715.1 4727.5 4729.9 7987.7 9457.7 9466.3 9476.0 9483.6 5137.4 5190.7 5201.2 5211.1 5220.1 3915.8 4216.0 4234.0 4751.7 4765.5 3755.7 3762.8 3842.3 3891.0 3900.3 1030.7 1260.4 1263.1 1271.5 1274.6 716.5 886.7 889.6 893.0 900.9 5546.6 5632.6 5665.8 5721.7 5823.9 941.0 944.3 976.3 987.3 1013.5 3742.7 3772.7 3776.1 3839.9 3862.4 9996.0 10189.3 10325.7 10328.4 10365.7 10250.7 10273.5 10276.5 10286.0 10319.4 6128.9 6449.7 7593.4 7827.0 8002.3 4476.4 4922.9 5473.8 5531.0 5608.0 9430.4 9489.8 9506.0 9522.9 9588.5 9497.1 9580.0 9640.2 9640.3 9737.2 6261.2 6496.7 6654.0 6712.8 6866.0 7877.2 8079.2 8085.4 8092.8 8097.9 7044.7 7105.5 7139.7 7193.5 7298.8 6333.4 6877.4 7002.7 7029.1 7102.5 6651.2 6681.4 6749.9 6793.9 6802.8 4788.0 4823.8 4831.7 4858.2 4922.8 4655.3 4666.5 4763.9 4826.1 4954.2 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4583.70 Median:5193.77 stddev:1444.02 (27.8%) stddev-sans-first:1410.64 20:42:55 INFO - PROCESS | 1649 | Values: 231.5 298.8 303.7 305.7 308.6 4733.1 5086.9 5505.1 5530.3 5591.4 5269.4 5423.5 5435.4 5460.8 5473.6 3452.4 4214.1 4214.8 4223.0 4223.0 3609.4 4024.2 4230.3 4238.6 4243.9 3546.4 4144.1 4149.6 4155.3 4165.6 3813.3 3948.8 4139.6 4146.1 4172.6 2806.2 2988.6 3608.0 3628.7 3681.8 5138.5 5289.5 5316.3 5335.6 5342.0 4278.2 4458.2 4723.7 4734.2 4742.9 4471.9 4756.6 5102.0 5741.7 5782.5 3168.8 3653.4 3824.0 3952.9 4000.9 5206.4 5822.8 6023.1 6080.0 6088.5 3598.4 3630.6 3900.4 4037.6 4092.8 3768.4 3883.1 4126.2 4132.6 4193.1 4272.6 4426.5 4512.4 4601.7 4769.6 4849.6 5623.8 5874.7 5884.2 5891.0 1132.8 1245.3 1247.8 1307.3 1315.2 713.7 879.4 894.2 900.2 901.5 4826.6 4922.5 5056.0 5261.8 5295.1 4823.5 5337.9 5401.6 5501.8 5815.4 4734.7 4959.6 5186.7 5239.8 5248.5 2575.0 2877.3 2892.2 2995.2 3011.2 4876.9 5200.8 5211.1 5729.1 5731.3 5243.0 5537.4 5841.0 5842.6 5856.5 5300.9 5389.7 5515.2 5689.6 5791.0 5668.8 6028.2 6081.7 6090.4 6142.8 6018.5 6020.8 6021.8 6032.9 6049.7 4512.5 4695.7 6022.2 6102.5 6159.0 3355.4 3425.3 3441.5 3664.6 4015.7 5247.2 5280.5 5546.7 6089.4 6103.1 4848.7 5660.8 5775.2 5784.0 5906.6 5818.8 5866.1 5875.6 5894.2 5934.8 4430.9 5514.9 5656.9 5753.3 5795.2 4977.9 5827.7 5864.5 5877.3 5909.4 4816.4 5379.8 5587.9 5643.0 5762.6 20:42:55 INFO - PROCESS | 1649 | 20:42:55 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:20699.37 Median:54517.95 stddev:31215.15 (57.3%) stddev-sans-first:31266.30 20:42:55 INFO - PROCESS | 1649 | Values: 618.8 709.4 718.4 726.8 727.2 14533.4 15310.4 15496.5 16627.2 17202.5 15208.5 16015.4 16290.9 16292.3 16315.8 806.5 838.9 840.6 840.8 845.3 645.9 673.7 783.2 810.8 828.6 862.7 867.1 880.7 882.3 883.6 867.9 874.0 874.1 874.7 876.2 649.9 649.9 650.3 652.5 655.1 15006.1 15344.4 15346.9 15499.8 15542.8 13892.9 13910.5 13925.2 14226.6 14283.8 11850.8 11974.0 12235.4 12246.4 12415.2 3633.8 3821.4 3834.3 3851.5 3866.5 110205.0 116426.0 117087.8 117830.2 121251.4 9771.0 10257.2 10356.3 10541.3 10693.4 21424.8 21430.7 21448.8 21458.9 21463.5 7557.4 7596.2 7656.8 7736.4 7768.3 3390.7 3659.4 3990.0 4143.9 4438.8 2934.7 3413.2 3487.8 3523.4 3525.2 602.7 622.1 627.3 635.9 641.1 564.9 570.3 608.0 634.4 647.1 7928.2 8247.5 8676.5 8736.5 9169.4 460.8 578.7 587.1 621.3 652.3 4283.4 4284.4 4285.4 4333.3 4406.9 78636.6 106139.0 107622.0 108307.5 109261.0 93205.5 94862.1 95499.5 95933.1 96621.5 78893.0 79242.0 79796.2 80321.6 81879.2 7800.0 8439.9 8487.0 8567.6 8638.7 43618.9 53072.5 54201.0 54417.5 54618.5 61480.7 67222.7 74680.6 77247.6 79165.2 9152.7 9716.7 10307.7 10588.9 10816.4 11932.5 12068.8 12244.9 12369.2 12440.8 9455.8 11710.0 11819.2 12054.5 12110.0 10084.4 10898.9 11639.6 11714.4 12285.1 11235.9 11472.7 11490.5 11528.3 11970.2 9621.9 9625.7 9703.5 9747.5 10061.8 8419.8 8953.6 9653.3 9839.4 9983.8 20:42:55 INFO - PROCESS | 1649 | -------- Summary: end -------- 20:42:55 INFO - PROCESS | 1649 | 20:42:56 INFO - TEST-INFO | 1649: exit 0 20:42:56 INFO - TEST-OK | dromaeo_css | took 1117755ms 20:42:56 INFO - TEST-START | kraken 20:42:56 INFO - operating with platform_type : mac_ 20:42:56 INFO - Initialising browser for kraken test... 20:42:56 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmp588kjf/profile http://localhost:49243/getInfo.html) 20:42:59 INFO - PROCESS | 1659 | 2016-12-04 20:42:59.127 plugin-container[1660:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 20:42:59 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:42:59 INFO - PROCESS | 1659 | 2016-12-04 20:42:59.130 plugin-container[1660:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.CFPasteboardClient' 20:42:59 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:42:59 INFO - PROCESS | 1659 | 2016-12-04 20:42:59.130 plugin-container[1660:10400] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:42:59 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 20:42:59 INFO - PROCESS | 1659 | colorDepth:24 20:42:59 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 20:42:59 INFO - PROCESS | 1659 | __metrics 20:42:59 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 20:43:00 INFO - TEST-INFO | 1659: exit 0 20:43:00 INFO - Browser initialized. 20:43:00 INFO - Running cycle 1/1 for kraken test... 20:43:00 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmp588kjf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:43:02 INFO - PROCESS | 1661 | 2016-12-04 20:43:02.845 plugin-container[1662:10515] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 20:43:02 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:43:02 INFO - PROCESS | 1661 | 2016-12-04 20:43:02.846 plugin-container[1662:10515] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 20:43:02 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:43:02 INFO - PROCESS | 1661 | 2016-12-04 20:43:02.846 plugin-container[1662:10515] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:43:12 INFO - PROCESS | 1661 | RSS: Main: 241479680 20:43:12 INFO - PROCESS | 1661 | 20:43:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 20:43:16 INFO - PROCESS | 1661 | RSS: Main: 253779968 20:43:16 INFO - PROCESS | 1661 | 20:43:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 20:43:19 INFO - PROCESS | 1661 | RSS: Main: 261320704 20:43:19 INFO - PROCESS | 1661 | 20:43:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 20:43:23 INFO - PROCESS | 1661 | RSS: Main: 264110080 20:43:23 INFO - PROCESS | 1661 | 20:43:25 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 20:43:26 INFO - PROCESS | 1661 | RSS: Main: 265256960 20:43:26 INFO - PROCESS | 1661 | 20:43:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:43:29 INFO - PROCESS | 1661 | RSS: Main: 266878976 20:43:29 INFO - PROCESS | 1661 | 20:43:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 20:43:32 INFO - PROCESS | 1661 | RSS: Main: 272617472 20:43:32 INFO - PROCESS | 1661 | 20:43:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 20:43:36 INFO - PROCESS | 1661 | RSS: Main: 271904768 20:43:36 INFO - PROCESS | 1661 | 20:43:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 20:43:40 INFO - PROCESS | 1661 | RSS: Main: 271691776 20:43:40 INFO - PROCESS | 1661 | 20:43:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:43:43 INFO - PROCESS | 1661 | RSS: Main: 270843904 20:43:43 INFO - PROCESS | 1661 | 20:43:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:43:45 INFO - PROCESS | 1661 | RSS: Main: 270974976 20:43:45 INFO - PROCESS | 1661 | 20:43:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:43:48 INFO - PROCESS | 1661 | RSS: Main: 270974976 20:43:48 INFO - PROCESS | 1661 | 20:43:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:43:52 INFO - PROCESS | 1661 | RSS: Main: 271306752 20:43:52 INFO - PROCESS | 1661 | 20:43:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:43:56 INFO - PROCESS | 1661 | RSS: Main: 271163392 20:43:56 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 20:43:58 INFO - PROCESS | 1661 | RSS: Main: 273633280 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | __start_tp_report 20:43:58 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 20:43:58 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 20:43:58 INFO - PROCESS | 1661 | |i|pagename|runs| 20:43:58 INFO - PROCESS | 1661 | |0;ai-astar;113;174;123;136;173;160;118;116;117;117 20:43:58 INFO - PROCESS | 1661 | |1;audio-beat-detection;174;148;119;152;117;119;119;137;168;117 20:43:58 INFO - PROCESS | 1661 | |2;audio-dft;188;212;222;229;258;276;199;274;271;196 20:43:58 INFO - PROCESS | 1661 | |3;audio-fft;83;59;59;90;84;85;68;59;73;60 20:43:58 INFO - PROCESS | 1661 | |4;audio-oscillator;83;70;82;73;57;86;102;60;77;113 20:43:58 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;107;122;137;132;136;87;100;80;129;85 20:43:58 INFO - PROCESS | 1661 | |6;imaging-darkroom;116;151;104;152;100;153;152;106;97;90 20:43:58 INFO - PROCESS | 1661 | |7;imaging-desaturate;93;148;146;82;145;152;108;115;145;150 20:43:58 INFO - PROCESS | 1661 | |8;json-parse-financial;56;57;57;59;58;60;57;57;58;57 20:43:58 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;102;112;111;96;82;75;71;73;73;73 20:43:58 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;97;109;110;88;89;76;109;113;81;103 20:43:58 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;164;152;146;150;142;212;168;157;220;179 20:43:58 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;175;174;175;172;177;175;184;179;175;178 20:43:58 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;69;94;88;72;72;69;71;70;70;73 20:43:58 INFO - PROCESS | 1661 | __end_tp_report 20:43:58 INFO - PROCESS | 1661 | __start_cc_report 20:43:58 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,930 20:43:58 INFO - PROCESS | 1661 | __end_cc_report 20:43:58 INFO - PROCESS | 1661 | __startTimestamp1480913038293__endTimestamp 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | ------- Summary: start ------- 20:43:58 INFO - PROCESS | 1661 | Number of tests: 14 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:134.70 Median:129.50 stddev:24.76 (19.1%) stddev-sans-first:24.98 20:43:58 INFO - PROCESS | 1661 | Values: 113.0 174.0 123.0 136.0 173.0 160.0 118.0 116.0 117.0 117.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:142.50 stddev:22.23 (15.6%) stddev-sans-first:19.13 20:43:58 INFO - PROCESS | 1661 | Values: 174.0 148.0 119.0 152.0 117.0 119.0 119.0 137.0 168.0 117.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:232.50 Median:243.50 stddev:34.52 (14.2%) stddev-sans-first:32.64 20:43:58 INFO - PROCESS | 1661 | Values: 188.0 212.0 222.0 229.0 258.0 276.0 199.0 274.0 271.0 196.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:72.00 Median:78.00 stddev:12.59 (16.1%) stddev-sans-first:12.71 20:43:58 INFO - PROCESS | 1661 | Values: 83.0 59.0 59.0 90.0 84.0 85.0 68.0 59.0 73.0 60.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:75.00 stddev:17.35 (23.1%) stddev-sans-first:18.37 20:43:58 INFO - PROCESS | 1661 | Values: 83.0 70.0 82.0 73.0 57.0 86.0 102.0 60.0 77.0 113.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:111.50 Median:136.50 stddev:22.45 (16.4%) stddev-sans-first:23.75 20:43:58 INFO - PROCESS | 1661 | Values: 107.0 122.0 137.0 132.0 136.0 87.0 100.0 80.0 129.0 85.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:122.10 Median:152.00 stddev:26.56 (17.5%) stddev-sans-first:28.08 20:43:58 INFO - PROCESS | 1661 | Values: 116.0 151.0 104.0 152.0 100.0 153.0 152.0 106.0 97.0 90.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:128.40 Median:149.00 stddev:26.40 (17.7%) stddev-sans-first:24.69 20:43:58 INFO - PROCESS | 1661 | Values: 93.0 148.0 146.0 82.0 145.0 152.0 108.0 115.0 145.0 150.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:1.17 (2.0%) stddev-sans-first:1.09 20:43:58 INFO - PROCESS | 1661 | Values: 56.0 57.0 57.0 59.0 58.0 60.0 57.0 57.0 58.0 57.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:86.80 Median:73.00 stddev:16.73 (22.9%) stddev-sans-first:16.82 20:43:58 INFO - PROCESS | 1661 | Values: 102.0 112.0 111.0 96.0 82.0 75.0 71.0 73.0 73.0 73.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:97.50 Median:78.50 stddev:13.29 (16.9%) stddev-sans-first:14.09 20:43:58 INFO - PROCESS | 1661 | Values: 97.0 109.0 110.0 88.0 89.0 76.0 109.0 113.0 81.0 103.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:169.00 Median:166.00 stddev:27.14 (16.3%) stddev-sans-first:28.72 20:43:58 INFO - PROCESS | 1661 | Values: 164.0 152.0 146.0 150.0 142.0 212.0 168.0 157.0 220.0 179.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:176.00 stddev:3.34 (1.9%) stddev-sans-first:3.50 20:43:58 INFO - PROCESS | 1661 | Values: 175.0 174.0 175.0 172.0 177.0 175.0 184.0 179.0 175.0 178.0 20:43:58 INFO - PROCESS | 1661 | 20:43:58 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:72.00 stddev:8.75 (12.2%) stddev-sans-first:9.03 20:43:58 INFO - PROCESS | 1661 | Values: 69.0 94.0 88.0 72.0 72.0 69.0 71.0 70.0 70.0 73.0 20:43:58 INFO - PROCESS | 1661 | -------- Summary: end -------- 20:43:58 INFO - PROCESS | 1661 | 20:43:59 INFO - TEST-INFO | 1661: exit 0 20:43:59 INFO - TEST-OK | kraken | took 63423ms 20:43:59 INFO - SUITE-END | took 1181s 20:43:59 INFO - Completed test suite (00:19:41) 20:43:59 INFO - javascript benchmark 20:44:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7165.889890739583, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3341.7159541438978, 3395.202127500037, 3424.195314101186, 3456.8962931112073, 3465.6880880720737, 5166.741662916853, 5659.094544872821, 5819.738111784964, 5846.707664616768, 5889.8822023559505, 5728.16941543476, 5767.077267637179, 5856.648601083927, 5868.911966320498, 5894.263217097863, 5184.11869982104, 5268.0780863348955, 5317.12267475866, 5324.840254792362, 5329.467053294659, 5279.894402111955, 5386.380566234874, 5395.0, 5402.324709411324, 5444.128939369702, 5326.2543243945875, 5361.115415956362, 5411.729413529328, 5416.295881535415, 5419.972900135494, 5171.948280517185, 5208.947910520922, 5227.895442091175, 5283.920741188886, 5286.762095705702, 5014.197728363444, 5052.292679024939, 5148.459411761749, 5167.534921857051, 5223.425423203445, 5450.264214331054, 5582.134769110794, 5583.3858275590055, 5589.524890384322, 5607.887842243133, 6093.482054025413, 6133.865234931552, 6140.907886381708, 6162.8767424651705, 6177.722002509898, 6114.66235542843, 6232.91470721991, 6247.781327653556, 6269.279032911228, 6272.372762723691, 5960.761569537257, 5968.910466343008, 5990.191324171268, 6005.459508644243, 6019.067044608137, 6332.936670633235, 6345.0, 6360.268569114519, 6378.77674281398, 6428.260750013808, 5138.665986710852, 6105.236845394325, 6110.801781737226, 6270.780522681775, 6285.057241413733, 5061.519155680205, 5513.145462453326, 6297.370262973757, 6305.4009869062365, 6316.905246421307, 5925.703714814242, 6143.6928153593035, 6179.887620228402, 6187.195664563578, 6210.844728881723, 6224.097505861625, 6225.346338634468, 6236.937630623726, 6280.152179455715, 6303.558750887394, 4782.589136204828, 4832.806687732451, 4934.5312195341385, 5035.823746168867, 5061.44324124346, 4160.54234034256, 4180.038591123997, 4467.039586488921, 4516.774161291923, 4542.613877820422, 5844.912326315109, 5858.179854820329, 5937.554683398676, 6096.268447786294, 6134.35589263121, 6256.593321434092, 6309.558330916883, 6314.778982735582, 6316.652584107826, 6323.114763933053, 5989.9101513477335, 6004.819855404345, 6026.6986650667295, 6044.24446944132, 6064.878702426015, 5207.635465517377, 5326.46735326472, 5347.117725575315, 5385.111456609616, 5423.511883930465, 6218.347073557302, 6240.500759939194, 6321.620702757849, 6343.302236754045, 6344.080108384258, 6293.4335909766505, 6306.842328941813, 6314.116023756769, 6319.494440444661, 6325.335839736761, 6201.162843016135, 6279.466245369012, 6322.241331040304, 6330.968345158245, 6331.145295385064, 5295.338082739658, 6185.41238582334, 6217.533684973555, 6221.626702397871, 6221.844453888689, 6103.908441373294, 6276.780312689034, 6283.528735344776, 6284.717187726472, 6295.150154729053, 5680.318361796611, 6029.638221706711, 6164.4143806338325, 6174.722137503733, 6177.96911015442, 6087.995480745627, 6120.204373431604, 6138.3861613840045, 6140.718981675666, 6218.129461875251, 5960.195373624505, 5983.760649574142, 6088.8782224356155, 6113.99119145336, 6125.540584456273, 5787.392323806108, 5791.884162316645, 5797.391273916178, 5840.620359676607, 5912.615679980788, 5912.793052243151, 6015.1278064681255, 6016.947034268898, 6063.120847477179, 6137.294211165773], "value": 5720.459023697782, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.22343521471964, 499.5853441643436, 547.945205479452, 552.2213678713013, 566.8945556165473, 30833.075007749776, 36713.449298260544, 36793.26413471729, 36797.81601091991, 36834.0, 37327.813360933156, 38025.23949521008, 38060.80969595155, 38061.80969095157, 38124.85625431235, 8310.33517318616, 8561.1866872647, 8961.775955601097, 9282.025387334334, 9302.627894884197, 10978.176636752236, 11317.943410282936, 11366.0, 11379.374134422604, 11474.426278686075, 11238.100951923827, 11289.774204515905, 11294.435278236097, 11295.830562541569, 11318.603848865292, 11202.215844890861, 11225.270357426823, 11275.210735248536, 11279.37963412012, 11290.153238507144, 10588.0, 10710.928907109226, 11079.169062320278, 11119.166062545342, 11142.721431964184, 33312.83343583291, 36466.63533364659, 36733.44899826505, 36823.631763682286, 36923.4461483078, 3194.824384497106, 3273.9345213095844, 3283.6387997320276, 3284.967150328514, 3365.5456513370627, 28068.438631227466, 28440.857795711097, 28671.0, 28699.995500157394, 28975.275618109506, 3181.984090079558, 3194.7284480819158, 3200.3439294944483, 3294.407006738786, 3297.3899828531557, 444179.0, 452688.0, 453581.9999999967, 454153.0, 454169.0, 54762.0, 54979.0, 55013.0, 55085.449145508835, 55087.0, 56829.71585142048, 57073.0, 57124.0, 57264.713676432184, 57356.0, 14842.332095055748, 15328.156951367559, 15337.386504539694, 15355.309011094527, 15359.0, 9175.128362805523, 10295.021972912562, 10899.1280697544, 11262.718432039264, 12072.517099316105, 15107.39570417171, 15116.168610726296, 15155.545333640008, 15223.771643425358, 15378.769318460232, 5373.0, 5400.378956420062, 6046.27444706638, 6541.182352205974, 6553.574017688835, 824.4805772363372, 941.4116177389132, 952.3333666433524, 970.7670159161892, 972.6401231544373, 27661.03186388507, 27890.163295101178, 28510.71489285122, 28546.71453285482, 28688.13935581936, 1005.261133067195, 1050.3855244681795, 1053.9367637941748, 1066.4880857188593, 1090.3566895531674, 12032.338221397733, 12669.430990626895, 13557.932210338886, 14691.412343506354, 15206.543803685907, 607327.0, 607670.0, 607956.0, 609457.0, 612256.0, 52273.73863130736, 52397.214041789404, 52423.21365179525, 52538.21192682113, 52639.0, 1329.0563699773077, 1340.215973655403, 1349.8785109340206, 1354.3837553912947, 1372.9588112356646, 27653.0, 28439.146825595264, 28806.567901481074, 28902.855485722437, 29156.854215728785, 45743.62769116799, 45942.540574595165, 46055.53944460512, 46148.30777538407, 46340.768296158305, 552000.0, 555517.0, 555811.0, 556756.0, 557739.0, 31642.208944776565, 32634.020979370656, 36609.81695091614, 37962.0509487265, 38017.2396552073, 42267.7886610565, 43092.78453607712, 43117.3532397008, 44935.77532112318, 45015.0, 35261.0, 35512.46731298981, 35861.64138358687, 35987.46018809667, 36776.0, 30337.938172163867, 31232.531512026882, 35884.92345229543, 36326.09184770402, 37081.88754337374, 34898.0, 34978.825105874304, 35765.0, 35895.461568075974, 36336.0, 31339.843300783352, 32410.675893240765, 32468.350632986734, 32497.675023249463, 32546.0, 20499.38501844947, 23818.88090559536, 24237.15169969113, 24747.87626061858, 25739.0], "value": 19142.910664362727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.49995507053933, 332.36352385182374, 379.32290860813447, 388.18674876134526, 400.82163437270407, 18758.718619220705, 19639.11623976921, 19917.701234481458, 20438.69341959872, 20663.0, 19294.807051929478, 19694.704579431283, 19790.40628781135, 20249.0, 20320.491987700316, 4036.656884164835, 5560.499555040035, 5821.0, 5832.270966129234, 5854.0, 6263.060540918876, 6463.644499552523, 6463.7091330890225, 6489.415952564268, 6492.350764923517, 6176.227971503562, 6332.0, 6430.067640192169, 6432.45324147448, 6455.354464553554, 5352.545033672142, 6417.486601071903, 6423.967880160616, 6458.354164583528, 6473.482121430275, 4252.574742525753, 4936.555709986083, 5378.623496355257, 5410.945890541084, 5859.619124756877, 24073.63889541658, 24150.79246037691, 24520.0, 24712.629310560354, 24864.627030594555, 2177.157317334905, 2250.189931624614, 2267.5691618592577, 2349.9765002349927, 3008.0524634740204, 14808.967006777368, 14848.851511484963, 15126.319315630823, 15469.5359139226, 15474.0, 1764.9647007059916, 1862.37610400516, 1865.580244444989, 1959.1086055844557, 1959.9024546254, 116721.0, 119364.0, 123150.0, 123914.38042809819, 124435.0, 9109.863352049724, 10508.474576271155, 10630.73423164427, 10635.20235982289, 10968.341899486057, 13458.394372253271, 13509.864901351057, 13524.864751352361, 13532.86467135316, 13573.52492662752, 8361.916380836114, 8409.0, 8470.49177049379, 8546.145385461528, 8675.0, 7075.469339799535, 8413.540495078065, 8509.914900851036, 8511.233988941025, 8641.56792160402, 5066.873328166826, 6343.111964324999, 6537.771178008747, 6693.799186024427, 6701.932980670228, 1554.331637395908, 1608.4290077022722, 1816.9000704961354, 1826.8081851405677, 1855.7587513623143, 859.6002858670748, 921.3918813583138, 922.7877588154762, 934.5560858592114, 1030.1964467715195, 20562.69155962662, 20890.582188356148, 21620.78379216219, 21622.567548648938, 21783.782162178493, 902.977425564353, 936.1995493852794, 943.3302355327656, 961.9086186812242, 966.1449617088927, 11276.774464510663, 11535.71160720972, 11587.304761714324, 11605.6518304451, 11678.474468648932, 564042.0, 570510.0, 574214.0, 585492.0, 586022.0, 113614.80312758971, 116191.2571311431, 118820.0, 130320.0, 139468.0, 955.5126885288553, 986.1814693804125, 1014.8224060789332, 1015.0458568945315, 1017.9592816287413, 18805.24779008879, 18997.90501047486, 19076.33232836844, 19128.426147215607, 19138.0, 54821.45178548164, 54842.0, 56016.0, 56115.0, 57036.85926281534, 79889.0, 81723.0, 81944.0, 82329.0, 84966.87582810479, 6594.8351291218105, 6958.686859091252, 6961.8955715665315, 7405.740799072007, 8075.676972921017, 8786.472811631322, 10353.4305613192, 11989.880101198876, 12800.61598152057, 13223.3388330585, 9320.440773553608, 9799.481080432544, 10710.0, 11148.665540033811, 11394.487248074023, 9356.859647105162, 9915.206783457163, 10484.528196231035, 10952.726181845517, 11184.496697648463, 10958.561657533928, 11132.38771867555, 11147.777044459228, 11152.330860148417, 11597.362145082101, 6071.574989750675, 7507.887381689388, 7581.6209189541405, 7610.9238907610215, 7708.535378278109, 10656.680299591024, 11044.33733975964, 11065.612703555336, 11214.7757044857, 11302.4913878874], "value": 9614.936740760646, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.563982985189, 1457.548160070378, 1491.962700932477, 1743.3026789284293, 1792.7221280701497, 7485.73799917003, 7722.07335119785, 8048.517088974659, 8472.364572657045, 8498.6175622097, 8027.19728027197, 8282.171782821715, 8300.460470069456, 8428.873566896502, 8453.281471074966, 2263.10607310112, 2316.9652455213186, 2316.976830231693, 2326.604477238865, 2330.7669233076726, 2315.780000899912, 2328.719204437561, 2362.2204672458133, 2366.420227044368, 2366.479374537599, 2298.8620682759006, 2303.0787684925995, 2323.9186628468005, 2325.244295603927, 2330.1960823515947, 2288.000158819967, 2288.0275882749847, 2307.457747429361, 2308.203669733939, 2323.2449453927456, 2267.6598510223434, 2284.4517315844246, 2288.2448791898632, 2291.140822191678, 2292.7821856923565, 7948.324392426651, 8030.879536806952, 8033.196680331978, 8380.916190838074, 8490.0, 5057.393112826484, 5198.818041368534, 5202.765875535572, 5207.1668533034535, 5214.60890433219, 3173.5239714043078, 3246.4481038223557, 3501.439769636846, 3504.667056629616, 3510.613832478451, 999.8900120986685, 1029.3566520924421, 1041.8958104189596, 1055.5619417941546, 1055.941923194224, 10934.0, 10966.670999870017, 11023.338599683964, 11104.722381940355, 11106.944465277622, 4470.485894122153, 4612.215923293048, 4715.104130215248, 4727.527247275245, 4729.858104256878, 7987.720429784929, 9457.716268511957, 9466.337356384987, 9476.0, 9483.620655173716, 5137.383513978347, 5190.662606930538, 5201.219817027476, 5211.114110601206, 5220.086484865134, 3915.7846318452757, 4216.030313028021, 4233.97883010583, 4751.691140075884, 4765.547273009059, 3755.7183211258716, 3762.811859407019, 3842.269968705937, 3891.0, 3900.3369427197445, 1030.7474668706066, 1260.420206704926, 1263.122130119558, 1271.4786937355639, 1274.5921305182253, 716.5199316457986, 886.6807949138305, 889.6352495476953, 892.9866052009226, 900.8648702694649, 5546.611737178358, 5632.577556683265, 5665.801696940587, 5721.742521586539, 5823.854403639943, 941.0, 944.2823454174877, 976.2873102635116, 987.3088837813416, 1013.5439052426437, 3742.700583953277, 3772.679322257639, 3776.0937375029794, 3839.9232015359535, 3862.4206369044305, 9996.0, 10189.337693049929, 10325.690229293134, 10328.431936243429, 10365.689029329133, 10250.692479225487, 10273.537690803932, 10276.486175691185, 10286.022827831344, 10319.432431216355, 6128.866159760459, 6449.710057988421, 7593.354564862048, 7827.0, 8002.319802816709, 4476.373307736984, 4922.92615610759, 5473.8357849264585, 5531.0, 5607.962526932367, 9430.43417394931, 9489.76275593088, 9505.95247023788, 9522.904770952202, 9588.472634004918, 9497.097775711296, 9579.952100239454, 9640.228781697588, 9640.325177237526, 9737.22102231834, 6261.186045814015, 6496.740130394731, 6654.0, 6712.83217919556, 6866.038754574263, 7877.172896845748, 8079.1516890725625, 8085.39359548048, 8092.838143236984, 8097.878531822145, 7044.682989265598, 7105.50261481712, 7139.678714457966, 7193.532420392867, 7298.781036568911, 6333.398327158912, 6877.449804015767, 7002.704499667578, 7029.121359830021, 7102.538335008208, 6651.16860392451, 6681.365270299314, 6749.932500675127, 6793.898091528532, 6802.795916122524, 4788.04239152164, 4823.807047718052, 4831.710097394168, 4858.174110401311, 4922.778474968563, 4655.301704744315, 4666.510016448224, 4763.857084287477, 4826.0589185108565, 4954.207326827835], "value": 4392.4664821554015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [231.54270316125653, 298.8431073686314, 303.7342325465219, 305.67598345753504, 308.64968261023733, 4733.124371991175, 5086.880886205038, 5505.073224371473, 5530.308711411074, 5591.3569939456975, 5269.446708095652, 5423.511883930455, 5435.402105768362, 5460.781568737246, 5473.644213126154, 3452.361313157068, 4214.136102099063, 4214.831406743727, 4223.0, 4223.049813791903, 3609.3864043112603, 4024.2152780207725, 4230.280852255109, 4238.618524332809, 4243.872683819491, 3546.350946809733, 4144.129732756125, 4149.564295748948, 4155.31437312843, 4165.604267594574, 3813.2649644411863, 3948.7630742155275, 4139.56534563871, 4146.072076484419, 4172.603602657743, 2806.214260007201, 2988.5965394671657, 3608.0, 3628.727845411605, 3681.834317455721, 5138.537531622134, 5289.497497737709, 5316.255724198615, 5335.586069691535, 5341.985022845647, 4278.187144442546, 4458.219811532968, 4723.669343145981, 4734.242521196592, 4742.928856067162, 4471.910561788779, 4756.595689366408, 5101.984716037145, 5741.684207368593, 5782.45066718661, 3168.825714585697, 3653.3971894637352, 3823.9808800955816, 3952.8616498422703, 4000.879973600797, 5206.37523497179, 5822.796202132948, 6023.126646636213, 6080.0, 6088.529538163204, 3598.3882739934274, 3630.565926459034, 3900.4344370066183, 4037.5760545142925, 4092.8158232878995, 3768.397056471006, 3883.126296583246, 4126.236646220459, 4132.586741325843, 4193.094079926494, 4272.636825869836, 4426.513083560806, 4512.427916087799, 4601.700889442175, 4769.642276829251, 4849.642100211946, 5623.775048997994, 5874.650139944057, 5884.176215329857, 5890.970545147246, 1132.841402203692, 1245.2528482910182, 1247.7691627049026, 1307.2613973105356, 1315.1911574381686, 713.7180813578607, 879.3932186791094, 894.2398960883197, 900.1988230474883, 901.5131828812367, 4826.638002149853, 4922.458529561745, 5056.0, 5261.76322065508, 5295.099833028395, 4823.493533178966, 5337.879045400509, 5401.621886467988, 5501.779928802803, 5815.41845815415, 4734.715917044989, 4959.578435832995, 5186.740662966837, 5239.790408383698, 5248.475152484721, 2574.9742502574736, 2877.2950627096075, 2892.2191008428026, 2995.221242476972, 3011.2170835582906, 4876.878078048077, 5200.7919683213, 5211.061431373882, 5729.054705973468, 5731.254936858182, 5243.0, 5537.418571050062, 5840.970795145997, 5842.620229685057, 5856.45684360964, 5300.92048619271, 5389.7035663038905, 5515.227868098389, 5689.573282003784, 5791.0, 5668.829935101953, 6028.186194863637, 6081.726322315595, 6090.390960903874, 6142.754289828356, 6018.51851851842, 6020.849478763066, 6021.819345419469, 6032.853757786007, 6049.667268300285, 4512.481064677604, 4695.671303008757, 6022.193341997471, 6102.4812890902995, 6158.983767678283, 3355.3558756208795, 3425.2635683328203, 3441.4513468938576, 3664.5602527696847, 4015.738976966488, 5247.239150323258, 5280.471952804689, 5546.696526316393, 6089.421504957022, 6103.108036508696, 4848.733319667452, 5660.801871934463, 5775.249217601685, 5783.971080144573, 5906.645601263938, 5818.8254352369495, 5866.090755932837, 5875.6474611523445, 5894.174815525744, 5934.7922822701, 4430.911381772411, 5514.944850551443, 5656.85857853557, 5753.338366087954, 5795.247896724645, 4977.850664480072, 5827.650340979385, 5864.530837533072, 5877.265341832271, 5909.4293982117915, 4816.4220293564995, 5379.838604841861, 5587.8882422352135, 5643.0, 5762.567807414544], "value": 4112.30508776405, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.7679620142446, 709.4147328454027, 718.3642476408379, 726.8437285983513, 727.2400341642982, 14533.418663253471, 15310.38758449662, 15496.535103946871, 16627.168641567903, 17202.483925482255, 15208.543743687707, 16015.35938562459, 16290.918545407256, 16292.26684799184, 16315.755263671024, 806.5160903457922, 838.9177960431067, 840.604915689628, 840.8318336332727, 845.2519520224585, 645.8805121052596, 673.7237732529663, 783.1776634533745, 810.8013536683521, 828.5981299069946, 862.6937437209795, 867.0635713429513, 880.6609455359701, 882.272125496468, 883.571467838098, 867.8784970104191, 873.9563021848882, 874.1302404107921, 874.7069731639908, 876.1720174435161, 649.8505343770912, 649.8895187818082, 650.2554575011585, 652.5301782716436, 655.0665301944757, 15006.099634021994, 15344.386224551004, 15346.923265383713, 15499.767503487457, 15542.844571554253, 13892.93053534726, 13910.51313204043, 13925.164490130523, 14226.573202803933, 14283.78574321386, 11850.822237666442, 11974.0, 12235.449404776806, 12246.387680615933, 12415.193012454161, 3633.7819730816236, 3821.445890345912, 3834.3098242316373, 3851.5378154621626, 3866.4586957825927, 110205.0, 116426.0, 117087.82912170855, 117830.23254651188, 121251.3937430316, 9771.0, 10257.230707696952, 10356.275060745675, 10541.31481453703, 10693.358398496115, 21424.78575214259, 21430.678539821914, 21448.78551214499, 21458.892705536684, 21463.46341341479, 7557.35762460197, 7596.164421913585, 7656.80857978555, 7736.419768517384, 7768.262015108556, 3390.694837464591, 3659.3779057560278, 3989.960100399017, 4143.943314358519, 4438.822447102079, 2934.6918573549897, 3413.2490852012515, 3487.773294735834, 3523.3657941570764, 3525.1715846775987, 602.6625089949555, 622.107276058857, 627.3193584960278, 635.8887194740994, 641.112059797175, 564.8954943335497, 570.3061906138761, 607.9785959587934, 634.403660559073, 647.0972992675191, 7928.197456993268, 8247.463914845515, 8676.522791246542, 8736.519491427905, 9169.403988740843, 460.8225833054231, 578.6932925549465, 587.1281147495965, 621.3258614403333, 652.2987788127782, 4283.357496375568, 4284.421603083544, 4285.421468101828, 4333.285007973712, 4406.867793966187, 78636.60681696555, 106139.0, 107622.0, 108307.45846270719, 109261.0, 93205.53397232971, 94862.05137948532, 95499.52250238704, 95933.12200633992, 96621.51689241649, 78893.0, 79242.0, 79796.20203798004, 80321.59839200767, 81879.18120818716, 7799.961000194962, 8439.873401898854, 8487.023992240971, 8567.614457349559, 8638.740837774876, 43618.90952726207, 53072.46927530675, 54201.0, 54417.45582544124, 54618.45381546134, 61480.69259653673, 67222.66388668025, 74680.62659686667, 77247.61376193083, 79165.20834791579, 9152.725418237464, 9716.651379558189, 10307.690769276935, 10588.947055264674, 10816.4050977197, 11932.463039163085, 12068.818967715315, 12244.877551224374, 12369.196002259825, 12440.813387799371, 9455.763605909908, 11710.00464960488, 11819.172657913863, 12054.51781928713, 12109.970652494285, 10084.394936303845, 10898.945505272422, 11639.64682994955, 11714.414279286171, 12285.078619103782, 11235.943820280847, 11472.713182170513, 11490.540378385105, 11528.250663707167, 11970.22193557416, 9621.90378096238, 9625.663101791404, 9703.466309353053, 9747.463889486145, 10061.798764024825, 8419.789505262417, 8953.59708813118, 9653.276004299845, 9839.360441571274, 9983.800323993624], "value": 7118.624754246968, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1680.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 174.0, 123.0, 136.0, 173.0, 160.0, 118.0, 116.0, 117.0, 117.0], "value": 134.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 148.0, 119.0, 152.0, 117.0, 119.0, 119.0, 137.0, 168.0, 117.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 212.0, 222.0, 229.0, 258.0, 276.0, 199.0, 274.0, 271.0, 196.0], "value": 232.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 90.0, 84.0, 85.0, 68.0, 59.0, 73.0, 60.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 82.0, 73.0, 57.0, 86.0, 102.0, 60.0, 77.0, 113.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 122.0, 137.0, 132.0, 136.0, 87.0, 100.0, 80.0, 129.0, 85.0], "value": 111.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 151.0, 104.0, 152.0, 100.0, 153.0, 152.0, 106.0, 97.0, 90.0], "value": 122.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 148.0, 146.0, 82.0, 145.0, 152.0, 108.0, 115.0, 145.0, 150.0], "value": 128.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 59.0, 58.0, 60.0, 57.0, 57.0, 58.0, 57.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 96.0, 82.0, 75.0, 71.0, 73.0, 73.0, 73.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 109.0, 110.0, 88.0, 89.0, 76.0, 109.0, 113.0, 81.0, 103.0], "value": 97.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 152.0, 146.0, 150.0, 142.0, 212.0, 168.0, 157.0, 220.0, 179.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 175.0, 172.0, 177.0, 175.0, 184.0, 179.0, 175.0, 178.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 94.0, 88.0, 72.0, 72.0, 69.0, 71.0, 70.0, 70.0, 73.0], "value": 74.8, "unit": "score"}]}]} 20:44:00 INFO - Return code: 0 20:44:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:44:00 ERROR - # TBPL SUCCESS # 20:44:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:44:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:44:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:44:00 INFO - 2016-12-04 20:44:00.104 screenresolution[1666:10898] starting screenresolution argv=screenresolution get 20:44:00 INFO - 2016-12-04 20:44:00.107 screenresolution[1666:10898] Display 0: 1600x1200x32@60 20:44:00 INFO - 2016-12-04 20:44:00.113 screenresolution[1667:10901] starting screenresolution argv=screenresolution list 20:44:00 INFO - Available Modes on Display 0 20:44:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:44:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:44:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:44:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:44:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:44:00 INFO - Intel Iris: 20:44:00 INFO - Chipset Model: Intel Iris 20:44:00 INFO - Type: GPU 20:44:00 INFO - Bus: Built-In 20:44:00 INFO - VRAM (Dynamic, Max): 1536 MB 20:44:00 INFO - Vendor: Intel (0x8086) 20:44:00 INFO - Device ID: 0x0a2e 20:44:00 INFO - Revision ID: 0x0009 20:44:00 INFO - Displays: 20:44:00 INFO - Display: 20:44:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:44:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:44:00 INFO - Display Serial Number: 1600x1200 60 20:44:00 INFO - Main Display: Yes 20:44:00 INFO - Mirror: Off 20:44:00 INFO - Online: Yes 20:44:00 INFO - Rotation: Supported 20:44:00 INFO - Return code: 0 20:44:00 INFO - Running post-action listener: _resource_record_post_action 20:44:00 INFO - [mozharness: 2016-12-05 04:44:00.347069Z] Finished run-tests step (success) 20:44:00 INFO - Running post-run listener: _resource_record_post_run 20:44:00 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 39946752; Write bytes: 699299840; Read time: 386; Write time: 2326 20:44:00 INFO - TinderboxPrint: CPU usage
54.5% 20:44:00 INFO - TinderboxPrint: I/O read bytes / time
39,946,752 / 386 20:44:00 INFO - TinderboxPrint: I/O write bytes / time
699,299,840 / 2,326 20:44:00 INFO - TinderboxPrint: CPU idle
2,209.2 (45.5%) 20:44:00 INFO - TinderboxPrint: CPU system
983.4 (20.2%) 20:44:00 INFO - TinderboxPrint: CPU user
1,665.2 (34.3%) 20:44:00 INFO - TinderboxPrint: Swap in / out
424,038,400 / 0 20:44:00 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 372388352; Write bytes: 369809408; Read time: 27780; Write time: 952 20:44:00 INFO - run-tests - Wall time: 1183s; CPU: 55.0%; Read bytes: 33686016; Write bytes: 311013376; Read time: 306; Write time: 1335 20:44:00 INFO - Running post-run listener: _upload_blobber_files 20:44:00 INFO - Blob upload gear active. 20:44:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:44:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:44:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:44:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:44:01 INFO - (blobuploader) - INFO - Open directory for files ... 20:44:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:44:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:44:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:44:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:44:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:44:02 INFO - (blobuploader) - INFO - Done attempting. 20:44:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:44:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:44:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:44:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:44:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:44:03 INFO - (blobuploader) - INFO - Done attempting. 20:44:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:44:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:44:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:44:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:44:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:44:05 INFO - (blobuploader) - INFO - Done attempting. 20:44:05 INFO - (blobuploader) - INFO - Iteration through files over. 20:44:05 INFO - Return code: 0 20:44:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:44:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:44:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa420daf3084a28619f4dd887e4fc2b1c2d1375caa509c16ca9b4836956d0423f832ed6e86e715434270a749243cd9f3249edf569407ea642059aa33e935ab88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7eae84fb4ecbd53213b5406a02c0dd8d59c650e76869cc2f3a7714a087e4d5c8c113f5abb285b42ad9c54e0bc7893a0c094ea035bbc2002a959b122eea5e11c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2aeffb38218f21a6c5459570d21889e8e32934df1ec800007e0dcd216c452f18de8f6e6e18765d82a6120a12fdb1d25e611e00d90f43fbba837c23d7682a6060"} 20:44:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:44:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:44:05 INFO - Contents: 20:44:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa420daf3084a28619f4dd887e4fc2b1c2d1375caa509c16ca9b4836956d0423f832ed6e86e715434270a749243cd9f3249edf569407ea642059aa33e935ab88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7eae84fb4ecbd53213b5406a02c0dd8d59c650e76869cc2f3a7714a087e4d5c8c113f5abb285b42ad9c54e0bc7893a0c094ea035bbc2002a959b122eea5e11c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2aeffb38218f21a6c5459570d21889e8e32934df1ec800007e0dcd216c452f18de8f6e6e18765d82a6120a12fdb1d25e611e00d90f43fbba837c23d7682a6060"} 20:44:05 INFO - Running post-run listener: copy_logs_to_upload_dir 20:44:05 INFO - Copying logs to upload dir... 20:44:05 INFO - mkdir: /builds/slave/test/build/upload/logs 20:44:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.614532 ========= master_lag: 2.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-04 20:44:07.883751) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 20:44:07.887508) ========= 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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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/Firefox/sha512/fa420daf3084a28619f4dd887e4fc2b1c2d1375caa509c16ca9b4836956d0423f832ed6e86e715434270a749243cd9f3249edf569407ea642059aa33e935ab88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7eae84fb4ecbd53213b5406a02c0dd8d59c650e76869cc2f3a7714a087e4d5c8c113f5abb285b42ad9c54e0bc7893a0c094ea035bbc2002a959b122eea5e11c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2aeffb38218f21a6c5459570d21889e8e32934df1ec800007e0dcd216c452f18de8f6e6e18765d82a6120a12fdb1d25e611e00d90f43fbba837c23d7682a6060"} build_url:https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011203 build_url: 'https://queue.taskcluster.net/v1/task/P0ujL2f0R8COiDtvkjDUUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa420daf3084a28619f4dd887e4fc2b1c2d1375caa509c16ca9b4836956d0423f832ed6e86e715434270a749243cd9f3249edf569407ea642059aa33e935ab88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7eae84fb4ecbd53213b5406a02c0dd8d59c650e76869cc2f3a7714a087e4d5c8c113f5abb285b42ad9c54e0bc7893a0c094ea035bbc2002a959b122eea5e11c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2aeffb38218f21a6c5459570d21889e8e32934df1ec800007e0dcd216c452f18de8f6e6e18765d82a6120a12fdb1d25e611e00d90f43fbba837c23d7682a6060"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 20:44:07.921876) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 20:44:07.922200) ========= 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.OwVjCS9sGS/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.fuaeNF7QEr/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.005148 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 20:44:07.975749) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 20:44:07.976061) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 20:44:07.976391) ========= ========= Total master_lag: 2.43 =========