builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0221 starttime: 1493780527.54 results: success (0) buildid: 20170502175620 builduid: 27770b31f3214526a0c2297f1a8de72e revision: b6126c6706d097aed0fb83ef0f44a836fe607f03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.537948) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.542168) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.543088) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.570741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.571150) ========= 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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-05-02 20:02:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 5.81M=0.002s 2017-05-02 20:02:07 (5.81 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.185027 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.780622) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.780978) ========= 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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.022175 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:07.829972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-02 20:02:07.830358) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b6126c6706d097aed0fb83ef0f44a836fe607f03 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b6126c6706d097aed0fb83ef0f44a836fe607f03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HBLBOSNXgp/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.Pxp98IeUTp/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-05-02 20:02:07,894 truncating revision to first 12 chars 2017-05-02 20:02:07,894 Setting DEBUG logging. 2017-05-02 20:02:07,894 attempt 1/10 2017-05-02 20:02:07,894 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b6126c6706d0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-02 20:02:09,437 unpacking tar archive at: mozilla-beta-b6126c6706d0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.808406 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-02 20:02:09.663260) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:09.663604) ========= script_repo_revision: b6126c6706d097aed0fb83ef0f44a836fe607f03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:09.664057) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:09.664324) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 20:02:09.685050) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-02 20:02:09.685451) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HBLBOSNXgp/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.Pxp98IeUTp/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:02:09 INFO - MultiFileLogger online at 20170502 20:02:09 in /builds/slave/test 20:02:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 20:02:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:02:09 INFO - {'append_to_log': False, 20:02:09 INFO - 'base_work_dir': '/builds/slave/test', 20:02:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:02:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:02:09 INFO - 'branch': 'Mozilla-Beta', 20:02:09 INFO - 'buildbot_json_path': 'buildprops.json', 20:02:09 INFO - 'config_files': ('talos/mac_config.py',), 20:02:09 INFO - 'default_actions': ('clobber', 20:02:09 INFO - 'read-buildbot-config', 20:02:09 INFO - 'download-and-extract', 20:02:09 INFO - 'populate-webroot', 20:02:09 INFO - 'create-virtualenv', 20:02:09 INFO - 'install', 20:02:09 INFO - 'run-tests'), 20:02:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:02:09 INFO - 'download_minidump_stackwalk': True, 20:02:09 INFO - 'download_symbols': 'ondemand', 20:02:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:02:09 INFO - 'tooltool.py': '/tools/tooltool.py', 20:02:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:02:09 INFO - '/tools/misc-python/virtualenv.py')}, 20:02:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:02:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:02:09 INFO - 'gecko_profile': False, 20:02:09 INFO - 'gecko_profile_interval': 0, 20:02:09 INFO - 'installer_path': 'installer.exe', 20:02:09 INFO - 'log_level': 'info', 20:02:09 INFO - 'log_name': 'talos', 20:02:09 INFO - 'log_to_console': True, 20:02:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:02:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:02:09 INFO - 'opt_config_files': (), 20:02:09 INFO - 'pip_index': False, 20:02:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:02:09 INFO - 'cmd': ('bash', 20:02:09 INFO - '-c', 20:02:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:02:09 INFO - 'enabled': True, 20:02:09 INFO - 'halt_on_failure': False, 20:02:09 INFO - 'name': 'check_screen_resolution'},), 20:02:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:02:09 INFO - 'cmd': ('bash', 20:02:09 INFO - '-c', 20:02:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:02:09 INFO - 'enabled': True, 20:02:09 INFO - 'halt_on_failure': False, 20:02:09 INFO - 'name': 'check_screen_resolution'},), 20:02:09 INFO - 'run_cmd_checks_enabled': True, 20:02:09 INFO - 'suite': 'dromaeojs-e10s', 20:02:09 INFO - 'system_bits': '32', 20:02:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:02:09 INFO - 'title': 't-yosemite-r7-0221', 20:02:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:02:09 INFO - 'use_talos_json': True, 20:02:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:02:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:02:09 INFO - 'work_dir': 'build'} 20:02:09 INFO - [mozharness: 2017-05-03 03:02:09.871380Z] Running clobber step. 20:02:09 INFO - Running pre-action listener: _resource_record_pre_action 20:02:09 INFO - Running main action method: clobber 20:02:09 INFO - rmtree: /builds/slave/test/build 20:02:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:02:11 INFO - Running post-action listener: _resource_record_post_action 20:02:11 INFO - [mozharness: 2017-05-03 03:02:11.857815Z] Finished clobber step (success) 20:02:11 INFO - [mozharness: 2017-05-03 03:02:11.857939Z] Running read-buildbot-config step. 20:02:11 INFO - Running pre-action listener: _resource_record_pre_action 20:02:11 INFO - Running main action method: read_buildbot_config 20:02:11 INFO - Using buildbot properties: 20:02:11 INFO - { 20:02:11 INFO - "project": "", 20:02:11 INFO - "product": "firefox", 20:02:11 INFO - "script_repo_revision": "production", 20:02:11 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 20:02:11 INFO - "repository": "", 20:02:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 20:02:11 INFO - "buildid": "20170502175620", 20:02:11 INFO - "pgo_build": "False", 20:02:11 INFO - "basedir": "/builds/slave/test", 20:02:11 INFO - "buildnumber": 18, 20:02:11 INFO - "slavename": "t-yosemite-r7-0221", 20:02:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:02:11 INFO - "platform": "yosemite_r7", 20:02:11 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 20:02:11 INFO - "branch": "mozilla-beta", 20:02:11 INFO - "repo_path": "releases/mozilla-beta", 20:02:11 INFO - "revision": "b6126c6706d097aed0fb83ef0f44a836fe607f03", 20:02:11 INFO - "stage_platform": "macosx64", 20:02:11 INFO - "builduid": "27770b31f3214526a0c2297f1a8de72e", 20:02:11 INFO - "slavebuilddir": "test" 20:02:11 INFO - } 20:02:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 20:02:11 INFO - Running post-action listener: _resource_record_post_action 20:02:11 INFO - [mozharness: 2017-05-03 03:02:11.861295Z] Finished read-buildbot-config step (success) 20:02:11 INFO - [mozharness: 2017-05-03 03:02:11.861429Z] Running download-and-extract step. 20:02:11 INFO - Running pre-action listener: _resource_record_pre_action 20:02:11 INFO - Running main action method: download_and_extract 20:02:11 INFO - mkdir: /builds/slave/test/build/tests 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:02:11 INFO - trying https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 20:02:11 INFO - Downloading https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 20:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 20:02:13 INFO - Downloaded 1287 bytes. 20:02:13 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 20:02:13 INFO - Using the following test package requirements: 20:02:13 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 20:02:13 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 20:02:13 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 20:02:13 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 20:02:13 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 20:02:13 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 20:02:13 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 20:02:13 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 20:02:13 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 20:02:13 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 20:02:13 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:02:13 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 20:02:13 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 20:02:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 20:02:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 20:02:13 INFO - Fetch https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 20:02:14 INFO - Expected file size: 51222352 20:02:14 INFO - Obtained file size: 51222352 20:02:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 20:02:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 20:02:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 20:02:31 INFO - Fetch https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 20:02:32 INFO - Expected file size: 51222352 20:02:32 INFO - Obtained file size: 51222352 20:02:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 20:02:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 20:02:48 INFO - Fetch https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 20:02:49 INFO - Expected file size: 14404449 20:02:49 INFO - Obtained file size: 14404449 20:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:02:50 INFO - trying https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 20:02:50 INFO - Downloading https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 20:02:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 20:02:51 INFO - Downloaded 59541163 bytes. 20:02:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 20:02:51 INFO - mkdir: /builds/slave/test/properties 20:02:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:02:51 INFO - Writing to file /builds/slave/test/properties/build_url 20:02:51 INFO - Contents: 20:02:51 INFO - build_url:https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 20:02:52 INFO - Running post-action listener: _resource_record_post_action 20:02:52 INFO - Running post-action listener: set_extra_try_arguments 20:02:52 INFO - [mozharness: 2017-05-03 03:02:52.558040Z] Finished download-and-extract step (success) 20:02:52 INFO - [mozharness: 2017-05-03 03:02:52.558161Z] Running populate-webroot step. 20:02:52 INFO - Running pre-action listener: _resource_record_pre_action 20:02:52 INFO - Running main action method: populate_webroot 20:02:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:02:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:02:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:02:52 INFO - '--tppagecycles', 20:02:52 INFO - '7'], 20:02:52 INFO - 'tests': ['tsvgm']}}, 20:02:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:02:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:02:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:02:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:02:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:02:52 INFO - 'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:02:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:02:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:02:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:02:52 INFO - 'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['damp', 'tps']}, 20:02:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:02:52 INFO - 'tests': ['damp', 'tps']}, 20:02:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['dromaeo_dom']}, 20:02:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:02:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['basic_compositor_video']}, 20:02:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:02:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['a11yr', 20:02:52 INFO - 'ts_paint', 20:02:52 INFO - 'tpaint', 20:02:52 INFO - 'sessionrestore', 20:02:52 INFO - 'sessionrestore_no_auto_restore', 20:02:52 INFO - 'tabpaint']}, 20:02:52 INFO - 'other-e10s': {'tests': ['a11yr', 20:02:52 INFO - 'ts_paint', 20:02:52 INFO - 'tpaint', 20:02:52 INFO - 'sessionrestore', 20:02:52 INFO - 'sessionrestore_no_auto_restore', 20:02:52 INFO - 'tabpaint']}, 20:02:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:02:52 INFO - 'ts_paint', 20:02:52 INFO - 'tpaint', 20:02:52 INFO - 'sessionrestore', 20:02:52 INFO - 'sessionrestore_no_auto_restore', 20:02:52 INFO - 'tabpaint']}, 20:02:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:02:52 INFO - 'ts_paint', 20:02:52 INFO - 'tpaint', 20:02:52 INFO - 'sessionrestore', 20:02:52 INFO - 'sessionrestore_no_auto_restore', 20:02:52 INFO - 'tabpaint']}, 20:02:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['a11yr', 20:02:52 INFO - 'ts_paint', 20:02:52 INFO - 'tpaint', 20:02:52 INFO - 'sessionrestore', 20:02:52 INFO - 'sessionrestore_no_auto_restore', 20:02:52 INFO - 'tabpaint']}, 20:02:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['a11yr', 20:02:52 INFO - 'ts_paint', 20:02:52 INFO - 'tpaint', 20:02:52 INFO - 'sessionrestore', 20:02:52 INFO - 'sessionrestore_no_auto_restore', 20:02:52 INFO - 'tabpaint']}, 20:02:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['tsvgx', 20:02:52 INFO - 'tsvgr_opacity', 20:02:52 INFO - 'tart', 20:02:52 INFO - 'tscrollx', 20:02:52 INFO - 'cart', 20:02:52 INFO - 'tsvg_static']}, 20:02:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:02:52 INFO - 'tsvgr_opacity', 20:02:52 INFO - 'tart', 20:02:52 INFO - 'tscrollx', 20:02:52 INFO - 'cart', 20:02:52 INFO - 'tsvg_static']}, 20:02:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:02:52 INFO - 'talos_options': ['--disable-e10s'], 20:02:52 INFO - 'tests': ['tp5o']}, 20:02:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:02:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:02:52 INFO - 'talos_options': ['--disable-e10s', 20:02:52 INFO - '--xperf_path', 20:02:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:02:52 INFO - 'tests': ['tp5n']}, 20:02:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:02:52 INFO - 'talos_options': ['--xperf_path', 20:02:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:02:52 INFO - 'tests': ['tp5n']}}, 20:02:52 INFO - 'talos.zip': {'path': '', 20:02:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:02:52 INFO - Running post-action listener: _resource_record_post_action 20:02:52 INFO - [mozharness: 2017-05-03 03:02:52.577974Z] Finished populate-webroot step (success) 20:02:52 INFO - [mozharness: 2017-05-03 03:02:52.578091Z] Running create-virtualenv step. 20:02:52 INFO - Running pre-action listener: _resource_record_pre_action 20:02:52 INFO - Running main action method: create_virtualenv 20:02:52 INFO - Creating virtualenv /builds/slave/test/build/venv 20:02:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:02:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:02:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:02:52 INFO - Using real prefix '/tools/python27' 20:02:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:02:53 INFO - Installing distribute.............................................................................................................................................................................................done. 20:02:56 INFO - Installing pip.................done. 20:02:56 INFO - Return code: 0 20:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:02:56 INFO - Reading from file tmpfile_stdout 20:02:56 INFO - Output received: 20:02:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:02:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:56 INFO - 'HOME': '/Users/cltbld', 20:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:56 INFO - 'LOGNAME': 'cltbld', 20:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:56 INFO - 'MOZ_NO_REMOTE': '1', 20:02:56 INFO - 'NO_EM_RESTART': '1', 20:02:56 INFO - 'PAGER': '/bin/cat', 20:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:56 INFO - 'PWD': '/builds/slave/test', 20:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:56 INFO - 'SHELL': '/bin/bash', 20:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:02:56 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:56 INFO - 'USER': 'cltbld', 20:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:56 INFO - 'XPC_FLAGS': '0x0', 20:02:56 INFO - 'XPC_SERVICE_NAME': '0', 20:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:02:56 INFO - Cleaning up... 20:02:56 INFO - Return code: 0 20:02:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:56 INFO - 'HOME': '/Users/cltbld', 20:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:56 INFO - 'LOGNAME': 'cltbld', 20:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:56 INFO - 'MOZ_NO_REMOTE': '1', 20:02:56 INFO - 'NO_EM_RESTART': '1', 20:02:56 INFO - 'PAGER': '/bin/cat', 20:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:56 INFO - 'PWD': '/builds/slave/test', 20:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:56 INFO - 'SHELL': '/bin/bash', 20:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:02:56 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:56 INFO - 'USER': 'cltbld', 20:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:56 INFO - 'XPC_FLAGS': '0x0', 20:02:56 INFO - 'XPC_SERVICE_NAME': '0', 20:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:57 INFO - Downloading/unpacking psutil>=3.1.1 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:03:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:03:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:03:00 INFO - Installing collected packages: psutil 20:03:00 INFO - Running setup.py install for psutil 20:03:00 INFO - building 'psutil._psutil_osx' extension 20:03:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:03:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:03:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:03:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:03:01 INFO - building 'psutil._psutil_posix' extension 20:03:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:03:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:03:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:03:01 INFO - ^ 20:03:01 INFO - 1 warning generated. 20:03:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:03:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:03:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:03:01 INFO - Successfully installed psutil 20:03:01 INFO - Cleaning up... 20:03:01 INFO - Return code: 0 20:03:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:03:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:01 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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:03:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:01 INFO - 'HOME': '/Users/cltbld', 20:03:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:01 INFO - 'LOGNAME': 'cltbld', 20:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:01 INFO - 'MOZ_NO_REMOTE': '1', 20:03:01 INFO - 'NO_EM_RESTART': '1', 20:03:01 INFO - 'PAGER': '/bin/cat', 20:03:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:01 INFO - 'PWD': '/builds/slave/test', 20:03:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:01 INFO - 'SHELL': '/bin/bash', 20:03:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:01 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:01 INFO - 'USER': 'cltbld', 20:03:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:01 INFO - 'XPC_FLAGS': '0x0', 20:03:01 INFO - 'XPC_SERVICE_NAME': '0', 20:03:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:03: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 20:03: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 20:03: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 20:03: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 20:03: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 20:03: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 20:03:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:03:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:03:04 INFO - Installing collected packages: mozsystemmonitor 20:03:04 INFO - Running setup.py install for mozsystemmonitor 20:03:04 INFO - Successfully installed mozsystemmonitor 20:03:04 INFO - Cleaning up... 20:03:04 INFO - Return code: 0 20:03:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03: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')]} 20:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03: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', '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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:03: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 jsonschema==2.5.1 20:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:05 INFO - 'HOME': '/Users/cltbld', 20:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:05 INFO - 'LOGNAME': 'cltbld', 20:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:05 INFO - 'MOZ_NO_REMOTE': '1', 20:03:05 INFO - 'NO_EM_RESTART': '1', 20:03:05 INFO - 'PAGER': '/bin/cat', 20:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:05 INFO - 'PWD': '/builds/slave/test', 20:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:05 INFO - 'SHELL': '/bin/bash', 20:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:05 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:05 INFO - 'USER': 'cltbld', 20:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:05 INFO - 'XPC_FLAGS': '0x0', 20:03:05 INFO - 'XPC_SERVICE_NAME': '0', 20:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:05 INFO - Downloading/unpacking jsonschema==2.5.1 20:03: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 20:03: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 20:03: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 20:03: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 20:03: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 20:03: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 20:03:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:03:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:03: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 20:03: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 20:03: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 20:03: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 20:03: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 20:03: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 20:03:09 INFO - Downloading functools32-3.2.3-2.tar.gz 20:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:03:09 INFO - warning: no files found matching '*.txt' 20:03:09 INFO - no previously-included directories found matching 'build' 20:03:09 INFO - no previously-included directories found matching 'dist' 20:03:09 INFO - no previously-included directories found matching '.git*' 20:03:09 INFO - Installing collected packages: jsonschema, functools32 20:03:09 INFO - Running setup.py install for functools32 20:03:09 INFO - warning: no files found matching '*.txt' 20:03:09 INFO - no previously-included directories found matching 'build' 20:03:09 INFO - no previously-included directories found matching 'dist' 20:03:09 INFO - no previously-included directories found matching '.git*' 20:03:09 INFO - Successfully installed jsonschema functools32 20:03:09 INFO - Cleaning up... 20:03:09 INFO - Return code: 0 20:03:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:09 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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:03:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:09 INFO - 'HOME': '/Users/cltbld', 20:03:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:09 INFO - 'LOGNAME': 'cltbld', 20:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:09 INFO - 'MOZ_NO_REMOTE': '1', 20:03:09 INFO - 'NO_EM_RESTART': '1', 20:03:09 INFO - 'PAGER': '/bin/cat', 20:03:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:09 INFO - 'PWD': '/builds/slave/test', 20:03:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:09 INFO - 'SHELL': '/bin/bash', 20:03:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:09 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:09 INFO - 'USER': 'cltbld', 20:03:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:09 INFO - 'XPC_FLAGS': '0x0', 20:03:09 INFO - 'XPC_SERVICE_NAME': '0', 20:03:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:03:10 INFO - Cleaning up... 20:03:10 INFO - Return code: 0 20:03:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:03: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')]} 20:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03: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')]} 20:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:10 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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:03:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:10 INFO - 'HOME': '/Users/cltbld', 20:03:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:10 INFO - 'LOGNAME': 'cltbld', 20:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:10 INFO - 'MOZ_NO_REMOTE': '1', 20:03:10 INFO - 'NO_EM_RESTART': '1', 20:03:10 INFO - 'PAGER': '/bin/cat', 20:03:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:10 INFO - 'PWD': '/builds/slave/test', 20:03:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:10 INFO - 'SHELL': '/bin/bash', 20:03:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:10 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:10 INFO - 'USER': 'cltbld', 20:03:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:10 INFO - 'XPC_FLAGS': '0x0', 20:03:10 INFO - 'XPC_SERVICE_NAME': '0', 20:03:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:10 INFO - Downloading/unpacking blobuploader==1.2.4 20:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:12 INFO - Downloading blobuploader-1.2.4.tar.gz 20:03:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:03:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:03:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:14 INFO - Downloading docopt-0.6.1.tar.gz 20:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:03:14 INFO - Installing collected packages: blobuploader, requests, docopt 20:03:14 INFO - Running setup.py install for blobuploader 20:03:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:03:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:03:14 INFO - Running setup.py install for requests 20:03:14 INFO - Running setup.py install for docopt 20:03:15 INFO - Successfully installed blobuploader requests docopt 20:03:15 INFO - Cleaning up... 20:03:15 INFO - Return code: 0 20:03:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:15 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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:15 INFO - 'HOME': '/Users/cltbld', 20:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:15 INFO - 'LOGNAME': 'cltbld', 20:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:15 INFO - 'MOZ_NO_REMOTE': '1', 20:03:15 INFO - 'NO_EM_RESTART': '1', 20:03:15 INFO - 'PAGER': '/bin/cat', 20:03:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:15 INFO - 'PWD': '/builds/slave/test', 20:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:15 INFO - 'SHELL': '/bin/bash', 20:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:15 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:15 INFO - 'USER': 'cltbld', 20:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:15 INFO - 'XPC_FLAGS': '0x0', 20:03:15 INFO - 'XPC_SERVICE_NAME': '0', 20:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:03:15 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ne4_VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:03:15 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-j6x781-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:03:15 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-OIWDAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:03:15 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-r38OUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:03:15 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-84UtZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:03:15 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-DrfL1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-DzTgVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-c6tBVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-TvfHbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-CkkkHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-JMVtrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-i916Xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-xTfdqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-XgFBvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:03:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-HmIJ8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:03:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-aTh652-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:03:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-VA_juw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:03:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:03:17 INFO - Running setup.py install for manifestparser 20:03:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:03:17 INFO - Running setup.py install for mozcrash 20:03:17 INFO - Running setup.py install for mozdebug 20:03:17 INFO - Running setup.py install for mozdevice 20:03:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:03:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:03:17 INFO - Running setup.py install for mozfile 20:03:18 INFO - Running setup.py install for mozhttpd 20:03:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Running setup.py install for mozinfo 20:03:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Running setup.py install for mozInstall 20:03:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Running setup.py install for mozleak 20:03:18 INFO - Running setup.py install for mozlog 20:03:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Running setup.py install for moznetwork 20:03:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:03:18 INFO - Running setup.py install for mozprocess 20:03:19 INFO - Running setup.py install for mozprofile 20:03:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:03:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:03:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:03:19 INFO - Running setup.py install for mozrunner 20:03:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:03:19 INFO - Running setup.py install for mozscreenshot 20:03:19 INFO - Running setup.py install for moztest 20:03:19 INFO - Running setup.py install for mozversion 20:03:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:03:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:03:19 INFO - Cleaning up... 20:03:19 INFO - Return code: 0 20:03:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:19 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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:19 INFO - 'HOME': '/Users/cltbld', 20:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:19 INFO - 'LOGNAME': 'cltbld', 20:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:19 INFO - 'MOZ_NO_REMOTE': '1', 20:03:19 INFO - 'NO_EM_RESTART': '1', 20:03:19 INFO - 'PAGER': '/bin/cat', 20:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:19 INFO - 'PWD': '/builds/slave/test', 20:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:19 INFO - 'SHELL': '/bin/bash', 20:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:19 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:19 INFO - 'USER': 'cltbld', 20:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:19 INFO - 'XPC_FLAGS': '0x0', 20:03:19 INFO - 'XPC_SERVICE_NAME': '0', 20:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-m0_ZG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-_3VQl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-X5KUpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-g9k71T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-S4KbIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-i7Fcmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Ew5DPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:03:20 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-kBMg1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-BJI0DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Wos2qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-rrPoqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-CMxeBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:03:21 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)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Txu9p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-tD0Y8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-WUwm8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-X1U7Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:03:21 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)) 20:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:03:21 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-J7GSZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:03:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:03:24 INFO - Downloading blessings-1.6.tar.gz 20:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:03:24 INFO - Installing collected packages: blessings 20:03:24 INFO - Running setup.py install for blessings 20:03:24 INFO - Successfully installed blessings 20:03:24 INFO - Cleaning up... 20:03:24 INFO - Return code: 0 20:03:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:03:25 INFO - Reading from file tmpfile_stdout 20:03:25 INFO - Current package versions: 20:03:25 INFO - blessings == 1.6 20:03:25 INFO - blobuploader == 1.2.4 20:03:25 INFO - docopt == 0.6.1 20:03:25 INFO - functools32 == 3.2.3-2 20:03:25 INFO - jsonschema == 2.5.1 20:03:25 INFO - manifestparser == 1.1 20:03:25 INFO - mozInstall == 1.12 20:03:25 INFO - mozcrash == 1.0 20:03:25 INFO - mozdebug == 0.1 20:03:25 INFO - mozdevice == 0.49 20:03:25 INFO - mozfile == 1.2 20:03:25 INFO - mozhttpd == 0.7 20:03:25 INFO - mozinfo == 0.9 20:03:25 INFO - mozleak == 0.1 20:03:25 INFO - mozlog == 3.4 20:03:25 INFO - moznetwork == 0.27 20:03:25 INFO - mozprocess == 0.25 20:03:25 INFO - mozprofile == 0.28 20:03:25 INFO - mozrunner == 6.13 20:03:25 INFO - mozscreenshot == 0.1 20:03:25 INFO - mozsystemmonitor == 0.3 20:03:25 INFO - moztest == 0.8 20:03:25 INFO - mozversion == 1.4 20:03:25 INFO - psutil == 3.1.1 20:03:25 INFO - requests == 1.2.3 20:03:25 INFO - wsgiref == 0.1.2 20:03:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:25 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 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:25 INFO - 'HOME': '/Users/cltbld', 20:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:25 INFO - 'LOGNAME': 'cltbld', 20:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:25 INFO - 'MOZ_NO_REMOTE': '1', 20:03:25 INFO - 'NO_EM_RESTART': '1', 20:03:25 INFO - 'PAGER': '/bin/cat', 20:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:25 INFO - 'PWD': '/builds/slave/test', 20:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:25 INFO - 'SHELL': '/bin/bash', 20:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:25 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:25 INFO - 'USER': 'cltbld', 20:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:25 INFO - 'XPC_FLAGS': '0x0', 20:03:25 INFO - 'XPC_SERVICE_NAME': '0', 20:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:03:25 INFO - Cleaning up... 20:03:25 INFO - Return code: 0 20:03:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:03:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f79eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef4a593bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, '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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:25 INFO - 'HOME': '/Users/cltbld', 20:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:25 INFO - 'LOGNAME': 'cltbld', 20:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:25 INFO - 'MOZ_NO_REMOTE': '1', 20:03:25 INFO - 'NO_EM_RESTART': '1', 20:03:25 INFO - 'PAGER': '/bin/cat', 20:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:25 INFO - 'PWD': '/builds/slave/test', 20:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:25 INFO - 'SHELL': '/bin/bash', 20:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:25 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:25 INFO - 'USER': 'cltbld', 20:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:25 INFO - 'XPC_FLAGS': '0x0', 20:03:25 INFO - 'XPC_SERVICE_NAME': '0', 20:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:03:25 INFO - Cleaning up... 20:03:25 INFO - Return code: 0 20:03:25 INFO - Running post-action listener: _resource_record_post_action 20:03:25 INFO - Running post-action listener: _start_resource_monitoring 20:03:25 INFO - Starting resource monitoring. 20:03:25 INFO - [mozharness: 2017-05-03 03:03:25.783064Z] Finished create-virtualenv step (success) 20:03:25 INFO - [mozharness: 2017-05-03 03:03:25.784102Z] Running install step. 20:03:25 INFO - Running pre-action listener: _resource_record_pre_action 20:03:25 INFO - Running main action method: install 20:03:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:03:26 INFO - Reading from file tmpfile_stdout 20:03:26 INFO - Detecting whether we're running mozinstall >=1.0... 20:03:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:03:26 INFO - Reading from file tmpfile_stdout 20:03:26 INFO - Output received: 20:03:26 INFO - Usage: mozinstall [options] installer 20:03:26 INFO - Options: 20:03:26 INFO - -h, --help show this help message and exit 20:03:26 INFO - -d DEST, --destination=DEST 20:03:26 INFO - Directory to install application into. [default: 20:03:26 INFO - "/builds/slave/test"] 20:03:26 INFO - --app=APP Application being installed. [default: firefox] 20:03:26 INFO - mkdir: /builds/slave/test/build/application 20:03:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 20:03:41 INFO - Reading from file tmpfile_stdout 20:03:41 INFO - Output received: 20:03:41 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 20:03:41 INFO - Running post-action listener: _resource_record_post_action 20:03:41 INFO - [mozharness: 2017-05-03 03:03:41.155334Z] Finished install step (success) 20:03:41 INFO - [mozharness: 2017-05-03 03:03:41.155462Z] Running run-tests step. 20:03:41 INFO - Running pre-action listener: _resource_record_pre_action 20:03:41 INFO - Running main action method: run_tests 20:03:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:03:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:03:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:03:41 INFO - 2017-05-02 20:03:41.170 screenresolution[3216:56541] starting screenresolution argv=screenresolution get 20:03:41 INFO - 2017-05-02 20:03:41.173 screenresolution[3216:56541] Display 0: 1600x1200x32@60 20:03:41 INFO - 2017-05-02 20:03:41.179 screenresolution[3217:56543] starting screenresolution argv=screenresolution list 20:03:41 INFO - Available Modes on Display 0 20:03:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:03:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:03:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:03:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:03:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:03:41 INFO - Intel Iris: 20:03:41 INFO - Chipset Model: Intel Iris 20:03:41 INFO - Type: GPU 20:03:41 INFO - Bus: Built-In 20:03:41 INFO - VRAM (Dynamic, Max): 1536 MB 20:03:41 INFO - Vendor: Intel (0x8086) 20:03:41 INFO - Device ID: 0x0a2e 20:03:41 INFO - Revision ID: 0x0009 20:03:41 INFO - Displays: 20:03:41 INFO - Display: 20:03:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:03:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:03:41 INFO - Display Serial Number: 1600x1200 60 20:03:41 INFO - Main Display: Yes 20:03:41 INFO - Mirror: Off 20:03:41 INFO - Online: Yes 20:03:41 INFO - Rotation: Supported 20:03:41 INFO - Return code: 0 20:03:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:03:41 INFO - Python 2.7.3 20:03:41 INFO - Return code: 0 20:03:41 INFO - grabbing minidump binary from tooltool 20:03: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')]} 20:03: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 0x10f57cd30>, '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 0x10f4a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f571be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:03: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 20:03: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 20:03: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 20:03:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:03:41 INFO - Return code: 0 20:03:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:03:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:03:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:03:41 INFO - ENV: RUST_BACKTRACE is now 1 20:03:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:03:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:03:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0221 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:03:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HBLBOSNXgp/Render', 20:03:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:41 INFO - 'HOME': '/Users/cltbld', 20:03:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:41 INFO - 'LOGNAME': 'cltbld', 20:03:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:03:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:41 INFO - 'MOZ_NO_REMOTE': '1', 20:03:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:03:41 INFO - 'NO_EM_RESTART': '1', 20:03:41 INFO - 'PAGER': '/bin/cat', 20:03:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:41 INFO - 'PWD': '/builds/slave/test', 20:03:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:03:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:41 INFO - 'RUST_BACKTRACE': '1', 20:03:41 INFO - 'SHELL': '/bin/bash', 20:03:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pxp98IeUTp/Listeners', 20:03:41 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 20:03:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:41 INFO - 'USER': 'cltbld', 20:03:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:41 INFO - 'XPC_FLAGS': '0x0', 20:03:41 INFO - 'XPC_SERVICE_NAME': '0', 20:03:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:03:41 INFO - mozversion application_buildid: 20170502175620 20:03:41 INFO - mozversion application_changeset: b6126c6706d097aed0fb83ef0f44a836fe607f03 20:03:41 INFO - mozversion application_display_name: Firefox 20:03:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:03:41 INFO - mozversion application_name: Firefox 20:03:41 INFO - mozversion application_remotingname: firefox 20:03:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:03:41 INFO - mozversion application_vendor: Mozilla 20:03:41 INFO - mozversion application_version: 54.0 20:03:41 INFO - mozversion platform_buildid: 20170502175620 20:03:41 INFO - mozversion platform_changeset: b6126c6706d097aed0fb83ef0f44a836fe607f03 20:03:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:03:41 INFO - mozversion platform_version: 54.0 20:03:41 INFO - using testdate: 1493780621 20:03:41 INFO - actual date: 1493780621 20:03:41 INFO - starting webserver on 'localhost:52031' 20:03:41 INFO - SUITE-START | Running 2 tests 20:03:41 INFO - TEST-START | dromaeo_css 20:03:41 INFO - operating with platform_type : mac_ 20:03:41 INFO - Initialising browser for dromaeo_css test... 20:03:41 INFO - TEST-INFO | started process 3227 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpAC_Ki1/profile http://localhost:52031/getInfo.html) 20:03:44 INFO - PROCESS | 3227 | 2017-05-02 20:03:44.572 plugin-container[3231:56713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 20:03:44 INFO - PROCESS | 3227 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:03:44 INFO - PROCESS | 3227 | 2017-05-02 20:03:44.573 plugin-container[3231:56713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 20:03:44 INFO - PROCESS | 3227 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:03:44 INFO - PROCESS | 3227 | 2017-05-02 20:03:44.573 plugin-container[3231:56713] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:03:44 INFO - PROCESS | 3227 | __metrics Screen width/height:1600/1200 20:03:44 INFO - PROCESS | 3227 | colorDepth:24 20:03:44 INFO - PROCESS | 3227 | Browser inner width/height: 1024/689 20:03:44 INFO - PROCESS | 3227 | __metrics 20:03:44 ERROR - PROCESS | 3227 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 20:03:45 INFO - TEST-INFO | 3227: exit 0 20:03:45 INFO - Browser initialized. 20:03:45 INFO - Running cycle 1/1 for dromaeo_css test... 20:03:45 INFO - TEST-INFO | started process 3232 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpAC_Ki1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:03:47 INFO - PROCESS | 3232 | 2017-05-02 20:03:47.641 plugin-container[3233:56844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8647, name = 'com.apple.tsm.portname' 20:03:47 INFO - PROCESS | 3232 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:03:47 INFO - PROCESS | 3232 | 2017-05-02 20:03:47.641 plugin-container[3233:56844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864b, name = 'com.apple.CFPasteboardClient' 20:03:47 INFO - PROCESS | 3232 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:03:47 INFO - PROCESS | 3232 | 2017-05-02 20:03:47.641 plugin-container[3233:56844] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:03:57 INFO - PROCESS | 3232 | RSS: Main: 174501888 20:03:57 INFO - PROCESS | 3232 | 20:07:00 INFO - PROCESS | 3232 | Cycle 1(1): loaded http://localhost:52031/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52031/tests/dromaeo/cssquery-ext.html) 20:07:01 INFO - PROCESS | 3232 | RSS: Main: 189476864 20:07:01 INFO - PROCESS | 3232 | 20:10:03 INFO - PROCESS | 3232 | Cycle 1(1): loaded http://localhost:52031/tests/dromaeo/cssquery-ext.html (next: http://localhost:52031/tests/dromaeo/cssquery-jquery.html) 20:10:04 INFO - PROCESS | 3232 | RSS: Main: 203223040 20:10:04 INFO - PROCESS | 3232 | 20:13:06 INFO - PROCESS | 3232 | Cycle 1(1): loaded http://localhost:52031/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52031/tests/dromaeo/cssquery-mootools.html) 20:13:06 INFO - PROCESS | 3232 | RSS: Main: 208801792 20:13:06 INFO - PROCESS | 3232 | 20:16:08 INFO - PROCESS | 3232 | Cycle 1(1): loaded http://localhost:52031/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52031/tests/dromaeo/cssquery-prototype.html) 20:16:09 INFO - PROCESS | 3232 | RSS: Main: 211861504 20:16:09 INFO - PROCESS | 3232 | 20:19:12 INFO - PROCESS | 3232 | Cycle 1(1): loaded http://localhost:52031/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52031/tests/dromaeo/cssquery-yui.html) 20:19:13 INFO - PROCESS | 3232 | RSS: Main: 214315008 20:19:13 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | Cycle 1(1): loaded http://localhost:52031/tests/dromaeo/cssquery-yui.html (next: http://localhost:52031/tests/dromaeo/cssquery-dojo.html) 20:22:15 INFO - PROCESS | 3232 | RSS: Main: 212611072 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | __start_tp_report 20:22:15 INFO - PROCESS | 3232 | _x_x_mozilla_page_load 20:22:15 INFO - PROCESS | 3232 | _x_x_mozilla_page_load_details 20:22:15 INFO - PROCESS | 3232 | |i|pagename|runs| 20:22:15 INFO - PROCESS | 3232 | |0;dojo.html;3008.2629755709854;3594.9820250898742;3596.838142283597;3602.9639703602957;3648.5074514940475;6092.116643086755;6159.3224745277985;6190.442860142586;6199.132121502993;6202.689865506718;6147.661878596687;6170.444659980601;6240.3759624037575;6241.875162496747;6251.937480625193;4636.675432719711;5454.890902181954;5488.286522752037;5569.053260945649;5587.329520457551;5598.2442370280105;5606.887862242753;5611.551075913917;5619.7471113799975;5625.831225063254;5425.159100339453;5587.078132108198;5602.38373778884;5614.29821272341;5654.688992105433;5443.292371991635;5535.58483113766;5552.278203833516;5573.331200255955;5598.272224610815;4625.953740462586;5454.972725136388;5561.360443549003;5579.0515612346;5593.8321850344555;6228.190335256433;6237.033259844731;6263.561550691454;6277.403646653561;6281.528885333618;6293.30773614902;6331.2719037310835;6441.355864413568;6465.062565927914;6501.674916254168;6608.405243528057;6634.734610615618;6647.268800431949;6655.500837437212;6665.100211471487;6298.086777417248;6303.653299068549;6311.589746666452;6326.398992095744;6336.239651241881;5619.831405057855;6411.647359395278;6582.473402127818;6649.601023938482;6690.096836926952;6446.290908000116;6498.220213574401;6501.252355979028;6501.577397469244;6507.511936604774;6607.537472376887;6614.206295244505;6618.63597502142;6625.17185351831;6640.800775976729;6301.842453938688;6337.366263373718;6339.841503962346;6503.089567460559;6577.309382514863;6421.839454013593;6428.357164283534;6439.80680579574;6488.545801793924;6515.7393704252245;5135.768890399941;5160.561352285098;5201.531862132426;5235.450277720861;5312.495312945264;4024.9195016099516;4620.792064357112;4667.089917466062;4677.95322046782;4690.273007683814;6294.527910406738;6305.274893387319;6318.526110541728;6323.620582765049;6328.493720502441;6392.4246817786625;6423.4861211103935;6531.575447595891;6640.335966403321;6659.567128136656;6169.938300616936;6277.497800175975;6282.623042617458;6301.621902685762;6341.207349081365;5710.114932185518;5755.395683453177;5790.594658373873;5790.855228619234;5794.768209271666;5380.757865895966;6550.868982620416;6787.423069039187;6791.93208067913;6813.4208592270215;5398.541124004503;6524.151860258136;6614.900776488453;6623.6688165592495;6650.068990341259;5102.387713474407;6494.058361537645;6841.521093523605;6859.1083159190985;6868.690908909211;6293;6463.709133089116;6484.805455836333;6538.476921846146;6619.569727967667;5355.464453554613;6371.362863713592;6505.31694172124;6590.9670451647435;6616.66916654175;6441.259255185713;6443.9355606445215;6452.677366131768;6460.935390646221;6462.870742585216;6366.6180029198395;6368.299487056513;6437.034444833313;6446.0975463434215;6479.157709497735;5308.946910531;6162.938370616236;6234.345393733593;6263.060540918898;6314.8737025258315;6440.388163124496;6445.6454894981225;6454.290028097;6455.580387274812;6458.063580780667;5141.25451809493;6147.354527774519;6194.659293738888;6280.9685951569945;6309.526785491014 20:22:15 INFO - PROCESS | 3232 | |1;ext.html;708.7165133946421;797.9945268961111;858.9484630922143;993.6522217223977;998.7952469743703;36238.81880590593;40980;42185.57814421855;42245.57754422455;42341.15317693648;40145.598544014625;40244.792656220285;40442.595574044324;40491.59508404915;40526.18947621053;8228.670853165866;8377.664893404257;11254;11514.366709830956;11685.649430517098;11295.830562541569;11351.545938162464;11356.545738170504;11372.545098196062;11381.430928453585;11336.716582085432;11337;11399.430028498584;11415.429228538582;11427.714307142347;11281.661550153467;11329.886701132966;11347.319160850293;11362.091032717362;11391.943040284828;10958.178136639704;10996.615118470894;11050.226484146113;11070.723231919186;11077.335359878432;32337.676623233703;32704.855330063332;35605.82197089024;35800.82099589511;35829.82085089558;4009.218202450523;4017.6384125428854;4025.4766880305665;4043.2115737431213;4071.5521292657786;26427.603585946224;27586.448271034667;27884.16347509578;27896.721032789614;28001.29996750077;3184.458642030837;3243.805371677683;3325.085601459618;3342.7492938029745;3441.6558344165633;364697;367091;367229;367242;370373.1481342603;46635.067298653834;46845.531544684796;46863.828404290165;46902.76548617235;47042;47869.76065119652;47983;48087.278690819665;48111.51888481071;48112.27831582529;14885.70228595422;14901.552953411416;14941.925290373478;14946;15001.549953501413;12818.102732808615;12827.422765975554;12828;12832.230066196058;12851.228926264454;11714.414279286002;12345.814812777815;12358.567450139382;12374.505019799108;12450.688732781753;5803.709814509258;5837.09525023622;5849.9177652134495;5874.73563689635;5878.911816322759;981.4307701533237;1091.2906610703108;1110.4059328259343;1131.2533727739653;1185.8852678482199;30065.849670751944;30359.115883990984;30543.93096241621;30939.84530077335;31173;1061.532925512772;1068.1454836131063;1105.8728246251785;1115.7824224276187;1116.1349953785882;13405;15157.393704251926;15294.158821264715;15510.21632512253;15949;359098;439159;440535;442272;443594.5640543553;40767.388489172685;49225.50774492281;49550;49650;49764;1307.3985966455537;1466.5453709350136;1486.6580686442178;1511.833698293187;1512.2136489025866;28176.577351340155;28411.71588284091;28851;29272.82908683629;29521.557176641938;42806.35790463083;42863.78568107139;42879.57120428756;42951.785241074846;43066.13867722565;424785;424796;425396.8730156453;425627;426332.86833565636;30574.847125765118;30773.8461307692;30909.536356955112;31105.222369440944;31191.84404077965;39527.80236098801;40361.79819100886;41135.58864411317;41272.587274126876;41393.58606413897;28133.43733125367;28918.85540572368;29078.56382154227;29555.55666665045;30011.69988300089;28283.86864525396;28654.713452865206;28735.568966465937;29085.7091429083;29755.851220743756;33701;34318.65681343254;34410;34562.48156277708;34653.48019779655;22408.663870041637;28566;28629;28861.85569072141;28893.422131557672;23604;23715.88142059279;23828;23842.88078559596;23949.521009580058 20:22:15 INFO - PROCESS | 3232 | |2;jquery.html;430.5866368286445;443.76702231328557;446.1723502902116;447.84325486079854;457.3688310132017;19874;20892.47768805779;22097.779022209772;22282.88858555709;22403;22787.430314242152;22896.542069158608;23756.88121559394;23926.64110038351;24055.278341649733;6845.349691779273;6928.168619765635;7073.328033836803;7163.892541611854;7200.35196832285;6976.058232138673;6978.965105174467;7023.2625574314725;7043.718251269943;7048.753293634723;6848.589084654912;6955.478339124561;7100.183478899915;7123.786286411416;7125.5724656520515;6987.371136597705;6990.196127445332;7058.364747172754;7284.162321333061;7618.8476230475635;7290.890636640454;7476.775696729107;7502.8124296892465;7524.774256772305;7525.698972041112;22969.540609187723;27399.589006164922;27527.761250743766;27550;28128.156155315373;2688.8252263602803;2961.7334439900515;3038.5290280006634;3054.770892183073;3061.4183305171946;16462.75305870413;16608.335666573323;16711.498655040366;16903.577410564834;17193;2131.658934570477;2158.653558537894;2165.1897262341317;2166.3717521918625;2174.7716489768663;168607;168643;171403.28596714;178048;182025;21324.680129798064;21526.923653817248;21667.458313542287;21737.78262217389;21984.560308793734;32848;33140.83429582837;33641;33678.83160584181;33712;10309.74225644365;10524.579016839241;10719.892801072045;10744;10817.567297308176;8774.956125219333;8811.911880881238;8893.599788009555;9002.189802917637;9082.40964337316;7995.360371170293;9793.804123917482;9833.704988850346;9961.501924903725;9974.351667141613;3652.0322114639644;4364.60718535327;4394.890127746768;4423.668224883147;4453.19842428366;808.7775861638097;874.4054043250651;892.7678803511134;1073.1843798712878;1083.4582708645678;20221.797782021993;22138.446538836335;22197;22231.333060008226;22478.763667998417;1060.114804138553;1064.2816099133054;1093.9890601093887;1104.9834252486219;1110.211749657752;10346.430946297874;11227.045701115334;11522.308661480338;11582.189246752814;11617.941910290394;791910;812438;818939;819321;823863;166516;167773.16113419353;168357.1582142106;170580.32388542546;171560;1237.270010693695;1317.1965101288206;1330.6274243211917;1336.7568161609602;1347.7102422979106;19937.401877943685;20193.59612807765;20595.4851128723;20689.965501724553;20843;60709;64354.678226608565;65028;65059.67470162619;65263.694726106165;79995.60002199952;80165.19834801578;85229.57385213034;93029;93801.5309923446;9221.815563688822;11656.53373865036;11716.531338746354;12005.63983080509;12124.515019399125;13018.15382000167;13031.218126912416;13046.34768261602;13360.331983400596;13456;9904.405735655884;10045.849312260176;10099.69700908974;10214.489275536045;11318.037966772916;10223.437710925673;10295;10511.159107271247;10576.41829699374;10733.355998640107;9944.403335799876;10324.277300588887;11666;11685.298882067103;11978.880211197777;11718.296902185895;11898.524059037542;11918.165728398928;11996.460159292677;12024.977876880563;10371.58513659445;10456.947715261374;10672.57309707603;11442.828357574477;11677.357745324089 20:22:15 INFO - PROCESS | 3232 | |3;mootools.html;1423.9857601423982;1631.3800755712832;1633.0773113191046;1633.795775528059;1637.1323198704683;10144.59421623136;10817.67546973591;11390.145739069572;11695.35675537845;12048.337341446228;11195.664130076091;11378.431078446087;11408.429578521082;11420.200585958986;11425.714357141076;2486.452980344326;2539.1366935242013;2545.8217924745277;2548.9362765930814;2553.234029791055;2487.2040946897;2503.5118151960373;2539.4890040426008;2540.491901619674;2543.631173479844;2448.902043918241;2489.4025433895827;2528.8609126498036;2540.199837051332;2549.8852551635223;2417.6494408310787;2484.664570283007;2492.763187497185;2493.5137648158616;2511.937201569957;2477.3682710908784;2480.7643273888953;2508.648789169518;2510.410053637394;2510.723820379757;11288.2662626929;11451.541938322458;11493.655190344303;11514.251573647685;11527.250728702607;6032.306284777263;6142.723577439026;6213.844653883689;6226.315105338409;6239.594426362272;3099.5815564898903;3612.3858943979326;3780.7542509736777;3796.6393192646656;3810.333191691443;1225.4730465899659;1230.1696354960366;1267.3789842976967;1282.7947528395412;1283.5250957145915;11826.704332391759;13977.72044559103;14282.143071415749;15159.545213643609;15792.842071579253;4196.475440569929;4814.422269327703;4816.590589799836;4882.340883980688;4985.077760614298;12215.572454964034;12439;12443.191192572454;12449.190802597983;12490.75018499625;6165.815025549241;6169.81490555284;6177.536684748662;6182.474489668429;6184.907226391608;5347.41178470368;5490;5584.692841893735;5594.328680558359;5612.07400778875;3982.561918188997;4048.4129801178665;4077.041895154636;4118.135191609796;4127.979360103181;947.5025611553989;1083.9458027098613;1087.5541028178409;1097.1771171621283;1097.9780404391868;823.6787652815407;840.815020695446;862.094800459515;872.8603423452217;886.4548302793864;6613.14029176204;6638.4357329626555;6773.288804675537;6779.457643388517;6912.861742765116;913.5204017890661;914.6478605736839;921.2031592672424;927.2813569483704;937.2361525356812;4144.854930077433;4220.915581688349;4233.089885674594;4246.596573325529;4346.434963454731;12166.391680415943;13979.951503637269;14018.649533761738;14323.498677546444;14354.784678229835;12281.754364912651;13325.267110309027;14026.298685065705;14312.928435357964;14386.35261413239;7988.480748751429;10673.35959842412;10754;10795.46022698862;10798.838017429745;6761.290064543152;6793.66031698423;6794.72821087151;6922.619255940972;7037.296270372922;10043.849342260019;12443.315617641116;12458.190217636187;12595.24428534291;12608.495660173858;12705.618831435071;12738.554150605056;12792.360381980678;12795.296258705861;12960.351982400654;9477.71566852968;9479.715608531755;9485.004074572347;9552.85670714926;9577.952110239405;10362.274640775073;10375.481225938824;10479.899610540784;10480.94759526228;10507.579696812041;8913.37606367574;8976.147266009719;9000.81998360016;9019.819603608023;9032.593533291149;8593.785155371166;8685.869711954452;8698.782530436789;8814.427062240933;8881.73354799357;7972.720954766555;7986.480878742863;8026.799330016796;8091.352691784761;8093.028836539653;5382.865428364165;5835.79574714883;5957.970210149095;6028.969855150696;6062.696865156813;6022.90965635507;6255;6290.119383286252;6291.276503202191;6331 20:22:15 INFO - PROCESS | 3232 | |4;prototype.html;351.69578314757734;354.1924412339866;385.6702519345959;407.1023393417513;408.17141203357176;5243.947560524393;5257.369115706116;5279.841604751854;5451.318585176853;5588.6646801191955;4648.558386953234;5102.923456148161;5136.691798492093;5260.949907606245;5279.577633789288;3426.8629254829775;3543.92912141757;3838.2707285615643;4388.047318943071;4399.758013309267;3876.5735769065523;4068.7997040873015;4075.8981025474304;4214.346776249687;4389.341598760179;3453.481977703352;3768.547774267092;4169.102473780724;4204.684648651348;4404.69167158299;3734.6825519830845;4309.978450107761;4360.563943605645;4413;4428.645708343325;3757.906052348686;4012.4181993611055;4095;4125.051238215197;4126.525449573308;4847.83032593861;4938.62960277977;5121.769520371592;5219.634625576211;5252.36971563411;4707.882302942419;5405.21624364465;5423.701696406696;5428.511433970962;5472.781088756485;5002.849914502571;5333.679979201261;5362.142057270857;5717.655761137955;5748.45389687979;4647.070585882803;4886.364772579577;4913.705177689314;4921.311016457699;4944.9258261126115;4571.977140114278;4697.060587882403;5064.088149314122;5176.611754118456;6007.978643630549;4300.634446072057;4534.45586529612;5037.186612819382;5058.64589478733;5099.526729536172;4268.996785755345;5007.549320561167;5158;5168.2764412982215;5181.419667001525;4611;4737.911783358437;5161.974190129025;5261.973690131525;5416.431274716098;5392.703401312887;5667.4615911488345;5682.54539636828;5687.687177205211;5740.770369185186;1643.2933838449342;1656.4699296225333;1690.8140104588485;1777.4934143768965;1784.5449410400524;1212.9150959432754;1228.3551135653674;1264.1403845385225;1338.9154784624486;1361.46902707944;4870.756462176877;4912.680675756064;4919.917618124007;4928.67963582366;5139.6335400312855;5124.718140502308;5227.457899919533;5911.4975227105315;5940.732667029994;5972.313183983897;4696.03731235096;4815.374001379798;4901;5073.506505066042;5375.172441369953;2672.919812405631;2782.8136783383316;2937.839553376447;3052.481078216664;3114.306125201684;5044.621653376012;5245.868853278622;5576.581756368208;5651.886962260732;5839.708014599253;5519.724013799294;5534.7786088556;5914.940850591525;5951.9107213391835;5967.731452084667;5192.144320623566;5479.123460183436;5716.628419152742;6078.574499784972;6119.020956647004;5019.623528235324;5149.87125321855;5630.3525094613005;5796.855078623068;5801.245838041028;4788.7366194859615;4887.535684110004;4945.826896058621;5603.831885043455;5753.1945527627;5401.918971215357;5882.941170588239;5971.223740913827;6135.570510064431;6163.660998645118;4974.980129073459;5105.668131571584;5113.79544818218;5132.357645553537;5181;5274.9450109978925;5442.673439593637;5444.156155795699;5789.594728368974;5839.620424672383;4635.351050852951;5359.517643412111;5666.829995100154;5683.914741278802;5698.059820129798;4596.931046034245;5128.97435512835;5204.81397812927;5258.421573626822;5636.730696196114;5129.384473863161;5333.653312534673;5498.45015498447;5503.504684578407;5514.310711161105;4822.590079843112;5669.603127781016;5671.688057156731;5676.4323567642905;5694.772209111589;5075.5178258065425;5136.895567452866;5375.704336261543;5576.581756368208;5592.077307244259 20:22:15 INFO - PROCESS | 3232 | |5;yui.html;759.6050053971938;773.0066864079652;804.1717031457597;890;897.7306807957613;15799.763003554956;17581.29674813006;17865.732014019784;17913.193906274217;17924.551886202837;13694.726105477885;15791.28939197736;16490.92808967419;16580.088095154762;16625.83374166258;1030.6032177611617;1060.0035966191795;1060.5174645536263;1063.478895341281;1069.9144068474504;994.2692121290845;1034.8913364096775;1039.4075377035092;1049.0977759127145;1049.4385503755493;1065.0893486069415;1086.3699054548383;1088.5210507376728;1092.2136062035322;1092.382803715902;1075.3386667199616;1081.23232504922;1083.3932997521413;1085.180688580121;1089.2320913755823;810.687885164216;810.7648781853255;814.250889181954;815.355868863597;816.3632366753941;17058.061806600632;17412.30350785967;17659.470215893543;18274.63450730978;18401.907990460095;14683.339249733788;14901.92549037248;14905;15030;15187.392504299814;11989.760204795943;12169.330686812222;12189.268643881307;12245.04047926647;12472.750544989049;3708;3710.5361829771277;3795.886123416302;3798.7150963677836;3810.79040652764;122489;128006.3599682005;128610;128840.0673989891;129283;9777.41335519871;9781.559829807677;9957.452340121217;9993.350432221881;10075.647352342632;21857.453563661034;22038.779612203674;22342.888285558794;22402.55194896093;22443.551128977328;7884;7959.084705258853;7985.960070199728;8071.556064416513;8107.83784324322;4516.051629157913;4580.564846339659;4623.075384922961;4654.953450465519;4693.718376897397;3601.711863051002;3678.6137455567314;3921.3725803871816;4078.7960601969785;4135.565765594569;732.6776218463965;733.7285204474272;737.2075019354108;741.6551303643723;743.8289193485529;685.6126288646899;721.8195451137216;722.631457956446;742.9294217049371;756.4175584799674;10709.571617135383;11011.779764404668;11152.944235278772;11301.26541774782;11610;687.3607544983114;710.754789597588;734.5834138797599;755.4522970846202;776.7902666280186;4771.73755443454;4878.512148785164;5083.87290317745;5352.411234764173;5386.8383948481605;108147.45926270318;112443.43778281056;113854;117240;118522.4073879625;86718;87585;88152.5592372034;89301.55349223212;90134.54932725425;88036.5598172005;88213;90591;91061;92017.61973570536;9959.601615935571;10359.119474844421;10378.481075946022;10380.117689996132;10411;52061.47938520566;53136;53772;55344.72327638336;55376.72311638416;63184.684076579324;63570;64894.35105648883;70221.29778702352;70741;13497.055206135476;13604;13896;14112.647183820076;14124.858751412354;15046.6990660184;15058.322375493348;15065.397384104514;15147.242637867843;15910.920445397698;14028.2985850705;14364.28178591087;14425.278736063365;14427.49503767363;15117.395304187708;14151;14265;14372.058434525781;14707.779383309045;14738.63153421173;14415.567532974028;14654.487092951695;14797.92601036988;14840.703185936436;14880.330385132478;10078.496075196357;10361.22290828206;10788.622398216025;11074.833877492005;11103.5558577656;10236.020641231671;10245;11598.246114002563;11827.40862956866;12276.447559860006 20:22:15 INFO - PROCESS | 3232 | __end_tp_report 20:22:15 INFO - PROCESS | 3232 | __start_cc_report 20:22:15 INFO - PROCESS | 3232 | _x_x_mozilla_cycle_collect,393 20:22:15 INFO - PROCESS | 3232 | __end_cc_report 20:22:15 INFO - PROCESS | 3232 | __startTimestamp1493781735755__endTimestamp 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | ------- Summary: start ------- 20:22:15 INFO - PROCESS | 3232 | Number of tests: 6 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | [#0] dojo.html Cycles:165 Average:6041.18 Median:6303.65 stddev:708.82 (11.2%) stddev-sans-first:669.88 20:22:15 INFO - PROCESS | 3232 | Values: 3008.3 3595.0 3596.8 3603.0 3648.5 6092.1 6159.3 6190.4 6199.1 6202.7 6147.7 6170.4 6240.4 6241.9 6251.9 4636.7 5454.9 5488.3 5569.1 5587.3 5598.2 5606.9 5611.6 5619.7 5625.8 5425.2 5587.1 5602.4 5614.3 5654.7 5443.3 5535.6 5552.3 5573.3 5598.3 4626.0 5455.0 5561.4 5579.1 5593.8 6228.2 6237.0 6263.6 6277.4 6281.5 6293.3 6331.3 6441.4 6465.1 6501.7 6608.4 6634.7 6647.3 6655.5 6665.1 6298.1 6303.7 6311.6 6326.4 6336.2 5619.8 6411.6 6582.5 6649.6 6690.1 6446.3 6498.2 6501.3 6501.6 6507.5 6607.5 6614.2 6618.6 6625.2 6640.8 6301.8 6337.4 6339.8 6503.1 6577.3 6421.8 6428.4 6439.8 6488.5 6515.7 5135.8 5160.6 5201.5 5235.5 5312.5 4024.9 4620.8 4667.1 4678.0 4690.3 6294.5 6305.3 6318.5 6323.6 6328.5 6392.4 6423.5 6531.6 6640.3 6659.6 6169.9 6277.5 6282.6 6301.6 6341.2 5710.1 5755.4 5790.6 5790.9 5794.8 5380.8 6550.9 6787.4 6791.9 6813.4 5398.5 6524.2 6614.9 6623.7 6650.1 5102.4 6494.1 6841.5 6859.1 6868.7 6293.0 6463.7 6484.8 6538.5 6619.6 5355.5 6371.4 6505.3 6591.0 6616.7 6441.3 6443.9 6452.7 6460.9 6462.9 6366.6 6368.3 6437.0 6446.1 6479.2 5308.9 6162.9 6234.3 6263.1 6314.9 6440.4 6445.6 6454.3 6455.6 6458.1 5141.3 6147.4 6194.7 6281.0 6309.5 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | [#1] ext.html Cycles:180 Average:54146.65 Median:30674.35 stddev:107784.54 (351.4%) stddev-sans-first:108012.23 20:22:15 INFO - PROCESS | 3232 | Values: 708.7 798.0 858.9 993.7 998.8 36238.8 40980.0 42185.6 42245.6 42341.2 40145.6 40244.8 40442.6 40491.6 40526.2 8228.7 8377.7 11254.0 11514.4 11685.6 11295.8 11351.5 11356.5 11372.5 11381.4 11336.7 11337.0 11399.4 11415.4 11427.7 11281.7 11329.9 11347.3 11362.1 11391.9 10958.2 10996.6 11050.2 11070.7 11077.3 32337.7 32704.9 35605.8 35800.8 35829.8 4009.2 4017.6 4025.5 4043.2 4071.6 26427.6 27586.4 27884.2 27896.7 28001.3 3184.5 3243.8 3325.1 3342.7 3441.7 364697.0 367091.0 367229.0 367242.0 370373.1 46635.1 46845.5 46863.8 46902.8 47042.0 47869.8 47983.0 48087.3 48111.5 48112.3 14885.7 14901.6 14941.9 14946.0 15001.5 12818.1 12827.4 12828.0 12832.2 12851.2 11714.4 12345.8 12358.6 12374.5 12450.7 5803.7 5837.1 5849.9 5874.7 5878.9 981.4 1091.3 1110.4 1131.3 1185.9 30065.8 30359.1 30543.9 30939.8 31173.0 1061.5 1068.1 1105.9 1115.8 1116.1 13405.0 15157.4 15294.2 15510.2 15949.0 359098.0 439159.0 440535.0 442272.0 443594.6 40767.4 49225.5 49550.0 49650.0 49764.0 1307.4 1466.5 1486.7 1511.8 1512.2 28176.6 28411.7 28851.0 29272.8 29521.6 42806.4 42863.8 42879.6 42951.8 43066.1 424785.0 424796.0 425396.9 425627.0 426332.9 30574.8 30773.8 30909.5 31105.2 31191.8 39527.8 40361.8 41135.6 41272.6 41393.6 28133.4 28918.9 29078.6 29555.6 30011.7 28283.9 28654.7 28735.6 29085.7 29755.9 33701.0 34318.7 34410.0 34562.5 34653.5 22408.7 28566.0 28629.0 28861.9 28893.4 23604.0 23715.9 23828.0 23842.9 23949.5 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | [#2] jquery.html Cycles:180 Average:46134.74 Median:22257.11 stddev:135926.35 (610.7%) stddev-sans-first:136264.33 20:22:15 INFO - PROCESS | 3232 | Values: 430.6 443.8 446.2 447.8 457.4 19874.0 20892.5 22097.8 22282.9 22403.0 22787.4 22896.5 23756.9 23926.6 24055.3 6845.3 6928.2 7073.3 7163.9 7200.4 6976.1 6979.0 7023.3 7043.7 7048.8 6848.6 6955.5 7100.2 7123.8 7125.6 6987.4 6990.2 7058.4 7284.2 7618.8 7290.9 7476.8 7502.8 7524.8 7525.7 22969.5 27399.6 27527.8 27550.0 28128.2 2688.8 2961.7 3038.5 3054.8 3061.4 16462.8 16608.3 16711.5 16903.6 17193.0 2131.7 2158.7 2165.2 2166.4 2174.8 168607.0 168643.0 171403.3 178048.0 182025.0 21324.7 21526.9 21667.5 21737.8 21984.6 32848.0 33140.8 33641.0 33678.8 33712.0 10309.7 10524.6 10719.9 10744.0 10817.6 8775.0 8811.9 8893.6 9002.2 9082.4 7995.4 9793.8 9833.7 9961.5 9974.4 3652.0 4364.6 4394.9 4423.7 4453.2 808.8 874.4 892.8 1073.2 1083.5 20221.8 22138.4 22197.0 22231.3 22478.8 1060.1 1064.3 1094.0 1105.0 1110.2 10346.4 11227.0 11522.3 11582.2 11617.9 791910.0 812438.0 818939.0 819321.0 823863.0 166516.0 167773.2 168357.2 170580.3 171560.0 1237.3 1317.2 1330.6 1336.8 1347.7 19937.4 20193.6 20595.5 20690.0 20843.0 60709.0 64354.7 65028.0 65059.7 65263.7 79995.6 80165.2 85229.6 93029.0 93801.5 9221.8 11656.5 11716.5 12005.6 12124.5 13018.2 13031.2 13046.3 13360.3 13456.0 9904.4 10045.8 10099.7 10214.5 11318.0 10223.4 10295.0 10511.2 10576.4 10733.4 9944.4 10324.3 11666.0 11685.3 11978.9 11718.3 11898.5 11918.2 11996.5 12025.0 10371.6 10456.9 10672.6 11442.8 11677.4 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | [#3] mootools.html Cycles:180 Average:6869.76 Median:2549.41 stddev:4229.03 (165.9%) stddev-sans-first:4221.10 20:22:15 INFO - PROCESS | 3232 | Values: 1424.0 1631.4 1633.1 1633.8 1637.1 10144.6 10817.7 11390.1 11695.4 12048.3 11195.7 11378.4 11408.4 11420.2 11425.7 2486.5 2539.1 2545.8 2548.9 2553.2 2487.2 2503.5 2539.5 2540.5 2543.6 2448.9 2489.4 2528.9 2540.2 2549.9 2417.6 2484.7 2492.8 2493.5 2511.9 2477.4 2480.8 2508.6 2510.4 2510.7 11288.3 11451.5 11493.7 11514.3 11527.3 6032.3 6142.7 6213.8 6226.3 6239.6 3099.6 3612.4 3780.8 3796.6 3810.3 1225.5 1230.2 1267.4 1282.8 1283.5 11826.7 13977.7 14282.1 15159.5 15792.8 4196.5 4814.4 4816.6 4882.3 4985.1 12215.6 12439.0 12443.2 12449.2 12490.8 6165.8 6169.8 6177.5 6182.5 6184.9 5347.4 5490.0 5584.7 5594.3 5612.1 3982.6 4048.4 4077.0 4118.1 4128.0 947.5 1083.9 1087.6 1097.2 1098.0 823.7 840.8 862.1 872.9 886.5 6613.1 6638.4 6773.3 6779.5 6912.9 913.5 914.6 921.2 927.3 937.2 4144.9 4220.9 4233.1 4246.6 4346.4 12166.4 13980.0 14018.6 14323.5 14354.8 12281.8 13325.3 14026.3 14312.9 14386.4 7988.5 10673.4 10754.0 10795.5 10798.8 6761.3 6793.7 6794.7 6922.6 7037.3 10043.8 12443.3 12458.2 12595.2 12608.5 12705.6 12738.6 12792.4 12795.3 12960.4 9477.7 9479.7 9485.0 9552.9 9578.0 10362.3 10375.5 10479.9 10480.9 10507.6 8913.4 8976.1 9000.8 9019.8 9032.6 8593.8 8685.9 8698.8 8814.4 8881.7 7972.7 7986.5 8026.8 8091.4 8093.0 5382.9 5835.8 5958.0 6029.0 6062.7 6022.9 6255.0 6290.1 6291.3 6331.0 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | [#4] prototype.html Cycles:180 Average:4718.14 Median:5129.18 stddev:1287.42 (25.1%) stddev-sans-first:1248.62 20:22:15 INFO - PROCESS | 3232 | Values: 351.7 354.2 385.7 407.1 408.2 5243.9 5257.4 5279.8 5451.3 5588.7 4648.6 5102.9 5136.7 5260.9 5279.6 3426.9 3543.9 3838.3 4388.0 4399.8 3876.6 4068.8 4075.9 4214.3 4389.3 3453.5 3768.5 4169.1 4204.7 4404.7 3734.7 4310.0 4360.6 4413.0 4428.6 3757.9 4012.4 4095.0 4125.1 4126.5 4847.8 4938.6 5121.8 5219.6 5252.4 4707.9 5405.2 5423.7 5428.5 5472.8 5002.8 5333.7 5362.1 5717.7 5748.5 4647.1 4886.4 4913.7 4921.3 4944.9 4572.0 4697.1 5064.1 5176.6 6008.0 4300.6 4534.5 5037.2 5058.6 5099.5 4269.0 5007.5 5158.0 5168.3 5181.4 4611.0 4737.9 5162.0 5262.0 5416.4 5392.7 5667.5 5682.5 5687.7 5740.8 1643.3 1656.5 1690.8 1777.5 1784.5 1212.9 1228.4 1264.1 1338.9 1361.5 4870.8 4912.7 4919.9 4928.7 5139.6 5124.7 5227.5 5911.5 5940.7 5972.3 4696.0 4815.4 4901.0 5073.5 5375.2 2672.9 2782.8 2937.8 3052.5 3114.3 5044.6 5245.9 5576.6 5651.9 5839.7 5519.7 5534.8 5914.9 5951.9 5967.7 5192.1 5479.1 5716.6 6078.6 6119.0 5019.6 5149.9 5630.4 5796.9 5801.2 4788.7 4887.5 4945.8 5603.8 5753.2 5401.9 5882.9 5971.2 6135.6 6163.7 4975.0 5105.7 5113.8 5132.4 5181.0 5274.9 5442.7 5444.2 5789.6 5839.6 4635.4 5359.5 5666.8 5683.9 5698.1 4596.9 5129.0 5204.8 5258.4 5636.7 5129.4 5333.7 5498.5 5503.5 5514.3 4822.6 5669.6 5671.7 5676.4 5694.8 5075.5 5136.9 5375.7 5576.6 5592.1 20:22:15 INFO - PROCESS | 3232 | 20:22:15 INFO - PROCESS | 3232 | [#5] yui.html Cycles:180 Average:22226.87 Median:17235.18 stddev:32689.87 (189.7%) stddev-sans-first:32741.83 20:22:15 INFO - PROCESS | 3232 | Values: 759.6 773.0 804.2 890.0 897.7 15799.8 17581.3 17865.7 17913.2 17924.6 13694.7 15791.3 16490.9 16580.1 16625.8 1030.6 1060.0 1060.5 1063.5 1069.9 994.3 1034.9 1039.4 1049.1 1049.4 1065.1 1086.4 1088.5 1092.2 1092.4 1075.3 1081.2 1083.4 1085.2 1089.2 810.7 810.8 814.3 815.4 816.4 17058.1 17412.3 17659.5 18274.6 18401.9 14683.3 14901.9 14905.0 15030.0 15187.4 11989.8 12169.3 12189.3 12245.0 12472.8 3708.0 3710.5 3795.9 3798.7 3810.8 122489.0 128006.4 128610.0 128840.1 129283.0 9777.4 9781.6 9957.5 9993.4 10075.6 21857.5 22038.8 22342.9 22402.6 22443.6 7884.0 7959.1 7986.0 8071.6 8107.8 4516.1 4580.6 4623.1 4655.0 4693.7 3601.7 3678.6 3921.4 4078.8 4135.6 732.7 733.7 737.2 741.7 743.8 685.6 721.8 722.6 742.9 756.4 10709.6 11011.8 11152.9 11301.3 11610.0 687.4 710.8 734.6 755.5 776.8 4771.7 4878.5 5083.9 5352.4 5386.8 108147.5 112443.4 113854.0 117240.0 118522.4 86718.0 87585.0 88152.6 89301.6 90134.5 88036.6 88213.0 90591.0 91061.0 92017.6 9959.6 10359.1 10378.5 10380.1 10411.0 52061.5 53136.0 53772.0 55344.7 55376.7 63184.7 63570.0 64894.4 70221.3 70741.0 13497.1 13604.0 13896.0 14112.6 14124.9 15046.7 15058.3 15065.4 15147.2 15910.9 14028.3 14364.3 14425.3 14427.5 15117.4 14151.0 14265.0 14372.1 14707.8 14738.6 14415.6 14654.5 14797.9 14840.7 14880.3 10078.5 10361.2 10788.6 11074.8 11103.6 10236.0 10245.0 11598.2 11827.4 12276.4 20:22:15 INFO - PROCESS | 3232 | -------- Summary: end -------- 20:22:15 INFO - PROCESS | 3232 | 20:22:16 INFO - TEST-INFO | 3232: exit 0 20:22:16 INFO - TEST-OK | dromaeo_css | took 1114572ms 20:22:16 INFO - TEST-START | kraken 20:22:16 INFO - operating with platform_type : mac_ 20:22:16 INFO - Initialising browser for kraken test... 20:22:16 INFO - TEST-INFO | started process 3237 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpa4zR6S/profile http://localhost:52031/getInfo.html) 20:22:19 INFO - PROCESS | 3237 | 2017-05-02 20:22:19.020 plugin-container[3239:58984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 20:22:19 INFO - PROCESS | 3237 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:19 INFO - PROCESS | 3237 | 2017-05-02 20:22:19.020 plugin-container[3239:58984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 20:22:19 INFO - PROCESS | 3237 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:19 INFO - PROCESS | 3237 | 2017-05-02 20:22:19.020 plugin-container[3239:58984] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:22:19 INFO - PROCESS | 3237 | __metrics Screen width/height:1600/1200 20:22:19 INFO - PROCESS | 3237 | colorDepth:24 20:22:19 INFO - PROCESS | 3237 | Browser inner width/height: 1024/689 20:22:19 INFO - PROCESS | 3237 | __metrics 20:22:19 ERROR - PROCESS | 3237 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 20:22:19 INFO - TEST-INFO | 3237: exit 0 20:22:19 INFO - Browser initialized. 20:22:19 INFO - Running cycle 1/1 for kraken test... 20:22:19 INFO - TEST-INFO | started process 3240 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpa4zR6S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:22:22 INFO - PROCESS | 3240 | 2017-05-02 20:22:22.033 plugin-container[3241:59107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 20:22:22 INFO - PROCESS | 3240 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:22 INFO - PROCESS | 3240 | 2017-05-02 20:22:22.035 plugin-container[3241:59107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 20:22:22 INFO - PROCESS | 3240 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:22 INFO - PROCESS | 3240 | 2017-05-02 20:22:22.035 plugin-container[3241:59107] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:22:31 INFO - PROCESS | 3240 | RSS: Main: 174657536 20:22:31 INFO - PROCESS | 3240 | 20:22:35 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52031/tests/kraken/driver.html?testName=audio-beat-detection) 20:22:35 INFO - PROCESS | 3240 | RSS: Main: 188039168 20:22:35 INFO - PROCESS | 3240 | 20:22:38 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52031/tests/kraken/driver.html?testName=audio-dft) 20:22:39 INFO - PROCESS | 3240 | RSS: Main: 195948544 20:22:39 INFO - PROCESS | 3240 | 20:22:42 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52031/tests/kraken/driver.html?testName=audio-fft) 20:22:43 INFO - PROCESS | 3240 | RSS: Main: 197419008 20:22:43 INFO - PROCESS | 3240 | 20:22:45 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52031/tests/kraken/driver.html?testName=audio-oscillator) 20:22:46 INFO - PROCESS | 3240 | RSS: Main: 199200768 20:22:46 INFO - PROCESS | 3240 | 20:22:48 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52031/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:22:48 INFO - PROCESS | 3240 | RSS: Main: 200921088 20:22:48 INFO - PROCESS | 3240 | 20:22:51 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52031/tests/kraken/driver.html?testName=imaging-darkroom) 20:22:52 INFO - PROCESS | 3240 | RSS: Main: 204595200 20:22:52 INFO - PROCESS | 3240 | 20:22:55 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52031/tests/kraken/driver.html?testName=imaging-desaturate) 20:22:56 INFO - PROCESS | 3240 | RSS: Main: 204103680 20:22:56 INFO - PROCESS | 3240 | 20:22:59 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52031/tests/kraken/driver.html?testName=json-parse-financial) 20:22:59 INFO - PROCESS | 3240 | RSS: Main: 204173312 20:22:59 INFO - PROCESS | 3240 | 20:23:01 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52031/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:23:02 INFO - PROCESS | 3240 | RSS: Main: 203612160 20:23:02 INFO - PROCESS | 3240 | 20:23:04 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:23:04 INFO - PROCESS | 3240 | RSS: Main: 203673600 20:23:04 INFO - PROCESS | 3240 | 20:23:07 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:23:07 INFO - PROCESS | 3240 | RSS: Main: 203755520 20:23:07 INFO - PROCESS | 3240 | 20:23:10 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:23:11 INFO - PROCESS | 3240 | RSS: Main: 204115968 20:23:11 INFO - PROCESS | 3240 | 20:23:14 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:23:14 INFO - PROCESS | 3240 | RSS: Main: 203845632 20:23:14 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | Cycle 1(1): loaded http://localhost:52031/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52031/tests/kraken/driver.html?testName=ai-astar) 20:23:16 INFO - PROCESS | 3240 | RSS: Main: 205713408 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | __start_tp_report 20:23:16 INFO - PROCESS | 3240 | _x_x_mozilla_page_load 20:23:16 INFO - PROCESS | 3240 | _x_x_mozilla_page_load_details 20:23:16 INFO - PROCESS | 3240 | |i|pagename|runs| 20:23:16 INFO - PROCESS | 3240 | |0;ai-astar;108;121;120;120;163;182;149;120;125;173 20:23:16 INFO - PROCESS | 3240 | |1;audio-beat-detection;140;98;118;176;136;143;161;99;161;99 20:23:16 INFO - PROCESS | 3240 | |2;audio-dft;267;280;196;198;222;232;235;252;195;204 20:23:16 INFO - PROCESS | 3240 | |3;audio-fft;85;60;73;111;88;53;70;84;84;76 20:23:16 INFO - PROCESS | 3240 | |4;audio-oscillator;87;73;106;104;104;79;82;73;71;80 20:23:16 INFO - PROCESS | 3240 | |5;imaging-gaussian-blur;88;89;88;96;87;89;89;88;103;138 20:23:16 INFO - PROCESS | 3240 | |6;imaging-darkroom;96;97;96;122;147;153;95;105;155;96 20:23:16 INFO - PROCESS | 3240 | |7;imaging-desaturate;98;92;138;143;90;90;91;90;91;138 20:23:16 INFO - PROCESS | 3240 | |8;json-parse-financial;65;63;64;68;68;67;68;67;67;68 20:23:16 INFO - PROCESS | 3240 | |9;json-stringify-tinderbox;56;63;97;92;90;58;57;58;58;61 20:23:16 INFO - PROCESS | 3240 | |10;stanford-crypto-aes;82;88;88;96;103;79;82;86;94;103 20:23:16 INFO - PROCESS | 3240 | |11;stanford-crypto-ccm;137;139;145;143;135;138;157;154;180;136 20:23:16 INFO - PROCESS | 3240 | |12;stanford-crypto-pbkdf2;166;166;166;167;168;167;167;165;174;166 20:23:16 INFO - PROCESS | 3240 | |13;stanford-crypto-sha256-iterative;75;92;83;78;66;64;63;64;64;64 20:23:16 INFO - PROCESS | 3240 | __end_tp_report 20:23:16 INFO - PROCESS | 3240 | __start_cc_report 20:23:16 INFO - PROCESS | 3240 | _x_x_mozilla_cycle_collect,973 20:23:16 INFO - PROCESS | 3240 | __end_cc_report 20:23:16 INFO - PROCESS | 3240 | __startTimestamp1493781796700__endTimestamp 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | ------- Summary: start ------- 20:23:16 INFO - PROCESS | 3240 | Number of tests: 14 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#0] ai-astar Cycles:10 Average:138.10 Median:137.00 stddev:26.32 (19.2%) stddev-sans-first:25.57 20:23:16 INFO - PROCESS | 3240 | Values: 108.0 121.0 120.0 120.0 163.0 182.0 149.0 120.0 125.0 173.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:168.50 stddev:28.55 (16.9%) stddev-sans-first:30.17 20:23:16 INFO - PROCESS | 3240 | Values: 140.0 98.0 118.0 176.0 136.0 143.0 161.0 99.0 161.0 99.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#2] audio-dft Cycles:10 Average:228.10 Median:233.50 stddev:30.72 (13.2%) stddev-sans-first:29.18 20:23:16 INFO - PROCESS | 3240 | Values: 267.0 280.0 196.0 198.0 222.0 232.0 235.0 252.0 195.0 204.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#3] audio-fft Cycles:10 Average:78.40 Median:80.00 stddev:16.16 (20.2%) stddev-sans-first:16.96 20:23:16 INFO - PROCESS | 3240 | Values: 85.0 60.0 73.0 111.0 88.0 53.0 70.0 84.0 84.0 76.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:76.00 stddev:13.80 (18.2%) stddev-sans-first:14.63 20:23:16 INFO - PROCESS | 3240 | Values: 87.0 73.0 106.0 104.0 104.0 79.0 82.0 73.0 71.0 80.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:88.50 stddev:15.74 (17.8%) stddev-sans-first:16.46 20:23:16 INFO - PROCESS | 3240 | Values: 88.0 89.0 88.0 96.0 87.0 89.0 89.0 88.0 103.0 138.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#6] imaging-darkroom Cycles:10 Average:116.20 Median:95.50 stddev:25.84 (27.1%) stddev-sans-first:26.35 20:23:16 INFO - PROCESS | 3240 | Values: 96.0 97.0 96.0 122.0 147.0 153.0 95.0 105.0 155.0 96.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#7] imaging-desaturate Cycles:10 Average:106.10 Median:90.50 stddev:23.32 (25.8%) stddev-sans-first:24.55 20:23:16 INFO - PROCESS | 3240 | Values: 98.0 92.0 138.0 143.0 90.0 90.0 91.0 90.0 91.0 138.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:67.50 stddev:1.84 (2.7%) stddev-sans-first:1.87 20:23:16 INFO - PROCESS | 3240 | Values: 65.0 63.0 64.0 68.0 68.0 67.0 68.0 67.0 67.0 68.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:62.00 stddev:16.77 (27.0%) stddev-sans-first:17.11 20:23:16 INFO - PROCESS | 3240 | Values: 56.0 63.0 97.0 92.0 90.0 58.0 57.0 58.0 58.0 61.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:87.00 stddev:8.58 (9.9%) stddev-sans-first:8.59 20:23:16 INFO - PROCESS | 3240 | Values: 82.0 88.0 88.0 96.0 103.0 79.0 82.0 86.0 94.0 103.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:144.00 stddev:14.00 (9.7%) stddev-sans-first:14.43 20:23:16 INFO - PROCESS | 3240 | Values: 137.0 139.0 145.0 143.0 135.0 138.0 157.0 154.0 180.0 136.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.00 stddev:2.53 (1.5%) stddev-sans-first:2.65 20:23:16 INFO - PROCESS | 3240 | Values: 166.0 166.0 166.0 167.0 168.0 167.0 167.0 165.0 174.0 166.0 20:23:16 INFO - PROCESS | 3240 | 20:23:16 INFO - PROCESS | 3240 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:70.50 stddev:10.19 (14.5%) stddev-sans-first:10.72 20:23:16 INFO - PROCESS | 3240 | Values: 75.0 92.0 83.0 78.0 66.0 64.0 63.0 64.0 64.0 64.0 20:23:16 INFO - PROCESS | 3240 | -------- Summary: end -------- 20:23:16 INFO - PROCESS | 3240 | 20:23:17 INFO - TEST-INFO | 3240: exit 0 20:23:17 INFO - TEST-OK | kraken | took 61221ms 20:23:17 INFO - SUITE-END | took 1175s 20:23:17 INFO - Completed test suite (00:19:36) 20:23:17 INFO - javascript benchmark 20:23:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7947.200964873462, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3008.2629755709854, 3594.9820250898742, 3596.838142283597, 3602.9639703602957, 3648.5074514940475, 6092.116643086755, 6159.3224745277985, 6190.442860142586, 6199.132121502993, 6202.689865506718, 6147.661878596687, 6170.444659980601, 6240.3759624037575, 6241.875162496747, 6251.937480625193, 4636.675432719711, 5454.890902181954, 5488.286522752037, 5569.053260945649, 5587.329520457551, 5598.2442370280105, 5606.887862242753, 5611.551075913917, 5619.7471113799975, 5625.831225063254, 5425.159100339453, 5587.078132108198, 5602.38373778884, 5614.29821272341, 5654.688992105433, 5443.292371991635, 5535.58483113766, 5552.278203833516, 5573.331200255955, 5598.272224610815, 4625.953740462586, 5454.972725136388, 5561.360443549003, 5579.0515612346, 5593.8321850344555, 6228.190335256433, 6237.033259844731, 6263.561550691454, 6277.403646653561, 6281.528885333618, 6293.30773614902, 6331.2719037310835, 6441.355864413568, 6465.062565927914, 6501.674916254168, 6608.405243528057, 6634.734610615618, 6647.268800431949, 6655.500837437212, 6665.100211471487, 6298.086777417248, 6303.653299068549, 6311.589746666452, 6326.398992095744, 6336.239651241881, 5619.831405057855, 6411.647359395278, 6582.473402127818, 6649.601023938482, 6690.096836926952, 6446.290908000116, 6498.220213574401, 6501.252355979028, 6501.577397469244, 6507.511936604774, 6607.537472376887, 6614.206295244505, 6618.63597502142, 6625.17185351831, 6640.800775976729, 6301.842453938688, 6337.366263373718, 6339.841503962346, 6503.089567460559, 6577.309382514863, 6421.839454013593, 6428.357164283534, 6439.80680579574, 6488.545801793924, 6515.7393704252245, 5135.768890399941, 5160.561352285098, 5201.531862132426, 5235.450277720861, 5312.495312945264, 4024.9195016099516, 4620.792064357112, 4667.089917466062, 4677.95322046782, 4690.273007683814, 6294.527910406738, 6305.274893387319, 6318.526110541728, 6323.620582765049, 6328.493720502441, 6392.4246817786625, 6423.4861211103935, 6531.575447595891, 6640.335966403321, 6659.567128136656, 6169.938300616936, 6277.497800175975, 6282.623042617458, 6301.621902685762, 6341.207349081365, 5710.114932185518, 5755.395683453177, 5790.594658373873, 5790.855228619234, 5794.768209271666, 5380.757865895966, 6550.868982620416, 6787.423069039187, 6791.93208067913, 6813.4208592270215, 5398.541124004503, 6524.151860258136, 6614.900776488453, 6623.6688165592495, 6650.068990341259, 5102.387713474407, 6494.058361537645, 6841.521093523605, 6859.1083159190985, 6868.690908909211, 6293.0, 6463.709133089116, 6484.805455836333, 6538.476921846146, 6619.569727967667, 5355.464453554613, 6371.362863713592, 6505.31694172124, 6590.9670451647435, 6616.66916654175, 6441.259255185713, 6443.9355606445215, 6452.677366131768, 6460.935390646221, 6462.870742585216, 6366.6180029198395, 6368.299487056513, 6437.034444833313, 6446.0975463434215, 6479.157709497735, 5308.946910531, 6162.938370616236, 6234.345393733593, 6263.060540918898, 6314.8737025258315, 6440.388163124496, 6445.6454894981225, 6454.290028097, 6455.580387274812, 6458.063580780667, 5141.25451809493, 6147.354527774519, 6194.659293738888, 6280.9685951569945, 6309.526785491014], "value": 5997.388368940269, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.7165133946421, 797.9945268961111, 858.9484630922143, 993.6522217223977, 998.7952469743703, 36238.81880590593, 40980.0, 42185.57814421855, 42245.57754422455, 42341.15317693648, 40145.598544014625, 40244.792656220285, 40442.595574044324, 40491.59508404915, 40526.18947621053, 8228.670853165866, 8377.664893404257, 11254.0, 11514.366709830956, 11685.649430517098, 11295.830562541569, 11351.545938162464, 11356.545738170504, 11372.545098196062, 11381.430928453585, 11336.716582085432, 11337.0, 11399.430028498584, 11415.429228538582, 11427.714307142347, 11281.661550153467, 11329.886701132966, 11347.319160850293, 11362.091032717362, 11391.943040284828, 10958.178136639704, 10996.615118470894, 11050.226484146113, 11070.723231919186, 11077.335359878432, 32337.676623233703, 32704.855330063332, 35605.82197089024, 35800.82099589511, 35829.82085089558, 4009.218202450523, 4017.6384125428854, 4025.4766880305665, 4043.2115737431213, 4071.5521292657786, 26427.603585946224, 27586.448271034667, 27884.16347509578, 27896.721032789614, 28001.29996750077, 3184.458642030837, 3243.805371677683, 3325.085601459618, 3342.7492938029745, 3441.6558344165633, 364697.0, 367091.0, 367229.0, 367242.0, 370373.1481342603, 46635.067298653834, 46845.531544684796, 46863.828404290165, 46902.76548617235, 47042.0, 47869.76065119652, 47983.0, 48087.278690819665, 48111.51888481071, 48112.27831582529, 14885.70228595422, 14901.552953411416, 14941.925290373478, 14946.0, 15001.549953501413, 12818.102732808615, 12827.422765975554, 12828.0, 12832.230066196058, 12851.228926264454, 11714.414279286002, 12345.814812777815, 12358.567450139382, 12374.505019799108, 12450.688732781753, 5803.709814509258, 5837.09525023622, 5849.9177652134495, 5874.73563689635, 5878.911816322759, 981.4307701533237, 1091.2906610703108, 1110.4059328259343, 1131.2533727739653, 1185.8852678482199, 30065.849670751944, 30359.115883990984, 30543.93096241621, 30939.84530077335, 31173.0, 1061.532925512772, 1068.1454836131063, 1105.8728246251785, 1115.7824224276187, 1116.1349953785882, 13405.0, 15157.393704251926, 15294.158821264715, 15510.21632512253, 15949.0, 359098.0, 439159.0, 440535.0, 442272.0, 443594.5640543553, 40767.388489172685, 49225.50774492281, 49550.0, 49650.0, 49764.0, 1307.3985966455537, 1466.5453709350136, 1486.6580686442178, 1511.833698293187, 1512.2136489025866, 28176.577351340155, 28411.71588284091, 28851.0, 29272.82908683629, 29521.557176641938, 42806.35790463083, 42863.78568107139, 42879.57120428756, 42951.785241074846, 43066.13867722565, 424785.0, 424796.0, 425396.8730156453, 425627.0, 426332.86833565636, 30574.847125765118, 30773.8461307692, 30909.536356955112, 31105.222369440944, 31191.84404077965, 39527.80236098801, 40361.79819100886, 41135.58864411317, 41272.587274126876, 41393.58606413897, 28133.43733125367, 28918.85540572368, 29078.56382154227, 29555.55666665045, 30011.69988300089, 28283.86864525396, 28654.713452865206, 28735.568966465937, 29085.7091429083, 29755.851220743756, 33701.0, 34318.65681343254, 34410.0, 34562.48156277708, 34653.48019779655, 22408.663870041637, 28566.0, 28629.0, 28861.85569072141, 28893.422131557672, 23604.0, 23715.88142059279, 23828.0, 23842.88078559596, 23949.521009580058], "value": 18773.06153360969, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.5866368286445, 443.76702231328557, 446.1723502902116, 447.84325486079854, 457.3688310132017, 19874.0, 20892.47768805779, 22097.779022209772, 22282.88858555709, 22403.0, 22787.430314242152, 22896.542069158608, 23756.88121559394, 23926.64110038351, 24055.278341649733, 6845.349691779273, 6928.168619765635, 7073.328033836803, 7163.892541611854, 7200.35196832285, 6976.058232138673, 6978.965105174467, 7023.2625574314725, 7043.718251269943, 7048.753293634723, 6848.589084654912, 6955.478339124561, 7100.183478899915, 7123.786286411416, 7125.5724656520515, 6987.371136597705, 6990.196127445332, 7058.364747172754, 7284.162321333061, 7618.8476230475635, 7290.890636640454, 7476.775696729107, 7502.8124296892465, 7524.774256772305, 7525.698972041112, 22969.540609187723, 27399.589006164922, 27527.761250743766, 27550.0, 28128.156155315373, 2688.8252263602803, 2961.7334439900515, 3038.5290280006634, 3054.770892183073, 3061.4183305171946, 16462.75305870413, 16608.335666573323, 16711.498655040366, 16903.577410564834, 17193.0, 2131.658934570477, 2158.653558537894, 2165.1897262341317, 2166.3717521918625, 2174.7716489768663, 168607.0, 168643.0, 171403.28596714, 178048.0, 182025.0, 21324.680129798064, 21526.923653817248, 21667.458313542287, 21737.78262217389, 21984.560308793734, 32848.0, 33140.83429582837, 33641.0, 33678.83160584181, 33712.0, 10309.74225644365, 10524.579016839241, 10719.892801072045, 10744.0, 10817.567297308176, 8774.956125219333, 8811.911880881238, 8893.599788009555, 9002.189802917637, 9082.40964337316, 7995.360371170293, 9793.804123917482, 9833.704988850346, 9961.501924903725, 9974.351667141613, 3652.0322114639644, 4364.60718535327, 4394.890127746768, 4423.668224883147, 4453.19842428366, 808.7775861638097, 874.4054043250651, 892.7678803511134, 1073.1843798712878, 1083.4582708645678, 20221.797782021993, 22138.446538836335, 22197.0, 22231.333060008226, 22478.763667998417, 1060.114804138553, 1064.2816099133054, 1093.9890601093887, 1104.9834252486219, 1110.211749657752, 10346.430946297874, 11227.045701115334, 11522.308661480338, 11582.189246752814, 11617.941910290394, 791910.0, 812438.0, 818939.0, 819321.0, 823863.0, 166516.0, 167773.16113419353, 168357.1582142106, 170580.32388542546, 171560.0, 1237.270010693695, 1317.1965101288206, 1330.6274243211917, 1336.7568161609602, 1347.7102422979106, 19937.401877943685, 20193.59612807765, 20595.4851128723, 20689.965501724553, 20843.0, 60709.0, 64354.678226608565, 65028.0, 65059.67470162619, 65263.694726106165, 79995.60002199952, 80165.19834801578, 85229.57385213034, 93029.0, 93801.5309923446, 9221.815563688822, 11656.53373865036, 11716.531338746354, 12005.63983080509, 12124.515019399125, 13018.15382000167, 13031.218126912416, 13046.34768261602, 13360.331983400596, 13456.0, 9904.405735655884, 10045.849312260176, 10099.69700908974, 10214.489275536045, 11318.037966772916, 10223.437710925673, 10295.0, 10511.159107271247, 10576.41829699374, 10733.355998640107, 9944.403335799876, 10324.277300588887, 11666.0, 11685.298882067103, 11978.880211197777, 11718.296902185895, 11898.524059037542, 11918.165728398928, 11996.460159292677, 12024.977876880563, 10371.58513659445, 10456.947715261374, 10672.57309707603, 11442.828357574477, 11677.357745324089], "value": 11997.436734259976, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.9857601423982, 1631.3800755712832, 1633.0773113191046, 1633.795775528059, 1637.1323198704683, 10144.59421623136, 10817.67546973591, 11390.145739069572, 11695.35675537845, 12048.337341446228, 11195.664130076091, 11378.431078446087, 11408.429578521082, 11420.200585958986, 11425.714357141076, 2486.452980344326, 2539.1366935242013, 2545.8217924745277, 2548.9362765930814, 2553.234029791055, 2487.2040946897, 2503.5118151960373, 2539.4890040426008, 2540.491901619674, 2543.631173479844, 2448.902043918241, 2489.4025433895827, 2528.8609126498036, 2540.199837051332, 2549.8852551635223, 2417.6494408310787, 2484.664570283007, 2492.763187497185, 2493.5137648158616, 2511.937201569957, 2477.3682710908784, 2480.7643273888953, 2508.648789169518, 2510.410053637394, 2510.723820379757, 11288.2662626929, 11451.541938322458, 11493.655190344303, 11514.251573647685, 11527.250728702607, 6032.306284777263, 6142.723577439026, 6213.844653883689, 6226.315105338409, 6239.594426362272, 3099.5815564898903, 3612.3858943979326, 3780.7542509736777, 3796.6393192646656, 3810.333191691443, 1225.4730465899659, 1230.1696354960366, 1267.3789842976967, 1282.7947528395412, 1283.5250957145915, 11826.704332391759, 13977.72044559103, 14282.143071415749, 15159.545213643609, 15792.842071579253, 4196.475440569929, 4814.422269327703, 4816.590589799836, 4882.340883980688, 4985.077760614298, 12215.572454964034, 12439.0, 12443.191192572454, 12449.190802597983, 12490.75018499625, 6165.815025549241, 6169.81490555284, 6177.536684748662, 6182.474489668429, 6184.907226391608, 5347.41178470368, 5490.0, 5584.692841893735, 5594.328680558359, 5612.07400778875, 3982.561918188997, 4048.4129801178665, 4077.041895154636, 4118.135191609796, 4127.979360103181, 947.5025611553989, 1083.9458027098613, 1087.5541028178409, 1097.1771171621283, 1097.9780404391868, 823.6787652815407, 840.815020695446, 862.094800459515, 872.8603423452217, 886.4548302793864, 6613.14029176204, 6638.4357329626555, 6773.288804675537, 6779.457643388517, 6912.861742765116, 913.5204017890661, 914.6478605736839, 921.2031592672424, 927.2813569483704, 937.2361525356812, 4144.854930077433, 4220.915581688349, 4233.089885674594, 4246.596573325529, 4346.434963454731, 12166.391680415943, 13979.951503637269, 14018.649533761738, 14323.498677546444, 14354.784678229835, 12281.754364912651, 13325.267110309027, 14026.298685065705, 14312.928435357964, 14386.35261413239, 7988.480748751429, 10673.35959842412, 10754.0, 10795.46022698862, 10798.838017429745, 6761.290064543152, 6793.66031698423, 6794.72821087151, 6922.619255940972, 7037.296270372922, 10043.849342260019, 12443.315617641116, 12458.190217636187, 12595.24428534291, 12608.495660173858, 12705.618831435071, 12738.554150605056, 12792.360381980678, 12795.296258705861, 12960.351982400654, 9477.71566852968, 9479.715608531755, 9485.004074572347, 9552.85670714926, 9577.952110239405, 10362.274640775073, 10375.481225938824, 10479.899610540784, 10480.94759526228, 10507.579696812041, 8913.37606367574, 8976.147266009719, 9000.81998360016, 9019.819603608023, 9032.593533291149, 8593.785155371166, 8685.869711954452, 8698.782530436789, 8814.427062240933, 8881.73354799357, 7972.720954766555, 7986.480878742863, 8026.799330016796, 8091.352691784761, 8093.028836539653, 5382.865428364165, 5835.79574714883, 5957.970210149095, 6028.969855150696, 6062.696865156813, 6022.90965635507, 6255.0, 6290.119383286252, 6291.276503202191, 6331.0], "value": 5250.185736321852, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.69578314757734, 354.1924412339866, 385.6702519345959, 407.1023393417513, 408.17141203357176, 5243.947560524393, 5257.369115706116, 5279.841604751854, 5451.318585176853, 5588.6646801191955, 4648.558386953234, 5102.923456148161, 5136.691798492093, 5260.949907606245, 5279.577633789288, 3426.8629254829775, 3543.92912141757, 3838.2707285615643, 4388.047318943071, 4399.758013309267, 3876.5735769065523, 4068.7997040873015, 4075.8981025474304, 4214.346776249687, 4389.341598760179, 3453.481977703352, 3768.547774267092, 4169.102473780724, 4204.684648651348, 4404.69167158299, 3734.6825519830845, 4309.978450107761, 4360.563943605645, 4413.0, 4428.645708343325, 3757.906052348686, 4012.4181993611055, 4095.0, 4125.051238215197, 4126.525449573308, 4847.83032593861, 4938.62960277977, 5121.769520371592, 5219.634625576211, 5252.36971563411, 4707.882302942419, 5405.21624364465, 5423.701696406696, 5428.511433970962, 5472.781088756485, 5002.849914502571, 5333.679979201261, 5362.142057270857, 5717.655761137955, 5748.45389687979, 4647.070585882803, 4886.364772579577, 4913.705177689314, 4921.311016457699, 4944.9258261126115, 4571.977140114278, 4697.060587882403, 5064.088149314122, 5176.611754118456, 6007.978643630549, 4300.634446072057, 4534.45586529612, 5037.186612819382, 5058.64589478733, 5099.526729536172, 4268.996785755345, 5007.549320561167, 5158.0, 5168.2764412982215, 5181.419667001525, 4611.0, 4737.911783358437, 5161.974190129025, 5261.973690131525, 5416.431274716098, 5392.703401312887, 5667.4615911488345, 5682.54539636828, 5687.687177205211, 5740.770369185186, 1643.2933838449342, 1656.4699296225333, 1690.8140104588485, 1777.4934143768965, 1784.5449410400524, 1212.9150959432754, 1228.3551135653674, 1264.1403845385225, 1338.9154784624486, 1361.46902707944, 4870.756462176877, 4912.680675756064, 4919.917618124007, 4928.67963582366, 5139.6335400312855, 5124.718140502308, 5227.457899919533, 5911.4975227105315, 5940.732667029994, 5972.313183983897, 4696.03731235096, 4815.374001379798, 4901.0, 5073.506505066042, 5375.172441369953, 2672.919812405631, 2782.8136783383316, 2937.839553376447, 3052.481078216664, 3114.306125201684, 5044.621653376012, 5245.868853278622, 5576.581756368208, 5651.886962260732, 5839.708014599253, 5519.724013799294, 5534.7786088556, 5914.940850591525, 5951.9107213391835, 5967.731452084667, 5192.144320623566, 5479.123460183436, 5716.628419152742, 6078.574499784972, 6119.020956647004, 5019.623528235324, 5149.87125321855, 5630.3525094613005, 5796.855078623068, 5801.245838041028, 4788.7366194859615, 4887.535684110004, 4945.826896058621, 5603.831885043455, 5753.1945527627, 5401.918971215357, 5882.941170588239, 5971.223740913827, 6135.570510064431, 6163.660998645118, 4974.980129073459, 5105.668131571584, 5113.79544818218, 5132.357645553537, 5181.0, 5274.9450109978925, 5442.673439593637, 5444.156155795699, 5789.594728368974, 5839.620424672383, 4635.351050852951, 5359.517643412111, 5666.829995100154, 5683.914741278802, 5698.059820129798, 4596.931046034245, 5128.97435512835, 5204.81397812927, 5258.421573626822, 5636.730696196114, 5129.384473863161, 5333.653312534673, 5498.45015498447, 5503.504684578407, 5514.310711161105, 4822.590079843112, 5669.603127781016, 5671.688057156731, 5676.4323567642905, 5694.772209111589, 5075.5178258065425, 5136.895567452866, 5375.704336261543, 5576.581756368208, 5592.077307244259], "value": 4348.7986441220555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.6050053971938, 773.0066864079652, 804.1717031457597, 890.0, 897.7306807957613, 15799.763003554956, 17581.29674813006, 17865.732014019784, 17913.193906274217, 17924.551886202837, 13694.726105477885, 15791.28939197736, 16490.92808967419, 16580.088095154762, 16625.83374166258, 1030.6032177611617, 1060.0035966191795, 1060.5174645536263, 1063.478895341281, 1069.9144068474504, 994.2692121290845, 1034.8913364096775, 1039.4075377035092, 1049.0977759127145, 1049.4385503755493, 1065.0893486069415, 1086.3699054548383, 1088.5210507376728, 1092.2136062035322, 1092.382803715902, 1075.3386667199616, 1081.23232504922, 1083.3932997521413, 1085.180688580121, 1089.2320913755823, 810.687885164216, 810.7648781853255, 814.250889181954, 815.355868863597, 816.3632366753941, 17058.061806600632, 17412.30350785967, 17659.470215893543, 18274.63450730978, 18401.907990460095, 14683.339249733788, 14901.92549037248, 14905.0, 15030.0, 15187.392504299814, 11989.760204795943, 12169.330686812222, 12189.268643881307, 12245.04047926647, 12472.750544989049, 3708.0, 3710.5361829771277, 3795.886123416302, 3798.7150963677836, 3810.79040652764, 122489.0, 128006.3599682005, 128610.0, 128840.0673989891, 129283.0, 9777.41335519871, 9781.559829807677, 9957.452340121217, 9993.350432221881, 10075.647352342632, 21857.453563661034, 22038.779612203674, 22342.888285558794, 22402.55194896093, 22443.551128977328, 7884.0, 7959.084705258853, 7985.960070199728, 8071.556064416513, 8107.83784324322, 4516.051629157913, 4580.564846339659, 4623.075384922961, 4654.953450465519, 4693.718376897397, 3601.711863051002, 3678.6137455567314, 3921.3725803871816, 4078.7960601969785, 4135.565765594569, 732.6776218463965, 733.7285204474272, 737.2075019354108, 741.6551303643723, 743.8289193485529, 685.6126288646899, 721.8195451137216, 722.631457956446, 742.9294217049371, 756.4175584799674, 10709.571617135383, 11011.779764404668, 11152.944235278772, 11301.26541774782, 11610.0, 687.3607544983114, 710.754789597588, 734.5834138797599, 755.4522970846202, 776.7902666280186, 4771.73755443454, 4878.512148785164, 5083.87290317745, 5352.411234764173, 5386.8383948481605, 108147.45926270318, 112443.43778281056, 113854.0, 117240.0, 118522.4073879625, 86718.0, 87585.0, 88152.5592372034, 89301.55349223212, 90134.54932725425, 88036.5598172005, 88213.0, 90591.0, 91061.0, 92017.61973570536, 9959.601615935571, 10359.119474844421, 10378.481075946022, 10380.117689996132, 10411.0, 52061.47938520566, 53136.0, 53772.0, 55344.72327638336, 55376.72311638416, 63184.684076579324, 63570.0, 64894.35105648883, 70221.29778702352, 70741.0, 13497.055206135476, 13604.0, 13896.0, 14112.647183820076, 14124.858751412354, 15046.6990660184, 15058.322375493348, 15065.397384104514, 15147.242637867843, 15910.920445397698, 14028.2985850705, 14364.28178591087, 14425.278736063365, 14427.49503767363, 15117.395304187708, 14151.0, 14265.0, 14372.058434525781, 14707.779383309045, 14738.63153421173, 14415.567532974028, 14654.487092951695, 14797.92601036988, 14840.703185936436, 14880.330385132478, 10078.496075196357, 10361.22290828206, 10788.622398216025, 11074.833877492005, 11103.5558577656, 10236.020641231671, 10245.0, 11598.246114002563, 11827.40862956866, 12276.447559860006], "value": 8168.002320264846, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 120.0, 120.0, 163.0, 182.0, 149.0, 120.0, 125.0, 173.0], "value": 138.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 98.0, 118.0, 176.0, 136.0, 143.0, 161.0, 99.0, 161.0, 99.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [267.0, 280.0, 196.0, 198.0, 222.0, 232.0, 235.0, 252.0, 195.0, 204.0], "value": 228.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 60.0, 73.0, 111.0, 88.0, 53.0, 70.0, 84.0, 84.0, 76.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 106.0, 104.0, 104.0, 79.0, 82.0, 73.0, 71.0, 80.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 96.0, 87.0, 89.0, 89.0, 88.0, 103.0, 138.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 122.0, 147.0, 153.0, 95.0, 105.0, 155.0, 96.0], "value": 116.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 92.0, 138.0, 143.0, 90.0, 90.0, 91.0, 90.0, 91.0, 138.0], "value": 106.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 97.0, 92.0, 90.0, 58.0, 57.0, 58.0, 58.0, 61.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 88.0, 96.0, 103.0, 79.0, 82.0, 86.0, 94.0, 103.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 145.0, 143.0, 135.0, 138.0, 157.0, 154.0, 180.0, 136.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 166.0, 167.0, 168.0, 167.0, 167.0, 165.0, 174.0, 166.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 92.0, 83.0, 78.0, 66.0, 64.0, 63.0, 64.0, 64.0, 64.0], "value": 71.3, "unit": "score"}]}]} 20:23:17 INFO - Return code: 0 20:23:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:23:18 ERROR - # TBPL SUCCESS # 20:23:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:23:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:23:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:23:18 INFO - 2017-05-02 20:23:18.041 screenresolution[3245:59484] starting screenresolution argv=screenresolution get 20:23:18 INFO - 2017-05-02 20:23:18.043 screenresolution[3245:59484] Display 0: 1600x1200x32@60 20:23:18 INFO - 2017-05-02 20:23:18.049 screenresolution[3246:59487] starting screenresolution argv=screenresolution list 20:23:18 INFO - Available Modes on Display 0 20:23:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:23:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:23:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:23:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:23:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:23:18 INFO - Intel Iris: 20:23:18 INFO - Chipset Model: Intel Iris 20:23:18 INFO - Type: GPU 20:23:18 INFO - Bus: Built-In 20:23:18 INFO - VRAM (Dynamic, Max): 1536 MB 20:23:18 INFO - Vendor: Intel (0x8086) 20:23:18 INFO - Device ID: 0x0a2e 20:23:18 INFO - Revision ID: 0x0009 20:23:18 INFO - Displays: 20:23:18 INFO - Display: 20:23:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:23:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:23:18 INFO - Display Serial Number: 1600x1200 60 20:23:18 INFO - Main Display: Yes 20:23:18 INFO - Mirror: Off 20:23:18 INFO - Online: Yes 20:23:18 INFO - Rotation: Supported 20:23:18 INFO - Return code: 0 20:23:18 INFO - Running post-action listener: _resource_record_post_action 20:23:18 INFO - [mozharness: 2017-05-03 03:23:18.284830Z] Finished run-tests step (success) 20:23:18 INFO - Running post-run listener: _resource_record_post_run 20:23:18 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 1093632; Write bytes: 424611328; Read time: 11; Write time: 1439 20:23:18 INFO - TinderboxPrint: CPU usage
54.4% 20:23:18 INFO - TinderboxPrint: I/O read bytes / time
1,093,632 / 11 20:23:18 INFO - TinderboxPrint: I/O write bytes / time
424,611,328 / 1,439 20:23:18 INFO - TinderboxPrint: CPU idle
2,175.8 (45.6%) 20:23:18 INFO - TinderboxPrint: CPU system
956.4 (20.1%) 20:23:18 INFO - TinderboxPrint: CPU user
1,637.5 (34.3%) 20:23:18 INFO - TinderboxPrint: Swap in / out
122,650,624 / 0 20:23:18 INFO - install - Wall time: 15s; CPU: 55.0%; Read bytes: 119286272; Write bytes: 115004928; Read time: 10700; Write time: 251 20:23:18 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1085440; Write bytes: 298539008; Read time: 11; Write time: 1149 20:23:18 INFO - Running post-run listener: _upload_blobber_files 20:23:18 INFO - Blob upload gear active. 20:23:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:23:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:23:18 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:23:18 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:23:19 INFO - (blobuploader) - INFO - Open directory for files ... 20:23:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:23:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:23:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:20 INFO - (blobuploader) - INFO - Done attempting. 20:23:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:23:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:21 INFO - (blobuploader) - INFO - Done attempting. 20:23:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:23:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:23:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:22 INFO - (blobuploader) - INFO - Done attempting. 20:23:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:23:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:23:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:24 INFO - (blobuploader) - INFO - Done attempting. 20:23:24 INFO - (blobuploader) - INFO - Iteration through files over. 20:23:24 INFO - Return code: 0 20:23:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:23:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:23:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/785bb287895f9da3f5d6ee79f209fb266813b631028ae4ae95cdcfb08fbce1f8ece09140de6234b5332d4b203348c425beafb2937c079b29e9820a5e53d4ad0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc060a2334ef3b51fd6b384a421033e4d2654b8d005c302ec7ab0166cfdf2d97ad96ea5479248476d55a310bc4200141805e5b932b7af3fbb42a4aed920055be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18a5a777470a56b8ab70195aac70c40ce8bba561eaaea341961dcd95a6ab3ed0d0beaa62c92c84281859002dad88621efe03a66dfaf20306d88240d3df1db1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dcb66a144800cc1873ea8d2c4c3aa3e59892b88f394b2b345dbcad53fea7cc3db748e2f0e962f9d119fee418ac392a8d00112cd63ab74fd60ce2b04fb868c4c"} 20:23:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:23:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:23:24 INFO - Contents: 20:23:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/785bb287895f9da3f5d6ee79f209fb266813b631028ae4ae95cdcfb08fbce1f8ece09140de6234b5332d4b203348c425beafb2937c079b29e9820a5e53d4ad0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc060a2334ef3b51fd6b384a421033e4d2654b8d005c302ec7ab0166cfdf2d97ad96ea5479248476d55a310bc4200141805e5b932b7af3fbb42a4aed920055be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18a5a777470a56b8ab70195aac70c40ce8bba561eaaea341961dcd95a6ab3ed0d0beaa62c92c84281859002dad88621efe03a66dfaf20306d88240d3df1db1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dcb66a144800cc1873ea8d2c4c3aa3e59892b88f394b2b345dbcad53fea7cc3db748e2f0e962f9d119fee418ac392a8d00112cd63ab74fd60ce2b04fb868c4c"} 20:23:24 INFO - Running post-run listener: copy_logs_to_upload_dir 20:23:24 INFO - Copying logs to upload dir... 20:23:24 INFO - mkdir: /builds/slave/test/build/upload/logs 20:23:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.169578 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-02 20:23:25.081880) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-05-02 20:23:25.085242) ========= 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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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/Mozilla-Beta/sha512/785bb287895f9da3f5d6ee79f209fb266813b631028ae4ae95cdcfb08fbce1f8ece09140de6234b5332d4b203348c425beafb2937c079b29e9820a5e53d4ad0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc060a2334ef3b51fd6b384a421033e4d2654b8d005c302ec7ab0166cfdf2d97ad96ea5479248476d55a310bc4200141805e5b932b7af3fbb42a4aed920055be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18a5a777470a56b8ab70195aac70c40ce8bba561eaaea341961dcd95a6ab3ed0d0beaa62c92c84281859002dad88621efe03a66dfaf20306d88240d3df1db1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dcb66a144800cc1873ea8d2c4c3aa3e59892b88f394b2b345dbcad53fea7cc3db748e2f0e962f9d119fee418ac392a8d00112cd63ab74fd60ce2b04fb868c4c"} build_url:https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/cn-6Tw3_R7OaTwqTObNhSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/785bb287895f9da3f5d6ee79f209fb266813b631028ae4ae95cdcfb08fbce1f8ece09140de6234b5332d4b203348c425beafb2937c079b29e9820a5e53d4ad0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc060a2334ef3b51fd6b384a421033e4d2654b8d005c302ec7ab0166cfdf2d97ad96ea5479248476d55a310bc4200141805e5b932b7af3fbb42a4aed920055be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18a5a777470a56b8ab70195aac70c40ce8bba561eaaea341961dcd95a6ab3ed0d0beaa62c92c84281859002dad88621efe03a66dfaf20306d88240d3df1db1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dcb66a144800cc1873ea8d2c4c3aa3e59892b88f394b2b345dbcad53fea7cc3db748e2f0e962f9d119fee418ac392a8d00112cd63ab74fd60ce2b04fb868c4c"}' ========= master_lag: 2.56 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-05-02 20:23:27.654097) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 20:23:27.654454) ========= 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.HBLBOSNXgp/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.Pxp98IeUTp/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.005211 ========= master_lag: 0.66 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 20:23:28.317815) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 20:23:28.318276) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 20:23:28.326359) ========= ========= Total master_lag: 3.52 =========