builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0107 starttime: 1491008461.96 results: success (0) buildid: 20170331171322 builduid: aa2be587e61344109cb15152c9bd5228 revision: 7fe20a149f135533746ecaa9a1cf99619101eae0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:01.962849) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:01.963400) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:01.963727) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:01.991352) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:01.991778) ========= 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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-03-31 18:01:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.83M=0.001s 2017-03-31 18:01:02 (9.83 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149596 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:02.163627) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:02.163994) ========= 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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.033277 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:02.220810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 18:01:02.221387) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7fe20a149f135533746ecaa9a1cf99619101eae0 --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 7fe20a149f135533746ecaa9a1cf99619101eae0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wRrD5Dogs9/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.bXcXRxFFOt/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-03-31 18:01:02,325 truncating revision to first 12 chars 2017-03-31 18:01:02,325 Setting DEBUG logging. 2017-03-31 18:01:02,325 attempt 1/10 2017-03-31 18:01:02,325 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7fe20a149f13?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-31 18:01:03,572 unpacking tar archive at: mozilla-central-7fe20a149f13/testing/mozharness/ program finished with exit code 0 elapsedTime=1.527229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 18:01:03.773228) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:03.773716) ========= script_repo_revision: 7fe20a149f135533746ecaa9a1cf99619101eae0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:03.774223) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:03.774576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 18:01:03.795027) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-31 18:01:03.795504) ========= /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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:01:03 INFO - MultiFileLogger online at 20170331 18:01:03 in /builds/slave/test 18:01:03 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 18:01:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:04 INFO - {'append_to_log': False, 18:01:04 INFO - 'base_work_dir': '/builds/slave/test', 18:01:04 INFO - 'blob_upload_branch': 'Firefox', 18:01:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:04 INFO - 'branch': 'Firefox', 18:01:04 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:04 INFO - 'config_files': ('talos/mac_config.py',), 18:01:04 INFO - 'default_actions': ('clobber', 18:01:04 INFO - 'read-buildbot-config', 18:01:04 INFO - 'download-and-extract', 18:01:04 INFO - 'populate-webroot', 18:01:04 INFO - 'create-virtualenv', 18:01:04 INFO - 'install', 18:01:04 INFO - 'run-tests'), 18:01:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:04 INFO - 'download_minidump_stackwalk': True, 18:01:04 INFO - 'download_symbols': 'ondemand', 18:01:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:04 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:04 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:04 INFO - 'gecko_profile': False, 18:01:04 INFO - 'gecko_profile_interval': 0, 18:01:04 INFO - 'installer_path': 'installer.exe', 18:01:04 INFO - 'log_level': 'info', 18:01:04 INFO - 'log_name': 'talos', 18:01:04 INFO - 'log_to_console': True, 18:01:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:01:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:01:04 INFO - 'opt_config_files': (), 18:01:04 INFO - 'pip_index': False, 18:01:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:04 INFO - 'cmd': ('bash', 18:01:04 INFO - '-c', 18:01:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:04 INFO - 'enabled': True, 18:01:04 INFO - 'halt_on_failure': False, 18:01:04 INFO - 'name': 'check_screen_resolution'},), 18:01:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:04 INFO - 'cmd': ('bash', 18:01:04 INFO - '-c', 18:01:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:04 INFO - 'enabled': True, 18:01:04 INFO - 'halt_on_failure': False, 18:01:04 INFO - 'name': 'check_screen_resolution'},), 18:01:04 INFO - 'run_cmd_checks_enabled': True, 18:01:04 INFO - 'suite': 'dromaeojs-e10s', 18:01:04 INFO - 'system_bits': '32', 18:01:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:04 INFO - 'title': 't-yosemite-r7-0107', 18:01:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:04 INFO - 'use_talos_json': True, 18:01:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:04 INFO - 'work_dir': 'build'} 18:01:04 INFO - [mozharness: 2017-04-01 01:01:04.009028Z] Running clobber step. 18:01:04 INFO - Running pre-action listener: _resource_record_pre_action 18:01:04 INFO - Running main action method: clobber 18:01:04 INFO - rmtree: /builds/slave/test/build 18:01:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:06 INFO - Running post-action listener: _resource_record_post_action 18:01:06 INFO - [mozharness: 2017-04-01 01:01:06.647157Z] Finished clobber step (success) 18:01:06 INFO - [mozharness: 2017-04-01 01:01:06.647427Z] Running read-buildbot-config step. 18:01:06 INFO - Running pre-action listener: _resource_record_pre_action 18:01:06 INFO - Running main action method: read_buildbot_config 18:01:06 INFO - Using buildbot properties: 18:01:06 INFO - { 18:01:06 INFO - "project": "", 18:01:06 INFO - "product": "firefox", 18:01:06 INFO - "script_repo_revision": "production", 18:01:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:01:06 INFO - "repository": "", 18:01:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:01:06 INFO - "buildid": "20170331171322", 18:01:06 INFO - "pgo_build": "False", 18:01:06 INFO - "basedir": "/builds/slave/test", 18:01:06 INFO - "buildnumber": 9, 18:01:06 INFO - "slavename": "t-yosemite-r7-0107", 18:01:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:01:06 INFO - "platform": "yosemite_r7", 18:01:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:01:06 INFO - "branch": "mozilla-central", 18:01:06 INFO - "repo_path": "mozilla-central", 18:01:06 INFO - "revision": "7fe20a149f135533746ecaa9a1cf99619101eae0", 18:01:06 INFO - "stage_platform": "macosx64", 18:01:06 INFO - "builduid": "aa2be587e61344109cb15152c9bd5228", 18:01:06 INFO - "slavebuilddir": "test" 18:01:06 INFO - } 18:01:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:01:06 INFO - Running post-action listener: _resource_record_post_action 18:01:06 INFO - [mozharness: 2017-04-01 01:01:06.652041Z] Finished read-buildbot-config step (success) 18:01:06 INFO - [mozharness: 2017-04-01 01:01:06.652175Z] Running download-and-extract step. 18:01:06 INFO - Running pre-action listener: _resource_record_pre_action 18:01:06 INFO - Running main action method: download_and_extract 18:01:06 INFO - mkdir: /builds/slave/test/build/tests 18:01:06 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')]} 18:01:06 INFO - trying https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:01:06 INFO - Downloading https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/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 18:01:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/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 18:01:07 INFO - Downloaded 1325 bytes. 18:01:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:01:07 INFO - Using the following test package requirements: 18:01:07 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:01:07 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:01:07 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:01:07 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:01:07 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'jsshell-mac.zip'], 18:01:07 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:01:07 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:01:07 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:01:07 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:01:07 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:01:07 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:01:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:01:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:01:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:07 INFO - Fetch https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:01:09 INFO - Expected file size: 52508076 18:01:09 INFO - Obtained file size: 52508076 18:01:26 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 18:01:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:01:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:26 INFO - Fetch https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:01:27 INFO - Expected file size: 52508076 18:01:27 INFO - Obtained file size: 52508076 18:01:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:01:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:01:43 INFO - Fetch https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:01:44 INFO - Expected file size: 14404152 18:01:44 INFO - Obtained file size: 14404152 18:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:44 INFO - trying https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:44 INFO - Downloading https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:01:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/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 18:01:46 INFO - Downloaded 75603268 bytes. 18:01:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:46 INFO - mkdir: /builds/slave/test/properties 18:01:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:46 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:46 INFO - Contents: 18:01:46 INFO - build_url:https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:47 INFO - Running post-action listener: _resource_record_post_action 18:01:47 INFO - Running post-action listener: set_extra_try_arguments 18:01:47 INFO - [mozharness: 2017-04-01 01:01:47.576596Z] Finished download-and-extract step (success) 18:01:47 INFO - [mozharness: 2017-04-01 01:01:47.576744Z] Running populate-webroot step. 18:01:47 INFO - Running pre-action listener: _resource_record_pre_action 18:01:47 INFO - Running main action method: populate_webroot 18:01:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:47 INFO - '--tppagecycles', 18:01:47 INFO - '7'], 18:01:47 INFO - 'tests': ['tsvgm']}}, 18:01:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:01:47 INFO - 'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:01:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:01:47 INFO - 'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['damp', 'tps']}, 18:01:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:01:47 INFO - 'tests': ['damp', 'tps']}, 18:01:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['dromaeo_dom']}, 18:01:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['basic_compositor_video']}, 18:01:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:01:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['a11yr', 18:01:47 INFO - 'ts_paint', 18:01:47 INFO - 'tpaint', 18:01:47 INFO - 'sessionrestore', 18:01:47 INFO - 'sessionrestore_no_auto_restore', 18:01:47 INFO - 'tabpaint']}, 18:01:47 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:47 INFO - 'ts_paint', 18:01:47 INFO - 'tpaint', 18:01:47 INFO - 'sessionrestore', 18:01:47 INFO - 'sessionrestore_no_auto_restore', 18:01:47 INFO - 'tabpaint']}, 18:01:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:47 INFO - 'ts_paint', 18:01:47 INFO - 'tpaint', 18:01:47 INFO - 'sessionrestore', 18:01:47 INFO - 'sessionrestore_no_auto_restore', 18:01:47 INFO - 'tabpaint']}, 18:01:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:47 INFO - 'ts_paint', 18:01:47 INFO - 'tpaint', 18:01:47 INFO - 'sessionrestore', 18:01:47 INFO - 'sessionrestore_no_auto_restore', 18:01:47 INFO - 'tabpaint']}, 18:01:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['a11yr', 18:01:47 INFO - 'ts_paint', 18:01:47 INFO - 'tpaint', 18:01:47 INFO - 'sessionrestore', 18:01:47 INFO - 'sessionrestore_no_auto_restore', 18:01:47 INFO - 'tabpaint']}, 18:01:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['a11yr', 18:01:47 INFO - 'ts_paint', 18:01:47 INFO - 'tpaint', 18:01:47 INFO - 'sessionrestore', 18:01:47 INFO - 'sessionrestore_no_auto_restore', 18:01:47 INFO - 'tabpaint']}, 18:01:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['tsvgx', 18:01:47 INFO - 'tsvgr_opacity', 18:01:47 INFO - 'tart', 18:01:47 INFO - 'tscrollx', 18:01:47 INFO - 'cart', 18:01:47 INFO - 'tsvg_static']}, 18:01:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:47 INFO - 'tsvgr_opacity', 18:01:47 INFO - 'tart', 18:01:47 INFO - 'tscrollx', 18:01:47 INFO - 'cart', 18:01:47 INFO - 'tsvg_static']}, 18:01:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:01:47 INFO - 'talos_options': ['--disable-e10s'], 18:01:47 INFO - 'tests': ['tp5o']}, 18:01:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:01:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:01:47 INFO - 'talos_options': ['--disable-e10s', 18:01:47 INFO - '--xperf_path', 18:01:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:47 INFO - 'tests': ['tp5n']}, 18:01:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:01:47 INFO - 'talos_options': ['--xperf_path', 18:01:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:47 INFO - 'tests': ['tp5n']}}, 18:01:47 INFO - 'talos.zip': {'path': '', 18:01:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:47 INFO - Running post-action listener: _resource_record_post_action 18:01:47 INFO - [mozharness: 2017-04-01 01:01:47.595280Z] Finished populate-webroot step (success) 18:01:47 INFO - [mozharness: 2017-04-01 01:01:47.595391Z] Running create-virtualenv step. 18:01:47 INFO - Running pre-action listener: _resource_record_pre_action 18:01:47 INFO - Running main action method: create_virtualenv 18:01:47 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:47 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 18:01:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:47 INFO - Using real prefix '/tools/python27' 18:01:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:48 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:51 INFO - Installing pip.................done. 18:01:51 INFO - Return code: 0 18:01:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:01:51 INFO - Reading from file tmpfile_stdout 18:01:51 INFO - Output received: 18:01:51 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) 18:01:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:01:51 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 18:01:51 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 18:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:51 INFO - 'HOME': '/Users/cltbld', 18:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:51 INFO - 'LOGNAME': 'cltbld', 18:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:51 INFO - 'MOZ_NO_REMOTE': '1', 18:01:51 INFO - 'NO_EM_RESTART': '1', 18:01:51 INFO - 'PAGER': '/bin/cat', 18:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:51 INFO - 'PWD': '/builds/slave/test', 18:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:51 INFO - 'SHELL': '/bin/bash', 18:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:01:51 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:51 INFO - 'USER': 'cltbld', 18:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:51 INFO - 'XPC_FLAGS': '0x0', 18:01:51 INFO - 'XPC_SERVICE_NAME': '0', 18:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:51 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 18:01:51 INFO - Cleaning up... 18:01:51 INFO - Return code: 0 18:01:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:01:51 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 18:01:51 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 18:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:51 INFO - 'HOME': '/Users/cltbld', 18:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:51 INFO - 'LOGNAME': 'cltbld', 18:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:51 INFO - 'MOZ_NO_REMOTE': '1', 18:01:51 INFO - 'NO_EM_RESTART': '1', 18:01:51 INFO - 'PAGER': '/bin/cat', 18:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:51 INFO - 'PWD': '/builds/slave/test', 18:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:51 INFO - 'SHELL': '/bin/bash', 18:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:01:51 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:51 INFO - 'USER': 'cltbld', 18:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:51 INFO - 'XPC_FLAGS': '0x0', 18:01:51 INFO - 'XPC_SERVICE_NAME': '0', 18:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:52 INFO - Downloading/unpacking psutil>=3.1.1 18:01: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 18:01: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 18:01: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 18:01: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 18:01: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 18:01: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 18:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:55 INFO - Installing collected packages: psutil 18:01:55 INFO - Running setup.py install for psutil 18:01:55 INFO - building 'psutil._psutil_osx' extension 18:01:55 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 18:01:56 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 18:01:56 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 18:01:56 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 18:01:56 INFO - building 'psutil._psutil_posix' extension 18:01:56 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 18:01:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:01:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:01:56 INFO - ^ 18:01:56 INFO - 1 warning generated. 18:01:56 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 18:01:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:56 INFO - Successfully installed psutil 18:01:56 INFO - Cleaning up... 18:01:57 INFO - Return code: 0 18:01:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:01:57 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')]} 18:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 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')]} 18:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:01:57 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 18:01:57 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 18:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:57 INFO - 'HOME': '/Users/cltbld', 18:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:57 INFO - 'LOGNAME': 'cltbld', 18:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:57 INFO - 'MOZ_NO_REMOTE': '1', 18:01:57 INFO - 'NO_EM_RESTART': '1', 18:01:57 INFO - 'PAGER': '/bin/cat', 18:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:57 INFO - 'PWD': '/builds/slave/test', 18:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:57 INFO - 'SHELL': '/bin/bash', 18:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:01:57 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:57 INFO - 'USER': 'cltbld', 18:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:57 INFO - 'XPC_FLAGS': '0x0', 18:01:57 INFO - 'XPC_SERVICE_NAME': '0', 18:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:01:57 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 18:01:57 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 18:01:57 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 18:01:57 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 18:01:57 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 18:01:57 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 18:01:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:01:59 INFO - Installing collected packages: mozsystemmonitor 18:01:59 INFO - Running setup.py install for mozsystemmonitor 18:02:00 INFO - Successfully installed mozsystemmonitor 18:02:00 INFO - Cleaning up... 18:02:00 INFO - Return code: 0 18:02:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:02:00 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')]} 18:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:00 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')]} 18:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:00 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:00 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 18:02:00 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 18:02:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:00 INFO - 'HOME': '/Users/cltbld', 18:02:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:00 INFO - 'LOGNAME': 'cltbld', 18:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:00 INFO - 'MOZ_NO_REMOTE': '1', 18:02:00 INFO - 'NO_EM_RESTART': '1', 18:02:00 INFO - 'PAGER': '/bin/cat', 18:02:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:00 INFO - 'PWD': '/builds/slave/test', 18:02:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:00 INFO - 'SHELL': '/bin/bash', 18:02:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:00 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:00 INFO - 'USER': 'cltbld', 18:02:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:00 INFO - 'XPC_FLAGS': '0x0', 18:02:00 INFO - 'XPC_SERVICE_NAME': '0', 18:02:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:01 INFO - Downloading/unpacking jsonschema==2.5.1 18:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:02:04 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 18:02:04 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 18:02:04 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 18:02:04 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 18:02:04 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 18:02:04 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 18:02:04 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:04 INFO - warning: no files found matching '*.txt' 18:02:04 INFO - no previously-included directories found matching 'build' 18:02:04 INFO - no previously-included directories found matching 'dist' 18:02:04 INFO - no previously-included directories found matching '.git*' 18:02:04 INFO - Installing collected packages: jsonschema, functools32 18:02:04 INFO - Running setup.py install for functools32 18:02:04 INFO - warning: no files found matching '*.txt' 18:02:04 INFO - no previously-included directories found matching 'build' 18:02:04 INFO - no previously-included directories found matching 'dist' 18:02:04 INFO - no previously-included directories found matching '.git*' 18:02:05 INFO - Successfully installed jsonschema functools32 18:02:05 INFO - Cleaning up... 18:02:05 INFO - Return code: 0 18:02:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:05 INFO - 'HOME': '/Users/cltbld', 18:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:05 INFO - 'LOGNAME': 'cltbld', 18:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:05 INFO - 'MOZ_NO_REMOTE': '1', 18:02:05 INFO - 'NO_EM_RESTART': '1', 18:02:05 INFO - 'PAGER': '/bin/cat', 18:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:05 INFO - 'PWD': '/builds/slave/test', 18:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:05 INFO - 'SHELL': '/bin/bash', 18:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:05 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:05 INFO - 'USER': 'cltbld', 18:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:05 INFO - 'XPC_FLAGS': '0x0', 18:02:05 INFO - 'XPC_SERVICE_NAME': '0', 18:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:02:05 INFO - Cleaning up... 18:02:05 INFO - Return code: 0 18:02:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:05 INFO - 'HOME': '/Users/cltbld', 18:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:05 INFO - 'LOGNAME': 'cltbld', 18:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:05 INFO - 'MOZ_NO_REMOTE': '1', 18:02:05 INFO - 'NO_EM_RESTART': '1', 18:02:05 INFO - 'PAGER': '/bin/cat', 18:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:05 INFO - 'PWD': '/builds/slave/test', 18:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:05 INFO - 'SHELL': '/bin/bash', 18:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:05 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:05 INFO - 'USER': 'cltbld', 18:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:05 INFO - 'XPC_FLAGS': '0x0', 18:02:05 INFO - 'XPC_SERVICE_NAME': '0', 18:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:05 INFO - Downloading/unpacking blobuploader==1.2.4 18:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:08 INFO - Downloading blobuploader-1.2.4.tar.gz 18:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:02:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:09 INFO - Downloading docopt-0.6.1.tar.gz 18:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:09 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:09 INFO - Running setup.py install for blobuploader 18:02:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:09 INFO - Running setup.py install for requests 18:02:10 INFO - Running setup.py install for docopt 18:02:10 INFO - Successfully installed blobuploader requests docopt 18:02:10 INFO - Cleaning up... 18:02:10 INFO - Return code: 0 18:02:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:10 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:10 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 18:02:10 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 18:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:10 INFO - 'HOME': '/Users/cltbld', 18:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:10 INFO - 'LOGNAME': 'cltbld', 18:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:10 INFO - 'MOZ_NO_REMOTE': '1', 18:02:10 INFO - 'NO_EM_RESTART': '1', 18:02:10 INFO - 'PAGER': '/bin/cat', 18:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:10 INFO - 'PWD': '/builds/slave/test', 18:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:10 INFO - 'SHELL': '/bin/bash', 18:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:10 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:10 INFO - 'USER': 'cltbld', 18:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:10 INFO - 'XPC_FLAGS': '0x0', 18:02:10 INFO - 'XPC_SERVICE_NAME': '0', 18:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:10 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-2prCab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:10 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-FzelJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:10 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-YQP6G4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-gAVhj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-7bY_IZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-0ylySQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-0zBNKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-NX0zQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-r3LOmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-afOIhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-q7sMT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-nFW0y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:11 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-9YaICD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:12 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-lIh1BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:12 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-PzNJOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:12 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-uYtyKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:12 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-FMbRM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:12 INFO - Running setup.py install for manifestparser 18:02:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozcrash 18:02:12 INFO - Running setup.py install for mozdebug 18:02:12 INFO - Running setup.py install for mozdevice 18:02:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozfile 18:02:13 INFO - Running setup.py install for mozhttpd 18:02:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozinfo 18:02:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozInstall 18:02:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozleak 18:02:13 INFO - Running setup.py install for mozlog 18:02:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:14 INFO - Running setup.py install for moznetwork 18:02:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:14 INFO - Running setup.py install for mozprocess 18:02:14 INFO - Running setup.py install for mozprofile 18:02:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:14 INFO - Running setup.py install for mozrunner 18:02:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:14 INFO - Running setup.py install for mozscreenshot 18:02:14 INFO - Running setup.py install for moztest 18:02:14 INFO - Running setup.py install for mozversion 18:02:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:15 INFO - Cleaning up... 18:02:15 INFO - Return code: 0 18:02:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:15 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:15 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 18:02:15 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 18:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:15 INFO - 'HOME': '/Users/cltbld', 18:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:15 INFO - 'LOGNAME': 'cltbld', 18:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:15 INFO - 'MOZ_NO_REMOTE': '1', 18:02:15 INFO - 'NO_EM_RESTART': '1', 18:02:15 INFO - 'PAGER': '/bin/cat', 18:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:15 INFO - 'PWD': '/builds/slave/test', 18:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:15 INFO - 'SHELL': '/bin/bash', 18:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:15 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:15 INFO - 'USER': 'cltbld', 18:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:15 INFO - 'XPC_FLAGS': '0x0', 18:02:15 INFO - 'XPC_SERVICE_NAME': '0', 18:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-nilMe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-_89l_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-vo1fiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-j2IkhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-E4DPek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-sh0BcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-dVSwZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-SYIUPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-sx0KVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-DR7ETy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-MR_UJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-zjIDn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-oFKpwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-BVLxec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-26NxSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-MvGniE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:17 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)) 18:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:17 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-yi8Mae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:17 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)) 18:02:17 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)) 18:02:17 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)) 18:02:17 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)) 18:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:02:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:19 INFO - Downloading blessings-1.6.tar.gz 18:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:19 INFO - Installing collected packages: blessings 18:02:19 INFO - Running setup.py install for blessings 18:02:20 INFO - Successfully installed blessings 18:02:20 INFO - Cleaning up... 18:02:20 INFO - Return code: 0 18:02:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:20 INFO - Reading from file tmpfile_stdout 18:02:20 INFO - Current package versions: 18:02:20 INFO - blessings == 1.6 18:02:20 INFO - blobuploader == 1.2.4 18:02:20 INFO - docopt == 0.6.1 18:02:20 INFO - functools32 == 3.2.3-2 18:02:20 INFO - jsonschema == 2.5.1 18:02:20 INFO - manifestparser == 1.1 18:02:20 INFO - mozInstall == 1.12 18:02:20 INFO - mozcrash == 1.0 18:02:20 INFO - mozdebug == 0.1 18:02:20 INFO - mozdevice == 0.49 18:02:20 INFO - mozfile == 1.2 18:02:20 INFO - mozhttpd == 0.7 18:02:20 INFO - mozinfo == 0.9 18:02:20 INFO - mozleak == 0.1 18:02:20 INFO - mozlog == 3.4 18:02:20 INFO - moznetwork == 0.27 18:02:20 INFO - mozprocess == 0.25 18:02:20 INFO - mozprofile == 0.28 18:02:20 INFO - mozrunner == 6.13 18:02:20 INFO - mozscreenshot == 0.1 18:02:20 INFO - mozsystemmonitor == 0.3 18:02:20 INFO - moztest == 0.8 18:02:20 INFO - mozversion == 1.4 18:02:20 INFO - psutil == 3.1.1 18:02:20 INFO - requests == 1.2.3 18:02:20 INFO - wsgiref == 0.1.2 18:02:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:20 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 18:02:20 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 18:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:20 INFO - 'HOME': '/Users/cltbld', 18:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:20 INFO - 'LOGNAME': 'cltbld', 18:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:20 INFO - 'MOZ_NO_REMOTE': '1', 18:02:20 INFO - 'NO_EM_RESTART': '1', 18:02:20 INFO - 'PAGER': '/bin/cat', 18:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:20 INFO - 'PWD': '/builds/slave/test', 18:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:20 INFO - 'SHELL': '/bin/bash', 18:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:20 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:20 INFO - 'USER': 'cltbld', 18:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:20 INFO - 'XPC_FLAGS': '0x0', 18:02:20 INFO - 'XPC_SERVICE_NAME': '0', 18:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 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)) 18:02:20 INFO - Cleaning up... 18:02:20 INFO - Return code: 0 18:02:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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 0x10fb02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe638d95df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, '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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:20 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 18:02:20 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 18:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:20 INFO - 'HOME': '/Users/cltbld', 18:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:20 INFO - 'LOGNAME': 'cltbld', 18:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:20 INFO - 'MOZ_NO_REMOTE': '1', 18:02:20 INFO - 'NO_EM_RESTART': '1', 18:02:20 INFO - 'PAGER': '/bin/cat', 18:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:20 INFO - 'PWD': '/builds/slave/test', 18:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:20 INFO - 'SHELL': '/bin/bash', 18:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:20 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:20 INFO - 'USER': 'cltbld', 18:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:20 INFO - 'XPC_FLAGS': '0x0', 18:02:20 INFO - 'XPC_SERVICE_NAME': '0', 18:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:02:21 INFO - Cleaning up... 18:02:21 INFO - Return code: 0 18:02:21 INFO - Running post-action listener: _resource_record_post_action 18:02:21 INFO - Running post-action listener: _start_resource_monitoring 18:02:21 INFO - Starting resource monitoring. 18:02:21 INFO - [mozharness: 2017-04-01 01:02:21.078065Z] Finished create-virtualenv step (success) 18:02:21 INFO - [mozharness: 2017-04-01 01:02:21.079027Z] Running install step. 18:02:21 INFO - Running pre-action listener: _resource_record_pre_action 18:02:21 INFO - Running main action method: install 18:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:21 INFO - Reading from file tmpfile_stdout 18:02:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:21 INFO - Reading from file tmpfile_stdout 18:02:21 INFO - Output received: 18:02:21 INFO - Usage: mozinstall [options] installer 18:02:21 INFO - Options: 18:02:21 INFO - -h, --help show this help message and exit 18:02:21 INFO - -d DEST, --destination=DEST 18:02:21 INFO - Directory to install application into. [default: 18:02:21 INFO - "/builds/slave/test"] 18:02:21 INFO - --app=APP Application being installed. [default: firefox] 18:02:21 INFO - mkdir: /builds/slave/test/build/application 18:02:21 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'] 18:02:21 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 18:02:41 INFO - Reading from file tmpfile_stdout 18:02:41 INFO - Output received: 18:02:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:02:41 INFO - Running post-action listener: _resource_record_post_action 18:02:41 INFO - [mozharness: 2017-04-01 01:02:41.626478Z] Finished install step (success) 18:02:41 INFO - [mozharness: 2017-04-01 01:02:41.626620Z] Running run-tests step. 18:02:41 INFO - Running pre-action listener: _resource_record_pre_action 18:02:41 INFO - Running main action method: run_tests 18:02:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:02:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:02:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:02:41 INFO - 2017-03-31 18:02:41.640 screenresolution[1623:7498] starting screenresolution argv=screenresolution get 18:02:41 INFO - 2017-03-31 18:02:41.643 screenresolution[1623:7498] Display 0: 1600x1200x32@60 18:02:41 INFO - 2017-03-31 18:02:41.649 screenresolution[1624:7501] starting screenresolution argv=screenresolution list 18:02:41 INFO - Available Modes on Display 0 18:02:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:02:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:02:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:02:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:02:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:02:41 INFO - Intel Iris: 18:02:41 INFO - Chipset Model: Intel Iris 18:02:41 INFO - Type: GPU 18:02:41 INFO - Bus: Built-In 18:02:41 INFO - VRAM (Dynamic, Max): 1536 MB 18:02:41 INFO - Vendor: Intel (0x8086) 18:02:41 INFO - Device ID: 0x0a2e 18:02:41 INFO - Revision ID: 0x0009 18:02:41 INFO - Displays: 18:02:41 INFO - Display: 18:02:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:02:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:02:41 INFO - Display Serial Number: 1600x1200 60 18:02:41 INFO - Main Display: Yes 18:02:41 INFO - Mirror: Off 18:02:41 INFO - Online: Yes 18:02:41 INFO - Rotation: Supported 18:02:41 INFO - Return code: 0 18:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:41 INFO - Python 2.7.3 18:02:41 INFO - Return code: 0 18:02:41 INFO - grabbing minidump binary from tooltool 18:02: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')]} 18:02:41 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 0x10f8e0d30>, '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 0x10f807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:41 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 18:02:41 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 18:02:41 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 18:02:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:42 INFO - Return code: 0 18:02:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:02:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:42 INFO - ENV: RUST_BACKTRACE is now 1 18:02:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:02:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:42 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/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 18:02:42 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/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0107 --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 18:02:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRrD5Dogs9/Render', 18:02:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:42 INFO - 'HOME': '/Users/cltbld', 18:02:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:42 INFO - 'LOGNAME': 'cltbld', 18:02:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:42 INFO - 'MOZ_NO_REMOTE': '1', 18:02:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:42 INFO - 'NO_EM_RESTART': '1', 18:02:42 INFO - 'PAGER': '/bin/cat', 18:02:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:42 INFO - 'PWD': '/builds/slave/test', 18:02:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:42 INFO - 'RUST_BACKTRACE': '1', 18:02:42 INFO - 'SHELL': '/bin/bash', 18:02:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXcXRxFFOt/Listeners', 18:02:42 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:02:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:42 INFO - 'USER': 'cltbld', 18:02:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:42 INFO - 'XPC_FLAGS': '0x0', 18:02:42 INFO - 'XPC_SERVICE_NAME': '0', 18:02:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:42 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/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 18:02:42 INFO - mozversion application_buildid: 20170331171322 18:02:42 INFO - mozversion application_changeset: 7fe20a149f135533746ecaa9a1cf99619101eae0 18:02:42 INFO - mozversion application_display_name: Nightly 18:02:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:42 INFO - mozversion application_name: Firefox 18:02:42 INFO - mozversion application_remotingname: firefox 18:02:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:02:42 INFO - mozversion application_vendor: Mozilla 18:02:42 INFO - mozversion application_version: 55.0a1 18:02:42 INFO - mozversion platform_buildid: 20170331171322 18:02:42 INFO - mozversion platform_changeset: 7fe20a149f135533746ecaa9a1cf99619101eae0 18:02:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:02:42 INFO - mozversion platform_version: 55.0a1 18:02:42 INFO - using testdate: 1491008562 18:02:42 INFO - actual date: 1491008562 18:02:42 INFO - starting webserver on 'localhost:49252' 18:02:42 INFO - SUITE-START | Running 2 tests 18:02:42 INFO - TEST-START | dromaeo_css 18:02:42 INFO - operating with platform_type : mac_ 18:02:42 INFO - Initialising browser for dromaeo_css test... 18:02:42 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpH9TcN_/profile http://localhost:49252/getInfo.html) 18:02:45 INFO - PID 1634 | 2017-03-31 18:02:45.302 plugin-container[1636:7686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 18:02:45 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:45 INFO - PID 1634 | 2017-03-31 18:02:45.305 plugin-container[1636:7686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 18:02:45 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:45 INFO - PID 1634 | 2017-03-31 18:02:45.305 plugin-container[1636:7686] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:45 INFO - PID 1634 | __metrics Screen width/height:1600/1200 18:02:45 INFO - PID 1634 | colorDepth:24 18:02:45 INFO - PID 1634 | Browser inner width/height: 1024/689 18:02:45 INFO - PID 1634 | __metrics 18:02:45 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:02:46 INFO - TEST-INFO | 1634: exit 0 18:02:46 INFO - Browser initialized. 18:02:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:46 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpH9TcN_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:48 INFO - PID 1637 | 2017-03-31 18:02:48.811 plugin-container[1638:7815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 18:02:48 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:48 INFO - PID 1637 | 2017-03-31 18:02:48.812 plugin-container[1638:7815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 18:02:48 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:48 INFO - PID 1637 | 2017-03-31 18:02:48.812 plugin-container[1638:7815] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:58 INFO - PID 1637 | RSS: Main: 229138432 18:02:58 INFO - PID 1637 | 18:06:02 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 18:06:04 INFO - PID 1637 | RSS: Main: 252555264 18:06:04 INFO - PID 1637 | 18:07:46 INFO - PID 1637 | ************************* 18:07:46 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 18:07:46 INFO - PID 1637 | 18:07:46 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:46 INFO - PID 1637 | Full stack: 18:07:46 INFO - PID 1637 | ************************* 18:07:46 INFO - PID 1637 | ************************* 18:07:46 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 18:07:46 INFO - PID 1637 | 18:07:46 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:46 INFO - PID 1637 | Full stack: 18:07:46 INFO - PID 1637 | ************************* 18:07:46 INFO - PID 1637 | ************************* 18:07:46 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 18:07:46 INFO - PID 1637 | 18:07:46 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:46 INFO - PID 1637 | Full stack: 18:07:46 INFO - PID 1637 | ************************* 18:07:46 INFO - PID 1637 | ************************* 18:07:46 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 18:07:46 INFO - PID 1637 | 18:07:46 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:46 INFO - PID 1637 | Full stack: 18:07:46 INFO - PID 1637 | ************************* 18:09:06 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 18:09:07 INFO - PID 1637 | RSS: Main: 261144576 18:09:07 INFO - PID 1637 | 18:12:09 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 18:12:09 INFO - PID 1637 | RSS: Main: 273227776 18:12:09 INFO - PID 1637 | 18:15:11 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 18:15:12 INFO - PID 1637 | RSS: Main: 265973760 18:15:12 INFO - PID 1637 | 18:18:15 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 18:18:16 INFO - PID 1637 | RSS: Main: 275779584 18:18:16 INFO - PID 1637 | 18:21:18 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 18:21:19 INFO - PID 1637 | RSS: Main: 269438976 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | __start_tp_report 18:21:19 INFO - PID 1637 | _x_x_mozilla_page_load 18:21:19 INFO - PID 1637 | _x_x_mozilla_page_load_details 18:21:19 INFO - PID 1637 | |i|pagename|runs| 18:21:19 INFO - PID 1637 | |0;dojo.html;3363.2768954674725;3491.982540087299;3577.373959557078;3633.418653015518;3663.523741913551;6133.30406059456;6241.531885108618;6241.625502469844;6283.465905398041;6303.905441418371;6187.628742275456;6196.101565273033;6216.937830621704;6298.118263443122;6309.400606942345;5327.680339179642;5531.225628412026;5568.136938774496;5675.120356344773;5719.084946408576;5494.230807686927;5589.105743081108;5719.370869204384;5725.57058220633;5731.226284451587;5416.1063424534905;5433.891322173574;5604.075327570946;5621.409751976024;5678.063119585264;5435.565154787647;5469.179623056573;5481.068218402841;5573.8606534836545;5584.190292407578;5386.8687575609165;5391.191321301797;5438.809641662523;5475.917861232085;5483.177523371526;5259.710715910624;5499.5976026113485;6191.318954914956;6254.4989202591505;6287.591306565105;6434.195725534309;6546.476281897484;6680.632565208912;6690.397864192246;6741.696623651947;5231.398389185254;5650.660960342392;6794.830129246759;6823;6886.449084073262;6233.781817636452;6348.968255158741;6365.745370185234;6367.42693157618;6386.201724784449;5058.165402708585;6426.967865160737;7027.754028608974;7034.718611255493;7071.469639777038;6374.199478474566;6535.8692826143215;6600.933990660128;6610.07458955737;6635.369639884203;6732.124823772878;6772.55978361405;6781.491388145909;6784.321567843275;6807.693653785592;6630.403263706289;6648.767293144813;6661.633610151488;6677.499187560952;6710.261871194165;6468.54720169593;6561.376669216513;6594.670266486753;6680.565763225472;6753.594784312957;5431.8098866539485;5441.646292990943;5444.183372494158;5472.91790623141;5498.065328894098;4775.403074615673;4802.927956080662;4812.25410061441;4823.27650852368;4836.903261934742;6236.532260080512;6306;6341.778037768749;6442.194725659293;6496.6426846522945;6829.52193346461;6878.8624227515165;6893.034975103489;6918.5502942309595;6931.618760968196;6472.967635161794;6487.351264873475;6492.805215843532;6502.414782669583;6523.086767852505;5793.217915581343;5908.438698323653;6037.973544497446;6065.878682426415;6069.2716873975405;6873.690683919235;6939.271376505394;6961.825954351182;7062.646867656699;7071.36357727807;6573;6883.174019117538;6886.552374095668;6892.035115083992;6894.069300644349;6801.18385793708;6860.485463590151;6942.548734332252;7047.365737083687;7065.399441047569;6098.847528811815;6393.976963685788;6718.260991291052;6726.6972986216715;6756.898646520208;5800.39095894925;6175.413335733098;6555.115059467102;6577.112089867806;6742.393184613408;6574.408303252731;6604.603723776589;6635.63504007265;6665.400113989765;6686.06395104676;6664.133662623828;6666.133402657623;6666.166729158856;6666.466682665472;6674.16572928384;6209.316975132641;6361.745530178741;6452.903206451813;6525.64108973992;6544.443722283469;6537.771178008747;6626.90059649096;6633.170853643294;6648.235452922976;6653.035309879943;6470.838229044311;6473.482121430368;6487;6487.3836985486305;6492.058651495412 18:21:19 INFO - PID 1637 | |1;ext.html;751.8345963887946;829.3531045784289;1126.9154813388993;1155.1625071822925;1234.555559998401;37098.588591741514;37970.860874173784;38080.61919380805;38112.618873811254;38122.8093859531;37406.62593374065;37811;37859.81070094646;37862.43206351907;37940.24119517608;5488.725563721818;6217.129601855744;7593.89888466172;7871;8042.517448953082;8398.790030249262;8535.615897284637;8623.180797824229;8638.049814520398;8647.956760216222;8635.95682021589;8700.738977830644;8702.695405660772;8712.215900568948;8728.43265187764;8619.267362274215;8675.219230269275;8677.609507572111;8678.392512524126;8706.259967902735;8542.74371768848;8547.829043419159;8613.870791938127;8620.612072456692;8630.568471576458;37418.43872341917;37745.62254377449;37751.81124094389;37777;37805.432918506245;3053.806824110455;3072.8156310621434;3094.2883136878418;3094.3811237752316;3116.626004879406;27482.725172748418;27505.449891002267;27672.58491122635;27817;28546.714532854614;2741.5476446386524;2828.7595554377904;2829.8443585602786;2851.5009873272093;2932.017774045697;276639;323143;325792;326187;327310;40124.59875401267;40918.59081409207;48840.511594883596;49953.25163619329;50058.99882002339;40620;51072.48927510752;51684.22473662898;51772.70568235824;51928.22107668388;11758.176927615155;14175.85824141766;14277.929155313393;14309.9983001191;14314.785278220836;11448.313101213955;11527.365994870363;11645.475953582109;11662.94168529152;11664.825027624593;10040.347377420445;12293.262404255773;12342.938285308515;12351.505939762488;12370.938145309396;4900.852974410774;5211.348581427322;5404.567634589223;5493.890122197534;5568.331800184003;921.5438358012692;1008.4453550547316;1009.7980403919098;1015.8628585141059;1051.2010871737382;21960.560788784136;23330;23833;23901.282961511184;24642.687529682233;900.9864852027225;974.7904200596905;1117.2905205194759;1137.556353022322;1151.8214676725122;11440.885591144148;12133.09001824849;12234;12249.755004900031;12280;440484;441367;441817;442454;442661;52611.47388526066;53064;53164.20253696197;53187.734061329444;53250.73374633102;1498.7826765119214;1509.1171664576127;1537.3005282596391;1546.180524322111;1561.773542836305;22146.44633884166;22472.438189045406;23414.531709365376;23503.764962350157;23558.0576776927;33552.32895342031;34337;37724.43413348747;37734;37773.05567360838;433842;436190;436253;436453;437621;32581.837090814395;32694.509582355808;32851.67148328486;32873.83563082169;32920.67079329176;41324.793376032925;41463.1707365845;42394;42505.5749442514;42632.78683606562;29042.564361534172;29350;29789;29883;29925.401491970475;28918.855405722836;29576.704232957396;29587.408251835273;29775.55336669995;29920;35282.29435411226;35651;35921;36003;36048.639513604525;28850.42299153963;29167.416651666423;30021.399572008002;30121.397572048874;30161.547576785928;22563.887180563994;23629;23836.284911452683;23863.880680596485;24115.03539858386 18:21:19 INFO - PID 1637 | |2;jquery.html;419.7250800725526;426.12218829211827;432.48318259679684;448.3857115751421;450.1761775950011;19781.40655780327;21442.142314307428;21983.23058692946;22134.003969821355;22249.332520024425;18054.368097116603;18497.907510462428;22713.205037823678;23000.07999680011;23269.76730232697;5269.841904742844;6088;6366.108744775712;6377.170967774183;6381.393767592094;5060.645754797166;5952;6164.84587885302;6235.064740288947;6261.592996455239;5114.514121158484;6191.628502289877;6201.782937597185;6212.471939885115;6218.844528886769;5882.735276912549;6066.878662426749;6090.512758979271;6103.481204097657;6124.601900876429;5142.948570514284;5344.893102137936;5457.5633949283965;5482.835514934558;5500.339959204921;26873.86563067172;27554;28079.71920280812;28770.280742981384;29182.854085729647;1978.2779285560666;1992.2529051605648;2010.9095090720807;2040.398082565632;2084.249670118756;15763.842361576351;16422.096784676836;16524.00855948635;17322.133893305283;17571.56071098233;1888.2541396148445;1911.6427336591037;1914.473519782057;1928.7106933959878;1952.1995981647456;110208.44895775469;117665;155413.44586554103;156559.2172039144;156950;12355.75288494225;12805.871941280468;12822.666106812296;12848.807267890988;12896.613101606967;16442;16516.917415412845;16548;16573.58566035859;16579.834201657828;7839.510493006311;8936;9184.540772961325;9306.953465232766;9337.972822989466;8676.175763302475;8879.866801997976;8912.955435222782;8919.152680495343;9097.681581144761;7876.652422720321;7926.564038977912;8150.83698326042;8157.428979971463;8166.510009399336;2062.7156938202884;2168.4687251623454;2176.053416763713;2177.618916689573;2178.0960901225812;852.5776064231483;866.276658989738;925.7130289610108;942.3827393057559;971.3103696375653;17719;19674.50813729668;24487.265382038568;26224;26514.602280965802;837.844998675247;963.629002833914;966.6036924860773;994.3238597753452;1007.5717819926502;8898.332625053146;10749.677509674566;10777.83833242502;10811.135109191246;10853.077488413574;601351;687185;690661;746287;747760;162497;165959;167833;169360;169894;1052.4369462337766;1164.1967042740482;1243.5585367194515;1247;1249.2379648414642;15221;20708.275210367567;21933.903304835014;21937.780622193575;22121.33635990923;65297.673511632136;65495.67252163708;65777.34222657712;65802.68394632176;66586;86637.13362866543;87777.56111219659;88154.55922720346;88172.11827881896;90920;7961.283484486424;8477.813106165266;8488.363372746946;8669.14223170683;8748.387612867038;12920.25576547154;13238.735225295248;13586;14016.29918504091;14293.213873237071;10227.846582301123;11753.706157346134;11777.411129443664;11784.292942423483;11923.761524769283;9951.054649808257;10958.232923695265;10970.067544258827;11241.60654377093;11279.887201127884;10844.945775271073;10943.398113103856;11231.831522527333;11239.66281011571;11265.831012534654;7789.325295061589;7973.880391794244;8020.839583208186;8089.595520224083;8097.87853182191;11534.59628912984;11642.582724005453;11669.132938729763;11809.70475738079;11869 18:21:19 INFO - PID 1637 | |3;mootools.html;1347.3263368315843;1391.7077413743114;1400.6078298076536;1409.2742237747555;1423.9074460160086;10517.263791534595;10859.51132199052;10961.506732197051;10978.451077446136;11164.497597608106;8269.669213231478;10672.466376681154;10769.676909692702;10862.402567858764;10926.071283940855;2081.375587323804;2417.867017314047;2438.5732496813075;2474.6659201007815;2477.2692055843568;2091.7489901211875;2279.840411171219;2442.792362649177;2445.3764290106087;2456.729759726438;2360.173939121311;2424.0546186987003;2434.050720219116;2436.135172013936;2451.705795304557;2364.7517010713973;2421.309926670899;2427.174760581402;2437.890294936732;2440.3045132137345;2052.0765655455107;2126.415235810149;2289.6794448777187;2346;2440.5362981033545;10915.727106822314;10986.890131098666;10989.175811814066;10997.835032474519;11002.119830413627;6095.664738439384;6332.620042797447;6340.651264180468;6346.746130154788;6347.555671103025;3952.3083460394605;4049.4330793688905;4092.1406504634065;4123.814428350702;4146.460960075201;999.1906555689842;1136.7662543102842;1143.2568830260313;1164.6389619218069;1167.947442365087;15105;15119.092854428769;15216.315265813066;15217.6195595111;15228.46700365482;4356.76037817923;4363.97920225525;4776.737279449594;5418.214358917935;5448.809291674773;11323;11329.886701133048;11365.147613928988;11369.943150284196;11376.976072153546;6212.099245609361;6237.4698150657705;6240.906386404207;6242.219722534684;6246.812595622138;5540.667559946335;5864.736086876101;5870.090136028923;5907;5955.630205052862;3633.8190088221436;4168.958310416857;4295.591918767712;4320.848770293024;4350.042990542076;1267.5690265309838;1268.9365531723377;1273.5542560104075;1286.9549565765153;1288.0532808385829;755.6070843161525;755.7241606813474;776.9961150194326;793.5833687314206;794.1304271822345;5956.6723830188885;5972.970135149297;6065.211522502046;6092.969535152384;6126.693665316806;581.5347721822525;585.8330375842864;822.4243029879109;914.401067300919;936.4708939449189;3975.3241948868763;4160.895977600524;4203.138356636943;4207.957920420818;4267.509236437787;13423.530176443974;13964.930175349058;14002.159870407808;14006.929965350108;14011.159330440207;13330.400131994083;13831.170129792245;13877.722445551033;13911.999999999798;14002.229877356844;9972.252081093948;10222.744431389276;10225.94887025575;10238.027387398186;10243.692689219186;6473.514486413444;6705.832354191184;6792.592444453348;6865.794026179223;6935.861282774215;12132.393380331125;12192.390380481118;12205.511779529075;12263.93868030654;12284.262944223376;12463.189892656948;12577.93711031439;12673.619791406272;12752.42614082387;12819.487220511075;8936.731898043068;9073.637054518009;9186.908130918873;9318.627254909728;9330.206932410549;10046.447445390282;10353.689389318333;10437.425941573287;10452.059314661412;10580.15358771281;7167.928320716726;8739.694110706096;8767.430117042626;8836.911630883866;9032.1935612879;6987;7605.429592780453;8542.615582298688;8631.956840215758;8789.252913502423;6841.657917104224;7425.740099096506;7817;7984.760457186294;7991.440599158003;5817.825465236049;5910.645361278337;5978.491828194478;6067.666278354733;6086.60437071589;5183.948160518346;5367.329083864517;5405.432429594836;5985.521158307412;6146.293176284785 18:21:19 INFO - PID 1637 | |4;prototype.html;335.41804968379864;389.8206824860564;397.8170041780781;407.6983032555906;410.29838975352146;5254.632175747699;6434.806955791334;6485.870282594351;6527.836804079894;6562.934370656292;4950.455449900508;4962.975185124069;5155.072087024334;5710.972025035492;6452.29024807272;4539.727616343013;4638.698484598507;4678.415198100238;4713.740744259065;4715.85852424427;4691.812327506896;4703.459102203256;4709;4712.316714076457;4731.57415832575;4439.800208990603;4659.974805542775;4676.088162808253;4705.3647757552635;4794.041191761644;4436.534163912791;4578.084383123389;4608.377868987711;4640.698354606973;4699.365585645945;3437.123533498967;3464.6188919218866;3785.8831644664615;4001.159756451149;4007.3988901664693;5547.532792004997;5611.971940140314;6273.780417685405;6297.779577714804;6305.59013664115;4968.627352948507;5270.841874743725;5500.3949565547755;5520.282363292747;5525.530329921961;5341.359036915595;6739.820531406984;6834.965825170842;6843.452523798134;6879.140107486564;4320.524742278347;4522.140793249305;4525.751083690396;4544.3637890695;4560.703554268962;6981;7212.459065570103;7263.673134708951;7327.633618319062;7425.777226683208;4490.753008584559;4599.655025873073;4620.168369693421;4622.480747992352;4623.306504024356;4695.671303008825;4699.859004229878;4700.271457924027;4702.600278976325;4706.294055891575;5322.467753224647;5443.673379597158;5486;5513.61404701667;5524.779008839681;5450.91823622646;5961.122246492428;6095.939040609625;6831.248562658104;6836.008778727057;1529.6864142850688;1582.4461438496435;1598.4165779490634;1605.823359430485;1606.020327600163;828;962.4754508792724;967.4969016111583;1045.6497073480468;1065.2436769893307;6536.333235025038;6573.474122070223;6577.6053436793945;6601.996091778229;6617.4706023518;6119.84700382494;7102.5665365995055;7126.2161162272105;7162.964185179041;7166.426685865118;5538.529225015838;6582.210134783761;6693.950160153135;6695.564788288746;6699.598024118569;3397.8301084945656;3654.8720794772057;3767.2653832502397;3779.263043706506;3793.013816407699;5711.800086997018;7441.55350678961;7443.181250062489;7456.7390141344795;7465.85068298631;5314.405678296573;6778.457723382117;6833.965830170917;7418.740344088039;7420.885047761362;6297.4017468340435;6945.166580010431;6963.582185068912;7121.608311542811;7480.531424629859;6526.540933305356;7222.241664625139;7226.458015648953;7240.493165478366;7245.5652660840515;6505;6586.814373412832;6758.898616520658;6874.175098987954;7090.574565526085;6749.628770417674;7045.471589630695;7283.635818209174;7422.47839192975;7488.663010164447;4015.79526142162;5002.474740152232;5068.163752980717;5090.694558326512;5106.846794596168;5877.471027607536;6499.642519661464;7120.323569260912;7182.856342873218;7197.632449834515;6892.551984121016;6908.585484870723;6914.792556223321;6917.896231556631;6938.27148149437;5633.690147041952;6667.43326817226;6838.290427393152;6924.653767311514;6931.237563868071;5543.1984605004345;5559.360673522597;6461.67691615408;6520.445762110084;6759.208809665344;6518.5111116667385;6564.934350656432;6623.900641490478;6658.9667051666365;6960.7215711373;6380.680965951777;6731.028693070915;6732.528722989344;6733.461323094239;6777.796666100025 18:21:19 INFO - PID 1637 | |5;yui.html;599.616245602814;723.1936390924119;727.1274470635236;733.6001878975958;740.8110931712414;13831.239281839507;15869.206539673014;16509;16518.42185523507;17054.403095891645;15064.849351506482;15220.695586088299;15233;15279.312430940607;15281.159536225503;933.9159475647191;941.6751220828803;961.4856052012175;989.579428742785;991.9553620087078;1010.3634710132607;1011.0546638892622;1011.6762635956497;1014.5282443663694;1014.5586669798661;1045.6131231444376;1065.2649671726522;1065.8435597376856;1066.807974564578;1066.855974443452;1044.4568824211406;1062.5112448273803;1062.5909025025383;1065.733566608348;1066.7279843639822;787.3425689549233;787.9566623835686;789.1122487201898;789.3054112381068;792.1959211400433;13470.39383227757;16324.918375408166;16697.749533757004;16954.576135596584;17140.485785426456;14615.853841461661;14693.706125877423;14774.131812926149;14889.255537223096;14947.177905215209;10336.173106151566;11724.472398742077;12034.33811140387;12102.092343074304;12108.273503589724;2732.7130651281727;3070.6949546442675;3146.9527957080663;3165.699258570432;3173.222560472699;110636.4468177662;116335;117679;117993;118358;8903.821923561622;11017.11863050954;11323.980841724284;11472.254303470248;11792.88207117935;24639.38401539976;24972.625410618493;25125.87437062803;25244.873775631004;25305.87347063253;7808.726694565663;7890.132085470594;8116.837663246702;8144.918550814416;8204.917950820416;4174.624283814472;4175.64507016907;4229.788510574521;4270.743755374688;4276.636485898671;3793.8482460701816;3820.1977584707524;4165.729227600196;4222.071144348239;4252.298370768788;852.8891244138223;857.9227869491775;858.4763294390417;862.7525296602386;882.567541904475;476.23290858506596;553.9861503462446;565.7482420322917;594.1296001358031;603.1314906534576;8808.339374546935;9286.442813431217;10271.794564108675;10315.74210644725;10419.479026048668;484.706752414791;546.8250159948858;586.4428792646917;587.9265091863517;593.6171169595586;4655.557722016403;4736.478987311393;4793.544613261734;4794.472608013116;4795.808167673324;106142;107337.38993915098;108197.45901270444;109653;113386.43306783414;97468.51265743627;97528;98341;99324;99856.50071749595;66757;78293.82559261502;84715.57642211749;84943.28903720473;87119;9784.217262618831;10126.101450941293;10928.235023548275;11037.944810275898;11240.437978101227;39040;39671.60328396795;39995.60004400035;40481.39277910775;40619;50841.745791270805;51811;52316.47683523116;52550;52950.676233094484;13821.378037988114;13890.166590004632;14577.198254095701;14646.9747117705;14771.482998095014;14727.852721472647;14764.187969661773;14788.704225915206;14800.555983320517;15245.771313430085;13592.728145437233;13715.588532344045;13721.176729396268;13760;14214.857851421353;11150.832737508781;13905.443782248596;14227.831152545203;14248;14278.714425711636;13488.258145802076;13573.38919748594;13574.524891628746;14114.223717695626;14311.284435778378;10094.747631309276;10292.639757608447;10456.634017809645;10649.307794993301;10723.410212438079;7313.780586582411;8889.333300002654;9232.630694772133;10543.841842372523;11041 18:21:19 INFO - PID 1637 | __end_tp_report 18:21:19 INFO - PID 1637 | __start_cc_report 18:21:19 INFO - PID 1637 | _x_x_mozilla_cycle_collect,435 18:21:19 INFO - PID 1637 | __end_cc_report 18:21:19 INFO - PID 1637 | __startTimestamp1491009679175__endTimestamp 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | ------- Summary: start ------- 18:21:19 INFO - PID 1637 | Number of tests: 6 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6196.28 Median:6468.55 stddev:733.05 (11.3%) stddev-sans-first:700.80 18:21:19 INFO - PID 1637 | Values: 3363.3 3492.0 3577.4 3633.4 3663.5 6133.3 6241.5 6241.6 6283.5 6303.9 6187.6 6196.1 6216.9 6298.1 6309.4 5327.7 5531.2 5568.1 5675.1 5719.1 5494.2 5589.1 5719.4 5725.6 5731.2 5416.1 5433.9 5604.1 5621.4 5678.1 5435.6 5469.2 5481.1 5573.9 5584.2 5386.9 5391.2 5438.8 5475.9 5483.2 5259.7 5499.6 6191.3 6254.5 6287.6 6434.2 6546.5 6680.6 6690.4 6741.7 5231.4 5650.7 6794.8 6823.0 6886.4 6233.8 6349.0 6365.7 6367.4 6386.2 5058.2 6427.0 7027.8 7034.7 7071.5 6374.2 6535.9 6600.9 6610.1 6635.4 6732.1 6772.6 6781.5 6784.3 6807.7 6630.4 6648.8 6661.6 6677.5 6710.3 6468.5 6561.4 6594.7 6680.6 6753.6 5431.8 5441.6 5444.2 5472.9 5498.1 4775.4 4802.9 4812.3 4823.3 4836.9 6236.5 6306.0 6341.8 6442.2 6496.6 6829.5 6878.9 6893.0 6918.6 6931.6 6473.0 6487.4 6492.8 6502.4 6523.1 5793.2 5908.4 6038.0 6065.9 6069.3 6873.7 6939.3 6961.8 7062.6 7071.4 6573.0 6883.2 6886.6 6892.0 6894.1 6801.2 6860.5 6942.5 7047.4 7065.4 6098.8 6394.0 6718.3 6726.7 6756.9 5800.4 6175.4 6555.1 6577.1 6742.4 6574.4 6604.6 6635.6 6665.4 6686.1 6664.1 6666.1 6666.2 6666.5 6674.2 6209.3 6361.7 6452.9 6525.6 6544.4 6537.8 6626.9 6633.2 6648.2 6653.0 6470.8 6473.5 6487.0 6487.4 6492.1 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:52661.06 Median:33944.66 stddev:106858.59 (314.8%) stddev-sans-first:107087.28 18:21:19 INFO - PID 1637 | Values: 751.8 829.4 1126.9 1155.2 1234.6 37098.6 37970.9 38080.6 38112.6 38122.8 37406.6 37811.0 37859.8 37862.4 37940.2 5488.7 6217.1 7593.9 7871.0 8042.5 8398.8 8535.6 8623.2 8638.0 8648.0 8636.0 8700.7 8702.7 8712.2 8728.4 8619.3 8675.2 8677.6 8678.4 8706.3 8542.7 8547.8 8613.9 8620.6 8630.6 37418.4 37745.6 37751.8 37777.0 37805.4 3053.8 3072.8 3094.3 3094.4 3116.6 27482.7 27505.4 27672.6 27817.0 28546.7 2741.5 2828.8 2829.8 2851.5 2932.0 276639.0 323143.0 325792.0 326187.0 327310.0 40124.6 40918.6 48840.5 49953.3 50059.0 40620.0 51072.5 51684.2 51772.7 51928.2 11758.2 14175.9 14277.9 14310.0 14314.8 11448.3 11527.4 11645.5 11662.9 11664.8 10040.3 12293.3 12342.9 12351.5 12370.9 4900.9 5211.3 5404.6 5493.9 5568.3 921.5 1008.4 1009.8 1015.9 1051.2 21960.6 23330.0 23833.0 23901.3 24642.7 901.0 974.8 1117.3 1137.6 1151.8 11440.9 12133.1 12234.0 12249.8 12280.0 440484.0 441367.0 441817.0 442454.0 442661.0 52611.5 53064.0 53164.2 53187.7 53250.7 1498.8 1509.1 1537.3 1546.2 1561.8 22146.4 22472.4 23414.5 23503.8 23558.1 33552.3 34337.0 37724.4 37734.0 37773.1 433842.0 436190.0 436253.0 436453.0 437621.0 32581.8 32694.5 32851.7 32873.8 32920.7 41324.8 41463.2 42394.0 42505.6 42632.8 29042.6 29350.0 29789.0 29883.0 29925.4 28918.9 29576.7 29587.4 29775.6 29920.0 35282.3 35651.0 35921.0 36003.0 36048.6 28850.4 29167.4 30021.4 30121.4 30161.5 22563.9 23629.0 23836.3 23863.9 24115.0 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:40718.29 Median:23878.52 stddev:117035.44 (490.1%) stddev-sans-first:117324.64 18:21:19 INFO - PID 1637 | Values: 419.7 426.1 432.5 448.4 450.2 19781.4 21442.1 21983.2 22134.0 22249.3 18054.4 18497.9 22713.2 23000.1 23269.8 5269.8 6088.0 6366.1 6377.2 6381.4 5060.6 5952.0 6164.8 6235.1 6261.6 5114.5 6191.6 6201.8 6212.5 6218.8 5882.7 6066.9 6090.5 6103.5 6124.6 5142.9 5344.9 5457.6 5482.8 5500.3 26873.9 27554.0 28079.7 28770.3 29182.9 1978.3 1992.3 2010.9 2040.4 2084.2 15763.8 16422.1 16524.0 17322.1 17571.6 1888.3 1911.6 1914.5 1928.7 1952.2 110208.4 117665.0 155413.4 156559.2 156950.0 12355.8 12805.9 12822.7 12848.8 12896.6 16442.0 16516.9 16548.0 16573.6 16579.8 7839.5 8936.0 9184.5 9307.0 9338.0 8676.2 8879.9 8913.0 8919.2 9097.7 7876.7 7926.6 8150.8 8157.4 8166.5 2062.7 2168.5 2176.1 2177.6 2178.1 852.6 866.3 925.7 942.4 971.3 17719.0 19674.5 24487.3 26224.0 26514.6 837.8 963.6 966.6 994.3 1007.6 8898.3 10749.7 10777.8 10811.1 10853.1 601351.0 687185.0 690661.0 746287.0 747760.0 162497.0 165959.0 167833.0 169360.0 169894.0 1052.4 1164.2 1243.6 1247.0 1249.2 15221.0 20708.3 21933.9 21937.8 22121.3 65297.7 65495.7 65777.3 65802.7 66586.0 86637.1 87777.6 88154.6 88172.1 90920.0 7961.3 8477.8 8488.4 8669.1 8748.4 12920.3 13238.7 13586.0 14016.3 14293.2 10227.8 11753.7 11777.4 11784.3 11923.8 9951.1 10958.2 10970.1 11241.6 11279.9 10844.9 10943.4 11231.8 11239.7 11265.8 7789.3 7973.9 8020.8 8089.6 8097.9 11534.6 11642.6 11669.1 11809.7 11869.0 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6732.27 Median:2475.97 stddev:4196.50 (169.5%) stddev-sans-first:4188.76 18:21:19 INFO - PID 1637 | Values: 1347.3 1391.7 1400.6 1409.3 1423.9 10517.3 10859.5 10961.5 10978.5 11164.5 8269.7 10672.5 10769.7 10862.4 10926.1 2081.4 2417.9 2438.6 2474.7 2477.3 2091.7 2279.8 2442.8 2445.4 2456.7 2360.2 2424.1 2434.1 2436.1 2451.7 2364.8 2421.3 2427.2 2437.9 2440.3 2052.1 2126.4 2289.7 2346.0 2440.5 10915.7 10986.9 10989.2 10997.8 11002.1 6095.7 6332.6 6340.7 6346.7 6347.6 3952.3 4049.4 4092.1 4123.8 4146.5 999.2 1136.8 1143.3 1164.6 1167.9 15105.0 15119.1 15216.3 15217.6 15228.5 4356.8 4364.0 4776.7 5418.2 5448.8 11323.0 11329.9 11365.1 11369.9 11377.0 6212.1 6237.5 6240.9 6242.2 6246.8 5540.7 5864.7 5870.1 5907.0 5955.6 3633.8 4169.0 4295.6 4320.8 4350.0 1267.6 1268.9 1273.6 1287.0 1288.1 755.6 755.7 777.0 793.6 794.1 5956.7 5973.0 6065.2 6093.0 6126.7 581.5 585.8 822.4 914.4 936.5 3975.3 4160.9 4203.1 4208.0 4267.5 13423.5 13964.9 14002.2 14006.9 14011.2 13330.4 13831.2 13877.7 13912.0 14002.2 9972.3 10222.7 10225.9 10238.0 10243.7 6473.5 6705.8 6792.6 6865.8 6935.9 12132.4 12192.4 12205.5 12263.9 12284.3 12463.2 12577.9 12673.6 12752.4 12819.5 8936.7 9073.6 9186.9 9318.6 9330.2 10046.4 10353.7 10437.4 10452.1 10580.2 7167.9 8739.7 8767.4 8836.9 9032.2 6987.0 7605.4 8542.6 8632.0 8789.3 6841.7 7425.7 7817.0 7984.8 7991.4 5817.8 5910.6 5978.5 6067.7 6086.6 5183.9 5367.3 5405.4 5985.5 6146.3 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5493.75 Median:6196.81 stddev:1747.87 (28.2%) stddev-sans-first:1709.35 18:21:19 INFO - PID 1637 | Values: 335.4 389.8 397.8 407.7 410.3 5254.6 6434.8 6485.9 6527.8 6562.9 4950.5 4963.0 5155.1 5711.0 6452.3 4539.7 4638.7 4678.4 4713.7 4715.9 4691.8 4703.5 4709.0 4712.3 4731.6 4439.8 4660.0 4676.1 4705.4 4794.0 4436.5 4578.1 4608.4 4640.7 4699.4 3437.1 3464.6 3785.9 4001.2 4007.4 5547.5 5612.0 6273.8 6297.8 6305.6 4968.6 5270.8 5500.4 5520.3 5525.5 5341.4 6739.8 6835.0 6843.5 6879.1 4320.5 4522.1 4525.8 4544.4 4560.7 6981.0 7212.5 7263.7 7327.6 7425.8 4490.8 4599.7 4620.2 4622.5 4623.3 4695.7 4699.9 4700.3 4702.6 4706.3 5322.5 5443.7 5486.0 5513.6 5524.8 5450.9 5961.1 6095.9 6831.2 6836.0 1529.7 1582.4 1598.4 1605.8 1606.0 828.0 962.5 967.5 1045.6 1065.2 6536.3 6573.5 6577.6 6602.0 6617.5 6119.8 7102.6 7126.2 7163.0 7166.4 5538.5 6582.2 6694.0 6695.6 6699.6 3397.8 3654.9 3767.3 3779.3 3793.0 5711.8 7441.6 7443.2 7456.7 7465.9 5314.4 6778.5 6834.0 7418.7 7420.9 6297.4 6945.2 6963.6 7121.6 7480.5 6526.5 7222.2 7226.5 7240.5 7245.6 6505.0 6586.8 6758.9 6874.2 7090.6 6749.6 7045.5 7283.6 7422.5 7488.7 4015.8 5002.5 5068.2 5090.7 5106.8 5877.5 6499.6 7120.3 7182.9 7197.6 6892.6 6908.6 6914.8 6917.9 6938.3 5633.7 6667.4 6838.3 6924.7 6931.2 5543.2 5559.4 6461.7 6520.4 6759.2 6518.5 6564.9 6623.9 6659.0 6960.7 6380.7 6731.0 6732.5 6733.5 6777.8 18:21:19 INFO - PID 1637 | 18:21:19 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:20781.48 Median:17004.49 stddev:30708.48 (180.6%) stddev-sans-first:30757.24 18:21:19 INFO - PID 1637 | Values: 599.6 723.2 727.1 733.6 740.8 13831.2 15869.2 16509.0 16518.4 17054.4 15064.8 15220.7 15233.0 15279.3 15281.2 933.9 941.7 961.5 989.6 992.0 1010.4 1011.1 1011.7 1014.5 1014.6 1045.6 1065.3 1065.8 1066.8 1066.9 1044.5 1062.5 1062.6 1065.7 1066.7 787.3 788.0 789.1 789.3 792.2 13470.4 16324.9 16697.7 16954.6 17140.5 14615.9 14693.7 14774.1 14889.3 14947.2 10336.2 11724.5 12034.3 12102.1 12108.3 2732.7 3070.7 3147.0 3165.7 3173.2 110636.4 116335.0 117679.0 117993.0 118358.0 8903.8 11017.1 11324.0 11472.3 11792.9 24639.4 24972.6 25125.9 25244.9 25305.9 7808.7 7890.1 8116.8 8144.9 8204.9 4174.6 4175.6 4229.8 4270.7 4276.6 3793.8 3820.2 4165.7 4222.1 4252.3 852.9 857.9 858.5 862.8 882.6 476.2 554.0 565.7 594.1 603.1 8808.3 9286.4 10271.8 10315.7 10419.5 484.7 546.8 586.4 587.9 593.6 4655.6 4736.5 4793.5 4794.5 4795.8 106142.0 107337.4 108197.5 109653.0 113386.4 97468.5 97528.0 98341.0 99324.0 99856.5 66757.0 78293.8 84715.6 84943.3 87119.0 9784.2 10126.1 10928.2 11037.9 11240.4 39040.0 39671.6 39995.6 40481.4 40619.0 50841.7 51811.0 52316.5 52550.0 52950.7 13821.4 13890.2 14577.2 14647.0 14771.5 14727.9 14764.2 14788.7 14800.6 15245.8 13592.7 13715.6 13721.2 13760.0 14214.9 11150.8 13905.4 14227.8 14248.0 14278.7 13488.3 13573.4 13574.5 14114.2 14311.3 10094.7 10292.6 10456.6 10649.3 10723.4 7313.8 8889.3 9232.6 10543.8 11041.0 18:21:19 INFO - PID 1637 | -------- Summary: end -------- 18:21:19 INFO - PID 1637 | 18:21:19 INFO - TEST-INFO | 1637: exit 0 18:21:19 INFO - TEST-OK | dromaeo_css | took 1117732ms 18:21:19 INFO - TEST-START | kraken 18:21:19 INFO - operating with platform_type : mac_ 18:21:19 INFO - Initialising browser for kraken test... 18:21:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpExc9rg/profile http://localhost:49252/getInfo.html) 18:21:22 INFO - PID 1648 | 2017-03-31 18:21:22.686 plugin-container[1649:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 18:21:22 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:22 INFO - PID 1648 | 2017-03-31 18:21:22.689 plugin-container[1649:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 18:21:22 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:22 INFO - PID 1648 | 2017-03-31 18:21:22.689 plugin-container[1649:10228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:22 INFO - PID 1648 | __metrics Screen width/height:1600/1200 18:21:22 INFO - PID 1648 | colorDepth:24 18:21:22 INFO - PID 1648 | Browser inner width/height: 1024/689 18:21:22 INFO - PID 1648 | __metrics 18:21:22 ERROR - PID 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:21:23 INFO - TEST-INFO | 1648: exit 0 18:21:23 INFO - Browser initialized. 18:21:23 INFO - Running cycle 1/1 for kraken test... 18:21:23 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpExc9rg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:26 INFO - PID 1650 | 2017-03-31 18:21:26.024 plugin-container[1651:10354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 18:21:26 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:26 INFO - PID 1650 | 2017-03-31 18:21:26.025 plugin-container[1651:10354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a53, name = 'com.apple.CFPasteboardClient' 18:21:26 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:26 INFO - PID 1650 | 2017-03-31 18:21:26.025 plugin-container[1651:10354] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:35 INFO - PID 1650 | RSS: Main: 228937728 18:21:35 INFO - PID 1650 | 18:21:38 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:39 INFO - PID 1650 | RSS: Main: 243904512 18:21:39 INFO - PID 1650 | 18:21:42 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 18:21:43 INFO - PID 1650 | RSS: Main: 252379136 18:21:43 INFO - PID 1650 | 18:21:47 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 18:21:47 INFO - PID 1650 | RSS: Main: 254701568 18:21:47 INFO - PID 1650 | 18:21:49 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 18:21:50 INFO - PID 1650 | RSS: Main: 256667648 18:21:50 INFO - PID 1650 | 18:21:52 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:53 INFO - PID 1650 | RSS: Main: 257044480 18:21:53 INFO - PID 1650 | 18:21:56 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:57 INFO - PID 1650 | RSS: Main: 262615040 18:21:57 INFO - PID 1650 | 18:22:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 18:22:01 INFO - PID 1650 | RSS: Main: 260714496 18:22:01 INFO - PID 1650 | 18:22:04 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 18:22:05 INFO - PID 1650 | RSS: Main: 260472832 18:22:05 INFO - PID 1650 | 18:22:07 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:07 INFO - PID 1650 | RSS: Main: 261775360 18:22:07 INFO - PID 1650 | 18:22:09 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:10 INFO - PID 1650 | RSS: Main: 262103040 18:22:10 INFO - PID 1650 | 18:22:12 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:13 INFO - PID 1650 | RSS: Main: 262254592 18:22:13 INFO - PID 1650 | 18:22:16 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:17 INFO - PID 1650 | RSS: Main: 263057408 18:22:17 INFO - PID 1650 | 18:22:20 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:22:21 INFO - PID 1650 | RSS: Main: 260743168 18:22:21 INFO - PID 1650 | 18:22:22 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 18:22:23 INFO - PID 1650 | RSS: Main: 264040448 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | __start_tp_report 18:22:23 INFO - PID 1650 | _x_x_mozilla_page_load 18:22:23 INFO - PID 1650 | _x_x_mozilla_page_load_details 18:22:23 INFO - PID 1650 | |i|pagename|runs| 18:22:23 INFO - PID 1650 | |0;ai-astar;109;125;154;119;185;101;184;183;166;120 18:22:23 INFO - PID 1650 | |1;audio-beat-detection;157;119;117;181;95;159;158;153;143;153 18:22:23 INFO - PID 1650 | |2;audio-dft;229;244;321;261;247;297;300;218;206;249 18:22:23 INFO - PID 1650 | |3;audio-fft;85;62;73;88;62;60;60;63;67;67 18:22:23 INFO - PID 1650 | |4;audio-oscillator;87;71;82;75;79;109;125;71;72;108 18:22:23 INFO - PID 1650 | |5;imaging-gaussian-blur;91;84;80;141;80;84;144;144;114;90 18:22:23 INFO - PID 1650 | |6;imaging-darkroom;137;161;121;101;101;102;111;163;99;99 18:22:23 INFO - PID 1650 | |7;imaging-desaturate;103;102;141;137;159;157;146;102;103;133 18:22:23 INFO - PID 1650 | |8;json-parse-financial;54;50;52;51;53;56;56;55;84;58 18:22:23 INFO - PID 1650 | |9;json-stringify-tinderbox;56;61;74;77;67;66;59;59;56;59 18:22:23 INFO - PID 1650 | |10;stanford-crypto-aes;106;90;87;92;116;117;113;87;90;89 18:22:23 INFO - PID 1650 | |11;stanford-crypto-ccm;148;145;163;161;156;164;176;180;219;178 18:22:23 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;198;201;222;275;204;198;195;204;223;198 18:22:23 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;70;72;71;70;70;71;72;72;70;70 18:22:23 INFO - PID 1650 | __end_tp_report 18:22:23 INFO - PID 1650 | __start_cc_report 18:22:23 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1027 18:22:23 INFO - PID 1650 | __end_cc_report 18:22:23 INFO - PID 1650 | __startTimestamp1491009743072__endTimestamp 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | ------- Summary: start ------- 18:22:23 INFO - PID 1650 | Number of tests: 14 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:144.60 Median:160.00 stddev:33.36 (20.9%) stddev-sans-first:32.81 18:22:23 INFO - PID 1650 | Values: 109.0 125.0 154.0 119.0 185.0 101.0 184.0 183.0 166.0 120.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:157.50 stddev:25.55 (16.2%) stddev-sans-first:26.63 18:22:23 INFO - PID 1650 | Values: 157.0 119.0 117.0 181.0 95.0 159.0 158.0 153.0 143.0 153.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:257.20 Median:255.00 stddev:37.71 (14.8%) stddev-sans-first:38.59 18:22:23 INFO - PID 1650 | Values: 229.0 244.0 321.0 261.0 247.0 297.0 300.0 218.0 206.0 249.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:68.70 Median:67.00 stddev:10.20 (15.2%) stddev-sans-first:8.95 18:22:23 INFO - PID 1650 | Values: 85.0 62.0 73.0 88.0 62.0 60.0 60.0 63.0 67.0 67.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:73.50 stddev:19.24 (26.2%) stddev-sans-first:20.40 18:22:23 INFO - PID 1650 | Values: 87.0 71.0 82.0 75.0 79.0 109.0 125.0 71.0 72.0 108.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:82.00 stddev:27.83 (33.9%) stddev-sans-first:29.04 18:22:23 INFO - PID 1650 | Values: 91.0 84.0 80.0 141.0 80.0 84.0 144.0 144.0 114.0 90.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:119.50 Median:149.00 stddev:25.44 (17.1%) stddev-sans-first:26.19 18:22:23 INFO - PID 1650 | Values: 137.0 161.0 121.0 101.0 101.0 102.0 111.0 163.0 99.0 99.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:128.30 Median:139.00 stddev:23.58 (17.0%) stddev-sans-first:23.16 18:22:23 INFO - PID 1650 | Values: 103.0 102.0 141.0 137.0 159.0 157.0 146.0 102.0 103.0 133.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:55.50 stddev:9.84 (17.7%) stddev-sans-first:10.38 18:22:23 INFO - PID 1650 | Values: 54.0 50.0 52.0 51.0 53.0 56.0 56.0 55.0 84.0 58.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:63.50 stddev:7.38 (11.6%) stddev-sans-first:7.33 18:22:23 INFO - PID 1650 | Values: 56.0 61.0 74.0 77.0 67.0 66.0 59.0 59.0 56.0 59.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:98.70 Median:88.00 stddev:12.72 (14.5%) stddev-sans-first:13.21 18:22:23 INFO - PID 1650 | Values: 106.0 90.0 87.0 92.0 116.0 117.0 113.0 87.0 90.0 89.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:169.00 Median:170.00 stddev:21.24 (12.5%) stddev-sans-first:21.13 18:22:23 INFO - PID 1650 | Values: 148.0 145.0 163.0 161.0 156.0 164.0 176.0 180.0 219.0 178.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:211.80 Median:204.00 stddev:24.30 (11.9%) stddev-sans-first:25.26 18:22:23 INFO - PID 1650 | Values: 198.0 201.0 222.0 275.0 204.0 198.0 195.0 204.0 223.0 198.0 18:22:23 INFO - PID 1650 | 18:22:23 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:71.00 stddev:0.92 (1.3%) stddev-sans-first:0.93 18:22:23 INFO - PID 1650 | Values: 70.0 72.0 71.0 70.0 70.0 71.0 72.0 72.0 70.0 70.0 18:22:23 INFO - PID 1650 | -------- Summary: end -------- 18:22:23 INFO - PID 1650 | 18:22:23 INFO - TEST-INFO | 1650: exit 0 18:22:23 INFO - TEST-OK | kraken | took 64006ms 18:22:23 INFO - SUITE-END | took 1181s 18:22:24 INFO - Completed test suite (00:19:42) 18:22:24 INFO - javascript benchmark 18:22:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7749.100464119115, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.2768954674725, 3491.982540087299, 3577.373959557078, 3633.418653015518, 3663.523741913551, 6133.30406059456, 6241.531885108618, 6241.625502469844, 6283.465905398041, 6303.905441418371, 6187.628742275456, 6196.101565273033, 6216.937830621704, 6298.118263443122, 6309.400606942345, 5327.680339179642, 5531.225628412026, 5568.136938774496, 5675.120356344773, 5719.084946408576, 5494.230807686927, 5589.105743081108, 5719.370869204384, 5725.57058220633, 5731.226284451587, 5416.1063424534905, 5433.891322173574, 5604.075327570946, 5621.409751976024, 5678.063119585264, 5435.565154787647, 5469.179623056573, 5481.068218402841, 5573.8606534836545, 5584.190292407578, 5386.8687575609165, 5391.191321301797, 5438.809641662523, 5475.917861232085, 5483.177523371526, 5259.710715910624, 5499.5976026113485, 6191.318954914956, 6254.4989202591505, 6287.591306565105, 6434.195725534309, 6546.476281897484, 6680.632565208912, 6690.397864192246, 6741.696623651947, 5231.398389185254, 5650.660960342392, 6794.830129246759, 6823.0, 6886.449084073262, 6233.781817636452, 6348.968255158741, 6365.745370185234, 6367.42693157618, 6386.201724784449, 5058.165402708585, 6426.967865160737, 7027.754028608974, 7034.718611255493, 7071.469639777038, 6374.199478474566, 6535.8692826143215, 6600.933990660128, 6610.07458955737, 6635.369639884203, 6732.124823772878, 6772.55978361405, 6781.491388145909, 6784.321567843275, 6807.693653785592, 6630.403263706289, 6648.767293144813, 6661.633610151488, 6677.499187560952, 6710.261871194165, 6468.54720169593, 6561.376669216513, 6594.670266486753, 6680.565763225472, 6753.594784312957, 5431.8098866539485, 5441.646292990943, 5444.183372494158, 5472.91790623141, 5498.065328894098, 4775.403074615673, 4802.927956080662, 4812.25410061441, 4823.27650852368, 4836.903261934742, 6236.532260080512, 6306.0, 6341.778037768749, 6442.194725659293, 6496.6426846522945, 6829.52193346461, 6878.8624227515165, 6893.034975103489, 6918.5502942309595, 6931.618760968196, 6472.967635161794, 6487.351264873475, 6492.805215843532, 6502.414782669583, 6523.086767852505, 5793.217915581343, 5908.438698323653, 6037.973544497446, 6065.878682426415, 6069.2716873975405, 6873.690683919235, 6939.271376505394, 6961.825954351182, 7062.646867656699, 7071.36357727807, 6573.0, 6883.174019117538, 6886.552374095668, 6892.035115083992, 6894.069300644349, 6801.18385793708, 6860.485463590151, 6942.548734332252, 7047.365737083687, 7065.399441047569, 6098.847528811815, 6393.976963685788, 6718.260991291052, 6726.6972986216715, 6756.898646520208, 5800.39095894925, 6175.413335733098, 6555.115059467102, 6577.112089867806, 6742.393184613408, 6574.408303252731, 6604.603723776589, 6635.63504007265, 6665.400113989765, 6686.06395104676, 6664.133662623828, 6666.133402657623, 6666.166729158856, 6666.466682665472, 6674.16572928384, 6209.316975132641, 6361.745530178741, 6452.903206451813, 6525.64108973992, 6544.443722283469, 6537.771178008747, 6626.90059649096, 6633.170853643294, 6648.235452922976, 6653.035309879943, 6470.838229044311, 6473.482121430368, 6487.0, 6487.3836985486305, 6492.058651495412], "value": 6149.2114730634385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.8345963887946, 829.3531045784289, 1126.9154813388993, 1155.1625071822925, 1234.555559998401, 37098.588591741514, 37970.860874173784, 38080.61919380805, 38112.618873811254, 38122.8093859531, 37406.62593374065, 37811.0, 37859.81070094646, 37862.43206351907, 37940.24119517608, 5488.725563721818, 6217.129601855744, 7593.89888466172, 7871.0, 8042.517448953082, 8398.790030249262, 8535.615897284637, 8623.180797824229, 8638.049814520398, 8647.956760216222, 8635.95682021589, 8700.738977830644, 8702.695405660772, 8712.215900568948, 8728.43265187764, 8619.267362274215, 8675.219230269275, 8677.609507572111, 8678.392512524126, 8706.259967902735, 8542.74371768848, 8547.829043419159, 8613.870791938127, 8620.612072456692, 8630.568471576458, 37418.43872341917, 37745.62254377449, 37751.81124094389, 37777.0, 37805.432918506245, 3053.806824110455, 3072.8156310621434, 3094.2883136878418, 3094.3811237752316, 3116.626004879406, 27482.725172748418, 27505.449891002267, 27672.58491122635, 27817.0, 28546.714532854614, 2741.5476446386524, 2828.7595554377904, 2829.8443585602786, 2851.5009873272093, 2932.017774045697, 276639.0, 323143.0, 325792.0, 326187.0, 327310.0, 40124.59875401267, 40918.59081409207, 48840.511594883596, 49953.25163619329, 50058.99882002339, 40620.0, 51072.48927510752, 51684.22473662898, 51772.70568235824, 51928.22107668388, 11758.176927615155, 14175.85824141766, 14277.929155313393, 14309.9983001191, 14314.785278220836, 11448.313101213955, 11527.365994870363, 11645.475953582109, 11662.94168529152, 11664.825027624593, 10040.347377420445, 12293.262404255773, 12342.938285308515, 12351.505939762488, 12370.938145309396, 4900.852974410774, 5211.348581427322, 5404.567634589223, 5493.890122197534, 5568.331800184003, 921.5438358012692, 1008.4453550547316, 1009.7980403919098, 1015.8628585141059, 1051.2010871737382, 21960.560788784136, 23330.0, 23833.0, 23901.282961511184, 24642.687529682233, 900.9864852027225, 974.7904200596905, 1117.2905205194759, 1137.556353022322, 1151.8214676725122, 11440.885591144148, 12133.09001824849, 12234.0, 12249.755004900031, 12280.0, 440484.0, 441367.0, 441817.0, 442454.0, 442661.0, 52611.47388526066, 53064.0, 53164.20253696197, 53187.734061329444, 53250.73374633102, 1498.7826765119214, 1509.1171664576127, 1537.3005282596391, 1546.180524322111, 1561.773542836305, 22146.44633884166, 22472.438189045406, 23414.531709365376, 23503.764962350157, 23558.0576776927, 33552.32895342031, 34337.0, 37724.43413348747, 37734.0, 37773.05567360838, 433842.0, 436190.0, 436253.0, 436453.0, 437621.0, 32581.837090814395, 32694.509582355808, 32851.67148328486, 32873.83563082169, 32920.67079329176, 41324.793376032925, 41463.1707365845, 42394.0, 42505.5749442514, 42632.78683606562, 29042.564361534172, 29350.0, 29789.0, 29883.0, 29925.401491970475, 28918.855405722836, 29576.704232957396, 29587.408251835273, 29775.55336669995, 29920.0, 35282.29435411226, 35651.0, 35921.0, 36003.0, 36048.639513604525, 28850.42299153963, 29167.416651666423, 30021.399572008002, 30121.397572048874, 30161.547576785928, 22563.887180563994, 23629.0, 23836.284911452683, 23863.880680596485, 24115.03539858386], "value": 17392.65914392713, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.7250800725526, 426.12218829211827, 432.48318259679684, 448.3857115751421, 450.1761775950011, 19781.40655780327, 21442.142314307428, 21983.23058692946, 22134.003969821355, 22249.332520024425, 18054.368097116603, 18497.907510462428, 22713.205037823678, 23000.07999680011, 23269.76730232697, 5269.841904742844, 6088.0, 6366.108744775712, 6377.170967774183, 6381.393767592094, 5060.645754797166, 5952.0, 6164.84587885302, 6235.064740288947, 6261.592996455239, 5114.514121158484, 6191.628502289877, 6201.782937597185, 6212.471939885115, 6218.844528886769, 5882.735276912549, 6066.878662426749, 6090.512758979271, 6103.481204097657, 6124.601900876429, 5142.948570514284, 5344.893102137936, 5457.5633949283965, 5482.835514934558, 5500.339959204921, 26873.86563067172, 27554.0, 28079.71920280812, 28770.280742981384, 29182.854085729647, 1978.2779285560666, 1992.2529051605648, 2010.9095090720807, 2040.398082565632, 2084.249670118756, 15763.842361576351, 16422.096784676836, 16524.00855948635, 17322.133893305283, 17571.56071098233, 1888.2541396148445, 1911.6427336591037, 1914.473519782057, 1928.7106933959878, 1952.1995981647456, 110208.44895775469, 117665.0, 155413.44586554103, 156559.2172039144, 156950.0, 12355.75288494225, 12805.871941280468, 12822.666106812296, 12848.807267890988, 12896.613101606967, 16442.0, 16516.917415412845, 16548.0, 16573.58566035859, 16579.834201657828, 7839.510493006311, 8936.0, 9184.540772961325, 9306.953465232766, 9337.972822989466, 8676.175763302475, 8879.866801997976, 8912.955435222782, 8919.152680495343, 9097.681581144761, 7876.652422720321, 7926.564038977912, 8150.83698326042, 8157.428979971463, 8166.510009399336, 2062.7156938202884, 2168.4687251623454, 2176.053416763713, 2177.618916689573, 2178.0960901225812, 852.5776064231483, 866.276658989738, 925.7130289610108, 942.3827393057559, 971.3103696375653, 17719.0, 19674.50813729668, 24487.265382038568, 26224.0, 26514.602280965802, 837.844998675247, 963.629002833914, 966.6036924860773, 994.3238597753452, 1007.5717819926502, 8898.332625053146, 10749.677509674566, 10777.83833242502, 10811.135109191246, 10853.077488413574, 601351.0, 687185.0, 690661.0, 746287.0, 747760.0, 162497.0, 165959.0, 167833.0, 169360.0, 169894.0, 1052.4369462337766, 1164.1967042740482, 1243.5585367194515, 1247.0, 1249.2379648414642, 15221.0, 20708.275210367567, 21933.903304835014, 21937.780622193575, 22121.33635990923, 65297.673511632136, 65495.67252163708, 65777.34222657712, 65802.68394632176, 66586.0, 86637.13362866543, 87777.56111219659, 88154.55922720346, 88172.11827881896, 90920.0, 7961.283484486424, 8477.813106165266, 8488.363372746946, 8669.14223170683, 8748.387612867038, 12920.25576547154, 13238.735225295248, 13586.0, 14016.29918504091, 14293.213873237071, 10227.846582301123, 11753.706157346134, 11777.411129443664, 11784.292942423483, 11923.761524769283, 9951.054649808257, 10958.232923695265, 10970.067544258827, 11241.60654377093, 11279.887201127884, 10844.945775271073, 10943.398113103856, 11231.831522527333, 11239.66281011571, 11265.831012534654, 7789.325295061589, 7973.880391794244, 8020.839583208186, 8089.595520224083, 8097.87853182191, 11534.59628912984, 11642.582724005453, 11669.132938729763, 11809.70475738079, 11869.0], "value": 10472.632860197426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.3263368315843, 1391.7077413743114, 1400.6078298076536, 1409.2742237747555, 1423.9074460160086, 10517.263791534595, 10859.51132199052, 10961.506732197051, 10978.451077446136, 11164.497597608106, 8269.669213231478, 10672.466376681154, 10769.676909692702, 10862.402567858764, 10926.071283940855, 2081.375587323804, 2417.867017314047, 2438.5732496813075, 2474.6659201007815, 2477.2692055843568, 2091.7489901211875, 2279.840411171219, 2442.792362649177, 2445.3764290106087, 2456.729759726438, 2360.173939121311, 2424.0546186987003, 2434.050720219116, 2436.135172013936, 2451.705795304557, 2364.7517010713973, 2421.309926670899, 2427.174760581402, 2437.890294936732, 2440.3045132137345, 2052.0765655455107, 2126.415235810149, 2289.6794448777187, 2346.0, 2440.5362981033545, 10915.727106822314, 10986.890131098666, 10989.175811814066, 10997.835032474519, 11002.119830413627, 6095.664738439384, 6332.620042797447, 6340.651264180468, 6346.746130154788, 6347.555671103025, 3952.3083460394605, 4049.4330793688905, 4092.1406504634065, 4123.814428350702, 4146.460960075201, 999.1906555689842, 1136.7662543102842, 1143.2568830260313, 1164.6389619218069, 1167.947442365087, 15105.0, 15119.092854428769, 15216.315265813066, 15217.6195595111, 15228.46700365482, 4356.76037817923, 4363.97920225525, 4776.737279449594, 5418.214358917935, 5448.809291674773, 11323.0, 11329.886701133048, 11365.147613928988, 11369.943150284196, 11376.976072153546, 6212.099245609361, 6237.4698150657705, 6240.906386404207, 6242.219722534684, 6246.812595622138, 5540.667559946335, 5864.736086876101, 5870.090136028923, 5907.0, 5955.630205052862, 3633.8190088221436, 4168.958310416857, 4295.591918767712, 4320.848770293024, 4350.042990542076, 1267.5690265309838, 1268.9365531723377, 1273.5542560104075, 1286.9549565765153, 1288.0532808385829, 755.6070843161525, 755.7241606813474, 776.9961150194326, 793.5833687314206, 794.1304271822345, 5956.6723830188885, 5972.970135149297, 6065.211522502046, 6092.969535152384, 6126.693665316806, 581.5347721822525, 585.8330375842864, 822.4243029879109, 914.401067300919, 936.4708939449189, 3975.3241948868763, 4160.895977600524, 4203.138356636943, 4207.957920420818, 4267.509236437787, 13423.530176443974, 13964.930175349058, 14002.159870407808, 14006.929965350108, 14011.159330440207, 13330.400131994083, 13831.170129792245, 13877.722445551033, 13911.999999999798, 14002.229877356844, 9972.252081093948, 10222.744431389276, 10225.94887025575, 10238.027387398186, 10243.692689219186, 6473.514486413444, 6705.832354191184, 6792.592444453348, 6865.794026179223, 6935.861282774215, 12132.393380331125, 12192.390380481118, 12205.511779529075, 12263.93868030654, 12284.262944223376, 12463.189892656948, 12577.93711031439, 12673.619791406272, 12752.42614082387, 12819.487220511075, 8936.731898043068, 9073.637054518009, 9186.908130918873, 9318.627254909728, 9330.206932410549, 10046.447445390282, 10353.689389318333, 10437.425941573287, 10452.059314661412, 10580.15358771281, 7167.928320716726, 8739.694110706096, 8767.430117042626, 8836.911630883866, 9032.1935612879, 6987.0, 7605.429592780453, 8542.615582298688, 8631.956840215758, 8789.252913502423, 6841.657917104224, 7425.740099096506, 7817.0, 7984.760457186294, 7991.440599158003, 5817.825465236049, 5910.645361278337, 5978.491828194478, 6067.666278354733, 6086.60437071589, 5183.948160518346, 5367.329083864517, 5405.432429594836, 5985.521158307412, 6146.293176284785], "value": 5102.16661943857, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.41804968379864, 389.8206824860564, 397.8170041780781, 407.6983032555906, 410.29838975352146, 5254.632175747699, 6434.806955791334, 6485.870282594351, 6527.836804079894, 6562.934370656292, 4950.455449900508, 4962.975185124069, 5155.072087024334, 5710.972025035492, 6452.29024807272, 4539.727616343013, 4638.698484598507, 4678.415198100238, 4713.740744259065, 4715.85852424427, 4691.812327506896, 4703.459102203256, 4709.0, 4712.316714076457, 4731.57415832575, 4439.800208990603, 4659.974805542775, 4676.088162808253, 4705.3647757552635, 4794.041191761644, 4436.534163912791, 4578.084383123389, 4608.377868987711, 4640.698354606973, 4699.365585645945, 3437.123533498967, 3464.6188919218866, 3785.8831644664615, 4001.159756451149, 4007.3988901664693, 5547.532792004997, 5611.971940140314, 6273.780417685405, 6297.779577714804, 6305.59013664115, 4968.627352948507, 5270.841874743725, 5500.3949565547755, 5520.282363292747, 5525.530329921961, 5341.359036915595, 6739.820531406984, 6834.965825170842, 6843.452523798134, 6879.140107486564, 4320.524742278347, 4522.140793249305, 4525.751083690396, 4544.3637890695, 4560.703554268962, 6981.0, 7212.459065570103, 7263.673134708951, 7327.633618319062, 7425.777226683208, 4490.753008584559, 4599.655025873073, 4620.168369693421, 4622.480747992352, 4623.306504024356, 4695.671303008825, 4699.859004229878, 4700.271457924027, 4702.600278976325, 4706.294055891575, 5322.467753224647, 5443.673379597158, 5486.0, 5513.61404701667, 5524.779008839681, 5450.91823622646, 5961.122246492428, 6095.939040609625, 6831.248562658104, 6836.008778727057, 1529.6864142850688, 1582.4461438496435, 1598.4165779490634, 1605.823359430485, 1606.020327600163, 828.0, 962.4754508792724, 967.4969016111583, 1045.6497073480468, 1065.2436769893307, 6536.333235025038, 6573.474122070223, 6577.6053436793945, 6601.996091778229, 6617.4706023518, 6119.84700382494, 7102.5665365995055, 7126.2161162272105, 7162.964185179041, 7166.426685865118, 5538.529225015838, 6582.210134783761, 6693.950160153135, 6695.564788288746, 6699.598024118569, 3397.8301084945656, 3654.8720794772057, 3767.2653832502397, 3779.263043706506, 3793.013816407699, 5711.800086997018, 7441.55350678961, 7443.181250062489, 7456.7390141344795, 7465.85068298631, 5314.405678296573, 6778.457723382117, 6833.965830170917, 7418.740344088039, 7420.885047761362, 6297.4017468340435, 6945.166580010431, 6963.582185068912, 7121.608311542811, 7480.531424629859, 6526.540933305356, 7222.241664625139, 7226.458015648953, 7240.493165478366, 7245.5652660840515, 6505.0, 6586.814373412832, 6758.898616520658, 6874.175098987954, 7090.574565526085, 6749.628770417674, 7045.471589630695, 7283.635818209174, 7422.47839192975, 7488.663010164447, 4015.79526142162, 5002.474740152232, 5068.163752980717, 5090.694558326512, 5106.846794596168, 5877.471027607536, 6499.642519661464, 7120.323569260912, 7182.856342873218, 7197.632449834515, 6892.551984121016, 6908.585484870723, 6914.792556223321, 6917.896231556631, 6938.27148149437, 5633.690147041952, 6667.43326817226, 6838.290427393152, 6924.653767311514, 6931.237563868071, 5543.1984605004345, 5559.360673522597, 6461.67691615408, 6520.445762110084, 6759.208809665344, 6518.5111116667385, 6564.934350656432, 6623.900641490478, 6658.9667051666365, 6960.7215711373, 6380.680965951777, 6731.028693070915, 6732.528722989344, 6733.461323094239, 6777.796666100025], "value": 4937.528462654179, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.616245602814, 723.1936390924119, 727.1274470635236, 733.6001878975958, 740.8110931712414, 13831.239281839507, 15869.206539673014, 16509.0, 16518.42185523507, 17054.403095891645, 15064.849351506482, 15220.695586088299, 15233.0, 15279.312430940607, 15281.159536225503, 933.9159475647191, 941.6751220828803, 961.4856052012175, 989.579428742785, 991.9553620087078, 1010.3634710132607, 1011.0546638892622, 1011.6762635956497, 1014.5282443663694, 1014.5586669798661, 1045.6131231444376, 1065.2649671726522, 1065.8435597376856, 1066.807974564578, 1066.855974443452, 1044.4568824211406, 1062.5112448273803, 1062.5909025025383, 1065.733566608348, 1066.7279843639822, 787.3425689549233, 787.9566623835686, 789.1122487201898, 789.3054112381068, 792.1959211400433, 13470.39383227757, 16324.918375408166, 16697.749533757004, 16954.576135596584, 17140.485785426456, 14615.853841461661, 14693.706125877423, 14774.131812926149, 14889.255537223096, 14947.177905215209, 10336.173106151566, 11724.472398742077, 12034.33811140387, 12102.092343074304, 12108.273503589724, 2732.7130651281727, 3070.6949546442675, 3146.9527957080663, 3165.699258570432, 3173.222560472699, 110636.4468177662, 116335.0, 117679.0, 117993.0, 118358.0, 8903.821923561622, 11017.11863050954, 11323.980841724284, 11472.254303470248, 11792.88207117935, 24639.38401539976, 24972.625410618493, 25125.87437062803, 25244.873775631004, 25305.87347063253, 7808.726694565663, 7890.132085470594, 8116.837663246702, 8144.918550814416, 8204.917950820416, 4174.624283814472, 4175.64507016907, 4229.788510574521, 4270.743755374688, 4276.636485898671, 3793.8482460701816, 3820.1977584707524, 4165.729227600196, 4222.071144348239, 4252.298370768788, 852.8891244138223, 857.9227869491775, 858.4763294390417, 862.7525296602386, 882.567541904475, 476.23290858506596, 553.9861503462446, 565.7482420322917, 594.1296001358031, 603.1314906534576, 8808.339374546935, 9286.442813431217, 10271.794564108675, 10315.74210644725, 10419.479026048668, 484.706752414791, 546.8250159948858, 586.4428792646917, 587.9265091863517, 593.6171169595586, 4655.557722016403, 4736.478987311393, 4793.544613261734, 4794.472608013116, 4795.808167673324, 106142.0, 107337.38993915098, 108197.45901270444, 109653.0, 113386.43306783414, 97468.51265743627, 97528.0, 98341.0, 99324.0, 99856.50071749595, 66757.0, 78293.82559261502, 84715.57642211749, 84943.28903720473, 87119.0, 9784.217262618831, 10126.101450941293, 10928.235023548275, 11037.944810275898, 11240.437978101227, 39040.0, 39671.60328396795, 39995.60004400035, 40481.39277910775, 40619.0, 50841.745791270805, 51811.0, 52316.47683523116, 52550.0, 52950.676233094484, 13821.378037988114, 13890.166590004632, 14577.198254095701, 14646.9747117705, 14771.482998095014, 14727.852721472647, 14764.187969661773, 14788.704225915206, 14800.555983320517, 15245.771313430085, 13592.728145437233, 13715.588532344045, 13721.176729396268, 13760.0, 14214.857851421353, 11150.832737508781, 13905.443782248596, 14227.831152545203, 14248.0, 14278.714425711636, 13488.258145802076, 13573.38919748594, 13574.524891628746, 14114.223717695626, 14311.284435778378, 10094.747631309276, 10292.639757608447, 10456.634017809645, 10649.307794993301, 10723.410212438079, 7313.780586582411, 8889.333300002654, 9232.630694772133, 10543.841842372523, 11041.0], "value": 7673.115543023731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1725.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 125.0, 154.0, 119.0, 185.0, 101.0, 184.0, 183.0, 166.0, 120.0], "value": 144.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 117.0, 181.0, 95.0, 159.0, 158.0, 153.0, 143.0, 153.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 244.0, 321.0, 261.0, 247.0, 297.0, 300.0, 218.0, 206.0, 249.0], "value": 257.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 73.0, 88.0, 62.0, 60.0, 60.0, 63.0, 67.0, 67.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 82.0, 75.0, 79.0, 109.0, 125.0, 71.0, 72.0, 108.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 80.0, 141.0, 80.0, 84.0, 144.0, 144.0, 114.0, 90.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 161.0, 121.0, 101.0, 101.0, 102.0, 111.0, 163.0, 99.0, 99.0], "value": 119.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 102.0, 141.0, 137.0, 159.0, 157.0, 146.0, 102.0, 103.0, 133.0], "value": 128.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 50.0, 52.0, 51.0, 53.0, 56.0, 56.0, 55.0, 84.0, 58.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 74.0, 77.0, 67.0, 66.0, 59.0, 59.0, 56.0, 59.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 90.0, 87.0, 92.0, 116.0, 117.0, 113.0, 87.0, 90.0, 89.0], "value": 98.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 145.0, 163.0, 161.0, 156.0, 164.0, 176.0, 180.0, 219.0, 178.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 222.0, 275.0, 204.0, 198.0, 195.0, 204.0, 223.0, 198.0], "value": 211.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 71.0, 70.0, 70.0, 71.0, 72.0, 72.0, 70.0, 70.0], "value": 70.8, "unit": "score"}]}]} 18:22:24 INFO - Return code: 0 18:22:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:22:24 ERROR - # TBPL SUCCESS # 18:22:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:24 INFO - 2017-03-31 18:22:24.436 screenresolution[1655:10740] starting screenresolution argv=screenresolution get 18:22:24 INFO - 2017-03-31 18:22:24.439 screenresolution[1655:10740] Display 0: 1600x1200x32@60 18:22:24 INFO - 2017-03-31 18:22:24.445 screenresolution[1656:10743] starting screenresolution argv=screenresolution list 18:22:24 INFO - Available Modes on Display 0 18:22:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:24 INFO - Intel Iris: 18:22:24 INFO - Chipset Model: Intel Iris 18:22:24 INFO - Type: GPU 18:22:24 INFO - Bus: Built-In 18:22:24 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:24 INFO - Vendor: Intel (0x8086) 18:22:24 INFO - Device ID: 0x0a2e 18:22:24 INFO - Revision ID: 0x0009 18:22:24 INFO - Displays: 18:22:24 INFO - Display: 18:22:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:24 INFO - Display Serial Number: 1600x1200 60 18:22:24 INFO - Main Display: Yes 18:22:24 INFO - Mirror: Off 18:22:24 INFO - Online: Yes 18:22:24 INFO - Rotation: Supported 18:22:24 INFO - Return code: 0 18:22:24 INFO - Running post-action listener: _resource_record_post_action 18:22:24 INFO - [mozharness: 2017-04-01 01:22:24.683088Z] Finished run-tests step (success) 18:22:24 INFO - Running post-run listener: _resource_record_post_run 18:22:24 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 40843776; Write bytes: 518335488; Read time: 396; Write time: 1565 18:22:24 INFO - TinderboxPrint: CPU usage
54.2% 18:22:24 INFO - TinderboxPrint: I/O read bytes / time
40,843,776 / 396 18:22:24 INFO - TinderboxPrint: I/O write bytes / time
518,335,488 / 1,565 18:22:24 INFO - TinderboxPrint: CPU idle
2,203.0 (45.8%) 18:22:24 INFO - TinderboxPrint: CPU system
973.5 (20.2%) 18:22:24 INFO - TinderboxPrint: CPU user
1,637.2 (34.0%) 18:22:24 INFO - TinderboxPrint: Swap in / out
251,813,888 / 0 18:22:24 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 207309312; Write bytes: 205514752; Read time: 15704; Write time: 430 18:22:24 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 38861312; Write bytes: 301986816; Read time: 375; Write time: 1101 18:22:25 INFO - Running post-run listener: _upload_blobber_files 18:22:25 INFO - Blob upload gear active. 18:22:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:25 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 18:22:25 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'] 18:22:25 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 18:22:25 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:22:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:26 INFO - (blobuploader) - INFO - Done attempting. 18:22:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:22:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:22:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:27 INFO - (blobuploader) - INFO - Done attempting. 18:22:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:22:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:22:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:28 INFO - (blobuploader) - INFO - Done attempting. 18:22:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:22:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:22:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:31 INFO - (blobuploader) - INFO - Done attempting. 18:22:31 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:31 INFO - Return code: 0 18:22:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6be3ebf09dc6024251948b778f150921810cb19226c106b3b5b813535772b9a06b4e404e432679a43cdbcf6c2e818bbbcedce56cb2e876f66d5fb2c45e939bca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52bad8a45de1d10b2a090871f4c8419fe1855abb8312d1b9b2011730bf57f3328104af2b002cef325464c24601b85ddfd00fb2e1e0c1fdd67455ced98761813e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa41d8a186c1d36019828a1f873ad0fa10a8214ee21679db33561f6833c6f9d868f2ac8d39d6e6cf2a45326bc3aa663cff0402f3fbea3069cbc093f24a2e36d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed3d9d93b1b363a7a17a9327f47bf6b4c2fe638a82f20e7ad345c8d62d22f5b8d67afc1663316af2d61c64e7391dae879486ea72a0e263a632a977c98ac06a53"} 18:22:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:31 INFO - Contents: 18:22:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6be3ebf09dc6024251948b778f150921810cb19226c106b3b5b813535772b9a06b4e404e432679a43cdbcf6c2e818bbbcedce56cb2e876f66d5fb2c45e939bca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52bad8a45de1d10b2a090871f4c8419fe1855abb8312d1b9b2011730bf57f3328104af2b002cef325464c24601b85ddfd00fb2e1e0c1fdd67455ced98761813e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa41d8a186c1d36019828a1f873ad0fa10a8214ee21679db33561f6833c6f9d868f2ac8d39d6e6cf2a45326bc3aa663cff0402f3fbea3069cbc093f24a2e36d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed3d9d93b1b363a7a17a9327f47bf6b4c2fe638a82f20e7ad345c8d62d22f5b8d67afc1663316af2d61c64e7391dae879486ea72a0e263a632a977c98ac06a53"} 18:22:31 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:31 INFO - Copying logs to upload dir... 18:22:31 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.380388 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-31 18:22:31.215171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 18:22:31.218843) ========= 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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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/6be3ebf09dc6024251948b778f150921810cb19226c106b3b5b813535772b9a06b4e404e432679a43cdbcf6c2e818bbbcedce56cb2e876f66d5fb2c45e939bca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52bad8a45de1d10b2a090871f4c8419fe1855abb8312d1b9b2011730bf57f3328104af2b002cef325464c24601b85ddfd00fb2e1e0c1fdd67455ced98761813e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa41d8a186c1d36019828a1f873ad0fa10a8214ee21679db33561f6833c6f9d868f2ac8d39d6e6cf2a45326bc3aa663cff0402f3fbea3069cbc093f24a2e36d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed3d9d93b1b363a7a17a9327f47bf6b4c2fe638a82f20e7ad345c8d62d22f5b8d67afc1663316af2d61c64e7391dae879486ea72a0e263a632a977c98ac06a53"} build_url:https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011100 build_url: 'https://queue.taskcluster.net/v1/task/D5Q_YRfWTVu4rL2_yl4-KA/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/6be3ebf09dc6024251948b778f150921810cb19226c106b3b5b813535772b9a06b4e404e432679a43cdbcf6c2e818bbbcedce56cb2e876f66d5fb2c45e939bca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52bad8a45de1d10b2a090871f4c8419fe1855abb8312d1b9b2011730bf57f3328104af2b002cef325464c24601b85ddfd00fb2e1e0c1fdd67455ced98761813e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa41d8a186c1d36019828a1f873ad0fa10a8214ee21679db33561f6833c6f9d868f2ac8d39d6e6cf2a45326bc3aa663cff0402f3fbea3069cbc093f24a2e36d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed3d9d93b1b363a7a17a9327f47bf6b4c2fe638a82f20e7ad345c8d62d22f5b8d67afc1663316af2d61c64e7391dae879486ea72a0e263a632a977c98ac06a53"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 18:22:31.253737) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 18:22:31.254054) ========= 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.wRrD5Dogs9/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.bXcXRxFFOt/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.005146 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 18:22:31.303300) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 18:22:31.303604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 18:22:31.303946) ========= ========= Total master_lag: 0.18 =========