builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0062 starttime: 1492443818.56 results: success (0) buildid: 20170417072206 builduid: 4aa4b5f50a554583ad19e628f0c4e512 revision: 05c212a94183838f12feebb2c3fd483a6eec18c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.564652) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.565130) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.565414) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.591621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.591994) ========= 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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-17 08:43:38-- 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% 23.7M=0s 2017-04-17 08:43:38 (23.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138912 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.758888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.759558) ========= 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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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.028776 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:38.813482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-17 08:43:38.813902) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 05c212a94183838f12feebb2c3fd483a6eec18c2 --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 05c212a94183838f12feebb2c3fd483a6eec18c2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-17 08:43:38,919 truncating revision to first 12 chars 2017-04-17 08:43:38,919 Setting DEBUG logging. 2017-04-17 08:43:38,919 attempt 1/10 2017-04-17 08:43:38,919 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/05c212a94183?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-17 08:43:39,713 unpacking tar archive at: mozilla-central-05c212a94183/testing/mozharness/ program finished with exit code 0 elapsedTime=1.063755 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-17 08:43:39.919906) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:39.920202) ========= script_repo_revision: 05c212a94183838f12feebb2c3fd483a6eec18c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:39.920581) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:39.921413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 08:43:39.941241) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-17 08:43:39.941536) ========= /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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:43:40 INFO - MultiFileLogger online at 20170417 08:43:40 in /builds/slave/test 08:43:40 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 08:43:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:40 INFO - {'append_to_log': False, 08:43:40 INFO - 'base_work_dir': '/builds/slave/test', 08:43:40 INFO - 'blob_upload_branch': 'Firefox', 08:43:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:40 INFO - 'branch': 'Firefox', 08:43:40 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:40 INFO - 'config_files': ('talos/mac_config.py',), 08:43:40 INFO - 'default_actions': ('clobber', 08:43:40 INFO - 'read-buildbot-config', 08:43:40 INFO - 'download-and-extract', 08:43:40 INFO - 'populate-webroot', 08:43:40 INFO - 'create-virtualenv', 08:43:40 INFO - 'install', 08:43:40 INFO - 'run-tests'), 08:43:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:40 INFO - 'download_minidump_stackwalk': True, 08:43:40 INFO - 'download_symbols': 'ondemand', 08:43:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:40 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:40 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:40 INFO - 'gecko_profile': False, 08:43:40 INFO - 'gecko_profile_interval': 0, 08:43:40 INFO - 'installer_path': 'installer.exe', 08:43:40 INFO - 'log_level': 'info', 08:43:40 INFO - 'log_name': 'talos', 08:43:40 INFO - 'log_to_console': True, 08:43:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:40 INFO - 'opt_config_files': (), 08:43:40 INFO - 'pip_index': False, 08:43:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:40 INFO - 'cmd': ('bash', 08:43:40 INFO - '-c', 08:43:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:40 INFO - 'enabled': True, 08:43:40 INFO - 'halt_on_failure': False, 08:43:40 INFO - 'name': 'check_screen_resolution'},), 08:43:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:40 INFO - 'cmd': ('bash', 08:43:40 INFO - '-c', 08:43:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:40 INFO - 'enabled': True, 08:43:40 INFO - 'halt_on_failure': False, 08:43:40 INFO - 'name': 'check_screen_resolution'},), 08:43:40 INFO - 'run_cmd_checks_enabled': True, 08:43:40 INFO - 'suite': 'dromaeojs-e10s', 08:43:40 INFO - 'system_bits': '32', 08:43:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:40 INFO - 'title': 't-yosemite-r7-0062', 08:43:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:40 INFO - 'use_talos_json': True, 08:43:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:40 INFO - 'work_dir': 'build'} 08:43:40 INFO - [mozharness: 2017-04-17 15:43:40.161315Z] Running clobber step. 08:43:40 INFO - Running pre-action listener: _resource_record_pre_action 08:43:40 INFO - Running main action method: clobber 08:43:40 INFO - rmtree: /builds/slave/test/build 08:43:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:42 INFO - Running post-action listener: _resource_record_post_action 08:43:42 INFO - [mozharness: 2017-04-17 15:43:42.751529Z] Finished clobber step (success) 08:43:42 INFO - [mozharness: 2017-04-17 15:43:42.751654Z] Running read-buildbot-config step. 08:43:42 INFO - Running pre-action listener: _resource_record_pre_action 08:43:42 INFO - Running main action method: read_buildbot_config 08:43:42 INFO - Using buildbot properties: 08:43:42 INFO - { 08:43:42 INFO - "project": "", 08:43:42 INFO - "product": "firefox", 08:43:42 INFO - "script_repo_revision": "production", 08:43:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:43:42 INFO - "repository": "", 08:43:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:43:42 INFO - "buildid": "20170417072206", 08:43:42 INFO - "pgo_build": "False", 08:43:42 INFO - "basedir": "/builds/slave/test", 08:43:42 INFO - "buildnumber": 39, 08:43:42 INFO - "slavename": "t-yosemite-r7-0062", 08:43:42 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:43:42 INFO - "platform": "yosemite_r7", 08:43:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:43:42 INFO - "branch": "mozilla-central", 08:43:42 INFO - "repo_path": "mozilla-central", 08:43:42 INFO - "revision": "05c212a94183838f12feebb2c3fd483a6eec18c2", 08:43:42 INFO - "stage_platform": "macosx64", 08:43:42 INFO - "builduid": "4aa4b5f50a554583ad19e628f0c4e512", 08:43:42 INFO - "slavebuilddir": "test" 08:43:42 INFO - } 08:43:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:43:42 INFO - Running post-action listener: _resource_record_post_action 08:43:42 INFO - [mozharness: 2017-04-17 15:43:42.755192Z] Finished read-buildbot-config step (success) 08:43:42 INFO - [mozharness: 2017-04-17 15:43:42.755303Z] Running download-and-extract step. 08:43:42 INFO - Running pre-action listener: _resource_record_pre_action 08:43:42 INFO - Running main action method: download_and_extract 08:43:42 INFO - mkdir: /builds/slave/test/build/tests 08:43:42 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')]} 08:43:42 INFO - trying https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:43:42 INFO - Downloading https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:43:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:43:43 INFO - Downloaded 1325 bytes. 08:43:43 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:43:43 INFO - Using the following test package requirements: 08:43:43 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:43:43 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:43:43 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:43:43 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:43:43 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'jsshell-mac.zip'], 08:43:43 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:43:43 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:43:43 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:43:43 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:43:43 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:43:43 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:43:43 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:43:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:43:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:43:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:43:43 INFO - Fetch https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:43:45 INFO - Expected file size: 52663783 08:43:45 INFO - Obtained file size: 52663783 08:44:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:44:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:44:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:44:01 INFO - Fetch https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:44:03 INFO - Expected file size: 52663783 08:44:03 INFO - Obtained file size: 52663783 08:44:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:44:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:44:19 INFO - Fetch https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:44:20 INFO - Expected file size: 14408802 08:44:20 INFO - Obtained file size: 14408802 08:44:20 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')]} 08:44:20 INFO - trying https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:44:20 INFO - Downloading https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:44:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:44:23 INFO - Downloaded 71429249 bytes. 08:44:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:44:23 INFO - mkdir: /builds/slave/test/properties 08:44:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:23 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:23 INFO - Contents: 08:44:23 INFO - build_url:https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:44:23 INFO - Running post-action listener: _resource_record_post_action 08:44:23 INFO - Running post-action listener: set_extra_try_arguments 08:44:23 INFO - [mozharness: 2017-04-17 15:44:23.683634Z] Finished download-and-extract step (success) 08:44:23 INFO - [mozharness: 2017-04-17 15:44:23.683754Z] Running populate-webroot step. 08:44:23 INFO - Running pre-action listener: _resource_record_pre_action 08:44:23 INFO - Running main action method: populate_webroot 08:44:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:23 INFO - '--tppagecycles', 08:44:23 INFO - '7'], 08:44:23 INFO - 'tests': ['tsvgm']}}, 08:44:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:44:23 INFO - 'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:44:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:44:23 INFO - 'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['damp', 'tps']}, 08:44:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:44:23 INFO - 'tests': ['damp', 'tps']}, 08:44:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['dromaeo_dom']}, 08:44:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:44:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:44:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['a11yr', 08:44:23 INFO - 'ts_paint', 08:44:23 INFO - 'tpaint', 08:44:23 INFO - 'sessionrestore', 08:44:23 INFO - 'sessionrestore_no_auto_restore', 08:44:23 INFO - 'tabpaint']}, 08:44:23 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:23 INFO - 'ts_paint', 08:44:23 INFO - 'tpaint', 08:44:23 INFO - 'sessionrestore', 08:44:23 INFO - 'sessionrestore_no_auto_restore', 08:44:23 INFO - 'tabpaint']}, 08:44:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:23 INFO - 'ts_paint', 08:44:23 INFO - 'tpaint', 08:44:23 INFO - 'sessionrestore', 08:44:23 INFO - 'sessionrestore_no_auto_restore', 08:44:23 INFO - 'tabpaint']}, 08:44:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:23 INFO - 'ts_paint', 08:44:23 INFO - 'tpaint', 08:44:23 INFO - 'sessionrestore', 08:44:23 INFO - 'sessionrestore_no_auto_restore', 08:44:23 INFO - 'tabpaint']}, 08:44:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['a11yr', 08:44:23 INFO - 'ts_paint', 08:44:23 INFO - 'tpaint', 08:44:23 INFO - 'sessionrestore', 08:44:23 INFO - 'sessionrestore_no_auto_restore', 08:44:23 INFO - 'tabpaint']}, 08:44:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['a11yr', 08:44:23 INFO - 'ts_paint', 08:44:23 INFO - 'tpaint', 08:44:23 INFO - 'sessionrestore', 08:44:23 INFO - 'sessionrestore_no_auto_restore', 08:44:23 INFO - 'tabpaint']}, 08:44:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:44:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:44:23 INFO - 'bloom_basic_ref']}, 08:44:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['tsvgx', 08:44:23 INFO - 'tsvgr_opacity', 08:44:23 INFO - 'tart', 08:44:23 INFO - 'tscrollx', 08:44:23 INFO - 'cart', 08:44:23 INFO - 'tsvg_static']}, 08:44:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:23 INFO - 'tsvgr_opacity', 08:44:23 INFO - 'tart', 08:44:23 INFO - 'tscrollx', 08:44:23 INFO - 'cart', 08:44:23 INFO - 'tsvg_static']}, 08:44:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:44:23 INFO - 'talos_options': ['--disable-e10s'], 08:44:23 INFO - 'tests': ['tp5o']}, 08:44:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:44:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:44:23 INFO - 'talos_options': ['--disable-e10s', 08:44:23 INFO - '--xperf_path', 08:44:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:23 INFO - 'tests': ['tp5n']}, 08:44:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:44:23 INFO - 'talos_options': ['--xperf_path', 08:44:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:23 INFO - 'tests': ['tp5n']}}, 08:44:23 INFO - 'talos.zip': {'path': '', 08:44:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:23 INFO - Running post-action listener: _resource_record_post_action 08:44:23 INFO - [mozharness: 2017-04-17 15:44:23.702879Z] Finished populate-webroot step (success) 08:44:23 INFO - [mozharness: 2017-04-17 15:44:23.702973Z] Running create-virtualenv step. 08:44:23 INFO - Running pre-action listener: _resource_record_pre_action 08:44:23 INFO - Running main action method: create_virtualenv 08:44:23 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:23 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 08:44:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:23 INFO - Using real prefix '/tools/python27' 08:44:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:24 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:27 INFO - Installing pip.................done. 08:44:27 INFO - Return code: 0 08:44:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:27 INFO - Reading from file tmpfile_stdout 08:44:27 INFO - Output received: 08:44:27 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) 08:44:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:44:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:27 INFO - 'HOME': '/Users/cltbld', 08:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:27 INFO - 'LOGNAME': 'cltbld', 08:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:27 INFO - 'MOZ_NO_REMOTE': '1', 08:44:27 INFO - 'NO_EM_RESTART': '1', 08:44:27 INFO - 'PAGER': '/bin/cat', 08:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:27 INFO - 'PWD': '/builds/slave/test', 08:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:27 INFO - 'SHELL': '/bin/bash', 08:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:27 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:27 INFO - 'USER': 'cltbld', 08:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:27 INFO - 'XPC_FLAGS': '0x0', 08:44:27 INFO - 'XPC_SERVICE_NAME': '0', 08:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:27 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 08:44:27 INFO - Cleaning up... 08:44:27 INFO - Return code: 0 08:44:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:44:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:27 INFO - 'HOME': '/Users/cltbld', 08:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:27 INFO - 'LOGNAME': 'cltbld', 08:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:27 INFO - 'MOZ_NO_REMOTE': '1', 08:44:27 INFO - 'NO_EM_RESTART': '1', 08:44:27 INFO - 'PAGER': '/bin/cat', 08:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:27 INFO - 'PWD': '/builds/slave/test', 08:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:27 INFO - 'SHELL': '/bin/bash', 08:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:27 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:27 INFO - 'USER': 'cltbld', 08:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:27 INFO - 'XPC_FLAGS': '0x0', 08:44:27 INFO - 'XPC_SERVICE_NAME': '0', 08:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:28 INFO - Downloading/unpacking psutil>=3.1.1 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:31 INFO - Installing collected packages: psutil 08:44:31 INFO - Running setup.py install for psutil 08:44:31 INFO - building 'psutil._psutil_osx' extension 08:44:31 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 08:44:32 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 08:44:32 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 08:44:32 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 08:44:32 INFO - building 'psutil._psutil_posix' extension 08:44:32 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 08:44:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:44:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:44:32 INFO - ^ 08:44:32 INFO - 1 warning generated. 08:44:32 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 08:44:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:32 INFO - Successfully installed psutil 08:44:32 INFO - Cleaning up... 08:44:32 INFO - Return code: 0 08:44:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:44: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 mozsystemmonitor==0.3 08:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:32 INFO - 'HOME': '/Users/cltbld', 08:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:32 INFO - 'LOGNAME': 'cltbld', 08:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:32 INFO - 'MOZ_NO_REMOTE': '1', 08:44:32 INFO - 'NO_EM_RESTART': '1', 08:44:32 INFO - 'PAGER': '/bin/cat', 08:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:32 INFO - 'PWD': '/builds/slave/test', 08:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:32 INFO - 'SHELL': '/bin/bash', 08:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:32 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:32 INFO - 'USER': 'cltbld', 08:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:32 INFO - 'XPC_FLAGS': '0x0', 08:44:32 INFO - 'XPC_SERVICE_NAME': '0', 08:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:35 INFO - Installing collected packages: mozsystemmonitor 08:44:35 INFO - Running setup.py install for mozsystemmonitor 08:44:35 INFO - Successfully installed mozsystemmonitor 08:44:35 INFO - Cleaning up... 08:44:36 INFO - Return code: 0 08:44:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:36 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 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:36 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 08:44:36 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 08:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:36 INFO - 'HOME': '/Users/cltbld', 08:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:36 INFO - 'LOGNAME': 'cltbld', 08:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:36 INFO - 'MOZ_NO_REMOTE': '1', 08:44:36 INFO - 'NO_EM_RESTART': '1', 08:44:36 INFO - 'PAGER': '/bin/cat', 08:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:36 INFO - 'PWD': '/builds/slave/test', 08:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:36 INFO - 'SHELL': '/bin/bash', 08:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:36 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:36 INFO - 'USER': 'cltbld', 08:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:36 INFO - 'XPC_FLAGS': '0x0', 08:44:36 INFO - 'XPC_SERVICE_NAME': '0', 08:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:36 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:36 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 08:44:36 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 08:44:36 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 08:44:36 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 08:44:36 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 08:44:36 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 08:44:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:40 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 08:44:40 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 08:44:40 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 08:44:40 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 08:44:40 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 08:44:40 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 08:44:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:40 INFO - warning: no files found matching '*.txt' 08:44:40 INFO - no previously-included directories found matching 'build' 08:44:40 INFO - no previously-included directories found matching 'dist' 08:44:40 INFO - no previously-included directories found matching '.git*' 08:44:40 INFO - Installing collected packages: jsonschema, functools32 08:44:40 INFO - Running setup.py install for functools32 08:44:40 INFO - warning: no files found matching '*.txt' 08:44:40 INFO - no previously-included directories found matching 'build' 08:44:40 INFO - no previously-included directories found matching 'dist' 08:44:40 INFO - no previously-included directories found matching '.git*' 08:44:40 INFO - Successfully installed jsonschema functools32 08:44:40 INFO - Cleaning up... 08:44:40 INFO - Return code: 0 08:44:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:40 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 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:40 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 08:44:40 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 08:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:40 INFO - 'HOME': '/Users/cltbld', 08:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:40 INFO - 'LOGNAME': 'cltbld', 08:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:40 INFO - 'MOZ_NO_REMOTE': '1', 08:44:40 INFO - 'NO_EM_RESTART': '1', 08:44:40 INFO - 'PAGER': '/bin/cat', 08:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:40 INFO - 'PWD': '/builds/slave/test', 08:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:40 INFO - 'SHELL': '/bin/bash', 08:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:40 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:40 INFO - 'USER': 'cltbld', 08:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:40 INFO - 'XPC_FLAGS': '0x0', 08:44:40 INFO - 'XPC_SERVICE_NAME': '0', 08:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:41 INFO - Cleaning up... 08:44:41 INFO - Return code: 0 08:44:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:41 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 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:41 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 08:44:41 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 08:44:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:41 INFO - 'HOME': '/Users/cltbld', 08:44:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:41 INFO - 'LOGNAME': 'cltbld', 08:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:41 INFO - 'MOZ_NO_REMOTE': '1', 08:44:41 INFO - 'NO_EM_RESTART': '1', 08:44:41 INFO - 'PAGER': '/bin/cat', 08:44:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:41 INFO - 'PWD': '/builds/slave/test', 08:44:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:41 INFO - 'SHELL': '/bin/bash', 08:44:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:41 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:41 INFO - 'USER': 'cltbld', 08:44:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:41 INFO - 'XPC_FLAGS': '0x0', 08:44:41 INFO - 'XPC_SERVICE_NAME': '0', 08:44:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:41 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:43 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:44 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 08:44:44 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 08:44:44 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 08:44:44 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 08:44:44 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 08:44:44 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 08:44:45 INFO - Downloading docopt-0.6.1.tar.gz 08:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:45 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:45 INFO - Running setup.py install for blobuploader 08:44:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:45 INFO - Running setup.py install for requests 08:44:45 INFO - Running setup.py install for docopt 08:44:46 INFO - Successfully installed blobuploader requests docopt 08:44:46 INFO - Cleaning up... 08:44:46 INFO - Return code: 0 08:44:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:46 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 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:46 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 08:44:46 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 08:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:46 INFO - 'HOME': '/Users/cltbld', 08:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:46 INFO - 'LOGNAME': 'cltbld', 08:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:46 INFO - 'MOZ_NO_REMOTE': '1', 08:44:46 INFO - 'NO_EM_RESTART': '1', 08:44:46 INFO - 'PAGER': '/bin/cat', 08:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:46 INFO - 'PWD': '/builds/slave/test', 08:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:46 INFO - 'SHELL': '/bin/bash', 08:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:46 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:46 INFO - 'USER': 'cltbld', 08:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:46 INFO - 'XPC_FLAGS': '0x0', 08:44:46 INFO - 'XPC_SERVICE_NAME': '0', 08:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:46 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-g2yRc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:46 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-vhTQ3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:46 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-TYXVVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:46 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-d3cgid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:46 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-E08c8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:46 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-6leIdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-f2Ss0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-MKBiAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-Ue9ujc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-vMFKzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-JGR5Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-7yau7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-rpVlrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-kBu55S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-2eKS66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:47 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-mx_6WI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:48 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-uqOCZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:48 INFO - Running setup.py install for manifestparser 08:44:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:48 INFO - Running setup.py install for mozcrash 08:44:48 INFO - Running setup.py install for mozdebug 08:44:48 INFO - Running setup.py install for mozdevice 08:44:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:44:48 INFO - Running setup.py install for mozfile 08:44:48 INFO - Running setup.py install for mozhttpd 08:44:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Running setup.py install for mozinfo 08:44:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Running setup.py install for mozInstall 08:44:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Running setup.py install for mozleak 08:44:49 INFO - Running setup.py install for mozlog 08:44:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Running setup.py install for moznetwork 08:44:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:44:49 INFO - Running setup.py install for mozprocess 08:44:49 INFO - Running setup.py install for mozprofile 08:44:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Running setup.py install for mozrunner 08:44:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Running setup.py install for mozscreenshot 08:44:50 INFO - Running setup.py install for moztest 08:44:50 INFO - Running setup.py install for mozversion 08:44:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:44:50 INFO - Cleaning up... 08:44:50 INFO - Return code: 0 08:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:50 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 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:50 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 08:44:50 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 08:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:50 INFO - 'HOME': '/Users/cltbld', 08:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:50 INFO - 'LOGNAME': 'cltbld', 08:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:50 INFO - 'MOZ_NO_REMOTE': '1', 08:44:50 INFO - 'NO_EM_RESTART': '1', 08:44:50 INFO - 'PAGER': '/bin/cat', 08:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:50 INFO - 'PWD': '/builds/slave/test', 08:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:50 INFO - 'SHELL': '/bin/bash', 08:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:50 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:50 INFO - 'USER': 'cltbld', 08:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:50 INFO - 'XPC_FLAGS': '0x0', 08:44:50 INFO - 'XPC_SERVICE_NAME': '0', 08:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:50 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-5W9YCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-2uhYpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-5aQuL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-9bSDUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-rTsQ0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-z60cAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-CEQt7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-k1BIFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-j7w92Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:51 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)) 08:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:51 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-lWNfW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:52 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)) 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:52 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-sGu0dC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:52 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)) 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:52 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-Y9Iw9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:52 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-g7dO62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:52 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)) 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:52 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-2VHwvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:52 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)) 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:52 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-n_sf6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:52 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)) 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:52 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-iwKtKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:52 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-rCyGQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:52 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)) 08:44:52 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)) 08:44:52 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)) 08:44:52 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)) 08:44:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:44:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:44:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:55 INFO - Downloading blessings-1.6.tar.gz 08:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:44:55 INFO - Installing collected packages: blessings 08:44:55 INFO - Running setup.py install for blessings 08:44:55 INFO - Successfully installed blessings 08:44:55 INFO - Cleaning up... 08:44:55 INFO - Return code: 0 08:44:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:55 INFO - Reading from file tmpfile_stdout 08:44:55 INFO - Current package versions: 08:44:55 INFO - blessings == 1.6 08:44:55 INFO - blobuploader == 1.2.4 08:44:55 INFO - docopt == 0.6.1 08:44:55 INFO - functools32 == 3.2.3-2 08:44:55 INFO - jsonschema == 2.5.1 08:44:55 INFO - manifestparser == 1.1 08:44:55 INFO - mozInstall == 1.12 08:44:55 INFO - mozcrash == 1.0 08:44:55 INFO - mozdebug == 0.1 08:44:55 INFO - mozdevice == 0.50 08:44:55 INFO - mozfile == 1.2 08:44:55 INFO - mozhttpd == 0.7 08:44:55 INFO - mozinfo == 0.9 08:44:55 INFO - mozleak == 0.1 08:44:55 INFO - mozlog == 3.4 08:44:55 INFO - moznetwork == 0.27 08:44:55 INFO - mozprocess == 0.25 08:44:55 INFO - mozprofile == 0.28 08:44:55 INFO - mozrunner == 6.13 08:44:55 INFO - mozscreenshot == 0.1 08:44:55 INFO - mozsystemmonitor == 0.3 08:44:55 INFO - moztest == 0.8 08:44:55 INFO - mozversion == 1.4 08:44:55 INFO - psutil == 3.1.1 08:44:55 INFO - requests == 1.2.3 08:44:55 INFO - wsgiref == 0.1.2 08:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:55 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 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:55 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 08:44:55 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 08:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:55 INFO - 'HOME': '/Users/cltbld', 08:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:55 INFO - 'LOGNAME': 'cltbld', 08:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:55 INFO - 'MOZ_NO_REMOTE': '1', 08:44:55 INFO - 'NO_EM_RESTART': '1', 08:44:55 INFO - 'PAGER': '/bin/cat', 08:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:55 INFO - 'PWD': '/builds/slave/test', 08:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:55 INFO - 'SHELL': '/bin/bash', 08:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:55 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:55 INFO - 'USER': 'cltbld', 08:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:55 INFO - 'XPC_FLAGS': '0x0', 08:44:55 INFO - 'XPC_SERVICE_NAME': '0', 08:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 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)) 08:44:56 INFO - Cleaning up... 08:44:56 INFO - Return code: 0 08:44:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e135715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, '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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 08:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:56 INFO - 'HOME': '/Users/cltbld', 08:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:56 INFO - 'LOGNAME': 'cltbld', 08:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:56 INFO - 'MOZ_NO_REMOTE': '1', 08:44:56 INFO - 'NO_EM_RESTART': '1', 08:44:56 INFO - 'PAGER': '/bin/cat', 08:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:56 INFO - 'PWD': '/builds/slave/test', 08:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:56 INFO - 'SHELL': '/bin/bash', 08:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:44:56 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:56 INFO - 'USER': 'cltbld', 08:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:56 INFO - 'XPC_FLAGS': '0x0', 08:44:56 INFO - 'XPC_SERVICE_NAME': '0', 08:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:44:56 INFO - Cleaning up... 08:44:56 INFO - Return code: 0 08:44:56 INFO - Running post-action listener: _resource_record_post_action 08:44:56 INFO - Running post-action listener: _start_resource_monitoring 08:44:56 INFO - Starting resource monitoring. 08:44:56 INFO - [mozharness: 2017-04-17 15:44:56.492759Z] Finished create-virtualenv step (success) 08:44:56 INFO - [mozharness: 2017-04-17 15:44:56.493734Z] Running install step. 08:44:56 INFO - Running pre-action listener: _resource_record_pre_action 08:44:56 INFO - Running main action method: install 08:44:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:56 INFO - Reading from file tmpfile_stdout 08:44:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:56 INFO - Reading from file tmpfile_stdout 08:44:56 INFO - Output received: 08:44:56 INFO - Usage: mozinstall [options] installer 08:44:56 INFO - Options: 08:44:56 INFO - -h, --help show this help message and exit 08:44:56 INFO - -d DEST, --destination=DEST 08:44:56 INFO - Directory to install application into. [default: 08:44:56 INFO - "/builds/slave/test"] 08:44:56 INFO - --app=APP Application being installed. [default: firefox] 08:44:56 INFO - mkdir: /builds/slave/test/build/application 08:44:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:45:16 INFO - Reading from file tmpfile_stdout 08:45:16 INFO - Output received: 08:45:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:45:16 INFO - Running post-action listener: _resource_record_post_action 08:45:16 INFO - [mozharness: 2017-04-17 15:45:16.268898Z] Finished install step (success) 08:45:16 INFO - [mozharness: 2017-04-17 15:45:16.269051Z] Running run-tests step. 08:45:16 INFO - Running pre-action listener: _resource_record_pre_action 08:45:16 INFO - Running main action method: run_tests 08:45:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:16 INFO - 2017-04-17 08:45:16.283 screenresolution[1628:7336] starting screenresolution argv=screenresolution get 08:45:16 INFO - 2017-04-17 08:45:16.286 screenresolution[1628:7336] Display 0: 1600x1200x32@60 08:45:16 INFO - 2017-04-17 08:45:16.292 screenresolution[1629:7339] starting screenresolution argv=screenresolution list 08:45:16 INFO - Available Modes on Display 0 08:45:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:16 INFO - Intel Iris: 08:45:16 INFO - Chipset Model: Intel Iris 08:45:16 INFO - Type: GPU 08:45:16 INFO - Bus: Built-In 08:45:16 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:16 INFO - Vendor: Intel (0x8086) 08:45:16 INFO - Device ID: 0x0a2e 08:45:16 INFO - Revision ID: 0x0009 08:45:16 INFO - Displays: 08:45:16 INFO - Display: 08:45:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:16 INFO - Display Serial Number: 1600x1200 60 08:45:16 INFO - Main Display: Yes 08:45:16 INFO - Mirror: Off 08:45:16 INFO - Online: Yes 08:45:16 INFO - Rotation: Supported 08:45:16 INFO - Return code: 0 08:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:16 INFO - Python 2.7.3 08:45:16 INFO - Return code: 0 08:45:16 INFO - grabbing minidump binary from tooltool 08:45: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')]} 08:45:16 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 0x1059aed30>, '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 0x1058d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:16 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 08:45:16 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 08:45:16 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 08:45:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:16 INFO - Return code: 0 08:45:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:45:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:16 INFO - ENV: RUST_BACKTRACE is now 1 08:45:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:45:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0062', '--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 08:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0062 --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 08:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZ3J0uYTyB/Render', 08:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:16 INFO - 'HOME': '/Users/cltbld', 08:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:16 INFO - 'LOGNAME': 'cltbld', 08:45:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:16 INFO - 'MOZ_NO_REMOTE': '1', 08:45:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:16 INFO - 'NO_EM_RESTART': '1', 08:45:16 INFO - 'PAGER': '/bin/cat', 08:45:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:16 INFO - 'PWD': '/builds/slave/test', 08:45:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:16 INFO - 'RUST_BACKTRACE': '1', 08:45:16 INFO - 'SHELL': '/bin/bash', 08:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xhCSVm0QRc/Listeners', 08:45:16 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 08:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:16 INFO - 'USER': 'cltbld', 08:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:16 INFO - 'XPC_FLAGS': '0x0', 08:45:16 INFO - 'XPC_SERVICE_NAME': '0', 08:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0062', '--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 08:45:16 INFO - mozversion application_buildid: 20170417072206 08:45:16 INFO - mozversion application_changeset: 05c212a94183838f12feebb2c3fd483a6eec18c2 08:45:16 INFO - mozversion application_display_name: Nightly 08:45:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:16 INFO - mozversion application_name: Firefox 08:45:16 INFO - mozversion application_remotingname: firefox 08:45:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:45:16 INFO - mozversion application_vendor: Mozilla 08:45:16 INFO - mozversion application_version: 55.0a1 08:45:16 INFO - mozversion platform_buildid: 20170417072206 08:45:16 INFO - mozversion platform_changeset: 05c212a94183838f12feebb2c3fd483a6eec18c2 08:45:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:45:16 INFO - mozversion platform_version: 55.0a1 08:45:16 INFO - using testdate: 1492443916 08:45:16 INFO - actual date: 1492443916 08:45:16 INFO - starting webserver on 'localhost:49248' 08:45:16 INFO - SUITE-START | Running 2 tests 08:45:16 INFO - TEST-START | dromaeo_css 08:45:16 INFO - operating with platform_type : mac_ 08:45:16 INFO - Initialising browser for dromaeo_css test... 08:45:16 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmp_g2YDW/profile http://localhost:49248/getInfo.html) 08:45:20 INFO - PID 1639 | 2017-04-17 08:45:20.199 plugin-container[1641:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4957, name = 'com.apple.tsm.portname' 08:45:20 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:20 INFO - PID 1639 | 2017-04-17 08:45:20.202 plugin-container[1641:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 08:45:20 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:20 INFO - PID 1639 | 2017-04-17 08:45:20.202 plugin-container[1641:7528] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:20 INFO - PID 1639 | __metrics Screen width/height:1600/1200 08:45:20 INFO - PID 1639 | colorDepth:24 08:45:20 INFO - PID 1639 | Browser inner width/height: 1024/689 08:45:20 INFO - PID 1639 | __metrics 08:45:20 ERROR - PID 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 08:45:21 INFO - TEST-INFO | 1639: exit 0 08:45:21 INFO - Browser initialized. 08:45:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:21 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmp_g2YDW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:24 INFO - PID 1642 | 2017-04-17 08:45:24.034 plugin-container[1643:7668] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.tsm.portname' 08:45:24 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:24 INFO - PID 1642 | 2017-04-17 08:45:24.035 plugin-container[1643:7668] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 08:45:24 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:24 INFO - PID 1642 | 2017-04-17 08:45:24.035 plugin-container[1643:7668] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:33 INFO - PID 1642 | RSS: Main: 235823104 08:45:33 INFO - PID 1642 | 08:48:37 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 08:48:39 INFO - PID 1642 | RSS: Main: 251465728 08:48:39 INFO - PID 1642 | 08:50:21 INFO - PID 1642 | ************************* 08:50:21 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 08:50:21 INFO - PID 1642 | 08:50:21 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:50:21 INFO - PID 1642 | Full stack: 08:50:21 INFO - PID 1642 | ************************* 08:50:21 INFO - PID 1642 | ************************* 08:50:21 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 08:50:21 INFO - PID 1642 | 08:50:21 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:50:21 INFO - PID 1642 | Full stack: 08:50:21 INFO - PID 1642 | ************************* 08:50:21 INFO - PID 1642 | ************************* 08:50:21 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 08:50:21 INFO - PID 1642 | 08:50:21 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:50:21 INFO - PID 1642 | Full stack: 08:50:21 INFO - PID 1642 | ************************* 08:50:21 INFO - PID 1642 | ************************* 08:50:21 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 08:50:21 INFO - PID 1642 | 08:50:21 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:50:21 INFO - PID 1642 | Full stack: 08:50:21 INFO - PID 1642 | ************************* 08:51:41 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 08:51:41 INFO - PID 1642 | RSS: Main: 255770624 08:51:41 INFO - PID 1642 | 08:54:43 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 08:54:44 INFO - PID 1642 | RSS: Main: 265523200 08:54:44 INFO - PID 1642 | 08:57:45 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 08:57:46 INFO - PID 1642 | RSS: Main: 258035712 08:57:46 INFO - PID 1642 | 09:00:49 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 09:00:50 INFO - PID 1642 | RSS: Main: 268578816 09:00:50 INFO - PID 1642 | 09:03:51 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 09:03:52 INFO - PID 1642 | RSS: Main: 263835648 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | __start_tp_report 09:03:52 INFO - PID 1642 | _x_x_mozilla_page_load 09:03:52 INFO - PID 1642 | _x_x_mozilla_page_load_details 09:03:52 INFO - PID 1642 | |i|pagename|runs| 09:03:52 INFO - PID 1642 | |0;dojo.html;3362.1762668146293;3523.700485458736;3602.6937710294624;3624.4563315502687;3664.560252769668;5252.501012403815;5344.786208551658;6068.514518838493;6121.928662484059;6239.4696450801675;5042.369703787027;6210.223722034746;6210.968945155268;6281.43467087962;6305.117283580303;4219.767912764797;4468.285074388098;5502.284702988606;5578.748956296956;5610.887782244353;5480.26016487775;5550.888982220353;5591.692456914868;5618.887622247573;5630.7184640768;5499.890002199933;5500.477454641843;5557.138643510262;5561.249231353756;5606.803761868336;5512.531434828044;5522.171674248895;5532.446755324476;5546.473085056953;5590.580706446992;5469;5489.835304940818;5498.45015498451;5505.201745746845;5513.338399392059;6095.085737139465;6139.553468857682;6189.752409903598;6211.25464944205;6292.685365731741;6727.730890764364;6784.0841486399195;6788.1175447191545;6790.287019862942;6791.762288319935;6840.521163518556;6872.759453419157;6872.896906546406;6877.312268773133;6886.173659160932;6188.009918413032;6194.969025154845;6314.490691449633;6338.492920566344;6346.492280617539;6913.0667359905765;6963.303669632996;6972.1981972072845;7004.719811207597;7005.302958361068;6442.355764423613;6524.771632992823;6536.411722944958;6633.7346506140175;6707.798766037026;6724.495662825308;6741.1236539249585;6824.351686589766;6841.350071743176;6856.657167141623;6159.5072394208355;6709.329067093349;6715.395614394728;6725.361090696474;6738.696758645872;5568.888622227532;6593.307702691244;6612.0412540181405;6670.96664516684;6680.298568650319;5382.67703937765;5386;5393.1370980642905;5397;5426.430224826416;4775.2359622460235;4791.760411979457;4802.135615589227;4813.061453016629;4825.372701548777;6200;6280.654563999024;6330.0821380899515;6366.6180029198395;6425.935740642627;6813.216480104752;6854.3145685432055;6872.072270243553;6875.3812156906115;6896.689648965808;6318.905216421757;6394.488440924715;6417.26201486835;6419.871602567922;6431.938730109479;5817.650940943557;5923.200367950358;6012.39876012404;6033.879322413527;6037.486813620892;6862.725490980405;6912.343327383891;6944;6987.5108742389575;6992.1259842519685;5629.577781666473;6584.452653626298;6862.588244705334;6866.931330686629;6872.965635171992;6881.941259745196;6982.965085174542;6998.860022799618;7009.1589009319205;7011.948207768875;4819.566239038503;6154;6348.873022539431;6683.064370987969;6708.765193218214;6846.554973926679;6858.177018757781;6869.450443964371;6869.965650171917;6873.415759660485;6358.904616430665;6492.448141907991;6503.252126005381;6639.966800165968;6662.900056499253;5501.669899806025;6518.380753828379;6617.503687223574;6628.966855165693;6644.83387915306;6253.5622506424115;6344.651044192429;6458.483321334188;6463.806085817433;6466.676666166767;6446.516511261768;6533.281339052605;6542.149520562297;6611.801645950629;6635.469162466895;6486.870262594816;6511.869762604816;6515.804525864231;6532.738690452519;6557.377049180321 09:03:52 INFO - PID 1642 | |1;ext.html;756.7427074794568;806.7579726082174;829.4940086547207;1272.0713878868426;1280.6542233596936;34107.14732131695;36149.638503615024;37618.87143385699;38804.41793373102;38841.61158388415;39350.40974385387;39675.80162099193;39733.8013309933;39827.60172398275;39883.40174897379;8044.758657240278;8207.261346478816;8552.444091134088;8808.295336373074;8826.249768769661;8691.130886911322;8828.117188281185;8830.072842351557;8831.77920551985;8836.779080522974;8785.604647790833;8841.690540831072;8860.24687901529;8863.867041994376;8866.468011919305;8729.650813967433;8815.118488151198;8821.911780882174;8829.0729473405;8846.867296990551;8636;8645.870311945326;8691.826163476695;8706.47761134334;8716.607752651145;31696.524552131737;38201.898895560735;38597.614023859685;38746.80626596877;38864.61135388638;3565.7682250653884;3567.8751243706606;3620.8913732587803;3624.0939765058733;3628.7822730636244;31421.528677069862;31587.36825263505;31590.526142107658;31600.367992640247;31657.683423165705;3171.3657268546385;3192.792468489564;3193.696598823108;3207.7754557181006;3219.8390080496115;302240;303364;307364;313760;314839;49838.252426213636;49965.75017124964;50046.749766250934;50159.24442644563;50167.74916125469;49688;49728.75135624299;55017;57042.42957570454;57131.71434142886;14560.854391456161;14591.78112328316;14719.705605887822;14775.408983640535;14793.186374749492;11763.823542646867;11847.526098956117;11850.288982661068;11875.287482751062;11886.821697674543;14287.9998400111;14291.213983230815;14334;14352.354144063542;14403.279836008367;6261.592996455216;6261.686915654199;6290.905636415458;6313.273973493107;6323.936760632335;1010.3735683876043;1023.2121266624657;1027.8715160604925;1060.3425875956957;1063.2185343772321;17656.028918409353;20781.89609051945;24670.629940550905;24969.25092247199;24988.875500602517;1054.6150655010865;1074.9731256718644;1106.9169812264113;1146.9541218351173;1156.3871148291419;10145.492725363702;11406.600768973212;12604.87395126037;12626.81059784104;12929;346430;348818;349744.251278742;349933;350554;40767.796161019;56050.71974640101;56257;56711.58221044424;56789.71605141948;1485.4800819713014;1506.8342482326934;1547.7833103365538;1553.5417051969696;1570.6780110077407;19406.8059319405;24367.634485483086;24388.75611243865;24393.390165245302;24416.75583244145;38520.22959540738;38593.807030964665;38701.41947870728;38708;38756.41865372078;295123;295813;297013;297130.0286997189;297553;35834.64165358313;36184.27631447409;36369;36452.45321320129;36492.0876978067;46537.30194047024;46645.06709865716;46903.53096468992;47047.76476117734;47376;29400.7059929398;30075.398492030476;31545;31786.52320215245;32017.6798232024;30849;30929.69070309268;31522.527162093047;31711;31972.200694981882;37134.44298335473;37789.05527361728;37792.811035944644;38023;38063.80968095142;25470.363240919127;28324.583770810965;30565.694343057174;32143.374909390295;32255.838720807184;22218.666719998888;23547.646785298577;24004;24558;24804.37989050288 09:03:52 INFO - PID 1642 | |2;jquery.html;431.45851955795473;434.3181205507354;440.48023332467676;452.6197993685305;460.5003571125331;20572.89713551434;21798.346049618518;22548.985295661692;22600.43498912528;22712.54574908501;22360.88819555904;22417.439564010867;22494.77505224947;22606.321810345675;24011;5977.910331345011;6240.344763799803;6414.294427612959;6418.967905160468;6435.131257280254;6231.345708700589;6316.873662526747;6318.368163183691;6329.873402531946;6335.588186767868;5504.2018907258425;6002.8799424011495;6199.783007594735;6213.378662133773;6276.560640755149;6225.754849030212;6233.376662333753;6290.937090629081;6292.119103325538;6293.559450838443;5449.373322067973;5533.806316778893;5554.777808887639;5590.832275031756;5596.244506991545;28437.289067773472;28575.85712071447;29212.853935730185;29253.26866828325;29359.853200733858;1850.9167087481096;2227.6658501224783;2228.721409823772;2230.654248591471;2234.6871437998693;14547.927260363736;16079.98000499875;18250;18258.543536411697;18399.632007359913;2112.503561663008;2132.2004248406847;2156.0621129808583;2165.978340216609;2181.454636340915;132869;162124;165834;166723.82675992296;166941.16529417396;10311.381317120997;10529.84205236922;12415.503379864704;12529.624111276495;12532.749345013048;13142.671433214246;15990.920045399931;16292.266847991868;16304.836951630568;16345.836541634431;7804.297613214838;8541.44480608771;8903.287736981027;8987.595558199766;9153.35926485153;8539.914600853912;8550.444221125606;8555.443896146731;8658.913410865936;8757.649694012296;6949.339812717783;7618.809529761689;7874.330681891989;7932.651449253641;8000.199980001953;1891.7919028906808;2005.097367687463;2141.5330498608505;2151.817095546896;2300.010995272049;974.3520558828442;981.4356744871727;993.4337427666305;1016.1007508355011;1036.5957276662107;18572;21195.152193912072;21699.45751356197;21881.74919257356;22047;860.3031544448892;1008.7427705935087;1033.214756784849;1053.2258789789498;1062.9096526795308;8836.823263534821;10289.662343895247;11050.447477626087;11401.662659154854;11441.370724610226;560378;606134;615356;624719;629889;129212.98888266749;132981.33509332585;133467;134641.55546778633;139313.3034334822;1207.6618546806908;1240.6774238698004;1258.704204511939;1259.7795385807447;1265.936703164838;21165.788342116382;22347.329580112622;22595;22664;22773.772262277827;59068.1139782895;59349;59393.218203453966;60311.79376412535;61403.692981534805;82631.17368826235;83387;83591;83686;84336;8883.555822208993;8894.86657700148;8906.332025098014;8913.55432228373;9163.86254206174;13331.133476324008;13604.455821767018;13790.724185516434;13839.723205536035;13901.165930043826;10326.915673854137;11468.369239691898;11943.761124777282;12016.978556822767;12032.157748957488;10882.61910833117;10953.288036277616;10970.067544258827;11168.832467512831;11314.151438642299;11615.76768464609;11732.298816671706;11997.64007079789;12233.510659573518;12241.143119981873;8517.957410213157;8717.694880679399;8747.518886461306;8773.912260877309;8805.867911981453;11627.302361858316;11717.472713727731;11835.349055802013;11902;12075.75848483043 09:03:52 INFO - PID 1642 | |3;mootools.html;1192.677976946224;1328.920264784113;1389.5206153876911;1441.990606575397;1455.6506438454774;10123.190144788417;11384.71538211544;11487.483063262152;11723.413829308543;12114.030877529798;11660.591879284206;11806.409679516033;11858.466369013393;11912.702182445442;11961.700957476067;2507.4358269389377;2540;2540.8729563521842;2559.027569523578;2570.8586027768465;2071.3682326890294;2462.7167875694254;2487.3657217409536;2532.696076470826;2535.4168541235526;2147.495338595437;2484.6769919910384;2514.283429222672;2516.0564788204424;2521.949561008788;2089.3105275259204;2395.5448464791634;2490.3151633300804;2514.4342522932375;2518.2571141513113;1911.5890083632087;2395.880205989711;2503.873257034342;2508.83692559983;2513.3716570857287;11775.764484710258;11789.292642441394;11835.940820295844;11845.703857403547;12004.039676825832;6564.409203171737;6748.763793267212;6822.5565338253355;6830.658467076676;6837.3162683731725;3588.6052534221217;3993.302846290315;4425.955740442587;4514.977425112854;4515.661325400608;1251.4681260464267;1255.409957320058;1268.2580690296188;1290.677330667333;1306.4774090363835;15914.442994495254;16026.439074632332;16183.19084045793;16283.02301861892;16755.329786808514;5054.797808087709;5621.49406553404;5759.913601295984;5902.704864756745;5926.407359264039;11764;12231.877681223074;12236.632901012983;12246.326452045045;12306.938465307616;6576.671166441659;6599.769008084694;6607.636579988147;6608.603483790988;6623.503237257225;5755.791283830443;6024.457798798042;6133.723982420802;6167.105769663373;6203.937960620336;4081.285774989365;4085.775282359439;4125.071858831739;4470.061287129674;4519.954800452019;1308.9672758180932;1321.993390033063;1333.926634035118;1343.2813444807177;1344.8453427855923;767.7351313796731;776.1268572855538;784.705735349244;805.5368163306123;814.6293436486385;6627.900581491282;6861.176658800876;6964.199117101497;6999.230084690782;7039.436845052383;907.841127802632;934.3879758758025;938.0947385772762;938.9436633801857;951.7715748220515;4086.9795651021554;4184.644305234089;4308.965848196411;4359.585839345257;4396.714213576107;14640.341184646717;15203.26341258562;15273.083614983136;15273.159976201416;15278.006929549765;14364.928175359057;15108.622284442758;15162.848371516364;15173.24133793328;15187.848121518864;8525.275351595059;8558.315334773204;8725.996510401259;8726.214640682241;8758.211760941545;6862.107925969592;7111.60886151246;7193.820154496179;7210.747623833141;7220.530665506725;10662.73343166396;13225.867741322463;13435.596932092052;13520.18878867271;13549.458021679417;13739.313034348443;13753.518626848012;13786.586402407944;13922.930385348009;14052.227127508086;9468.822170900869;9703.708888733348;9727.708168754949;9728.513574321398;9745.366551174151;10701.464926753475;10716;10719.785604288027;10751.193660475652;10861.67414977552;9118.680846170351;9298.814023719622;9336.06639336061;9367.391119577205;9372.95313523428;8420.705275315335;8960.148785865333;8980.46117232968;9111.407758495941;9215.539223038688;6762.019507171531;7924.088729796147;7987.600619969095;8067.193280671886;8142.715004975034;6002.33974262822;6118.479929206067;6130.693465326805;6141.3551577085545;6149.2313460817395;6045.123457098608;6098.329183789869;6166.352532984151;6173.641798804256;6248.812535623939 09:03:52 INFO - PID 1642 | |4;prototype.html;384.2641341830395;410.01391653074364;411.20021558069527;411.96086371794667;424.57754534238416;5200.297959775429;6313.400226978441;6410.935890641093;6439.130717353156;6506.707198176082;5097.949020509794;5272.446393128724;5979.641221526701;6015.21802165719;6194.938050619492;4722;4729.787159577827;4764.261539461371;4773.493915694254;4786.82722732931;4670.7664616769025;4786.545278198566;4788.832390866321;4808.773762690509;4906.509349065101;3691.593924668284;4428.225060614395;4695.3426520287185;4720.622350211992;4724.858254252378;4713.1987562114355;4736.550027747358;4741.407324084489;4744.976275118637;4752.7861246244;3574.981130377856;3660.33454778078;3873.8378486453953;4210.7473551587;4216.430781844442;4880.975595122037;5722.9984752668115;6093.7812437512675;6130.835141323134;6154.261488621394;4914.3611330527165;5634.323881134249;5658.660480371191;5667.546596272288;5683.4424508970715;6412.435025989595;6636.900446493307;6715.66421678919;6734.427573656245;6759.188897332302;4703.106409782131;4755.83354582588;4769.42766867978;4777.904441911177;4825.831095911662;5448.91826622601;5943.732532036069;6973.396118892632;7106.538075025159;7140.571565706075;3778.848846046127;4453.487848897354;4651.069786042805;4785.617150627942;4864.124457597666;4332.523422423531;4756.500567440438;4835.879103022453;5012;5022.296878437021;4858.708477491362;5003.424606170264;5478.383876756367;5647.67460444305;5657.755293835349;6344.762771259652;6523.347665233438;6717.832054198587;6735.831604209934;6766.323367663293;1693.2813194607484;1723.2503860820584;1727.06738361286;1728.4814555633259;1730.9134543272785;1055.984160237597;1087.2280680716622;1132.5583022621183;1170.5083864776814;1192.6839387562302;6302.180716506825;6344.445610825377;6353.5234857385885;6378.298387177407;6387.776427825004;6758.864822703518;6888.1082188563305;6955.374016338554;6965.407940325028;7069.6111713856235;5009.298698182258;6292.716827742763;6317.336679648571;6320.62076275425;6356.555041147168;3507.1582820122985;3614.1868079681863;3641.617630148849;3646.817659117087;3653.1962968147495;6179.752809887644;7075.646217689095;7087.192765844779;7096.822579435452;7102.502824802317;5768.94231057684;6649.506435086842;6748.628825414551;7114.679839407239;7160.176579693402;5548.667079975215;6419.614823110722;6749.156355455568;6835.829104272333;7197;5629.7748090077575;6141.385861413823;6967.163940327193;6971.5468494547695;6990.930090699231;5565.1095824667855;5722.853929776334;6508.219013718195;6823.577629941497;6871.622060786504;6717.865642687021;6884;6892.758753443605;7052.541584796972;7155.390037241579;4876.341693871424;4877.853704379539;4905.7792399342825;4909.189983652657;4927.162382394929;6284;6735.865282694416;6741.865162696817;6873.278305778022;6925.792226233221;5718.456746609066;5739.9139012915675;6594.505412094016;6628.27089020217;6658.700358483783;5462.426445223195;5526.723663816873;6456.257530384066;6598.637074960731;6608.7356505739235;5141.665791723526;5372.865678358073;6475.967620162058;6505.161280656703;6509.283978762238;6268.655223962726;6295.622262664255;6534.150560427114;6651.46788256948;6699.598024118569;5265.6840589564745;6075.878482430238;6228.564000520101;6330.1454303668415;6619.437347825488 09:03:52 INFO - PID 1642 | |5;yui.html;759.3621358059229;829.8713749300949;834.3492076180573;859.286791962671;901.3465237702667;16389.83610163898;16788.916055419722;16832.24254908529;18059.27762889483;18737.437876863714;17740.959789624198;17863.10684465775;17930.37243696471;18012.09939503023;18181.27274909002;1032.1897310611153;1035.792841431713;1059.1368035051446;1071.7159952612565;1081.3457857995918;1052.489542571852;1057.0011339284379;1059.8357254625507;1062.1078294232843;1064.159314141827;1117.3016864459712;1122.8428020077197;1122.893325134111;1128.3963079752334;1128.5260190719875;1075.2097208551709;1097.3470784882982;1113.6325012745774;1115.893990070942;1118.1278602689874;636.7325723196269;797.1589972578927;806.4757907360204;806.6813608624619;810.0643756461296;16847.83152168487;16880.409185678443;16927.3229070837;16981.83018169827;17075.14624268794;16884.240209190615;16972.575685607833;17064.658706825918;17190.398336058177;17384.47846564605;13790.793138102936;13859.376328065262;13929.512467063705;14157.575272741835;14160.575182744535;3353.077903576536;3408.897733068012;3424.7945123292684;3425.1608355952703;3426.3318652862704;65261.36846578826;113707;135063;136388;136854.31572842173;11415.258008229439;11643.941780291045;11684.591039313584;13377.197368157942;13842.307884605729;24245;24554.140605078737;24776.25671229866;24930.626040609404;24944.251672449856;7479.513831601037;7631.3894888408795;7651.5409075455655;7655.2344765523;7661;3987.3620220764537;3999.7000224983244;4061.4923134608175;4071.1247081877536;4073.307537718595;4693.460252070987;4838.322634831126;4839.443464001614;4890.657653964259;4891.26631005352;797.5175019113469;831.6216121664628;988.0958922585897;1000.7298029531986;1000.9749756256006;624.4848000399688;643.276314146585;660.8480049588621;665.3679004945321;676.4284179868004;10001.399916005063;11373.829392559119;11451.083913286917;11466.713332166762;11582.957533821997;645.3159650770199;649.2112083818089;657.5627207906689;660.2901880478505;665.6586977240863;5033.974830125826;5898.115282707542;6018.6990650467305;6028.517718582503;6082.57421980466;108971.91028089776;110502;110843;111170;112779;73976;95411.56882646766;96276.0372396267;96891;98110;84878.57560712157;85659.5717021411;86720;87475.56262218648;87603.56198218968;12033.217840840318;12309.384530773246;12330.691732706755;12372.13395062337;12406.193597416139;24900;42951;43066.56933430751;43187.78406108075;43271.783641081594;50458.74770626123;52525.212121818964;54666.72666636641;55305.89388212294;55379.44620553743;16409.67180656404;16427.91786041062;16557.503274901774;16579.171041447637;16645.91677041607;14259.42962281497;17797.555061123574;18459.446216613524;18709.812901870806;18971.62056758885;16029.679406411573;16218.756718648994;16316.102614356325;16939.830601694317;17112.91443542774;16399.42602008924;16535.751963720795;16980;17064;17256.913715431343;16278.186090695655;16440.342386304892;16449.026116865574;16530.83469165293;16537.91731041337;11719.12106592026;11913.583024594098;12022;12054.15620906528;12128.818067728815;12939.138422521384;12974.481020759064;12974.675633109247;13020.674483137618;13111.344432778133 09:03:52 INFO - PID 1642 | __end_tp_report 09:03:52 INFO - PID 1642 | __start_cc_report 09:03:52 INFO - PID 1642 | _x_x_mozilla_cycle_collect,416 09:03:52 INFO - PID 1642 | __end_cc_report 09:03:52 INFO - PID 1642 | __startTimestamp1492445032041__endTimestamp 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | ------- Summary: start ------- 09:03:52 INFO - PID 1642 | Number of tests: 6 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6172.71 Median:6419.87 stddev:772.16 (12.0%) stddev-sans-first:742.39 09:03:52 INFO - PID 1642 | Values: 3362.2 3523.7 3602.7 3624.5 3664.6 5252.5 5344.8 6068.5 6121.9 6239.5 5042.4 6210.2 6211.0 6281.4 6305.1 4219.8 4468.3 5502.3 5578.7 5610.9 5480.3 5550.9 5591.7 5618.9 5630.7 5499.9 5500.5 5557.1 5561.2 5606.8 5512.5 5522.2 5532.4 5546.5 5590.6 5469.0 5489.8 5498.5 5505.2 5513.3 6095.1 6139.6 6189.8 6211.3 6292.7 6727.7 6784.1 6788.1 6790.3 6791.8 6840.5 6872.8 6872.9 6877.3 6886.2 6188.0 6195.0 6314.5 6338.5 6346.5 6913.1 6963.3 6972.2 7004.7 7005.3 6442.4 6524.8 6536.4 6633.7 6707.8 6724.5 6741.1 6824.4 6841.4 6856.7 6159.5 6709.3 6715.4 6725.4 6738.7 5568.9 6593.3 6612.0 6671.0 6680.3 5382.7 5386.0 5393.1 5397.0 5426.4 4775.2 4791.8 4802.1 4813.1 4825.4 6200.0 6280.7 6330.1 6366.6 6425.9 6813.2 6854.3 6872.1 6875.4 6896.7 6318.9 6394.5 6417.3 6419.9 6431.9 5817.7 5923.2 6012.4 6033.9 6037.5 6862.7 6912.3 6944.0 6987.5 6992.1 5629.6 6584.5 6862.6 6866.9 6873.0 6881.9 6983.0 6998.9 7009.2 7011.9 4819.6 6154.0 6348.9 6683.1 6708.8 6846.6 6858.2 6869.5 6870.0 6873.4 6358.9 6492.4 6503.3 6640.0 6662.9 5501.7 6518.4 6617.5 6629.0 6644.8 6253.6 6344.7 6458.5 6463.8 6466.7 6446.5 6533.3 6542.1 6611.8 6635.5 6486.9 6511.9 6515.8 6532.7 6557.4 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:47008.92 Median:349281.13 stddev:83736.81 (24.0%) stddev-sans-first:83899.71 09:03:52 INFO - PID 1642 | Values: 756.7 806.8 829.5 1272.1 1280.7 34107.1 36149.6 37618.9 38804.4 38841.6 39350.4 39675.8 39733.8 39827.6 39883.4 8044.8 8207.3 8552.4 8808.3 8826.2 8691.1 8828.1 8830.1 8831.8 8836.8 8785.6 8841.7 8860.2 8863.9 8866.5 8729.7 8815.1 8821.9 8829.1 8846.9 8636.0 8645.9 8691.8 8706.5 8716.6 31696.5 38201.9 38597.6 38746.8 38864.6 3565.8 3567.9 3620.9 3624.1 3628.8 31421.5 31587.4 31590.5 31600.4 31657.7 3171.4 3192.8 3193.7 3207.8 3219.8 302240.0 303364.0 307364.0 313760.0 314839.0 49838.3 49965.8 50046.7 50159.2 50167.7 49688.0 49728.8 55017.0 57042.4 57131.7 14560.9 14591.8 14719.7 14775.4 14793.2 11763.8 11847.5 11850.3 11875.3 11886.8 14288.0 14291.2 14334.0 14352.4 14403.3 6261.6 6261.7 6290.9 6313.3 6323.9 1010.4 1023.2 1027.9 1060.3 1063.2 17656.0 20781.9 24670.6 24969.3 24988.9 1054.6 1075.0 1106.9 1147.0 1156.4 10145.5 11406.6 12604.9 12626.8 12929.0 346430.0 348818.0 349744.3 349933.0 350554.0 40767.8 56050.7 56257.0 56711.6 56789.7 1485.5 1506.8 1547.8 1553.5 1570.7 19406.8 24367.6 24388.8 24393.4 24416.8 38520.2 38593.8 38701.4 38708.0 38756.4 295123.0 295813.0 297013.0 297130.0 297553.0 35834.6 36184.3 36369.0 36452.5 36492.1 46537.3 46645.1 46903.5 47047.8 47376.0 29400.7 30075.4 31545.0 31786.5 32017.7 30849.0 30929.7 31522.5 31711.0 31972.2 37134.4 37789.1 37792.8 38023.0 38063.8 25470.4 28324.6 30565.7 32143.4 32255.8 22218.7 23547.6 24004.0 24558.0 24804.4 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:37750.18 Median:22521.88 stddev:102609.80 (455.6%) stddev-sans-first:102859.39 09:03:52 INFO - PID 1642 | Values: 431.5 434.3 440.5 452.6 460.5 20572.9 21798.3 22549.0 22600.4 22712.5 22360.9 22417.4 22494.8 22606.3 24011.0 5977.9 6240.3 6414.3 6419.0 6435.1 6231.3 6316.9 6318.4 6329.9 6335.6 5504.2 6002.9 6199.8 6213.4 6276.6 6225.8 6233.4 6290.9 6292.1 6293.6 5449.4 5533.8 5554.8 5590.8 5596.2 28437.3 28575.9 29212.9 29253.3 29359.9 1850.9 2227.7 2228.7 2230.7 2234.7 14547.9 16080.0 18250.0 18258.5 18399.6 2112.5 2132.2 2156.1 2166.0 2181.5 132869.0 162124.0 165834.0 166723.8 166941.2 10311.4 10529.8 12415.5 12529.6 12532.7 13142.7 15990.9 16292.3 16304.8 16345.8 7804.3 8541.4 8903.3 8987.6 9153.4 8539.9 8550.4 8555.4 8658.9 8757.6 6949.3 7618.8 7874.3 7932.7 8000.2 1891.8 2005.1 2141.5 2151.8 2300.0 974.4 981.4 993.4 1016.1 1036.6 18572.0 21195.2 21699.5 21881.7 22047.0 860.3 1008.7 1033.2 1053.2 1062.9 8836.8 10289.7 11050.4 11401.7 11441.4 560378.0 606134.0 615356.0 624719.0 629889.0 129213.0 132981.3 133467.0 134641.6 139313.3 1207.7 1240.7 1258.7 1259.8 1265.9 21165.8 22347.3 22595.0 22664.0 22773.8 59068.1 59349.0 59393.2 60311.8 61403.7 82631.2 83387.0 83591.0 83686.0 84336.0 8883.6 8894.9 8906.3 8913.6 9163.9 13331.1 13604.5 13790.7 13839.7 13901.2 10326.9 11468.4 11943.8 12017.0 12032.2 10882.6 10953.3 10970.1 11168.8 11314.2 11615.8 11732.3 11997.6 12233.5 12241.1 8518.0 8717.7 8747.5 8773.9 8805.9 11627.3 11717.5 11835.3 11902.0 12075.8 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7148.39 Median:3790.95 stddev:4512.02 (119.0%) stddev-sans-first:4502.47 09:03:52 INFO - PID 1642 | Values: 1192.7 1328.9 1389.5 1442.0 1455.7 10123.2 11384.7 11487.5 11723.4 12114.0 11660.6 11806.4 11858.5 11912.7 11961.7 2507.4 2540.0 2540.9 2559.0 2570.9 2071.4 2462.7 2487.4 2532.7 2535.4 2147.5 2484.7 2514.3 2516.1 2521.9 2089.3 2395.5 2490.3 2514.4 2518.3 1911.6 2395.9 2503.9 2508.8 2513.4 11775.8 11789.3 11835.9 11845.7 12004.0 6564.4 6748.8 6822.6 6830.7 6837.3 3588.6 3993.3 4426.0 4515.0 4515.7 1251.5 1255.4 1268.3 1290.7 1306.5 15914.4 16026.4 16183.2 16283.0 16755.3 5054.8 5621.5 5759.9 5902.7 5926.4 11764.0 12231.9 12236.6 12246.3 12306.9 6576.7 6599.8 6607.6 6608.6 6623.5 5755.8 6024.5 6133.7 6167.1 6203.9 4081.3 4085.8 4125.1 4470.1 4520.0 1309.0 1322.0 1333.9 1343.3 1344.8 767.7 776.1 784.7 805.5 814.6 6627.9 6861.2 6964.2 6999.2 7039.4 907.8 934.4 938.1 938.9 951.8 4087.0 4184.6 4309.0 4359.6 4396.7 14640.3 15203.3 15273.1 15273.2 15278.0 14364.9 15108.6 15162.8 15173.2 15187.8 8525.3 8558.3 8726.0 8726.2 8758.2 6862.1 7111.6 7193.8 7210.7 7220.5 10662.7 13225.9 13435.6 13520.2 13549.5 13739.3 13753.5 13786.6 13922.9 14052.2 9468.8 9703.7 9727.7 9728.5 9745.4 10701.5 10716.0 10719.8 10751.2 10861.7 9118.7 9298.8 9336.1 9367.4 9373.0 8420.7 8960.1 8980.5 9111.4 9215.5 6762.0 7924.1 7987.6 8067.2 8142.7 6002.3 6118.5 6130.7 6141.4 6149.2 6045.1 6098.3 6166.4 6173.6 6248.8 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5382.36 Median:5722.93 stddev:1623.11 (28.4%) stddev-sans-first:1583.71 09:03:52 INFO - PID 1642 | Values: 384.3 410.0 411.2 412.0 424.6 5200.3 6313.4 6410.9 6439.1 6506.7 5097.9 5272.4 5979.6 6015.2 6194.9 4722.0 4729.8 4764.3 4773.5 4786.8 4670.8 4786.5 4788.8 4808.8 4906.5 3691.6 4428.2 4695.3 4720.6 4724.9 4713.2 4736.6 4741.4 4745.0 4752.8 3575.0 3660.3 3873.8 4210.7 4216.4 4881.0 5723.0 6093.8 6130.8 6154.3 4914.4 5634.3 5658.7 5667.5 5683.4 6412.4 6636.9 6715.7 6734.4 6759.2 4703.1 4755.8 4769.4 4777.9 4825.8 5448.9 5943.7 6973.4 7106.5 7140.6 3778.8 4453.5 4651.1 4785.6 4864.1 4332.5 4756.5 4835.9 5012.0 5022.3 4858.7 5003.4 5478.4 5647.7 5657.8 6344.8 6523.3 6717.8 6735.8 6766.3 1693.3 1723.3 1727.1 1728.5 1730.9 1056.0 1087.2 1132.6 1170.5 1192.7 6302.2 6344.4 6353.5 6378.3 6387.8 6758.9 6888.1 6955.4 6965.4 7069.6 5009.3 6292.7 6317.3 6320.6 6356.6 3507.2 3614.2 3641.6 3646.8 3653.2 6179.8 7075.6 7087.2 7096.8 7102.5 5768.9 6649.5 6748.6 7114.7 7160.2 5548.7 6419.6 6749.2 6835.8 7197.0 5629.8 6141.4 6967.2 6971.5 6990.9 5565.1 5722.9 6508.2 6823.6 6871.6 6717.9 6884.0 6892.8 7052.5 7155.4 4876.3 4877.9 4905.8 4909.2 4927.2 6284.0 6735.9 6741.9 6873.3 6925.8 5718.5 5739.9 6594.5 6628.3 6658.7 5462.4 5526.7 6456.3 6598.6 6608.7 5141.7 5372.9 6476.0 6505.2 6509.3 6268.7 6295.6 6534.2 6651.5 6699.6 5265.7 6075.9 6228.6 6330.1 6619.4 09:03:52 INFO - PID 1642 | 09:03:52 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:21854.74 Median:17094.03 stddev:31047.84 (181.6%) stddev-sans-first:31094.53 09:03:52 INFO - PID 1642 | Values: 759.4 829.9 834.3 859.3 901.3 16389.8 16788.9 16832.2 18059.3 18737.4 17741.0 17863.1 17930.4 18012.1 18181.3 1032.2 1035.8 1059.1 1071.7 1081.3 1052.5 1057.0 1059.8 1062.1 1064.2 1117.3 1122.8 1122.9 1128.4 1128.5 1075.2 1097.3 1113.6 1115.9 1118.1 636.7 797.2 806.5 806.7 810.1 16847.8 16880.4 16927.3 16981.8 17075.1 16884.2 16972.6 17064.7 17190.4 17384.5 13790.8 13859.4 13929.5 14157.6 14160.6 3353.1 3408.9 3424.8 3425.2 3426.3 65261.4 113707.0 135063.0 136388.0 136854.3 11415.3 11643.9 11684.6 13377.2 13842.3 24245.0 24554.1 24776.3 24930.6 24944.3 7479.5 7631.4 7651.5 7655.2 7661.0 3987.4 3999.7 4061.5 4071.1 4073.3 4693.5 4838.3 4839.4 4890.7 4891.3 797.5 831.6 988.1 1000.7 1001.0 624.5 643.3 660.8 665.4 676.4 10001.4 11373.8 11451.1 11466.7 11583.0 645.3 649.2 657.6 660.3 665.7 5034.0 5898.1 6018.7 6028.5 6082.6 108971.9 110502.0 110843.0 111170.0 112779.0 73976.0 95411.6 96276.0 96891.0 98110.0 84878.6 85659.6 86720.0 87475.6 87603.6 12033.2 12309.4 12330.7 12372.1 12406.2 24900.0 42951.0 43066.6 43187.8 43271.8 50458.7 52525.2 54666.7 55305.9 55379.4 16409.7 16427.9 16557.5 16579.2 16645.9 14259.4 17797.6 18459.4 18709.8 18971.6 16029.7 16218.8 16316.1 16939.8 17112.9 16399.4 16535.8 16980.0 17064.0 17256.9 16278.2 16440.3 16449.0 16530.8 16537.9 11719.1 11913.6 12022.0 12054.2 12128.8 12939.1 12974.5 12974.7 13020.7 13111.3 09:03:52 INFO - PID 1642 | -------- Summary: end -------- 09:03:52 INFO - PID 1642 | 09:03:52 INFO - TEST-INFO | 1642: exit 0 09:03:52 INFO - TEST-OK | dromaeo_css | took 1115975ms 09:03:52 INFO - TEST-START | kraken 09:03:52 INFO - operating with platform_type : mac_ 09:03:52 INFO - Initialising browser for kraken test... 09:03:52 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmp5DSMdJ/profile http://localhost:49248/getInfo.html) 09:03:55 INFO - PID 1655 | 2017-04-17 09:03:55.470 plugin-container[1656:10108] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:03:55 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:55 INFO - PID 1655 | 2017-04-17 09:03:55.473 plugin-container[1656:10108] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 09:03:55 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:55 INFO - PID 1655 | 2017-04-17 09:03:55.473 plugin-container[1656:10108] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:55 INFO - PID 1655 | __metrics Screen width/height:1600/1200 09:03:55 INFO - PID 1655 | colorDepth:24 09:03:55 INFO - PID 1655 | Browser inner width/height: 1024/689 09:03:55 INFO - PID 1655 | __metrics 09:03:55 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 09:03:56 INFO - TEST-INFO | 1655: exit 0 09:03:56 INFO - Browser initialized. 09:03:56 INFO - Running cycle 1/1 for kraken test... 09:03:56 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmp5DSMdJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:59 INFO - PID 1657 | 2017-04-17 09:03:59.214 plugin-container[1658:10229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 09:03:59 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:59 INFO - PID 1657 | 2017-04-17 09:03:59.214 plugin-container[1658:10229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1f, name = 'com.apple.CFPasteboardClient' 09:03:59 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:59 INFO - PID 1657 | 2017-04-17 09:03:59.214 plugin-container[1658:10229] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:09 INFO - PID 1657 | RSS: Main: 237170688 09:04:09 INFO - PID 1657 | 09:04:11 INFO - PID 1657 | 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) 09:04:12 INFO - PID 1657 | RSS: Main: 248971264 09:04:12 INFO - PID 1657 | 09:04:15 INFO - PID 1657 | 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) 09:04:16 INFO - PID 1657 | RSS: Main: 257503232 09:04:16 INFO - PID 1657 | 09:04:19 INFO - PID 1657 | 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) 09:04:20 INFO - PID 1657 | RSS: Main: 259706880 09:04:20 INFO - PID 1657 | 09:04:22 INFO - PID 1657 | 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) 09:04:22 INFO - PID 1657 | RSS: Main: 260857856 09:04:22 INFO - PID 1657 | 09:04:24 INFO - PID 1657 | 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) 09:04:25 INFO - PID 1657 | RSS: Main: 261222400 09:04:25 INFO - PID 1657 | 09:04:28 INFO - PID 1657 | 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) 09:04:28 INFO - PID 1657 | RSS: Main: 268414976 09:04:28 INFO - PID 1657 | 09:04:31 INFO - PID 1657 | 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) 09:04:32 INFO - PID 1657 | RSS: Main: 269574144 09:04:32 INFO - PID 1657 | 09:04:35 INFO - PID 1657 | 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) 09:04:36 INFO - PID 1657 | RSS: Main: 269508608 09:04:36 INFO - PID 1657 | 09:04:37 INFO - PID 1657 | 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) 09:04:38 INFO - PID 1657 | RSS: Main: 268619776 09:04:38 INFO - PID 1657 | 09:04:40 INFO - PID 1657 | 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) 09:04:40 INFO - PID 1657 | RSS: Main: 268677120 09:04:40 INFO - PID 1657 | 09:04:43 INFO - PID 1657 | 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) 09:04:43 INFO - PID 1657 | RSS: Main: 268267520 09:04:43 INFO - PID 1657 | 09:04:46 INFO - PID 1657 | 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) 09:04:47 INFO - PID 1657 | RSS: Main: 268496896 09:04:47 INFO - PID 1657 | 09:04:50 INFO - PID 1657 | 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) 09:04:51 INFO - PID 1657 | RSS: Main: 268632064 09:04:51 INFO - PID 1657 | 09:04:52 INFO - PID 1657 | 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) 09:04:53 INFO - PID 1657 | RSS: Main: 270069760 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | __start_tp_report 09:04:53 INFO - PID 1657 | _x_x_mozilla_page_load 09:04:53 INFO - PID 1657 | _x_x_mozilla_page_load_details 09:04:53 INFO - PID 1657 | |i|pagename|runs| 09:04:53 INFO - PID 1657 | |0;ai-astar;105;120;115;96;174;110;146;123;175;173 09:04:53 INFO - PID 1657 | |1;audio-beat-detection;146;111;112;146;114;143;95;143;158;149 09:04:53 INFO - PID 1657 | |2;audio-dft;197;201;215;237;223;285;267;198;198;195 09:04:53 INFO - PID 1657 | |3;audio-fft;78;53;54;106;88;85;87;59;76;59 09:04:53 INFO - PID 1657 | |4;audio-oscillator;90;73;84;80;104;79;90;69;68;88 09:04:53 INFO - PID 1657 | |5;imaging-gaussian-blur;89;88;88;94;91;88;88;88;90;88 09:04:53 INFO - PID 1657 | |6;imaging-darkroom;98;97;95;100;95;96;122;96;95;96 09:04:53 INFO - PID 1657 | |7;imaging-desaturate;75;148;142;96;90;129;141;92;95;91 09:04:53 INFO - PID 1657 | |8;json-parse-financial;51;55;50;53;51;50;52;52;51;51 09:04:53 INFO - PID 1657 | |9;json-stringify-tinderbox;64;62;68;63;63;60;57;56;56;56 09:04:53 INFO - PID 1657 | |10;stanford-crypto-aes;84;83;82;82;86;85;106;86;82;86 09:04:53 INFO - PID 1657 | |11;stanford-crypto-ccm;145;155;150;149;149;156;135;160;205;156 09:04:53 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;178;189;187;195;185;185;198;184;180;232 09:04:53 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;70;69;68;69;68;70;66;68;68;67 09:04:53 INFO - PID 1657 | __end_tp_report 09:04:53 INFO - PID 1657 | __start_cc_report 09:04:53 INFO - PID 1657 | _x_x_mozilla_cycle_collect,982 09:04:53 INFO - PID 1657 | __end_cc_report 09:04:53 INFO - PID 1657 | __startTimestamp1492445093182__endTimestamp 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | ------- Summary: start ------- 09:04:53 INFO - PID 1657 | Number of tests: 14 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:133.70 Median:159.50 stddev:30.70 (19.2%) stddev-sans-first:30.76 09:04:53 INFO - PID 1657 | Values: 105.0 120.0 115.0 96.0 174.0 110.0 146.0 123.0 175.0 173.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:146.00 stddev:21.43 (14.7%) stddev-sans-first:22.09 09:04:53 INFO - PID 1657 | Values: 146.0 111.0 112.0 146.0 114.0 143.0 95.0 143.0 158.0 149.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:221.60 Median:219.00 stddev:32.00 (14.6%) stddev-sans-first:32.68 09:04:53 INFO - PID 1657 | Values: 197.0 201.0 215.0 237.0 223.0 285.0 267.0 198.0 198.0 195.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:74.50 Median:77.00 stddev:17.70 (23.0%) stddev-sans-first:18.72 09:04:53 INFO - PID 1657 | Values: 78.0 53.0 54.0 106.0 88.0 85.0 87.0 59.0 76.0 59.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:82.00 stddev:11.10 (13.5%) stddev-sans-first:11.43 09:04:53 INFO - PID 1657 | Values: 90.0 73.0 84.0 80.0 104.0 79.0 90.0 69.0 68.0 88.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:88.50 stddev:1.99 (2.2%) stddev-sans-first:2.11 09:04:53 INFO - PID 1657 | Values: 89.0 88.0 88.0 94.0 91.0 88.0 88.0 88.0 90.0 88.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:96.00 stddev:8.23 (8.6%) stddev-sans-first:8.72 09:04:53 INFO - PID 1657 | Values: 98.0 97.0 95.0 100.0 95.0 96.0 122.0 96.0 95.0 96.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:109.90 Median:90.50 stddev:26.92 (29.7%) stddev-sans-first:25.42 09:04:53 INFO - PID 1657 | Values: 75.0 148.0 142.0 96.0 90.0 129.0 141.0 92.0 95.0 91.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.50 stddev:1.51 (2.9%) stddev-sans-first:1.58 09:04:53 INFO - PID 1657 | Values: 51.0 55.0 50.0 53.0 51.0 50.0 52.0 52.0 51.0 51.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:62.50 stddev:4.17 (6.7%) stddev-sans-first:4.23 09:04:53 INFO - PID 1657 | Values: 64.0 62.0 68.0 63.0 63.0 60.0 57.0 56.0 56.0 56.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:84.50 stddev:7.16 (8.5%) stddev-sans-first:7.55 09:04:53 INFO - PID 1657 | Values: 84.0 83.0 82.0 82.0 86.0 85.0 106.0 86.0 82.0 86.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:155.50 stddev:18.60 (12.0%) stddev-sans-first:19.30 09:04:53 INFO - PID 1657 | Values: 145.0 155.0 150.0 149.0 149.0 156.0 135.0 160.0 205.0 156.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.30 Median:188.00 stddev:15.55 (8.3%) stddev-sans-first:15.73 09:04:53 INFO - PID 1657 | Values: 178.0 189.0 187.0 195.0 185.0 185.0 198.0 184.0 180.0 232.0 09:04:53 INFO - PID 1657 | 09:04:53 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.25 (1.8%) stddev-sans-first:1.17 09:04:53 INFO - PID 1657 | Values: 70.0 69.0 68.0 69.0 68.0 70.0 66.0 68.0 68.0 67.0 09:04:53 INFO - PID 1657 | -------- Summary: end -------- 09:04:53 INFO - PID 1657 | 09:04:54 INFO - TEST-INFO | 1657: exit 0 09:04:54 INFO - TEST-OK | kraken | took 61447ms 09:04:54 INFO - SUITE-END | took 1177s 09:04:54 INFO - Completed test suite (00:19:37) 09:04:54 INFO - javascript benchmark 09:04:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7987.776817900011, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.1762668146293, 3523.700485458736, 3602.6937710294624, 3624.4563315502687, 3664.560252769668, 5252.501012403815, 5344.786208551658, 6068.514518838493, 6121.928662484059, 6239.4696450801675, 5042.369703787027, 6210.223722034746, 6210.968945155268, 6281.43467087962, 6305.117283580303, 4219.767912764797, 4468.285074388098, 5502.284702988606, 5578.748956296956, 5610.887782244353, 5480.26016487775, 5550.888982220353, 5591.692456914868, 5618.887622247573, 5630.7184640768, 5499.890002199933, 5500.477454641843, 5557.138643510262, 5561.249231353756, 5606.803761868336, 5512.531434828044, 5522.171674248895, 5532.446755324476, 5546.473085056953, 5590.580706446992, 5469.0, 5489.835304940818, 5498.45015498451, 5505.201745746845, 5513.338399392059, 6095.085737139465, 6139.553468857682, 6189.752409903598, 6211.25464944205, 6292.685365731741, 6727.730890764364, 6784.0841486399195, 6788.1175447191545, 6790.287019862942, 6791.762288319935, 6840.521163518556, 6872.759453419157, 6872.896906546406, 6877.312268773133, 6886.173659160932, 6188.009918413032, 6194.969025154845, 6314.490691449633, 6338.492920566344, 6346.492280617539, 6913.0667359905765, 6963.303669632996, 6972.1981972072845, 7004.719811207597, 7005.302958361068, 6442.355764423613, 6524.771632992823, 6536.411722944958, 6633.7346506140175, 6707.798766037026, 6724.495662825308, 6741.1236539249585, 6824.351686589766, 6841.350071743176, 6856.657167141623, 6159.5072394208355, 6709.329067093349, 6715.395614394728, 6725.361090696474, 6738.696758645872, 5568.888622227532, 6593.307702691244, 6612.0412540181405, 6670.96664516684, 6680.298568650319, 5382.67703937765, 5386.0, 5393.1370980642905, 5397.0, 5426.430224826416, 4775.2359622460235, 4791.760411979457, 4802.135615589227, 4813.061453016629, 4825.372701548777, 6200.0, 6280.654563999024, 6330.0821380899515, 6366.6180029198395, 6425.935740642627, 6813.216480104752, 6854.3145685432055, 6872.072270243553, 6875.3812156906115, 6896.689648965808, 6318.905216421757, 6394.488440924715, 6417.26201486835, 6419.871602567922, 6431.938730109479, 5817.650940943557, 5923.200367950358, 6012.39876012404, 6033.879322413527, 6037.486813620892, 6862.725490980405, 6912.343327383891, 6944.0, 6987.5108742389575, 6992.1259842519685, 5629.577781666473, 6584.452653626298, 6862.588244705334, 6866.931330686629, 6872.965635171992, 6881.941259745196, 6982.965085174542, 6998.860022799618, 7009.1589009319205, 7011.948207768875, 4819.566239038503, 6154.0, 6348.873022539431, 6683.064370987969, 6708.765193218214, 6846.554973926679, 6858.177018757781, 6869.450443964371, 6869.965650171917, 6873.415759660485, 6358.904616430665, 6492.448141907991, 6503.252126005381, 6639.966800165968, 6662.900056499253, 5501.669899806025, 6518.380753828379, 6617.503687223574, 6628.966855165693, 6644.83387915306, 6253.5622506424115, 6344.651044192429, 6458.483321334188, 6463.806085817433, 6466.676666166767, 6446.516511261768, 6533.281339052605, 6542.149520562297, 6611.801645950629, 6635.469162466895, 6486.870262594816, 6511.869762604816, 6515.804525864231, 6532.738690452519, 6557.377049180321], "value": 6121.924966754888, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.7427074794568, 806.7579726082174, 829.4940086547207, 1272.0713878868426, 1280.6542233596936, 34107.14732131695, 36149.638503615024, 37618.87143385699, 38804.41793373102, 38841.61158388415, 39350.40974385387, 39675.80162099193, 39733.8013309933, 39827.60172398275, 39883.40174897379, 8044.758657240278, 8207.261346478816, 8552.444091134088, 8808.295336373074, 8826.249768769661, 8691.130886911322, 8828.117188281185, 8830.072842351557, 8831.77920551985, 8836.779080522974, 8785.604647790833, 8841.690540831072, 8860.24687901529, 8863.867041994376, 8866.468011919305, 8729.650813967433, 8815.118488151198, 8821.911780882174, 8829.0729473405, 8846.867296990551, 8636.0, 8645.870311945326, 8691.826163476695, 8706.47761134334, 8716.607752651145, 31696.524552131737, 38201.898895560735, 38597.614023859685, 38746.80626596877, 38864.61135388638, 3565.7682250653884, 3567.8751243706606, 3620.8913732587803, 3624.0939765058733, 3628.7822730636244, 31421.528677069862, 31587.36825263505, 31590.526142107658, 31600.367992640247, 31657.683423165705, 3171.3657268546385, 3192.792468489564, 3193.696598823108, 3207.7754557181006, 3219.8390080496115, 302240.0, 303364.0, 307364.0, 313760.0, 314839.0, 49838.252426213636, 49965.75017124964, 50046.749766250934, 50159.24442644563, 50167.74916125469, 49688.0, 49728.75135624299, 55017.0, 57042.42957570454, 57131.71434142886, 14560.854391456161, 14591.78112328316, 14719.705605887822, 14775.408983640535, 14793.186374749492, 11763.823542646867, 11847.526098956117, 11850.288982661068, 11875.287482751062, 11886.821697674543, 14287.9998400111, 14291.213983230815, 14334.0, 14352.354144063542, 14403.279836008367, 6261.592996455216, 6261.686915654199, 6290.905636415458, 6313.273973493107, 6323.936760632335, 1010.3735683876043, 1023.2121266624657, 1027.8715160604925, 1060.3425875956957, 1063.2185343772321, 17656.028918409353, 20781.89609051945, 24670.629940550905, 24969.25092247199, 24988.875500602517, 1054.6150655010865, 1074.9731256718644, 1106.9169812264113, 1146.9541218351173, 1156.3871148291419, 10145.492725363702, 11406.600768973212, 12604.87395126037, 12626.81059784104, 12929.0, 346430.0, 348818.0, 349744.251278742, 349933.0, 350554.0, 40767.796161019, 56050.71974640101, 56257.0, 56711.58221044424, 56789.71605141948, 1485.4800819713014, 1506.8342482326934, 1547.7833103365538, 1553.5417051969696, 1570.6780110077407, 19406.8059319405, 24367.634485483086, 24388.75611243865, 24393.390165245302, 24416.75583244145, 38520.22959540738, 38593.807030964665, 38701.41947870728, 38708.0, 38756.41865372078, 295123.0, 295813.0, 297013.0, 297130.0286997189, 297553.0, 35834.64165358313, 36184.27631447409, 36369.0, 36452.45321320129, 36492.0876978067, 46537.30194047024, 46645.06709865716, 46903.53096468992, 47047.76476117734, 47376.0, 29400.7059929398, 30075.398492030476, 31545.0, 31786.52320215245, 32017.6798232024, 30849.0, 30929.69070309268, 31522.527162093047, 31711.0, 31972.200694981882, 37134.44298335473, 37789.05527361728, 37792.811035944644, 38023.0, 38063.80968095142, 25470.363240919127, 28324.583770810965, 30565.694343057174, 32143.374909390295, 32255.838720807184, 22218.666719998888, 23547.646785298577, 24004.0, 24558.0, 24804.37989050288], "value": 17937.32879427314, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.45851955795473, 434.3181205507354, 440.48023332467676, 452.6197993685305, 460.5003571125331, 20572.89713551434, 21798.346049618518, 22548.985295661692, 22600.43498912528, 22712.54574908501, 22360.88819555904, 22417.439564010867, 22494.77505224947, 22606.321810345675, 24011.0, 5977.910331345011, 6240.344763799803, 6414.294427612959, 6418.967905160468, 6435.131257280254, 6231.345708700589, 6316.873662526747, 6318.368163183691, 6329.873402531946, 6335.588186767868, 5504.2018907258425, 6002.8799424011495, 6199.783007594735, 6213.378662133773, 6276.560640755149, 6225.754849030212, 6233.376662333753, 6290.937090629081, 6292.119103325538, 6293.559450838443, 5449.373322067973, 5533.806316778893, 5554.777808887639, 5590.832275031756, 5596.244506991545, 28437.289067773472, 28575.85712071447, 29212.853935730185, 29253.26866828325, 29359.853200733858, 1850.9167087481096, 2227.6658501224783, 2228.721409823772, 2230.654248591471, 2234.6871437998693, 14547.927260363736, 16079.98000499875, 18250.0, 18258.543536411697, 18399.632007359913, 2112.503561663008, 2132.2004248406847, 2156.0621129808583, 2165.978340216609, 2181.454636340915, 132869.0, 162124.0, 165834.0, 166723.82675992296, 166941.16529417396, 10311.381317120997, 10529.84205236922, 12415.503379864704, 12529.624111276495, 12532.749345013048, 13142.671433214246, 15990.920045399931, 16292.266847991868, 16304.836951630568, 16345.836541634431, 7804.297613214838, 8541.44480608771, 8903.287736981027, 8987.595558199766, 9153.35926485153, 8539.914600853912, 8550.444221125606, 8555.443896146731, 8658.913410865936, 8757.649694012296, 6949.339812717783, 7618.809529761689, 7874.330681891989, 7932.651449253641, 8000.199980001953, 1891.7919028906808, 2005.097367687463, 2141.5330498608505, 2151.817095546896, 2300.010995272049, 974.3520558828442, 981.4356744871727, 993.4337427666305, 1016.1007508355011, 1036.5957276662107, 18572.0, 21195.152193912072, 21699.45751356197, 21881.74919257356, 22047.0, 860.3031544448892, 1008.7427705935087, 1033.214756784849, 1053.2258789789498, 1062.9096526795308, 8836.823263534821, 10289.662343895247, 11050.447477626087, 11401.662659154854, 11441.370724610226, 560378.0, 606134.0, 615356.0, 624719.0, 629889.0, 129212.98888266749, 132981.33509332585, 133467.0, 134641.55546778633, 139313.3034334822, 1207.6618546806908, 1240.6774238698004, 1258.704204511939, 1259.7795385807447, 1265.936703164838, 21165.788342116382, 22347.329580112622, 22595.0, 22664.0, 22773.772262277827, 59068.1139782895, 59349.0, 59393.218203453966, 60311.79376412535, 61403.692981534805, 82631.17368826235, 83387.0, 83591.0, 83686.0, 84336.0, 8883.555822208993, 8894.86657700148, 8906.332025098014, 8913.55432228373, 9163.86254206174, 13331.133476324008, 13604.455821767018, 13790.724185516434, 13839.723205536035, 13901.165930043826, 10326.915673854137, 11468.369239691898, 11943.761124777282, 12016.978556822767, 12032.157748957488, 10882.61910833117, 10953.288036277616, 10970.067544258827, 11168.832467512831, 11314.151438642299, 11615.76768464609, 11732.298816671706, 11997.64007079789, 12233.510659573518, 12241.143119981873, 8517.957410213157, 8717.694880679399, 8747.518886461306, 8773.912260877309, 8805.867911981453, 11627.302361858316, 11717.472713727731, 11835.349055802013, 11902.0, 12075.75848483043], "value": 10568.87351706616, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1192.677976946224, 1328.920264784113, 1389.5206153876911, 1441.990606575397, 1455.6506438454774, 10123.190144788417, 11384.71538211544, 11487.483063262152, 11723.413829308543, 12114.030877529798, 11660.591879284206, 11806.409679516033, 11858.466369013393, 11912.702182445442, 11961.700957476067, 2507.4358269389377, 2540.0, 2540.8729563521842, 2559.027569523578, 2570.8586027768465, 2071.3682326890294, 2462.7167875694254, 2487.3657217409536, 2532.696076470826, 2535.4168541235526, 2147.495338595437, 2484.6769919910384, 2514.283429222672, 2516.0564788204424, 2521.949561008788, 2089.3105275259204, 2395.5448464791634, 2490.3151633300804, 2514.4342522932375, 2518.2571141513113, 1911.5890083632087, 2395.880205989711, 2503.873257034342, 2508.83692559983, 2513.3716570857287, 11775.764484710258, 11789.292642441394, 11835.940820295844, 11845.703857403547, 12004.039676825832, 6564.409203171737, 6748.763793267212, 6822.5565338253355, 6830.658467076676, 6837.3162683731725, 3588.6052534221217, 3993.302846290315, 4425.955740442587, 4514.977425112854, 4515.661325400608, 1251.4681260464267, 1255.409957320058, 1268.2580690296188, 1290.677330667333, 1306.4774090363835, 15914.442994495254, 16026.439074632332, 16183.19084045793, 16283.02301861892, 16755.329786808514, 5054.797808087709, 5621.49406553404, 5759.913601295984, 5902.704864756745, 5926.407359264039, 11764.0, 12231.877681223074, 12236.632901012983, 12246.326452045045, 12306.938465307616, 6576.671166441659, 6599.769008084694, 6607.636579988147, 6608.603483790988, 6623.503237257225, 5755.791283830443, 6024.457798798042, 6133.723982420802, 6167.105769663373, 6203.937960620336, 4081.285774989365, 4085.775282359439, 4125.071858831739, 4470.061287129674, 4519.954800452019, 1308.9672758180932, 1321.993390033063, 1333.926634035118, 1343.2813444807177, 1344.8453427855923, 767.7351313796731, 776.1268572855538, 784.705735349244, 805.5368163306123, 814.6293436486385, 6627.900581491282, 6861.176658800876, 6964.199117101497, 6999.230084690782, 7039.436845052383, 907.841127802632, 934.3879758758025, 938.0947385772762, 938.9436633801857, 951.7715748220515, 4086.9795651021554, 4184.644305234089, 4308.965848196411, 4359.585839345257, 4396.714213576107, 14640.341184646717, 15203.26341258562, 15273.083614983136, 15273.159976201416, 15278.006929549765, 14364.928175359057, 15108.622284442758, 15162.848371516364, 15173.24133793328, 15187.848121518864, 8525.275351595059, 8558.315334773204, 8725.996510401259, 8726.214640682241, 8758.211760941545, 6862.107925969592, 7111.60886151246, 7193.820154496179, 7210.747623833141, 7220.530665506725, 10662.73343166396, 13225.867741322463, 13435.596932092052, 13520.18878867271, 13549.458021679417, 13739.313034348443, 13753.518626848012, 13786.586402407944, 13922.930385348009, 14052.227127508086, 9468.822170900869, 9703.708888733348, 9727.708168754949, 9728.513574321398, 9745.366551174151, 10701.464926753475, 10716.0, 10719.785604288027, 10751.193660475652, 10861.67414977552, 9118.680846170351, 9298.814023719622, 9336.06639336061, 9367.391119577205, 9372.95313523428, 8420.705275315335, 8960.148785865333, 8980.46117232968, 9111.407758495941, 9215.539223038688, 6762.019507171531, 7924.088729796147, 7987.600619969095, 8067.193280671886, 8142.715004975034, 6002.33974262822, 6118.479929206067, 6130.693465326805, 6141.3551577085545, 6149.2313460817395, 6045.123457098608, 6098.329183789869, 6166.352532984151, 6173.641798804256, 6248.812535623939], "value": 5400.204651874652, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.2641341830395, 410.01391653074364, 411.20021558069527, 411.96086371794667, 424.57754534238416, 5200.297959775429, 6313.400226978441, 6410.935890641093, 6439.130717353156, 6506.707198176082, 5097.949020509794, 5272.446393128724, 5979.641221526701, 6015.21802165719, 6194.938050619492, 4722.0, 4729.787159577827, 4764.261539461371, 4773.493915694254, 4786.82722732931, 4670.7664616769025, 4786.545278198566, 4788.832390866321, 4808.773762690509, 4906.509349065101, 3691.593924668284, 4428.225060614395, 4695.3426520287185, 4720.622350211992, 4724.858254252378, 4713.1987562114355, 4736.550027747358, 4741.407324084489, 4744.976275118637, 4752.7861246244, 3574.981130377856, 3660.33454778078, 3873.8378486453953, 4210.7473551587, 4216.430781844442, 4880.975595122037, 5722.9984752668115, 6093.7812437512675, 6130.835141323134, 6154.261488621394, 4914.3611330527165, 5634.323881134249, 5658.660480371191, 5667.546596272288, 5683.4424508970715, 6412.435025989595, 6636.900446493307, 6715.66421678919, 6734.427573656245, 6759.188897332302, 4703.106409782131, 4755.83354582588, 4769.42766867978, 4777.904441911177, 4825.831095911662, 5448.91826622601, 5943.732532036069, 6973.396118892632, 7106.538075025159, 7140.571565706075, 3778.848846046127, 4453.487848897354, 4651.069786042805, 4785.617150627942, 4864.124457597666, 4332.523422423531, 4756.500567440438, 4835.879103022453, 5012.0, 5022.296878437021, 4858.708477491362, 5003.424606170264, 5478.383876756367, 5647.67460444305, 5657.755293835349, 6344.762771259652, 6523.347665233438, 6717.832054198587, 6735.831604209934, 6766.323367663293, 1693.2813194607484, 1723.2503860820584, 1727.06738361286, 1728.4814555633259, 1730.9134543272785, 1055.984160237597, 1087.2280680716622, 1132.5583022621183, 1170.5083864776814, 1192.6839387562302, 6302.180716506825, 6344.445610825377, 6353.5234857385885, 6378.298387177407, 6387.776427825004, 6758.864822703518, 6888.1082188563305, 6955.374016338554, 6965.407940325028, 7069.6111713856235, 5009.298698182258, 6292.716827742763, 6317.336679648571, 6320.62076275425, 6356.555041147168, 3507.1582820122985, 3614.1868079681863, 3641.617630148849, 3646.817659117087, 3653.1962968147495, 6179.752809887644, 7075.646217689095, 7087.192765844779, 7096.822579435452, 7102.502824802317, 5768.94231057684, 6649.506435086842, 6748.628825414551, 7114.679839407239, 7160.176579693402, 5548.667079975215, 6419.614823110722, 6749.156355455568, 6835.829104272333, 7197.0, 5629.7748090077575, 6141.385861413823, 6967.163940327193, 6971.5468494547695, 6990.930090699231, 5565.1095824667855, 5722.853929776334, 6508.219013718195, 6823.577629941497, 6871.622060786504, 6717.865642687021, 6884.0, 6892.758753443605, 7052.541584796972, 7155.390037241579, 4876.341693871424, 4877.853704379539, 4905.7792399342825, 4909.189983652657, 4927.162382394929, 6284.0, 6735.865282694416, 6741.865162696817, 6873.278305778022, 6925.792226233221, 5718.456746609066, 5739.9139012915675, 6594.505412094016, 6628.27089020217, 6658.700358483783, 5462.426445223195, 5526.723663816873, 6456.257530384066, 6598.637074960731, 6608.7356505739235, 5141.665791723526, 5372.865678358073, 6475.967620162058, 6505.161280656703, 6509.283978762238, 6268.655223962726, 6295.622262664255, 6534.150560427114, 6651.46788256948, 6699.598024118569, 5265.6840589564745, 6075.878482430238, 6228.564000520101, 6330.1454303668415, 6619.437347825488], "value": 4892.004694299149, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.3621358059229, 829.8713749300949, 834.3492076180573, 859.286791962671, 901.3465237702667, 16389.83610163898, 16788.916055419722, 16832.24254908529, 18059.27762889483, 18737.437876863714, 17740.959789624198, 17863.10684465775, 17930.37243696471, 18012.09939503023, 18181.27274909002, 1032.1897310611153, 1035.792841431713, 1059.1368035051446, 1071.7159952612565, 1081.3457857995918, 1052.489542571852, 1057.0011339284379, 1059.8357254625507, 1062.1078294232843, 1064.159314141827, 1117.3016864459712, 1122.8428020077197, 1122.893325134111, 1128.3963079752334, 1128.5260190719875, 1075.2097208551709, 1097.3470784882982, 1113.6325012745774, 1115.893990070942, 1118.1278602689874, 636.7325723196269, 797.1589972578927, 806.4757907360204, 806.6813608624619, 810.0643756461296, 16847.83152168487, 16880.409185678443, 16927.3229070837, 16981.83018169827, 17075.14624268794, 16884.240209190615, 16972.575685607833, 17064.658706825918, 17190.398336058177, 17384.47846564605, 13790.793138102936, 13859.376328065262, 13929.512467063705, 14157.575272741835, 14160.575182744535, 3353.077903576536, 3408.897733068012, 3424.7945123292684, 3425.1608355952703, 3426.3318652862704, 65261.36846578826, 113707.0, 135063.0, 136388.0, 136854.31572842173, 11415.258008229439, 11643.941780291045, 11684.591039313584, 13377.197368157942, 13842.307884605729, 24245.0, 24554.140605078737, 24776.25671229866, 24930.626040609404, 24944.251672449856, 7479.513831601037, 7631.3894888408795, 7651.5409075455655, 7655.2344765523, 7661.0, 3987.3620220764537, 3999.7000224983244, 4061.4923134608175, 4071.1247081877536, 4073.307537718595, 4693.460252070987, 4838.322634831126, 4839.443464001614, 4890.657653964259, 4891.26631005352, 797.5175019113469, 831.6216121664628, 988.0958922585897, 1000.7298029531986, 1000.9749756256006, 624.4848000399688, 643.276314146585, 660.8480049588621, 665.3679004945321, 676.4284179868004, 10001.399916005063, 11373.829392559119, 11451.083913286917, 11466.713332166762, 11582.957533821997, 645.3159650770199, 649.2112083818089, 657.5627207906689, 660.2901880478505, 665.6586977240863, 5033.974830125826, 5898.115282707542, 6018.6990650467305, 6028.517718582503, 6082.57421980466, 108971.91028089776, 110502.0, 110843.0, 111170.0, 112779.0, 73976.0, 95411.56882646766, 96276.0372396267, 96891.0, 98110.0, 84878.57560712157, 85659.5717021411, 86720.0, 87475.56262218648, 87603.56198218968, 12033.217840840318, 12309.384530773246, 12330.691732706755, 12372.13395062337, 12406.193597416139, 24900.0, 42951.0, 43066.56933430751, 43187.78406108075, 43271.783641081594, 50458.74770626123, 52525.212121818964, 54666.72666636641, 55305.89388212294, 55379.44620553743, 16409.67180656404, 16427.91786041062, 16557.503274901774, 16579.171041447637, 16645.91677041607, 14259.42962281497, 17797.555061123574, 18459.446216613524, 18709.812901870806, 18971.62056758885, 16029.679406411573, 16218.756718648994, 16316.102614356325, 16939.830601694317, 17112.91443542774, 16399.42602008924, 16535.751963720795, 16980.0, 17064.0, 17256.913715431343, 16278.186090695655, 16440.342386304892, 16449.026116865574, 16530.83469165293, 16537.91731041337, 11719.12106592026, 11913.583024594098, 12022.0, 12054.15620906528, 12128.818067728815, 12939.138422521384, 12974.481020759064, 12974.675633109247, 13020.674483137618, 13111.344432778133], "value": 8471.366951832497, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 120.0, 115.0, 96.0, 174.0, 110.0, 146.0, 123.0, 175.0, 173.0], "value": 133.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 111.0, 112.0, 146.0, 114.0, 143.0, 95.0, 143.0, 158.0, 149.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 215.0, 237.0, 223.0, 285.0, 267.0, 198.0, 198.0, 195.0], "value": 221.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 53.0, 54.0, 106.0, 88.0, 85.0, 87.0, 59.0, 76.0, 59.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 84.0, 80.0, 104.0, 79.0, 90.0, 69.0, 68.0, 88.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 94.0, 91.0, 88.0, 88.0, 88.0, 90.0, 88.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 95.0, 100.0, 95.0, 96.0, 122.0, 96.0, 95.0, 96.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 148.0, 142.0, 96.0, 90.0, 129.0, 141.0, 92.0, 95.0, 91.0], "value": 109.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 55.0, 50.0, 53.0, 51.0, 50.0, 52.0, 52.0, 51.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 68.0, 63.0, 63.0, 60.0, 57.0, 56.0, 56.0, 56.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 82.0, 86.0, 85.0, 106.0, 86.0, 82.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 155.0, 150.0, 149.0, 149.0, 156.0, 135.0, 160.0, 205.0, 156.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 189.0, 187.0, 195.0, 185.0, 185.0, 198.0, 184.0, 180.0, 232.0], "value": 191.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 69.0, 68.0, 70.0, 66.0, 68.0, 68.0, 67.0], "value": 68.3, "unit": "score"}]}]} 09:04:54 INFO - Return code: 0 09:04:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:04:54 ERROR - # TBPL SUCCESS # 09:04:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:54 INFO - 2017-04-17 09:04:54.525 screenresolution[1663:10625] starting screenresolution argv=screenresolution get 09:04:54 INFO - 2017-04-17 09:04:54.528 screenresolution[1663:10625] Display 0: 1600x1200x32@60 09:04:54 INFO - 2017-04-17 09:04:54.534 screenresolution[1664:10628] starting screenresolution argv=screenresolution list 09:04:54 INFO - Available Modes on Display 0 09:04:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:54 INFO - Intel Iris: 09:04:54 INFO - Chipset Model: Intel Iris 09:04:54 INFO - Type: GPU 09:04:54 INFO - Bus: Built-In 09:04:54 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:54 INFO - Vendor: Intel (0x8086) 09:04:54 INFO - Device ID: 0x0a2e 09:04:54 INFO - Revision ID: 0x0009 09:04:54 INFO - Displays: 09:04:54 INFO - Display: 09:04:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:54 INFO - Display Serial Number: 1600x1200 60 09:04:54 INFO - Main Display: Yes 09:04:54 INFO - Mirror: Off 09:04:54 INFO - Online: Yes 09:04:54 INFO - Rotation: Supported 09:04:54 INFO - Return code: 0 09:04:54 INFO - Running post-action listener: _resource_record_post_action 09:04:54 INFO - [mozharness: 2017-04-17 16:04:54.777756Z] Finished run-tests step (success) 09:04:54 INFO - Running post-run listener: _resource_record_post_run 09:04:54 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40549376; Write bytes: 514782720; Read time: 397; Write time: 1715 09:04:54 INFO - TinderboxPrint: CPU usage
54.2% 09:04:54 INFO - TinderboxPrint: I/O read bytes / time
40,549,376 / 397 09:04:54 INFO - TinderboxPrint: I/O write bytes / time
514,782,720 / 1,715 09:04:54 INFO - TinderboxPrint: CPU idle
2,193.0 (45.8%) 09:04:54 INFO - TinderboxPrint: CPU system
966.9 (20.2%) 09:04:54 INFO - TinderboxPrint: CPU user
1,629.9 (34.0%) 09:04:54 INFO - TinderboxPrint: Swap in / out
249,135,104 / 0 09:04:54 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 197118464; Write bytes: 193971712; Read time: 15035; Write time: 434 09:04:54 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 39549952; Write bytes: 303968256; Read time: 380; Write time: 1234 09:04:55 INFO - Running post-run listener: _upload_blobber_files 09:04:55 INFO - Blob upload gear active. 09:04:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:04:55 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 09:04:55 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'] 09:04:55 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 09:04:55 INFO - (blobuploader) - INFO - Open directory for files ... 09:04:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:04:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:04:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:56 INFO - (blobuploader) - INFO - Done attempting. 09:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:04:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:57 INFO - (blobuploader) - INFO - Done attempting. 09:04:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:04:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:58 INFO - (blobuploader) - INFO - Done attempting. 09:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:00 INFO - (blobuploader) - INFO - Done attempting. 09:05:00 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:00 INFO - Return code: 0 09:05:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/622c5b3083160d28f6549053c417e278c01eaf130cc955f502241ade48e971fe11aa55d57e86260527d69fe7fc1a15d98e18ea6ffcbb0bc76e5b42e36f6d1dc1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ee5e595697cb43864fd49ac0bb46cd690447adeaaf0f4fc3e7aa6d8b0f7bada859c529dbd06b1db7a26330846c02b97e7a55d3fe2ca7c8bdac60117f1ef397", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ddf33c822b162dadd19b5b3ab8bb63982cfa767863692df1e80e3011a9e647b6f529c04b3cfb6b3a104473a0cf742237abe7c3e1eea752bea7c9cb230160dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b34bfbb5a869bde1622d59b30ed5ba83e0088d234cfa1bb69685d7c389d17a03f8554cbc90b917f267dc18ab66bd5352d48cb819bc4d4782e1446427ccf00d07"} 09:05:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:00 INFO - Contents: 09:05:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/622c5b3083160d28f6549053c417e278c01eaf130cc955f502241ade48e971fe11aa55d57e86260527d69fe7fc1a15d98e18ea6ffcbb0bc76e5b42e36f6d1dc1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ee5e595697cb43864fd49ac0bb46cd690447adeaaf0f4fc3e7aa6d8b0f7bada859c529dbd06b1db7a26330846c02b97e7a55d3fe2ca7c8bdac60117f1ef397", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ddf33c822b162dadd19b5b3ab8bb63982cfa767863692df1e80e3011a9e647b6f529c04b3cfb6b3a104473a0cf742237abe7c3e1eea752bea7c9cb230160dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b34bfbb5a869bde1622d59b30ed5ba83e0088d234cfa1bb69685d7c389d17a03f8554cbc90b917f267dc18ab66bd5352d48cb819bc4d4782e1446427ccf00d07"} 09:05:00 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:00 INFO - Copying logs to upload dir... 09:05:00 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.732488 ========= master_lag: 0.82 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-17 09:05:01.497333) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 09:05:01.501172) ========= 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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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/622c5b3083160d28f6549053c417e278c01eaf130cc955f502241ade48e971fe11aa55d57e86260527d69fe7fc1a15d98e18ea6ffcbb0bc76e5b42e36f6d1dc1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ee5e595697cb43864fd49ac0bb46cd690447adeaaf0f4fc3e7aa6d8b0f7bada859c529dbd06b1db7a26330846c02b97e7a55d3fe2ca7c8bdac60117f1ef397", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ddf33c822b162dadd19b5b3ab8bb63982cfa767863692df1e80e3011a9e647b6f529c04b3cfb6b3a104473a0cf742237abe7c3e1eea752bea7c9cb230160dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b34bfbb5a869bde1622d59b30ed5ba83e0088d234cfa1bb69685d7c389d17a03f8554cbc90b917f267dc18ab66bd5352d48cb819bc4d4782e1446427ccf00d07"} build_url:https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010851 build_url: 'https://queue.taskcluster.net/v1/task/RCv59c4yRm-UHuZb9uSSug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/622c5b3083160d28f6549053c417e278c01eaf130cc955f502241ade48e971fe11aa55d57e86260527d69fe7fc1a15d98e18ea6ffcbb0bc76e5b42e36f6d1dc1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ee5e595697cb43864fd49ac0bb46cd690447adeaaf0f4fc3e7aa6d8b0f7bada859c529dbd06b1db7a26330846c02b97e7a55d3fe2ca7c8bdac60117f1ef397", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ddf33c822b162dadd19b5b3ab8bb63982cfa767863692df1e80e3011a9e647b6f529c04b3cfb6b3a104473a0cf742237abe7c3e1eea752bea7c9cb230160dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b34bfbb5a869bde1622d59b30ed5ba83e0088d234cfa1bb69685d7c389d17a03f8554cbc90b917f267dc18ab66bd5352d48cb819bc4d4782e1446427ccf00d07"}' ========= master_lag: 0.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 09:05:01.660318) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:05:01.660609) ========= 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.lZ3J0uYTyB/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.xhCSVm0QRc/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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.005046 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:05:01.708314) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 09:05:01.708590) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 09:05:01.708878) ========= ========= Total master_lag: 1.11 =========