builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0114 starttime: 1482150992.79 results: success (0) buildid: 20161219030207 builduid: c30bb37e85bb4dfcb67f74a5e58d3911 revision: 863c2b61bd27bb6099104933134d3be7c052551a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:32.794838) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:32.800757) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:32.801067) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:32.827356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:32.827697) ========= 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.FeWixSUvhr/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.KM26809fDu/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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-19 04:36:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-12-19 04:36:32 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141912 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:32.993600) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:32.993913) ========= 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.FeWixSUvhr/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.KM26809fDu/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.027011 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:33.043071) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:33.043566) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 863c2b61bd27bb6099104933134d3be7c052551a --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 863c2b61bd27bb6099104933134d3be7c052551a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FeWixSUvhr/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.KM26809fDu/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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-19 04:36:33,136 truncating revision to first 12 chars 2016-12-19 04:36:33,136 Setting DEBUG logging. 2016-12-19 04:36:33,136 attempt 1/10 2016-12-19 04:36:33,136 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/863c2b61bd27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-19 04:36:33,506 unpacking tar archive at: mozilla-central-863c2b61bd27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.646410 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:33.713167) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:33.713489) ========= script_repo_revision: 863c2b61bd27bb6099104933134d3be7c052551a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:33.713860) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:33.714143) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 04:36:33.733826) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-19 04:36:33.734205) ========= /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.FeWixSUvhr/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.KM26809fDu/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:36:33 INFO - MultiFileLogger online at 20161219 04:36:33 in /builds/slave/test 04:36:33 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 04:36:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:36:33 INFO - {'append_to_log': False, 04:36:33 INFO - 'base_work_dir': '/builds/slave/test', 04:36:33 INFO - 'blob_upload_branch': 'Firefox', 04:36:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:36:33 INFO - 'branch': 'Firefox', 04:36:33 INFO - 'buildbot_json_path': 'buildprops.json', 04:36:33 INFO - 'config_files': ('talos/mac_config.py',), 04:36:33 INFO - 'default_actions': ('clobber', 04:36:33 INFO - 'read-buildbot-config', 04:36:33 INFO - 'download-and-extract', 04:36:33 INFO - 'populate-webroot', 04:36:33 INFO - 'create-virtualenv', 04:36:33 INFO - 'install', 04:36:33 INFO - 'run-tests'), 04:36:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:36:33 INFO - 'download_minidump_stackwalk': True, 04:36:33 INFO - 'download_symbols': 'ondemand', 04:36:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:36:33 INFO - 'tooltool.py': '/tools/tooltool.py', 04:36:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:36:33 INFO - '/tools/misc-python/virtualenv.py')}, 04:36:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:36:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:36:33 INFO - 'installer_path': 'installer.exe', 04:36:33 INFO - 'log_level': 'info', 04:36:33 INFO - 'log_name': 'talos', 04:36:33 INFO - 'log_to_console': True, 04:36:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:36:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:36:33 INFO - 'opt_config_files': (), 04:36:33 INFO - 'pip_index': False, 04:36:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:36:33 INFO - 'cmd': ('bash', 04:36:33 INFO - '-c', 04:36:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:36:33 INFO - 'enabled': True, 04:36:33 INFO - 'halt_on_failure': False, 04:36:33 INFO - 'name': 'check_screen_resolution'},), 04:36:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:36:33 INFO - 'cmd': ('bash', 04:36:33 INFO - '-c', 04:36:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:36:33 INFO - 'enabled': True, 04:36:33 INFO - 'halt_on_failure': False, 04:36:33 INFO - 'name': 'check_screen_resolution'},), 04:36:33 INFO - 'run_cmd_checks_enabled': True, 04:36:33 INFO - 'sps_profile': False, 04:36:33 INFO - 'sps_profile_interval': 0, 04:36:33 INFO - 'suite': 'dromaeojs-e10s', 04:36:33 INFO - 'system_bits': '32', 04:36:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:36:33 INFO - 'title': 't-yosemite-r7-0114', 04:36:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:36:33 INFO - 'use_talos_json': True, 04:36:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:36:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:36:33 INFO - 'work_dir': 'build'} 04:36:33 INFO - [mozharness: 2016-12-19 12:36:33.942108Z] Running clobber step. 04:36:33 INFO - Running pre-action listener: _resource_record_pre_action 04:36:33 INFO - Running main action method: clobber 04:36:33 INFO - rmtree: /builds/slave/test/build 04:36:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:36:36 INFO - Running post-action listener: _resource_record_post_action 04:36:36 INFO - [mozharness: 2016-12-19 12:36:36.450346Z] Finished clobber step (success) 04:36:36 INFO - [mozharness: 2016-12-19 12:36:36.450483Z] Running read-buildbot-config step. 04:36:36 INFO - Running pre-action listener: _resource_record_pre_action 04:36:36 INFO - Running main action method: read_buildbot_config 04:36:36 INFO - Using buildbot properties: 04:36:36 INFO - { 04:36:36 INFO - "project": "", 04:36:36 INFO - "product": "firefox", 04:36:36 INFO - "script_repo_revision": "production", 04:36:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:36:36 INFO - "repository": "", 04:36:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:36:36 INFO - "buildid": "20161219030207", 04:36:36 INFO - "stage_platform": "macosx64", 04:36:36 INFO - "basedir": "/builds/slave/test", 04:36:36 INFO - "nightly_build": "True", 04:36:36 INFO - "buildnumber": 40, 04:36:36 INFO - "slavename": "t-yosemite-r7-0114", 04:36:36 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:36:36 INFO - "platform": "yosemite_r7", 04:36:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:36:36 INFO - "branch": "mozilla-central", 04:36:36 INFO - "repo_path": "mozilla-central", 04:36:36 INFO - "revision": "863c2b61bd27bb6099104933134d3be7c052551a", 04:36:36 INFO - "pgo_build": "False", 04:36:36 INFO - "builduid": "c30bb37e85bb4dfcb67f74a5e58d3911", 04:36:36 INFO - "slavebuilddir": "test" 04:36:36 INFO - } 04:36:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:36:36 INFO - Running post-action listener: _resource_record_post_action 04:36:36 INFO - [mozharness: 2016-12-19 12:36:36.454004Z] Finished read-buildbot-config step (success) 04:36:36 INFO - [mozharness: 2016-12-19 12:36:36.454141Z] Running download-and-extract step. 04:36:36 INFO - Running pre-action listener: _resource_record_pre_action 04:36:36 INFO - Running main action method: download_and_extract 04:36:36 INFO - mkdir: /builds/slave/test/build/tests 04:36: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')]} 04:36:36 INFO - trying https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:36:36 INFO - Downloading https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/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 04:36:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/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 04:36:37 INFO - Downloaded 1199 bytes. 04:36:37 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:36:37 INFO - Using the following test package requirements: 04:36:37 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:36:37 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:36:37 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:36:37 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'jsshell-mac.zip'], 04:36:37 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:36:37 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:36:37 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:36:37 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:36:37 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:36:37 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:37 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:36:37 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:36:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:36:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:36:37 INFO - Fetch https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:36:39 INFO - Expected file size: 16276165 04:36:39 INFO - Obtained file size: 16276165 04:36:45 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 04:36:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:36:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:36:45 INFO - Fetch https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:36:46 INFO - Expected file size: 16276165 04:36:46 INFO - Obtained file size: 16276165 04:36:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:36:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:36:53 INFO - Fetch https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:36:54 INFO - Expected file size: 14409734 04:36:54 INFO - Obtained file size: 14409734 04:36:54 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')]} 04:36:54 INFO - trying https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:36:54 INFO - Downloading https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:36:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/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 04:36:57 INFO - Downloaded 69622939 bytes. 04:36:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:36:57 INFO - mkdir: /builds/slave/test/properties 04:36:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:36:57 INFO - Writing to file /builds/slave/test/properties/build_url 04:36:57 INFO - Contents: 04:36:57 INFO - build_url:https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:36:58 INFO - Running post-action listener: _resource_record_post_action 04:36:58 INFO - Running post-action listener: set_extra_try_arguments 04:36:58 INFO - [mozharness: 2016-12-19 12:36:58.590772Z] Finished download-and-extract step (success) 04:36:58 INFO - [mozharness: 2016-12-19 12:36:58.590875Z] Running populate-webroot step. 04:36:58 INFO - Running pre-action listener: _resource_record_pre_action 04:36:58 INFO - Running main action method: populate_webroot 04:36:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:36:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:36:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:36:58 INFO - '--tppagecycles', 04:36:58 INFO - '7'], 04:36:58 INFO - 'tests': ['tsvgm']}}, 04:36:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:36:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:36:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:36:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:36:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:36:58 INFO - 'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:36:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:36:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:36:58 INFO - 'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['damp', 'tps']}, 04:36:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:36:58 INFO - 'tests': ['damp', 'tps']}, 04:36:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['dromaeo_dom']}, 04:36:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:36:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['basic_compositor_video']}, 04:36:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:36:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['a11yr', 04:36:58 INFO - 'ts_paint', 04:36:58 INFO - 'tpaint', 04:36:58 INFO - 'sessionrestore', 04:36:58 INFO - 'sessionrestore_no_auto_restore', 04:36:58 INFO - 'tabpaint']}, 04:36:58 INFO - 'other-e10s': {'tests': ['a11yr', 04:36:58 INFO - 'ts_paint', 04:36:58 INFO - 'tpaint', 04:36:58 INFO - 'sessionrestore', 04:36:58 INFO - 'sessionrestore_no_auto_restore', 04:36:58 INFO - 'tabpaint']}, 04:36:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:36:58 INFO - 'ts_paint', 04:36:58 INFO - 'tpaint', 04:36:58 INFO - 'sessionrestore', 04:36:58 INFO - 'sessionrestore_no_auto_restore', 04:36:58 INFO - 'tabpaint']}, 04:36:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:36:58 INFO - 'ts_paint', 04:36:58 INFO - 'tpaint', 04:36:58 INFO - 'sessionrestore', 04:36:58 INFO - 'sessionrestore_no_auto_restore', 04:36:58 INFO - 'tabpaint']}, 04:36:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['a11yr', 04:36:58 INFO - 'ts_paint', 04:36:58 INFO - 'tpaint', 04:36:58 INFO - 'sessionrestore', 04:36:58 INFO - 'sessionrestore_no_auto_restore', 04:36:58 INFO - 'tabpaint']}, 04:36:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['a11yr', 04:36:58 INFO - 'ts_paint', 04:36:58 INFO - 'tpaint', 04:36:58 INFO - 'sessionrestore', 04:36:58 INFO - 'sessionrestore_no_auto_restore', 04:36:58 INFO - 'tabpaint']}, 04:36:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['tsvgx', 04:36:58 INFO - 'tsvgr_opacity', 04:36:58 INFO - 'tart', 04:36:58 INFO - 'tscrollx', 04:36:58 INFO - 'cart', 04:36:58 INFO - 'tsvg_static']}, 04:36:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:36:58 INFO - 'tsvgr_opacity', 04:36:58 INFO - 'tart', 04:36:58 INFO - 'tscrollx', 04:36:58 INFO - 'cart', 04:36:58 INFO - 'tsvg_static']}, 04:36:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:36:58 INFO - 'talos_options': ['--disable-e10s'], 04:36:58 INFO - 'tests': ['tp5o']}, 04:36:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:36:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:36:58 INFO - 'talos_options': ['--disable-e10s', 04:36:58 INFO - '--xperf_path', 04:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:36:58 INFO - 'tests': ['tp5n']}, 04:36:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:36:58 INFO - 'talos_options': ['--xperf_path', 04:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:36:58 INFO - 'tests': ['tp5n']}}, 04:36:58 INFO - 'talos.zip': {'path': '', 04:36:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:36:58 INFO - Running post-action listener: _resource_record_post_action 04:36:58 INFO - [mozharness: 2016-12-19 12:36:58.608854Z] Finished populate-webroot step (success) 04:36:58 INFO - [mozharness: 2016-12-19 12:36:58.608961Z] Running create-virtualenv step. 04:36:58 INFO - Running pre-action listener: _resource_record_pre_action 04:36:58 INFO - Running main action method: create_virtualenv 04:36:58 INFO - Creating virtualenv /builds/slave/test/build/venv 04:36:58 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 04:36:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:36:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:36:58 INFO - Using real prefix '/tools/python27' 04:36:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:36:59 INFO - Installing distribute.............................................................................................................................................................................................done. 04:37:02 INFO - Installing pip.................done. 04:37:02 INFO - Return code: 0 04:37:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:37:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:37:02 INFO - Reading from file tmpfile_stdout 04:37:02 INFO - Output received: 04:37:02 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) 04:37:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:37:02 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')]} 04:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:02 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')]} 04:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:02 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 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:02 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 04:37:02 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 04:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:02 INFO - 'HOME': '/Users/cltbld', 04:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:02 INFO - 'LOGNAME': 'cltbld', 04:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:02 INFO - 'MOZ_NO_REMOTE': '1', 04:37:02 INFO - 'NO_EM_RESTART': '1', 04:37:02 INFO - 'PAGER': '/bin/cat', 04:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:02 INFO - 'PWD': '/builds/slave/test', 04:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:02 INFO - 'SHELL': '/bin/bash', 04:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:02 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:02 INFO - 'USER': 'cltbld', 04:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:02 INFO - 'XPC_FLAGS': '0x0', 04:37:02 INFO - 'XPC_SERVICE_NAME': '0', 04:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:02 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 04:37:02 INFO - Cleaning up... 04:37:02 INFO - Return code: 0 04:37:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:37:02 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')]} 04:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:02 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')]} 04:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:02 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 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:02 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 04:37:02 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 04:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:02 INFO - 'HOME': '/Users/cltbld', 04:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:02 INFO - 'LOGNAME': 'cltbld', 04:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:02 INFO - 'MOZ_NO_REMOTE': '1', 04:37:02 INFO - 'NO_EM_RESTART': '1', 04:37:02 INFO - 'PAGER': '/bin/cat', 04:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:02 INFO - 'PWD': '/builds/slave/test', 04:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:02 INFO - 'SHELL': '/bin/bash', 04:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:02 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:02 INFO - 'USER': 'cltbld', 04:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:02 INFO - 'XPC_FLAGS': '0x0', 04:37:02 INFO - 'XPC_SERVICE_NAME': '0', 04:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:03 INFO - Downloading/unpacking psutil>=3.1.1 04:37:03 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 04:37:03 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 04:37:03 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 04:37:03 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 04:37:03 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 04:37:03 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 04:37:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:37:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:37:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:37:07 INFO - Installing collected packages: psutil 04:37:07 INFO - Running setup.py install for psutil 04:37:07 INFO - building 'psutil._psutil_osx' extension 04:37:07 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 04:37:08 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 04:37:08 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 04:37:08 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 04:37:08 INFO - building 'psutil._psutil_posix' extension 04:37:08 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 04:37:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:37:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:37:08 INFO - ^ 04:37:08 INFO - 1 warning generated. 04:37:08 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 04:37:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:37:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:37:08 INFO - Successfully installed psutil 04:37:08 INFO - Cleaning up... 04:37:08 INFO - Return code: 0 04:37:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:08 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 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:08 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 04:37:08 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 04:37:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:08 INFO - 'HOME': '/Users/cltbld', 04:37:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:08 INFO - 'LOGNAME': 'cltbld', 04:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:08 INFO - 'MOZ_NO_REMOTE': '1', 04:37:08 INFO - 'NO_EM_RESTART': '1', 04:37:08 INFO - 'PAGER': '/bin/cat', 04:37:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:08 INFO - 'PWD': '/builds/slave/test', 04:37:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:08 INFO - 'SHELL': '/bin/bash', 04:37:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:08 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:08 INFO - 'USER': 'cltbld', 04:37:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:08 INFO - 'XPC_FLAGS': '0x0', 04:37:08 INFO - 'XPC_SERVICE_NAME': '0', 04:37:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:37:09 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 04:37:09 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 04:37:09 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 04:37:09 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 04:37:09 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 04:37:09 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 04:37:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:37:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:37:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:37:11 INFO - Installing collected packages: mozsystemmonitor 04:37:11 INFO - Running setup.py install for mozsystemmonitor 04:37:11 INFO - Successfully installed mozsystemmonitor 04:37:11 INFO - Cleaning up... 04:37:11 INFO - Return code: 0 04:37:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:12 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 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:12 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 04:37:12 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 04:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:12 INFO - 'HOME': '/Users/cltbld', 04:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:12 INFO - 'LOGNAME': 'cltbld', 04:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:12 INFO - 'MOZ_NO_REMOTE': '1', 04:37:12 INFO - 'NO_EM_RESTART': '1', 04:37:12 INFO - 'PAGER': '/bin/cat', 04:37:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:12 INFO - 'PWD': '/builds/slave/test', 04:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:12 INFO - 'SHELL': '/bin/bash', 04:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:12 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:12 INFO - 'USER': 'cltbld', 04:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:12 INFO - 'XPC_FLAGS': '0x0', 04:37:12 INFO - 'XPC_SERVICE_NAME': '0', 04:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:12 INFO - Downloading/unpacking jsonschema==2.5.1 04:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:37:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:37:15 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 04:37:15 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 04:37:15 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 04:37:15 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 04:37:15 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 04:37:15 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 04:37:15 INFO - Downloading functools32-3.2.3-2.tar.gz 04:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:37:15 INFO - warning: no files found matching '*.txt' 04:37:15 INFO - no previously-included directories found matching 'build' 04:37:15 INFO - no previously-included directories found matching 'dist' 04:37:15 INFO - no previously-included directories found matching '.git*' 04:37:15 INFO - Installing collected packages: jsonschema, functools32 04:37:15 INFO - Running setup.py install for functools32 04:37:15 INFO - warning: no files found matching '*.txt' 04:37:15 INFO - no previously-included directories found matching 'build' 04:37:15 INFO - no previously-included directories found matching 'dist' 04:37:15 INFO - no previously-included directories found matching '.git*' 04:37:15 INFO - Successfully installed jsonschema functools32 04:37:15 INFO - Cleaning up... 04:37:15 INFO - Return code: 0 04:37:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:15 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 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:15 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 04:37:15 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 04:37:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:15 INFO - 'HOME': '/Users/cltbld', 04:37:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:15 INFO - 'LOGNAME': 'cltbld', 04:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:15 INFO - 'MOZ_NO_REMOTE': '1', 04:37:15 INFO - 'NO_EM_RESTART': '1', 04:37:15 INFO - 'PAGER': '/bin/cat', 04:37:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:15 INFO - 'PWD': '/builds/slave/test', 04:37:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:15 INFO - 'SHELL': '/bin/bash', 04:37:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:15 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:15 INFO - 'USER': 'cltbld', 04:37:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:15 INFO - 'XPC_FLAGS': '0x0', 04:37:15 INFO - 'XPC_SERVICE_NAME': '0', 04:37:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:37:16 INFO - Cleaning up... 04:37:16 INFO - Return code: 0 04:37:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:37:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:16 INFO - 'HOME': '/Users/cltbld', 04:37:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:16 INFO - 'LOGNAME': 'cltbld', 04:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:16 INFO - 'MOZ_NO_REMOTE': '1', 04:37:16 INFO - 'NO_EM_RESTART': '1', 04:37:16 INFO - 'PAGER': '/bin/cat', 04:37:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:16 INFO - 'PWD': '/builds/slave/test', 04:37:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:16 INFO - 'SHELL': '/bin/bash', 04:37:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:16 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:16 INFO - 'USER': 'cltbld', 04:37:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:16 INFO - 'XPC_FLAGS': '0x0', 04:37:16 INFO - 'XPC_SERVICE_NAME': '0', 04:37:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:16 INFO - Downloading/unpacking blobuploader==1.2.4 04:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:19 INFO - Downloading blobuploader-1.2.4.tar.gz 04:37:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:37:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:37:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37:20 INFO - Downloading docopt-0.6.1.tar.gz 04:37:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:37:20 INFO - Installing collected packages: blobuploader, requests, docopt 04:37:20 INFO - Running setup.py install for blobuploader 04:37:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:37:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:37:20 INFO - Running setup.py install for requests 04:37:21 INFO - Running setup.py install for docopt 04:37:21 INFO - Successfully installed blobuploader requests docopt 04:37:21 INFO - Cleaning up... 04:37:21 INFO - Return code: 0 04:37:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37:21 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')]} 04:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:21 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')]} 04:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:21 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 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:21 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 04:37:21 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 04:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:21 INFO - 'HOME': '/Users/cltbld', 04:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:21 INFO - 'LOGNAME': 'cltbld', 04:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:21 INFO - 'MOZ_NO_REMOTE': '1', 04:37:21 INFO - 'NO_EM_RESTART': '1', 04:37:21 INFO - 'PAGER': '/bin/cat', 04:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:21 INFO - 'PWD': '/builds/slave/test', 04:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:21 INFO - 'SHELL': '/bin/bash', 04:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:21 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:21 INFO - 'USER': 'cltbld', 04:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:21 INFO - 'XPC_FLAGS': '0x0', 04:37:21 INFO - 'XPC_SERVICE_NAME': '0', 04:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:37:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ldWAcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:37:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-CItk_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:37:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-MwuzBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:37:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-CWE2uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-FlcZID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-h1l2ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-FqNoBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-0_E1N2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-EB1zir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-A8VLFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Z7SOUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8hLECi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-YS8Jp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:37:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-XXPV4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:23 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-uNXNrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:37:23 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-A0_71o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:37:23 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-vhNJBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:37:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:37:23 INFO - Running setup.py install for manifestparser 04:37:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:37:23 INFO - Running setup.py install for mozcrash 04:37:23 INFO - Running setup.py install for mozdebug 04:37:23 INFO - Running setup.py install for mozdevice 04:37:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:37:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:37:23 INFO - Running setup.py install for mozfile 04:37:24 INFO - Running setup.py install for mozhttpd 04:37:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Running setup.py install for mozinfo 04:37:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Running setup.py install for mozInstall 04:37:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Running setup.py install for mozleak 04:37:24 INFO - Running setup.py install for mozlog 04:37:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Running setup.py install for moznetwork 04:37:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:37:24 INFO - Running setup.py install for mozprocess 04:37:25 INFO - Running setup.py install for mozprofile 04:37:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:37:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:37:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:37:25 INFO - Running setup.py install for mozrunner 04:37:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:37:25 INFO - Running setup.py install for mozscreenshot 04:37:25 INFO - Running setup.py install for moztest 04:37:25 INFO - Running setup.py install for mozversion 04:37:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:37:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:37:25 INFO - Cleaning up... 04:37:25 INFO - Return code: 0 04:37:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:37:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:26 INFO - 'HOME': '/Users/cltbld', 04:37:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:26 INFO - 'LOGNAME': 'cltbld', 04:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:26 INFO - 'MOZ_NO_REMOTE': '1', 04:37:26 INFO - 'NO_EM_RESTART': '1', 04:37:26 INFO - 'PAGER': '/bin/cat', 04:37:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:26 INFO - 'PWD': '/builds/slave/test', 04:37:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:26 INFO - 'SHELL': '/bin/bash', 04:37:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:26 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:26 INFO - 'USER': 'cltbld', 04:37:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:26 INFO - 'XPC_FLAGS': '0x0', 04:37:26 INFO - 'XPC_SERVICE_NAME': '0', 04:37:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:37:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-B5ACaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-NVZXX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:37:27 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)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-yTvhJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-LIfVgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:37:27 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)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-iVrsZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-1fspWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-q3Qnlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-vqqJcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:37:27 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)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-eaDIrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:37:27 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)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-s7OXlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:37:27 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)) 04:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:37:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-2YfWR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:37:28 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-5AMdLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:37:28 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-e7voWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:37:28 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Qb5HR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:37:28 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)) 04:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:28 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-dAroMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:37:28 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-q3V168-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:37:28 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8Llvao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:37:28 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)) 04:37:28 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)) 04:37:28 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)) 04:37:28 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)) 04:37:28 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)) 04:37:28 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)) 04:37:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:31 INFO - Downloading blessings-1.6.tar.gz 04:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:37:31 INFO - Installing collected packages: blessings 04:37:31 INFO - Running setup.py install for blessings 04:37:31 INFO - Successfully installed blessings 04:37:31 INFO - Cleaning up... 04:37:32 INFO - Return code: 0 04:37:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:37:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:37:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:37:32 INFO - Reading from file tmpfile_stdout 04:37:32 INFO - Current package versions: 04:37:32 INFO - blessings == 1.6 04:37:32 INFO - blobuploader == 1.2.4 04:37:32 INFO - docopt == 0.6.1 04:37:32 INFO - functools32 == 3.2.3-2 04:37:32 INFO - jsonschema == 2.5.1 04:37:32 INFO - manifestparser == 1.1 04:37:32 INFO - mozInstall == 1.12 04:37:32 INFO - mozcrash == 1.0 04:37:32 INFO - mozdebug == 0.1 04:37:32 INFO - mozdevice == 0.49 04:37:32 INFO - mozfile == 1.2 04:37:32 INFO - mozhttpd == 0.7 04:37:32 INFO - mozinfo == 0.9 04:37:32 INFO - mozleak == 0.1 04:37:32 INFO - mozlog == 3.4 04:37:32 INFO - moznetwork == 0.27 04:37:32 INFO - mozprocess == 0.23 04:37:32 INFO - mozprofile == 0.28 04:37:32 INFO - mozrunner == 6.13 04:37:32 INFO - mozscreenshot == 0.1 04:37:32 INFO - mozsystemmonitor == 0.3 04:37:32 INFO - moztest == 0.7 04:37:32 INFO - mozversion == 1.4 04:37:32 INFO - psutil == 3.1.1 04:37:32 INFO - requests == 1.2.3 04:37:32 INFO - wsgiref == 0.1.2 04:37:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:37:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:32 INFO - 'HOME': '/Users/cltbld', 04:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:32 INFO - 'LOGNAME': 'cltbld', 04:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:32 INFO - 'MOZ_NO_REMOTE': '1', 04:37:32 INFO - 'NO_EM_RESTART': '1', 04:37:32 INFO - 'PAGER': '/bin/cat', 04:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:32 INFO - 'PWD': '/builds/slave/test', 04:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:32 INFO - 'SHELL': '/bin/bash', 04:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:32 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:32 INFO - 'USER': 'cltbld', 04:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:32 INFO - 'XPC_FLAGS': '0x0', 04:37:32 INFO - 'XPC_SERVICE_NAME': '0', 04:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:37:32 INFO - Cleaning up... 04:37:32 INFO - Return code: 0 04:37:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d244c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, '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.FeWixSUvhr/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.KM26809fDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 04:37:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:37:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:32 INFO - 'HOME': '/Users/cltbld', 04:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:32 INFO - 'LOGNAME': 'cltbld', 04:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:32 INFO - 'MOZ_NO_REMOTE': '1', 04:37:32 INFO - 'NO_EM_RESTART': '1', 04:37:32 INFO - 'PAGER': '/bin/cat', 04:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:32 INFO - 'PWD': '/builds/slave/test', 04:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:32 INFO - 'SHELL': '/bin/bash', 04:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:32 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:32 INFO - 'USER': 'cltbld', 04:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:32 INFO - 'XPC_FLAGS': '0x0', 04:37:32 INFO - 'XPC_SERVICE_NAME': '0', 04:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:37:32 INFO - Cleaning up... 04:37:32 INFO - Return code: 0 04:37:32 INFO - Running post-action listener: _resource_record_post_action 04:37:32 INFO - Running post-action listener: _start_resource_monitoring 04:37:32 INFO - Starting resource monitoring. 04:37:32 INFO - [mozharness: 2016-12-19 12:37:32.830134Z] Finished create-virtualenv step (success) 04:37:32 INFO - [mozharness: 2016-12-19 12:37:32.830938Z] Running install step. 04:37:32 INFO - Running pre-action listener: _resource_record_pre_action 04:37:32 INFO - Running main action method: install 04:37:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:37:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:37:33 INFO - Reading from file tmpfile_stdout 04:37:33 INFO - Detecting whether we're running mozinstall >=1.0... 04:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:37:33 INFO - Reading from file tmpfile_stdout 04:37:33 INFO - Output received: 04:37:33 INFO - Usage: mozinstall [options] installer 04:37:33 INFO - Options: 04:37:33 INFO - -h, --help show this help message and exit 04:37:33 INFO - -d DEST, --destination=DEST 04:37:33 INFO - Directory to install application into. [default: 04:37:33 INFO - "/builds/slave/test"] 04:37:33 INFO - --app=APP Application being installed. [default: firefox] 04:37:33 INFO - mkdir: /builds/slave/test/build/application 04:37:33 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'] 04:37:33 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 04:37:54 INFO - Reading from file tmpfile_stdout 04:37:54 INFO - Output received: 04:37:54 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:37:54 INFO - Running post-action listener: _resource_record_post_action 04:37:54 INFO - [mozharness: 2016-12-19 12:37:54.926012Z] Finished install step (success) 04:37:54 INFO - [mozharness: 2016-12-19 12:37:54.926168Z] Running run-tests step. 04:37:54 INFO - Running pre-action listener: _resource_record_pre_action 04:37:54 INFO - Running main action method: run_tests 04:37:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:37:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:37:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:37:54 INFO - 2016-12-19 04:37:54.940 screenresolution[1624:7365] starting screenresolution argv=screenresolution get 04:37:54 INFO - 2016-12-19 04:37:54.943 screenresolution[1624:7365] Display 0: 1600x1200x32@60 04:37:54 INFO - 2016-12-19 04:37:54.949 screenresolution[1625:7367] starting screenresolution argv=screenresolution list 04:37:54 INFO - Available Modes on Display 0 04:37:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:37:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:37:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:37:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:37:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:37:55 INFO - Intel Iris: 04:37:55 INFO - Chipset Model: Intel Iris 04:37:55 INFO - Type: GPU 04:37:55 INFO - Bus: Built-In 04:37:55 INFO - VRAM (Dynamic, Max): 1536 MB 04:37:55 INFO - Vendor: Intel (0x8086) 04:37:55 INFO - Device ID: 0x0a2e 04:37:55 INFO - Revision ID: 0x0009 04:37:55 INFO - Displays: 04:37:55 INFO - Display: 04:37:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:37:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:37:55 INFO - Display Serial Number: 1600x1200 60 04:37:55 INFO - Main Display: Yes 04:37:55 INFO - Mirror: Off 04:37:55 INFO - Online: Yes 04:37:55 INFO - Rotation: Supported 04:37:55 INFO - Return code: 0 04:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:37:55 INFO - Python 2.7.3 04:37:55 INFO - Return code: 0 04:37:55 INFO - grabbing minidump binary from tooltool 04:37:55 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')]} 04:37:55 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 0x104e85d30>, '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 0x104dacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:37:55 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 04:37:55 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 04:37:55 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 04:37:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:37:55 INFO - Return code: 0 04:37:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:37:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:37:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:37:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:37:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:37:55 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 04:37:55 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0114 --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 04:37:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FeWixSUvhr/Render', 04:37:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:55 INFO - 'HOME': '/Users/cltbld', 04:37:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:55 INFO - 'LOGNAME': 'cltbld', 04:37:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:37:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:55 INFO - 'MOZ_NO_REMOTE': '1', 04:37:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:37:55 INFO - 'NO_EM_RESTART': '1', 04:37:55 INFO - 'PAGER': '/bin/cat', 04:37:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:55 INFO - 'PWD': '/builds/slave/test', 04:37:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:37:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:55 INFO - 'SHELL': '/bin/bash', 04:37:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KM26809fDu/Listeners', 04:37:55 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 04:37:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:55 INFO - 'USER': 'cltbld', 04:37:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:55 INFO - 'XPC_FLAGS': '0x0', 04:37:55 INFO - 'XPC_SERVICE_NAME': '0', 04:37:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:55 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 04:37:55 INFO - mozversion application_buildid: 20161219030207 04:37:55 INFO - mozversion application_changeset: 863c2b61bd27bb6099104933134d3be7c052551a 04:37:55 INFO - mozversion application_display_name: Nightly 04:37:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:37:55 INFO - mozversion application_name: Firefox 04:37:55 INFO - mozversion application_remotingname: firefox 04:37:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:37:55 INFO - mozversion application_vendor: Mozilla 04:37:55 INFO - mozversion application_version: 53.0a1 04:37:55 INFO - mozversion platform_buildid: 20161219030207 04:37:55 INFO - mozversion platform_changeset: 863c2b61bd27bb6099104933134d3be7c052551a 04:37:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:37:55 INFO - mozversion platform_version: 53.0a1 04:37:55 INFO - using testdate: 1482151075 04:37:55 INFO - actual date: 1482151075 04:37:55 INFO - starting webserver on 'localhost:49248' 04:37:55 INFO - SUITE-START | Running 2 tests 04:37:55 INFO - TEST-START | dromaeo_css 04:37:55 INFO - operating with platform_type : mac_ 04:37:55 INFO - Initialising browser for dromaeo_css test... 04:37:55 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpOh7fp8/profile http://localhost:49248/getInfo.html) 04:37:58 INFO - PROCESS | 1635 | 2016-12-19 04:37:58.666 plugin-container[1638:7538] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b53, name = 'com.apple.tsm.portname' 04:37:58 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:58 INFO - PROCESS | 1635 | 2016-12-19 04:37:58.666 plugin-container[1638:7538] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 04:37:58 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:58 INFO - PROCESS | 1635 | 2016-12-19 04:37:58.666 plugin-container[1638:7538] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:37:58 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 04:37:58 INFO - PROCESS | 1635 | colorDepth:24 04:37:58 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 04:37:58 INFO - PROCESS | 1635 | __metrics 04:37:58 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 04:37:59 INFO - TEST-INFO | 1635: exit 0 04:37:59 INFO - Browser initialized. 04:37:59 INFO - Running cycle 1/1 for dromaeo_css test... 04:37:59 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpOh7fp8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:38:02 INFO - PROCESS | 1639 | 2016-12-19 04:38:02.057 plugin-container[1640:7662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 04:38:02 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:38:02 INFO - PROCESS | 1639 | 2016-12-19 04:38:02.057 plugin-container[1640:7662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 04:38:02 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:38:02 INFO - PROCESS | 1639 | 2016-12-19 04:38:02.057 plugin-container[1640:7662] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:38:11 INFO - PROCESS | 1639 | RSS: Main: 236969984 04:38:11 INFO - PROCESS | 1639 | 04:39:01 INFO - PROCESS | 1639 | 1482151141247 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] 04:41:12 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 04:41:15 INFO - PROCESS | 1639 | RSS: Main: 252878848 04:41:15 INFO - PROCESS | 1639 | 04:44:17 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 04:44:18 INFO - PROCESS | 1639 | RSS: Main: 264114176 04:44:18 INFO - PROCESS | 1639 | 04:47:20 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 04:47:21 INFO - PROCESS | 1639 | RSS: Main: 269389824 04:47:21 INFO - PROCESS | 1639 | 04:50:23 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 04:50:23 INFO - PROCESS | 1639 | RSS: Main: 268382208 04:50:23 INFO - PROCESS | 1639 | 04:53:26 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 04:53:27 INFO - PROCESS | 1639 | RSS: Main: 273260544 04:53:27 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 04:56:30 INFO - PROCESS | 1639 | RSS: Main: 275668992 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | __start_tp_report 04:56:30 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 04:56:30 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 04:56:30 INFO - PROCESS | 1639 | |i|pagename|runs| 04:56:30 INFO - PROCESS | 1639 | |0;dojo.html;2626.658534390529;2778.458200650873;3199.5040768680865;3248.8375581220935;3255.3489302139565;4064.715469917107;4802.2556503741935;5301.072312345339;5312.176612625044;5348.518633323;5254.973725131378;5313.07021271277;5313.495217954298;5336.199570064492;5340.519353258206;4571.542845715435;4964.702117872939;4993.226049962253;5000.274960130779;5011.448740638527;4887.535684110004;4949.183384741514;4952.430470495885;4981.103401387749;4991.351124353829;4974.87562810929;4977;4988.526090021461;5000.399952005764;5036.244563315514;4738.265568836836;4793;4812.975935120337;4922.655414121013;4928.778204980785;4831.661783675144;4897.33885925399;4901.436334821506;4911.09144808208;4936.901261974776;3855.1711382052995;5236.92144617831;5275.366955965271;5292.994331077083;5331.813386531491;5591.30108736408;5694.088945768699;5739.282589676291;5767.509761670263;5787.594868359217;5687.687177205252;5844.853878653025;5857.531397488234;5862.325832529271;5957.582969192158;4298.656107511423;4785.521447855222;5382.0043291991105;5493.945060549423;5495.175723641406;5040.344755181876;6033.849153771191;6054.848628784228;6082.422169893853;6088.11722300264;4565.543445655408;5493.066178749622;5551.583631227674;5560.888782224333;5592.692401917852;5696.3164420269;5741.45456181662;5749.8275051748515;5795.130730390475;5804.767809287666;5595.972020139873;5688.146777983336;5697.116946873159;5697.914531281951;5704.657720536781;5534.889302213933;5639.013172694762;5640.492355687926;5698.917205730898;5737.8565535861935;4046.1705350403095;4644.651651126179;4653.581177694024;4654.813807447664;4683.648726345537;4087.3868919662286;4162.91674166515;4166.500019997619;4172.144710334374;4189.581041895847;5349.839504814861;5380.650257733235;5437.646552974044;5474.09677403224;5522.475364840334;5834.358220595731;5852.4147585241135;5853.795117170878;5859.062549991981;5890.764369425261;5438.809641662523;5460.153676180198;5470.234167216593;5489.094299440548;5490.835274941758;5018.974905125451;5053.772580233898;5063.87340316495;5083.923741143886;5084.9745751271;6117.492485019875;6137.560853825989;6138.355472675305;6159.137720719103;6225.657588832658;5924.11138329254;5964.8807023860145;5989.131575921554;5995.100734889801;6038.064100064497;6154.507639388748;6175.3515880831865;6191.907121393093;6192.876142477216;6206.565540412128;4673.485916549109;5563.888722225452;5637.182608521659;5637.295338082739;5706.629069110494;5685.545156387562;5788.797392091256;5797.913031304618;5819.098039803837;5828.59199856006;5627.352854421794;5633.971830140823;5636.957162924872;5637.098064309855;5663.85840353978;5408.0806262936185;5593.188987596857;5598.552115830806;5609.971950140223;5621.381648018796;3793.582705902293;5451.291332126798;5466.72666366688;5478.397376288684;5487.890242195213;5695.886082278248;5704.743286552032;5714.257146570919;5730.5702072345575;5790.363060063305;5469.179623056414;5556.944430555643;5564.55483561306;5567.359753628384;5611.859703507445 04:56:30 INFO - PROCESS | 1639 | |1;ext.html;439.84605388114164;461.1537828085464;478.49518757710615;501.12553593978515;523.3536582320836;35285.29429411414;37733.245335093314;37820.810895945484;37901.431478527775;37955;31459.685403145962;31745.841270793673;38684.83945481633;38931.80534097325;38974;8289.41974061816;8506.659733610648;9095.863562046557;9342.532873356338;9375.156235938764;11013.394263315515;11323;11350.43247837609;11482.425878706073;11496.19526633136;9717.785276840395;11225.045871100967;11249.10007199422;11317.83023254652;11327.943360283187;10341.79316413675;10375.014373634569;11166.162537809656;11227.494762735696;11239.976804175245;11081.501332439979;11175.273607215586;11188.328700277929;11219.990200881879;11221.887781122166;37897.62102378996;38155.809220954;38165.23669526622;38221.61778382208;38238.80880595607;3392.4741665041956;3484.6341134181052;3609.747317687763;3626.256617393428;3858.9807050964846;28740.712592874013;29865.70134298651;29921.40157196844;29923.551146732818;30019.849900750356;3057.6483704373836;3185.984070079658;3228.80627162371;3278.4754439289836;3282.458394364927;375774;465384;479335;479938;480995;55081;55521;55533;55635.60910977258;55689;44821;56106.877862442525;56121.71939140278;56194.34736742099;56456.43543564594;15291.694166116838;15556.844431555766;15561.066336019874;15570.987885787388;15583.76624350613;10866.076383507332;11080.61217857371;11841.940790296167;12108.03135749138;12125.090618203494;11882.168248222715;14789.29923058862;14806.62983425423;14808.481703140338;14840.332185051699;4786.814342908946;6635.137432133888;6667.833304167338;6708.530402871752;6783.219929708048;847.5507980770202;875.2472873328932;885.2917665867409;898.3666515106834;988.7231575158967;22900.083996640282;27689.584656230174;28722.712772872423;29014;29822.850885745433;852.1733918099434;899.6446403670514;1041.7132160537092;1081.6052140968648;1183.8342632031522;14550.708985820223;14939.327730252166;14974.401023959137;15212.011219270711;15275.618109547127;583158;729584;812216;823039;824845.8757706293;48172.03655926862;48855;48877.266840997414;48940.75529622329;48977;1286.2925391034894;1323.6227675112548;1325.3638253638308;1329.8204742359658;1337.1375462826422;22680.886595566917;27061.729382705922;27221;27248.318792030357;27617.447651046463;41934.37098443587;42096.789516052224;42574.361384579875;42623.7212883614;42684.359734603386;552061;553552;554186;554719;555013;23059;23114.422139446648;23285.0685972566;23439.179628712915;23462.765372346057;32486.51270230901;32572;38546.229075418894;38898;38980.6101938977;31016.534751978288;32391;33143.83996560109;33289.83355083306;33616.83191584027;32604.51093233556;32652.020439385906;33345.66654333426;33517.32965340728;33905.49141762826;32274.515882262254;32457;32684.836575816968;32735.672643274214;32802.67197327996;21518.462038449165;23747.643785342887;25829.3542661435;25837.870810645825;26310.21069367922;19061.61876762429;19786.70319945173;21430.6785398216;22536.323910282717;23406.882965585064 04:56:30 INFO - PROCESS | 1639 | |2;jquery.html;355.71720482216654;374.61788975245247;377.1946893382627;379.9031247032007;388.7686826338328;18325.45023649291;18775.436736897882;19115.617687646238;19448.611027779454;19603.509912252164;11721.941390293037;12097.092718046139;12130.211536250137;12144.39278036097;12185.817212741817;4978.62660300478;5260.842174734745;5325.254464374972;5369.892602147955;5380.865478363053;3825.3305671507374;4056.229316429869;4936.753162341868;5286.471352864702;5330.173823057432;4332.740035597874;6492.545521813475;6555.541112122153;6563.1796025496815;6566.737330506774;6329.620222786648;6395.104685344056;6426.357364263559;6525.967370163166;6577.539572229945;5713.657180569179;5716.256886604757;5722.055860782966;5728.283964504437;5729.742161602738;12692.619221423372;22741.886290568607;22864.314070577908;23801.047958081657;23841.88079059611;1930.9517262068562;2003.8497112716605;2139.70044193813;2365.550545396369;2981.925451863699;9708.514574271258;9757.414555126716;9795.216382689368;10082.49587520621;10288.537015834305;1815.6641021410949;1898.7278523389225;1902.838258748008;1907.2466375781628;1935.5838494723703;106616;115219.4239028808;115678;116093;116468.41765791201;9500.714978550517;11587.942060289644;11609.535618575332;11703.941480292544;11725.472353744102;10880.367944808342;11531.76936461266;14362.281885905662;14383.856161438462;14417;7670.463067585327;7763.805904852424;7785.18255583167;7881.802954926173;7888.802779930547;8314.127016663284;8331.37514686401;8332.250097491255;8458.788530286669;8482.787930301669;3562.28754249146;3653.6894363978827;3670.6696397324367;3679.2089700714473;3725.0873535983314;1560.8673262772566;1631.298541627088;1738.356807981055;1801.4685667728052;1954.4722924810505;704.1796307302038;707.6108140522795;754.8037510247375;781.5935713428985;873.6723728601775;15199.62000949963;16534.503964881074;16960.067196304073;17830.286788528574;18061.09694515269;826.4380221449472;833.5707110837923;839.7606682095574;855.2473823035689;860.617025423693;7672.386209103258;8465.788355291044;9022.86465703015;9153.679621213225;9261.861072083924;544540;547368;548351.516484838;558532;559525;86319.8420039504;115648;118426;128421.08420784152;134730.6526934718;1008.606643409071;1009.4548943570536;1046.1369370269406;1056.9471526423647;1057.3038751184602;15706.528804135894;18317.450476485727;19479.415617531497;19561.82629042262;19610;33905;39226.60773392344;43815.78092109646;51213.48786512087;51344.973100538526;69060;73806.63096684482;74210;74340.25659743333;75112;8036.839263214586;8069.314108300626;8201.056878459054;8202.548859812767;8307.460015098999;11776.941115294369;12799.168054076456;12966.80549791735;12980.091393602735;13183.868161318263;10748.946255268673;10991.175661825235;11320.150988675718;11355.602553910574;11438.599649012245;9410.200132988499;9720.902790972283;10727.785444290914;11065.944670276596;11199.160062995144;11237.550497980315;11274.774504510027;11371.886281137082;11604.593839215588;11716.297022178696;7735.613219339123;8072;8141.267285944294;8144.83710325802;8270.83458330842;8163.1428699985645;9242;9921.25590580695;11139.275947063416;11182.937620925999 04:56:30 INFO - PROCESS | 1639 | |3;mootools.html;1457.1912588513374;1487.330701184467;1693;1799.4691565988032;1839.5125291797676;7516.849663006737;7752.224777522245;8081.5959202039885;8534.91465085349;8555.272801811854;7703.268189521987;7755.681534139196;7998.840023199533;8309.961254843145;8321.251087402132;2286.9313920582367;2336.310788317442;2340.169239919821;2341.508283260517;2347.295811256625;2292.002978704261;2314.409825494505;2314.7916687498123;2324.7094113235844;2339.789418952294;2286.3826766772963;2292.6904867842877;2294.7705229477087;2304.1474654377903;2317.1079134533243;1923.6633589121848;2009.25657506722;2055.8589982559674;2276.760940101282;2306.5617532668825;2311.734150572689;2320.071971211512;2329.4758679297192;2343.0510643189536;2344.284993077111;8374.120717324715;8420.073791882887;8423.452475589129;8436.114208008194;8455.957720211421;5042.3949126104735;5206.0368831766245;5211.687298762048;5219.11275083233;5222.138347172712;3222.081706713599;3356.8153751543655;3410.1304167437142;3428.708559772422;3454.11919960411;1045.1586472889321;1053.1311667874033;1073.6188653028219;1087.3258579680648;1091.5100887288872;10671.519781609846;10739.78520429595;10762.031417172413;10790.622328218475;10812.351258924411;4480.551944805494;4583.862484125481;4671.883202919887;4685.648576356787;4727.0073284609925;7906.5651389173;9338.159565638989;9384.8592271116;9398.67104651334;9408.858867116998;5149.356330458692;5207.6875387476875;5251.36983561975;5252.2384254282915;5255.605829562798;4287.935680964788;4289.0992891492515;4694.741789201558;4751.311059896365;4762.976185119052;3737.271232109767;3796.2217745362686;3854.3062248795486;3873.6707379872464;3903.082775547744;1105.2484310668822;1260.4328052376468;1284.17170924753;1287.4850059975897;1296.0409297120252;887.2148148888151;887.3921363865772;892.455602082729;902.3232575568323;928.4893308680199;5341.5459685927135;5635.211070450058;5669.518090962314;5768.077107662838;5776.971115144482;791.2245998920974;853.2576658307312;914.6295750221171;918.9081091890891;952.3857112162755;3694.131879008431;3738.9439158412647;3810.099788327754;3859.286032084073;3888.183481468867;9771.4625695586;10089.394636321698;10099.697009089592;10120.392776433291;10155.796884062278;9938.503074846229;9975.052370024836;10031.448270345056;10059.245556583286;10070.647527336509;7792.844143117106;7893.447458677838;7911;7944.404169687296;7954.681812727542;5345.759440825094;5480.095784195563;5606.159076138611;5616.438356164351;5646.209530665792;9209.033051529761;9378.437293762396;9392.436453812794;9401.20089792348;9420.717378478657;6939;9375.671851485438;9382.624695012397;9408.623655053721;9511.524423778645;6594.373534514213;6762.594244345355;6988.440924726111;7147.392471639969;7307.853842923006;7698.653560589675;7951.602419878867;7983;7997.92002079995;8008.799780005546;6024.638521688713;6090.634561926298;7085;7197.496175267681;7363.521371110861;5882.235309409921;5976.970115149396;7006.894896576658;7034.507584469243;7073.2219455858785;6488.416042556192;6655.500837437308;6665.0668906352175;6705.396514313735;6766.086578311865;3720.8883733488037;4689;4877.170880950176;4906.484819093944;4992.126377883783;4669.883252918704;4963.404391473046;4971.676841005323;4977.576905963034;5003.599712022957 04:56:30 INFO - PROCESS | 1639 | |4;prototype.html;289.29412234148674;315.4763093265179;352.8659109538375;371.54856848928546;372.56037875307146;4861.440934292557;5037.974810125949;5120.361484325017;5227.607929405291;5322.520973112414;4930.654854160211;5012.82455114071;5133.794648214067;5236.005159019793;5242.501962313584;4052.5542190359038;4122.443470131524;4146.253674338618;4153.107081977374;4164.625183733463;3980.2636512245326;4077.2049450357194;4140;4160.375943608453;4186.539480657125;3265.3959017581824;3597.1007248187952;4026.697997650188;4040.8381515889005;4049.2913740095514;3148.307372378073;3369.7472689548135;3557;4072.8574499892657;4093.3041382964966;3430.3482338355884;3651.507046548735;3670.889873303805;3689.7048236141045;3694.9630503695157;4441.444819397575;4604.447466304032;4646.395968524104;4748.477667456577;5054.1407960646775;4271.273883439822;4693.366395536627;4702.36518070063;4728.753841925058;4732.361131247272;5048.924266136011;5486.281797654015;5486.972565137189;5515.283013208297;5596.804111856066;3511.367953768346;3538.4515400113023;3765.9623403765886;4048.6356227939627;4062.4921884764403;5644.858878528029;5804.332501762309;5824.970875145597;5833.7083145842535;5889.911651325191;3619.782813031174;3864.169203621179;3876.321643712339;3889.4749208857006;3899.8440062397754;3427.234973988398;3728.6084961079237;3791.7914514701406;3929.548101968253;3955.7428767130045;3670.632936706308;4092.894918372023;4156.729812562174;4171.666266698657;4515.503294637587;4871.074495845778;5091.618128640367;5279.736013199325;5479.561635069185;5609.4390560943575;992.593036854886;1150.6250031212621;1171.5196769324536;1226.1600803449664;1241.0878004666563;755.7341453066092;768.3737753730692;837.7026155714756;856.3620103023287;873.7116751471999;4429.512753597101;4673.462551806518;5041.521350268303;5053;5108.846734597893;4926.704397736147;5400.540954018952;5445.455454454602;5450.91823622646;5465.5243084367;4388.720908473099;4641.605463535638;5012.498750125032;5033.949660503348;5056.9494305057215;2592.585186370172;2782.916512504628;2826.929326766847;2837.730415610514;2873.5258682317603;5015.949840501548;5209.817656382084;5420.864478387993;5708.686022268815;5749.8275051748515;4935.555799978019;5351.973240133852;5458.645188062843;5476.780928762805;5928.288605367297;4599.724016559017;5888.438529239795;6066.817995460143;6092.39076092396;6098.512119030555;5438.117797567295;5784.074548072288;5812.645878419247;5819.6508209507565;5820.447057529528;5357.517823395913;5375.6774593524515;5412.377576578744;5651.943480565142;5673.8297851064535;5161.612879034163;5999.220101386792;6009.579329446897;6038.889971664684;6057.909131362945;3417.2652879631;3719.3305205062265;3833.1758671885677;3873.457715919717;3878.185581028016;4947.975260123821;5550.777968881361;5655.915161272502;5693.544516438592;5760.769569217352;4421.911561768811;4550.954490455053;5459.39946605881;5607.186957891001;5705.773258749389;4716;4779.737114458738;4926.827561035347;5574.777008919597;5679.204911312337;4678;4808.591269742111;5028.700461105702;5150.845474635767;5493.807716729895;5404.918926216189;5506.752196151103;5568.693721845338;5577.330720313588;5585.609007369445;4689.929651055305;5269.552088072557;5378.489043540857;5461.5357694596405;5485.696007676705 04:56:30 INFO - PROCESS | 1639 | |5;yui.html;634.1248334183787;716.853045125749;771.1671394893516;793.0087390761548;809.46979728278;14830.925845370772;15825;15848.366065357372;15855.048697078184;15856.920715396407;14150.50473233437;14326.426942922271;14356.856431435683;14383.993120481571;14411.423543058265;663.9435647969929;685.3865790117842;701.8455939693272;829.3365307753803;854.2866706300247;833.8832563441093;843.2284459719349;844.2233145506118;844.8056946902216;847.5719761520423;878.7012415778634;897.2552781191596;903.5482258870564;910.622091831889;913.5660561233427;782.8943092682529;908.3596064774351;910.4264313482497;910.4719262827545;911.6216770040426;666.0675054923098;670.765232168737;670.8389986403276;681.9283975182584;684.5003147702205;12634.936825315906;14667.413303467849;15030.774538381933;15056.623584410478;15521.223938803014;14080.15519068859;14364.856351436456;14508.347124379323;14520.419183232658;14655.560333190022;9630.374025688378;11691.240069395462;11783.960720130923;11803.232789868716;11820;3672.140234185529;3706.6478684524927;3734.7012239021083;3736.7384283100196;3774.754640948357;101486;102036.48981755118;102165;104816.95183048148;106119.46940265327;8871.733847984571;10934.890651093547;10953.671389858317;10994.890051099546;11232.157588180919;22491.88754056219;22716.318510444715;22740;22764.544709105725;23060.077596895935;7754.3796496280165;7781.727639532589;7912.327452166515;8004.199580041949;8068.798280043045;4289.549597292239;4297.119090586422;4369.799938073682;4371.759553224603;4431.778411079433;3476.91307717304;3548.929021419609;3552.1474846036767;3572.392693242155;3575.606683264839;453.77538118631486;580.1240127407644;594.2779522879722;621.3382747374033;627.4102343796816;548.3036543589619;607.7720854679495;613.2212090644856;677.4681874728315;695.1067877777133;7963.800904977422;8800.840641636574;9156.771080723036;9211.263098952068;9291.303152263607;640.8750293692685;654.8509495834121;669.293894940842;677.0622687577642;679.1035832700886;4419.160359531678;4488.596026357578;4612.7001744886475;4670.556297151765;4695.248760198352;97701.5114924435;102974;105089;105474.47262763637;105503;66277.66861165663;77353.066173345;96637.51681241549;96723.51638241764;96868.51565742267;84615.576922115;86295;86522.13477865266;87072;88044;8115.878261826196;8447.577621118797;8651.437656552303;8672.78318042054;8783.692570759993;48086.22965244053;48243.5175648239;48941;52097.218541722665;52261.216081758044;57756;69887;70695.64652176706;71379.64310178623;71790.56418871698;11249.268797528135;11329.886701133213;11332.433378330885;11356.510833972085;11368.943155284502;11217.822128676371;11752.439115215679;12294;12649.747005059664;12706.809397858855;11893.405329733305;12059.974902133066;12307.138500305251;12310.81533777012;12961.870381296067;13548.72902541924;13835.654108647364;13843.238621875893;14181.929090354895;14625;13149.474021039052;13604.387802548712;13738.519151829638;13878.375473103533;13976.938101706392;11328.32030078198;11334.943325283652;11547.538098475967;11762;11866.169368144492;8389.874151887849;9146.725598232064;9933.843752323131;10283.560301557638;11705.88294117048 04:56:30 INFO - PROCESS | 1639 | __end_tp_report 04:56:30 INFO - PROCESS | 1639 | __start_cc_report 04:56:30 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,366 04:56:30 INFO - PROCESS | 1639 | __end_cc_report 04:56:30 INFO - PROCESS | 1639 | __startTimestamp1482152190484__endTimestamp 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | ------- Summary: start ------- 04:56:30 INFO - PROCESS | 1639 | Number of tests: 6 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5318.83 Median:5493.07 stddev:661.69 (12.0%) stddev-sans-first:629.12 04:56:30 INFO - PROCESS | 1639 | Values: 2626.7 2778.5 3199.5 3248.8 3255.3 4064.7 4802.3 5301.1 5312.2 5348.5 5255.0 5313.1 5313.5 5336.2 5340.5 4571.5 4964.7 4993.2 5000.3 5011.4 4887.5 4949.2 4952.4 4981.1 4991.4 4974.9 4977.0 4988.5 5000.4 5036.2 4738.3 4793.0 4813.0 4922.7 4928.8 4831.7 4897.3 4901.4 4911.1 4936.9 3855.2 5236.9 5275.4 5293.0 5331.8 5591.3 5694.1 5739.3 5767.5 5787.6 5687.7 5844.9 5857.5 5862.3 5957.6 4298.7 4785.5 5382.0 5493.9 5495.2 5040.3 6033.8 6054.8 6082.4 6088.1 4565.5 5493.1 5551.6 5560.9 5592.7 5696.3 5741.5 5749.8 5795.1 5804.8 5596.0 5688.1 5697.1 5697.9 5704.7 5534.9 5639.0 5640.5 5698.9 5737.9 4046.2 4644.7 4653.6 4654.8 4683.6 4087.4 4162.9 4166.5 4172.1 4189.6 5349.8 5380.7 5437.6 5474.1 5522.5 5834.4 5852.4 5853.8 5859.1 5890.8 5438.8 5460.2 5470.2 5489.1 5490.8 5019.0 5053.8 5063.9 5083.9 5085.0 6117.5 6137.6 6138.4 6159.1 6225.7 5924.1 5964.9 5989.1 5995.1 6038.1 6154.5 6175.4 6191.9 6192.9 6206.6 4673.5 5563.9 5637.2 5637.3 5706.6 5685.5 5788.8 5797.9 5819.1 5828.6 5627.4 5634.0 5637.0 5637.1 5663.9 5408.1 5593.2 5598.6 5610.0 5621.4 3793.6 5451.3 5466.7 5478.4 5487.9 5695.9 5704.7 5714.3 5730.6 5790.4 5469.2 5556.9 5564.6 5567.4 5611.9 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:69190.15 Median:32710.25 stddev:162606.82 (497.1%) stddev-sans-first:162981.04 04:56:30 INFO - PROCESS | 1639 | Values: 439.8 461.2 478.5 501.1 523.4 35285.3 37733.2 37820.8 37901.4 37955.0 31459.7 31745.8 38684.8 38931.8 38974.0 8289.4 8506.7 9095.9 9342.5 9375.2 11013.4 11323.0 11350.4 11482.4 11496.2 9717.8 11225.0 11249.1 11317.8 11327.9 10341.8 10375.0 11166.2 11227.5 11240.0 11081.5 11175.3 11188.3 11220.0 11221.9 37897.6 38155.8 38165.2 38221.6 38238.8 3392.5 3484.6 3609.7 3626.3 3859.0 28740.7 29865.7 29921.4 29923.6 30019.8 3057.6 3186.0 3228.8 3278.5 3282.5 375774.0 465384.0 479335.0 479938.0 480995.0 55081.0 55521.0 55533.0 55635.6 55689.0 44821.0 56106.9 56121.7 56194.3 56456.4 15291.7 15556.8 15561.1 15571.0 15583.8 10866.1 11080.6 11841.9 12108.0 12125.1 11882.2 14789.3 14806.6 14808.5 14840.3 4786.8 6635.1 6667.8 6708.5 6783.2 847.6 875.2 885.3 898.4 988.7 22900.1 27689.6 28722.7 29014.0 29822.9 852.2 899.6 1041.7 1081.6 1183.8 14550.7 14939.3 14974.4 15212.0 15275.6 583158.0 729584.0 812216.0 823039.0 824845.9 48172.0 48855.0 48877.3 48940.8 48977.0 1286.3 1323.6 1325.4 1329.8 1337.1 22680.9 27061.7 27221.0 27248.3 27617.4 41934.4 42096.8 42574.4 42623.7 42684.4 552061.0 553552.0 554186.0 554719.0 555013.0 23059.0 23114.4 23285.1 23439.2 23462.8 32486.5 32572.0 38546.2 38898.0 38980.6 31016.5 32391.0 33143.8 33289.8 33616.8 32604.5 32652.0 33345.7 33517.3 33905.5 32274.5 32457.0 32684.8 32735.7 32802.7 21518.5 23747.6 25829.4 25837.9 26310.2 19061.6 19786.7 21430.7 22536.3 23406.9 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:32201.90 Median:3702.15 stddev:92221.11 (2491.0%) stddev-sans-first:92448.81 04:56:30 INFO - PROCESS | 1639 | Values: 355.7 374.6 377.2 379.9 388.8 18325.5 18775.4 19115.6 19448.6 19603.5 11721.9 12097.1 12130.2 12144.4 12185.8 4978.6 5260.8 5325.3 5369.9 5380.9 3825.3 4056.2 4936.8 5286.5 5330.2 4332.7 6492.5 6555.5 6563.2 6566.7 6329.6 6395.1 6426.4 6526.0 6577.5 5713.7 5716.3 5722.1 5728.3 5729.7 12692.6 22741.9 22864.3 23801.0 23841.9 1931.0 2003.8 2139.7 2365.6 2981.9 9708.5 9757.4 9795.2 10082.5 10288.5 1815.7 1898.7 1902.8 1907.2 1935.6 106616.0 115219.4 115678.0 116093.0 116468.4 9500.7 11587.9 11609.5 11703.9 11725.5 10880.4 11531.8 14362.3 14383.9 14417.0 7670.5 7763.8 7785.2 7881.8 7888.8 8314.1 8331.4 8332.3 8458.8 8482.8 3562.3 3653.7 3670.7 3679.2 3725.1 1560.9 1631.3 1738.4 1801.5 1954.5 704.2 707.6 754.8 781.6 873.7 15199.6 16534.5 16960.1 17830.3 18061.1 826.4 833.6 839.8 855.2 860.6 7672.4 8465.8 9022.9 9153.7 9261.9 544540.0 547368.0 548351.5 558532.0 559525.0 86319.8 115648.0 118426.0 128421.1 134730.7 1008.6 1009.5 1046.1 1056.9 1057.3 15706.5 18317.5 19479.4 19561.8 19610.0 33905.0 39226.6 43815.8 51213.5 51345.0 69060.0 73806.6 74210.0 74340.3 75112.0 8036.8 8069.3 8201.1 8202.5 8307.5 11776.9 12799.2 12966.8 12980.1 13183.9 10748.9 10991.2 11320.2 11355.6 11438.6 9410.2 9720.9 10727.8 11065.9 11199.2 11237.6 11274.8 11371.9 11604.6 11716.3 7735.6 8072.0 8141.3 8144.8 8270.8 8163.1 9242.0 9921.3 11139.3 11182.9 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5388.43 Median:5209.69 stddev:2942.32 (56.5%) stddev-sans-first:2935.74 04:56:30 INFO - PROCESS | 1639 | Values: 1457.2 1487.3 1693.0 1799.5 1839.5 7516.8 7752.2 8081.6 8534.9 8555.3 7703.3 7755.7 7998.8 8310.0 8321.3 2286.9 2336.3 2340.2 2341.5 2347.3 2292.0 2314.4 2314.8 2324.7 2339.8 2286.4 2292.7 2294.8 2304.1 2317.1 1923.7 2009.3 2055.9 2276.8 2306.6 2311.7 2320.1 2329.5 2343.1 2344.3 8374.1 8420.1 8423.5 8436.1 8456.0 5042.4 5206.0 5211.7 5219.1 5222.1 3222.1 3356.8 3410.1 3428.7 3454.1 1045.2 1053.1 1073.6 1087.3 1091.5 10671.5 10739.8 10762.0 10790.6 10812.4 4480.6 4583.9 4671.9 4685.6 4727.0 7906.6 9338.2 9384.9 9398.7 9408.9 5149.4 5207.7 5251.4 5252.2 5255.6 4287.9 4289.1 4694.7 4751.3 4763.0 3737.3 3796.2 3854.3 3873.7 3903.1 1105.2 1260.4 1284.2 1287.5 1296.0 887.2 887.4 892.5 902.3 928.5 5341.5 5635.2 5669.5 5768.1 5777.0 791.2 853.3 914.6 918.9 952.4 3694.1 3738.9 3810.1 3859.3 3888.2 9771.5 10089.4 10099.7 10120.4 10155.8 9938.5 9975.1 10031.4 10059.2 10070.6 7792.8 7893.4 7911.0 7944.4 7954.7 5345.8 5480.1 5606.2 5616.4 5646.2 9209.0 9378.4 9392.4 9401.2 9420.7 6939.0 9375.7 9382.6 9408.6 9511.5 6594.4 6762.6 6988.4 7147.4 7307.9 7698.7 7951.6 7983.0 7997.9 8008.8 6024.6 6090.6 7085.0 7197.5 7363.5 5882.2 5977.0 7006.9 7034.5 7073.2 6488.4 6655.5 6665.1 6705.4 6766.1 3720.9 4689.0 4877.2 4906.5 4992.1 4669.9 4963.4 4971.7 4977.6 5003.6 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4445.25 Median:4941.77 stddev:1377.69 (27.9%) stddev-sans-first:1345.78 04:56:30 INFO - PROCESS | 1639 | Values: 289.3 315.5 352.9 371.5 372.6 4861.4 5038.0 5120.4 5227.6 5322.5 4930.7 5012.8 5133.8 5236.0 5242.5 4052.6 4122.4 4146.3 4153.1 4164.6 3980.3 4077.2 4140.0 4160.4 4186.5 3265.4 3597.1 4026.7 4040.8 4049.3 3148.3 3369.7 3557.0 4072.9 4093.3 3430.3 3651.5 3670.9 3689.7 3695.0 4441.4 4604.4 4646.4 4748.5 5054.1 4271.3 4693.4 4702.4 4728.8 4732.4 5048.9 5486.3 5487.0 5515.3 5596.8 3511.4 3538.5 3766.0 4048.6 4062.5 5644.9 5804.3 5825.0 5833.7 5889.9 3619.8 3864.2 3876.3 3889.5 3899.8 3427.2 3728.6 3791.8 3929.5 3955.7 3670.6 4092.9 4156.7 4171.7 4515.5 4871.1 5091.6 5279.7 5479.6 5609.4 992.6 1150.6 1171.5 1226.2 1241.1 755.7 768.4 837.7 856.4 873.7 4429.5 4673.5 5041.5 5053.0 5108.8 4926.7 5400.5 5445.5 5450.9 5465.5 4388.7 4641.6 5012.5 5033.9 5056.9 2592.6 2782.9 2826.9 2837.7 2873.5 5015.9 5209.8 5420.9 5708.7 5749.8 4935.6 5352.0 5458.6 5476.8 5928.3 4599.7 5888.4 6066.8 6092.4 6098.5 5438.1 5784.1 5812.6 5819.7 5820.4 5357.5 5375.7 5412.4 5651.9 5673.8 5161.6 5999.2 6009.6 6038.9 6057.9 3417.3 3719.3 3833.2 3873.5 3878.2 4948.0 5550.8 5655.9 5693.5 5760.8 4421.9 4551.0 5459.4 5607.2 5705.8 4716.0 4779.7 4926.8 5574.8 5679.2 4678.0 4808.6 5028.7 5150.8 5493.8 5404.9 5506.8 5568.7 5577.3 5585.6 4689.9 5269.6 5378.5 5461.5 5485.7 04:56:30 INFO - PROCESS | 1639 | 04:56:30 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20414.94 Median:2471.19 stddev:29753.36 (1204.0%) stddev-sans-first:29799.76 04:56:30 INFO - PROCESS | 1639 | Values: 634.1 716.9 771.2 793.0 809.5 14830.9 15825.0 15848.4 15855.0 15856.9 14150.5 14326.4 14356.9 14384.0 14411.4 663.9 685.4 701.8 829.3 854.3 833.9 843.2 844.2 844.8 847.6 878.7 897.3 903.5 910.6 913.6 782.9 908.4 910.4 910.5 911.6 666.1 670.8 670.8 681.9 684.5 12634.9 14667.4 15030.8 15056.6 15521.2 14080.2 14364.9 14508.3 14520.4 14655.6 9630.4 11691.2 11784.0 11803.2 11820.0 3672.1 3706.6 3734.7 3736.7 3774.8 101486.0 102036.5 102165.0 104817.0 106119.5 8871.7 10934.9 10953.7 10994.9 11232.2 22491.9 22716.3 22740.0 22764.5 23060.1 7754.4 7781.7 7912.3 8004.2 8068.8 4289.5 4297.1 4369.8 4371.8 4431.8 3476.9 3548.9 3552.1 3572.4 3575.6 453.8 580.1 594.3 621.3 627.4 548.3 607.8 613.2 677.5 695.1 7963.8 8800.8 9156.8 9211.3 9291.3 640.9 654.9 669.3 677.1 679.1 4419.2 4488.6 4612.7 4670.6 4695.2 97701.5 102974.0 105089.0 105474.5 105503.0 66277.7 77353.1 96637.5 96723.5 96868.5 84615.6 86295.0 86522.1 87072.0 88044.0 8115.9 8447.6 8651.4 8672.8 8783.7 48086.2 48243.5 48941.0 52097.2 52261.2 57756.0 69887.0 70695.6 71379.6 71790.6 11249.3 11329.9 11332.4 11356.5 11368.9 11217.8 11752.4 12294.0 12649.7 12706.8 11893.4 12060.0 12307.1 12310.8 12961.9 13548.7 13835.7 13843.2 14181.9 14625.0 13149.5 13604.4 13738.5 13878.4 13976.9 11328.3 11334.9 11547.5 11762.0 11866.2 8389.9 9146.7 9933.8 10283.6 11705.9 04:56:30 INFO - PROCESS | 1639 | -------- Summary: end -------- 04:56:30 INFO - PROCESS | 1639 | 04:56:31 INFO - TEST-INFO | 1639: exit 0 04:56:31 INFO - TEST-OK | dromaeo_css | took 1115629ms 04:56:31 INFO - TEST-START | kraken 04:56:31 INFO - operating with platform_type : mac_ 04:56:31 INFO - Initialising browser for kraken test... 04:56:31 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpLxBugt/profile http://localhost:49248/getInfo.html) 04:56:33 INFO - PROCESS | 1651 | 2016-12-19 04:56:33.948 plugin-container[1653:10043] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 04:56:33 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:33 INFO - PROCESS | 1651 | 2016-12-19 04:56:33.949 plugin-container[1653:10043] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 04:56:33 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:33 INFO - PROCESS | 1651 | 2016-12-19 04:56:33.949 plugin-container[1653:10043] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:56:34 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 04:56:34 INFO - PROCESS | 1651 | colorDepth:24 04:56:34 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 04:56:34 INFO - PROCESS | 1651 | __metrics 04:56:34 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 04:56:34 INFO - TEST-INFO | 1651: exit 0 04:56:34 INFO - Browser initialized. 04:56:34 INFO - Running cycle 1/1 for kraken test... 04:56:34 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpLxBugt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:56:37 INFO - PROCESS | 1654 | 2016-12-19 04:56:37.348 plugin-container[1655:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 04:56:37 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:37 INFO - PROCESS | 1654 | 2016-12-19 04:56:37.349 plugin-container[1655:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 04:56:37 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:37 INFO - PROCESS | 1654 | 2016-12-19 04:56:37.349 plugin-container[1655:10161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:56:47 INFO - PROCESS | 1654 | RSS: Main: 236875776 04:56:47 INFO - PROCESS | 1654 | 04:56:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 04:56:51 INFO - PROCESS | 1654 | RSS: Main: 249552896 04:56:51 INFO - PROCESS | 1654 | 04:56:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 04:56:54 INFO - PROCESS | 1654 | RSS: Main: 257081344 04:56:54 INFO - PROCESS | 1654 | 04:56:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 04:56:58 INFO - PROCESS | 1654 | RSS: Main: 259780608 04:56:58 INFO - PROCESS | 1654 | 04:57:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 04:57:01 INFO - PROCESS | 1654 | RSS: Main: 261984256 04:57:01 INFO - PROCESS | 1654 | 04:57:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:57:03 INFO - PROCESS | 1654 | RSS: Main: 262610944 04:57:03 INFO - PROCESS | 1654 | 04:57:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 04:57:07 INFO - PROCESS | 1654 | RSS: Main: 268632064 04:57:07 INFO - PROCESS | 1654 | 04:57:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 04:57:11 INFO - PROCESS | 1654 | RSS: Main: 267628544 04:57:11 INFO - PROCESS | 1654 | 04:57:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 04:57:14 INFO - PROCESS | 1654 | RSS: Main: 267452416 04:57:14 INFO - PROCESS | 1654 | 04:57:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:57:17 INFO - PROCESS | 1654 | RSS: Main: 266866688 04:57:17 INFO - PROCESS | 1654 | 04:57:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:57:19 INFO - PROCESS | 1654 | RSS: Main: 266780672 04:57:19 INFO - PROCESS | 1654 | 04:57:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:57:22 INFO - PROCESS | 1654 | RSS: Main: 266801152 04:57:22 INFO - PROCESS | 1654 | 04:57:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:57:26 INFO - PROCESS | 1654 | RSS: Main: 267108352 04:57:26 INFO - PROCESS | 1654 | 04:57:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:57:29 INFO - PROCESS | 1654 | RSS: Main: 266952704 04:57:29 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 04:57:31 INFO - PROCESS | 1654 | RSS: Main: 268042240 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | __start_tp_report 04:57:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 04:57:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 04:57:31 INFO - PROCESS | 1654 | |i|pagename|runs| 04:57:31 INFO - PROCESS | 1654 | |0;ai-astar;104;118;127;115;118;117;117;130;173;160 04:57:31 INFO - PROCESS | 1654 | |1;audio-beat-detection;152;114;114;148;124;149;110;144;165;138 04:57:31 INFO - PROCESS | 1654 | |2;audio-dft;209;275;279;203;200;198;192;192;232;252 04:57:31 INFO - PROCESS | 1654 | |3;audio-fft;81;59;59;83;63;62;60;59;73;62 04:57:31 INFO - PROCESS | 1654 | |4;audio-oscillator;87;71;85;104;103;102;91;71;71;83 04:57:31 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;109;114;111;113;109;143;148;112;110;111 04:57:31 INFO - PROCESS | 1654 | |6;imaging-darkroom;100;101;100;100;100;100;172;100;102;102 04:57:31 INFO - PROCESS | 1654 | |7;imaging-desaturate;98;94;93;94;93;91;93;94;92;91 04:57:31 INFO - PROCESS | 1654 | |8;json-parse-financial;58;58;58;70;76;50;58;83;91;90 04:57:31 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;68;72;70;72;78;75;68;86;73;70 04:57:31 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;87;89;89;104;109;109;85;92;91 04:57:31 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;174;158;151;139;140;142;140;156;213;174 04:57:31 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;170;171;171;236;208;173;179;175;171;172 04:57:31 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;68;67;69;71;73;70;67;68;66 04:57:31 INFO - PROCESS | 1654 | __end_tp_report 04:57:31 INFO - PROCESS | 1654 | __start_cc_report 04:57:31 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,933 04:57:31 INFO - PROCESS | 1654 | __end_cc_report 04:57:31 INFO - PROCESS | 1654 | __startTimestamp1482152251844__endTimestamp 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | ------- Summary: start ------- 04:57:31 INFO - PROCESS | 1654 | Number of tests: 14 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:127.90 Median:122.50 stddev:21.71 (17.7%) stddev-sans-first:21.23 04:57:31 INFO - PROCESS | 1654 | Values: 104.0 118.0 127.0 115.0 118.0 117.0 117.0 130.0 173.0 160.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:146.00 stddev:19.05 (13.0%) stddev-sans-first:19.28 04:57:31 INFO - PROCESS | 1654 | Values: 152.0 114.0 114.0 148.0 124.0 149.0 110.0 144.0 165.0 138.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:223.20 Median:220.50 stddev:34.05 (15.4%) stddev-sans-first:35.72 04:57:31 INFO - PROCESS | 1654 | Values: 209.0 275.0 279.0 203.0 200.0 198.0 192.0 192.0 232.0 252.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:66.10 Median:62.50 stddev:9.35 (15.0%) stddev-sans-first:8.22 04:57:31 INFO - PROCESS | 1654 | Values: 81.0 59.0 59.0 83.0 63.0 62.0 60.0 59.0 73.0 62.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:77.00 stddev:13.22 (17.2%) stddev-sans-first:14.02 04:57:31 INFO - PROCESS | 1654 | Values: 87.0 71.0 85.0 104.0 103.0 102.0 91.0 71.0 71.0 83.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:112.50 stddev:14.63 (13.0%) stddev-sans-first:15.15 04:57:31 INFO - PROCESS | 1654 | Values: 109.0 114.0 111.0 113.0 109.0 143.0 148.0 112.0 110.0 111.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:107.70 Median:100.50 stddev:22.61 (22.5%) stddev-sans-first:23.81 04:57:31 INFO - PROCESS | 1654 | Values: 100.0 101.0 100.0 100.0 100.0 100.0 172.0 100.0 102.0 102.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:93.50 stddev:2.00 (2.1%) stddev-sans-first:1.20 04:57:31 INFO - PROCESS | 1654 | Values: 98.0 94.0 93.0 94.0 93.0 91.0 93.0 94.0 92.0 91.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:69.20 Median:73.00 stddev:14.97 (20.5%) stddev-sans-first:15.31 04:57:31 INFO - PROCESS | 1654 | Values: 58.0 58.0 58.0 70.0 76.0 50.0 58.0 83.0 91.0 90.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:72.50 stddev:5.45 (7.5%) stddev-sans-first:5.45 04:57:31 INFO - PROCESS | 1654 | Values: 68.0 72.0 70.0 72.0 78.0 75.0 68.0 86.0 73.0 70.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:88.00 stddev:9.38 (10.7%) stddev-sans-first:9.58 04:57:31 INFO - PROCESS | 1654 | Values: 87.0 87.0 89.0 89.0 104.0 109.0 109.0 85.0 92.0 91.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:157.00 stddev:23.21 (14.8%) stddev-sans-first:23.95 04:57:31 INFO - PROCESS | 1654 | Values: 174.0 158.0 151.0 139.0 140.0 142.0 140.0 156.0 213.0 174.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.60 Median:174.00 stddev:21.95 (12.6%) stddev-sans-first:22.80 04:57:31 INFO - PROCESS | 1654 | Values: 170.0 171.0 171.0 236.0 208.0 173.0 179.0 175.0 171.0 172.0 04:57:31 INFO - PROCESS | 1654 | 04:57:31 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:2.11 (3.1%) stddev-sans-first:2.22 04:57:31 INFO - PROCESS | 1654 | Values: 68.0 68.0 67.0 69.0 71.0 73.0 70.0 67.0 68.0 66.0 04:57:31 INFO - PROCESS | 1654 | -------- Summary: end -------- 04:57:31 INFO - PROCESS | 1654 | 04:57:32 INFO - TEST-INFO | 1654: exit 0 04:57:32 INFO - TEST-OK | kraken | took 61575ms 04:57:32 INFO - SUITE-END | took 1177s 04:57:33 INFO - Completed test suite (00:19:37) 04:57:33 INFO - javascript benchmark 04:57:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6935.14733448289, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2626.658534390529, 2778.458200650873, 3199.5040768680865, 3248.8375581220935, 3255.3489302139565, 4064.715469917107, 4802.2556503741935, 5301.072312345339, 5312.176612625044, 5348.518633323, 5254.973725131378, 5313.07021271277, 5313.495217954298, 5336.199570064492, 5340.519353258206, 4571.542845715435, 4964.702117872939, 4993.226049962253, 5000.274960130779, 5011.448740638527, 4887.535684110004, 4949.183384741514, 4952.430470495885, 4981.103401387749, 4991.351124353829, 4974.87562810929, 4977.0, 4988.526090021461, 5000.399952005764, 5036.244563315514, 4738.265568836836, 4793.0, 4812.975935120337, 4922.655414121013, 4928.778204980785, 4831.661783675144, 4897.33885925399, 4901.436334821506, 4911.09144808208, 4936.901261974776, 3855.1711382052995, 5236.92144617831, 5275.366955965271, 5292.994331077083, 5331.813386531491, 5591.30108736408, 5694.088945768699, 5739.282589676291, 5767.509761670263, 5787.594868359217, 5687.687177205252, 5844.853878653025, 5857.531397488234, 5862.325832529271, 5957.582969192158, 4298.656107511423, 4785.521447855222, 5382.0043291991105, 5493.945060549423, 5495.175723641406, 5040.344755181876, 6033.849153771191, 6054.848628784228, 6082.422169893853, 6088.11722300264, 4565.543445655408, 5493.066178749622, 5551.583631227674, 5560.888782224333, 5592.692401917852, 5696.3164420269, 5741.45456181662, 5749.8275051748515, 5795.130730390475, 5804.767809287666, 5595.972020139873, 5688.146777983336, 5697.116946873159, 5697.914531281951, 5704.657720536781, 5534.889302213933, 5639.013172694762, 5640.492355687926, 5698.917205730898, 5737.8565535861935, 4046.1705350403095, 4644.651651126179, 4653.581177694024, 4654.813807447664, 4683.648726345537, 4087.3868919662286, 4162.91674166515, 4166.500019997619, 4172.144710334374, 4189.581041895847, 5349.839504814861, 5380.650257733235, 5437.646552974044, 5474.09677403224, 5522.475364840334, 5834.358220595731, 5852.4147585241135, 5853.795117170878, 5859.062549991981, 5890.764369425261, 5438.809641662523, 5460.153676180198, 5470.234167216593, 5489.094299440548, 5490.835274941758, 5018.974905125451, 5053.772580233898, 5063.87340316495, 5083.923741143886, 5084.9745751271, 6117.492485019875, 6137.560853825989, 6138.355472675305, 6159.137720719103, 6225.657588832658, 5924.11138329254, 5964.8807023860145, 5989.131575921554, 5995.100734889801, 6038.064100064497, 6154.507639388748, 6175.3515880831865, 6191.907121393093, 6192.876142477216, 6206.565540412128, 4673.485916549109, 5563.888722225452, 5637.182608521659, 5637.295338082739, 5706.629069110494, 5685.545156387562, 5788.797392091256, 5797.913031304618, 5819.098039803837, 5828.59199856006, 5627.352854421794, 5633.971830140823, 5636.957162924872, 5637.098064309855, 5663.85840353978, 5408.0806262936185, 5593.188987596857, 5598.552115830806, 5609.971950140223, 5621.381648018796, 3793.582705902293, 5451.291332126798, 5466.72666366688, 5478.397376288684, 5487.890242195213, 5695.886082278248, 5704.743286552032, 5714.257146570919, 5730.5702072345575, 5790.363060063305, 5469.179623056414, 5556.944430555643, 5564.55483561306, 5567.359753628384, 5611.859703507445], "value": 5276.877542974445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.84605388114164, 461.1537828085464, 478.49518757710615, 501.12553593978515, 523.3536582320836, 35285.29429411414, 37733.245335093314, 37820.810895945484, 37901.431478527775, 37955.0, 31459.685403145962, 31745.841270793673, 38684.83945481633, 38931.80534097325, 38974.0, 8289.41974061816, 8506.659733610648, 9095.863562046557, 9342.532873356338, 9375.156235938764, 11013.394263315515, 11323.0, 11350.43247837609, 11482.425878706073, 11496.19526633136, 9717.785276840395, 11225.045871100967, 11249.10007199422, 11317.83023254652, 11327.943360283187, 10341.79316413675, 10375.014373634569, 11166.162537809656, 11227.494762735696, 11239.976804175245, 11081.501332439979, 11175.273607215586, 11188.328700277929, 11219.990200881879, 11221.887781122166, 37897.62102378996, 38155.809220954, 38165.23669526622, 38221.61778382208, 38238.80880595607, 3392.4741665041956, 3484.6341134181052, 3609.747317687763, 3626.256617393428, 3858.9807050964846, 28740.712592874013, 29865.70134298651, 29921.40157196844, 29923.551146732818, 30019.849900750356, 3057.6483704373836, 3185.984070079658, 3228.80627162371, 3278.4754439289836, 3282.458394364927, 375774.0, 465384.0, 479335.0, 479938.0, 480995.0, 55081.0, 55521.0, 55533.0, 55635.60910977258, 55689.0, 44821.0, 56106.877862442525, 56121.71939140278, 56194.34736742099, 56456.43543564594, 15291.694166116838, 15556.844431555766, 15561.066336019874, 15570.987885787388, 15583.76624350613, 10866.076383507332, 11080.61217857371, 11841.940790296167, 12108.03135749138, 12125.090618203494, 11882.168248222715, 14789.29923058862, 14806.62983425423, 14808.481703140338, 14840.332185051699, 4786.814342908946, 6635.137432133888, 6667.833304167338, 6708.530402871752, 6783.219929708048, 847.5507980770202, 875.2472873328932, 885.2917665867409, 898.3666515106834, 988.7231575158967, 22900.083996640282, 27689.584656230174, 28722.712772872423, 29014.0, 29822.850885745433, 852.1733918099434, 899.6446403670514, 1041.7132160537092, 1081.6052140968648, 1183.8342632031522, 14550.708985820223, 14939.327730252166, 14974.401023959137, 15212.011219270711, 15275.618109547127, 583158.0, 729584.0, 812216.0, 823039.0, 824845.8757706293, 48172.03655926862, 48855.0, 48877.266840997414, 48940.75529622329, 48977.0, 1286.2925391034894, 1323.6227675112548, 1325.3638253638308, 1329.8204742359658, 1337.1375462826422, 22680.886595566917, 27061.729382705922, 27221.0, 27248.318792030357, 27617.447651046463, 41934.37098443587, 42096.789516052224, 42574.361384579875, 42623.7212883614, 42684.359734603386, 552061.0, 553552.0, 554186.0, 554719.0, 555013.0, 23059.0, 23114.422139446648, 23285.0685972566, 23439.179628712915, 23462.765372346057, 32486.51270230901, 32572.0, 38546.229075418894, 38898.0, 38980.6101938977, 31016.534751978288, 32391.0, 33143.83996560109, 33289.83355083306, 33616.83191584027, 32604.51093233556, 32652.020439385906, 33345.66654333426, 33517.32965340728, 33905.49141762826, 32274.515882262254, 32457.0, 32684.836575816968, 32735.672643274214, 32802.67197327996, 21518.462038449165, 23747.643785342887, 25829.3542661435, 25837.870810645825, 26310.21069367922, 19061.61876762429, 19786.70319945173, 21430.6785398216, 22536.323910282717, 23406.882965585064], "value": 18537.97634374736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.71720482216654, 374.61788975245247, 377.1946893382627, 379.9031247032007, 388.7686826338328, 18325.45023649291, 18775.436736897882, 19115.617687646238, 19448.611027779454, 19603.509912252164, 11721.941390293037, 12097.092718046139, 12130.211536250137, 12144.39278036097, 12185.817212741817, 4978.62660300478, 5260.842174734745, 5325.254464374972, 5369.892602147955, 5380.865478363053, 3825.3305671507374, 4056.229316429869, 4936.753162341868, 5286.471352864702, 5330.173823057432, 4332.740035597874, 6492.545521813475, 6555.541112122153, 6563.1796025496815, 6566.737330506774, 6329.620222786648, 6395.104685344056, 6426.357364263559, 6525.967370163166, 6577.539572229945, 5713.657180569179, 5716.256886604757, 5722.055860782966, 5728.283964504437, 5729.742161602738, 12692.619221423372, 22741.886290568607, 22864.314070577908, 23801.047958081657, 23841.88079059611, 1930.9517262068562, 2003.8497112716605, 2139.70044193813, 2365.550545396369, 2981.925451863699, 9708.514574271258, 9757.414555126716, 9795.216382689368, 10082.49587520621, 10288.537015834305, 1815.6641021410949, 1898.7278523389225, 1902.838258748008, 1907.2466375781628, 1935.5838494723703, 106616.0, 115219.4239028808, 115678.0, 116093.0, 116468.41765791201, 9500.714978550517, 11587.942060289644, 11609.535618575332, 11703.941480292544, 11725.472353744102, 10880.367944808342, 11531.76936461266, 14362.281885905662, 14383.856161438462, 14417.0, 7670.463067585327, 7763.805904852424, 7785.18255583167, 7881.802954926173, 7888.802779930547, 8314.127016663284, 8331.37514686401, 8332.250097491255, 8458.788530286669, 8482.787930301669, 3562.28754249146, 3653.6894363978827, 3670.6696397324367, 3679.2089700714473, 3725.0873535983314, 1560.8673262772566, 1631.298541627088, 1738.356807981055, 1801.4685667728052, 1954.4722924810505, 704.1796307302038, 707.6108140522795, 754.8037510247375, 781.5935713428985, 873.6723728601775, 15199.62000949963, 16534.503964881074, 16960.067196304073, 17830.286788528574, 18061.09694515269, 826.4380221449472, 833.5707110837923, 839.7606682095574, 855.2473823035689, 860.617025423693, 7672.386209103258, 8465.788355291044, 9022.86465703015, 9153.679621213225, 9261.861072083924, 544540.0, 547368.0, 548351.516484838, 558532.0, 559525.0, 86319.8420039504, 115648.0, 118426.0, 128421.08420784152, 134730.6526934718, 1008.606643409071, 1009.4548943570536, 1046.1369370269406, 1056.9471526423647, 1057.3038751184602, 15706.528804135894, 18317.450476485727, 19479.415617531497, 19561.82629042262, 19610.0, 33905.0, 39226.60773392344, 43815.78092109646, 51213.48786512087, 51344.973100538526, 69060.0, 73806.63096684482, 74210.0, 74340.25659743333, 75112.0, 8036.839263214586, 8069.314108300626, 8201.056878459054, 8202.548859812767, 8307.460015098999, 11776.941115294369, 12799.168054076456, 12966.80549791735, 12980.091393602735, 13183.868161318263, 10748.946255268673, 10991.175661825235, 11320.150988675718, 11355.602553910574, 11438.599649012245, 9410.200132988499, 9720.902790972283, 10727.785444290914, 11065.944670276596, 11199.160062995144, 11237.550497980315, 11274.774504510027, 11371.886281137082, 11604.593839215588, 11716.297022178696, 7735.613219339123, 8072.0, 8141.267285944294, 8144.83710325802, 8270.83458330842, 8163.1428699985645, 9242.0, 9921.25590580695, 11139.275947063416, 11182.937620925999], "value": 8860.370785332463, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.1912588513374, 1487.330701184467, 1693.0, 1799.4691565988032, 1839.5125291797676, 7516.849663006737, 7752.224777522245, 8081.5959202039885, 8534.91465085349, 8555.272801811854, 7703.268189521987, 7755.681534139196, 7998.840023199533, 8309.961254843145, 8321.251087402132, 2286.9313920582367, 2336.310788317442, 2340.169239919821, 2341.508283260517, 2347.295811256625, 2292.002978704261, 2314.409825494505, 2314.7916687498123, 2324.7094113235844, 2339.789418952294, 2286.3826766772963, 2292.6904867842877, 2294.7705229477087, 2304.1474654377903, 2317.1079134533243, 1923.6633589121848, 2009.25657506722, 2055.8589982559674, 2276.760940101282, 2306.5617532668825, 2311.734150572689, 2320.071971211512, 2329.4758679297192, 2343.0510643189536, 2344.284993077111, 8374.120717324715, 8420.073791882887, 8423.452475589129, 8436.114208008194, 8455.957720211421, 5042.3949126104735, 5206.0368831766245, 5211.687298762048, 5219.11275083233, 5222.138347172712, 3222.081706713599, 3356.8153751543655, 3410.1304167437142, 3428.708559772422, 3454.11919960411, 1045.1586472889321, 1053.1311667874033, 1073.6188653028219, 1087.3258579680648, 1091.5100887288872, 10671.519781609846, 10739.78520429595, 10762.031417172413, 10790.622328218475, 10812.351258924411, 4480.551944805494, 4583.862484125481, 4671.883202919887, 4685.648576356787, 4727.0073284609925, 7906.5651389173, 9338.159565638989, 9384.8592271116, 9398.67104651334, 9408.858867116998, 5149.356330458692, 5207.6875387476875, 5251.36983561975, 5252.2384254282915, 5255.605829562798, 4287.935680964788, 4289.0992891492515, 4694.741789201558, 4751.311059896365, 4762.976185119052, 3737.271232109767, 3796.2217745362686, 3854.3062248795486, 3873.6707379872464, 3903.082775547744, 1105.2484310668822, 1260.4328052376468, 1284.17170924753, 1287.4850059975897, 1296.0409297120252, 887.2148148888151, 887.3921363865772, 892.455602082729, 902.3232575568323, 928.4893308680199, 5341.5459685927135, 5635.211070450058, 5669.518090962314, 5768.077107662838, 5776.971115144482, 791.2245998920974, 853.2576658307312, 914.6295750221171, 918.9081091890891, 952.3857112162755, 3694.131879008431, 3738.9439158412647, 3810.099788327754, 3859.286032084073, 3888.183481468867, 9771.4625695586, 10089.394636321698, 10099.697009089592, 10120.392776433291, 10155.796884062278, 9938.503074846229, 9975.052370024836, 10031.448270345056, 10059.245556583286, 10070.647527336509, 7792.844143117106, 7893.447458677838, 7911.0, 7944.404169687296, 7954.681812727542, 5345.759440825094, 5480.095784195563, 5606.159076138611, 5616.438356164351, 5646.209530665792, 9209.033051529761, 9378.437293762396, 9392.436453812794, 9401.20089792348, 9420.717378478657, 6939.0, 9375.671851485438, 9382.624695012397, 9408.623655053721, 9511.524423778645, 6594.373534514213, 6762.594244345355, 6988.440924726111, 7147.392471639969, 7307.853842923006, 7698.653560589675, 7951.602419878867, 7983.0, 7997.92002079995, 8008.799780005546, 6024.638521688713, 6090.634561926298, 7085.0, 7197.496175267681, 7363.521371110861, 5882.235309409921, 5976.970115149396, 7006.894896576658, 7034.507584469243, 7073.2219455858785, 6488.416042556192, 6655.500837437308, 6665.0668906352175, 6705.396514313735, 6766.086578311865, 3720.8883733488037, 4689.0, 4877.170880950176, 4906.484819093944, 4992.126377883783, 4669.883252918704, 4963.404391473046, 4971.676841005323, 4977.576905963034, 5003.599712022957], "value": 4381.036555191099, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.29412234148674, 315.4763093265179, 352.8659109538375, 371.54856848928546, 372.56037875307146, 4861.440934292557, 5037.974810125949, 5120.361484325017, 5227.607929405291, 5322.520973112414, 4930.654854160211, 5012.82455114071, 5133.794648214067, 5236.005159019793, 5242.501962313584, 4052.5542190359038, 4122.443470131524, 4146.253674338618, 4153.107081977374, 4164.625183733463, 3980.2636512245326, 4077.2049450357194, 4140.0, 4160.375943608453, 4186.539480657125, 3265.3959017581824, 3597.1007248187952, 4026.697997650188, 4040.8381515889005, 4049.2913740095514, 3148.307372378073, 3369.7472689548135, 3557.0, 4072.8574499892657, 4093.3041382964966, 3430.3482338355884, 3651.507046548735, 3670.889873303805, 3689.7048236141045, 3694.9630503695157, 4441.444819397575, 4604.447466304032, 4646.395968524104, 4748.477667456577, 5054.1407960646775, 4271.273883439822, 4693.366395536627, 4702.36518070063, 4728.753841925058, 4732.361131247272, 5048.924266136011, 5486.281797654015, 5486.972565137189, 5515.283013208297, 5596.804111856066, 3511.367953768346, 3538.4515400113023, 3765.9623403765886, 4048.6356227939627, 4062.4921884764403, 5644.858878528029, 5804.332501762309, 5824.970875145597, 5833.7083145842535, 5889.911651325191, 3619.782813031174, 3864.169203621179, 3876.321643712339, 3889.4749208857006, 3899.8440062397754, 3427.234973988398, 3728.6084961079237, 3791.7914514701406, 3929.548101968253, 3955.7428767130045, 3670.632936706308, 4092.894918372023, 4156.729812562174, 4171.666266698657, 4515.503294637587, 4871.074495845778, 5091.618128640367, 5279.736013199325, 5479.561635069185, 5609.4390560943575, 992.593036854886, 1150.6250031212621, 1171.5196769324536, 1226.1600803449664, 1241.0878004666563, 755.7341453066092, 768.3737753730692, 837.7026155714756, 856.3620103023287, 873.7116751471999, 4429.512753597101, 4673.462551806518, 5041.521350268303, 5053.0, 5108.846734597893, 4926.704397736147, 5400.540954018952, 5445.455454454602, 5450.91823622646, 5465.5243084367, 4388.720908473099, 4641.605463535638, 5012.498750125032, 5033.949660503348, 5056.9494305057215, 2592.585186370172, 2782.916512504628, 2826.929326766847, 2837.730415610514, 2873.5258682317603, 5015.949840501548, 5209.817656382084, 5420.864478387993, 5708.686022268815, 5749.8275051748515, 4935.555799978019, 5351.973240133852, 5458.645188062843, 5476.780928762805, 5928.288605367297, 4599.724016559017, 5888.438529239795, 6066.817995460143, 6092.39076092396, 6098.512119030555, 5438.117797567295, 5784.074548072288, 5812.645878419247, 5819.6508209507565, 5820.447057529528, 5357.517823395913, 5375.6774593524515, 5412.377576578744, 5651.943480565142, 5673.8297851064535, 5161.612879034163, 5999.220101386792, 6009.579329446897, 6038.889971664684, 6057.909131362945, 3417.2652879631, 3719.3305205062265, 3833.1758671885677, 3873.457715919717, 3878.185581028016, 4947.975260123821, 5550.777968881361, 5655.915161272502, 5693.544516438592, 5760.769569217352, 4421.911561768811, 4550.954490455053, 5459.39946605881, 5607.186957891001, 5705.773258749389, 4716.0, 4779.737114458738, 4926.827561035347, 5574.777008919597, 5679.204911312337, 4678.0, 4808.591269742111, 5028.700461105702, 5150.845474635767, 5493.807716729895, 5404.918926216189, 5506.752196151103, 5568.693721845338, 5577.330720313588, 5585.609007369445, 4689.929651055305, 5269.552088072557, 5378.489043540857, 5461.5357694596405, 5485.696007676705], "value": 4008.013328068528, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.1248334183787, 716.853045125749, 771.1671394893516, 793.0087390761548, 809.46979728278, 14830.925845370772, 15825.0, 15848.366065357372, 15855.048697078184, 15856.920715396407, 14150.50473233437, 14326.426942922271, 14356.856431435683, 14383.993120481571, 14411.423543058265, 663.9435647969929, 685.3865790117842, 701.8455939693272, 829.3365307753803, 854.2866706300247, 833.8832563441093, 843.2284459719349, 844.2233145506118, 844.8056946902216, 847.5719761520423, 878.7012415778634, 897.2552781191596, 903.5482258870564, 910.622091831889, 913.5660561233427, 782.8943092682529, 908.3596064774351, 910.4264313482497, 910.4719262827545, 911.6216770040426, 666.0675054923098, 670.765232168737, 670.8389986403276, 681.9283975182584, 684.5003147702205, 12634.936825315906, 14667.413303467849, 15030.774538381933, 15056.623584410478, 15521.223938803014, 14080.15519068859, 14364.856351436456, 14508.347124379323, 14520.419183232658, 14655.560333190022, 9630.374025688378, 11691.240069395462, 11783.960720130923, 11803.232789868716, 11820.0, 3672.140234185529, 3706.6478684524927, 3734.7012239021083, 3736.7384283100196, 3774.754640948357, 101486.0, 102036.48981755118, 102165.0, 104816.95183048148, 106119.46940265327, 8871.733847984571, 10934.890651093547, 10953.671389858317, 10994.890051099546, 11232.157588180919, 22491.88754056219, 22716.318510444715, 22740.0, 22764.544709105725, 23060.077596895935, 7754.3796496280165, 7781.727639532589, 7912.327452166515, 8004.199580041949, 8068.798280043045, 4289.549597292239, 4297.119090586422, 4369.799938073682, 4371.759553224603, 4431.778411079433, 3476.91307717304, 3548.929021419609, 3552.1474846036767, 3572.392693242155, 3575.606683264839, 453.77538118631486, 580.1240127407644, 594.2779522879722, 621.3382747374033, 627.4102343796816, 548.3036543589619, 607.7720854679495, 613.2212090644856, 677.4681874728315, 695.1067877777133, 7963.800904977422, 8800.840641636574, 9156.771080723036, 9211.263098952068, 9291.303152263607, 640.8750293692685, 654.8509495834121, 669.293894940842, 677.0622687577642, 679.1035832700886, 4419.160359531678, 4488.596026357578, 4612.7001744886475, 4670.556297151765, 4695.248760198352, 97701.5114924435, 102974.0, 105089.0, 105474.47262763637, 105503.0, 66277.66861165663, 77353.066173345, 96637.51681241549, 96723.51638241764, 96868.51565742267, 84615.576922115, 86295.0, 86522.13477865266, 87072.0, 88044.0, 8115.878261826196, 8447.577621118797, 8651.437656552303, 8672.78318042054, 8783.692570759993, 48086.22965244053, 48243.5175648239, 48941.0, 52097.218541722665, 52261.216081758044, 57756.0, 69887.0, 70695.64652176706, 71379.64310178623, 71790.56418871698, 11249.268797528135, 11329.886701133213, 11332.433378330885, 11356.510833972085, 11368.943155284502, 11217.822128676371, 11752.439115215679, 12294.0, 12649.747005059664, 12706.809397858855, 11893.405329733305, 12059.974902133066, 12307.138500305251, 12310.81533777012, 12961.870381296067, 13548.72902541924, 13835.654108647364, 13843.238621875893, 14181.929090354895, 14625.0, 13149.474021039052, 13604.387802548712, 13738.519151829638, 13878.375473103533, 13976.938101706392, 11328.32030078198, 11334.943325283652, 11547.538098475967, 11762.0, 11866.169368144492, 8389.874151887849, 9146.725598232064, 9933.843752323131, 10283.560301557638, 11705.88294117048], "value": 7309.555740615775, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 127.0, 115.0, 118.0, 117.0, 117.0, 130.0, 173.0, 160.0], "value": 127.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 114.0, 148.0, 124.0, 149.0, 110.0, 144.0, 165.0, 138.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 275.0, 279.0, 203.0, 200.0, 198.0, 192.0, 192.0, 232.0, 252.0], "value": 223.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 83.0, 63.0, 62.0, 60.0, 59.0, 73.0, 62.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 85.0, 104.0, 103.0, 102.0, 91.0, 71.0, 71.0, 83.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 114.0, 111.0, 113.0, 109.0, 143.0, 148.0, 112.0, 110.0, 111.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 100.0, 100.0, 100.0, 100.0, 172.0, 100.0, 102.0, 102.0], "value": 107.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 93.0, 94.0, 93.0, 91.0, 93.0, 94.0, 92.0, 91.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 70.0, 76.0, 50.0, 58.0, 83.0, 91.0, 90.0], "value": 69.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 70.0, 72.0, 78.0, 75.0, 68.0, 86.0, 73.0, 70.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 89.0, 89.0, 104.0, 109.0, 109.0, 85.0, 92.0, 91.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 158.0, 151.0, 139.0, 140.0, 142.0, 140.0, 156.0, 213.0, 174.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 171.0, 236.0, 208.0, 173.0, 179.0, 175.0, 171.0, 172.0], "value": 182.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 69.0, 71.0, 73.0, 70.0, 67.0, 68.0, 66.0], "value": 68.7, "unit": "score"}]}]} 04:57:33 INFO - Return code: 0 04:57:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:57:33 ERROR - # TBPL SUCCESS # 04:57:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:57:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:57:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:57:33 INFO - 2016-12-19 04:57:33.176 screenresolution[1659:10543] starting screenresolution argv=screenresolution get 04:57:33 INFO - 2016-12-19 04:57:33.178 screenresolution[1659:10543] Display 0: 1600x1200x32@60 04:57:33 INFO - 2016-12-19 04:57:33.184 screenresolution[1660:10547] starting screenresolution argv=screenresolution list 04:57:33 INFO - Available Modes on Display 0 04:57:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:57:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:57:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:57:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:57:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:57:33 INFO - Intel Iris: 04:57:33 INFO - Chipset Model: Intel Iris 04:57:33 INFO - Type: GPU 04:57:33 INFO - Bus: Built-In 04:57:33 INFO - VRAM (Dynamic, Max): 1536 MB 04:57:33 INFO - Vendor: Intel (0x8086) 04:57:33 INFO - Device ID: 0x0a2e 04:57:33 INFO - Revision ID: 0x0009 04:57:33 INFO - Displays: 04:57:33 INFO - Display: 04:57:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:57:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:57:33 INFO - Display Serial Number: 1600x1200 60 04:57:33 INFO - Main Display: Yes 04:57:33 INFO - Mirror: Off 04:57:33 INFO - Online: Yes 04:57:33 INFO - Rotation: Supported 04:57:33 INFO - Return code: 0 04:57:33 INFO - Running post-action listener: _resource_record_post_action 04:57:33 INFO - [mozharness: 2016-12-19 12:57:33.419821Z] Finished run-tests step (success) 04:57:33 INFO - Running post-run listener: _resource_record_post_run 04:57:33 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 40089600; Write bytes: 556095488; Read time: 394; Write time: 1806 04:57:33 INFO - TinderboxPrint: CPU usage
54.6% 04:57:33 INFO - TinderboxPrint: I/O read bytes / time
40,089,600 / 394 04:57:33 INFO - TinderboxPrint: I/O write bytes / time
556,095,488 / 1,806 04:57:33 INFO - TinderboxPrint: CPU idle
2,180.4 (45.4%) 04:57:33 INFO - TinderboxPrint: CPU system
968.0 (20.2%) 04:57:33 INFO - TinderboxPrint: CPU user
1,653.4 (34.4%) 04:57:33 INFO - TinderboxPrint: Swap in / out
290,074,624 / 0 04:57:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250567168; Write bytes: 253251584; Read time: 18225; Write time: 578 04:57:33 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35739648; Write bytes: 301488128; Read time: 359; Write time: 1224 04:57:33 INFO - Running post-run listener: _upload_blobber_files 04:57:33 INFO - Blob upload gear active. 04:57:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:57:33 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 04:57:33 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'] 04:57:33 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 04:57:34 INFO - (blobuploader) - INFO - Open directory for files ... 04:57:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:57:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:57:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:35 INFO - (blobuploader) - INFO - Done attempting. 04:57:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:57:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:57:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:35 INFO - (blobuploader) - INFO - Done attempting. 04:57:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:57:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:57:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:37 INFO - (blobuploader) - INFO - Done attempting. 04:57:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:57:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:57:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:39 INFO - (blobuploader) - INFO - Done attempting. 04:57:39 INFO - (blobuploader) - INFO - Iteration through files over. 04:57:39 INFO - Return code: 0 04:57:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:57:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:57:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e94357152c22884ba897ca533893e5a824235a0955a665298f94345095d505a132824eec4171fc5f91f832db2b94a5555ce5bbc3551ce8e4e75cd844a93d69f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4fcd7a5c0100f951a444e6f12869cbe4acb37afe50185266969b259e1fa14e84995fb4945ac8751e8620ba170fab5ddb3511f6019c7c947d38749330ff8691", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32d4787f3d9e7b5b148c500afdbcffcf164f912a257c45ddaaa2939183b16d5e69dfa6db69dd1caac360a2480caec03933c636be507201a9086f6548f8238234", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47fdf5cf62b5ee3e6e703cd8185a3aa3f7a884e3ab2dbeec838840030bd9469202484f5b1730d8c1145396fb142bf6474aefde8c5fd43b154c30c541c32329ee"} 04:57:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:57:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:57:39 INFO - Contents: 04:57:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e94357152c22884ba897ca533893e5a824235a0955a665298f94345095d505a132824eec4171fc5f91f832db2b94a5555ce5bbc3551ce8e4e75cd844a93d69f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4fcd7a5c0100f951a444e6f12869cbe4acb37afe50185266969b259e1fa14e84995fb4945ac8751e8620ba170fab5ddb3511f6019c7c947d38749330ff8691", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32d4787f3d9e7b5b148c500afdbcffcf164f912a257c45ddaaa2939183b16d5e69dfa6db69dd1caac360a2480caec03933c636be507201a9086f6548f8238234", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47fdf5cf62b5ee3e6e703cd8185a3aa3f7a884e3ab2dbeec838840030bd9469202484f5b1730d8c1145396fb142bf6474aefde8c5fd43b154c30c541c32329ee"} 04:57:39 INFO - Running post-run listener: copy_logs_to_upload_dir 04:57:39 INFO - Copying logs to upload dir... 04:57:39 INFO - mkdir: /builds/slave/test/build/upload/logs 04:57:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.454368 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-19 04:57:39.229435) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 04:57:39.233595) ========= 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.FeWixSUvhr/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.KM26809fDu/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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/6e94357152c22884ba897ca533893e5a824235a0955a665298f94345095d505a132824eec4171fc5f91f832db2b94a5555ce5bbc3551ce8e4e75cd844a93d69f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4fcd7a5c0100f951a444e6f12869cbe4acb37afe50185266969b259e1fa14e84995fb4945ac8751e8620ba170fab5ddb3511f6019c7c947d38749330ff8691", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32d4787f3d9e7b5b148c500afdbcffcf164f912a257c45ddaaa2939183b16d5e69dfa6db69dd1caac360a2480caec03933c636be507201a9086f6548f8238234", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47fdf5cf62b5ee3e6e703cd8185a3aa3f7a884e3ab2dbeec838840030bd9469202484f5b1730d8c1145396fb142bf6474aefde8c5fd43b154c30c541c32329ee"} build_url:https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/S4xJ5v3pT7Sdn9Z-RA8TiA/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/6e94357152c22884ba897ca533893e5a824235a0955a665298f94345095d505a132824eec4171fc5f91f832db2b94a5555ce5bbc3551ce8e4e75cd844a93d69f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4fcd7a5c0100f951a444e6f12869cbe4acb37afe50185266969b259e1fa14e84995fb4945ac8751e8620ba170fab5ddb3511f6019c7c947d38749330ff8691", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32d4787f3d9e7b5b148c500afdbcffcf164f912a257c45ddaaa2939183b16d5e69dfa6db69dd1caac360a2480caec03933c636be507201a9086f6548f8238234", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47fdf5cf62b5ee3e6e703cd8185a3aa3f7a884e3ab2dbeec838840030bd9469202484f5b1730d8c1145396fb142bf6474aefde8c5fd43b154c30c541c32329ee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 04:57:39.268380) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:57:39.268752) ========= 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.FeWixSUvhr/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.KM26809fDu/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.005110 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 04:57:39.317235) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 04:57:39.317564) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 04:57:39.317923) ========= ========= Total master_lag: 0.18 =========