builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0001 starttime: 1474806158.89 results: success (0) buildid: 20160925030226 builduid: a63625bcc9e8401281e61cd368cc5121 revision: 29beaebdfaccbdaeb4c1ee5a43a9795ab015ef49 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:38.895055) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:38.895449) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:38.896001) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:39.114091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:39.114448) ========= 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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-25 05:22:39-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-09-25 05:22:39 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.554691 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:39.703262) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:39.703557) ========= 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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.020393 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:39.752356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:39.752662) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 29beaebdfaccbdaeb4c1ee5a43a9795ab015ef49 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 29beaebdfaccbdaeb4c1ee5a43a9795ab015ef49 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c4xLI6MQtH/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.HUr3oWOI9m/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-25 05:22:39,827 truncating revision to first 12 chars 2016-09-25 05:22:39,827 Setting DEBUG logging. 2016-09-25 05:22:39,827 attempt 1/10 2016-09-25 05:22:39,827 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/29beaebdfacc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-25 05:22:40,522 unpacking tar archive at: mozilla-central-29beaebdfacc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.939776 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:40.719792) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:40.720388) ========= script_repo_revision: 29beaebdfaccbdaeb4c1ee5a43a9795ab015ef49 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:40.721058) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:40.721978) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 05:22:40.741460) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-25 05:22:40.741778) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c4xLI6MQtH/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.HUr3oWOI9m/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:22:40 INFO - MultiFileLogger online at 20160925 05:22:40 in /builds/slave/test 05:22:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:22:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:22:40 INFO - {'append_to_log': False, 05:22:40 INFO - 'base_work_dir': '/builds/slave/test', 05:22:40 INFO - 'blob_upload_branch': 'Firefox', 05:22:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:22:40 INFO - 'branch': 'Firefox', 05:22:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:22:40 INFO - 'config_files': ('talos/mac_config.py',), 05:22:40 INFO - 'default_actions': ('clobber', 05:22:40 INFO - 'read-buildbot-config', 05:22:40 INFO - 'download-and-extract', 05:22:40 INFO - 'populate-webroot', 05:22:40 INFO - 'create-virtualenv', 05:22:40 INFO - 'install', 05:22:40 INFO - 'run-tests'), 05:22:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:22:40 INFO - 'download_minidump_stackwalk': True, 05:22:40 INFO - 'download_symbols': 'ondemand', 05:22:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:22:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:22:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:22:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:22:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:22:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:22:40 INFO - 'installer_path': 'installer.exe', 05:22:40 INFO - 'log_level': 'info', 05:22:40 INFO - 'log_name': 'talos', 05:22:40 INFO - 'log_to_console': True, 05:22:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:22:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:22:40 INFO - 'opt_config_files': (), 05:22:40 INFO - 'pip_index': False, 05:22:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:40 INFO - 'cmd': ('bash', 05:22:40 INFO - '-c', 05:22:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:40 INFO - 'enabled': True, 05:22:40 INFO - 'halt_on_failure': False, 05:22:40 INFO - 'name': 'check_screen_resolution'},), 05:22:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:40 INFO - 'cmd': ('bash', 05:22:40 INFO - '-c', 05:22:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:40 INFO - 'enabled': True, 05:22:40 INFO - 'halt_on_failure': False, 05:22:40 INFO - 'name': 'check_screen_resolution'},), 05:22:40 INFO - 'run_cmd_checks_enabled': True, 05:22:40 INFO - 'sps_profile': False, 05:22:40 INFO - 'sps_profile_interval': 0, 05:22:40 INFO - 'suite': 'dromaeojs-e10s', 05:22:40 INFO - 'system_bits': '32', 05:22:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:22:40 INFO - 'title': 't-yosemite-r7-0001', 05:22:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:22:40 INFO - 'use_talos_json': True, 05:22:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:22:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:22:40 INFO - 'work_dir': 'build'} 05:22:40 INFO - [mozharness: 2016-09-25 12:22:40.930508Z] Running clobber step. 05:22:40 INFO - Running pre-action listener: _resource_record_pre_action 05:22:40 INFO - Running main action method: clobber 05:22:40 INFO - rmtree: /builds/slave/test/build 05:22:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:22:41 INFO - Running post-action listener: _resource_record_post_action 05:22:41 INFO - [mozharness: 2016-09-25 12:22:41.122195Z] Finished clobber step (success) 05:22:41 INFO - [mozharness: 2016-09-25 12:22:41.122362Z] Running read-buildbot-config step. 05:22:41 INFO - Running pre-action listener: _resource_record_pre_action 05:22:41 INFO - Running main action method: read_buildbot_config 05:22:41 INFO - Using buildbot properties: 05:22:41 INFO - { 05:22:41 INFO - "project": "", 05:22:41 INFO - "product": "firefox", 05:22:41 INFO - "script_repo_revision": "production", 05:22:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:22:41 INFO - "repository": "", 05:22:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:22:41 INFO - "buildid": "20160925030226", 05:22:41 INFO - "stage_platform": "macosx64", 05:22:41 INFO - "basedir": "/builds/slave/test", 05:22:41 INFO - "nightly_build": "True", 05:22:41 INFO - "buildnumber": 299, 05:22:41 INFO - "slavename": "t-yosemite-r7-0001", 05:22:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:22:41 INFO - "platform": "yosemite_r7", 05:22:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:22:41 INFO - "branch": "mozilla-central", 05:22:41 INFO - "repo_path": "mozilla-central", 05:22:41 INFO - "revision": "29beaebdfaccbdaeb4c1ee5a43a9795ab015ef49", 05:22:41 INFO - "pgo_build": "False", 05:22:41 INFO - "builduid": "a63625bcc9e8401281e61cd368cc5121", 05:22:41 INFO - "slavebuilddir": "test" 05:22:41 INFO - } 05:22:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:22:41 INFO - Running post-action listener: _resource_record_post_action 05:22:41 INFO - [mozharness: 2016-09-25 12:22:41.125882Z] Finished read-buildbot-config step (success) 05:22:41 INFO - [mozharness: 2016-09-25 12:22:41.126028Z] Running download-and-extract step. 05:22:41 INFO - Running pre-action listener: _resource_record_pre_action 05:22:41 INFO - Running main action method: download_and_extract 05:22:41 INFO - mkdir: /builds/slave/test/build/tests 05:22: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')]} 05:22:41 INFO - trying https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:22:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:22:42 INFO - Downloaded 1199 bytes. 05:22:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:22:42 INFO - Using the following test package requirements: 05:22:42 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:22:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:22:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:22:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'jsshell-mac.zip'], 05:22:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:22:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:22:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:22:42 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:22:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:22:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:22:42 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:22:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:22:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:22:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:22:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:22:43 INFO - Expected file size: 16438995 05:22:50 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:22:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:22:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:22:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:22:50 INFO - Expected file size: 16438995 05:22:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:22:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:22:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:22:58 INFO - Expected file size: 14414059 05:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:00 INFO - trying https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:23:04 INFO - Downloaded 111715834 bytes. 05:23:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:23:04 INFO - mkdir: /builds/slave/test/properties 05:23:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:04 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:04 INFO - Contents: 05:23:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:23:05 INFO - Running post-action listener: _resource_record_post_action 05:23:05 INFO - Running post-action listener: set_extra_try_arguments 05:23:05 INFO - [mozharness: 2016-09-25 12:23:05.134351Z] Finished download-and-extract step (success) 05:23:05 INFO - [mozharness: 2016-09-25 12:23:05.134498Z] Running populate-webroot step. 05:23:05 INFO - Running pre-action listener: _resource_record_pre_action 05:23:05 INFO - Running main action method: populate_webroot 05:23:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:05 INFO - '--tppagecycles', 05:23:05 INFO - '7'], 05:23:05 INFO - 'tests': ['tsvgm']}}, 05:23:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['damp', 'tps']}, 05:23:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'tests': ['damp', 'tps']}, 05:23:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['dromaeo_dom']}, 05:23:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['basic_compositor_video']}, 05:23:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:23:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['a11yr', 05:23:05 INFO - 'ts_paint', 05:23:05 INFO - 'tpaint', 05:23:05 INFO - 'sessionrestore', 05:23:05 INFO - 'sessionrestore_no_auto_restore', 05:23:05 INFO - 'tabpaint']}, 05:23:05 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:05 INFO - 'ts_paint', 05:23:05 INFO - 'tpaint', 05:23:05 INFO - 'sessionrestore', 05:23:05 INFO - 'sessionrestore_no_auto_restore', 05:23:05 INFO - 'tabpaint']}, 05:23:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:05 INFO - 'ts_paint', 05:23:05 INFO - 'tpaint', 05:23:05 INFO - 'sessionrestore', 05:23:05 INFO - 'sessionrestore_no_auto_restore', 05:23:05 INFO - 'tabpaint']}, 05:23:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:05 INFO - 'ts_paint', 05:23:05 INFO - 'tpaint', 05:23:05 INFO - 'sessionrestore', 05:23:05 INFO - 'sessionrestore_no_auto_restore', 05:23:05 INFO - 'tabpaint']}, 05:23:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['a11yr', 05:23:05 INFO - 'ts_paint', 05:23:05 INFO - 'tpaint', 05:23:05 INFO - 'sessionrestore', 05:23:05 INFO - 'sessionrestore_no_auto_restore', 05:23:05 INFO - 'tabpaint']}, 05:23:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['a11yr', 05:23:05 INFO - 'ts_paint', 05:23:05 INFO - 'tpaint', 05:23:05 INFO - 'sessionrestore', 05:23:05 INFO - 'sessionrestore_no_auto_restore', 05:23:05 INFO - 'tabpaint']}, 05:23:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['tsvgx', 05:23:05 INFO - 'tsvgr_opacity', 05:23:05 INFO - 'tart', 05:23:05 INFO - 'tscrollx', 05:23:05 INFO - 'cart']}, 05:23:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:05 INFO - 'tsvgr_opacity', 05:23:05 INFO - 'tart', 05:23:05 INFO - 'tscrollx', 05:23:05 INFO - 'cart']}, 05:23:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'talos_options': ['--disable-e10s'], 05:23:05 INFO - 'tests': ['tp5o']}, 05:23:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'tests': ['tp5o']}, 05:23:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'talos_options': ['--disable-e10s', 05:23:05 INFO - '--xperf_path', 05:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:05 INFO - 'tests': ['tp5n']}, 05:23:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:05 INFO - 'talos_options': ['--xperf_path', 05:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:05 INFO - 'tests': ['tp5n']}}, 05:23:05 INFO - 'talos.zip': {'path': '', 05:23:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:05 INFO - Running post-action listener: _resource_record_post_action 05:23:05 INFO - [mozharness: 2016-09-25 12:23:05.153173Z] Finished populate-webroot step (success) 05:23:05 INFO - [mozharness: 2016-09-25 12:23:05.153260Z] Running create-virtualenv step. 05:23:05 INFO - Running pre-action listener: _resource_record_pre_action 05:23:05 INFO - Running main action method: create_virtualenv 05:23:05 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:05 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 05:23:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:05 INFO - Using real prefix '/tools/python27' 05:23:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:08 INFO - Installing pip.................done. 05:23:08 INFO - Return code: 0 05:23:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:08 INFO - 'HOME': '/Users/cltbld', 05:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:08 INFO - 'LOGNAME': 'cltbld', 05:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:08 INFO - 'MOZ_NO_REMOTE': '1', 05:23:08 INFO - 'NO_EM_RESTART': '1', 05:23:08 INFO - 'PAGER': '/bin/cat', 05:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:08 INFO - 'PWD': '/builds/slave/test', 05:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:08 INFO - 'SHELL': '/bin/bash', 05:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:08 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:08 INFO - 'USER': 'cltbld', 05:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:08 INFO - 'XPC_FLAGS': '0x0', 05:23:08 INFO - 'XPC_SERVICE_NAME': '0', 05:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:09 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 05:23:09 INFO - Cleaning up... 05:23:09 INFO - Return code: 0 05:23:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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', '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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:23: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 psutil>=3.1.1 05:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:09 INFO - 'HOME': '/Users/cltbld', 05:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:09 INFO - 'LOGNAME': 'cltbld', 05:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:09 INFO - 'MOZ_NO_REMOTE': '1', 05:23:09 INFO - 'NO_EM_RESTART': '1', 05:23:09 INFO - 'PAGER': '/bin/cat', 05:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:09 INFO - 'PWD': '/builds/slave/test', 05:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:09 INFO - 'SHELL': '/bin/bash', 05:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:09 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:09 INFO - 'USER': 'cltbld', 05:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:09 INFO - 'XPC_FLAGS': '0x0', 05:23:09 INFO - 'XPC_SERVICE_NAME': '0', 05:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:09 INFO - Downloading/unpacking psutil>=3.1.1 05:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:13 INFO - Installing collected packages: psutil 05:23:13 INFO - Running setup.py install for psutil 05:23:13 INFO - building 'psutil._psutil_osx' extension 05:23:13 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 05:23:13 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 05:23:13 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 05:23:13 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 05:23:13 INFO - building 'psutil._psutil_posix' extension 05:23:13 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 05:23:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:23:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:23:14 INFO - ^ 05:23:14 INFO - 1 warning generated. 05:23:14 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 05:23:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:14 INFO - Successfully installed psutil 05:23:14 INFO - Cleaning up... 05:23:14 INFO - Return code: 0 05:23:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:23:14 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')]} 05:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:14 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')]} 05:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:14 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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:14 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 05:23:14 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 05:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:14 INFO - 'HOME': '/Users/cltbld', 05:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:14 INFO - 'LOGNAME': 'cltbld', 05:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:14 INFO - 'MOZ_NO_REMOTE': '1', 05:23:14 INFO - 'NO_EM_RESTART': '1', 05:23:14 INFO - 'PAGER': '/bin/cat', 05:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:14 INFO - 'PWD': '/builds/slave/test', 05:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:14 INFO - 'SHELL': '/bin/bash', 05:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:14 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:14 INFO - 'USER': 'cltbld', 05:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:14 INFO - 'XPC_FLAGS': '0x0', 05:23:14 INFO - 'XPC_SERVICE_NAME': '0', 05:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:23:14 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 05:23:14 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 05:23:14 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 05:23:14 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 05:23:14 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 05:23:14 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 05:23:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:23:17 INFO - Installing collected packages: mozsystemmonitor 05:23:17 INFO - Running setup.py install for mozsystemmonitor 05:23:17 INFO - Successfully installed mozsystemmonitor 05:23:17 INFO - Cleaning up... 05:23:17 INFO - Return code: 0 05:23:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:23:17 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')]} 05:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:17 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')]} 05:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:17 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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:17 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 05:23:17 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 05:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:17 INFO - 'HOME': '/Users/cltbld', 05:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:17 INFO - 'LOGNAME': 'cltbld', 05:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:17 INFO - 'MOZ_NO_REMOTE': '1', 05:23:17 INFO - 'NO_EM_RESTART': '1', 05:23:17 INFO - 'PAGER': '/bin/cat', 05:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:17 INFO - 'PWD': '/builds/slave/test', 05:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:17 INFO - 'SHELL': '/bin/bash', 05:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:17 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:17 INFO - 'USER': 'cltbld', 05:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:17 INFO - 'XPC_FLAGS': '0x0', 05:23:17 INFO - 'XPC_SERVICE_NAME': '0', 05:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:17 INFO - Downloading/unpacking jsonschema==2.5.1 05:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:23:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:23:20 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 05:23:20 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 05:23:20 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 05:23:20 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 05:23:20 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 05:23:20 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 05:23:20 INFO - Downloading functools32-3.2.3-2.tar.gz 05:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:23:20 INFO - warning: no files found matching '*.txt' 05:23:20 INFO - no previously-included directories found matching 'build' 05:23:20 INFO - no previously-included directories found matching 'dist' 05:23:20 INFO - no previously-included directories found matching '.git*' 05:23:20 INFO - Installing collected packages: jsonschema, functools32 05:23:20 INFO - Running setup.py install for functools32 05:23:20 INFO - warning: no files found matching '*.txt' 05:23:20 INFO - no previously-included directories found matching 'build' 05:23:20 INFO - no previously-included directories found matching 'dist' 05:23:20 INFO - no previously-included directories found matching '.git*' 05:23:20 INFO - Successfully installed jsonschema functools32 05:23:20 INFO - Cleaning up... 05:23:20 INFO - Return code: 0 05:23:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:20 INFO - 'HOME': '/Users/cltbld', 05:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:20 INFO - 'LOGNAME': 'cltbld', 05:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:20 INFO - 'MOZ_NO_REMOTE': '1', 05:23:20 INFO - 'NO_EM_RESTART': '1', 05:23:20 INFO - 'PAGER': '/bin/cat', 05:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:20 INFO - 'PWD': '/builds/slave/test', 05:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:20 INFO - 'SHELL': '/bin/bash', 05:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:20 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:20 INFO - 'USER': 'cltbld', 05:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:20 INFO - 'XPC_FLAGS': '0x0', 05:23:20 INFO - 'XPC_SERVICE_NAME': '0', 05:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:23:21 INFO - Cleaning up... 05:23:21 INFO - Return code: 0 05:23:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:21 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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:21 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 05:23:21 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 05:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:21 INFO - 'HOME': '/Users/cltbld', 05:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:21 INFO - 'LOGNAME': 'cltbld', 05:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:21 INFO - 'MOZ_NO_REMOTE': '1', 05:23:21 INFO - 'NO_EM_RESTART': '1', 05:23:21 INFO - 'PAGER': '/bin/cat', 05:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:21 INFO - 'PWD': '/builds/slave/test', 05:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:21 INFO - 'SHELL': '/bin/bash', 05:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:21 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:21 INFO - 'USER': 'cltbld', 05:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:21 INFO - 'XPC_FLAGS': '0x0', 05:23:21 INFO - 'XPC_SERVICE_NAME': '0', 05:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:21 INFO - Downloading/unpacking blobuploader==1.2.4 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:23 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23:23 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 05:23:23 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 05:23:23 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 05:23:23 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 05:23:23 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 05:23:23 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 05:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:24 INFO - Downloading docopt-0.6.1.tar.gz 05:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:25 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:25 INFO - Running setup.py install for blobuploader 05:23:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:25 INFO - Running setup.py install for requests 05:23:25 INFO - Running setup.py install for docopt 05:23:25 INFO - Successfully installed blobuploader requests docopt 05:23:25 INFO - Cleaning up... 05:23:26 INFO - Return code: 0 05:23:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:26 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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:26 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 05:23:26 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 05:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:26 INFO - 'HOME': '/Users/cltbld', 05:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:26 INFO - 'LOGNAME': 'cltbld', 05:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:26 INFO - 'MOZ_NO_REMOTE': '1', 05:23:26 INFO - 'NO_EM_RESTART': '1', 05:23:26 INFO - 'PAGER': '/bin/cat', 05:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:26 INFO - 'PWD': '/builds/slave/test', 05:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:26 INFO - 'SHELL': '/bin/bash', 05:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:26 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:26 INFO - 'USER': 'cltbld', 05:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:26 INFO - 'XPC_FLAGS': '0x0', 05:23:26 INFO - 'XPC_SERVICE_NAME': '0', 05:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-l7C99I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8yYHbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ativVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-l5uvF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-g_4d_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-wyAqRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-f0Ir8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-xES5HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-rqxGSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-la0NkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-lpeRaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-a2NwVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8Ntfvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-vFZ3RB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-OO5nxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-6AG4Xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-nAKxD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:28 INFO - Running setup.py install for manifestparser 05:23:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:28 INFO - Running setup.py install for mozcrash 05:23:28 INFO - Running setup.py install for mozdebug 05:23:28 INFO - Running setup.py install for mozdevice 05:23:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:28 INFO - Running setup.py install for mozfile 05:23:28 INFO - Running setup.py install for mozhttpd 05:23:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:28 INFO - Running setup.py install for mozinfo 05:23:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Running setup.py install for mozInstall 05:23:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Running setup.py install for mozleak 05:23:29 INFO - Running setup.py install for mozlog 05:23:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Running setup.py install for moznetwork 05:23:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Running setup.py install for mozprocess 05:23:29 INFO - Running setup.py install for mozprofile 05:23:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:30 INFO - Running setup.py install for mozrunner 05:23:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:30 INFO - Running setup.py install for mozscreenshot 05:23:30 INFO - Running setup.py install for moztest 05:23:30 INFO - Running setup.py install for mozversion 05:23:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:30 INFO - Cleaning up... 05:23:30 INFO - Return code: 0 05:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:30 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')]} 05:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:30 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')]} 05:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:30 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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:30 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 05:23:30 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 05:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:30 INFO - 'HOME': '/Users/cltbld', 05:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:30 INFO - 'LOGNAME': 'cltbld', 05:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:30 INFO - 'MOZ_NO_REMOTE': '1', 05:23:30 INFO - 'NO_EM_RESTART': '1', 05:23:30 INFO - 'PAGER': '/bin/cat', 05:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:30 INFO - 'PWD': '/builds/slave/test', 05:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:30 INFO - 'SHELL': '/bin/bash', 05:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:30 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:30 INFO - 'USER': 'cltbld', 05:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:30 INFO - 'XPC_FLAGS': '0x0', 05:23:30 INFO - 'XPC_SERVICE_NAME': '0', 05:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-c5Lerx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-NPs1p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-uwgH5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-aH4O4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-KTfG0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-1Tnpfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-mK_u7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-0KH4K3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-bwhpfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-s2obWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:31 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)) 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-iWtfal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:32 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)) 05:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:32 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-VcbSyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:32 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-xWgGtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:32 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)) 05:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:32 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Pg6HeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:32 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-RLWaB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:32 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)) 05:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:32 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-uBGlOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:32 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-0HxEVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:32 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)) 05:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:32 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)) 05:23:32 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:23:32 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:23:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:32 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 05:23:32 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 05:23:32 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 05:23:32 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 05:23:32 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 05:23:32 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 05:23:35 INFO - Downloading blessings-1.6.tar.gz 05:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:35 INFO - Installing collected packages: blessings 05:23:35 INFO - Running setup.py install for blessings 05:23:35 INFO - Successfully installed blessings 05:23:35 INFO - Cleaning up... 05:23:35 INFO - Return code: 0 05:23:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:35 INFO - Reading from file tmpfile_stdout 05:23:35 INFO - Current package versions: 05:23:35 INFO - blessings == 1.6 05:23:35 INFO - blobuploader == 1.2.4 05:23:35 INFO - docopt == 0.6.1 05:23:35 INFO - functools32 == 3.2.3-2 05:23:35 INFO - jsonschema == 2.5.1 05:23:35 INFO - manifestparser == 1.1 05:23:35 INFO - mozInstall == 1.12 05:23:35 INFO - mozcrash == 0.17 05:23:35 INFO - mozdebug == 0.1 05:23:35 INFO - mozdevice == 0.48 05:23:35 INFO - mozfile == 1.2 05:23:35 INFO - mozhttpd == 0.7 05:23:35 INFO - mozinfo == 0.9 05:23:35 INFO - mozleak == 0.1 05:23:35 INFO - mozlog == 3.4 05:23:35 INFO - moznetwork == 0.27 05:23:35 INFO - mozprocess == 0.23 05:23:35 INFO - mozprofile == 0.28 05:23:35 INFO - mozrunner == 6.12 05:23:35 INFO - mozscreenshot == 0.1 05:23:35 INFO - mozsystemmonitor == 0.3 05:23:35 INFO - moztest == 0.7 05:23:35 INFO - mozversion == 1.4 05:23:35 INFO - psutil == 3.1.1 05:23:35 INFO - requests == 1.2.3 05:23:35 INFO - wsgiref == 0.1.2 05:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:35 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')]} 05:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:35 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')]} 05:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:35 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 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:35 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 05:23:35 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 05:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:35 INFO - 'HOME': '/Users/cltbld', 05:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:35 INFO - 'LOGNAME': 'cltbld', 05:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:35 INFO - 'MOZ_NO_REMOTE': '1', 05:23:35 INFO - 'NO_EM_RESTART': '1', 05:23:35 INFO - 'PAGER': '/bin/cat', 05:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:35 INFO - 'PWD': '/builds/slave/test', 05:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:35 INFO - 'SHELL': '/bin/bash', 05:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:35 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:35 INFO - 'USER': 'cltbld', 05:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:35 INFO - 'XPC_FLAGS': '0x0', 05:23:35 INFO - 'XPC_SERVICE_NAME': '0', 05:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 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)) 05:23:36 INFO - Cleaning up... 05:23:36 INFO - Return code: 0 05:23:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106936570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10bcddb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, '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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 05:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:36 INFO - 'HOME': '/Users/cltbld', 05:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:36 INFO - 'LOGNAME': 'cltbld', 05:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:36 INFO - 'MOZ_NO_REMOTE': '1', 05:23:36 INFO - 'NO_EM_RESTART': '1', 05:23:36 INFO - 'PAGER': '/bin/cat', 05:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:36 INFO - 'PWD': '/builds/slave/test', 05:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:36 INFO - 'SHELL': '/bin/bash', 05:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:23:36 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:36 INFO - 'USER': 'cltbld', 05:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:36 INFO - 'XPC_FLAGS': '0x0', 05:23:36 INFO - 'XPC_SERVICE_NAME': '0', 05:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:36 INFO - Cleaning up... 05:23:36 INFO - Return code: 0 05:23:36 INFO - Running post-action listener: _resource_record_post_action 05:23:36 INFO - Running post-action listener: _start_resource_monitoring 05:23:36 INFO - Starting resource monitoring. 05:23:36 INFO - [mozharness: 2016-09-25 12:23:36.426700Z] Finished create-virtualenv step (success) 05:23:36 INFO - [mozharness: 2016-09-25 12:23:36.427726Z] Running install step. 05:23:36 INFO - Running pre-action listener: _resource_record_pre_action 05:23:36 INFO - Running main action method: install 05:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:36 INFO - Reading from file tmpfile_stdout 05:23:36 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:36 INFO - Reading from file tmpfile_stdout 05:23:36 INFO - Output received: 05:23:36 INFO - Usage: mozinstall [options] installer 05:23:36 INFO - Options: 05:23:36 INFO - -h, --help show this help message and exit 05:23:36 INFO - -d DEST, --destination=DEST 05:23:36 INFO - Directory to install application into. [default: 05:23:36 INFO - "/builds/slave/test"] 05:23:36 INFO - --app=APP Application being installed. [default: firefox] 05:23:36 INFO - mkdir: /builds/slave/test/build/application 05:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:24:10 INFO - Reading from file tmpfile_stdout 05:24:10 INFO - Output received: 05:24:10 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:24:10 INFO - Running post-action listener: _resource_record_post_action 05:24:10 INFO - [mozharness: 2016-09-25 12:24:10.869300Z] Finished install step (success) 05:24:10 INFO - [mozharness: 2016-09-25 12:24:10.869442Z] Running run-tests step. 05:24:10 INFO - Running pre-action listener: _resource_record_pre_action 05:24:10 INFO - Running main action method: run_tests 05:24:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:10 INFO - 2016-09-25 05:24:10.884 screenresolution[2085:24825] starting screenresolution argv=screenresolution get 05:24:10 INFO - 2016-09-25 05:24:10.886 screenresolution[2085:24825] Display 0: 1600x1200x32@60 05:24:10 INFO - 2016-09-25 05:24:10.892 screenresolution[2086:24828] starting screenresolution argv=screenresolution list 05:24:10 INFO - Available Modes on Display 0 05:24:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:11 INFO - Intel Iris: 05:24:11 INFO - Chipset Model: Intel Iris 05:24:11 INFO - Type: GPU 05:24:11 INFO - Bus: Built-In 05:24:11 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:11 INFO - Vendor: Intel (0x8086) 05:24:11 INFO - Device ID: 0x0a2e 05:24:11 INFO - Revision ID: 0x0009 05:24:11 INFO - Displays: 05:24:11 INFO - Display: 05:24:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:11 INFO - Display Serial Number: 1600x1200 60 05:24:11 INFO - Main Display: Yes 05:24:11 INFO - Mirror: Off 05:24:11 INFO - Online: Yes 05:24:11 INFO - Rotation: Supported 05:24:11 INFO - Return code: 0 05:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:11 INFO - Python 2.7.3 05:24:11 INFO - Return code: 0 05:24:11 INFO - grabbing minidump binary from tooltool 05:24: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')]} 05:24:11 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 0x1067d4d30>, '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 0x1065fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067ccb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:11 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 05:24:11 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 05:24:11 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 05:24:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:11 INFO - Return code: 0 05:24:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:24:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:24:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 05:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0001 --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 05:24:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c4xLI6MQtH/Render', 05:24:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:11 INFO - 'HOME': '/Users/cltbld', 05:24:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:11 INFO - 'LOGNAME': 'cltbld', 05:24:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:24:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:11 INFO - 'MOZ_NO_REMOTE': '1', 05:24:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:11 INFO - 'NO_EM_RESTART': '1', 05:24:11 INFO - 'PAGER': '/bin/cat', 05:24:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:11 INFO - 'PWD': '/builds/slave/test', 05:24:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:11 INFO - 'SHELL': '/bin/bash', 05:24:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HUr3oWOI9m/Listeners', 05:24:11 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 05:24:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:11 INFO - 'USER': 'cltbld', 05:24:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:11 INFO - 'XPC_FLAGS': '0x0', 05:24:11 INFO - 'XPC_SERVICE_NAME': '0', 05:24:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 05:24:11 INFO - mozversion application_buildid: 20160925030226 05:24:11 INFO - mozversion application_changeset: 29beaebdfaccbdaeb4c1ee5a43a9795ab015ef49 05:24:11 INFO - mozversion application_display_name: Nightly 05:24:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:11 INFO - mozversion application_name: Firefox 05:24:11 INFO - mozversion application_remotingname: firefox 05:24:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:24:11 INFO - mozversion application_vendor: Mozilla 05:24:11 INFO - mozversion application_version: 52.0a1 05:24:11 INFO - mozversion platform_buildid: 20160925030226 05:24:11 INFO - mozversion platform_changeset: 29beaebdfaccbdaeb4c1ee5a43a9795ab015ef49 05:24:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:24:11 INFO - mozversion platform_version: 52.0a1 05:24:11 INFO - using testdate: 1474806251 05:24:11 INFO - actual date: 1474806251 05:24:11 INFO - starting webserver on 'localhost:49370' 05:24:11 INFO - SUITE-START | Running 2 tests 05:24:11 INFO - TEST-START | dromaeo_css 05:24:11 INFO - operating with platform_type : mac_ 05:24:11 INFO - Initialising browser for dromaeo_css test... 05:24:11 INFO - TEST-INFO | started process 2096 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpon4562/profile http://localhost:49370/getInfo.html) 05:24:14 INFO - PROCESS | 2096 | 2016-09-25 05:24:14.692 plugin-container[2099:24990] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e47, name = 'com.apple.tsm.portname' 05:24:14 INFO - PROCESS | 2096 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:14 INFO - PROCESS | 2096 | 2016-09-25 05:24:14.693 plugin-container[2099:24990] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 05:24:14 INFO - PROCESS | 2096 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:14 INFO - PROCESS | 2096 | 2016-09-25 05:24:14.693 plugin-container[2099:24990] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:14 INFO - PROCESS | 2096 | __metrics Screen width/height:1600/1200 05:24:14 INFO - PROCESS | 2096 | colorDepth:24 05:24:14 INFO - PROCESS | 2096 | Browser inner width/height: 1024/663 05:24:14 INFO - PROCESS | 2096 | __metrics 05:24:14 ERROR - PROCESS | 2096 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 05:24:15 INFO - TEST-INFO | 2096: exit 0 05:24:15 INFO - Browser initialized. 05:24:15 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:15 INFO - TEST-INFO | started process 2101 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpon4562/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:18 INFO - PROCESS | 2101 | 2016-09-25 05:24:18.131 plugin-container[2102:25113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8727, name = 'com.apple.tsm.portname' 05:24:18 INFO - PROCESS | 2101 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:18 INFO - PROCESS | 2101 | 2016-09-25 05:24:18.132 plugin-container[2102:25113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 05:24:18 INFO - PROCESS | 2101 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:18 INFO - PROCESS | 2101 | 2016-09-25 05:24:18.132 plugin-container[2102:25113] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:27 INFO - PROCESS | 2101 | RSS: Main: 249815040 05:24:27 INFO - PROCESS | 2101 | 05:25:17 INFO - PROCESS | 2101 | 1474806317386 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:27:29 INFO - PROCESS | 2101 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49370/tests/dromaeo/cssquery-ext.html) 05:27:30 INFO - PROCESS | 2101 | RSS: Main: 263249920 05:27:30 INFO - PROCESS | 2101 | 05:30:32 INFO - PROCESS | 2101 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-ext.html (next: http://localhost:49370/tests/dromaeo/cssquery-jquery.html) 05:30:33 INFO - PROCESS | 2101 | RSS: Main: 273543168 05:30:33 INFO - PROCESS | 2101 | 05:33:35 INFO - PROCESS | 2101 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49370/tests/dromaeo/cssquery-mootools.html) 05:33:36 INFO - PROCESS | 2101 | RSS: Main: 278085632 05:33:36 INFO - PROCESS | 2101 | 05:36:37 INFO - PROCESS | 2101 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49370/tests/dromaeo/cssquery-prototype.html) 05:36:38 INFO - PROCESS | 2101 | RSS: Main: 277692416 05:36:38 INFO - PROCESS | 2101 | 05:39:41 INFO - PROCESS | 2101 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49370/tests/dromaeo/cssquery-yui.html) 05:39:41 INFO - PROCESS | 2101 | RSS: Main: 283156480 05:39:41 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-yui.html (next: http://localhost:49370/tests/dromaeo/cssquery-dojo.html) 05:42:44 INFO - PROCESS | 2101 | RSS: Main: 286515200 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | __start_tp_report 05:42:44 INFO - PROCESS | 2101 | _x_x_mozilla_page_load 05:42:44 INFO - PROCESS | 2101 | _x_x_mozilla_page_load_details 05:42:44 INFO - PROCESS | 2101 | |i|pagename|runs| 05:42:44 INFO - PROCESS | 2101 | |0;dojo.html;3085.398347322272;3315.850786714596;3370.5618269624974;3371.730261579074;3382.2389962258494;5466.945330546693;5496.587755918307;5503;5514.172874068891;5538.667679939206;5314.867128321794;5520.779168833252;5594.356648985368;5693.003724348233;5714.628549144302;5077.289179514871;5099.770510327035;5117.692938423706;5200.141976573861;5212.270282160501;5121.359830021247;5204.115300398922;5218.191180367049;5236.057509648262;5291.814786482474;5031.396232452093;5082.23766435034;5149.17613181891;5183.352080989876;5207.244949482323;5110.667806592597;5121.078205922932;5151.5106064923775;5153.072446959546;5178.119719647669;5092.083424983502;5096.668716533413;5103.56619687327;5109.080365534202;5194.428612852583;5413.729313534347;5485.396606373296;5505.614606977513;5530.391656917735;5533.142362933751;5955.315138759055;5957.957357462426;5975.223220981288;6002.78990235344;6009.609375390585;6024.126501657235;6051;6051.062085376773;6081.969590152065;6094.421030002187;5605.243292155588;5610.607257491936;5611.551075913917;5620.213170156181;5707.6575405475805;6116.944513560587;6143.235544005126;6166.630002199792;6247.843803904848;6264.436200741864;5956.315023772322;5958.076498142795;5966.791162309299;5984.012637914695;6015.939840601625;5991.221141251609;6073.271207455045;6141.232345956755;6143.662098584531;6172.475339596095;6013.909791353133;6103.877922441527;6121.326654068049;6126.295476020226;6127.816165515042;6096.817095487142;6109.131392048963;6123.785667501616;6127.724252408652;6141.324454310023;4117.464729585135;4847.781849816767;4859.95140048595;4890.388701412324;4906.165951788204;4359.258925982591;4386.780660966939;4389.100234451929;4397.824087036547;4418;5637.774489020475;5685.772569097273;5688.573356998241;5748.798792042342;5799.8550036249435;6118.984682626245;6124.136831511461;6149.893019256488;6243.96878015607;6257.092721555349;5740.138979153077;5798.246227990418;5803.622764520293;5816.040353341736;5822.970885145547;5252.501012403815;5358.490943360374;5362.892742145135;5372.70450125239;5390.326209223847;4591.977040114778;4884.316195732671;5535.806246781504;6176.4441200292185;6200.131981522591;4480.350349199413;6208.192934918432;6226.25284965807;6236.968815155895;6254.343293954069;4713.340132381538;6273.9372606273355;6277.843053923689;6300.9684951574945;6301.4328710416285;6012.9098063529955;6060.060690592965;6077.939220607915;6102.176206212104;6117.816465506042;6170.413810688157;6196.039613859859;6198;6202.503799696104;6203.193584833943;5674.77300907959;5843.590948633555;6006.299825781244;6032.939670603238;6131.724072416663;4800.543948324904;4976.751162441791;5198.402183748766;6096.116063170904;6098.786542470993;5318.734063296897;5394.460553944731;5833.970830145822;5906.202662640489;5936.525077993837;5249.923765627961;6065.423784740443;6073.30157031947;6084.665343406155;6128.785492507741;5708.743106560131;5765.596408251382;5768.221290125779;5891.351951285441;5902.4097590240635 05:42:44 INFO - PROCESS | 2101 | |1;ext.html;493.9506049395061;511.5242824173519;514.1028904561541;534.9381477766633;545.6507834985607;31746.841265793668;41806.37290440642;42103.36844947328;42116.157676846444;42167.734967950935;42746.572534274645;42783;43228.567714322846;43261.35107973375;43275.78362108193;8671.609777559994;9912.752181195474;10122;10122.797544049115;10125.797484050314;9860.852087218698;9995.450250236234;9996.600135994551;10002.299839011272;10029.24780641451;10021.749456263578;10024.799504009952;10040.09639132478;10053.547590358416;10066.496675166249;9052.728418147466;9566.186874115709;9995.2003839693;10000.349977251492;10013.048760367754;9952.751181220456;10062.89937100627;10077.294589378746;10091.2431567632;10125.392476451363;40575.59424405748;40702.79648601768;40791.388129178085;40975.590244097475;41135.7943210282;3442.6901578857774;3562.0738607961857;3784.791836448994;4009.6190861868367;4042.514898212204;26041.73958260431;32129.357412851612;32438.675613243802;32690.836545817358;32876.50685239724;3265.2653153040374;3346.4311067118647;3370.1237678203597;3473.5484387029524;3476.5654293213347;463365;473058;473365.99999999657;473738;473892;31513.8424307877;60298.09552856711;60506.697466512385;60603;60890;34294.314113717584;35450.822745886624;35552.28895422077;35567.932962010666;35660;14627.19550424737;14663.780043299359;14781.630459238606;14787.778183327258;14798;12322.507099715911;12388.380580970916;12477.688057798629;12493.93753031229;12502.49990000408;7676.616169191518;7710.036245469317;7784.221577842283;7796.8050798729355;7811.296983271419;2753.8623068846478;2939.8089124207286;3218.11501836992;3258.169166862435;3316.2538428853795;820.7086484298108;961.5480724059681;1016.8357230970645;1039.4646756920192;1058.9523471443804;28227;30838.37873780709;30947;30966.845165774026;31037.534436983464;835.6657337065222;1013.6350913671073;1048.308116643012;1058.8517607534952;1060.067140915989;15768.448104316294;15978.60053498672;16118.274677639534;16316.592085197964;16394.83605163957;642668;642744;643176;645122;645804;50056.9988600226;52521.737391312796;57446;57587.71206143942;57764;1077.8329358949375;1343.348475989164;1371.945122195121;1378.4486205517874;1380.365032085252;28826.711732882402;29084.709152909047;29292.853535732185;29721.10836674903;30616.84691576528;35919.922402327975;46116.76941615405;48761;50296.24555631595;50409.73975650638;438651;475372.6231368821;475482.6225868848;476480;478424;21803.454913627287;26638.7336126644;27130.32174195661;27426.040088596805;27443;47964.7601761989;48615.027699446524;48984;49160;49235.75382123067;38889.6111038886;39458;40166;40928.386074209506;41241.381379279934;30378.696213037587;39385;39414.40878386769;39884.80345589637;40971.590284096776;38393;38441.231175375775;38490.42264365981;39271.41092883552;39527.60472395354;32821.835890820395;35385.245819715594;35579.82210088933;35638.8218058908;36003.91988240357;21715;24750;25544.744552554235;26054.73945260523;26333.341666457727 05:42:44 INFO - PROCESS | 2101 | |2;jquery.html;331.4845415379085;376.22122207031424;383.14940831354386;384.77065774849365;402.91740193260387;19746.407607771773;21373.35879923603;21748;21839.890800545996;22005.889970550124;17192.742108868377;19803.306884259055;20865.687014694755;21063.47341316464;21202.833844138568;4659.464161621406;5907.172995780594;6089.908651370233;6149.262088549381;6158.261008678965;6021.367756385582;6080.179175811275;6133.601315914473;6138.079288106797;6138.570300078996;5844.26946631671;6004.5796794224425;6045.758169673208;6057.303410107828;6066.848328791793;5891.116332550152;6051.818445446644;6053.061775424816;6072.726727297282;6084.634921904678;6009;6053.1828203192445;6130.877382452326;6132.233470816148;6150.8769824603705;13199.076064675477;18008;18465.538361540934;24892.626610600855;25859.61210581843;2150.193677370986;2160.546285280093;2267.6145055340467;2390.8685022323766;2439.780419762212;9056.094390560957;9873;10136.645217417356;10137.797244055151;10289.382637041801;1850.5003649014825;1939.7575303087115;1949.7229314798828;1956.902154892264;1966.9016549172627;116217;116264;117163;123917;127947.99999999907;9652.613895444103;11289.096872250364;11345.376004319842;11540.192186546861;11625.941870290764;14459.638509037359;14480;14499.710005800036;14514;14522.709545809235;8222.506649601042;8394.874076888851;8443.788905277417;8593.226609605166;8622.698205562901;8276.006879174538;8388.412811103164;8394.57292260305;8540.103289154673;8588.055313915464;3843.5964223756664;3935.2129574084724;4423.867283981485;4473.485549161823;4641.930371044437;1659.5104444188994;1675.2796297592151;1952.258141906095;1961.568454939911;1978.4855937456366;846.8221673448521;872.0712441250175;912.2337236721186;942.9764255893657;946.1248345280588;21141;21421.464463388227;21897.343079707633;21897.343079707633;21959.890200548896;795.6697970342359;861.8233262181237;865;908.5638893331238;911.2527727263713;10592.258541902147;11056.557737690564;11090.889091108986;11101.444927753742;11160.274582152135;559074;567870;569948;573774;584914;103494.4208196122;109152.4542377283;122785;129790;130200;965.5317171171976;1028.1671845804922;1072.4342460008475;1081.145894743144;1086.559943222996;19418.8058119417;19946.800531994493;20021;20398.898005509876;20428.897855510626;36681.08297292589;45492.545074548834;51479.485205147466;52733;57642;84345.31309373901;84744.57627711822;85019.72470412825;85476;85780.57109714411;7204.459665525212;8148.470349427268;8288.171182881904;8325.500469971821;8363.749087527383;10899.285398592534;13337.733245335234;13744.86255137436;13917.79123313131;13946.30268486592;11075;11429.142814288794;11708.238964467282;11785.528578857093;11840.76318473643;11345.489452974802;11367.886321136682;11554.248973817012;11608.88391116078;11687.298762074302;11657.708557286136;11756.823647645464;11943.641690749291;11960;11989.34058626784;7596.354309883501;8183.836323273382;8272.958635206785;8313.459625124347;8314;9597.23222142241;11049.33703977764;11698.415079245833;11859.34773587461;12078.27530348182 05:42:44 INFO - PROCESS | 2101 | |3;mootools.html;1387.944482220711;1515.704437634661;1595.9042457452547;1703.4463799265243;1843.4838245291314;7333.339999400053;8198.59007049648;8664.956675216623;9272.072792720734;9287.257019438437;8613.397062205648;8936.731898043054;8945.329100317469;9089.636414543427;9097.681581144661;2384.0225507541913;2406.554787364343;2410.8071354291615;2411.915582954597;2419.177479656916;2393.9521209575796;2420.491696743677;2434.233216536785;2435.3789783605157;2436.719777225615;2402.0511897800357;2403.702000919501;2421.225207933462;2425.527022230666;2430.5503481855912;2387.3196139100273;2389.976100238993;2394.0663141374876;2412.5054363855365;2416.9879150604224;2386.1648423051965;2389.784919357249;2393.389685630171;2397.2568503763887;2399.3042017814987;9079.546022698905;9092.181703646704;9095.590698418588;9129.863052054225;9131.680391186343;5232.39827419844;5375.865603359908;5437.456254374571;5456.672599644034;5474.178873169057;3129.671384504617;3509.666581674737;3712.610175931515;3727.589965103836;3759.981200094009;1075.4138994248153;1098.6099934523209;1113.2819331531198;1125.3360517294755;1126.0316128129803;11871.287722736577;11972.221805582696;12009.63971080869;12078.758424831542;12088.697782555419;4496.955030449653;4622.653301002371;4807.990322032342;4819.783109759999;4823.324734537167;10096.949515252376;10122.848157277647;10172.745681357877;10179.287449878586;10188.981101889753;5409.404965453797;5411.728243862689;5420.674759514442;5420.864478388072;5430.484104010113;4468.798904049326;4588.88527786808;4784.258439941815;4914.877128071756;4958.7024778513405;3920.666743326849;3960.821763020671;4017.352885317006;4070;4085.325921222965;1094.026316578246;1194.2416565480983;1295.4235365262368;1304.1979182802509;1308.3981368570373;776.7203806629609;873.5064688451005;894.3856339307482;937.5921474158763;944.763809047738;5722.399148089391;5940.6435613863305;5960.433758792908;6162.352952939967;6233.34549872266;780.7306479264645;787.220651554968;899.2042042792176;925.3383830561181;992.8163289791412;3098.643655979591;3879.282332768447;3908.6091390861257;4020;4057.797110144481;10763;11015.779684406427;11126.833097503544;11145.72135696614;11164.832527512093;11080.61217857371;11102.2783519071;11108.389038602954;11129.387883666475;11140.275882067639;7176.210616832144;7231;7242.456815738736;7254.6735396908325;8322.16778322175;4698.412698412699;5095.974520127375;5658.660480371191;5924.674142922181;5938.940610593839;9918.156956658764;10089.899101008896;10294.794104117726;10370.481475926023;10458.058774710313;8982.191602755784;9585.9041409585;10148;10646.520906559379;10663.4135122569;7098.858022839411;7142;7156.248593897566;7198.064251647253;7202.207757146605;8276.544790036605;8336.958315208385;8420.915790842013;8439;8441.071482136836;5835.503982161564;7465.104187497535;7558.58427786455;7587.848243034998;7728.574928378993;7295.424953533798;7367.705291788483;7449.180590134973;7523.88714169277;7534.39724822002;6925.411340036153;7048.400885924754;7068;7083.468739844429;7092.5389849659605;4966.453690094015;5111.565516931102;5154.948450515447;5200;5239.633225674166;5132.5124113209185;5146;5203.895922081461;5234.895302093861;5412.134058550613 05:42:44 INFO - PROCESS | 2101 | |4;prototype.html;250.93852006258464;299.3071040541147;309.6841221953607;314.2301361663924;314.60831265074984;4465.888352791174;5291.867703307419;5304.549113325367;5305.4164041955355;5307.761150748216;4069.532003819562;4306.437669586591;4689.296605509176;5262.4474430184855;5277.947220527793;4019.316716158253;4044.0092177416573;4049.6557792587664;4064.7764372959477;4073.87778366648;3736.701063914894;4060.8578699745517;4061.4923134608175;4061.675065994728;4095.1809638072355;3148.338848841746;3972.523297204325;3996.600339965995;4018.176273863851;4027.1140349123148;3476.9826150869335;3482.791032538043;3668.8715894943816;3992.9037255440835;4020.6783457323345;3046.588710524073;3405.7445691572984;3493.8602455901732;3510.630853966955;3528.1179705073732;4949.257611358325;5160.92258616121;5166.741662916839;5253.6322457428;5304.93107688002;4551.65862560306;4555.772211389417;4602.953970460286;4603.227757313425;4616.884577885579;5615.354234263031;5629.3526244482;5629.408912064216;5631.873625274961;5699.401562835884;3154.2272143324803;3340.599128104643;3403.5745531808525;3796.620337966209;3823;5843;5867.325257595388;5868.559858010623;5902.822915312505;5945.948647716574;3591.299696559146;3729.533808273966;3878.2243551289853;3892.377219644843;3901.297766402075;3269.7057264846276;3543.7596841105924;3939.803009849496;3949.5063117110362;3951.664108550748;3869.7484663496784;4063.654589359878;4113.91772164555;4157.812898419578;4525.366448697185;4665.3934988451965;5293.973530132324;5618.971905140448;5659.26429564154;5678.677973385533;1116.4975760907623;1184.390039129849;1258.3393718297966;1288.8131220972907;1333.999500374719;876.1950382212663;890.4523717913567;899.7179019896621;903.1014140929817;907.8502047162276;4696.624270058387;5179.456157103526;5207.270982062514;5225.372955245319;5346.786128554814;5632.239647647515;5668.518175955008;5670.249928784536;5704.3154821421695;5760.481556659921;4603.355530225771;4768.141734487756;4786.282057691375;5172.818951336685;5196.259253150161;2105.2631578947553;2488.9670786623346;2538.13703340865;2538.530371881171;2553.553128202594;4276.679249056333;4702.482726900038;4932.53140951609;4981.402231732215;5048.949510504922;3704.4258139988347;4299.78501074945;4730.455997560256;4805.318138651444;4854.732989685531;3921.9607803921594;4105.900045465714;4684.50812664679;4896.3389942358035;4987.202047672427;4156.875293741193;4268.911427585946;4707.7175369477945;4872.220444728827;4935.827246046371;3996.2616261926155;4503.459584849839;4558.148364631881;4786.904261914672;4851.320815085962;4312.331588603772;4832.9125946663125;4905.975470122627;4908.9754551227015;4960;2948.4545359108633;3007.1429642551766;3214.61424629046;3289.013296011139;3304.471284594453;4571.702839315434;4707.529247075265;4833.903321933612;4834.395700537433;4867.951320486892;4700.600448961876;5309.177077552986;5535.308086489189;5545.279113715353;5642.135137430873;5598.516393155898;5612.326520817528;5626.971865140648;5630.493255607015;5631.1271752878365;5240.5326508577655;5419.241306217212;5521.447855214607;5540.972295138498;5665.235193249024;5638.913601967299;5677.318721753416;5681.886362272814;5690.743916523849;5712.571557133148;5151.248575484354;5512.3385193777;5546.805861794818;5563.332400112013;5615.298087739033 05:42:44 INFO - PROCESS | 2101 | |5;yui.html;677.9084823548822;709.3934685843602;768.9307962283393;771.9807004824883;792.0099875156055;15094.698106037873;15399.230038498059;16059.75910361344;16081;16124.516264512069;14540.418383264683;14549;14671;14672.779908301358;14730.042547234421;837.5184269045293;849.2993280543561;876.1588874680315;882.4176043811086;885.954573603148;669.3808227389669;706.6078326528774;832.7085520067989;880.2825697056878;881.0924747510074;930.02812061396;930.7952250504878;934.4486752815837;945.4043952310035;946.6260826973376;935.5743136872707;936.1855185988165;937.5593471068621;938.6714649872524;941.2187884056251;650.5056157320423;683.3815397065664;690.1717938473852;695.8330000799772;696.195893742728;14641.41434342625;14679.119252844863;14681.559553213421;14698.779518307234;14703.55889323322;14371.281435928266;14422.855771442257;14482.131072135706;14493.565193044225;14735.115893046452;11801;11856.585019524362;11888.28670279786;12008.339541325224;12159.51361945521;3213.7589680773804;3221.387936292097;3862.5364956205153;3979.6617287530307;3987.2224916141363;113517.29724054146;115821;115890;117368.99999999914;119176.80823191744;10194.541245643964;10433.217508686726;10582.576696932036;11035.282706624044;11503.252288601214;17544.210510527057;20651.380458586267;20914.790852091286;20915.37253882386;20945.266915657878;7581.090269167735;7650.655720492591;7712.421568382281;7809.258120478546;7905.249001344863;4358.934615980763;4405.911881762347;4416.072624748775;4451.465824101128;4538.591526762541;3450.723942084627;3486.965130348664;3562.447820587813;3580.964190358063;3581.212133330663;626.6991843914947;638.8881945659491;639.434100820777;646.7865604350553;647.8801421736994;475.7502311286533;545.9208414779835;584.8128299551917;633.7465013994347;639.044628280714;8668.783280417914;9132.406393584395;9162.770930726652;9260.861087083698;9265.675701350421;565.1212364772817;575.1975993489059;592.9644221346733;621.9160413344231;634.3592971099223;3588.4079126944284;3885.7668539887695;3984.980075099606;4461.977690111528;4530.116627257653;117192.8280717199;119552;122136.77863221432;136654;139380;122450.38774806069;124397;124595;130366;132590;99436;104064.67482601374;106138.46930765451;114008;114217;9456.72334234897;9783.51082445889;9940.304178707422;10101.898981010096;10146.188304935735;48451;62330;62461.68769156125;63718.36281637124;63847.88541604348;92407;94019;94254.63199047963;95537.522312388;97522;12886.262983734612;12990.805137923127;13103.934480327538;13360;13746.518871839438;14525;14617.780733288797;14741.55775326742;14929.55211343618;15238.23808809533;13537.729245415234;13931.442742290601;14352.856471435152;14412.855871441152;14526.910481713703;13153.605391837877;13463.214611148342;13692;14144.858551414354;14339.641508962359;13519;13519;13659.931700341434;13695.383707732977;14062.296885155905;9000.149752907755;10007.649732259628;10331.431771252353;11129.777404451703;11340.945776289884;8207.876881846896;8671.566421678765;8785.514324855338;10187.941573390395;10686.305390149615 05:42:44 INFO - PROCESS | 2101 | __end_tp_report 05:42:44 INFO - PROCESS | 2101 | __start_cc_report 05:42:44 INFO - PROCESS | 2101 | _x_x_mozilla_cycle_collect,357 05:42:44 INFO - PROCESS | 2101 | __end_cc_report 05:42:44 INFO - PROCESS | 2101 | __startTimestamp1474807364890__endTimestamp 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | ------- Summary: start ------- 05:42:44 INFO - PROCESS | 2101 | Number of tests: 6 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | [#0] dojo.html Cycles:165 Average:5584.99 Median:5748.80 stddev:646.53 (11.2%) stddev-sans-first:618.06 05:42:44 INFO - PROCESS | 2101 | Values: 3085.4 3315.9 3370.6 3371.7 3382.2 5466.9 5496.6 5503.0 5514.2 5538.7 5314.9 5520.8 5594.4 5693.0 5714.6 5077.3 5099.8 5117.7 5200.1 5212.3 5121.4 5204.1 5218.2 5236.1 5291.8 5031.4 5082.2 5149.2 5183.4 5207.2 5110.7 5121.1 5151.5 5153.1 5178.1 5092.1 5096.7 5103.6 5109.1 5194.4 5413.7 5485.4 5505.6 5530.4 5533.1 5955.3 5958.0 5975.2 6002.8 6009.6 6024.1 6051.0 6051.1 6082.0 6094.4 5605.2 5610.6 5611.6 5620.2 5707.7 6116.9 6143.2 6166.6 6247.8 6264.4 5956.3 5958.1 5966.8 5984.0 6015.9 5991.2 6073.3 6141.2 6143.7 6172.5 6013.9 6103.9 6121.3 6126.3 6127.8 6096.8 6109.1 6123.8 6127.7 6141.3 4117.5 4847.8 4860.0 4890.4 4906.2 4359.3 4386.8 4389.1 4397.8 4418.0 5637.8 5685.8 5688.6 5748.8 5799.9 6119.0 6124.1 6149.9 6244.0 6257.1 5740.1 5798.2 5803.6 5816.0 5823.0 5252.5 5358.5 5362.9 5372.7 5390.3 4592.0 4884.3 5535.8 6176.4 6200.1 4480.4 6208.2 6226.3 6237.0 6254.3 4713.3 6273.9 6277.8 6301.0 6301.4 6012.9 6060.1 6077.9 6102.2 6117.8 6170.4 6196.0 6198.0 6202.5 6203.2 5674.8 5843.6 6006.3 6032.9 6131.7 4800.5 4976.8 5198.4 6096.1 6098.8 5318.7 5394.5 5834.0 5906.2 5936.5 5249.9 6065.4 6073.3 6084.7 6128.8 5708.7 5765.6 5768.2 5891.4 5902.4 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | [#1] ext.html Cycles:180 Average:65479.40 Median:35649.41 stddev:142884.40 (400.8%) stddev-sans-first:143201.92 05:42:44 INFO - PROCESS | 2101 | Values: 494.0 511.5 514.1 534.9 545.7 31746.8 41806.4 42103.4 42116.2 42167.7 42746.6 42783.0 43228.6 43261.4 43275.8 8671.6 9912.8 10122.0 10122.8 10125.8 9860.9 9995.5 9996.6 10002.3 10029.2 10021.7 10024.8 10040.1 10053.5 10066.5 9052.7 9566.2 9995.2 10000.3 10013.0 9952.8 10062.9 10077.3 10091.2 10125.4 40575.6 40702.8 40791.4 40975.6 41135.8 3442.7 3562.1 3784.8 4009.6 4042.5 26041.7 32129.4 32438.7 32690.8 32876.5 3265.3 3346.4 3370.1 3473.5 3476.6 463365.0 473058.0 473366.0 473738.0 473892.0 31513.8 60298.1 60506.7 60603.0 60890.0 34294.3 35450.8 35552.3 35567.9 35660.0 14627.2 14663.8 14781.6 14787.8 14798.0 12322.5 12388.4 12477.7 12493.9 12502.5 7676.6 7710.0 7784.2 7796.8 7811.3 2753.9 2939.8 3218.1 3258.2 3316.3 820.7 961.5 1016.8 1039.5 1059.0 28227.0 30838.4 30947.0 30966.8 31037.5 835.7 1013.6 1048.3 1058.9 1060.1 15768.4 15978.6 16118.3 16316.6 16394.8 642668.0 642744.0 643176.0 645122.0 645804.0 50057.0 52521.7 57446.0 57587.7 57764.0 1077.8 1343.3 1371.9 1378.4 1380.4 28826.7 29084.7 29292.9 29721.1 30616.8 35919.9 46116.8 48761.0 50296.2 50409.7 438651.0 475372.6 475482.6 476480.0 478424.0 21803.5 26638.7 27130.3 27426.0 27443.0 47964.8 48615.0 48984.0 49160.0 49235.8 38889.6 39458.0 40166.0 40928.4 41241.4 30378.7 39385.0 39414.4 39884.8 40971.6 38393.0 38441.2 38490.4 39271.4 39527.6 32821.8 35385.2 35579.8 35638.8 36003.9 21715.0 24750.0 25544.7 26054.7 26333.3 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | [#2] jquery.html Cycles:180 Average:34167.54 Median:2329.24 stddev:95512.57 (4100.6%) stddev-sans-first:95746.71 05:42:44 INFO - PROCESS | 2101 | Values: 331.5 376.2 383.1 384.8 402.9 19746.4 21373.4 21748.0 21839.9 22005.9 17192.7 19803.3 20865.7 21063.5 21202.8 4659.5 5907.2 6089.9 6149.3 6158.3 6021.4 6080.2 6133.6 6138.1 6138.6 5844.3 6004.6 6045.8 6057.3 6066.8 5891.1 6051.8 6053.1 6072.7 6084.6 6009.0 6053.2 6130.9 6132.2 6150.9 13199.1 18008.0 18465.5 24892.6 25859.6 2150.2 2160.5 2267.6 2390.9 2439.8 9056.1 9873.0 10136.6 10137.8 10289.4 1850.5 1939.8 1949.7 1956.9 1966.9 116217.0 116264.0 117163.0 123917.0 127948.0 9652.6 11289.1 11345.4 11540.2 11625.9 14459.6 14480.0 14499.7 14514.0 14522.7 8222.5 8394.9 8443.8 8593.2 8622.7 8276.0 8388.4 8394.6 8540.1 8588.1 3843.6 3935.2 4423.9 4473.5 4641.9 1659.5 1675.3 1952.3 1961.6 1978.5 846.8 872.1 912.2 943.0 946.1 21141.0 21421.5 21897.3 21897.3 21959.9 795.7 861.8 865.0 908.6 911.3 10592.3 11056.6 11090.9 11101.4 11160.3 559074.0 567870.0 569948.0 573774.0 584914.0 103494.4 109152.5 122785.0 129790.0 130200.0 965.5 1028.2 1072.4 1081.1 1086.6 19418.8 19946.8 20021.0 20398.9 20428.9 36681.1 45492.5 51479.5 52733.0 57642.0 84345.3 84744.6 85019.7 85476.0 85780.6 7204.5 8148.5 8288.2 8325.5 8363.7 10899.3 13337.7 13744.9 13917.8 13946.3 11075.0 11429.1 11708.2 11785.5 11840.8 11345.5 11367.9 11554.2 11608.9 11687.3 11657.7 11756.8 11943.6 11960.0 11989.3 7596.4 8183.8 8273.0 8313.5 8314.0 9597.2 11049.3 11698.4 11859.3 12078.3 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | [#3] mootools.html Cycles:180 Average:5751.86 Median:4869.10 stddev:3260.36 (67.0%) stddev-sans-first:3253.01 05:42:44 INFO - PROCESS | 2101 | Values: 1387.9 1515.7 1595.9 1703.4 1843.5 7333.3 8198.6 8665.0 9272.1 9287.3 8613.4 8936.7 8945.3 9089.6 9097.7 2384.0 2406.6 2410.8 2411.9 2419.2 2394.0 2420.5 2434.2 2435.4 2436.7 2402.1 2403.7 2421.2 2425.5 2430.6 2387.3 2390.0 2394.1 2412.5 2417.0 2386.2 2389.8 2393.4 2397.3 2399.3 9079.5 9092.2 9095.6 9129.9 9131.7 5232.4 5375.9 5437.5 5456.7 5474.2 3129.7 3509.7 3712.6 3727.6 3760.0 1075.4 1098.6 1113.3 1125.3 1126.0 11871.3 11972.2 12009.6 12078.8 12088.7 4497.0 4622.7 4808.0 4819.8 4823.3 10096.9 10122.8 10172.7 10179.3 10189.0 5409.4 5411.7 5420.7 5420.9 5430.5 4468.8 4588.9 4784.3 4914.9 4958.7 3920.7 3960.8 4017.4 4070.0 4085.3 1094.0 1194.2 1295.4 1304.2 1308.4 776.7 873.5 894.4 937.6 944.8 5722.4 5940.6 5960.4 6162.4 6233.3 780.7 787.2 899.2 925.3 992.8 3098.6 3879.3 3908.6 4020.0 4057.8 10763.0 11015.8 11126.8 11145.7 11164.8 11080.6 11102.3 11108.4 11129.4 11140.3 7176.2 7231.0 7242.5 7254.7 8322.2 4698.4 5096.0 5658.7 5924.7 5938.9 9918.2 10089.9 10294.8 10370.5 10458.1 8982.2 9585.9 10148.0 10646.5 10663.4 7098.9 7142.0 7156.2 7198.1 7202.2 8276.5 8337.0 8420.9 8439.0 8441.1 5835.5 7465.1 7558.6 7587.8 7728.6 7295.4 7367.7 7449.2 7523.9 7534.4 6925.4 7048.4 7068.0 7083.5 7092.5 4966.5 5111.6 5154.9 5200.0 5239.6 5132.5 5146.0 5203.9 5234.9 5412.1 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | [#4] prototype.html Cycles:180 Average:4292.33 Median:4701.54 stddev:1348.36 (28.7%) stddev-sans-first:1317.58 05:42:44 INFO - PROCESS | 2101 | Values: 250.9 299.3 309.7 314.2 314.6 4465.9 5291.9 5304.5 5305.4 5307.8 4069.5 4306.4 4689.3 5262.4 5277.9 4019.3 4044.0 4049.7 4064.8 4073.9 3736.7 4060.9 4061.5 4061.7 4095.2 3148.3 3972.5 3996.6 4018.2 4027.1 3477.0 3482.8 3668.9 3992.9 4020.7 3046.6 3405.7 3493.9 3510.6 3528.1 4949.3 5160.9 5166.7 5253.6 5304.9 4551.7 4555.8 4603.0 4603.2 4616.9 5615.4 5629.4 5629.4 5631.9 5699.4 3154.2 3340.6 3403.6 3796.6 3823.0 5843.0 5867.3 5868.6 5902.8 5945.9 3591.3 3729.5 3878.2 3892.4 3901.3 3269.7 3543.8 3939.8 3949.5 3951.7 3869.7 4063.7 4113.9 4157.8 4525.4 4665.4 5294.0 5619.0 5659.3 5678.7 1116.5 1184.4 1258.3 1288.8 1334.0 876.2 890.5 899.7 903.1 907.9 4696.6 5179.5 5207.3 5225.4 5346.8 5632.2 5668.5 5670.2 5704.3 5760.5 4603.4 4768.1 4786.3 5172.8 5196.3 2105.3 2489.0 2538.1 2538.5 2553.6 4276.7 4702.5 4932.5 4981.4 5048.9 3704.4 4299.8 4730.5 4805.3 4854.7 3922.0 4105.9 4684.5 4896.3 4987.2 4156.9 4268.9 4707.7 4872.2 4935.8 3996.3 4503.5 4558.1 4786.9 4851.3 4312.3 4832.9 4906.0 4909.0 4960.0 2948.5 3007.1 3214.6 3289.0 3304.5 4571.7 4707.5 4833.9 4834.4 4868.0 4700.6 5309.2 5535.3 5545.3 5642.1 5598.5 5612.3 5627.0 5630.5 5631.1 5240.5 5419.2 5521.4 5541.0 5665.2 5638.9 5677.3 5681.9 5690.7 5712.6 5151.2 5512.3 5546.8 5563.3 5615.3 05:42:44 INFO - PROCESS | 2101 | 05:42:44 INFO - PROCESS | 2101 | [#5] yui.html Cycles:180 Average:24268.20 Median:3524.71 stddev:38087.51 (1080.6%) stddev-sans-first:38153.17 05:42:44 INFO - PROCESS | 2101 | Values: 677.9 709.4 768.9 772.0 792.0 15094.7 15399.2 16059.8 16081.0 16124.5 14540.4 14549.0 14671.0 14672.8 14730.0 837.5 849.3 876.2 882.4 886.0 669.4 706.6 832.7 880.3 881.1 930.0 930.8 934.4 945.4 946.6 935.6 936.2 937.6 938.7 941.2 650.5 683.4 690.2 695.8 696.2 14641.4 14679.1 14681.6 14698.8 14703.6 14371.3 14422.9 14482.1 14493.6 14735.1 11801.0 11856.6 11888.3 12008.3 12159.5 3213.8 3221.4 3862.5 3979.7 3987.2 113517.3 115821.0 115890.0 117369.0 119176.8 10194.5 10433.2 10582.6 11035.3 11503.3 17544.2 20651.4 20914.8 20915.4 20945.3 7581.1 7650.7 7712.4 7809.3 7905.2 4358.9 4405.9 4416.1 4451.5 4538.6 3450.7 3487.0 3562.4 3581.0 3581.2 626.7 638.9 639.4 646.8 647.9 475.8 545.9 584.8 633.7 639.0 8668.8 9132.4 9162.8 9260.9 9265.7 565.1 575.2 593.0 621.9 634.4 3588.4 3885.8 3985.0 4462.0 4530.1 117192.8 119552.0 122136.8 136654.0 139380.0 122450.4 124397.0 124595.0 130366.0 132590.0 99436.0 104064.7 106138.5 114008.0 114217.0 9456.7 9783.5 9940.3 10101.9 10146.2 48451.0 62330.0 62461.7 63718.4 63847.9 92407.0 94019.0 94254.6 95537.5 97522.0 12886.3 12990.8 13103.9 13360.0 13746.5 14525.0 14617.8 14741.6 14929.6 15238.2 13537.7 13931.4 14352.9 14412.9 14526.9 13153.6 13463.2 13692.0 14144.9 14339.6 13519.0 13519.0 13659.9 13695.4 14062.3 9000.1 10007.6 10331.4 11129.8 11340.9 8207.9 8671.6 8785.5 10187.9 10686.3 05:42:44 INFO - PROCESS | 2101 | -------- Summary: end -------- 05:42:44 INFO - PROCESS | 2101 | 05:42:45 INFO - TEST-INFO | 2101: exit 0 05:42:45 INFO - TEST-OK | dromaeo_css | took 1114068ms 05:42:45 INFO - TEST-START | kraken 05:42:45 INFO - operating with platform_type : mac_ 05:42:45 INFO - Initialising browser for kraken test... 05:42:45 INFO - TEST-INFO | started process 2107 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmppFbiG0/profile http://localhost:49370/getInfo.html) 05:42:48 INFO - PROCESS | 2107 | 2016-09-25 05:42:48.165 plugin-container[2109:27165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 05:42:48 INFO - PROCESS | 2107 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:48 INFO - PROCESS | 2107 | 2016-09-25 05:42:48.165 plugin-container[2109:27165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 05:42:48 INFO - PROCESS | 2107 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:48 INFO - PROCESS | 2107 | 2016-09-25 05:42:48.165 plugin-container[2109:27165] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:48 INFO - PROCESS | 2107 | __metrics Screen width/height:1600/1200 05:42:48 INFO - PROCESS | 2107 | colorDepth:24 05:42:48 INFO - PROCESS | 2107 | Browser inner width/height: 1024/663 05:42:48 INFO - PROCESS | 2107 | __metrics 05:42:48 ERROR - PROCESS | 2107 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 05:42:49 INFO - TEST-INFO | 2107: exit 0 05:42:49 INFO - Browser initialized. 05:42:49 INFO - Running cycle 1/1 for kraken test... 05:42:49 INFO - TEST-INFO | started process 2110 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmppFbiG0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:51 INFO - PROCESS | 2110 | 2016-09-25 05:42:51.386 plugin-container[2111:27271] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x871f, name = 'com.apple.tsm.portname' 05:42:51 INFO - PROCESS | 2110 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:51 INFO - PROCESS | 2110 | 2016-09-25 05:42:51.386 plugin-container[2111:27271] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 05:42:51 INFO - PROCESS | 2110 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:51 INFO - PROCESS | 2110 | 2016-09-25 05:42:51.386 plugin-container[2111:27271] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:43:01 INFO - PROCESS | 2110 | RSS: Main: 245792768 05:43:01 INFO - PROCESS | 2110 | 05:43:03 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection) 05:43:04 INFO - PROCESS | 2110 | RSS: Main: 257150976 05:43:04 INFO - PROCESS | 2110 | 05:43:07 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-dft) 05:43:08 INFO - PROCESS | 2110 | RSS: Main: 264638464 05:43:08 INFO - PROCESS | 2110 | 05:43:11 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-fft) 05:43:11 INFO - PROCESS | 2110 | RSS: Main: 263262208 05:43:11 INFO - PROCESS | 2110 | 05:43:13 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator) 05:43:14 INFO - PROCESS | 2110 | RSS: Main: 264257536 05:43:14 INFO - PROCESS | 2110 | 05:43:16 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:16 INFO - PROCESS | 2110 | RSS: Main: 264351744 05:43:16 INFO - PROCESS | 2110 | 05:43:19 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:20 INFO - PROCESS | 2110 | RSS: Main: 265437184 05:43:20 INFO - PROCESS | 2110 | 05:43:23 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:24 INFO - PROCESS | 2110 | RSS: Main: 265302016 05:43:24 INFO - PROCESS | 2110 | 05:43:27 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial) 05:43:27 INFO - PROCESS | 2110 | RSS: Main: 265875456 05:43:27 INFO - PROCESS | 2110 | 05:43:29 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:29 INFO - PROCESS | 2110 | RSS: Main: 265482240 05:43:29 INFO - PROCESS | 2110 | 05:43:32 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:32 INFO - PROCESS | 2110 | RSS: Main: 265547776 05:43:32 INFO - PROCESS | 2110 | 05:43:34 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:35 INFO - PROCESS | 2110 | RSS: Main: 265547776 05:43:35 INFO - PROCESS | 2110 | 05:43:38 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:39 INFO - PROCESS | 2110 | RSS: Main: 265568256 05:43:39 INFO - PROCESS | 2110 | 05:43:42 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:43:42 INFO - PROCESS | 2110 | RSS: Main: 265863168 05:43:42 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49370/tests/kraken/driver.html?testName=ai-astar) 05:43:44 INFO - PROCESS | 2110 | RSS: Main: 267722752 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | __start_tp_report 05:43:44 INFO - PROCESS | 2110 | _x_x_mozilla_page_load 05:43:44 INFO - PROCESS | 2110 | _x_x_mozilla_page_load_details 05:43:44 INFO - PROCESS | 2110 | |i|pagename|runs| 05:43:44 INFO - PROCESS | 2110 | |0;ai-astar;115;98;177;174;126;115;118;117;118;118 05:43:44 INFO - PROCESS | 2110 | |1;audio-beat-detection;143;116;112;147;112;111;112;116;170;134 05:43:44 INFO - PROCESS | 2110 | |2;audio-dft;199;199;205;227;198;196;196;195;197;199 05:43:44 INFO - PROCESS | 2110 | |3;audio-fft;81;57;59;83;60;61;60;61;77;72 05:43:44 INFO - PROCESS | 2110 | |4;audio-oscillator;89;71;85;75;68;69;86;70;71;85 05:43:44 INFO - PROCESS | 2110 | |5;imaging-gaussian-blur;89;85;89;85;90;133;93;74;136;134 05:43:44 INFO - PROCESS | 2110 | |6;imaging-darkroom;93;101;95;97;155;123;94;94;94;96 05:43:44 INFO - PROCESS | 2110 | |7;imaging-desaturate;91;93;89;122;142;101;97;148;143;91 05:43:44 INFO - PROCESS | 2110 | |8;json-parse-financial;57;57;56;72;74;70;58;58;58;58 05:43:44 INFO - PROCESS | 2110 | |9;json-stringify-tinderbox;68;73;69;99;111;109;102;104;60;71 05:43:44 INFO - PROCESS | 2110 | |10;stanford-crypto-aes;89;86;86;88;89;87;87;91;87;87 05:43:44 INFO - PROCESS | 2110 | |11;stanford-crypto-ccm;161;158;159;140;144;162;148;148;167;128 05:43:44 INFO - PROCESS | 2110 | |12;stanford-crypto-pbkdf2;220;231;182;174;173;168;174;172;193;230 05:43:44 INFO - PROCESS | 2110 | |13;stanford-crypto-sha256-iterative;69;69;69;70;68;70;71;67;71;85 05:43:44 INFO - PROCESS | 2110 | __end_tp_report 05:43:44 INFO - PROCESS | 2110 | __start_cc_report 05:43:44 INFO - PROCESS | 2110 | _x_x_mozilla_cycle_collect,928 05:43:44 INFO - PROCESS | 2110 | __end_cc_report 05:43:44 INFO - PROCESS | 2110 | __startTimestamp1474807424775__endTimestamp 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | ------- Summary: start ------- 05:43:44 INFO - PROCESS | 2110 | Number of tests: 14 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#0] ai-astar Cycles:10 Average:127.60 Median:122.00 stddev:26.20 (21.5%) stddev-sans-first:27.39 05:43:44 INFO - PROCESS | 2110 | Values: 115.0 98.0 177.0 174.0 126.0 115.0 118.0 117.0 118.0 118.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:125.00 stddev:20.35 (16.3%) stddev-sans-first:20.77 05:43:44 INFO - PROCESS | 2110 | Values: 143.0 116.0 112.0 147.0 112.0 111.0 112.0 116.0 170.0 134.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#2] audio-dft Cycles:10 Average:201.10 Median:199.00 stddev:9.52 (4.8%) stddev-sans-first:10.06 05:43:44 INFO - PROCESS | 2110 | Values: 199.0 199.0 205.0 227.0 198.0 196.0 196.0 195.0 197.0 199.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#3] audio-fft Cycles:10 Average:67.10 Median:66.50 stddev:10.06 (15.1%) stddev-sans-first:9.33 05:43:44 INFO - PROCESS | 2110 | Values: 81.0 57.0 59.0 83.0 60.0 61.0 60.0 61.0 77.0 72.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:80.00 stddev:8.32 (10.4%) stddev-sans-first:7.58 05:43:44 INFO - PROCESS | 2110 | Values: 89.0 71.0 85.0 75.0 68.0 69.0 86.0 70.0 71.0 85.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#5] imaging-gaussian-blur Cycles:10 Average:100.80 Median:87.00 stddev:23.69 (27.2%) stddev-sans-first:24.74 05:43:44 INFO - PROCESS | 2110 | Values: 89.0 85.0 89.0 85.0 90.0 133.0 93.0 74.0 136.0 134.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:94.00 stddev:19.96 (21.2%) stddev-sans-first:20.76 05:43:44 INFO - PROCESS | 2110 | Values: 93.0 101.0 95.0 97.0 155.0 123.0 94.0 94.0 94.0 96.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#7] imaging-desaturate Cycles:10 Average:111.70 Median:90.00 stddev:24.44 (27.2%) stddev-sans-first:24.74 05:43:44 INFO - PROCESS | 2110 | Values: 91.0 93.0 89.0 122.0 142.0 101.0 97.0 148.0 143.0 91.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:58.00 stddev:7.13 (12.3%) stddev-sans-first:7.35 05:43:44 INFO - PROCESS | 2110 | Values: 57.0 57.0 56.0 72.0 74.0 70.0 58.0 58.0 58.0 58.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#9] json-stringify-tinderbox Cycles:10 Average:86.60 Median:68.50 stddev:19.95 (29.1%) stddev-sans-first:19.99 05:43:44 INFO - PROCESS | 2110 | Values: 68.0 73.0 69.0 99.0 111.0 109.0 102.0 104.0 60.0 71.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.50 stddev:1.57 (1.8%) stddev-sans-first:1.59 05:43:44 INFO - PROCESS | 2110 | Values: 89.0 86.0 86.0 88.0 89.0 87.0 87.0 91.0 87.0 87.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:158.50 stddev:12.04 (7.6%) stddev-sans-first:12.27 05:43:44 INFO - PROCESS | 2110 | Values: 161.0 158.0 159.0 140.0 144.0 162.0 148.0 148.0 167.0 128.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.70 Median:187.50 stddev:25.46 (13.6%) stddev-sans-first:24.86 05:43:44 INFO - PROCESS | 2110 | Values: 220.0 231.0 182.0 174.0 173.0 168.0 174.0 172.0 193.0 230.0 05:43:44 INFO - PROCESS | 2110 | 05:43:44 INFO - PROCESS | 2110 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:70.00 stddev:5.11 (7.3%) stddev-sans-first:5.37 05:43:44 INFO - PROCESS | 2110 | Values: 69.0 69.0 69.0 70.0 68.0 70.0 71.0 67.0 71.0 85.0 05:43:44 INFO - PROCESS | 2110 | -------- Summary: end -------- 05:43:44 INFO - PROCESS | 2110 | 05:43:45 INFO - TEST-INFO | 2110: exit 0 05:43:45 INFO - TEST-OK | kraken | took 60035ms 05:43:45 INFO - SUITE-END | took 1174s 05:43:45 INFO - Completed test suite (00:19:34) 05:43:45 INFO - javascript benchmark 05:43:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7169.710558940767, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3085.398347322272, 3315.850786714596, 3370.5618269624974, 3371.730261579074, 3382.2389962258494, 5466.945330546693, 5496.587755918307, 5503.0, 5514.172874068891, 5538.667679939206, 5314.867128321794, 5520.779168833252, 5594.356648985368, 5693.003724348233, 5714.628549144302, 5077.289179514871, 5099.770510327035, 5117.692938423706, 5200.141976573861, 5212.270282160501, 5121.359830021247, 5204.115300398922, 5218.191180367049, 5236.057509648262, 5291.814786482474, 5031.396232452093, 5082.23766435034, 5149.17613181891, 5183.352080989876, 5207.244949482323, 5110.667806592597, 5121.078205922932, 5151.5106064923775, 5153.072446959546, 5178.119719647669, 5092.083424983502, 5096.668716533413, 5103.56619687327, 5109.080365534202, 5194.428612852583, 5413.729313534347, 5485.396606373296, 5505.614606977513, 5530.391656917735, 5533.142362933751, 5955.315138759055, 5957.957357462426, 5975.223220981288, 6002.78990235344, 6009.609375390585, 6024.126501657235, 6051.0, 6051.062085376773, 6081.969590152065, 6094.421030002187, 5605.243292155588, 5610.607257491936, 5611.551075913917, 5620.213170156181, 5707.6575405475805, 6116.944513560587, 6143.235544005126, 6166.630002199792, 6247.843803904848, 6264.436200741864, 5956.315023772322, 5958.076498142795, 5966.791162309299, 5984.012637914695, 6015.939840601625, 5991.221141251609, 6073.271207455045, 6141.232345956755, 6143.662098584531, 6172.475339596095, 6013.909791353133, 6103.877922441527, 6121.326654068049, 6126.295476020226, 6127.816165515042, 6096.817095487142, 6109.131392048963, 6123.785667501616, 6127.724252408652, 6141.324454310023, 4117.464729585135, 4847.781849816767, 4859.95140048595, 4890.388701412324, 4906.165951788204, 4359.258925982591, 4386.780660966939, 4389.100234451929, 4397.824087036547, 4418.0, 5637.774489020475, 5685.772569097273, 5688.573356998241, 5748.798792042342, 5799.8550036249435, 6118.984682626245, 6124.136831511461, 6149.893019256488, 6243.96878015607, 6257.092721555349, 5740.138979153077, 5798.246227990418, 5803.622764520293, 5816.040353341736, 5822.970885145547, 5252.501012403815, 5358.490943360374, 5362.892742145135, 5372.70450125239, 5390.326209223847, 4591.977040114778, 4884.316195732671, 5535.806246781504, 6176.4441200292185, 6200.131981522591, 4480.350349199413, 6208.192934918432, 6226.25284965807, 6236.968815155895, 6254.343293954069, 4713.340132381538, 6273.9372606273355, 6277.843053923689, 6300.9684951574945, 6301.4328710416285, 6012.9098063529955, 6060.060690592965, 6077.939220607915, 6102.176206212104, 6117.816465506042, 6170.413810688157, 6196.039613859859, 6198.0, 6202.503799696104, 6203.193584833943, 5674.77300907959, 5843.590948633555, 6006.299825781244, 6032.939670603238, 6131.724072416663, 4800.543948324904, 4976.751162441791, 5198.402183748766, 6096.116063170904, 6098.786542470993, 5318.734063296897, 5394.460553944731, 5833.970830145822, 5906.202662640489, 5936.525077993837, 5249.923765627961, 6065.423784740443, 6073.30157031947, 6084.665343406155, 6128.785492507741, 5708.743106560131, 5765.596408251382, 5768.221290125779, 5891.351951285441, 5902.4097590240635], "value": 5547.227283548277, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.9506049395061, 511.5242824173519, 514.1028904561541, 534.9381477766633, 545.6507834985607, 31746.841265793668, 41806.37290440642, 42103.36844947328, 42116.157676846444, 42167.734967950935, 42746.572534274645, 42783.0, 43228.567714322846, 43261.35107973375, 43275.78362108193, 8671.609777559994, 9912.752181195474, 10122.0, 10122.797544049115, 10125.797484050314, 9860.852087218698, 9995.450250236234, 9996.600135994551, 10002.299839011272, 10029.24780641451, 10021.749456263578, 10024.799504009952, 10040.09639132478, 10053.547590358416, 10066.496675166249, 9052.728418147466, 9566.186874115709, 9995.2003839693, 10000.349977251492, 10013.048760367754, 9952.751181220456, 10062.89937100627, 10077.294589378746, 10091.2431567632, 10125.392476451363, 40575.59424405748, 40702.79648601768, 40791.388129178085, 40975.590244097475, 41135.7943210282, 3442.6901578857774, 3562.0738607961857, 3784.791836448994, 4009.6190861868367, 4042.514898212204, 26041.73958260431, 32129.357412851612, 32438.675613243802, 32690.836545817358, 32876.50685239724, 3265.2653153040374, 3346.4311067118647, 3370.1237678203597, 3473.5484387029524, 3476.5654293213347, 463365.0, 473058.0, 473365.99999999657, 473738.0, 473892.0, 31513.8424307877, 60298.09552856711, 60506.697466512385, 60603.0, 60890.0, 34294.314113717584, 35450.822745886624, 35552.28895422077, 35567.932962010666, 35660.0, 14627.19550424737, 14663.780043299359, 14781.630459238606, 14787.778183327258, 14798.0, 12322.507099715911, 12388.380580970916, 12477.688057798629, 12493.93753031229, 12502.49990000408, 7676.616169191518, 7710.036245469317, 7784.221577842283, 7796.8050798729355, 7811.296983271419, 2753.8623068846478, 2939.8089124207286, 3218.11501836992, 3258.169166862435, 3316.2538428853795, 820.7086484298108, 961.5480724059681, 1016.8357230970645, 1039.4646756920192, 1058.9523471443804, 28227.0, 30838.37873780709, 30947.0, 30966.845165774026, 31037.534436983464, 835.6657337065222, 1013.6350913671073, 1048.308116643012, 1058.8517607534952, 1060.067140915989, 15768.448104316294, 15978.60053498672, 16118.274677639534, 16316.592085197964, 16394.83605163957, 642668.0, 642744.0, 643176.0, 645122.0, 645804.0, 50056.9988600226, 52521.737391312796, 57446.0, 57587.71206143942, 57764.0, 1077.8329358949375, 1343.348475989164, 1371.945122195121, 1378.4486205517874, 1380.365032085252, 28826.711732882402, 29084.709152909047, 29292.853535732185, 29721.10836674903, 30616.84691576528, 35919.922402327975, 46116.76941615405, 48761.0, 50296.24555631595, 50409.73975650638, 438651.0, 475372.6231368821, 475482.6225868848, 476480.0, 478424.0, 21803.454913627287, 26638.7336126644, 27130.32174195661, 27426.040088596805, 27443.0, 47964.7601761989, 48615.027699446524, 48984.0, 49160.0, 49235.75382123067, 38889.6111038886, 39458.0, 40166.0, 40928.386074209506, 41241.381379279934, 30378.696213037587, 39385.0, 39414.40878386769, 39884.80345589637, 40971.590284096776, 38393.0, 38441.231175375775, 38490.42264365981, 39271.41092883552, 39527.60472395354, 32821.835890820395, 35385.245819715594, 35579.82210088933, 35638.8218058908, 36003.91988240357, 21715.0, 24750.0, 25544.744552554235, 26054.73945260523, 26333.341666457727], "value": 18627.993775062176, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.4845415379085, 376.22122207031424, 383.14940831354386, 384.77065774849365, 402.91740193260387, 19746.407607771773, 21373.35879923603, 21748.0, 21839.890800545996, 22005.889970550124, 17192.742108868377, 19803.306884259055, 20865.687014694755, 21063.47341316464, 21202.833844138568, 4659.464161621406, 5907.172995780594, 6089.908651370233, 6149.262088549381, 6158.261008678965, 6021.367756385582, 6080.179175811275, 6133.601315914473, 6138.079288106797, 6138.570300078996, 5844.26946631671, 6004.5796794224425, 6045.758169673208, 6057.303410107828, 6066.848328791793, 5891.116332550152, 6051.818445446644, 6053.061775424816, 6072.726727297282, 6084.634921904678, 6009.0, 6053.1828203192445, 6130.877382452326, 6132.233470816148, 6150.8769824603705, 13199.076064675477, 18008.0, 18465.538361540934, 24892.626610600855, 25859.61210581843, 2150.193677370986, 2160.546285280093, 2267.6145055340467, 2390.8685022323766, 2439.780419762212, 9056.094390560957, 9873.0, 10136.645217417356, 10137.797244055151, 10289.382637041801, 1850.5003649014825, 1939.7575303087115, 1949.7229314798828, 1956.902154892264, 1966.9016549172627, 116217.0, 116264.0, 117163.0, 123917.0, 127947.99999999907, 9652.613895444103, 11289.096872250364, 11345.376004319842, 11540.192186546861, 11625.941870290764, 14459.638509037359, 14480.0, 14499.710005800036, 14514.0, 14522.709545809235, 8222.506649601042, 8394.874076888851, 8443.788905277417, 8593.226609605166, 8622.698205562901, 8276.006879174538, 8388.412811103164, 8394.57292260305, 8540.103289154673, 8588.055313915464, 3843.5964223756664, 3935.2129574084724, 4423.867283981485, 4473.485549161823, 4641.930371044437, 1659.5104444188994, 1675.2796297592151, 1952.258141906095, 1961.568454939911, 1978.4855937456366, 846.8221673448521, 872.0712441250175, 912.2337236721186, 942.9764255893657, 946.1248345280588, 21141.0, 21421.464463388227, 21897.343079707633, 21897.343079707633, 21959.890200548896, 795.6697970342359, 861.8233262181237, 865.0, 908.5638893331238, 911.2527727263713, 10592.258541902147, 11056.557737690564, 11090.889091108986, 11101.444927753742, 11160.274582152135, 559074.0, 567870.0, 569948.0, 573774.0, 584914.0, 103494.4208196122, 109152.4542377283, 122785.0, 129790.0, 130200.0, 965.5317171171976, 1028.1671845804922, 1072.4342460008475, 1081.145894743144, 1086.559943222996, 19418.8058119417, 19946.800531994493, 20021.0, 20398.898005509876, 20428.897855510626, 36681.08297292589, 45492.545074548834, 51479.485205147466, 52733.0, 57642.0, 84345.31309373901, 84744.57627711822, 85019.72470412825, 85476.0, 85780.57109714411, 7204.459665525212, 8148.470349427268, 8288.171182881904, 8325.500469971821, 8363.749087527383, 10899.285398592534, 13337.733245335234, 13744.86255137436, 13917.79123313131, 13946.30268486592, 11075.0, 11429.142814288794, 11708.238964467282, 11785.528578857093, 11840.76318473643, 11345.489452974802, 11367.886321136682, 11554.248973817012, 11608.88391116078, 11687.298762074302, 11657.708557286136, 11756.823647645464, 11943.641690749291, 11960.0, 11989.34058626784, 7596.354309883501, 8183.836323273382, 8272.958635206785, 8313.459625124347, 8314.0, 9597.23222142241, 11049.33703977764, 11698.415079245833, 11859.34773587461, 12078.27530348182], "value": 9568.550334249987, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.944482220711, 1515.704437634661, 1595.9042457452547, 1703.4463799265243, 1843.4838245291314, 7333.339999400053, 8198.59007049648, 8664.956675216623, 9272.072792720734, 9287.257019438437, 8613.397062205648, 8936.731898043054, 8945.329100317469, 9089.636414543427, 9097.681581144661, 2384.0225507541913, 2406.554787364343, 2410.8071354291615, 2411.915582954597, 2419.177479656916, 2393.9521209575796, 2420.491696743677, 2434.233216536785, 2435.3789783605157, 2436.719777225615, 2402.0511897800357, 2403.702000919501, 2421.225207933462, 2425.527022230666, 2430.5503481855912, 2387.3196139100273, 2389.976100238993, 2394.0663141374876, 2412.5054363855365, 2416.9879150604224, 2386.1648423051965, 2389.784919357249, 2393.389685630171, 2397.2568503763887, 2399.3042017814987, 9079.546022698905, 9092.181703646704, 9095.590698418588, 9129.863052054225, 9131.680391186343, 5232.39827419844, 5375.865603359908, 5437.456254374571, 5456.672599644034, 5474.178873169057, 3129.671384504617, 3509.666581674737, 3712.610175931515, 3727.589965103836, 3759.981200094009, 1075.4138994248153, 1098.6099934523209, 1113.2819331531198, 1125.3360517294755, 1126.0316128129803, 11871.287722736577, 11972.221805582696, 12009.63971080869, 12078.758424831542, 12088.697782555419, 4496.955030449653, 4622.653301002371, 4807.990322032342, 4819.783109759999, 4823.324734537167, 10096.949515252376, 10122.848157277647, 10172.745681357877, 10179.287449878586, 10188.981101889753, 5409.404965453797, 5411.728243862689, 5420.674759514442, 5420.864478388072, 5430.484104010113, 4468.798904049326, 4588.88527786808, 4784.258439941815, 4914.877128071756, 4958.7024778513405, 3920.666743326849, 3960.821763020671, 4017.352885317006, 4070.0, 4085.325921222965, 1094.026316578246, 1194.2416565480983, 1295.4235365262368, 1304.1979182802509, 1308.3981368570373, 776.7203806629609, 873.5064688451005, 894.3856339307482, 937.5921474158763, 944.763809047738, 5722.399148089391, 5940.6435613863305, 5960.433758792908, 6162.352952939967, 6233.34549872266, 780.7306479264645, 787.220651554968, 899.2042042792176, 925.3383830561181, 992.8163289791412, 3098.643655979591, 3879.282332768447, 3908.6091390861257, 4020.0, 4057.797110144481, 10763.0, 11015.779684406427, 11126.833097503544, 11145.72135696614, 11164.832527512093, 11080.61217857371, 11102.2783519071, 11108.389038602954, 11129.387883666475, 11140.275882067639, 7176.210616832144, 7231.0, 7242.456815738736, 7254.6735396908325, 8322.16778322175, 4698.412698412699, 5095.974520127375, 5658.660480371191, 5924.674142922181, 5938.940610593839, 9918.156956658764, 10089.899101008896, 10294.794104117726, 10370.481475926023, 10458.058774710313, 8982.191602755784, 9585.9041409585, 10148.0, 10646.520906559379, 10663.4135122569, 7098.858022839411, 7142.0, 7156.248593897566, 7198.064251647253, 7202.207757146605, 8276.544790036605, 8336.958315208385, 8420.915790842013, 8439.0, 8441.071482136836, 5835.503982161564, 7465.104187497535, 7558.58427786455, 7587.848243034998, 7728.574928378993, 7295.424953533798, 7367.705291788483, 7449.180590134973, 7523.88714169277, 7534.39724822002, 6925.411340036153, 7048.400885924754, 7068.0, 7083.468739844429, 7092.5389849659605, 4966.453690094015, 5111.565516931102, 5154.948450515447, 5200.0, 5239.633225674166, 5132.5124113209185, 5146.0, 5203.895922081461, 5234.895302093861, 5412.134058550613], "value": 4610.353082246738, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.93852006258464, 299.3071040541147, 309.6841221953607, 314.2301361663924, 314.60831265074984, 4465.888352791174, 5291.867703307419, 5304.549113325367, 5305.4164041955355, 5307.761150748216, 4069.532003819562, 4306.437669586591, 4689.296605509176, 5262.4474430184855, 5277.947220527793, 4019.316716158253, 4044.0092177416573, 4049.6557792587664, 4064.7764372959477, 4073.87778366648, 3736.701063914894, 4060.8578699745517, 4061.4923134608175, 4061.675065994728, 4095.1809638072355, 3148.338848841746, 3972.523297204325, 3996.600339965995, 4018.176273863851, 4027.1140349123148, 3476.9826150869335, 3482.791032538043, 3668.8715894943816, 3992.9037255440835, 4020.6783457323345, 3046.588710524073, 3405.7445691572984, 3493.8602455901732, 3510.630853966955, 3528.1179705073732, 4949.257611358325, 5160.92258616121, 5166.741662916839, 5253.6322457428, 5304.93107688002, 4551.65862560306, 4555.772211389417, 4602.953970460286, 4603.227757313425, 4616.884577885579, 5615.354234263031, 5629.3526244482, 5629.408912064216, 5631.873625274961, 5699.401562835884, 3154.2272143324803, 3340.599128104643, 3403.5745531808525, 3796.620337966209, 3823.0, 5843.0, 5867.325257595388, 5868.559858010623, 5902.822915312505, 5945.948647716574, 3591.299696559146, 3729.533808273966, 3878.2243551289853, 3892.377219644843, 3901.297766402075, 3269.7057264846276, 3543.7596841105924, 3939.803009849496, 3949.5063117110362, 3951.664108550748, 3869.7484663496784, 4063.654589359878, 4113.91772164555, 4157.812898419578, 4525.366448697185, 4665.3934988451965, 5293.973530132324, 5618.971905140448, 5659.26429564154, 5678.677973385533, 1116.4975760907623, 1184.390039129849, 1258.3393718297966, 1288.8131220972907, 1333.999500374719, 876.1950382212663, 890.4523717913567, 899.7179019896621, 903.1014140929817, 907.8502047162276, 4696.624270058387, 5179.456157103526, 5207.270982062514, 5225.372955245319, 5346.786128554814, 5632.239647647515, 5668.518175955008, 5670.249928784536, 5704.3154821421695, 5760.481556659921, 4603.355530225771, 4768.141734487756, 4786.282057691375, 5172.818951336685, 5196.259253150161, 2105.2631578947553, 2488.9670786623346, 2538.13703340865, 2538.530371881171, 2553.553128202594, 4276.679249056333, 4702.482726900038, 4932.53140951609, 4981.402231732215, 5048.949510504922, 3704.4258139988347, 4299.78501074945, 4730.455997560256, 4805.318138651444, 4854.732989685531, 3921.9607803921594, 4105.900045465714, 4684.50812664679, 4896.3389942358035, 4987.202047672427, 4156.875293741193, 4268.911427585946, 4707.7175369477945, 4872.220444728827, 4935.827246046371, 3996.2616261926155, 4503.459584849839, 4558.148364631881, 4786.904261914672, 4851.320815085962, 4312.331588603772, 4832.9125946663125, 4905.975470122627, 4908.9754551227015, 4960.0, 2948.4545359108633, 3007.1429642551766, 3214.61424629046, 3289.013296011139, 3304.471284594453, 4571.702839315434, 4707.529247075265, 4833.903321933612, 4834.395700537433, 4867.951320486892, 4700.600448961876, 5309.177077552986, 5535.308086489189, 5545.279113715353, 5642.135137430873, 5598.516393155898, 5612.326520817528, 5626.971865140648, 5630.493255607015, 5631.1271752878365, 5240.5326508577655, 5419.241306217212, 5521.447855214607, 5540.972295138498, 5665.235193249024, 5638.913601967299, 5677.318721753416, 5681.886362272814, 5690.743916523849, 5712.571557133148, 5151.248575484354, 5512.3385193777, 5546.805861794818, 5563.332400112013, 5615.298087739033], "value": 3869.46793051821, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.9084823548822, 709.3934685843602, 768.9307962283393, 771.9807004824883, 792.0099875156055, 15094.698106037873, 15399.230038498059, 16059.75910361344, 16081.0, 16124.516264512069, 14540.418383264683, 14549.0, 14671.0, 14672.779908301358, 14730.042547234421, 837.5184269045293, 849.2993280543561, 876.1588874680315, 882.4176043811086, 885.954573603148, 669.3808227389669, 706.6078326528774, 832.7085520067989, 880.2825697056878, 881.0924747510074, 930.02812061396, 930.7952250504878, 934.4486752815837, 945.4043952310035, 946.6260826973376, 935.5743136872707, 936.1855185988165, 937.5593471068621, 938.6714649872524, 941.2187884056251, 650.5056157320423, 683.3815397065664, 690.1717938473852, 695.8330000799772, 696.195893742728, 14641.41434342625, 14679.119252844863, 14681.559553213421, 14698.779518307234, 14703.55889323322, 14371.281435928266, 14422.855771442257, 14482.131072135706, 14493.565193044225, 14735.115893046452, 11801.0, 11856.585019524362, 11888.28670279786, 12008.339541325224, 12159.51361945521, 3213.7589680773804, 3221.387936292097, 3862.5364956205153, 3979.6617287530307, 3987.2224916141363, 113517.29724054146, 115821.0, 115890.0, 117368.99999999914, 119176.80823191744, 10194.541245643964, 10433.217508686726, 10582.576696932036, 11035.282706624044, 11503.252288601214, 17544.210510527057, 20651.380458586267, 20914.790852091286, 20915.37253882386, 20945.266915657878, 7581.090269167735, 7650.655720492591, 7712.421568382281, 7809.258120478546, 7905.249001344863, 4358.934615980763, 4405.911881762347, 4416.072624748775, 4451.465824101128, 4538.591526762541, 3450.723942084627, 3486.965130348664, 3562.447820587813, 3580.964190358063, 3581.212133330663, 626.6991843914947, 638.8881945659491, 639.434100820777, 646.7865604350553, 647.8801421736994, 475.7502311286533, 545.9208414779835, 584.8128299551917, 633.7465013994347, 639.044628280714, 8668.783280417914, 9132.406393584395, 9162.770930726652, 9260.861087083698, 9265.675701350421, 565.1212364772817, 575.1975993489059, 592.9644221346733, 621.9160413344231, 634.3592971099223, 3588.4079126944284, 3885.7668539887695, 3984.980075099606, 4461.977690111528, 4530.116627257653, 117192.8280717199, 119552.0, 122136.77863221432, 136654.0, 139380.0, 122450.38774806069, 124397.0, 124595.0, 130366.0, 132590.0, 99436.0, 104064.67482601374, 106138.46930765451, 114008.0, 114217.0, 9456.72334234897, 9783.51082445889, 9940.304178707422, 10101.898981010096, 10146.188304935735, 48451.0, 62330.0, 62461.68769156125, 63718.36281637124, 63847.88541604348, 92407.0, 94019.0, 94254.63199047963, 95537.522312388, 97522.0, 12886.262983734612, 12990.805137923127, 13103.934480327538, 13360.0, 13746.518871839438, 14525.0, 14617.780733288797, 14741.55775326742, 14929.55211343618, 15238.23808809533, 13537.729245415234, 13931.442742290601, 14352.856471435152, 14412.855871441152, 14526.910481713703, 13153.605391837877, 13463.214611148342, 13692.0, 14144.858551414354, 14339.641508962359, 13519.0, 13519.0, 13659.931700341434, 13695.383707732977, 14062.296885155905, 9000.149752907755, 10007.649732259628, 10331.431771252353, 11129.777404451703, 11340.945776289884, 8207.876881846896, 8671.566421678765, 8785.514324855338, 10187.941573390395, 10686.305390149615], "value": 7699.982469268476, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 98.0, 177.0, 174.0, 126.0, 115.0, 118.0, 117.0, 118.0, 118.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 112.0, 147.0, 112.0, 111.0, 112.0, 116.0, 170.0, 134.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 199.0, 205.0, 227.0, 198.0, 196.0, 196.0, 195.0, 197.0, 199.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 59.0, 83.0, 60.0, 61.0, 60.0, 61.0, 77.0, 72.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 85.0, 75.0, 68.0, 69.0, 86.0, 70.0, 71.0, 85.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 85.0, 90.0, 133.0, 93.0, 74.0, 136.0, 134.0], "value": 100.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 95.0, 97.0, 155.0, 123.0, 94.0, 94.0, 94.0, 96.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 89.0, 122.0, 142.0, 101.0, 97.0, 148.0, 143.0, 91.0], "value": 111.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 72.0, 74.0, 70.0, 58.0, 58.0, 58.0, 58.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 69.0, 99.0, 111.0, 109.0, 102.0, 104.0, 60.0, 71.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 86.0, 88.0, 89.0, 87.0, 87.0, 91.0, 87.0, 87.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 158.0, 159.0, 140.0, 144.0, 162.0, 148.0, 148.0, 167.0, 128.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 231.0, 182.0, 174.0, 173.0, 168.0, 174.0, 172.0, 193.0, 230.0], "value": 191.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 70.0, 68.0, 70.0, 71.0, 67.0, 71.0, 85.0], "value": 70.9, "unit": "score"}]}]} 05:43:45 INFO - Return code: 0 05:43:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:43:46 ERROR - # TBPL SUCCESS # 05:43:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:43:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:43:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:43:46 INFO - 2016-09-25 05:43:46.081 screenresolution[2115:27658] starting screenresolution argv=screenresolution get 05:43:46 INFO - 2016-09-25 05:43:46.083 screenresolution[2115:27658] Display 0: 1600x1200x32@60 05:43:46 INFO - 2016-09-25 05:43:46.089 screenresolution[2116:27661] starting screenresolution argv=screenresolution list 05:43:46 INFO - Available Modes on Display 0 05:43:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:43:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:43:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:43:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:43:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:43:46 INFO - Intel Iris: 05:43:46 INFO - Chipset Model: Intel Iris 05:43:46 INFO - Type: GPU 05:43:46 INFO - Bus: Built-In 05:43:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:43:46 INFO - Vendor: Intel (0x8086) 05:43:46 INFO - Device ID: 0x0a2e 05:43:46 INFO - Revision ID: 0x0009 05:43:46 INFO - Displays: 05:43:46 INFO - Display: 05:43:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:43:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:43:46 INFO - Display Serial Number: 1600x1200 60 05:43:46 INFO - Main Display: Yes 05:43:46 INFO - Mirror: Off 05:43:46 INFO - Online: Yes 05:43:46 INFO - Rotation: Supported 05:43:46 INFO - Return code: 0 05:43:46 INFO - Running post-action listener: _resource_record_post_action 05:43:46 INFO - [mozharness: 2016-09-25 12:43:46.325659Z] Finished run-tests step (success) 05:43:46 INFO - Running post-run listener: _resource_record_post_run 05:43:46 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 30765056; Write bytes: 710002688; Read time: 290; Write time: 1903 05:43:46 INFO - TinderboxPrint: CPU usage
54.5% 05:43:46 INFO - TinderboxPrint: I/O read bytes / time
30,765,056 / 290 05:43:46 INFO - TinderboxPrint: I/O write bytes / time
710,002,688 / 1,903 05:43:46 INFO - TinderboxPrint: CPU idle
2,202.1 (45.5%) 05:43:46 INFO - TinderboxPrint: CPU system
976.4 (20.2%) 05:43:46 INFO - TinderboxPrint: CPU user
1,659.1 (34.3%) 05:43:46 INFO - TinderboxPrint: Swap in / out
431,591,424 / 0 05:43:46 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398989824; Write bytes: 394210816; Read time: 29725; Write time: 690 05:43:46 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30593024; Write bytes: 299002368; Read time: 287; Write time: 1172 05:43:46 INFO - Running post-run listener: _upload_blobber_files 05:43:46 INFO - Blob upload gear active. 05:43:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:46 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 05:43:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:43:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:43:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:43:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:48 INFO - (blobuploader) - INFO - Done attempting. 05:43:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:43:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:43:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:49 INFO - (blobuploader) - INFO - Done attempting. 05:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:43:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:51 INFO - (blobuploader) - INFO - Done attempting. 05:43:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:51 INFO - Return code: 0 05:43:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f7ceee2f5f9853c9ad419c033d9c58a5eccdade6d5fdf8408e48e1e79f97490c25a41eefffb5305c3750924c6b6b54d515c07391adc24a39770df41cba681a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d55d221159a88731fc267041e2fdc787899a4c18e3c4b900374df13cacd81f8ae8ea95f8d3cb4c8107516a2b995bfdd44a1155c578af7ff6710f5ea683ecaf68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b893357adc02ffa00d77e5ac385917de2a3face5578ebb5a0ca35813da25df689be997c014d5cc6d27122b570e384377f1d22a84211657923c1563a2cdbab69c"} 05:43:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:51 INFO - Contents: 05:43:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f7ceee2f5f9853c9ad419c033d9c58a5eccdade6d5fdf8408e48e1e79f97490c25a41eefffb5305c3750924c6b6b54d515c07391adc24a39770df41cba681a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d55d221159a88731fc267041e2fdc787899a4c18e3c4b900374df13cacd81f8ae8ea95f8d3cb4c8107516a2b995bfdd44a1155c578af7ff6710f5ea683ecaf68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b893357adc02ffa00d77e5ac385917de2a3face5578ebb5a0ca35813da25df689be997c014d5cc6d27122b570e384377f1d22a84211657923c1563a2cdbab69c"} 05:43:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:51 INFO - Copying logs to upload dir... 05:43:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.709258 ========= master_lag: 0.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-25 05:43:52.200551) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 05:43:52.207183) ========= 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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f7ceee2f5f9853c9ad419c033d9c58a5eccdade6d5fdf8408e48e1e79f97490c25a41eefffb5305c3750924c6b6b54d515c07391adc24a39770df41cba681a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d55d221159a88731fc267041e2fdc787899a4c18e3c4b900374df13cacd81f8ae8ea95f8d3cb4c8107516a2b995bfdd44a1155c578af7ff6710f5ea683ecaf68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b893357adc02ffa00d77e5ac385917de2a3face5578ebb5a0ca35813da25df689be997c014d5cc6d27122b570e384377f1d22a84211657923c1563a2cdbab69c"} build_url:https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010961 build_url: 'https://queue.taskcluster.net/v1/task/Vo4anWfrSj2n3zjmDfa4sQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f7ceee2f5f9853c9ad419c033d9c58a5eccdade6d5fdf8408e48e1e79f97490c25a41eefffb5305c3750924c6b6b54d515c07391adc24a39770df41cba681a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d55d221159a88731fc267041e2fdc787899a4c18e3c4b900374df13cacd81f8ae8ea95f8d3cb4c8107516a2b995bfdd44a1155c578af7ff6710f5ea683ecaf68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b893357adc02ffa00d77e5ac385917de2a3face5578ebb5a0ca35813da25df689be997c014d5cc6d27122b570e384377f1d22a84211657923c1563a2cdbab69c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 05:43:52.245104) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:43:52.245503) ========= 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.c4xLI6MQtH/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.HUr3oWOI9m/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.005094 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 05:43:52.296823) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-25 05:43:52.297184) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-25 05:43:52.297617) ========= ========= Total master_lag: 0.91 =========