builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0328 starttime: 1482421847.63 results: success (0) buildid: 20161222063953 builduid: 02d59cf721b941f48b218bb02044a76d revision: 05d4c2e9bb1ee731b2d821a56cb3c4cdf1114b63 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.632796) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.633238) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.633566) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.699177) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.699462) ========= 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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-22 07:50:47-- 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% 187M=0s 2016-12-22 07:50:47 (187 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158413 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.884007) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.884438) ========= 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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.025244 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.937176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:47.937866) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 05d4c2e9bb1ee731b2d821a56cb3c4cdf1114b63 --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 05d4c2e9bb1ee731b2d821a56cb3c4cdf1114b63 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vA9QZQ6LWg/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.cDUmDsFU89/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-22 07:50:48,044 truncating revision to first 12 chars 2016-12-22 07:50:48,044 Setting DEBUG logging. 2016-12-22 07:50:48,044 attempt 1/10 2016-12-22 07:50:48,044 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/05d4c2e9bb1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 07:50:48,670 unpacking tar archive at: mozilla-central-05d4c2e9bb1e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.902347 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:48.868413) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:48.868782) ========= script_repo_revision: 05d4c2e9bb1ee731b2d821a56cb3c4cdf1114b63 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:48.869243) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:48.869536) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 07:50:48.894094) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-22 07:50:48.894476) ========= /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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:50:49 INFO - MultiFileLogger online at 20161222 07:50:49 in /builds/slave/test 07:50:49 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 07:50:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:50:49 INFO - {'append_to_log': False, 07:50:49 INFO - 'base_work_dir': '/builds/slave/test', 07:50:49 INFO - 'blob_upload_branch': 'Firefox', 07:50:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:50:49 INFO - 'branch': 'Firefox', 07:50:49 INFO - 'buildbot_json_path': 'buildprops.json', 07:50:49 INFO - 'config_files': ('talos/mac_config.py',), 07:50:49 INFO - 'default_actions': ('clobber', 07:50:49 INFO - 'read-buildbot-config', 07:50:49 INFO - 'download-and-extract', 07:50:49 INFO - 'populate-webroot', 07:50:49 INFO - 'create-virtualenv', 07:50:49 INFO - 'install', 07:50:49 INFO - 'run-tests'), 07:50:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:50:49 INFO - 'download_minidump_stackwalk': True, 07:50:49 INFO - 'download_symbols': 'ondemand', 07:50:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:50:49 INFO - 'tooltool.py': '/tools/tooltool.py', 07:50:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:50:49 INFO - '/tools/misc-python/virtualenv.py')}, 07:50:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:50:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:50:49 INFO - 'installer_path': 'installer.exe', 07:50:49 INFO - 'log_level': 'info', 07:50:49 INFO - 'log_name': 'talos', 07:50:49 INFO - 'log_to_console': True, 07:50:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:50:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:50:49 INFO - 'opt_config_files': (), 07:50:49 INFO - 'pip_index': False, 07:50:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:50:49 INFO - 'cmd': ('bash', 07:50:49 INFO - '-c', 07:50:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:50:49 INFO - 'enabled': True, 07:50:49 INFO - 'halt_on_failure': False, 07:50:49 INFO - 'name': 'check_screen_resolution'},), 07:50:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:50:49 INFO - 'cmd': ('bash', 07:50:49 INFO - '-c', 07:50:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:50:49 INFO - 'enabled': True, 07:50:49 INFO - 'halt_on_failure': False, 07:50:49 INFO - 'name': 'check_screen_resolution'},), 07:50:49 INFO - 'run_cmd_checks_enabled': True, 07:50:49 INFO - 'sps_profile': False, 07:50:49 INFO - 'sps_profile_interval': 0, 07:50:49 INFO - 'suite': 'dromaeojs-e10s', 07:50:49 INFO - 'system_bits': '32', 07:50:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:50:49 INFO - 'title': 't-yosemite-r7-0328', 07:50:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:50:49 INFO - 'use_talos_json': True, 07:50:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:50:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:50:49 INFO - 'work_dir': 'build'} 07:50:49 INFO - [mozharness: 2016-12-22 15:50:49.105831Z] Running clobber step. 07:50:49 INFO - Running pre-action listener: _resource_record_pre_action 07:50:49 INFO - Running main action method: clobber 07:50:49 INFO - rmtree: /builds/slave/test/build 07:50:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:50:51 INFO - Running post-action listener: _resource_record_post_action 07:50:51 INFO - [mozharness: 2016-12-22 15:50:51.481564Z] Finished clobber step (success) 07:50:51 INFO - [mozharness: 2016-12-22 15:50:51.481734Z] Running read-buildbot-config step. 07:50:51 INFO - Running pre-action listener: _resource_record_pre_action 07:50:51 INFO - Running main action method: read_buildbot_config 07:50:51 INFO - Using buildbot properties: 07:50:51 INFO - { 07:50:51 INFO - "project": "", 07:50:51 INFO - "product": "firefox", 07:50:51 INFO - "script_repo_revision": "production", 07:50:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:50:51 INFO - "repository": "", 07:50:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:50:51 INFO - "buildid": "20161222063953", 07:50:51 INFO - "pgo_build": "False", 07:50:51 INFO - "basedir": "/builds/slave/test", 07:50:51 INFO - "buildnumber": 15, 07:50:51 INFO - "slavename": "t-yosemite-r7-0328", 07:50:51 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:50:51 INFO - "platform": "yosemite_r7", 07:50:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:50:51 INFO - "branch": "mozilla-central", 07:50:51 INFO - "repo_path": "mozilla-central", 07:50:51 INFO - "revision": "05d4c2e9bb1ee731b2d821a56cb3c4cdf1114b63", 07:50:51 INFO - "stage_platform": "macosx64", 07:50:51 INFO - "builduid": "02d59cf721b941f48b218bb02044a76d", 07:50:51 INFO - "slavebuilddir": "test" 07:50:51 INFO - } 07:50:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:50:51 INFO - Running post-action listener: _resource_record_post_action 07:50:51 INFO - [mozharness: 2016-12-22 15:50:51.485070Z] Finished read-buildbot-config step (success) 07:50:51 INFO - [mozharness: 2016-12-22 15:50:51.485277Z] Running download-and-extract step. 07:50:51 INFO - Running pre-action listener: _resource_record_pre_action 07:50:51 INFO - Running main action method: download_and_extract 07:50:51 INFO - mkdir: /builds/slave/test/build/tests 07:50:51 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')]} 07:50:51 INFO - trying https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:50:51 INFO - Downloading https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/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 07:50:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/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 07:50:52 INFO - Downloaded 1199 bytes. 07:50:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:50:52 INFO - Using the following test package requirements: 07:50:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:50:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:50:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:50:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'jsshell-mac.zip'], 07:50:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:50:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:50:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:50:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:50:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:50:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:50:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:50:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:50:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:50:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:50:52 INFO - Fetch https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:50:54 INFO - Expected file size: 16289185 07:50:54 INFO - Obtained file size: 16289185 07:51:00 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 07:51:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:51:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:51:00 INFO - Fetch https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:51:01 INFO - Expected file size: 16289185 07:51:01 INFO - Obtained file size: 16289185 07:51:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:51:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:51:07 INFO - Fetch https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:51:09 INFO - Expected file size: 14409734 07:51:09 INFO - Obtained file size: 14409734 07:51:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:10 INFO - trying https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:51:10 INFO - Downloading https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:51:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/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 07:51:13 INFO - Downloaded 69666064 bytes. 07:51:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:51:13 INFO - mkdir: /builds/slave/test/properties 07:51:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:51:13 INFO - Writing to file /builds/slave/test/properties/build_url 07:51:13 INFO - Contents: 07:51:13 INFO - build_url:https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:51:14 INFO - Running post-action listener: _resource_record_post_action 07:51:14 INFO - Running post-action listener: set_extra_try_arguments 07:51:14 INFO - [mozharness: 2016-12-22 15:51:14.602378Z] Finished download-and-extract step (success) 07:51:14 INFO - [mozharness: 2016-12-22 15:51:14.602515Z] Running populate-webroot step. 07:51:14 INFO - Running pre-action listener: _resource_record_pre_action 07:51:14 INFO - Running main action method: populate_webroot 07:51:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:51:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:51:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:51:14 INFO - '--tppagecycles', 07:51:14 INFO - '7'], 07:51:14 INFO - 'tests': ['tsvgm']}}, 07:51:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:51:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:51:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:51:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:51:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:51:14 INFO - 'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:51:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:51:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:51:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:51:14 INFO - 'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['damp', 'tps']}, 07:51:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:51:14 INFO - 'tests': ['damp', 'tps']}, 07:51:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['dromaeo_dom']}, 07:51:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:51:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['basic_compositor_video']}, 07:51:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:51:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['a11yr', 07:51:14 INFO - 'ts_paint', 07:51:14 INFO - 'tpaint', 07:51:14 INFO - 'sessionrestore', 07:51:14 INFO - 'sessionrestore_no_auto_restore', 07:51:14 INFO - 'tabpaint']}, 07:51:14 INFO - 'other-e10s': {'tests': ['a11yr', 07:51:14 INFO - 'ts_paint', 07:51:14 INFO - 'tpaint', 07:51:14 INFO - 'sessionrestore', 07:51:14 INFO - 'sessionrestore_no_auto_restore', 07:51:14 INFO - 'tabpaint']}, 07:51:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:51:14 INFO - 'ts_paint', 07:51:14 INFO - 'tpaint', 07:51:14 INFO - 'sessionrestore', 07:51:14 INFO - 'sessionrestore_no_auto_restore', 07:51:14 INFO - 'tabpaint']}, 07:51:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:51:14 INFO - 'ts_paint', 07:51:14 INFO - 'tpaint', 07:51:14 INFO - 'sessionrestore', 07:51:14 INFO - 'sessionrestore_no_auto_restore', 07:51:14 INFO - 'tabpaint']}, 07:51:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['a11yr', 07:51:14 INFO - 'ts_paint', 07:51:14 INFO - 'tpaint', 07:51:14 INFO - 'sessionrestore', 07:51:14 INFO - 'sessionrestore_no_auto_restore', 07:51:14 INFO - 'tabpaint']}, 07:51:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['a11yr', 07:51:14 INFO - 'ts_paint', 07:51:14 INFO - 'tpaint', 07:51:14 INFO - 'sessionrestore', 07:51:14 INFO - 'sessionrestore_no_auto_restore', 07:51:14 INFO - 'tabpaint']}, 07:51:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['tsvgx', 07:51:14 INFO - 'tsvgr_opacity', 07:51:14 INFO - 'tart', 07:51:14 INFO - 'tscrollx', 07:51:14 INFO - 'cart', 07:51:14 INFO - 'tsvg_static']}, 07:51:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:51:14 INFO - 'tsvgr_opacity', 07:51:14 INFO - 'tart', 07:51:14 INFO - 'tscrollx', 07:51:14 INFO - 'cart', 07:51:14 INFO - 'tsvg_static']}, 07:51:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:51:14 INFO - 'talos_options': ['--disable-e10s'], 07:51:14 INFO - 'tests': ['tp5o']}, 07:51:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:51:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:51:14 INFO - 'talos_options': ['--disable-e10s', 07:51:14 INFO - '--xperf_path', 07:51:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:51:14 INFO - 'tests': ['tp5n']}, 07:51:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:51:14 INFO - 'talos_options': ['--xperf_path', 07:51:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:51:14 INFO - 'tests': ['tp5n']}}, 07:51:14 INFO - 'talos.zip': {'path': '', 07:51:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:51:14 INFO - Running post-action listener: _resource_record_post_action 07:51:14 INFO - [mozharness: 2016-12-22 15:51:14.620650Z] Finished populate-webroot step (success) 07:51:14 INFO - [mozharness: 2016-12-22 15:51:14.620782Z] Running create-virtualenv step. 07:51:14 INFO - Running pre-action listener: _resource_record_pre_action 07:51:14 INFO - Running main action method: create_virtualenv 07:51:14 INFO - Creating virtualenv /builds/slave/test/build/venv 07:51:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:51:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:51:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:51:14 INFO - Using real prefix '/tools/python27' 07:51:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:51:15 INFO - Installing distribute.............................................................................................................................................................................................done. 07:51:18 INFO - Installing pip.................done. 07:51:18 INFO - Return code: 0 07:51:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:51:18 INFO - Reading from file tmpfile_stdout 07:51:18 INFO - Output received: 07:51:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:51:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:51: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')]} 07:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51: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')]} 07:51:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:51:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:18 INFO - 'HOME': '/Users/cltbld', 07:51:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:18 INFO - 'LOGNAME': 'cltbld', 07:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:18 INFO - 'MOZ_NO_REMOTE': '1', 07:51:18 INFO - 'NO_EM_RESTART': '1', 07:51:18 INFO - 'PAGER': '/bin/cat', 07:51:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:18 INFO - 'PWD': '/builds/slave/test', 07:51:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:18 INFO - 'SHELL': '/bin/bash', 07:51:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:18 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:18 INFO - 'USER': 'cltbld', 07:51:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:18 INFO - 'XPC_FLAGS': '0x0', 07:51:18 INFO - 'XPC_SERVICE_NAME': '0', 07:51:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:18 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 07:51:18 INFO - Cleaning up... 07:51:18 INFO - Return code: 0 07:51:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:51: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')]} 07:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51: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')]} 07:51:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:51:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:18 INFO - 'HOME': '/Users/cltbld', 07:51:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:18 INFO - 'LOGNAME': 'cltbld', 07:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:18 INFO - 'MOZ_NO_REMOTE': '1', 07:51:18 INFO - 'NO_EM_RESTART': '1', 07:51:18 INFO - 'PAGER': '/bin/cat', 07:51:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:18 INFO - 'PWD': '/builds/slave/test', 07:51:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:18 INFO - 'SHELL': '/bin/bash', 07:51:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:18 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:18 INFO - 'USER': 'cltbld', 07:51:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:18 INFO - 'XPC_FLAGS': '0x0', 07:51:18 INFO - 'XPC_SERVICE_NAME': '0', 07:51:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:19 INFO - Downloading/unpacking psutil>=3.1.1 07:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:51:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:51:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:51:23 INFO - Installing collected packages: psutil 07:51:23 INFO - Running setup.py install for psutil 07:51:23 INFO - building 'psutil._psutil_osx' extension 07:51:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:51:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:51:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:51:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:51:24 INFO - building 'psutil._psutil_posix' extension 07:51:24 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 07:51:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:51:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:51:24 INFO - ^ 07:51:24 INFO - 1 warning generated. 07:51:24 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 07:51:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:51:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:51:24 INFO - Successfully installed psutil 07:51:24 INFO - Cleaning up... 07:51:24 INFO - Return code: 0 07:51:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:24 INFO - 'HOME': '/Users/cltbld', 07:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:24 INFO - 'LOGNAME': 'cltbld', 07:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:24 INFO - 'MOZ_NO_REMOTE': '1', 07:51:24 INFO - 'NO_EM_RESTART': '1', 07:51:24 INFO - 'PAGER': '/bin/cat', 07:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:24 INFO - 'PWD': '/builds/slave/test', 07:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:24 INFO - 'SHELL': '/bin/bash', 07:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:24 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:24 INFO - 'USER': 'cltbld', 07:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:24 INFO - 'XPC_FLAGS': '0x0', 07:51:24 INFO - 'XPC_SERVICE_NAME': '0', 07:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:51:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:51:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:51:27 INFO - Installing collected packages: mozsystemmonitor 07:51:27 INFO - Running setup.py install for mozsystemmonitor 07:51:27 INFO - Successfully installed mozsystemmonitor 07:51:27 INFO - Cleaning up... 07:51:27 INFO - Return code: 0 07:51:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:27 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 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:27 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 07:51:27 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 07:51:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:27 INFO - 'HOME': '/Users/cltbld', 07:51:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:27 INFO - 'LOGNAME': 'cltbld', 07:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:27 INFO - 'MOZ_NO_REMOTE': '1', 07:51:27 INFO - 'NO_EM_RESTART': '1', 07:51:27 INFO - 'PAGER': '/bin/cat', 07:51:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:27 INFO - 'PWD': '/builds/slave/test', 07:51:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:27 INFO - 'SHELL': '/bin/bash', 07:51:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:27 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:27 INFO - 'USER': 'cltbld', 07:51:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:27 INFO - 'XPC_FLAGS': '0x0', 07:51:27 INFO - 'XPC_SERVICE_NAME': '0', 07:51:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:27 INFO - Downloading/unpacking jsonschema==2.5.1 07:51:27 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 07:51:27 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 07:51:27 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 07:51:27 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 07:51:27 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 07:51:27 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 07:51:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:51:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:51:30 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 07:51:30 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 07:51:30 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 07:51:30 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 07:51:30 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 07:51:30 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 07:51:31 INFO - Downloading functools32-3.2.3-2.tar.gz 07:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:51:31 INFO - warning: no files found matching '*.txt' 07:51:31 INFO - no previously-included directories found matching 'build' 07:51:31 INFO - no previously-included directories found matching 'dist' 07:51:31 INFO - no previously-included directories found matching '.git*' 07:51:31 INFO - Installing collected packages: jsonschema, functools32 07:51:31 INFO - Running setup.py install for functools32 07:51:31 INFO - warning: no files found matching '*.txt' 07:51:31 INFO - no previously-included directories found matching 'build' 07:51:31 INFO - no previously-included directories found matching 'dist' 07:51:31 INFO - no previously-included directories found matching '.git*' 07:51:31 INFO - Successfully installed jsonschema functools32 07:51:31 INFO - Cleaning up... 07:51:31 INFO - Return code: 0 07:51:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:51:31 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')]} 07:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:31 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')]} 07:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:31 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 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:31 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 07:51:31 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 07:51:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:31 INFO - 'HOME': '/Users/cltbld', 07:51:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:31 INFO - 'LOGNAME': 'cltbld', 07:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:31 INFO - 'MOZ_NO_REMOTE': '1', 07:51:31 INFO - 'NO_EM_RESTART': '1', 07:51:31 INFO - 'PAGER': '/bin/cat', 07:51:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:31 INFO - 'PWD': '/builds/slave/test', 07:51:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:31 INFO - 'SHELL': '/bin/bash', 07:51:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:31 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:31 INFO - 'USER': 'cltbld', 07:51:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:31 INFO - 'XPC_FLAGS': '0x0', 07:51:31 INFO - 'XPC_SERVICE_NAME': '0', 07:51:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:51:31 INFO - Cleaning up... 07:51:31 INFO - Return code: 0 07:51:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:51:31 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')]} 07:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:31 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')]} 07:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:31 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 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:31 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 07:51:31 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 07:51:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:31 INFO - 'HOME': '/Users/cltbld', 07:51:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:31 INFO - 'LOGNAME': 'cltbld', 07:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:31 INFO - 'MOZ_NO_REMOTE': '1', 07:51:31 INFO - 'NO_EM_RESTART': '1', 07:51:31 INFO - 'PAGER': '/bin/cat', 07:51:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:31 INFO - 'PWD': '/builds/slave/test', 07:51:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:31 INFO - 'SHELL': '/bin/bash', 07:51:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:31 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:31 INFO - 'USER': 'cltbld', 07:51:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:31 INFO - 'XPC_FLAGS': '0x0', 07:51:31 INFO - 'XPC_SERVICE_NAME': '0', 07:51:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:31 INFO - Downloading/unpacking blobuploader==1.2.4 07:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:34 INFO - Downloading blobuploader-1.2.4.tar.gz 07:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:51:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:51:34 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 07:51:34 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 07:51:34 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 07:51:34 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 07:51:34 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 07:51:34 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 07:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:51:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:35 INFO - Downloading docopt-0.6.1.tar.gz 07:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:51:35 INFO - Installing collected packages: blobuploader, requests, docopt 07:51:35 INFO - Running setup.py install for blobuploader 07:51:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:51:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:51:36 INFO - Running setup.py install for requests 07:51:36 INFO - Running setup.py install for docopt 07:51:36 INFO - Successfully installed blobuploader requests docopt 07:51:36 INFO - Cleaning up... 07:51:36 INFO - Return code: 0 07:51:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:36 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 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:36 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 07:51:36 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 07:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:36 INFO - 'HOME': '/Users/cltbld', 07:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:36 INFO - 'LOGNAME': 'cltbld', 07:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:36 INFO - 'MOZ_NO_REMOTE': '1', 07:51:36 INFO - 'NO_EM_RESTART': '1', 07:51:36 INFO - 'PAGER': '/bin/cat', 07:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:36 INFO - 'PWD': '/builds/slave/test', 07:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:36 INFO - 'SHELL': '/bin/bash', 07:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:36 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:36 INFO - 'USER': 'cltbld', 07:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:36 INFO - 'XPC_FLAGS': '0x0', 07:51:36 INFO - 'XPC_SERVICE_NAME': '0', 07:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-qQ6T4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-JPnLxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-CWzbtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-0Xw34x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-VtA2k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-OxdIpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-ODV8zM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-xajROg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:51:37 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-BrLKKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-qTkEOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-kbuyt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-4tsptl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-TrVLdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-GEybB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-1O5WP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-zmq0FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:51:38 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-i96ntV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:51:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:51:38 INFO - Running setup.py install for manifestparser 07:51:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:51:39 INFO - Running setup.py install for mozcrash 07:51:39 INFO - Running setup.py install for mozdebug 07:51:39 INFO - Running setup.py install for mozdevice 07:51:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:51:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:51:39 INFO - Running setup.py install for mozfile 07:51:39 INFO - Running setup.py install for mozhttpd 07:51:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:51:39 INFO - Running setup.py install for mozinfo 07:51:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:51:39 INFO - Running setup.py install for mozInstall 07:51:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Running setup.py install for mozleak 07:51:40 INFO - Running setup.py install for mozlog 07:51:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Running setup.py install for moznetwork 07:51:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Running setup.py install for mozprocess 07:51:40 INFO - Running setup.py install for mozprofile 07:51:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:51:40 INFO - Running setup.py install for mozrunner 07:51:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:51:41 INFO - Running setup.py install for mozscreenshot 07:51:41 INFO - Running setup.py install for moztest 07:51:41 INFO - Running setup.py install for mozversion 07:51:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:51:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:51:41 INFO - Cleaning up... 07:51:41 INFO - Return code: 0 07:51:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:41 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')]} 07:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:41 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')]} 07:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:41 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 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:41 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 07:51:41 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 07:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:41 INFO - 'HOME': '/Users/cltbld', 07:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:41 INFO - 'LOGNAME': 'cltbld', 07:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:41 INFO - 'MOZ_NO_REMOTE': '1', 07:51:41 INFO - 'NO_EM_RESTART': '1', 07:51:41 INFO - 'PAGER': '/bin/cat', 07:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:41 INFO - 'PWD': '/builds/slave/test', 07:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:41 INFO - 'SHELL': '/bin/bash', 07:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:41 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:41 INFO - 'USER': 'cltbld', 07:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:41 INFO - 'XPC_FLAGS': '0x0', 07:51:41 INFO - 'XPC_SERVICE_NAME': '0', 07:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:51:41 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-usioB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:51:41 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)) 07:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:51:41 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-hJz1vH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-3x4TRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-uBbgbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-9632Tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-cFcSQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-kwkNV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:51:42 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)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-bA2Zbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:51:42 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)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-_TKCBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:51:42 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)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-kyIsUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:51:42 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)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-MeB0nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:51:42 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)) 07:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:51:42 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-8yROTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:51:43 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)) 07:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:51:43 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-5LcKns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:51:43 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-OWvuZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:43 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-MVVjw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:51:43 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-NkpS3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:51:43 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)) 07:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:51:43 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-NXwJcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:51:43 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)) 07:51:43 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)) 07:51:43 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)) 07:51:43 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)) 07:51:43 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)) 07:51:43 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)) 07:51:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:46 INFO - Downloading blessings-1.6.tar.gz 07:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:51:46 INFO - Installing collected packages: blessings 07:51:46 INFO - Running setup.py install for blessings 07:51:46 INFO - Successfully installed blessings 07:51:46 INFO - Cleaning up... 07:51:46 INFO - Return code: 0 07:51:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:51:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:51:46 INFO - Reading from file tmpfile_stdout 07:51:46 INFO - Current package versions: 07:51:46 INFO - blessings == 1.6 07:51:46 INFO - blobuploader == 1.2.4 07:51:46 INFO - docopt == 0.6.1 07:51:46 INFO - functools32 == 3.2.3-2 07:51:46 INFO - jsonschema == 2.5.1 07:51:46 INFO - manifestparser == 1.1 07:51:46 INFO - mozInstall == 1.12 07:51:46 INFO - mozcrash == 1.0 07:51:46 INFO - mozdebug == 0.1 07:51:46 INFO - mozdevice == 0.49 07:51:46 INFO - mozfile == 1.2 07:51:46 INFO - mozhttpd == 0.7 07:51:46 INFO - mozinfo == 0.9 07:51:46 INFO - mozleak == 0.1 07:51:46 INFO - mozlog == 3.4 07:51:46 INFO - moznetwork == 0.27 07:51:46 INFO - mozprocess == 0.23 07:51:46 INFO - mozprofile == 0.28 07:51:46 INFO - mozrunner == 6.13 07:51:46 INFO - mozscreenshot == 0.1 07:51:46 INFO - mozsystemmonitor == 0.3 07:51:46 INFO - moztest == 0.7 07:51:46 INFO - mozversion == 1.4 07:51:46 INFO - psutil == 3.1.1 07:51:46 INFO - requests == 1.2.3 07:51:46 INFO - wsgiref == 0.1.2 07:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:46 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')]} 07:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:46 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')]} 07:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:46 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 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:46 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 07:51:46 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 07:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:46 INFO - 'HOME': '/Users/cltbld', 07:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:46 INFO - 'LOGNAME': 'cltbld', 07:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:46 INFO - 'MOZ_NO_REMOTE': '1', 07:51:46 INFO - 'NO_EM_RESTART': '1', 07:51:46 INFO - 'PAGER': '/bin/cat', 07:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:46 INFO - 'PWD': '/builds/slave/test', 07:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:46 INFO - 'SHELL': '/bin/bash', 07:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:46 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:46 INFO - 'USER': 'cltbld', 07:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:46 INFO - 'XPC_FLAGS': '0x0', 07:51:46 INFO - 'XPC_SERVICE_NAME': '0', 07:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:51:47 INFO - Cleaning up... 07:51:47 INFO - Return code: 0 07:51:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b88eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b891768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d2d71b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, '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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 07:51:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:47 INFO - 'HOME': '/Users/cltbld', 07:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:47 INFO - 'LOGNAME': 'cltbld', 07:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:47 INFO - 'MOZ_NO_REMOTE': '1', 07:51:47 INFO - 'NO_EM_RESTART': '1', 07:51:47 INFO - 'PAGER': '/bin/cat', 07:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:47 INFO - 'PWD': '/builds/slave/test', 07:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:47 INFO - 'SHELL': '/bin/bash', 07:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:51:47 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:47 INFO - 'USER': 'cltbld', 07:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:47 INFO - 'XPC_FLAGS': '0x0', 07:51:47 INFO - 'XPC_SERVICE_NAME': '0', 07:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:51:47 INFO - Cleaning up... 07:51:47 INFO - Return code: 0 07:51:47 INFO - Running post-action listener: _resource_record_post_action 07:51:47 INFO - Running post-action listener: _start_resource_monitoring 07:51:47 INFO - Starting resource monitoring. 07:51:47 INFO - [mozharness: 2016-12-22 15:51:47.476416Z] Finished create-virtualenv step (success) 07:51:47 INFO - [mozharness: 2016-12-22 15:51:47.477475Z] Running install step. 07:51:47 INFO - Running pre-action listener: _resource_record_pre_action 07:51:47 INFO - Running main action method: install 07:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:51:47 INFO - Reading from file tmpfile_stdout 07:51:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:51:47 INFO - Reading from file tmpfile_stdout 07:51:47 INFO - Output received: 07:51:47 INFO - Usage: mozinstall [options] installer 07:51:47 INFO - Options: 07:51:47 INFO - -h, --help show this help message and exit 07:51:47 INFO - -d DEST, --destination=DEST 07:51:47 INFO - Directory to install application into. [default: 07:51:47 INFO - "/builds/slave/test"] 07:51:47 INFO - --app=APP Application being installed. [default: firefox] 07:51:47 INFO - mkdir: /builds/slave/test/build/application 07:51:47 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'] 07:51:47 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 07:52:09 INFO - Reading from file tmpfile_stdout 07:52:09 INFO - Output received: 07:52:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:52:09 INFO - Running post-action listener: _resource_record_post_action 07:52:09 INFO - [mozharness: 2016-12-22 15:52:09.628038Z] Finished install step (success) 07:52:09 INFO - [mozharness: 2016-12-22 15:52:09.628205Z] Running run-tests step. 07:52:09 INFO - Running pre-action listener: _resource_record_pre_action 07:52:09 INFO - Running main action method: run_tests 07:52:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:52:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:52:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:52:09 INFO - 2016-12-22 07:52:09.642 screenresolution[1632:9546] starting screenresolution argv=screenresolution get 07:52:09 INFO - 2016-12-22 07:52:09.645 screenresolution[1632:9546] Display 0: 1600x1200x32@60 07:52:09 INFO - 2016-12-22 07:52:09.651 screenresolution[1633:9548] starting screenresolution argv=screenresolution list 07:52:09 INFO - Available Modes on Display 0 07:52:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:52:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:52:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:52:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:52:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:52:09 INFO - Intel Iris: 07:52:09 INFO - Chipset Model: Intel Iris 07:52:09 INFO - Type: GPU 07:52:09 INFO - Bus: Built-In 07:52:09 INFO - VRAM (Dynamic, Max): 1536 MB 07:52:09 INFO - Vendor: Intel (0x8086) 07:52:09 INFO - Device ID: 0x0a2e 07:52:09 INFO - Revision ID: 0x0009 07:52:09 INFO - Displays: 07:52:09 INFO - Display: 07:52:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:52:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:52:09 INFO - Display Serial Number: 1600x1200 60 07:52:09 INFO - Main Display: Yes 07:52:09 INFO - Mirror: Off 07:52:09 INFO - Online: Yes 07:52:09 INFO - Rotation: Supported 07:52:09 INFO - Return code: 0 07:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:52:09 INFO - Python 2.7.3 07:52:09 INFO - Return code: 0 07:52:09 INFO - grabbing minidump binary from tooltool 07:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b66cd30>, '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 0x10b593db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b661be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:52:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:52:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:52:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:52:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:52:10 INFO - Return code: 0 07:52:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:52:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:52:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:52:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:52:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:52:10 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/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 07:52:10 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/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0328 --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 07:52:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vA9QZQ6LWg/Render', 07:52:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:10 INFO - 'HOME': '/Users/cltbld', 07:52:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:10 INFO - 'LOGNAME': 'cltbld', 07:52:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:52:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:10 INFO - 'MOZ_NO_REMOTE': '1', 07:52:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:52:10 INFO - 'NO_EM_RESTART': '1', 07:52:10 INFO - 'PAGER': '/bin/cat', 07:52:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:10 INFO - 'PWD': '/builds/slave/test', 07:52:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:52:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:10 INFO - 'SHELL': '/bin/bash', 07:52:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDUmDsFU89/Listeners', 07:52:10 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 07:52:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:10 INFO - 'USER': 'cltbld', 07:52:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:10 INFO - 'XPC_FLAGS': '0x0', 07:52:10 INFO - 'XPC_SERVICE_NAME': '0', 07:52:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:10 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/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 07:52:10 INFO - mozversion application_buildid: 20161222063953 07:52:10 INFO - mozversion application_changeset: 05d4c2e9bb1ee731b2d821a56cb3c4cdf1114b63 07:52:10 INFO - mozversion application_display_name: Nightly 07:52:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:52:10 INFO - mozversion application_name: Firefox 07:52:10 INFO - mozversion application_remotingname: firefox 07:52:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:52:10 INFO - mozversion application_vendor: Mozilla 07:52:10 INFO - mozversion application_version: 53.0a1 07:52:10 INFO - mozversion platform_buildid: 20161222063953 07:52:10 INFO - mozversion platform_changeset: 05d4c2e9bb1ee731b2d821a56cb3c4cdf1114b63 07:52:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:52:10 INFO - mozversion platform_version: 53.0a1 07:52:10 INFO - using testdate: 1482421930 07:52:10 INFO - actual date: 1482421930 07:52:10 INFO - starting webserver on 'localhost:49235' 07:52:10 INFO - SUITE-START | Running 2 tests 07:52:10 INFO - TEST-START | dromaeo_css 07:52:10 INFO - operating with platform_type : mac_ 07:52:10 INFO - Initialising browser for dromaeo_css test... 07:52:10 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpkHhlIJ/profile http://localhost:49235/getInfo.html) 07:52:13 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 07:52:13 INFO - PROCESS | 1643 | colorDepth:24 07:52:13 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 07:52:13 INFO - PROCESS | 1643 | __metrics 07:52:13 INFO - PROCESS | 1643 | 2016-12-22 07:52:13.079 plugin-container[1646:9725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 07:52:13 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:13 INFO - PROCESS | 1643 | 2016-12-22 07:52:13.081 plugin-container[1646:9725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 07:52:13 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:13 INFO - PROCESS | 1643 | 2016-12-22 07:52:13.081 plugin-container[1646:9725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:52:13 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 07:52:13 INFO - TEST-INFO | 1643: exit 0 07:52:13 INFO - Browser initialized. 07:52:13 INFO - Running cycle 1/1 for dromaeo_css test... 07:52:13 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpkHhlIJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:16 INFO - PROCESS | 1647 | 2016-12-22 07:52:16.349 plugin-container[1648:9841] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 07:52:16 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:16 INFO - PROCESS | 1647 | 2016-12-22 07:52:16.349 plugin-container[1648:9841] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 07:52:16 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:16 INFO - PROCESS | 1647 | 2016-12-22 07:52:16.349 plugin-container[1648:9841] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:52:25 INFO - PROCESS | 1647 | RSS: Main: 235528192 07:52:25 INFO - PROCESS | 1647 | 07:53:15 INFO - PROCESS | 1647 | 1482421995658 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] 07:55:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 07:55:30 INFO - PROCESS | 1647 | RSS: Main: 254304256 07:55:30 INFO - PROCESS | 1647 | 07:58:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 07:58:33 INFO - PROCESS | 1647 | RSS: Main: 264384512 07:58:33 INFO - PROCESS | 1647 | 08:01:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 08:01:36 INFO - PROCESS | 1647 | RSS: Main: 269389824 08:01:36 INFO - PROCESS | 1647 | 08:04:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 08:04:38 INFO - PROCESS | 1647 | RSS: Main: 271093760 08:04:38 INFO - PROCESS | 1647 | 08:07:41 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 08:07:42 INFO - PROCESS | 1647 | RSS: Main: 275533824 08:07:42 INFO - PROCESS | 1647 | 08:10:44 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 08:10:44 INFO - PROCESS | 1647 | RSS: Main: 277958656 08:10:44 INFO - PROCESS | 1647 | 08:10:44 INFO - PROCESS | 1647 | __start_tp_report 08:10:44 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 08:10:44 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 08:10:44 INFO - PROCESS | 1647 | |i|pagename|runs| 08:10:44 INFO - PROCESS | 1647 | |0;dojo.html;3265.967340326597;3318.5022246662984;3327.9500807487875;3376.966230337696;3383.1372999885025;5506;5532.474414930585;5541.612087153901;5644.266245388104;5648.604597678159;5493.752781124849;5521.4478552144765;5563.527100196478;5597.524210442118;5683.374828768832;4716.127516409458;4926.704397736147;5021.648484606079;5186.170212765958;5216.947830521684;5032.094223039851;5175.792968281265;5199.454057323983;5215.921761173586;5247.553957913563;5026.547610715053;5071.594272458195;5165.173572228426;5238.4499627539135;5276.1030624793875;5033.7734801934;5097.7196254206;5127.256547800585;5149.0731668299695;5205.349331333583;5102.336696229467;5119.692818430906;5123.564497017758;5138.2806407103035;5167.509086636801;5412.810551630674;5438.972805135949;5626.85932851682;5647.124695672177;5671.744771485293;5988.19159413478;6064.120702498113;6078.118672792464;6092;6120.14317995481;6109.374301065099;6121.209103132296;6125.021800333839;6152.077188421727;6152.830962117184;5640.492355688008;5718.628289161191;5735.684537350444;5748.597598168171;5776.9422305776825;6175.2898416681755;6187.195664563578;6209.96895015531;6302.558820882494;6316.747330106836;5243.292155559027;5264.973330200574;5914.970425147847;6073.635581865102;6086.60437071589;4955.851324460272;5075.56857667095;5864.237649105674;6081.695915204222;6107.511399088063;6072.423119803611;6123.84690382744;6130.63216207029;6140.4473597376455;6143.447089761943;5410.729463526808;6101.298350689727;6112.419320164578;6123.050927106306;6138.324784273726;3844.5386553613744;4713.222318317507;4755.690880092783;4765.999140180532;4799.904001919942;3802.961970380261;4250.936235956463;4269.893252668646;4279.807408666617;4346.543612920661;5742.540596752333;5771.249737534094;5773.162891380726;5776.566757493205;5782.421757824268;6024.246969128859;6096;6192.256929168529;6201.627902325875;6217.751289948442;5615.185798059259;5682.914756278659;5695.1741997410145;5703.287089113861;5745.253117094751;4521.412216411911;5214.2960700304975;5235.921461178085;5260.789568417221;5272.393674727456;6191;6204.844878878064;6238.438540531374;6250.6874656267;6260.373962603703;6197.690115494117;6202.7208775606105;6203.751849925952;6205.937940620536;6259.405356491126;6272.686365681607;6275.246970363403;6277;6285.1515045470105;6305.211848518935;5884.823455296177;5932.199153114274;5932.5550583705535;5956.37458066914;6110.816675499742;5843.444872737083;5860.531157507304;6115.270764408881;6132.754689812357;6191.102290167811;5900.203472531325;5906.143609176731;5921.822345329647;5933.258342707161;5943.732532035982;5789.507891829242;5805.3614102447855;5812.360640329476;5922.348541660328;5941.287045554561;5523.475269849358;5664.37691853904;5723.427657234243;5794.971025144847;5796.42035796417;4922.237053256751;5921.082232254007;5927.733252003584;5930.7924222652;5963.612365196248;5681.375048744552;5733.655980641175;5759.51044161251;5767.221425107725;5779.306483222041 08:10:44 INFO - PROCESS | 1647 | |1;ext.html;457.91986402379587;459.8436531579263;486.80771095417333;502.226571080536;525.7765449683886;38719;41174.38238426426;41263.96840078999;41288.380674289874;41347;42435.363469547905;42955.71132866019;43001.784991075;43079.78460107703;43128.92177695559;9289.489078100702;9482;9487.715368538955;9496.620135194584;10935.398553079578;8931.821363572724;10958.342499450018;11047.171462140332;11061.391623460706;11132.944335278313;8617.698380556682;9363.39137956034;10403.531841067132;10972.890271097267;11154.721131971684;10999.835002474969;11057.834132487938;11072.615923009664;11077.667669969915;11107.278026928305;10962.013418792267;11014;11021.338719676845;11057.11543076552;11072.778544429148;36177;36588.26823463543;36672.63327366719;36709.71515996954;36714.08214794625;3067.969320306813;3185.649578546358;3340.515625234327;3350.961202027355;3690.5202323698018;26976;28099.85950070257;28629;28819.855900720573;28924.27689307763;3133.7493000559903;3191.952120718191;3199.600049993751;3301.1912081539936;3349.279904820475;482428;484773;488048.55975719896;488981;491012.5449372766;52526.47473525292;53026.20460693093;53040.46959530433;53241.2013819793;53370.19944700832;55768;55906.44093559094;56336.436635633116;56418;56566.7171664139;14550.417983280762;14904.62738431548;14998.550043498712;15008.174550399834;15063;10586.57653693859;10867.510962006729;11370.658880233606;11569.826452603218;11651.650450486499;16470.917645411697;16473.752893706605;16475.505734827973;16522.50432487027;16606.418775342805;7486.288802563748;7502.549847009197;7552;7609.592225438201;7615.390768738488;828.9005319361715;850.7915560687547;857.2716956635932;892.6030761857672;1009.2682804966487;27149.86425067862;27376;27495.900163993218;27942.86028569844;27999.580006299922;873.9038705742363;938.9953050234706;972.4991629310911;1077.5689724110418;1107.5293000474767;13658.726825463435;13738.900887928941;14118.717625647634;14424.134551926918;14440.783388248974;695708;710203;711845.3746894037;847274.2908856372;889276;51180.48819511832;51470.485295147315;51788;51969.740151299;52022;1327.1307293722627;1340.3901224942629;1354.2483921423507;1368.4868174434587;1385.3765805387616;27702.307442314104;27866.58200126959;27918;28588;28695.42609147847;44727.32909006302;44814.77592612016;45374;45520.537535495954;45606.771966141285;485019;487974;489091;489127;489558;22138.88930555337;22702.864856756765;24435.75564244406;24816.379590510383;24952;40264.59735402608;40508;40588.39117413182;40693.79653101716;41337;34192.31615367629;34319;34365.656343437244;35171.472427914116;35437.64562354344;34209.14477137992;34287.657123429446;34692.47961280633;35182.64817351794;35183.29633407369;33945.830270849474;34085.48871766975;34101.48847767335;35547.82226088853;36649.45025824668;27653.861730691217;30053.948111815982;31876.840615796773;31914.52128218125;32136.35727285488;19291.710624340925;22358.88820555887;23114.844257786706;24279.393015174763;24374.87812560926 08:10:45 INFO - PROCESS | 1647 | |2;jquery.html;305.9709327613876;336.4734190991099;376.2024508042949;385.8148088917321;399.96010373030106;18913.82491013534;19291.710624340627;20175.394738157844;20765.961701914883;21011;19138.42584722457;19968.20127194914;20242.898785506088;20754.89622551889;20808.47978800531;4459.643228541709;4528.796204170821;5095.235714642806;5283.022640811462;5291.735413229342;4755.381800365947;5871.588988770788;5875.559333050017;5878.882422351572;5882.529397648178;5329.280547126146;5822.388649191837;5854.034084376073;5880.441358070976;5893.469587737103;6481.416672499474;6522;6528.967355163218;6534.803955881332;6551.541392102555;5568.053430916754;5714.885702285931;5760.481556659879;5779.988502012131;5861.003629382973;12345.321007344592;12718.491260349574;12752.80870786939;22026.33920982357;24685.5062898741;2247.71903512061;2300.9851434019247;2325.6744055832196;2326.2439707095264;2463.6550882876413;9720.465374404404;9773.33853244943;9786.266030047704;10135.290529662927;10170.59317627294;1857.944261672152;1887.4526387347653;1898.9620207595908;1909.1408866010213;1916.281394477071;111001.44499277533;114163.42918285438;114215;116623.41688291589;118753.40623296828;11165.83251251232;11466;11636.88363116358;11639.883601164049;11757.823632645517;14595.78106328406;14664.340104695315;14672.193029383487;14684.412623495155;14701.338439770236;8104.918950810535;8304.418690691593;8329.37529685276;8374.371922105867;8490.23587877094;8167.428280020342;8171.509709417454;8175.836483270301;8300.460470069425;8335.583220838933;3680.429533422324;4522.208613492691;4551.95448045522;4562.064776720765;4590.563896429834;1860.135037207705;1917.3960202536157;1927.7783054948861;1961.4900125967351;1970.645283848921;797.784598158494;811.5414790643385;857.4640849469082;877.1929824561419;970.3401686852867;19560;19745.81525108498;20634;21016.579668406546;21529;924.6440120553501;944.6619616449233;975.8633791269228;988.5205675247499;990.9950450247846;9133.858267716536;9325.834270716161;9468.763280918032;10706.625268115578;11421.885781142248;532041;536130;542135;553877;560153;102964;106758.86482270467;127269;131385;140058;853.1425916953547;975.8389865672226;1007.6120693532896;1024.2215915903819;1028.6399760083912;16960.406385776438;17202.31190752356;17811.643767124846;19538.925359105386;19685.40943771689;43571;51108.74445627748;52671.73664131655;53681.19478207752;53772.62273772591;70397.64801176166;70592.6470367645;72753.27246727466;73531.63234183795;78799.81800273115;5033.6476446648385;5403.864903377447;6895.586264824126;7257.383122434652;7258.49190556656;11255.831162532731;12412.882840544395;12504.74990500203;12590.685232868886;13129.212247265195;10967.50646220938;11022.779544408906;11340.829887551858;11490.482928268415;11507.654770356903;10685.358878467317;10984.670459886216;11210.943945280222;11288.717782055513;11426.771464570827;10554.208434367607;10878.40168790724;11171.832422513506;11631.360275184608;11850.703732406759;7143.964280178566;7296.708131674886;7331.78004659861;7446.367058800062;7545.547267163988;9340.626374945197;11246.381449020055;11496.770064598828;11590.246633968765;11608 08:10:45 INFO - PROCESS | 1647 | |3;mootools.html;1465.45778062117;1484.2282013353056;1703.5996540812907;1774.1218097041967;1849.6578133045386;7488.887666685004;7734.458587898849;7935.801604959872;8411.285040771534;8412.495250284986;7885.881711774328;8171.8365632687455;8193.139720329355;8271.751847444571;8274.089850116483;2257.48077942073;2306.1582522379376;2313.710786151731;2314.0743702519044;2316.6061769499142;2295.0819672131197;2300.850444721096;2333.8132949364094;2334.684817549626;2340.192633541434;2266.8073213776847;2277.7038984931937;2301.6892719482908;2302.021640802652;2303.5738388398117;1754.3684273661472;2275.8862056897087;2292;2295.942601434969;2303.7235531736214;2279.1567120165646;2299.7585253548305;2319.2694301295205;2319.860808351504;2334.5330933813307;8249.463784853966;8295.585220739;8316.875246871241;8317.376196785266;8321.500709957421;5012.323336349583;5171.327727395452;5176.7929282828645;5182.455842136559;5183.844484665428;3211.4379983502795;3301.4222511060707;3490.127468132967;3491.877784277538;3495.860165593373;1032.1381646325328;1047.6438011076195;1052.9102379037736;1068.7702144038992;1089.629525961169;10544.209184311207;10593.311434756715;10615.840762388492;10737.785244295148;10743.247972641919;4411.470623525133;4540.79566419512;4583.633309335245;4622.14490319292;4625.098105869391;9224.7693807654;9244.7688807779;9249.445033298023;9271.304652151115;9273;5115.411727651293;5141.434442211354;5148.716820574829;5151;5161.612879034087;3486.9302613948084;3658.396364599811;3863.9806800965816;4571.3600095986585;4706.176419126639;3459.325431540876;3531.7462300882817;3806.1816709407613;3847.115163512352;3948.723589348718;1105.2042529378834;1228.3244215681339;1246.4328730427633;1252.1485389934933;1265.816456613786;692.8614277144591;750.8385697075155;810.5015415519531;826.4215348683985;843.9535825529532;5440.782368705208;5618.606697531133;5694.259746233046;5714.485696287354;5741.85645358861;887.8002449448951;906.3429013964823;914.2548822709471;924.1220840201835;944.2021491839384;3679.9264014719556;3731.0858839584475;3784.7729136251364;3887.2614203301846;3925.2345792570745;9779.168770654433;10060.39637621745;10063.396196228103;10063.496825158712;10133.13868321201;9900.55447504864;10002.799944001079;10026.498675066217;10031.99680031991;10054.899451005542;7655.311022007934;7747.92252077472;7883.881741773878;7918.841623167505;7920.841583168304;5425.457454254543;5505.9724701376235;5548.805791797267;5559.249501317432;5629.915551266652;9270;9273.350865439354;9290.256779457763;9327.720168394959;9329.533523323671;9105.726828195166;9229.538523073954;9309.208717258838;9390.483523406278;9470.668526601536;6558.245801732862;6570.080188773671;6699.028640846953;6710.194776626836;6724.7646332378135;7633.5419874807685;7725.729599464216;7745.65144568507;7748.57382843949;7764.5729484878875;7006.544574602838;7046.154461464657;7076.2923707628825;7109.253528379652;7245.203027666847;5754.942450575608;6906.033155358355;6943.166819981635;6963.79108626742;6974.302569742985;6467.159269294962;6551.639659818565;6605.867882642224;6614.305497922841;6641.50188735837;4712;4806.807727690853;4829.227323628203;4858.198397264553;4943.777530011086;4778.689385189951;4824.324594556835;4875.000624871964;4919.581835544019;4977.407229686465 08:10:45 INFO - PROCESS | 1647 | |4;prototype.html;352.429064914838;354.73040489228185;363.16472114137486;367.35345791407116;369.50670854409344;5146.716930568818;5208.343744690207;5300.628955973083;5382.219578161169;5383.89232215356;5087.364079490064;5196.714180720059;5307.601929855257;5319.468053194679;5321.4412486688925;3374.493825926106;3479.982600086996;3559.4516385372317;4018.8794336169963;4181.762756925045;3782.186829831586;3833.310004199257;4169.666426685858;4173.436586060889;4208.45290112285;3159.7788154829173;3968.8964848630126;4015.5783642717533;4032.1330913853517;4035.57626449223;3488.70917760429;3646.3801153803784;4018.4976877890263;4020.43713880057;4024.839006439739;3491.074865160709;3511.087117349482;3513.1568423578415;3529.9117522061897;3551.5027896094566;4539.116266749295;4852.902941941142;4950.653454258203;5220.947790522122;5226.973865130688;4561.315802629599;4582.839600613963;4586.312053192015;4597.057603191338;4618.745968971705;4557.8377513733785;5161.870953226199;5273.626867909417;5645.802396916089;5652.434756524356;3477.7043951264172;3672.155404257009;3811.4473401356927;3813.8474461021524;3824.8661296854757;4577.656675749299;4680.602148817321;4748.765321016561;5755.338136114358;5779.826605201851;3821.923561528754;3830.272248272819;3856.5564960029956;3857.691384689218;3861.189150278417;3545.592256890439;3869.090763670535;3895.7078219133678;3908.902277443087;3910.4916360872903;3772.5850156482766;3883.359245724424;4043.3732771420478;4248.91502169961;4389.297712366006;5362.533234711998;5403.864903377447;5632.295963004624;5646.407127251661;5654.6324488908085;1079.6545105566142;1081.545321542524;1127.7800828838463;1169.2867350915935;1250.012490132785;746.3357611725569;754.8037510247375;830.6760363458257;872.8690696395591;883.8143989762222;4580.908381832344;4645.86062418128;4987.1771157759285;5055.786611213282;5121.4622464641425;4966.1941003333;5052.57053150479;5133.6919784813035;5266.552343050884;5552.972235138798;4444.555544445529;4545.0909818036525;4710.69380490267;5291.973540132275;5335.893282134413;2685.3018215264174;2930.593315208712;2939.5884576159356;2950.985245073761;2953.2321596385095;4854.344663470386;5019.598432125421;5154.664946778447;5428.484293992144;5821.650700957872;4843.505594618774;4953.7275449849885;5569.415211402745;5688.516476099495;5747.103455859557;5109.616778741609;5530.419305972895;5769.365369809318;5799.2750906136735;5828.193260089386;4512.887177820581;4585.908281834411;4782.689125206851;5219.859857458424;5555.500004999408;5040.521150490697;5054.241863720325;5351.839444816662;5436.891262174813;5521.012435523288;4829.154897892784;4971.701697898137;5449.468789179065;5795.826125216082;5802.390748971297;2999.4600971824407;3588.802615856153;3858.864939727096;3880.7477513961503;3893.9221215576094;5777.8555536111935;5785.363610002981;5791.131330300489;5805.535557155333;5806.274215723035;4431.4903786065015;4857.032901674827;5374.462553744751;5526.956126886022;5617.269754931833;4374.606285434326;4876.29293752411;4955.45489996093;5230.2677625131755;5532.529734972572;5254;5386.145342968233;5462.590305727007;5469.78826303736;5535.833924982257;5477.972610136924;5605.122284034894;5616.079638587993;5633.324001119892;5641.464060914206;4479.641628669764;4900.215965445512;4963.70217786934;5229.47705229474;5508.504234618904 08:10:45 INFO - PROCESS | 1647 | |5;yui.html;740.9184989651137;761.7714685594324;773.1958762886599;797.4099166616705;846.4117438380326;13001.934990325048;15591.766123508141;15668.451604193855;15739.055656660608;15844;13138.737225255489;14415.351309191085;14486.782698259534;14608;14681;876.9870799226887;885.6501681835671;894.6555576103215;895.4269267668698;901.1664210605197;863.6027427383411;899.9775005624879;900.3337530227617;901.6889173235256;910.3945875992457;945.6879229854165;946.2099147212086;946.6213514594149;947.4930911962101;951.015698751792;933.6078846884325;937.7233716053746;937.8499440089587;938.6573900526292;952.8665986761858;700.2997002997002;700.9053777739991;701.4598758955626;703.3634560722554;703.5145749432875;12321.753564928651;14633.634159146;15259.923700381427;15390.846091539166;15469.149196794284;13783.655408614764;13930.721385572333;13971.70902699504;14178.220197889112;14188.787168192486;10067.899321006842;11595.94202028993;11723.120765942504;11813.704657383549;12082.879171208264;3639.108418437473;3673.761205521659;3701.9444708329397;3706.2402207547657;3825.431573055062;109696.47545548527;110656.34015489774;110852;111689;112882.87117128723;9206.427933736812;10707.571697132184;10727.624533141303;10773.192010599236;10788.244822862323;18818.341358052403;21486;22246.666300005512;22262.777372226396;22350.217742379264;6890.414314783199;7652.273034061755;7713;7731.922680773233;7794.415418843496;4276.102018576134;4328.220920234325;4367.890802729958;4420.778961051935;4499.190145773792;3513.244652399695;3574.1422058705725;3634.0914771307175;3634.909527141847;3647.598764135943;637.7831537277348;644.7906658875097;655.8688262347549;663.141232104426;669.8995150727333;591.6420565557776;608.3247595169358;653.3629711031801;662.483263054818;679.1341040173719;7769;8206.384521160937;8911.197992180605;9439.716808495756;9640.807183856283;634.30226750574;648.8799572079087;657.3590749019763;662.7282814046218;698.2424069884131;4309;4416.1830061438095;4473.1277400907165;4477.395551600493;4490.903208143596;79895;90134;116126;116193;116493;70087;70521.64739176272;73261.53476930538;77236;88423.11576884148;79652.60173699094;80718;81507.18492815114;81578.51318177558;82529.76205356924;9852.852207216754;10059.547320370748;10089.596416143566;10143.442110683984;10147.79704405893;50593.73515662138;52710.57381270205;53708.19437708516;53716.92566148577;54342.72828635832;60879;62236.68881655563;70135.64932175307;72669.6366518164;74830;13147.539836106072;13184.868151318748;13286.80069798973;13701.177929324273;13781.862181378057;14729.55811325662;14773.03975241649;14833.035852669542;14893.776593351326;15037.924810375878;13665.795013074612;13799.862001379857;13814.861851381358;13933.23367214813;14104.81431900425;12330.075244356889;12773.425195866022;13677.247751373372;13827.58517244444;13850.30748462593;13250;13544.119632223874;13552.59342219735;13620.387082581507;13890.513832015831;11099.167562432689;11119.888011199138;11350.829737553779;11606.013488853541;11619.477123529292;8720;8928;8933.46399216049;10466.581336746445;10513.89486105129 08:10:45 INFO - PROCESS | 1647 | __end_tp_report 08:10:45 INFO - PROCESS | 1647 | __start_cc_report 08:10:45 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,361 08:10:45 INFO - PROCESS | 1647 | __end_cc_report 08:10:45 INFO - PROCESS | 1647 | __startTimestamp1482423044991__endTimestamp 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - PROCESS | 1647 | ------- Summary: start ------- 08:10:45 INFO - PROCESS | 1647 | Number of tests: 6 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5587.11 Median:5767.22 stddev:655.20 (11.4%) stddev-sans-first:631.40 08:10:45 INFO - PROCESS | 1647 | Values: 3266.0 3318.5 3328.0 3377.0 3383.1 5506.0 5532.5 5541.6 5644.3 5648.6 5493.8 5521.4 5563.5 5597.5 5683.4 4716.1 4926.7 5021.6 5186.2 5216.9 5032.1 5175.8 5199.5 5215.9 5247.6 5026.5 5071.6 5165.2 5238.4 5276.1 5033.8 5097.7 5127.3 5149.1 5205.3 5102.3 5119.7 5123.6 5138.3 5167.5 5412.8 5439.0 5626.9 5647.1 5671.7 5988.2 6064.1 6078.1 6092.0 6120.1 6109.4 6121.2 6125.0 6152.1 6152.8 5640.5 5718.6 5735.7 5748.6 5776.9 6175.3 6187.2 6210.0 6302.6 6316.7 5243.3 5265.0 5915.0 6073.6 6086.6 4955.9 5075.6 5864.2 6081.7 6107.5 6072.4 6123.8 6130.6 6140.4 6143.4 5410.7 6101.3 6112.4 6123.1 6138.3 3844.5 4713.2 4755.7 4766.0 4799.9 3803.0 4250.9 4269.9 4279.8 4346.5 5742.5 5771.2 5773.2 5776.6 5782.4 6024.2 6096.0 6192.3 6201.6 6217.8 5615.2 5682.9 5695.2 5703.3 5745.3 4521.4 5214.3 5235.9 5260.8 5272.4 6191.0 6204.8 6238.4 6250.7 6260.4 6197.7 6202.7 6203.8 6205.9 6259.4 6272.7 6275.2 6277.0 6285.2 6305.2 5884.8 5932.2 5932.6 5956.4 6110.8 5843.4 5860.5 6115.3 6132.8 6191.1 5900.2 5906.1 5921.8 5933.3 5943.7 5789.5 5805.4 5812.4 5922.3 5941.3 5523.5 5664.4 5723.4 5795.0 5796.4 4922.2 5921.1 5927.7 5930.8 5963.6 5681.4 5733.7 5759.5 5767.2 5779.3 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:69604.28 Median:34303.33 stddev:161048.30 (469.5%) stddev-sans-first:161416.41 08:10:45 INFO - PROCESS | 1647 | Values: 457.9 459.8 486.8 502.2 525.8 38719.0 41174.4 41264.0 41288.4 41347.0 42435.4 42955.7 43001.8 43079.8 43128.9 9289.5 9482.0 9487.7 9496.6 10935.4 8931.8 10958.3 11047.2 11061.4 11132.9 8617.7 9363.4 10403.5 10972.9 11154.7 10999.8 11057.8 11072.6 11077.7 11107.3 10962.0 11014.0 11021.3 11057.1 11072.8 36177.0 36588.3 36672.6 36709.7 36714.1 3068.0 3185.6 3340.5 3351.0 3690.5 26976.0 28099.9 28629.0 28819.9 28924.3 3133.7 3192.0 3199.6 3301.2 3349.3 482428.0 484773.0 488048.6 488981.0 491012.5 52526.5 53026.2 53040.5 53241.2 53370.2 55768.0 55906.4 56336.4 56418.0 56566.7 14550.4 14904.6 14998.6 15008.2 15063.0 10586.6 10867.5 11370.7 11569.8 11651.7 16470.9 16473.8 16475.5 16522.5 16606.4 7486.3 7502.5 7552.0 7609.6 7615.4 828.9 850.8 857.3 892.6 1009.3 27149.9 27376.0 27495.9 27942.9 27999.6 873.9 939.0 972.5 1077.6 1107.5 13658.7 13738.9 14118.7 14424.1 14440.8 695708.0 710203.0 711845.4 847274.3 889276.0 51180.5 51470.5 51788.0 51969.7 52022.0 1327.1 1340.4 1354.2 1368.5 1385.4 27702.3 27866.6 27918.0 28588.0 28695.4 44727.3 44814.8 45374.0 45520.5 45606.8 485019.0 487974.0 489091.0 489127.0 489558.0 22138.9 22702.9 24435.8 24816.4 24952.0 40264.6 40508.0 40588.4 40693.8 41337.0 34192.3 34319.0 34365.7 35171.5 35437.6 34209.1 34287.7 34692.5 35182.6 35183.3 33945.8 34085.5 34101.5 35547.8 36649.5 27653.9 30053.9 31876.8 31914.5 32136.4 19291.7 22358.9 23114.8 24279.4 24374.9 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:32702.17 Median:12495.74 stddev:91270.11 (730.4%) stddev-sans-first:91493.73 08:10:45 INFO - PROCESS | 1647 | Values: 306.0 336.5 376.2 385.8 400.0 18913.8 19291.7 20175.4 20766.0 21011.0 19138.4 19968.2 20242.9 20754.9 20808.5 4459.6 4528.8 5095.2 5283.0 5291.7 4755.4 5871.6 5875.6 5878.9 5882.5 5329.3 5822.4 5854.0 5880.4 5893.5 6481.4 6522.0 6529.0 6534.8 6551.5 5568.1 5714.9 5760.5 5780.0 5861.0 12345.3 12718.5 12752.8 22026.3 24685.5 2247.7 2301.0 2325.7 2326.2 2463.7 9720.5 9773.3 9786.3 10135.3 10170.6 1857.9 1887.5 1899.0 1909.1 1916.3 111001.4 114163.4 114215.0 116623.4 118753.4 11165.8 11466.0 11636.9 11639.9 11757.8 14595.8 14664.3 14672.2 14684.4 14701.3 8104.9 8304.4 8329.4 8374.4 8490.2 8167.4 8171.5 8175.8 8300.5 8335.6 3680.4 4522.2 4552.0 4562.1 4590.6 1860.1 1917.4 1927.8 1961.5 1970.6 797.8 811.5 857.5 877.2 970.3 19560.0 19745.8 20634.0 21016.6 21529.0 924.6 944.7 975.9 988.5 991.0 9133.9 9325.8 9468.8 10706.6 11421.9 532041.0 536130.0 542135.0 553877.0 560153.0 102964.0 106758.9 127269.0 131385.0 140058.0 853.1 975.8 1007.6 1024.2 1028.6 16960.4 17202.3 17811.6 19538.9 19685.4 43571.0 51108.7 52671.7 53681.2 53772.6 70397.6 70592.6 72753.3 73531.6 78799.8 5033.6 5403.9 6895.6 7257.4 7258.5 11255.8 12412.9 12504.7 12590.7 13129.2 10967.5 11022.8 11340.8 11490.5 11507.7 10685.4 10984.7 11210.9 11288.7 11426.8 10554.2 10878.4 11171.8 11631.4 11850.7 7144.0 7296.7 7331.8 7446.4 7545.5 9340.6 11246.4 11496.8 11590.2 11608.0 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5369.57 Median:5145.08 stddev:2946.72 (57.3%) stddev-sans-first:2940.38 08:10:45 INFO - PROCESS | 1647 | Values: 1465.5 1484.2 1703.6 1774.1 1849.7 7488.9 7734.5 7935.8 8411.3 8412.5 7885.9 8171.8 8193.1 8271.8 8274.1 2257.5 2306.2 2313.7 2314.1 2316.6 2295.1 2300.9 2333.8 2334.7 2340.2 2266.8 2277.7 2301.7 2302.0 2303.6 1754.4 2275.9 2292.0 2295.9 2303.7 2279.2 2299.8 2319.3 2319.9 2334.5 8249.5 8295.6 8316.9 8317.4 8321.5 5012.3 5171.3 5176.8 5182.5 5183.8 3211.4 3301.4 3490.1 3491.9 3495.9 1032.1 1047.6 1052.9 1068.8 1089.6 10544.2 10593.3 10615.8 10737.8 10743.2 4411.5 4540.8 4583.6 4622.1 4625.1 9224.8 9244.8 9249.4 9271.3 9273.0 5115.4 5141.4 5148.7 5151.0 5161.6 3486.9 3658.4 3864.0 4571.4 4706.2 3459.3 3531.7 3806.2 3847.1 3948.7 1105.2 1228.3 1246.4 1252.1 1265.8 692.9 750.8 810.5 826.4 844.0 5440.8 5618.6 5694.3 5714.5 5741.9 887.8 906.3 914.3 924.1 944.2 3679.9 3731.1 3784.8 3887.3 3925.2 9779.2 10060.4 10063.4 10063.5 10133.1 9900.6 10002.8 10026.5 10032.0 10054.9 7655.3 7747.9 7883.9 7918.8 7920.8 5425.5 5506.0 5548.8 5559.2 5629.9 9270.0 9273.4 9290.3 9327.7 9329.5 9105.7 9229.5 9309.2 9390.5 9470.7 6558.2 6570.1 6699.0 6710.2 6724.8 7633.5 7725.7 7745.7 7748.6 7764.6 7006.5 7046.2 7076.3 7109.3 7245.2 5754.9 6906.0 6943.2 6963.8 6974.3 6467.2 6551.6 6605.9 6614.3 6641.5 4712.0 4806.8 4829.2 4858.2 4943.8 4778.7 4824.3 4875.0 4919.6 4977.4 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4388.44 Median:4866.66 stddev:1343.26 (27.6%) stddev-sans-first:1312.42 08:10:45 INFO - PROCESS | 1647 | Values: 352.4 354.7 363.2 367.4 369.5 5146.7 5208.3 5300.6 5382.2 5383.9 5087.4 5196.7 5307.6 5319.5 5321.4 3374.5 3480.0 3559.5 4018.9 4181.8 3782.2 3833.3 4169.7 4173.4 4208.5 3159.8 3968.9 4015.6 4032.1 4035.6 3488.7 3646.4 4018.5 4020.4 4024.8 3491.1 3511.1 3513.2 3529.9 3551.5 4539.1 4852.9 4950.7 5220.9 5227.0 4561.3 4582.8 4586.3 4597.1 4618.7 4557.8 5161.9 5273.6 5645.8 5652.4 3477.7 3672.2 3811.4 3813.8 3824.9 4577.7 4680.6 4748.8 5755.3 5779.8 3821.9 3830.3 3856.6 3857.7 3861.2 3545.6 3869.1 3895.7 3908.9 3910.5 3772.6 3883.4 4043.4 4248.9 4389.3 5362.5 5403.9 5632.3 5646.4 5654.6 1079.7 1081.5 1127.8 1169.3 1250.0 746.3 754.8 830.7 872.9 883.8 4580.9 4645.9 4987.2 5055.8 5121.5 4966.2 5052.6 5133.7 5266.6 5553.0 4444.6 4545.1 4710.7 5292.0 5335.9 2685.3 2930.6 2939.6 2951.0 2953.2 4854.3 5019.6 5154.7 5428.5 5821.7 4843.5 4953.7 5569.4 5688.5 5747.1 5109.6 5530.4 5769.4 5799.3 5828.2 4512.9 4585.9 4782.7 5219.9 5555.5 5040.5 5054.2 5351.8 5436.9 5521.0 4829.2 4971.7 5449.5 5795.8 5802.4 2999.5 3588.8 3858.9 3880.7 3893.9 5777.9 5785.4 5791.1 5805.5 5806.3 4431.5 4857.0 5374.5 5527.0 5617.3 4374.6 4876.3 4955.5 5230.3 5532.5 5254.0 5386.1 5462.6 5469.8 5535.8 5478.0 5605.1 5616.1 5633.3 5641.5 4479.6 4900.2 4963.7 5229.5 5508.5 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20391.60 Median:4348.06 stddev:29578.16 (680.3%) stddev-sans-first:29624.33 08:10:45 INFO - PROCESS | 1647 | Values: 740.9 761.8 773.2 797.4 846.4 13001.9 15591.8 15668.5 15739.1 15844.0 13138.7 14415.4 14486.8 14608.0 14681.0 877.0 885.7 894.7 895.4 901.2 863.6 900.0 900.3 901.7 910.4 945.7 946.2 946.6 947.5 951.0 933.6 937.7 937.8 938.7 952.9 700.3 700.9 701.5 703.4 703.5 12321.8 14633.6 15259.9 15390.8 15469.1 13783.7 13930.7 13971.7 14178.2 14188.8 10067.9 11595.9 11723.1 11813.7 12082.9 3639.1 3673.8 3701.9 3706.2 3825.4 109696.5 110656.3 110852.0 111689.0 112882.9 9206.4 10707.6 10727.6 10773.2 10788.2 18818.3 21486.0 22246.7 22262.8 22350.2 6890.4 7652.3 7713.0 7731.9 7794.4 4276.1 4328.2 4367.9 4420.8 4499.2 3513.2 3574.1 3634.1 3634.9 3647.6 637.8 644.8 655.9 663.1 669.9 591.6 608.3 653.4 662.5 679.1 7769.0 8206.4 8911.2 9439.7 9640.8 634.3 648.9 657.4 662.7 698.2 4309.0 4416.2 4473.1 4477.4 4490.9 79895.0 90134.0 116126.0 116193.0 116493.0 70087.0 70521.6 73261.5 77236.0 88423.1 79652.6 80718.0 81507.2 81578.5 82529.8 9852.9 10059.5 10089.6 10143.4 10147.8 50593.7 52710.6 53708.2 53716.9 54342.7 60879.0 62236.7 70135.6 72669.6 74830.0 13147.5 13184.9 13286.8 13701.2 13781.9 14729.6 14773.0 14833.0 14893.8 15037.9 13665.8 13799.9 13814.9 13933.2 14104.8 12330.1 12773.4 13677.2 13827.6 13850.3 13250.0 13544.1 13552.6 13620.4 13890.5 11099.2 11119.9 11350.8 11606.0 11619.5 8720.0 8928.0 8933.5 10466.6 10513.9 08:10:45 INFO - PROCESS | 1647 | -------- Summary: end -------- 08:10:45 INFO - PROCESS | 1647 | 08:10:45 INFO - TEST-INFO | 1647: exit 0 08:10:45 INFO - TEST-OK | dromaeo_css | took 1115427ms 08:10:45 INFO - TEST-START | kraken 08:10:45 INFO - operating with platform_type : mac_ 08:10:45 INFO - Initialising browser for kraken test... 08:10:45 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmp004eNy/profile http://localhost:49235/getInfo.html) 08:10:48 INFO - PROCESS | 1654 | 2016-12-22 08:10:48.359 plugin-container[1655:12163] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x892b, name = 'com.apple.tsm.portname' 08:10:48 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:48 INFO - PROCESS | 1654 | 2016-12-22 08:10:48.361 plugin-container[1655:12163] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451f, name = 'com.apple.CFPasteboardClient' 08:10:48 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:48 INFO - PROCESS | 1654 | 2016-12-22 08:10:48.361 plugin-container[1655:12163] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:10:48 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 08:10:48 INFO - PROCESS | 1654 | colorDepth:24 08:10:48 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 08:10:48 INFO - PROCESS | 1654 | __metrics 08:10:48 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 08:10:49 INFO - TEST-INFO | 1654: exit 0 08:10:49 INFO - Browser initialized. 08:10:49 INFO - Running cycle 1/1 for kraken test... 08:10:49 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmp004eNy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:51 INFO - PROCESS | 1656 | 2016-12-22 08:10:51.591 plugin-container[1657:12277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:10:51 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:51 INFO - PROCESS | 1656 | 2016-12-22 08:10:51.592 plugin-container[1657:12277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:10:51 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:51 INFO - PROCESS | 1656 | 2016-12-22 08:10:51.593 plugin-container[1657:12277] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:11:01 INFO - PROCESS | 1656 | RSS: Main: 235233280 08:11:01 INFO - PROCESS | 1656 | 08:11:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 08:11:05 INFO - PROCESS | 1656 | RSS: Main: 248078336 08:11:05 INFO - PROCESS | 1656 | 08:11:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 08:11:08 INFO - PROCESS | 1656 | RSS: Main: 255664128 08:11:08 INFO - PROCESS | 1656 | 08:11:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 08:11:12 INFO - PROCESS | 1656 | RSS: Main: 258269184 08:11:12 INFO - PROCESS | 1656 | 08:11:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 08:11:14 INFO - PROCESS | 1656 | RSS: Main: 259575808 08:11:14 INFO - PROCESS | 1656 | 08:11:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:11:17 INFO - PROCESS | 1656 | RSS: Main: 259895296 08:11:17 INFO - PROCESS | 1656 | 08:11:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 08:11:20 INFO - PROCESS | 1656 | RSS: Main: 265977856 08:11:20 INFO - PROCESS | 1656 | 08:11:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 08:11:24 INFO - PROCESS | 1656 | RSS: Main: 264732672 08:11:24 INFO - PROCESS | 1656 | 08:11:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 08:11:28 INFO - PROCESS | 1656 | RSS: Main: 265011200 08:11:28 INFO - PROCESS | 1656 | 08:11:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:11:30 INFO - PROCESS | 1656 | RSS: Main: 263979008 08:11:30 INFO - PROCESS | 1656 | 08:11:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:11:32 INFO - PROCESS | 1656 | RSS: Main: 264232960 08:11:32 INFO - PROCESS | 1656 | 08:11:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:11:35 INFO - PROCESS | 1656 | RSS: Main: 265367552 08:11:35 INFO - PROCESS | 1656 | 08:11:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:11:39 INFO - PROCESS | 1656 | RSS: Main: 265756672 08:11:39 INFO - PROCESS | 1656 | 08:11:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:11:43 INFO - PROCESS | 1656 | RSS: Main: 265515008 08:11:43 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 08:11:45 INFO - PROCESS | 1656 | RSS: Main: 266178560 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | __start_tp_report 08:11:45 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:11:45 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:11:45 INFO - PROCESS | 1656 | |i|pagename|runs| 08:11:45 INFO - PROCESS | 1656 | |0;ai-astar;108;133;118;124;117;117;119;118;118;116 08:11:45 INFO - PROCESS | 1656 | |1;audio-beat-detection;141;113;125;141;109;101;98;105;169;146 08:11:45 INFO - PROCESS | 1656 | |2;audio-dft;195;203;210;207;198;190;198;198;204;185 08:11:45 INFO - PROCESS | 1656 | |3;audio-fft;74;61;60;83;59;59;76;86;99;49 08:11:45 INFO - PROCESS | 1656 | |4;audio-oscillator;91;70;83;72;72;73;83;72;72;83 08:11:45 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;90;88;88;87;90;86;106;88;89;87 08:11:45 INFO - PROCESS | 1656 | |6;imaging-darkroom;154;108;96;97;118;96;97;95;155;117 08:11:45 INFO - PROCESS | 1656 | |7;imaging-desaturate;102;124;97;93;91;92;92;93;97;92 08:11:45 INFO - PROCESS | 1656 | |8;json-parse-financial;60;59;59;60;59;62;62;61;62;61 08:11:45 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;68;78;76;78;77;71;72;71;72;69 08:11:45 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;101;81;94;112;112;81;110;105;112;80 08:11:45 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;154;154;128;136;167;157;157;145;213;138 08:11:45 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;187;234;163;235;230;240;209;174;170;163 08:11:45 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;68;69;66;67;67;63;68;67;71 08:11:45 INFO - PROCESS | 1656 | __end_tp_report 08:11:45 INFO - PROCESS | 1656 | __start_cc_report 08:11:45 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,913 08:11:45 INFO - PROCESS | 1656 | __end_cc_report 08:11:45 INFO - PROCESS | 1656 | __startTimestamp1482423105468__endTimestamp 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:11:45 INFO - PROCESS | 1656 | Number of tests: 14 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:118.80 Median:118.00 stddev:6.34 (5.4%) stddev-sans-first:5.39 08:11:45 INFO - PROCESS | 1656 | Values: 108.0 133.0 118.0 124.0 117.0 117.0 119.0 118.0 118.0 116.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:141.00 stddev:23.56 (16.7%) stddev-sans-first:24.24 08:11:45 INFO - PROCESS | 1656 | Values: 141.0 113.0 125.0 141.0 109.0 101.0 98.0 105.0 169.0 146.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:198.80 Median:200.50 stddev:7.61 (3.8%) stddev-sans-first:7.95 08:11:45 INFO - PROCESS | 1656 | Values: 195.0 203.0 210.0 207.0 198.0 190.0 198.0 198.0 204.0 185.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:70.60 Median:75.00 stddev:15.56 (20.7%) stddev-sans-first:16.45 08:11:45 INFO - PROCESS | 1656 | Values: 74.0 61.0 60.0 83.0 59.0 59.0 76.0 86.0 99.0 49.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.00 stddev:7.22 (9.3%) stddev-sans-first:5.64 08:11:45 INFO - PROCESS | 1656 | Values: 91.0 70.0 83.0 72.0 72.0 73.0 83.0 72.0 72.0 83.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:88.00 stddev:5.80 (6.6%) stddev-sans-first:6.15 08:11:45 INFO - PROCESS | 1656 | Values: 90.0 88.0 88.0 87.0 90.0 86.0 106.0 88.0 89.0 87.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:113.30 Median:95.50 stddev:23.39 (24.5%) stddev-sans-first:19.63 08:11:45 INFO - PROCESS | 1656 | Values: 154.0 108.0 96.0 97.0 118.0 96.0 97.0 95.0 155.0 117.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:92.50 stddev:9.98 (10.8%) stddev-sans-first:10.44 08:11:45 INFO - PROCESS | 1656 | Values: 102.0 124.0 97.0 93.0 91.0 92.0 92.0 93.0 97.0 92.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:61.00 stddev:1.27 (2.1%) stddev-sans-first:1.33 08:11:45 INFO - PROCESS | 1656 | Values: 60.0 59.0 59.0 60.0 59.0 62.0 62.0 61.0 62.0 61.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:74.00 stddev:3.74 (5.0%) stddev-sans-first:3.46 08:11:45 INFO - PROCESS | 1656 | Values: 68.0 78.0 76.0 78.0 77.0 71.0 72.0 71.0 72.0 69.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:98.80 Median:96.00 stddev:13.75 (14.3%) stddev-sans-first:14.56 08:11:45 INFO - PROCESS | 1656 | Values: 101.0 81.0 94.0 112.0 112.0 81.0 110.0 105.0 112.0 80.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:155.50 stddev:23.56 (15.2%) stddev-sans-first:24.99 08:11:45 INFO - PROCESS | 1656 | Values: 154.0 154.0 128.0 136.0 167.0 157.0 157.0 145.0 213.0 138.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.50 Median:219.50 stddev:32.39 (14.8%) stddev-sans-first:33.99 08:11:45 INFO - PROCESS | 1656 | Values: 187.0 234.0 163.0 235.0 230.0 240.0 209.0 174.0 170.0 163.0 08:11:45 INFO - PROCESS | 1656 | 08:11:45 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:2.07 (3.0%) stddev-sans-first:2.18 08:11:45 INFO - PROCESS | 1656 | Values: 68.0 68.0 69.0 66.0 67.0 67.0 63.0 68.0 67.0 71.0 08:11:45 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:11:45 INFO - PROCESS | 1656 | 08:11:46 INFO - TEST-INFO | 1656: exit 0 08:11:46 INFO - TEST-OK | kraken | took 60698ms 08:11:46 INFO - SUITE-END | took 1176s 08:11:46 INFO - Completed test suite (00:19:36) 08:11:46 INFO - javascript benchmark 08:11:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7077.057798987028, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.967340326597, 3318.5022246662984, 3327.9500807487875, 3376.966230337696, 3383.1372999885025, 5506.0, 5532.474414930585, 5541.612087153901, 5644.266245388104, 5648.604597678159, 5493.752781124849, 5521.4478552144765, 5563.527100196478, 5597.524210442118, 5683.374828768832, 4716.127516409458, 4926.704397736147, 5021.648484606079, 5186.170212765958, 5216.947830521684, 5032.094223039851, 5175.792968281265, 5199.454057323983, 5215.921761173586, 5247.553957913563, 5026.547610715053, 5071.594272458195, 5165.173572228426, 5238.4499627539135, 5276.1030624793875, 5033.7734801934, 5097.7196254206, 5127.256547800585, 5149.0731668299695, 5205.349331333583, 5102.336696229467, 5119.692818430906, 5123.564497017758, 5138.2806407103035, 5167.509086636801, 5412.810551630674, 5438.972805135949, 5626.85932851682, 5647.124695672177, 5671.744771485293, 5988.19159413478, 6064.120702498113, 6078.118672792464, 6092.0, 6120.14317995481, 6109.374301065099, 6121.209103132296, 6125.021800333839, 6152.077188421727, 6152.830962117184, 5640.492355688008, 5718.628289161191, 5735.684537350444, 5748.597598168171, 5776.9422305776825, 6175.2898416681755, 6187.195664563578, 6209.96895015531, 6302.558820882494, 6316.747330106836, 5243.292155559027, 5264.973330200574, 5914.970425147847, 6073.635581865102, 6086.60437071589, 4955.851324460272, 5075.56857667095, 5864.237649105674, 6081.695915204222, 6107.511399088063, 6072.423119803611, 6123.84690382744, 6130.63216207029, 6140.4473597376455, 6143.447089761943, 5410.729463526808, 6101.298350689727, 6112.419320164578, 6123.050927106306, 6138.324784273726, 3844.5386553613744, 4713.222318317507, 4755.690880092783, 4765.999140180532, 4799.904001919942, 3802.961970380261, 4250.936235956463, 4269.893252668646, 4279.807408666617, 4346.543612920661, 5742.540596752333, 5771.249737534094, 5773.162891380726, 5776.566757493205, 5782.421757824268, 6024.246969128859, 6096.0, 6192.256929168529, 6201.627902325875, 6217.751289948442, 5615.185798059259, 5682.914756278659, 5695.1741997410145, 5703.287089113861, 5745.253117094751, 4521.412216411911, 5214.2960700304975, 5235.921461178085, 5260.789568417221, 5272.393674727456, 6191.0, 6204.844878878064, 6238.438540531374, 6250.6874656267, 6260.373962603703, 6197.690115494117, 6202.7208775606105, 6203.751849925952, 6205.937940620536, 6259.405356491126, 6272.686365681607, 6275.246970363403, 6277.0, 6285.1515045470105, 6305.211848518935, 5884.823455296177, 5932.199153114274, 5932.5550583705535, 5956.37458066914, 6110.816675499742, 5843.444872737083, 5860.531157507304, 6115.270764408881, 6132.754689812357, 6191.102290167811, 5900.203472531325, 5906.143609176731, 5921.822345329647, 5933.258342707161, 5943.732532035982, 5789.507891829242, 5805.3614102447855, 5812.360640329476, 5922.348541660328, 5941.287045554561, 5523.475269849358, 5664.37691853904, 5723.427657234243, 5794.971025144847, 5796.42035796417, 4922.237053256751, 5921.082232254007, 5927.733252003584, 5930.7924222652, 5963.612365196248, 5681.375048744552, 5733.655980641175, 5759.51044161251, 5767.221425107725, 5779.306483222041], "value": 5545.423647388282, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.91986402379587, 459.8436531579263, 486.80771095417333, 502.226571080536, 525.7765449683886, 38719.0, 41174.38238426426, 41263.96840078999, 41288.380674289874, 41347.0, 42435.363469547905, 42955.71132866019, 43001.784991075, 43079.78460107703, 43128.92177695559, 9289.489078100702, 9482.0, 9487.715368538955, 9496.620135194584, 10935.398553079578, 8931.821363572724, 10958.342499450018, 11047.171462140332, 11061.391623460706, 11132.944335278313, 8617.698380556682, 9363.39137956034, 10403.531841067132, 10972.890271097267, 11154.721131971684, 10999.835002474969, 11057.834132487938, 11072.615923009664, 11077.667669969915, 11107.278026928305, 10962.013418792267, 11014.0, 11021.338719676845, 11057.11543076552, 11072.778544429148, 36177.0, 36588.26823463543, 36672.63327366719, 36709.71515996954, 36714.08214794625, 3067.969320306813, 3185.649578546358, 3340.515625234327, 3350.961202027355, 3690.5202323698018, 26976.0, 28099.85950070257, 28629.0, 28819.855900720573, 28924.27689307763, 3133.7493000559903, 3191.952120718191, 3199.600049993751, 3301.1912081539936, 3349.279904820475, 482428.0, 484773.0, 488048.55975719896, 488981.0, 491012.5449372766, 52526.47473525292, 53026.20460693093, 53040.46959530433, 53241.2013819793, 53370.19944700832, 55768.0, 55906.44093559094, 56336.436635633116, 56418.0, 56566.7171664139, 14550.417983280762, 14904.62738431548, 14998.550043498712, 15008.174550399834, 15063.0, 10586.57653693859, 10867.510962006729, 11370.658880233606, 11569.826452603218, 11651.650450486499, 16470.917645411697, 16473.752893706605, 16475.505734827973, 16522.50432487027, 16606.418775342805, 7486.288802563748, 7502.549847009197, 7552.0, 7609.592225438201, 7615.390768738488, 828.9005319361715, 850.7915560687547, 857.2716956635932, 892.6030761857672, 1009.2682804966487, 27149.86425067862, 27376.0, 27495.900163993218, 27942.86028569844, 27999.580006299922, 873.9038705742363, 938.9953050234706, 972.4991629310911, 1077.5689724110418, 1107.5293000474767, 13658.726825463435, 13738.900887928941, 14118.717625647634, 14424.134551926918, 14440.783388248974, 695708.0, 710203.0, 711845.3746894037, 847274.2908856372, 889276.0, 51180.48819511832, 51470.485295147315, 51788.0, 51969.740151299, 52022.0, 1327.1307293722627, 1340.3901224942629, 1354.2483921423507, 1368.4868174434587, 1385.3765805387616, 27702.307442314104, 27866.58200126959, 27918.0, 28588.0, 28695.42609147847, 44727.32909006302, 44814.77592612016, 45374.0, 45520.537535495954, 45606.771966141285, 485019.0, 487974.0, 489091.0, 489127.0, 489558.0, 22138.88930555337, 22702.864856756765, 24435.75564244406, 24816.379590510383, 24952.0, 40264.59735402608, 40508.0, 40588.39117413182, 40693.79653101716, 41337.0, 34192.31615367629, 34319.0, 34365.656343437244, 35171.472427914116, 35437.64562354344, 34209.14477137992, 34287.657123429446, 34692.47961280633, 35182.64817351794, 35183.29633407369, 33945.830270849474, 34085.48871766975, 34101.48847767335, 35547.82226088853, 36649.45025824668, 27653.861730691217, 30053.948111815982, 31876.840615796773, 31914.52128218125, 32136.35727285488, 19291.710624340925, 22358.88820555887, 23114.844257786706, 24279.393015174763, 24374.87812560926], "value": 19052.58213157449, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.9709327613876, 336.4734190991099, 376.2024508042949, 385.8148088917321, 399.96010373030106, 18913.82491013534, 19291.710624340627, 20175.394738157844, 20765.961701914883, 21011.0, 19138.42584722457, 19968.20127194914, 20242.898785506088, 20754.89622551889, 20808.47978800531, 4459.643228541709, 4528.796204170821, 5095.235714642806, 5283.022640811462, 5291.735413229342, 4755.381800365947, 5871.588988770788, 5875.559333050017, 5878.882422351572, 5882.529397648178, 5329.280547126146, 5822.388649191837, 5854.034084376073, 5880.441358070976, 5893.469587737103, 6481.416672499474, 6522.0, 6528.967355163218, 6534.803955881332, 6551.541392102555, 5568.053430916754, 5714.885702285931, 5760.481556659879, 5779.988502012131, 5861.003629382973, 12345.321007344592, 12718.491260349574, 12752.80870786939, 22026.33920982357, 24685.5062898741, 2247.71903512061, 2300.9851434019247, 2325.6744055832196, 2326.2439707095264, 2463.6550882876413, 9720.465374404404, 9773.33853244943, 9786.266030047704, 10135.290529662927, 10170.59317627294, 1857.944261672152, 1887.4526387347653, 1898.9620207595908, 1909.1408866010213, 1916.281394477071, 111001.44499277533, 114163.42918285438, 114215.0, 116623.41688291589, 118753.40623296828, 11165.83251251232, 11466.0, 11636.88363116358, 11639.883601164049, 11757.823632645517, 14595.78106328406, 14664.340104695315, 14672.193029383487, 14684.412623495155, 14701.338439770236, 8104.918950810535, 8304.418690691593, 8329.37529685276, 8374.371922105867, 8490.23587877094, 8167.428280020342, 8171.509709417454, 8175.836483270301, 8300.460470069425, 8335.583220838933, 3680.429533422324, 4522.208613492691, 4551.95448045522, 4562.064776720765, 4590.563896429834, 1860.135037207705, 1917.3960202536157, 1927.7783054948861, 1961.4900125967351, 1970.645283848921, 797.784598158494, 811.5414790643385, 857.4640849469082, 877.1929824561419, 970.3401686852867, 19560.0, 19745.81525108498, 20634.0, 21016.579668406546, 21529.0, 924.6440120553501, 944.6619616449233, 975.8633791269228, 988.5205675247499, 990.9950450247846, 9133.858267716536, 9325.834270716161, 9468.763280918032, 10706.625268115578, 11421.885781142248, 532041.0, 536130.0, 542135.0, 553877.0, 560153.0, 102964.0, 106758.86482270467, 127269.0, 131385.0, 140058.0, 853.1425916953547, 975.8389865672226, 1007.6120693532896, 1024.2215915903819, 1028.6399760083912, 16960.406385776438, 17202.31190752356, 17811.643767124846, 19538.925359105386, 19685.40943771689, 43571.0, 51108.74445627748, 52671.73664131655, 53681.19478207752, 53772.62273772591, 70397.64801176166, 70592.6470367645, 72753.27246727466, 73531.63234183795, 78799.81800273115, 5033.6476446648385, 5403.864903377447, 6895.586264824126, 7257.383122434652, 7258.49190556656, 11255.831162532731, 12412.882840544395, 12504.74990500203, 12590.685232868886, 13129.212247265195, 10967.50646220938, 11022.779544408906, 11340.829887551858, 11490.482928268415, 11507.654770356903, 10685.358878467317, 10984.670459886216, 11210.943945280222, 11288.717782055513, 11426.771464570827, 10554.208434367607, 10878.40168790724, 11171.832422513506, 11631.360275184608, 11850.703732406759, 7143.964280178566, 7296.708131674886, 7331.78004659861, 7446.367058800062, 7545.547267163988, 9340.626374945197, 11246.381449020055, 11496.770064598828, 11590.246633968765, 11608.0], "value": 9200.85530174325, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1465.45778062117, 1484.2282013353056, 1703.5996540812907, 1774.1218097041967, 1849.6578133045386, 7488.887666685004, 7734.458587898849, 7935.801604959872, 8411.285040771534, 8412.495250284986, 7885.881711774328, 8171.8365632687455, 8193.139720329355, 8271.751847444571, 8274.089850116483, 2257.48077942073, 2306.1582522379376, 2313.710786151731, 2314.0743702519044, 2316.6061769499142, 2295.0819672131197, 2300.850444721096, 2333.8132949364094, 2334.684817549626, 2340.192633541434, 2266.8073213776847, 2277.7038984931937, 2301.6892719482908, 2302.021640802652, 2303.5738388398117, 1754.3684273661472, 2275.8862056897087, 2292.0, 2295.942601434969, 2303.7235531736214, 2279.1567120165646, 2299.7585253548305, 2319.2694301295205, 2319.860808351504, 2334.5330933813307, 8249.463784853966, 8295.585220739, 8316.875246871241, 8317.376196785266, 8321.500709957421, 5012.323336349583, 5171.327727395452, 5176.7929282828645, 5182.455842136559, 5183.844484665428, 3211.4379983502795, 3301.4222511060707, 3490.127468132967, 3491.877784277538, 3495.860165593373, 1032.1381646325328, 1047.6438011076195, 1052.9102379037736, 1068.7702144038992, 1089.629525961169, 10544.209184311207, 10593.311434756715, 10615.840762388492, 10737.785244295148, 10743.247972641919, 4411.470623525133, 4540.79566419512, 4583.633309335245, 4622.14490319292, 4625.098105869391, 9224.7693807654, 9244.7688807779, 9249.445033298023, 9271.304652151115, 9273.0, 5115.411727651293, 5141.434442211354, 5148.716820574829, 5151.0, 5161.612879034087, 3486.9302613948084, 3658.396364599811, 3863.9806800965816, 4571.3600095986585, 4706.176419126639, 3459.325431540876, 3531.7462300882817, 3806.1816709407613, 3847.115163512352, 3948.723589348718, 1105.2042529378834, 1228.3244215681339, 1246.4328730427633, 1252.1485389934933, 1265.816456613786, 692.8614277144591, 750.8385697075155, 810.5015415519531, 826.4215348683985, 843.9535825529532, 5440.782368705208, 5618.606697531133, 5694.259746233046, 5714.485696287354, 5741.85645358861, 887.8002449448951, 906.3429013964823, 914.2548822709471, 924.1220840201835, 944.2021491839384, 3679.9264014719556, 3731.0858839584475, 3784.7729136251364, 3887.2614203301846, 3925.2345792570745, 9779.168770654433, 10060.39637621745, 10063.396196228103, 10063.496825158712, 10133.13868321201, 9900.55447504864, 10002.799944001079, 10026.498675066217, 10031.99680031991, 10054.899451005542, 7655.311022007934, 7747.92252077472, 7883.881741773878, 7918.841623167505, 7920.841583168304, 5425.457454254543, 5505.9724701376235, 5548.805791797267, 5559.249501317432, 5629.915551266652, 9270.0, 9273.350865439354, 9290.256779457763, 9327.720168394959, 9329.533523323671, 9105.726828195166, 9229.538523073954, 9309.208717258838, 9390.483523406278, 9470.668526601536, 6558.245801732862, 6570.080188773671, 6699.028640846953, 6710.194776626836, 6724.7646332378135, 7633.5419874807685, 7725.729599464216, 7745.65144568507, 7748.57382843949, 7764.5729484878875, 7006.544574602838, 7046.154461464657, 7076.2923707628825, 7109.253528379652, 7245.203027666847, 5754.942450575608, 6906.033155358355, 6943.166819981635, 6963.79108626742, 6974.302569742985, 6467.159269294962, 6551.639659818565, 6605.867882642224, 6614.305497922841, 6641.50188735837, 4712.0, 4806.807727690853, 4829.227323628203, 4858.198397264553, 4943.777530011086, 4778.689385189951, 4824.324594556835, 4875.000624871964, 4919.581835544019, 4977.407229686465], "value": 4348.603604971349, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.429064914838, 354.73040489228185, 363.16472114137486, 367.35345791407116, 369.50670854409344, 5146.716930568818, 5208.343744690207, 5300.628955973083, 5382.219578161169, 5383.89232215356, 5087.364079490064, 5196.714180720059, 5307.601929855257, 5319.468053194679, 5321.4412486688925, 3374.493825926106, 3479.982600086996, 3559.4516385372317, 4018.8794336169963, 4181.762756925045, 3782.186829831586, 3833.310004199257, 4169.666426685858, 4173.436586060889, 4208.45290112285, 3159.7788154829173, 3968.8964848630126, 4015.5783642717533, 4032.1330913853517, 4035.57626449223, 3488.70917760429, 3646.3801153803784, 4018.4976877890263, 4020.43713880057, 4024.839006439739, 3491.074865160709, 3511.087117349482, 3513.1568423578415, 3529.9117522061897, 3551.5027896094566, 4539.116266749295, 4852.902941941142, 4950.653454258203, 5220.947790522122, 5226.973865130688, 4561.315802629599, 4582.839600613963, 4586.312053192015, 4597.057603191338, 4618.745968971705, 4557.8377513733785, 5161.870953226199, 5273.626867909417, 5645.802396916089, 5652.434756524356, 3477.7043951264172, 3672.155404257009, 3811.4473401356927, 3813.8474461021524, 3824.8661296854757, 4577.656675749299, 4680.602148817321, 4748.765321016561, 5755.338136114358, 5779.826605201851, 3821.923561528754, 3830.272248272819, 3856.5564960029956, 3857.691384689218, 3861.189150278417, 3545.592256890439, 3869.090763670535, 3895.7078219133678, 3908.902277443087, 3910.4916360872903, 3772.5850156482766, 3883.359245724424, 4043.3732771420478, 4248.91502169961, 4389.297712366006, 5362.533234711998, 5403.864903377447, 5632.295963004624, 5646.407127251661, 5654.6324488908085, 1079.6545105566142, 1081.545321542524, 1127.7800828838463, 1169.2867350915935, 1250.012490132785, 746.3357611725569, 754.8037510247375, 830.6760363458257, 872.8690696395591, 883.8143989762222, 4580.908381832344, 4645.86062418128, 4987.1771157759285, 5055.786611213282, 5121.4622464641425, 4966.1941003333, 5052.57053150479, 5133.6919784813035, 5266.552343050884, 5552.972235138798, 4444.555544445529, 4545.0909818036525, 4710.69380490267, 5291.973540132275, 5335.893282134413, 2685.3018215264174, 2930.593315208712, 2939.5884576159356, 2950.985245073761, 2953.2321596385095, 4854.344663470386, 5019.598432125421, 5154.664946778447, 5428.484293992144, 5821.650700957872, 4843.505594618774, 4953.7275449849885, 5569.415211402745, 5688.516476099495, 5747.103455859557, 5109.616778741609, 5530.419305972895, 5769.365369809318, 5799.2750906136735, 5828.193260089386, 4512.887177820581, 4585.908281834411, 4782.689125206851, 5219.859857458424, 5555.500004999408, 5040.521150490697, 5054.241863720325, 5351.839444816662, 5436.891262174813, 5521.012435523288, 4829.154897892784, 4971.701697898137, 5449.468789179065, 5795.826125216082, 5802.390748971297, 2999.4600971824407, 3588.802615856153, 3858.864939727096, 3880.7477513961503, 3893.9221215576094, 5777.8555536111935, 5785.363610002981, 5791.131330300489, 5805.535557155333, 5806.274215723035, 4431.4903786065015, 4857.032901674827, 5374.462553744751, 5526.956126886022, 5617.269754931833, 4374.606285434326, 4876.29293752411, 4955.45489996093, 5230.2677625131755, 5532.529734972572, 5254.0, 5386.145342968233, 5462.590305727007, 5469.78826303736, 5535.833924982257, 5477.972610136924, 5605.122284034894, 5616.079638587993, 5633.324001119892, 5641.464060914206, 4479.641628669764, 4900.215965445512, 4963.70217786934, 5229.47705229474, 5508.504234618904], "value": 3969.862881673828, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.9184989651137, 761.7714685594324, 773.1958762886599, 797.4099166616705, 846.4117438380326, 13001.934990325048, 15591.766123508141, 15668.451604193855, 15739.055656660608, 15844.0, 13138.737225255489, 14415.351309191085, 14486.782698259534, 14608.0, 14681.0, 876.9870799226887, 885.6501681835671, 894.6555576103215, 895.4269267668698, 901.1664210605197, 863.6027427383411, 899.9775005624879, 900.3337530227617, 901.6889173235256, 910.3945875992457, 945.6879229854165, 946.2099147212086, 946.6213514594149, 947.4930911962101, 951.015698751792, 933.6078846884325, 937.7233716053746, 937.8499440089587, 938.6573900526292, 952.8665986761858, 700.2997002997002, 700.9053777739991, 701.4598758955626, 703.3634560722554, 703.5145749432875, 12321.753564928651, 14633.634159146, 15259.923700381427, 15390.846091539166, 15469.149196794284, 13783.655408614764, 13930.721385572333, 13971.70902699504, 14178.220197889112, 14188.787168192486, 10067.899321006842, 11595.94202028993, 11723.120765942504, 11813.704657383549, 12082.879171208264, 3639.108418437473, 3673.761205521659, 3701.9444708329397, 3706.2402207547657, 3825.431573055062, 109696.47545548527, 110656.34015489774, 110852.0, 111689.0, 112882.87117128723, 9206.427933736812, 10707.571697132184, 10727.624533141303, 10773.192010599236, 10788.244822862323, 18818.341358052403, 21486.0, 22246.666300005512, 22262.777372226396, 22350.217742379264, 6890.414314783199, 7652.273034061755, 7713.0, 7731.922680773233, 7794.415418843496, 4276.102018576134, 4328.220920234325, 4367.890802729958, 4420.778961051935, 4499.190145773792, 3513.244652399695, 3574.1422058705725, 3634.0914771307175, 3634.909527141847, 3647.598764135943, 637.7831537277348, 644.7906658875097, 655.8688262347549, 663.141232104426, 669.8995150727333, 591.6420565557776, 608.3247595169358, 653.3629711031801, 662.483263054818, 679.1341040173719, 7769.0, 8206.384521160937, 8911.197992180605, 9439.716808495756, 9640.807183856283, 634.30226750574, 648.8799572079087, 657.3590749019763, 662.7282814046218, 698.2424069884131, 4309.0, 4416.1830061438095, 4473.1277400907165, 4477.395551600493, 4490.903208143596, 79895.0, 90134.0, 116126.0, 116193.0, 116493.0, 70087.0, 70521.64739176272, 73261.53476930538, 77236.0, 88423.11576884148, 79652.60173699094, 80718.0, 81507.18492815114, 81578.51318177558, 82529.76205356924, 9852.852207216754, 10059.547320370748, 10089.596416143566, 10143.442110683984, 10147.79704405893, 50593.73515662138, 52710.57381270205, 53708.19437708516, 53716.92566148577, 54342.72828635832, 60879.0, 62236.68881655563, 70135.64932175307, 72669.6366518164, 74830.0, 13147.539836106072, 13184.868151318748, 13286.80069798973, 13701.177929324273, 13781.862181378057, 14729.55811325662, 14773.03975241649, 14833.035852669542, 14893.776593351326, 15037.924810375878, 13665.795013074612, 13799.862001379857, 13814.861851381358, 13933.23367214813, 14104.81431900425, 12330.075244356889, 12773.425195866022, 13677.247751373372, 13827.58517244444, 13850.30748462593, 13250.0, 13544.119632223874, 13552.59342219735, 13620.387082581507, 13890.513832015831, 11099.167562432689, 11119.888011199138, 11350.829737553779, 11606.013488853541, 11619.477123529292, 8720.0, 8928.0, 8933.46399216049, 10466.581336746445, 10513.89486105129], "value": 7485.606084790358, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 133.0, 118.0, 124.0, 117.0, 117.0, 119.0, 118.0, 118.0, 116.0], "value": 118.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 125.0, 141.0, 109.0, 101.0, 98.0, 105.0, 169.0, 146.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 203.0, 210.0, 207.0, 198.0, 190.0, 198.0, 198.0, 204.0, 185.0], "value": 198.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 61.0, 60.0, 83.0, 59.0, 59.0, 76.0, 86.0, 99.0, 49.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 83.0, 72.0, 72.0, 73.0, 83.0, 72.0, 72.0, 83.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 88.0, 87.0, 90.0, 86.0, 106.0, 88.0, 89.0, 87.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 108.0, 96.0, 97.0, 118.0, 96.0, 97.0, 95.0, 155.0, 117.0], "value": 113.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 124.0, 97.0, 93.0, 91.0, 92.0, 92.0, 93.0, 97.0, 92.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 60.0, 59.0, 62.0, 62.0, 61.0, 62.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 76.0, 78.0, 77.0, 71.0, 72.0, 71.0, 72.0, 69.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 81.0, 94.0, 112.0, 112.0, 81.0, 110.0, 105.0, 112.0, 80.0], "value": 98.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 128.0, 136.0, 167.0, 157.0, 157.0, 145.0, 213.0, 138.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 234.0, 163.0, 235.0, 230.0, 240.0, 209.0, 174.0, 170.0, 163.0], "value": 200.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 66.0, 67.0, 67.0, 63.0, 68.0, 67.0, 71.0], "value": 67.4, "unit": "score"}]}]} 08:11:46 INFO - Return code: 0 08:11:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:11:46 ERROR - # TBPL SUCCESS # 08:11:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:11:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:11:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:11:46 INFO - 2016-12-22 08:11:46.775 screenresolution[1661:12668] starting screenresolution argv=screenresolution get 08:11:46 INFO - 2016-12-22 08:11:46.778 screenresolution[1661:12668] Display 0: 1600x1200x32@60 08:11:46 INFO - 2016-12-22 08:11:46.784 screenresolution[1662:12671] starting screenresolution argv=screenresolution list 08:11:46 INFO - Available Modes on Display 0 08:11:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:11:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:11:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:11:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:11:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:11:47 INFO - Intel Iris: 08:11:47 INFO - Chipset Model: Intel Iris 08:11:47 INFO - Type: GPU 08:11:47 INFO - Bus: Built-In 08:11:47 INFO - VRAM (Dynamic, Max): 1536 MB 08:11:47 INFO - Vendor: Intel (0x8086) 08:11:47 INFO - Device ID: 0x0a2e 08:11:47 INFO - Revision ID: 0x0009 08:11:47 INFO - Displays: 08:11:47 INFO - Display: 08:11:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:11:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:11:47 INFO - Display Serial Number: 1600x1200 60 08:11:47 INFO - Main Display: Yes 08:11:47 INFO - Mirror: Off 08:11:47 INFO - Online: Yes 08:11:47 INFO - Rotation: Supported 08:11:47 INFO - Return code: 0 08:11:47 INFO - Running post-action listener: _resource_record_post_action 08:11:47 INFO - [mozharness: 2016-12-22 16:11:47.018575Z] Finished run-tests step (success) 08:11:47 INFO - Running post-run listener: _resource_record_post_run 08:11:47 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 33227264; Write bytes: 555097600; Read time: 206; Write time: 1706 08:11:47 INFO - TinderboxPrint: CPU usage
54.6% 08:11:47 INFO - TinderboxPrint: I/O read bytes / time
33,227,264 / 206 08:11:47 INFO - TinderboxPrint: I/O write bytes / time
555,097,600 / 1,706 08:11:47 INFO - TinderboxPrint: CPU idle
2,175.7 (45.3%) 08:11:47 INFO - TinderboxPrint: CPU system
968.0 (20.2%) 08:11:47 INFO - TinderboxPrint: CPU user
1,654.0 (34.5%) 08:11:47 INFO - TinderboxPrint: Swap in / out
279,011,328 / 0 08:11:47 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250632704; Write bytes: 252298752; Read time: 18457; Write time: 560 08:11:47 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30011904; Write bytes: 301193216; Read time: 183; Write time: 1140 08:11:47 INFO - Running post-run listener: _upload_blobber_files 08:11:47 INFO - Blob upload gear active. 08:11:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:11:47 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 08:11:47 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'] 08:11:47 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 08:11:47 INFO - (blobuploader) - INFO - Open directory for files ... 08:11:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:11:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:11:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:48 INFO - (blobuploader) - INFO - Done attempting. 08:11:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:11:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:11:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:49 INFO - (blobuploader) - INFO - Done attempting. 08:11:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:11:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:11:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:50 INFO - (blobuploader) - INFO - Done attempting. 08:11:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:11:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:11:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:52 INFO - (blobuploader) - INFO - Done attempting. 08:11:52 INFO - (blobuploader) - INFO - Iteration through files over. 08:11:52 INFO - Return code: 0 08:11:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:11:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:11:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43eaa01700da9f9afe9bfe3e867caa3a3dbdd85af736774d2d4b73548ba757b3895e5519ecbb6aaacb9f014f5f398a2ecfaf0d8e9d04a7ca1da19e236c27ab84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe1349aa3343993a6062baa5501fac5506d70ccdf788dc8cae746003fa5817780c2a29130898d7fd3d421543327d97b92d23ebbf8022fd70a89e8e101b5b6c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9180b978612ce3f1d2844eace281b4fb4799b70df435ceb537db21e50f4e988cd89c1e4df2aad57dc3d603ccc805505e080fcd1994edff02d7e0ad639bd37742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b5f0075068f02020b098e5b69537b53a5e4fec535029840a7d07b4098bf285336fc86dee34ff9654a7e01a431aae075bc0092e289d497f751a34aba9ecc946"} 08:11:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:11:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:11:52 INFO - Contents: 08:11:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43eaa01700da9f9afe9bfe3e867caa3a3dbdd85af736774d2d4b73548ba757b3895e5519ecbb6aaacb9f014f5f398a2ecfaf0d8e9d04a7ca1da19e236c27ab84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe1349aa3343993a6062baa5501fac5506d70ccdf788dc8cae746003fa5817780c2a29130898d7fd3d421543327d97b92d23ebbf8022fd70a89e8e101b5b6c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9180b978612ce3f1d2844eace281b4fb4799b70df435ceb537db21e50f4e988cd89c1e4df2aad57dc3d603ccc805505e080fcd1994edff02d7e0ad639bd37742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b5f0075068f02020b098e5b69537b53a5e4fec535029840a7d07b4098bf285336fc86dee34ff9654a7e01a431aae075bc0092e289d497f751a34aba9ecc946"} 08:11:52 INFO - Running post-run listener: copy_logs_to_upload_dir 08:11:52 INFO - Copying logs to upload dir... 08:11:52 INFO - mkdir: /builds/slave/test/build/upload/logs 08:11:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.992008 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-22 08:11:52.928401) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 08:11:52.931941) ========= 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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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/43eaa01700da9f9afe9bfe3e867caa3a3dbdd85af736774d2d4b73548ba757b3895e5519ecbb6aaacb9f014f5f398a2ecfaf0d8e9d04a7ca1da19e236c27ab84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe1349aa3343993a6062baa5501fac5506d70ccdf788dc8cae746003fa5817780c2a29130898d7fd3d421543327d97b92d23ebbf8022fd70a89e8e101b5b6c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9180b978612ce3f1d2844eace281b4fb4799b70df435ceb537db21e50f4e988cd89c1e4df2aad57dc3d603ccc805505e080fcd1994edff02d7e0ad639bd37742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b5f0075068f02020b098e5b69537b53a5e4fec535029840a7d07b4098bf285336fc86dee34ff9654a7e01a431aae075bc0092e289d497f751a34aba9ecc946"} build_url:https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/IvIDmfZMR_OAPcgKeg5NmA/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/43eaa01700da9f9afe9bfe3e867caa3a3dbdd85af736774d2d4b73548ba757b3895e5519ecbb6aaacb9f014f5f398a2ecfaf0d8e9d04a7ca1da19e236c27ab84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe1349aa3343993a6062baa5501fac5506d70ccdf788dc8cae746003fa5817780c2a29130898d7fd3d421543327d97b92d23ebbf8022fd70a89e8e101b5b6c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9180b978612ce3f1d2844eace281b4fb4799b70df435ceb537db21e50f4e988cd89c1e4df2aad57dc3d603ccc805505e080fcd1994edff02d7e0ad639bd37742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b5f0075068f02020b098e5b69537b53a5e4fec535029840a7d07b4098bf285336fc86dee34ff9654a7e01a431aae075bc0092e289d497f751a34aba9ecc946"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 08:11:52.967260) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:11:52.967646) ========= 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.vA9QZQ6LWg/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.cDUmDsFU89/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.005044 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:11:53.016493) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 08:11:53.016832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 08:11:53.017189) ========= ========= Total master_lag: 0.19 =========