builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1494235764.31 results: success (0) buildid: 20170508011056 builduid: 670e784f80eb484fa9d3434e0bac99a6 revision: 1fda52a1f3b81cf1a821155998dca637bb64e3d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.310765) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.311212) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.311488) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.414149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.414470) ========= 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.ECFHN87aOy/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.4EQvvhMeV1/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-08 02:29:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 176M=0s 2017-05-08 02:29:24 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.497082 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.939036) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.939554) ========= 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.ECFHN87aOy/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.4EQvvhMeV1/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.028646 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:24.991118) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-08 02:29:24.991447) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1fda52a1f3b81cf1a821155998dca637bb64e3d9 --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 1fda52a1f3b81cf1a821155998dca637bb64e3d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ECFHN87aOy/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.4EQvvhMeV1/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-08 02:29:25,092 truncating revision to first 12 chars 2017-05-08 02:29:25,092 Setting DEBUG logging. 2017-05-08 02:29:25,092 attempt 1/10 2017-05-08 02:29:25,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1fda52a1f3b8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-08 02:29:26,041 unpacking tar archive at: mozilla-central-1fda52a1f3b8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.221339 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-08 02:29:26.238432) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:26.238797) ========= script_repo_revision: 1fda52a1f3b81cf1a821155998dca637bb64e3d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:26.239250) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:26.239556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 02:29:26.261830) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-08 02:29:26.262277) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.ECFHN87aOy/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.4EQvvhMeV1/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:29:26 INFO - MultiFileLogger online at 20170508 02:29:26 in /builds/slave/test 02:29:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:29:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:29:26 INFO - {'append_to_log': False, 02:29:26 INFO - 'base_work_dir': '/builds/slave/test', 02:29:26 INFO - 'blob_upload_branch': 'Firefox', 02:29:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:29:26 INFO - 'branch': 'Firefox', 02:29:26 INFO - 'buildbot_json_path': 'buildprops.json', 02:29:26 INFO - 'config_files': ('talos/mac_config.py',), 02:29:26 INFO - 'default_actions': ('clobber', 02:29:26 INFO - 'read-buildbot-config', 02:29:26 INFO - 'download-and-extract', 02:29:26 INFO - 'populate-webroot', 02:29:26 INFO - 'create-virtualenv', 02:29:26 INFO - 'install', 02:29:26 INFO - 'run-tests'), 02:29:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:29:26 INFO - 'download_minidump_stackwalk': True, 02:29:26 INFO - 'download_symbols': 'ondemand', 02:29:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:29:26 INFO - 'tooltool.py': '/tools/tooltool.py', 02:29:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:29:26 INFO - '/tools/misc-python/virtualenv.py')}, 02:29:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:29:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:29:26 INFO - 'gecko_profile': False, 02:29:26 INFO - 'gecko_profile_interval': 0, 02:29:26 INFO - 'installer_path': 'installer.exe', 02:29:26 INFO - 'log_level': 'info', 02:29:26 INFO - 'log_name': 'talos', 02:29:26 INFO - 'log_to_console': True, 02:29:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:29:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:29:26 INFO - 'opt_config_files': (), 02:29:26 INFO - 'pip_index': False, 02:29:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:29:26 INFO - 'cmd': ('bash', 02:29:26 INFO - '-c', 02:29:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:29:26 INFO - 'enabled': True, 02:29:26 INFO - 'halt_on_failure': False, 02:29:26 INFO - 'name': 'check_screen_resolution'},), 02:29:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:29:26 INFO - 'cmd': ('bash', 02:29:26 INFO - '-c', 02:29:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:29:26 INFO - 'enabled': True, 02:29:26 INFO - 'halt_on_failure': False, 02:29:26 INFO - 'name': 'check_screen_resolution'},), 02:29:26 INFO - 'run_cmd_checks_enabled': True, 02:29:26 INFO - 'suite': 'dromaeojs', 02:29:26 INFO - 'system_bits': '32', 02:29:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:29:26 INFO - 'title': 't-yosemite-r7-0033', 02:29:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:29:26 INFO - 'use_talos_json': True, 02:29:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:29:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:29:26 INFO - 'work_dir': 'build'} 02:29:26 INFO - [mozharness: 2017-05-08 09:29:26.485909Z] Running clobber step. 02:29:26 INFO - Running pre-action listener: _resource_record_pre_action 02:29:26 INFO - Running main action method: clobber 02:29:26 INFO - rmtree: /builds/slave/test/build 02:29:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:29:29 INFO - Running post-action listener: _resource_record_post_action 02:29:29 INFO - [mozharness: 2017-05-08 09:29:29.087471Z] Finished clobber step (success) 02:29:29 INFO - [mozharness: 2017-05-08 09:29:29.087598Z] Running read-buildbot-config step. 02:29:29 INFO - Running pre-action listener: _resource_record_pre_action 02:29:29 INFO - Running main action method: read_buildbot_config 02:29:29 INFO - Using buildbot properties: 02:29:29 INFO - { 02:29:29 INFO - "project": "", 02:29:29 INFO - "product": "firefox", 02:29:29 INFO - "script_repo_revision": "production", 02:29:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:29:29 INFO - "repository": "", 02:29:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:29:29 INFO - "buildid": "20170508011056", 02:29:29 INFO - "pgo_build": "False", 02:29:29 INFO - "basedir": "/builds/slave/test", 02:29:29 INFO - "buildnumber": 19, 02:29:29 INFO - "slavename": "t-yosemite-r7-0033", 02:29:29 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:29:29 INFO - "platform": "yosemite_r7", 02:29:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:29:29 INFO - "branch": "mozilla-central", 02:29:29 INFO - "repo_path": "mozilla-central", 02:29:29 INFO - "revision": "1fda52a1f3b81cf1a821155998dca637bb64e3d9", 02:29:29 INFO - "stage_platform": "macosx64", 02:29:29 INFO - "builduid": "670e784f80eb484fa9d3434e0bac99a6", 02:29:29 INFO - "slavebuilddir": "test" 02:29:29 INFO - } 02:29:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:29:29 INFO - Running post-action listener: _resource_record_post_action 02:29:29 INFO - [mozharness: 2017-05-08 09:29:29.091147Z] Finished read-buildbot-config step (success) 02:29:29 INFO - [mozharness: 2017-05-08 09:29:29.091286Z] Running download-and-extract step. 02:29:29 INFO - Running pre-action listener: _resource_record_pre_action 02:29:29 INFO - Running main action method: download_and_extract 02:29:29 INFO - mkdir: /builds/slave/test/build/tests 02:29:29 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')]} 02:29:29 INFO - trying https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:29:29 INFO - Downloading https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 02:29:30 INFO - Downloaded 1325 bytes. 02:29:30 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:29:30 INFO - Using the following test package requirements: 02:29:30 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:29:30 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:29:30 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:29:30 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:29:30 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'jsshell-mac.zip'], 02:29:30 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:29:30 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:29:30 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:29:30 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:29:30 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:29:30 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:29:30 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:29:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:29:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:29:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:29:30 INFO - Fetch https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:29:31 INFO - Content-Length response header: 52706122 02:29:31 INFO - Bytes received: 52706122 02:29:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:29:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:29:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:29:48 INFO - Fetch https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:29:50 INFO - Content-Length response header: 52706122 02:29:50 INFO - Bytes received: 52706122 02:30:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:30:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:30:05 INFO - Fetch https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:30:06 INFO - Content-Length response header: 14409362 02:30:06 INFO - Bytes received: 14409362 02:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:06 INFO - trying https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:30:06 INFO - Downloading https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:30:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 02:30:08 INFO - Downloaded 70935741 bytes. 02:30:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:30:08 INFO - mkdir: /builds/slave/test/properties 02:30:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:30:08 INFO - Writing to file /builds/slave/test/properties/build_url 02:30:08 INFO - Contents: 02:30:08 INFO - build_url:https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:30:09 INFO - Running post-action listener: _resource_record_post_action 02:30:09 INFO - Running post-action listener: set_extra_try_arguments 02:30:09 INFO - [mozharness: 2017-05-08 09:30:09.817811Z] Finished download-and-extract step (success) 02:30:09 INFO - [mozharness: 2017-05-08 09:30:09.817973Z] Running populate-webroot step. 02:30:09 INFO - Running pre-action listener: _resource_record_pre_action 02:30:09 INFO - Running main action method: populate_webroot 02:30:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:30:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:30:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:30:09 INFO - '--tppagecycles', 02:30:09 INFO - '7'], 02:30:09 INFO - 'tests': ['tsvgm']}}, 02:30:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:30:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:30:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:30:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:30:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:30:09 INFO - 'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:30:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:30:09 INFO - 'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['damp', 'tps']}, 02:30:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:30:09 INFO - 'tests': ['damp', 'tps']}, 02:30:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['dromaeo_dom']}, 02:30:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:30:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:30:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:30:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['a11yr', 02:30:09 INFO - 'ts_paint', 02:30:09 INFO - 'tpaint', 02:30:09 INFO - 'sessionrestore', 02:30:09 INFO - 'sessionrestore_no_auto_restore', 02:30:09 INFO - 'tabpaint']}, 02:30:09 INFO - 'other-e10s': {'tests': ['a11yr', 02:30:09 INFO - 'ts_paint', 02:30:09 INFO - 'tpaint', 02:30:09 INFO - 'sessionrestore', 02:30:09 INFO - 'sessionrestore_no_auto_restore', 02:30:09 INFO - 'tabpaint']}, 02:30:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:30:09 INFO - 'ts_paint', 02:30:09 INFO - 'tpaint', 02:30:09 INFO - 'sessionrestore', 02:30:09 INFO - 'sessionrestore_no_auto_restore', 02:30:09 INFO - 'tabpaint']}, 02:30:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:30:09 INFO - 'ts_paint', 02:30:09 INFO - 'tpaint', 02:30:09 INFO - 'sessionrestore', 02:30:09 INFO - 'sessionrestore_no_auto_restore', 02:30:09 INFO - 'tabpaint']}, 02:30:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['a11yr', 02:30:09 INFO - 'ts_paint', 02:30:09 INFO - 'tpaint', 02:30:09 INFO - 'sessionrestore', 02:30:09 INFO - 'sessionrestore_no_auto_restore', 02:30:09 INFO - 'tabpaint']}, 02:30:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['a11yr', 02:30:09 INFO - 'ts_paint', 02:30:09 INFO - 'tpaint', 02:30:09 INFO - 'sessionrestore', 02:30:09 INFO - 'sessionrestore_no_auto_restore', 02:30:09 INFO - 'tabpaint']}, 02:30:09 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:30:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:30:09 INFO - 'bloom_basic_ref']}, 02:30:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['tsvgx', 02:30:09 INFO - 'tsvgr_opacity', 02:30:09 INFO - 'tart', 02:30:09 INFO - 'tscrollx', 02:30:09 INFO - 'cart', 02:30:09 INFO - 'tsvg_static']}, 02:30:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:30:09 INFO - 'tsvgr_opacity', 02:30:09 INFO - 'tart', 02:30:09 INFO - 'tscrollx', 02:30:09 INFO - 'cart', 02:30:09 INFO - 'tsvg_static']}, 02:30:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:30:09 INFO - 'talos_options': ['--disable-e10s'], 02:30:09 INFO - 'tests': ['tp5o']}, 02:30:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:30:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:30:09 INFO - 'talos_options': ['--disable-e10s', 02:30:09 INFO - '--xperf_path', 02:30:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:09 INFO - 'tests': ['tp5n']}, 02:30:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:30:09 INFO - 'talos_options': ['--xperf_path', 02:30:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:09 INFO - 'tests': ['tp5n']}}, 02:30:09 INFO - 'talos.zip': {'path': '', 02:30:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:30:09 INFO - Running post-action listener: _resource_record_post_action 02:30:09 INFO - [mozharness: 2017-05-08 09:30:09.837361Z] Finished populate-webroot step (success) 02:30:09 INFO - [mozharness: 2017-05-08 09:30:09.837474Z] Running create-virtualenv step. 02:30:09 INFO - Running pre-action listener: _resource_record_pre_action 02:30:09 INFO - Running main action method: create_virtualenv 02:30:09 INFO - Creating virtualenv /builds/slave/test/build/venv 02:30:09 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 02:30:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:30:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:30:09 INFO - Using real prefix '/tools/python27' 02:30:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:30:10 INFO - Installing distribute.............................................................................................................................................................................................done. 02:30:13 INFO - Installing pip.................done. 02:30:13 INFO - Return code: 0 02:30:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:30:13 INFO - Reading from file tmpfile_stdout 02:30:13 INFO - Output received: 02:30:13 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:30:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:30:13 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')]} 02:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:13 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')]} 02:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:13 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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30:13 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 02:30:13 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 02:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:13 INFO - 'HOME': '/Users/cltbld', 02:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:13 INFO - 'LOGNAME': 'cltbld', 02:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:13 INFO - 'MOZ_NO_REMOTE': '1', 02:30:13 INFO - 'NO_EM_RESTART': '1', 02:30:13 INFO - 'PAGER': '/bin/cat', 02:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:13 INFO - 'PWD': '/builds/slave/test', 02:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:13 INFO - 'SHELL': '/bin/bash', 02:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:13 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:13 INFO - 'USER': 'cltbld', 02:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:13 INFO - 'XPC_FLAGS': '0x0', 02:30:13 INFO - 'XPC_SERVICE_NAME': '0', 02:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:14 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 02:30:14 INFO - Cleaning up... 02:30:14 INFO - Return code: 0 02:30:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:30: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')]} 02:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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')]} 02:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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', '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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:30: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 psutil>=3.1.1 02:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:14 INFO - 'HOME': '/Users/cltbld', 02:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:14 INFO - 'LOGNAME': 'cltbld', 02:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:14 INFO - 'MOZ_NO_REMOTE': '1', 02:30:14 INFO - 'NO_EM_RESTART': '1', 02:30:14 INFO - 'PAGER': '/bin/cat', 02:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:14 INFO - 'PWD': '/builds/slave/test', 02:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:14 INFO - 'SHELL': '/bin/bash', 02:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:14 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:14 INFO - 'USER': 'cltbld', 02:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:14 INFO - 'XPC_FLAGS': '0x0', 02:30:14 INFO - 'XPC_SERVICE_NAME': '0', 02:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:14 INFO - Downloading/unpacking psutil>=3.1.1 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:30:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:30:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:30:16 INFO - Installing collected packages: psutil 02:30:16 INFO - Running setup.py install for psutil 02:30:17 INFO - building 'psutil._psutil_osx' extension 02:30:17 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 02:30:17 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 02:30:17 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 02:30:17 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 02:30:17 INFO - building 'psutil._psutil_posix' extension 02:30:17 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 02:30:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:30:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:30:17 INFO - ^ 02:30:17 INFO - 1 warning generated. 02:30:17 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 02:30:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:30:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:30:18 INFO - Successfully installed psutil 02:30:18 INFO - Cleaning up... 02:30:18 INFO - Return code: 0 02:30:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:30:18 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')]} 02:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:18 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')]} 02:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:18 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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30:18 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 02:30:18 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 02:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:18 INFO - 'HOME': '/Users/cltbld', 02:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:18 INFO - 'LOGNAME': 'cltbld', 02:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:18 INFO - 'MOZ_NO_REMOTE': '1', 02:30:18 INFO - 'NO_EM_RESTART': '1', 02:30:18 INFO - 'PAGER': '/bin/cat', 02:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:18 INFO - 'PWD': '/builds/slave/test', 02:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:18 INFO - 'SHELL': '/bin/bash', 02:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:18 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:18 INFO - 'USER': 'cltbld', 02:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:18 INFO - 'XPC_FLAGS': '0x0', 02:30:18 INFO - 'XPC_SERVICE_NAME': '0', 02:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:30:18 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 02:30:18 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 02:30:18 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 02:30:18 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 02:30:18 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 02:30:18 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 02:30:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:30:19 INFO - Installing collected packages: mozsystemmonitor 02:30:19 INFO - Running setup.py install for mozsystemmonitor 02:30:20 INFO - Successfully installed mozsystemmonitor 02:30:20 INFO - Cleaning up... 02:30:20 INFO - Return code: 0 02:30:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:30: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')]} 02:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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')]} 02:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:20 INFO - 'HOME': '/Users/cltbld', 02:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:20 INFO - 'LOGNAME': 'cltbld', 02:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:20 INFO - 'MOZ_NO_REMOTE': '1', 02:30:20 INFO - 'NO_EM_RESTART': '1', 02:30:20 INFO - 'PAGER': '/bin/cat', 02:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:20 INFO - 'PWD': '/builds/slave/test', 02:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:20 INFO - 'SHELL': '/bin/bash', 02:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:20 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:20 INFO - 'USER': 'cltbld', 02:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:20 INFO - 'XPC_FLAGS': '0x0', 02:30:20 INFO - 'XPC_SERVICE_NAME': '0', 02:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:20 INFO - Downloading/unpacking jsonschema==2.5.1 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:30:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:21 INFO - Downloading functools32-3.2.3-2.tar.gz 02:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:30:22 INFO - warning: no files found matching '*.txt' 02:30:22 INFO - no previously-included directories found matching 'build' 02:30:22 INFO - no previously-included directories found matching 'dist' 02:30:22 INFO - no previously-included directories found matching '.git*' 02:30:22 INFO - Installing collected packages: jsonschema, functools32 02:30:22 INFO - Running setup.py install for functools32 02:30:22 INFO - warning: no files found matching '*.txt' 02:30:22 INFO - no previously-included directories found matching 'build' 02:30:22 INFO - no previously-included directories found matching 'dist' 02:30:22 INFO - no previously-included directories found matching '.git*' 02:30:22 INFO - Successfully installed jsonschema functools32 02:30:22 INFO - Cleaning up... 02:30:22 INFO - Return code: 0 02:30:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:30:22 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')]} 02:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 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')]} 02:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30:22 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 02:30:22 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 02:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:22 INFO - 'HOME': '/Users/cltbld', 02:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:22 INFO - 'LOGNAME': 'cltbld', 02:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:22 INFO - 'MOZ_NO_REMOTE': '1', 02:30:22 INFO - 'NO_EM_RESTART': '1', 02:30:22 INFO - 'PAGER': '/bin/cat', 02:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:22 INFO - 'PWD': '/builds/slave/test', 02:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:22 INFO - 'SHELL': '/bin/bash', 02:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:22 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:22 INFO - 'USER': 'cltbld', 02:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:22 INFO - 'XPC_FLAGS': '0x0', 02:30:22 INFO - 'XPC_SERVICE_NAME': '0', 02:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:30:22 INFO - Cleaning up... 02:30:22 INFO - Return code: 0 02:30:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:30:22 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')]} 02:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 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')]} 02:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30:22 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 02:30:22 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 02:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:22 INFO - 'HOME': '/Users/cltbld', 02:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:22 INFO - 'LOGNAME': 'cltbld', 02:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:22 INFO - 'MOZ_NO_REMOTE': '1', 02:30:22 INFO - 'NO_EM_RESTART': '1', 02:30:22 INFO - 'PAGER': '/bin/cat', 02:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:22 INFO - 'PWD': '/builds/slave/test', 02:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:22 INFO - 'SHELL': '/bin/bash', 02:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:22 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:22 INFO - 'USER': 'cltbld', 02:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:22 INFO - 'XPC_FLAGS': '0x0', 02:30:22 INFO - 'XPC_SERVICE_NAME': '0', 02:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:22 INFO - Downloading/unpacking blobuploader==1.2.4 02:30:22 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 02:30:22 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 02:30:22 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 02:30:22 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 02:30:22 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 02:30:22 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 02:30:24 INFO - Downloading blobuploader-1.2.4.tar.gz 02:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:30:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:30:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:25 INFO - Downloading docopt-0.6.1.tar.gz 02:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:30:25 INFO - Installing collected packages: blobuploader, requests, docopt 02:30:25 INFO - Running setup.py install for blobuploader 02:30:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:30:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:30:25 INFO - Running setup.py install for requests 02:30:25 INFO - Running setup.py install for docopt 02:30:25 INFO - Successfully installed blobuploader requests docopt 02:30:25 INFO - Cleaning up... 02:30:26 INFO - Return code: 0 02:30:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30: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')]} 02:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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')]} 02:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30: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 02:30: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 02:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:26 INFO - 'HOME': '/Users/cltbld', 02:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:26 INFO - 'LOGNAME': 'cltbld', 02:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:26 INFO - 'MOZ_NO_REMOTE': '1', 02:30:26 INFO - 'NO_EM_RESTART': '1', 02:30:26 INFO - 'PAGER': '/bin/cat', 02:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:26 INFO - 'PWD': '/builds/slave/test', 02:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:26 INFO - 'SHELL': '/bin/bash', 02:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:26 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:26 INFO - 'USER': 'cltbld', 02:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:26 INFO - 'XPC_FLAGS': '0x0', 02:30:26 INFO - 'XPC_SERVICE_NAME': '0', 02:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_2ouDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-R80OEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-TsiyiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-2Y63qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-XLF1mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iF8Sm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iue2KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:30:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-n9uZyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-srEa4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VFowfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-h5aHWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Rpq6J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-DeBPuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-mmDS5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IWDAni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-V7emOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:30:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-jIUKsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:30:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:30:28 INFO - Running setup.py install for manifestparser 02:30:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:30:28 INFO - Running setup.py install for mozcrash 02:30:28 INFO - Running setup.py install for mozdebug 02:30:28 INFO - Running setup.py install for mozdevice 02:30:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:30:28 INFO - Running setup.py install for mozfile 02:30:28 INFO - Running setup.py install for mozhttpd 02:30:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:30:28 INFO - Running setup.py install for mozinfo 02:30:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Running setup.py install for mozInstall 02:30:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Running setup.py install for mozleak 02:30:29 INFO - Running setup.py install for mozlog 02:30:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Running setup.py install for moznetwork 02:30:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Running setup.py install for mozprocess 02:30:29 INFO - Running setup.py install for mozprofile 02:30:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:30:29 INFO - Running setup.py install for mozrunner 02:30:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:30:30 INFO - Running setup.py install for mozscreenshot 02:30:30 INFO - Running setup.py install for moztest 02:30:30 INFO - Running setup.py install for mozversion 02:30:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:30:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:30:30 INFO - Cleaning up... 02:30:30 INFO - Return code: 0 02:30:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30: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')]} 02:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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')]} 02:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30: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 02:30: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 02:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:30 INFO - 'HOME': '/Users/cltbld', 02:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:30 INFO - 'LOGNAME': 'cltbld', 02:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:30 INFO - 'MOZ_NO_REMOTE': '1', 02:30:30 INFO - 'NO_EM_RESTART': '1', 02:30:30 INFO - 'PAGER': '/bin/cat', 02:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:30 INFO - 'PWD': '/builds/slave/test', 02:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:30 INFO - 'SHELL': '/bin/bash', 02:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:30 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:30 INFO - 'USER': 'cltbld', 02:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:30 INFO - 'XPC_FLAGS': '0x0', 02:30:30 INFO - 'XPC_SERVICE_NAME': '0', 02:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:30:30 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Tpjfuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:30:30 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)) 02:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tYX3GS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-nwd60_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:30: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)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qlySbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-01gG1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:30: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)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-01Zhxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:30: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)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-rC3PYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:30: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)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-WOjigW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:30: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)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-MXXDvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:30: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)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-izrDsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:30: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)) 02:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:30:31 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-7vH1m7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:30: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)) 02:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:30:32 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-sxqMtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:30:32 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_CEfLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:30: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)) 02:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:30:32 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tZ_FQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:32 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-o27rC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:30: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)) 02:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:30:32 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-7JyHBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:30:32 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kEe6Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:30: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)) 02:30: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:30: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:30: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)) 02:30: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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:30: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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:30:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:34 INFO - Downloading blessings-1.6.tar.gz 02:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:30:34 INFO - Installing collected packages: blessings 02:30:34 INFO - Running setup.py install for blessings 02:30:34 INFO - Successfully installed blessings 02:30:34 INFO - Cleaning up... 02:30:34 INFO - Return code: 0 02:30:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:30:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:30:34 INFO - Reading from file tmpfile_stdout 02:30:34 INFO - Current package versions: 02:30:34 INFO - blessings == 1.6 02:30:34 INFO - blobuploader == 1.2.4 02:30:34 INFO - docopt == 0.6.1 02:30:34 INFO - functools32 == 3.2.3-2 02:30:34 INFO - jsonschema == 2.5.1 02:30:34 INFO - manifestparser == 1.1 02:30:34 INFO - mozInstall == 1.12 02:30:34 INFO - mozcrash == 1.0 02:30:34 INFO - mozdebug == 0.1 02:30:34 INFO - mozdevice == 0.50 02:30:34 INFO - mozfile == 1.2 02:30:34 INFO - mozhttpd == 0.7 02:30:34 INFO - mozinfo == 0.9 02:30:34 INFO - mozleak == 0.1 02:30:34 INFO - mozlog == 3.4 02:30:34 INFO - moznetwork == 0.27 02:30:34 INFO - mozprocess == 0.25 02:30:34 INFO - mozprofile == 0.28 02:30:34 INFO - mozrunner == 6.13 02:30:34 INFO - mozscreenshot == 0.1 02:30:34 INFO - mozsystemmonitor == 0.3 02:30:34 INFO - moztest == 0.8 02:30:34 INFO - mozversion == 1.4 02:30:34 INFO - psutil == 3.1.1 02:30:34 INFO - requests == 1.2.3 02:30:34 INFO - wsgiref == 0.1.2 02:30:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30:34 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')]} 02:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:34 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')]} 02:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:34 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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30:34 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 02:30:34 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 02:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:34 INFO - 'HOME': '/Users/cltbld', 02:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:34 INFO - 'LOGNAME': 'cltbld', 02:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:34 INFO - 'MOZ_NO_REMOTE': '1', 02:30:34 INFO - 'NO_EM_RESTART': '1', 02:30:34 INFO - 'PAGER': '/bin/cat', 02:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:34 INFO - 'PWD': '/builds/slave/test', 02:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:34 INFO - 'SHELL': '/bin/bash', 02:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:34 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:34 INFO - 'USER': 'cltbld', 02:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:34 INFO - 'XPC_FLAGS': '0x0', 02:30:34 INFO - 'XPC_SERVICE_NAME': '0', 02:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 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)) 02:30:34 INFO - Cleaning up... 02:30:34 INFO - Return code: 0 02:30:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:30:34 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')]} 02:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:34 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')]} 02:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:34 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 0x103271b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103274768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb85741c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, '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.ECFHN87aOy/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.4EQvvhMeV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 02:30:34 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 02:30:34 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 02:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:34 INFO - 'HOME': '/Users/cltbld', 02:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:34 INFO - 'LOGNAME': 'cltbld', 02:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:34 INFO - 'MOZ_NO_REMOTE': '1', 02:30:34 INFO - 'NO_EM_RESTART': '1', 02:30:34 INFO - 'PAGER': '/bin/cat', 02:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:34 INFO - 'PWD': '/builds/slave/test', 02:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:34 INFO - 'SHELL': '/bin/bash', 02:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:34 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:34 INFO - 'USER': 'cltbld', 02:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:34 INFO - 'XPC_FLAGS': '0x0', 02:30:34 INFO - 'XPC_SERVICE_NAME': '0', 02:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:30:35 INFO - Cleaning up... 02:30:35 INFO - Return code: 0 02:30:35 INFO - Running post-action listener: _resource_record_post_action 02:30:35 INFO - Running post-action listener: _start_resource_monitoring 02:30:35 INFO - Starting resource monitoring. 02:30:35 INFO - [mozharness: 2017-05-08 09:30:35.220389Z] Finished create-virtualenv step (success) 02:30:35 INFO - [mozharness: 2017-05-08 09:30:35.221333Z] Running install step. 02:30:35 INFO - Running pre-action listener: _resource_record_pre_action 02:30:35 INFO - Running main action method: install 02:30:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:30:35 INFO - Reading from file tmpfile_stdout 02:30:35 INFO - Detecting whether we're running mozinstall >=1.0... 02:30:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:30:35 INFO - Reading from file tmpfile_stdout 02:30:35 INFO - Output received: 02:30:35 INFO - Usage: mozinstall [options] installer 02:30:35 INFO - Options: 02:30:35 INFO - -h, --help show this help message and exit 02:30:35 INFO - -d DEST, --destination=DEST 02:30:35 INFO - Directory to install application into. [default: 02:30:35 INFO - "/builds/slave/test"] 02:30:35 INFO - --app=APP Application being installed. [default: firefox] 02:30:35 INFO - mkdir: /builds/slave/test/build/application 02:30:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:30:54 INFO - Reading from file tmpfile_stdout 02:30:54 INFO - Output received: 02:30:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:30:54 INFO - Running post-action listener: _resource_record_post_action 02:30:54 INFO - [mozharness: 2017-05-08 09:30:54.982884Z] Finished install step (success) 02:30:54 INFO - [mozharness: 2017-05-08 09:30:54.983022Z] Running run-tests step. 02:30:54 INFO - Running pre-action listener: _resource_record_pre_action 02:30:54 INFO - Running main action method: run_tests 02:30:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:54 INFO - 2017-05-08 02:30:54.997 screenresolution[1636:9704] starting screenresolution argv=screenresolution get 02:30:55 INFO - 2017-05-08 02:30:55.000 screenresolution[1636:9704] Display 0: 1600x1200x32@60 02:30:55 INFO - 2017-05-08 02:30:55.006 screenresolution[1637:9706] starting screenresolution argv=screenresolution list 02:30:55 INFO - Available Modes on Display 0 02:30:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:30:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:30:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:30:55 INFO - Intel Iris: 02:30:55 INFO - Chipset Model: Intel Iris 02:30:55 INFO - Type: GPU 02:30:55 INFO - Bus: Built-In 02:30:55 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:55 INFO - Vendor: Intel (0x8086) 02:30:55 INFO - Device ID: 0x0a2e 02:30:55 INFO - Revision ID: 0x0009 02:30:55 INFO - Displays: 02:30:55 INFO - Display: 02:30:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:30:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:55 INFO - Display Serial Number: 1600x1200 60 02:30:55 INFO - Main Display: Yes 02:30:55 INFO - Mirror: Off 02:30:55 INFO - Online: Yes 02:30:55 INFO - Rotation: Supported 02:30:55 INFO - Return code: 0 02:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:30:55 INFO - Python 2.7.3 02:30:55 INFO - Return code: 0 02:30:55 INFO - grabbing minidump binary from tooltool 02:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:55 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f82d30>, '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 0x102f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f76be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:30:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:30:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:30:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:30:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:30:55 INFO - Return code: 0 02:30:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:30:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:30:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:30:55 INFO - ENV: RUST_BACKTRACE is now 1 02:30:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:30:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ECFHN87aOy/Render', 02:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:55 INFO - 'HOME': '/Users/cltbld', 02:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:55 INFO - 'LOGNAME': 'cltbld', 02:30:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:30:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:55 INFO - 'MOZ_NO_REMOTE': '1', 02:30:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:30:55 INFO - 'NO_EM_RESTART': '1', 02:30:55 INFO - 'PAGER': '/bin/cat', 02:30:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:55 INFO - 'PWD': '/builds/slave/test', 02:30:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:55 INFO - 'RUST_BACKTRACE': '1', 02:30:55 INFO - 'SHELL': '/bin/bash', 02:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4EQvvhMeV1/Listeners', 02:30:55 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 02:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:55 INFO - 'USER': 'cltbld', 02:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:55 INFO - 'XPC_FLAGS': '0x0', 02:30:55 INFO - 'XPC_SERVICE_NAME': '0', 02:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:30:55 INFO - mozversion application_buildid: 20170508011056 02:30:55 INFO - mozversion application_changeset: 1fda52a1f3b81cf1a821155998dca637bb64e3d9 02:30:55 INFO - mozversion application_display_name: Nightly 02:30:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:30:55 INFO - mozversion application_name: Firefox 02:30:55 INFO - mozversion application_remotingname: firefox 02:30:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:30:55 INFO - mozversion application_vendor: Mozilla 02:30:55 INFO - mozversion application_version: 55.0a1 02:30:55 INFO - mozversion platform_buildid: 20170508011056 02:30:55 INFO - mozversion platform_changeset: 1fda52a1f3b81cf1a821155998dca637bb64e3d9 02:30:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:30:55 INFO - mozversion platform_version: 55.0a1 02:30:55 INFO - using testdate: 1494235855 02:30:55 INFO - actual date: 1494235855 02:30:55 INFO - starting webserver on 'localhost:49242' 02:30:55 INFO - SUITE-START | Running 2 tests 02:30:55 INFO - TEST-START | dromaeo_css 02:30:55 INFO - operating with platform_type : mac_ 02:30:55 INFO - Initialising browser for dromaeo_css test... 02:30:55 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpbIDCcj/profile http://localhost:49242/getInfo.html) 02:30:58 INFO - PID 1647 | __metrics Screen width/height:1600/1200 02:30:58 INFO - PID 1647 | colorDepth:24 02:30:58 INFO - PID 1647 | Browser inner width/height: 1024/691 02:30:58 INFO - PID 1647 | __metrics 02:30:58 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:30:58 INFO - PID 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:30:59 INFO - TEST-INFO | 1647: exit 0 02:30:59 INFO - Browser initialized. 02:30:59 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:59 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpbIDCcj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:31:11 INFO - PID 1650 | RSS: Main: 240308224 02:31:11 INFO - PID 1650 | 02:34:16 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 02:34:17 INFO - PID 1650 | RSS: Main: 396390400 02:34:17 INFO - PID 1650 | 02:37:20 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 02:37:21 INFO - PID 1650 | RSS: Main: 364273664 02:37:21 INFO - PID 1650 | 02:40:23 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 02:40:23 INFO - PID 1650 | RSS: Main: 331542528 02:40:23 INFO - PID 1650 | 02:43:26 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 02:43:26 INFO - PID 1650 | RSS: Main: 326807552 02:43:26 INFO - PID 1650 | 02:46:30 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 02:46:30 INFO - PID 1650 | RSS: Main: 326049792 02:46:30 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 02:49:33 INFO - PID 1650 | RSS: Main: 367276032 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | __start_tp_report 02:49:33 INFO - PID 1650 | _x_x_mozilla_page_load 02:49:33 INFO - PID 1650 | _x_x_mozilla_page_load_details 02:49:33 INFO - PID 1650 | |i|pagename|runs| 02:49:33 INFO - PID 1650 | |0;dojo.html;3480.721542276618;3519.348920449717;3533.5759708834935;3547.946780798288;3575.6245594212605;6056.818295451132;6117.005394430401;6163.414475624814;6163.784267550636;6177.969110154443;6121.693915304239;6165;6188.628682279055;6205.22434695663;6206.286277078138;5198.68807871527;5237.816676416326;5353.7590808413715;5373.328333958255;5385.3537575491;5464.508194262516;5464.9453505465035;5465.754041068161;5473.2063850741615;5487.972560137214;5284.392294886099;5345.973270133663;5349.51854333108;5394.6493477924205;5407.459254074601;5299.152135658276;5333.066713325192;5363.517283444469;5378.058839703075;5382.219578161144;5237.895242095175;5280.498352656491;5289;5291.629585928987;5357.196420536911;6083.8174854754425;6096.4817990470865;6097.02447608385;6106.297775755756;6142.416470435302;6333.11336412898;6342.80971570849;6342.968285158545;6361.077643741678;6462.838429039311;6604;6636.502262330297;6657.167854018247;6671.099401580773;6675.899861502081;6154.415330543681;6303.0545418187185;6307.05394190875;6307.8738425231695;6340.619562826245;6554.541182117206;6632.602043877383;6765.255821859591;6772.119624448888;6801.489888258301;5401.297831281908;6368.14030105939;6402;6408.327125651739;6414.1340918976275;6551.639659818756;6564.770233041821;6621.4040736333;6633.568818026813;6634.436072933758;6313.5896166749935;6370;6474.352564743488;6476.287608363076;6498.967505162444;6435.324290949477;6515.315891831384;6526.118973938553;6539.967300163468;6553.475721942234;5227.503387178212;5232.790688372423;5235.685858848481;5241.50205730455;5260.316158899395;4665.6967297125575;4687.1328804171335;4721.740304283228;4753.954130091374;4776;5966.731497082555;6110.801781737137;6140.815775526741;6142.355052719489;6185.226846644169;6557.704903279364;6613.900791488035;6630.7679231226675;6632.635205063768;6651.135352404156;6259.71831267594;6268.655223962726;6272;6279.529035322369;6280.7487700491465;5645.463680950304;5720.170575266563;5782.392848750821;5792.044312688444;5797.101449275369;6633.834154146281;6635.104260924713;6734.764283250062;6807.3873351398615;6845.007473916155;6314.526410519137;6379.712912918837;6397.936020639734;6624.138861947916;6642.036904648702;6552.082708420729;6622.63575503352;6643.368879956396;6646.202455705346;6646.933530664632;6313.463355614824;6322.683865806599;6341.112244285896;6384.93615063862;6447.3552644735155;6619.933800662125;6620.8013759585365;6623;6633.800985970429;6641.037049627874;5387.299651045339;6324.272708638566;6386;6404.391582799627;6411.4229719325485;6193.907091393543;6266.091416744638;6274.152989346379;6297;6353;6220.7511699531515;6288.339724328879;6317.96841015792;6323.272823625342;6323.9683801580695;6399.64801935898;6403.167588213502;6406.359364063556;6408;6440.516961228021;6208.534359922934;6234;6252.656103914328;6286.088517164894;6287.93105172131 02:49:33 INFO - PID 1650 | |1;ext.html;773.6402572803648;798.5727635714893;811.4725428471493;1133.6315697398347;1144.273386399636;25290;35256.471152932696;35266;35544;35548;37343.4398484023;37721.43417848735;37813.62186378135;37840.62159378405;37841.24317513648;5861.677607731573;6874.415674667647;8275.420720549564;8286.668533258662;8294.294984926288;5896.911546326808;5990.400959903996;5990.790322338719;5999.340072592011;7341.669624866867;8251.669933202666;8280.875786863202;8293.21214484623;8341.374396920224;8348.41561090724;6963.268856770017;8189.95905020477;8260.4217704761;8262.42163048587;8265.049519305237;5813.215215945836;5907.02534081876;6987.231404545496;7877.606119693993;8215.876761848518;34961.85343319088;35102.82448587766;35196.12009699778;35202;35220.47169292463;3551.3607550640872;3598.676119149263;3598.838052287653;3599.532060832075;3599.9280014399565;29003.839846406117;29100;29111.41777164466;29111.85444072787;29276.853615731787;3050.771192160597;3102.6431960324626;3143.6699146589735;3152.7793054486197;3154.274516861135;283098;287474;287688;287861;289168;47990;48137;48161.03677926422;48230.03539929182;48336;50035.49964500381;50102.49897501051;50182.74543136451;50275;50292.74268143252;13819.170849749047;13918.09532380392;13933.790993135111;13936.930315348358;13942.442302307794;11320.150988675883;11327.093832493381;11330.263532870502;11335.263207891625;11345.376004319842;10423.166146708245;10427.791444171075;10469.581216751398;10502.73743156412;12278.447469863877;4564.109998550317;4587.128445595326;5006.4743201964;5167.483251674803;5176.715280659525;845.9492430454193;1011.4841430870309;1019.495349801838;1042.7758032023;1052.3370276725766;18228.544286392946;22963.88518057399;23607.173748918707;23618.527629447657;23771.881140594185;996.928302075272;1008.8940661230612;1156.2426610569921;1157.386585109893;1158.71611455194;10635.46822658864;12136.696582585506;12152.939235303767;12154.088443366783;12179.634610961508;308823;309194;309272;309420;309747;51712.707182320744;51902.74048629733;51932.74033629808;52104.47895520996;52132.69668258248;1476.5570328901288;1493.8431464696268;1545.042073914158;1552.774847647085;1569.9529014129594;21168.78831211668;22022.678639281694;22867.199648012906;22991.54016919686;23032.272579556266;36261.45607815832;36315.72894948664;36328.81835590911;36349.63650363568;36361;274249;275414;275641;275826;275990;32469.7012119522;32565.34869302648;32609.836950815094;32634.34731305313;32644.34711305808;40164.39753403643;40720;42117.57882421136;42246;42680.5731942689;27917.441651167268;28892.711072889844;29442.411151776418;29608.407831843673;30053.699463005963;27991.860040699667;28111.859440702665;28838.855805720836;29479.705202947694;29787.851060744557;35816;35822.56709731581;35866.74466393664;35873.82063089668;35943.64056359507;28232.85883570569;30351.392972140875;30374;30392.696073038987;30413.543796843507;23325.950332234752;23532.29403117909;23645.527089458457;23848.761512385347;23930.40173995664 02:49:33 INFO - PID 1650 | |2;jquery.html;389.1205874723125;434.6045098959947;437.37574552683895;443.8712773295744;448.8832903445103;17826.376076837296;21201.939903004866;22139.557208855815;22918.197863074816;23305.533889322203;22943.42641433965;23284.41788955277;23666;23947.760522394816;24009;5539.335279766434;5826.388229235933;5946.672932988673;6085.9653858843885;6170.753169873222;6009.549283803711;6088.6042407243385;6134.049222370518;6135.938640613604;6140.447359737623;5774;5971.134185543116;6053.303870054934;6056.152138700585;6056.212692349988;5997.460228579449;6004.219451471303;6012.518998480111;6078.996965500709;6093.360197179299;5278.551323137538;5356.544693701001;5376.51611354976;5383.703896285702;5387.4612538746205;27287.727122728716;27703.753331099946;27805.582916256273;28066.158015259574;28212;2219.1678120704737;2261.8642881427168;2262.468319944812;2263.807576356012;2265.4223173090923;15330.540083797503;16894.74657880133;17796.02121883296;17966.820331796647;18311.54221144469;2087.7912208779153;2096.6121267565395;2152.526444182293;2156;2166.7833216678364;149922.25038874737;151597;152339;152488;155264.223678882;9215.493147876798;11855.822162667568;11970.700732481584;12618.558350457874;12706;14975.251237438302;15372.769408458882;15412.998155119882;15415.306311216022;16308.67382652364;8947.821043579092;9015.053419391;9042.412243204171;9069.093090690878;9186.816263674822;8574;8595.616009297975;8665.740027799176;8755;8863.867041994376;7790.766277011699;7935.444518883738;7940.205979402129;7943.483673561315;7959.124496305401;2163.3942496101117;2170.305502239268;2170.533335332911;2181.2692747929623;2189.3618190080915;908.355067901784;978.9265805064506;993.5677980078689;996.3225006995168;1029.4235228272041;21163.470913226985;21851.890740546514;21853.016614252396;21876.781232187794;21918.013689384017;885.4112015509743;1001.379144930148;1037.1288117980932;1042.8487869258915;1050.7320633238476;9277;10469.487323042567;10620.734481638021;10969.23215374916;11025.117990560735;556126;566007;567169;571672;579014;123874.76125238813;127774;134753;138373;144037.5596244024;1196.8144937534696;1243.9813402798966;1248.4007676315418;1252.2549083295426;1262.4192871279315;21065.15739370408;21874;22686.432839179153;23190.028847547474;23228;59687;60146;60423.77033607605;60544;61238.10095192463;72929.27070729225;76220.23779762132;82924.17075829164;83962.58018709868;84563.154368458;10546.894531054591;12695.80956285675;13152.342382881008;13274;13529.72940541164;14334.068285561405;14343.354549045109;14514.927425362806;14695;14743.041702289316;9138.811954445879;11514.30914145154;11664.825027624423;11685.824712629148;12187.939060304641;11342.829857551978;11528.769424611628;11534.423278835857;11878.287302761863;11879;12011;12053.011253143133;12231.75536489283;12336.938315308365;12340;12829.807552886528;13086.411111499814;13159;13258.270795106362;13305.004249362286;11824.1131915105;11971.880281197076;12119.45462454174;12148.817767733668;12211.633650990485 02:49:33 INFO - PID 1650 | |3;mootools.html;1316.591856524478;1388.0769288423198;1442.6970336229392;1463.3268696399655;1470.1032370254143;11121.777564448706;11238.044766194862;11696.941515292423;11701.70745731357;11731.882681173196;11430.25703329283;11634.767304653902;11640.476178571964;11641.70895727605;11649.242799218046;2482.875856207187;2519.5716728156167;2526.68416447944;2532.4555220627562;2533.0121252711456;2102.5794841031857;2183.912643494258;2430.0644251962945;2512.170983575425;2518.4837108392735;1846.7876194237701;2408.6266628672583;2507.323022783847;2518.44594189279;2525.217182673368;2472.765087316702;2489.166129346671;2520.6849143856925;2521.7478252174637;2525.785308248801;2131.031091197202;2382.0114652419225;2416.9637455438183;2442.914497992562;2446.645236440724;11129.332240065622;11328.886711132865;11334.093272538177;11340.20618556693;11357.943210283896;5694.174344719992;6431;6670.666466676646;6693.665316734193;6702.128723265993;3895.7467764595212;4223.683223758199;4292.699511034229;4313.698041137121;4317.76252306123;1243.1422318600137;1244.3156264054733;1274.1654216488214;1280.673428275793;1290.812832139344;15943.760843587355;16271;16286.592835179097;16287.846599117149;16308.836911630851;5736.282964629421;5866.120081987736;5953.642781433128;5974;6070.969645151746;13527;13533.052686311863;13563.11839730434;13576.93211533936;13591.116577422634;6718.294579069225;6737;6737.225219099768;6739.5619284746335;6740.831479213059;5025.849224523197;6017.909731354033;6097.176881120992;6211.565190436626;6216.191895053614;3739.9439008414897;4569.109023740339;4633.328167415706;4681.9297710534365;4688.296755486636;1133.18410744264;1402.6212922510865;1447.5223176351778;1462.7659574468246;1469.669324402001;665.3432951949611;722.7217521254249;794.7059587952494;806.5765473126538;818.2349503214514;5863;5868.149118377812;5953.529285346533;6303.716332765037;6637.701303441357;957.1003256938455;968.932174747775;969.5007071358256;970.8058388322364;972.9318947673736;3684.0053185639727;4025.9194816103513;4220.577942205816;4226.873193804191;4385.627221686193;14338.56984290473;14609.342579583945;14868.851311486964;14888.553343399499;14959.476418325306;14340.56978290653;14583.22918645454;14750.262486875614;14761.40954361835;14817.851821481863;7225.385842203366;8393.160683931557;8602.91397086021;8661.30709543235;8673.739787806377;6486.610803351814;6557.114789503356;6677.2988836173445;6739.966300168468;6743;10464.215183861394;12912.677183070498;13284.80072798928;13481.2585307809;13566.050376473946;13029.739405212033;13491.730165396833;13528.797068044185;13552.728945421233;13588.524401645895;8351;9340.159385655325;9347.112024357675;9370.156685898302;9373.20327772147;9272.350935434455;10107.444090574794;10198.43908585035;10535.525901334306;10657;8892.599833007402;9088.500132492776;9101.044390339184;9136.817263654822;9480.146786789222;8727.127287271222;8848.1151884811;8893.866592000995;8943.329250306384;8960.820783584422;7846.568438735924;8016.278534931884;8019;8023.398245131755;8025.799355016171;5930.584859059824;5962.612430192024;6077.149199112039;6131.386861314011;6185.041318595625;5984.850378740392;6116.66358350295;6165.198524191826;6179.62922224668;6347.270063942706 02:49:33 INFO - PID 1650 | |4;prototype.html;391.8883118311282;395.55697618667097;397.30471674569503;400.26950814763063;403.7436227995223;5302.78788848446;5847.941520584793;5855;5939.1291743101;5939.613925094865;5702.572307076975;5715.971420142904;5793.565482588801;5795.971020144893;5823.621464604808;4302.01053757636;4462.237416220591;4469.843555475559;4475.239209334405;4476.418065651461;3861;4182.581741825808;4476.149531588988;4489.663275254353;4491.483479399862;4191.119864828374;4298.484181898177;4412.955870441287;4453.2206863798865;4456.509783923766;4393.7363758174615;4407.757573333465;4422.159789639958;4423;4463.308187231;3333.8833140839956;3928.5874983126932;3940.290747665419;3944.684425245973;3959.46547216125;4253.447051883266;4623.768811559442;4687.320338550891;5448.727563621803;5700.572457065695;5288.588860185159;5322.680639161663;5328.280682107905;5343.305370301875;5352.330958630171;5891.909996650634;6183.6598987055695;6262.718177682015;6278.968605156945;6282.277538083134;4017.4174744662155;4320.956790432087;4407.338899165119;4475.283954567253;4499.122671079141;6233.781817636361;6367.235931688227;6436.485081193493;6494;6654.467642588581;4021.1153546219784;4684;4755.809767609257;4790.209615413488;4795.976020119877;4606.539346065434;4677.929831052537;4712.811487540528;4858.732769697701;4867.367242258484;4681.391419115561;5068.594512438996;5305.44292849253;5346.465353464622;5445.809396671098;5428.158635411518;6290.867643824064;6341.746330146742;6401.64790936503;6420.261669907927;1410.605030591436;1475.9188244646432;1499.0930487055393;1499.8500149985132;1589.6423304756338;811.0065170166499;828.1139181076311;954.0030667951868;983.306768728045;984.2038280138776;5151.2530683050745;5797.8550536236935;5951.940480595139;5968.7015649217365;5986;5282.550983166474;5789.681567513827;6535.725533520917;6565.67171641416;6584.670766461658;4744.715317080917;4783.425988881357;5038.949610503922;5399.352077750695;5741.25363702716;2802.9159125226274;3045.193023848682;3443.364401909073;3484.982575087159;3501.0197144799495;5803.82588522345;6649.966750166315;6717.783131566572;6817.829554261183;6891.241963384024;6186.958303759839;6696.330366963362;6804.217514985741;6817.659117044227;6835.624040677811;5732.512736417368;6576.80269591913;6824.010518474794;6860.897086543706;6875.037494750739;5471.8084867029465;5851.94148058531;6187.566870319034;6587.242467116152;6611.933880661132;5065.442801291929;5651.745671444877;6202.689865506797;6359.523035772243;6575.942186883122;4899.020195960751;5160.987214986233;6608.281846719983;6803.9319606805275;6839.487038472035;3789.77261364319;4247.638950689103;4281.779692787541;4303.967047908417;4306.514252582854;5525.972370138124;6449.161608990801;6465.838354041187;6536.477081833537;6585.6377899216;5843.558895239734;6342.7780027700655;6350.269718982377;6366.013267943476;6456.2575303838785;6031.763488484762;6356.491480681627;6374.681265936777;6389.840253993687;6412.551121421456;5341.78632854697;6170.290416602148;6292.276388215414;6308.400701933309;6325.462335701517;6088.756449741961;6334.556581039283;6430.967845160744;6453.870572649674;6488.124103246001;6189.195404597373;6223;6238.251409830849;6247.937850565324;6258.873402787542 02:49:33 INFO - PID 1650 | |5;yui.html;849.8427790858696;857.2327766648848;871.6513394642143;875.6935072724551;884.1247165306345;13773.655658608539;15839.524814255576;16528.917355413236;16535.842491025633;16569;16924.746128808078;17158;17310.913445432787;17350.652986940284;17365.479035628952;844.5945945945949;988.1650005745134;988.2588058955783;988.367444835306;990.4997976022099;978.0317485689187;980.186445250444;981.2542467724545;983.7589790501302;1019.7348689340788;1013.6198925402974;1021.9131373833233;1024.1192574386023;1024.692592222334;1062.3041907550567;1014.0265345268551;1016.3495362967707;1018.4703953943982;1020.4846552490944;1058.9417582032986;778.1284960843891;779.2207792207793;779.5478622399052;779.9548604869502;783.0642382353091;16398.26207820639;16616.169191540375;16815.41146059894;17059.658806823794;17402.39091631787;16837;16922.27709903819;17130.91434542832;17279.74080388795;17379.21793519282;13634.659133521642;13684.589462316146;13829.861701382957;13898.444062237499;13976.371063302176;3486.738494612914;3494.1264683829045;3504.0714210734177;3505.6143824179576;3508.210652603144;62819.37180628181;96758.51620741922;109679;113069;130930.69069309281;11030.889691103146;13416.12795168311;13617.727645447036;13624.04631675773;13672.453101875813;24453.144139955017;24838.875805620857;24986.750132498804;25258.873705631355;25278.873605631856;7699.153093159748;7733.381329493627;7874.330681891989;7877.803054923558;7884.526928384315;3805.296020236265;3920.157166209279;3922.646961773454;3923.588023257517;3927.23418933311;4236.724612900151;4269;4309.245881970643;4310.374995625617;4314.395984562164;917.5962576466334;929.939553929006;954.2223088183108;955.4840386191518;956.6556039825797;629.3140476880222;663.2571519897746;670.6009924095156;673.3233100733829;690.6788343420334;10769.515371808287;11354.48904799286;11719.765604687858;11983.76032479363;12136.271823690608;634.0710858592092;634.1280738983897;634.7651368993502;645.1773988165127;674.275154209227;5207.557357624644;5208.817691380784;5306.071437498423;5350.785968561292;5435.076037073707;97758.02241977632;100796.48805267927;101690.49154754326;101938.98061019443;103891;67495;90415.54792225997;90908.18183636422;91282;93369.99816012941;41066.1786764263;62302.37697622966;75169.49661006859;78054;78977.60511197522;10618.575256989634;11515.596954106566;11560.710982225512;11921.880781192078;12185.878141218474;39250;39408.21183576255;39755.801220994865;39989.800305990866;40174.59825401825;66247;68433.31566684469;69844;70186;72903.63548182225;16279.837201627832;16871.91564042172;16875.662486749952;17034.403795867085;17114.743278851078;17525.824741752418;17955.922644641363;18374.63250734951;18388.724169137742;18455.72316415228;16497.67004659876;17258.395956141474;17340.219690114227;17360.39238626642;17459.95240285587;16259.837401626306;16557.91721041387;17225.22486488086;17337.653246935242;17644.647107058045;16217.675646486769;16363.263653135875;16700.49898503047;16817.159142043092;16928.23822927996;9471.10024547667;9688.612455501701;10217.591296348062;11527.88472115268;12206.877931220573;12046.75906481883;12077.456514456695;12271.877281227431;12594.87405125937;12887.484500620245 02:49:33 INFO - PID 1650 | __end_tp_report 02:49:33 INFO - PID 1650 | __start_cc_report 02:49:33 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1619 02:49:33 INFO - PID 1650 | __end_cc_report 02:49:33 INFO - PID 1650 | __startTimestamp1494236973461__endTimestamp 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | ------- Summary: start ------- 02:49:33 INFO - PID 1650 | Number of tests: 6 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6042.93 Median:6288.34 stddev:685.61 (10.9%) stddev-sans-first:657.59 02:49:33 INFO - PID 1650 | Values: 3480.7 3519.3 3533.6 3547.9 3575.6 6056.8 6117.0 6163.4 6163.8 6178.0 6121.7 6165.0 6188.6 6205.2 6206.3 5198.7 5237.8 5353.8 5373.3 5385.4 5464.5 5464.9 5465.8 5473.2 5488.0 5284.4 5346.0 5349.5 5394.6 5407.5 5299.2 5333.1 5363.5 5378.1 5382.2 5237.9 5280.5 5289.0 5291.6 5357.2 6083.8 6096.5 6097.0 6106.3 6142.4 6333.1 6342.8 6343.0 6361.1 6462.8 6604.0 6636.5 6657.2 6671.1 6675.9 6154.4 6303.1 6307.1 6307.9 6340.6 6554.5 6632.6 6765.3 6772.1 6801.5 5401.3 6368.1 6402.0 6408.3 6414.1 6551.6 6564.8 6621.4 6633.6 6634.4 6313.6 6370.0 6474.4 6476.3 6499.0 6435.3 6515.3 6526.1 6540.0 6553.5 5227.5 5232.8 5235.7 5241.5 5260.3 4665.7 4687.1 4721.7 4754.0 4776.0 5966.7 6110.8 6140.8 6142.4 6185.2 6557.7 6613.9 6630.8 6632.6 6651.1 6259.7 6268.7 6272.0 6279.5 6280.7 5645.5 5720.2 5782.4 5792.0 5797.1 6633.8 6635.1 6734.8 6807.4 6845.0 6314.5 6379.7 6397.9 6624.1 6642.0 6552.1 6622.6 6643.4 6646.2 6646.9 6313.5 6322.7 6341.1 6384.9 6447.4 6619.9 6620.8 6623.0 6633.8 6641.0 5387.3 6324.3 6386.0 6404.4 6411.4 6193.9 6266.1 6274.2 6297.0 6353.0 6220.8 6288.3 6318.0 6323.3 6324.0 6399.6 6403.2 6406.4 6408.0 6440.5 6208.5 6234.0 6252.7 6286.1 6287.9 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:43371.24 Median:17811.99 stddev:76378.20 (428.8%) stddev-sans-first:76525.50 02:49:33 INFO - PID 1650 | Values: 773.6 798.6 811.5 1133.6 1144.3 25290.0 35256.5 35266.0 35544.0 35548.0 37343.4 37721.4 37813.6 37840.6 37841.2 5861.7 6874.4 8275.4 8286.7 8294.3 5896.9 5990.4 5990.8 5999.3 7341.7 8251.7 8280.9 8293.2 8341.4 8348.4 6963.3 8190.0 8260.4 8262.4 8265.0 5813.2 5907.0 6987.2 7877.6 8215.9 34961.9 35102.8 35196.1 35202.0 35220.5 3551.4 3598.7 3598.8 3599.5 3599.9 29003.8 29100.0 29111.4 29111.9 29276.9 3050.8 3102.6 3143.7 3152.8 3154.3 283098.0 287474.0 287688.0 287861.0 289168.0 47990.0 48137.0 48161.0 48230.0 48336.0 50035.5 50102.5 50182.7 50275.0 50292.7 13819.2 13918.1 13933.8 13936.9 13942.4 11320.2 11327.1 11330.3 11335.3 11345.4 10423.2 10427.8 10469.6 10502.7 12278.4 4564.1 4587.1 5006.5 5167.5 5176.7 845.9 1011.5 1019.5 1042.8 1052.3 18228.5 22963.9 23607.2 23618.5 23771.9 996.9 1008.9 1156.2 1157.4 1158.7 10635.5 12136.7 12152.9 12154.1 12179.6 308823.0 309194.0 309272.0 309420.0 309747.0 51712.7 51902.7 51932.7 52104.5 52132.7 1476.6 1493.8 1545.0 1552.8 1570.0 21168.8 22022.7 22867.2 22991.5 23032.3 36261.5 36315.7 36328.8 36349.6 36361.0 274249.0 275414.0 275641.0 275826.0 275990.0 32469.7 32565.3 32609.8 32634.3 32644.3 40164.4 40720.0 42117.6 42246.0 42680.6 27917.4 28892.7 29442.4 29608.4 30053.7 27991.9 28111.9 28838.9 29479.7 29787.9 35816.0 35822.6 35866.7 35873.8 35943.6 28232.9 30351.4 30374.0 30392.7 30413.5 23326.0 23532.3 23645.5 23848.8 23930.4 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:36644.38 Median:22028.79 stddev:96103.73 (436.3%) stddev-sans-first:96334.77 02:49:33 INFO - PID 1650 | Values: 389.1 434.6 437.4 443.9 448.9 17826.4 21201.9 22139.6 22918.2 23305.5 22943.4 23284.4 23666.0 23947.8 24009.0 5539.3 5826.4 5946.7 6086.0 6170.8 6009.5 6088.6 6134.0 6135.9 6140.4 5774.0 5971.1 6053.3 6056.2 6056.2 5997.5 6004.2 6012.5 6079.0 6093.4 5278.6 5356.5 5376.5 5383.7 5387.5 27287.7 27703.8 27805.6 28066.2 28212.0 2219.2 2261.9 2262.5 2263.8 2265.4 15330.5 16894.7 17796.0 17966.8 18311.5 2087.8 2096.6 2152.5 2156.0 2166.8 149922.3 151597.0 152339.0 152488.0 155264.2 9215.5 11855.8 11970.7 12618.6 12706.0 14975.3 15372.8 15413.0 15415.3 16308.7 8947.8 9015.1 9042.4 9069.1 9186.8 8574.0 8595.6 8665.7 8755.0 8863.9 7790.8 7935.4 7940.2 7943.5 7959.1 2163.4 2170.3 2170.5 2181.3 2189.4 908.4 978.9 993.6 996.3 1029.4 21163.5 21851.9 21853.0 21876.8 21918.0 885.4 1001.4 1037.1 1042.8 1050.7 9277.0 10469.5 10620.7 10969.2 11025.1 556126.0 566007.0 567169.0 571672.0 579014.0 123874.8 127774.0 134753.0 138373.0 144037.6 1196.8 1244.0 1248.4 1252.3 1262.4 21065.2 21874.0 22686.4 23190.0 23228.0 59687.0 60146.0 60423.8 60544.0 61238.1 72929.3 76220.2 82924.2 83962.6 84563.2 10546.9 12695.8 13152.3 13274.0 13529.7 14334.1 14343.4 14514.9 14695.0 14743.0 9138.8 11514.3 11664.8 11685.8 12187.9 11342.8 11528.8 11534.4 11878.3 11879.0 12011.0 12053.0 12231.8 12336.9 12340.0 12829.8 13086.4 13159.0 13258.3 13305.0 11824.1 11971.9 12119.5 12148.8 12211.6 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7056.20 Median:3817.85 stddev:4461.80 (116.9%) stddev-sans-first:4453.47 02:49:33 INFO - PID 1650 | Values: 1316.6 1388.1 1442.7 1463.3 1470.1 11121.8 11238.0 11696.9 11701.7 11731.9 11430.3 11634.8 11640.5 11641.7 11649.2 2482.9 2519.6 2526.7 2532.5 2533.0 2102.6 2183.9 2430.1 2512.2 2518.5 1846.8 2408.6 2507.3 2518.4 2525.2 2472.8 2489.2 2520.7 2521.7 2525.8 2131.0 2382.0 2417.0 2442.9 2446.6 11129.3 11328.9 11334.1 11340.2 11357.9 5694.2 6431.0 6670.7 6693.7 6702.1 3895.7 4223.7 4292.7 4313.7 4317.8 1243.1 1244.3 1274.2 1280.7 1290.8 15943.8 16271.0 16286.6 16287.8 16308.8 5736.3 5866.1 5953.6 5974.0 6071.0 13527.0 13533.1 13563.1 13576.9 13591.1 6718.3 6737.0 6737.2 6739.6 6740.8 5025.8 6017.9 6097.2 6211.6 6216.2 3739.9 4569.1 4633.3 4681.9 4688.3 1133.2 1402.6 1447.5 1462.8 1469.7 665.3 722.7 794.7 806.6 818.2 5863.0 5868.1 5953.5 6303.7 6637.7 957.1 968.9 969.5 970.8 972.9 3684.0 4025.9 4220.6 4226.9 4385.6 14338.6 14609.3 14868.9 14888.6 14959.5 14340.6 14583.2 14750.3 14761.4 14817.9 7225.4 8393.2 8602.9 8661.3 8673.7 6486.6 6557.1 6677.3 6740.0 6743.0 10464.2 12912.7 13284.8 13481.3 13566.1 13029.7 13491.7 13528.8 13552.7 13588.5 8351.0 9340.2 9347.1 9370.2 9373.2 9272.4 10107.4 10198.4 10535.5 10657.0 8892.6 9088.5 9101.0 9136.8 9480.1 8727.1 8848.1 8893.9 8943.3 8960.8 7846.6 8016.3 8019.0 8023.4 8025.8 5930.6 5962.6 6077.1 6131.4 6185.0 5984.9 6116.7 6165.2 6179.6 6347.3 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5102.71 Median:5709.27 stddev:1580.86 (27.7%) stddev-sans-first:1545.25 02:49:33 INFO - PID 1650 | Values: 391.9 395.6 397.3 400.3 403.7 5302.8 5847.9 5855.0 5939.1 5939.6 5702.6 5716.0 5793.6 5796.0 5823.6 4302.0 4462.2 4469.8 4475.2 4476.4 3861.0 4182.6 4476.1 4489.7 4491.5 4191.1 4298.5 4413.0 4453.2 4456.5 4393.7 4407.8 4422.2 4423.0 4463.3 3333.9 3928.6 3940.3 3944.7 3959.5 4253.4 4623.8 4687.3 5448.7 5700.6 5288.6 5322.7 5328.3 5343.3 5352.3 5891.9 6183.7 6262.7 6279.0 6282.3 4017.4 4321.0 4407.3 4475.3 4499.1 6233.8 6367.2 6436.5 6494.0 6654.5 4021.1 4684.0 4755.8 4790.2 4796.0 4606.5 4677.9 4712.8 4858.7 4867.4 4681.4 5068.6 5305.4 5346.5 5445.8 5428.2 6290.9 6341.7 6401.6 6420.3 1410.6 1475.9 1499.1 1499.9 1589.6 811.0 828.1 954.0 983.3 984.2 5151.3 5797.9 5951.9 5968.7 5986.0 5282.6 5789.7 6535.7 6565.7 6584.7 4744.7 4783.4 5038.9 5399.4 5741.3 2802.9 3045.2 3443.4 3485.0 3501.0 5803.8 6650.0 6717.8 6817.8 6891.2 6187.0 6696.3 6804.2 6817.7 6835.6 5732.5 6576.8 6824.0 6860.9 6875.0 5471.8 5851.9 6187.6 6587.2 6611.9 5065.4 5651.7 6202.7 6359.5 6575.9 4899.0 5161.0 6608.3 6803.9 6839.5 3789.8 4247.6 4281.8 4304.0 4306.5 5526.0 6449.2 6465.8 6536.5 6585.6 5843.6 6342.8 6350.3 6366.0 6456.3 6031.8 6356.5 6374.7 6389.8 6412.6 5341.8 6170.3 6292.3 6308.4 6325.5 6088.8 6334.6 6431.0 6453.9 6488.1 6189.2 6223.0 6238.3 6247.9 6258.9 02:49:33 INFO - PID 1650 | 02:49:33 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:20845.01 Median:21454.43 stddev:28292.45 (131.9%) stddev-sans-first:28331.98 02:49:33 INFO - PID 1650 | Values: 849.8 857.2 871.7 875.7 884.1 13773.7 15839.5 16528.9 16535.8 16569.0 16924.7 17158.0 17310.9 17350.7 17365.5 844.6 988.2 988.3 988.4 990.5 978.0 980.2 981.3 983.8 1019.7 1013.6 1021.9 1024.1 1024.7 1062.3 1014.0 1016.3 1018.5 1020.5 1058.9 778.1 779.2 779.5 780.0 783.1 16398.3 16616.2 16815.4 17059.7 17402.4 16837.0 16922.3 17130.9 17279.7 17379.2 13634.7 13684.6 13829.9 13898.4 13976.4 3486.7 3494.1 3504.1 3505.6 3508.2 62819.4 96758.5 109679.0 113069.0 130930.7 11030.9 13416.1 13617.7 13624.0 13672.5 24453.1 24838.9 24986.8 25258.9 25278.9 7699.2 7733.4 7874.3 7877.8 7884.5 3805.3 3920.2 3922.6 3923.6 3927.2 4236.7 4269.0 4309.2 4310.4 4314.4 917.6 929.9 954.2 955.5 956.7 629.3 663.3 670.6 673.3 690.7 10769.5 11354.5 11719.8 11983.8 12136.3 634.1 634.1 634.8 645.2 674.3 5207.6 5208.8 5306.1 5350.8 5435.1 97758.0 100796.5 101690.5 101939.0 103891.0 67495.0 90415.5 90908.2 91282.0 93370.0 41066.2 62302.4 75169.5 78054.0 78977.6 10618.6 11515.6 11560.7 11921.9 12185.9 39250.0 39408.2 39755.8 39989.8 40174.6 66247.0 68433.3 69844.0 70186.0 72903.6 16279.8 16871.9 16875.7 17034.4 17114.7 17525.8 17955.9 18374.6 18388.7 18455.7 16497.7 17258.4 17340.2 17360.4 17460.0 16259.8 16557.9 17225.2 17337.7 17644.6 16217.7 16363.3 16700.5 16817.2 16928.2 9471.1 9688.6 10217.6 11527.9 12206.9 12046.8 12077.5 12271.9 12594.9 12887.5 02:49:33 INFO - PID 1650 | -------- Summary: end -------- 02:49:33 INFO - PID 1650 | 02:49:34 INFO - TEST-INFO | 1650: exit 0 02:49:34 INFO - TEST-OK | dromaeo_css | took 1118554ms 02:49:34 INFO - TEST-START | kraken 02:49:34 INFO - operating with platform_type : mac_ 02:49:34 INFO - Initialising browser for kraken test... 02:49:34 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpbFB8Xv/profile http://localhost:49242/getInfo.html) 02:49:36 INFO - PID 1659 | __metrics Screen width/height:1600/1200 02:49:36 INFO - PID 1659 | colorDepth:24 02:49:36 INFO - PID 1659 | Browser inner width/height: 1024/691 02:49:36 INFO - PID 1659 | __metrics 02:49:36 INFO - PID 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:49:36 INFO - PID 1659 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:49:37 INFO - TEST-INFO | 1659: exit 0 02:49:37 INFO - Browser initialized. 02:49:37 INFO - Running cycle 1/1 for kraken test... 02:49:37 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpbFB8Xv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:49 INFO - PID 1660 | RSS: Main: 239779840 02:49:49 INFO - PID 1660 | 02:49:52 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 02:49:53 INFO - PID 1660 | RSS: Main: 308178944 02:49:53 INFO - PID 1660 | 02:49:55 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 02:49:56 INFO - PID 1660 | RSS: Main: 290959360 02:49:56 INFO - PID 1660 | 02:49:59 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 02:50:00 INFO - PID 1660 | RSS: Main: 383062016 02:50:00 INFO - PID 1660 | 02:50:02 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 02:50:02 INFO - PID 1660 | RSS: Main: 284860416 02:50:02 INFO - PID 1660 | 02:50:04 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:50:05 INFO - PID 1660 | RSS: Main: 288432128 02:50:05 INFO - PID 1660 | 02:50:08 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 02:50:08 INFO - PID 1660 | RSS: Main: 354398208 02:50:08 INFO - PID 1660 | 02:50:11 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 02:50:12 INFO - PID 1660 | RSS: Main: 375660544 02:50:12 INFO - PID 1660 | 02:50:14 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 02:50:15 INFO - PID 1660 | RSS: Main: 390348800 02:50:15 INFO - PID 1660 | 02:50:17 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:50:17 INFO - PID 1660 | RSS: Main: 330645504 02:50:17 INFO - PID 1660 | 02:50:19 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:50:20 INFO - PID 1660 | RSS: Main: 331829248 02:50:20 INFO - PID 1660 | 02:50:22 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:50:22 INFO - PID 1660 | RSS: Main: 333733888 02:50:22 INFO - PID 1660 | 02:50:25 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:50:26 INFO - PID 1660 | RSS: Main: 351670272 02:50:26 INFO - PID 1660 | 02:50:29 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:50:30 INFO - PID 1660 | RSS: Main: 344592384 02:50:30 INFO - PID 1660 | 02:50:31 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 02:50:32 INFO - PID 1660 | RSS: Main: 388210688 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | __start_tp_report 02:50:32 INFO - PID 1660 | _x_x_mozilla_page_load 02:50:32 INFO - PID 1660 | _x_x_mozilla_page_load_details 02:50:32 INFO - PID 1660 | |i|pagename|runs| 02:50:32 INFO - PID 1660 | |0;ai-astar;109;112;110;110;110;112;110;110;109;111 02:50:32 INFO - PID 1660 | |1;audio-beat-detection;138;111;108;149;110;107;106;114;150;88 02:50:32 INFO - PID 1660 | |2;audio-dft;186;181;184;183;184;186;181;186;183;181 02:50:32 INFO - PID 1660 | |3;audio-fft;80;56;59;91;57;60;59;56;81;58 02:50:32 INFO - PID 1660 | |4;audio-oscillator;89;68;84;67;68;68;89;69;68;90 02:50:32 INFO - PID 1660 | |5;imaging-gaussian-blur;86;85;86;86;86;86;86;86;85;85 02:50:32 INFO - PID 1660 | |6;imaging-darkroom;92;93;93;94;92;94;93;93;92;93 02:50:32 INFO - PID 1660 | |7;imaging-desaturate;85;86;83;85;84;86;86;83;84;83 02:50:32 INFO - PID 1660 | |8;json-parse-financial;51;49;49;47;47;47;50;50;49;49 02:50:32 INFO - PID 1660 | |9;json-stringify-tinderbox;53;56;62;66;63;60;58;57;56;57 02:50:32 INFO - PID 1660 | |10;stanford-crypto-aes;83;81;85;88;82;82;82;82;82;81 02:50:32 INFO - PID 1660 | |11;stanford-crypto-ccm;152;144;132;140;150;134;136;135;172;144 02:50:32 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;183;182;184;184;184;182;185;182;202;184 02:50:32 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;68;68;67;72;67;67;68;66;68;70 02:50:32 INFO - PID 1660 | __end_tp_report 02:50:32 INFO - PID 1660 | __start_cc_report 02:50:32 INFO - PID 1660 | _x_x_mozilla_cycle_collect,2141 02:50:32 INFO - PID 1660 | __end_cc_report 02:50:32 INFO - PID 1660 | __startTimestamp1494237032105__endTimestamp 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | ------- Summary: start ------- 02:50:32 INFO - PID 1660 | Number of tests: 14 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:1.06 (1.0%) stddev-sans-first:1.01 02:50:32 INFO - PID 1660 | Values: 109.0 112.0 110.0 110.0 110.0 112.0 110.0 110.0 109.0 111.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:126.00 stddev:20.49 (16.3%) stddev-sans-first:20.43 02:50:32 INFO - PID 1660 | Values: 138.0 111.0 108.0 149.0 110.0 107.0 106.0 114.0 150.0 88.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:2.07 (1.1%) stddev-sans-first:1.99 02:50:32 INFO - PID 1660 | Values: 186.0 181.0 184.0 183.0 184.0 186.0 181.0 186.0 183.0 181.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:65.70 Median:59.50 stddev:13.01 (21.9%) stddev-sans-first:12.73 02:50:32 INFO - PID 1660 | Values: 80.0 56.0 59.0 91.0 57.0 60.0 59.0 56.0 81.0 58.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:76.50 stddev:10.46 (13.7%) stddev-sans-first:9.98 02:50:32 INFO - PID 1660 | Values: 89.0 68.0 84.0 67.0 68.0 68.0 89.0 69.0 68.0 90.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 02:50:32 INFO - PID 1660 | Values: 86.0 85.0 86.0 86.0 86.0 86.0 86.0 86.0 85.0 85.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 02:50:32 INFO - PID 1660 | Values: 92.0 93.0 93.0 94.0 92.0 94.0 93.0 93.0 92.0 93.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.27 (1.5%) stddev-sans-first:1.33 02:50:32 INFO - PID 1660 | Values: 85.0 86.0 83.0 85.0 84.0 86.0 86.0 83.0 84.0 83.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:48.80 Median:49.00 stddev:1.40 (2.9%) stddev-sans-first:1.24 02:50:32 INFO - PID 1660 | Values: 51.0 49.0 49.0 47.0 47.0 47.0 50.0 50.0 49.0 49.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:3.91 (6.6%) stddev-sans-first:3.54 02:50:32 INFO - PID 1660 | Values: 53.0 56.0 62.0 66.0 63.0 60.0 58.0 57.0 56.0 57.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:82.00 stddev:2.15 (2.6%) stddev-sans-first:2.28 02:50:32 INFO - PID 1660 | Values: 83.0 81.0 85.0 88.0 82.0 82.0 82.0 82.0 82.0 81.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:144.00 stddev:11.97 (8.3%) stddev-sans-first:12.33 02:50:32 INFO - PID 1660 | Values: 152.0 144.0 132.0 140.0 150.0 134.0 136.0 135.0 172.0 144.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:184.00 stddev:6.00 (3.3%) stddev-sans-first:6.31 02:50:32 INFO - PID 1660 | Values: 183.0 182.0 184.0 184.0 184.0 182.0 185.0 182.0 202.0 184.0 02:50:32 INFO - PID 1660 | 02:50:32 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.73 (2.5%) stddev-sans-first:1.83 02:50:32 INFO - PID 1660 | Values: 68.0 68.0 67.0 72.0 67.0 67.0 68.0 66.0 68.0 70.0 02:50:32 INFO - PID 1660 | -------- Summary: end -------- 02:50:32 INFO - PID 1660 | 02:50:33 INFO - TEST-INFO | 1660: exit 0 02:50:33 INFO - TEST-OK | kraken | took 59020ms 02:50:33 INFO - SUITE-END | took 1177s 02:50:33 INFO - Completed test suite (00:19:37) 02:50:33 INFO - javascript benchmark 02:50:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7757.132220031276, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.721542276618, 3519.348920449717, 3533.5759708834935, 3547.946780798288, 3575.6245594212605, 6056.818295451132, 6117.005394430401, 6163.414475624814, 6163.784267550636, 6177.969110154443, 6121.693915304239, 6165.0, 6188.628682279055, 6205.22434695663, 6206.286277078138, 5198.68807871527, 5237.816676416326, 5353.7590808413715, 5373.328333958255, 5385.3537575491, 5464.508194262516, 5464.9453505465035, 5465.754041068161, 5473.2063850741615, 5487.972560137214, 5284.392294886099, 5345.973270133663, 5349.51854333108, 5394.6493477924205, 5407.459254074601, 5299.152135658276, 5333.066713325192, 5363.517283444469, 5378.058839703075, 5382.219578161144, 5237.895242095175, 5280.498352656491, 5289.0, 5291.629585928987, 5357.196420536911, 6083.8174854754425, 6096.4817990470865, 6097.02447608385, 6106.297775755756, 6142.416470435302, 6333.11336412898, 6342.80971570849, 6342.968285158545, 6361.077643741678, 6462.838429039311, 6604.0, 6636.502262330297, 6657.167854018247, 6671.099401580773, 6675.899861502081, 6154.415330543681, 6303.0545418187185, 6307.05394190875, 6307.8738425231695, 6340.619562826245, 6554.541182117206, 6632.602043877383, 6765.255821859591, 6772.119624448888, 6801.489888258301, 5401.297831281908, 6368.14030105939, 6402.0, 6408.327125651739, 6414.1340918976275, 6551.639659818756, 6564.770233041821, 6621.4040736333, 6633.568818026813, 6634.436072933758, 6313.5896166749935, 6370.0, 6474.352564743488, 6476.287608363076, 6498.967505162444, 6435.324290949477, 6515.315891831384, 6526.118973938553, 6539.967300163468, 6553.475721942234, 5227.503387178212, 5232.790688372423, 5235.685858848481, 5241.50205730455, 5260.316158899395, 4665.6967297125575, 4687.1328804171335, 4721.740304283228, 4753.954130091374, 4776.0, 5966.731497082555, 6110.801781737137, 6140.815775526741, 6142.355052719489, 6185.226846644169, 6557.704903279364, 6613.900791488035, 6630.7679231226675, 6632.635205063768, 6651.135352404156, 6259.71831267594, 6268.655223962726, 6272.0, 6279.529035322369, 6280.7487700491465, 5645.463680950304, 5720.170575266563, 5782.392848750821, 5792.044312688444, 5797.101449275369, 6633.834154146281, 6635.104260924713, 6734.764283250062, 6807.3873351398615, 6845.007473916155, 6314.526410519137, 6379.712912918837, 6397.936020639734, 6624.138861947916, 6642.036904648702, 6552.082708420729, 6622.63575503352, 6643.368879956396, 6646.202455705346, 6646.933530664632, 6313.463355614824, 6322.683865806599, 6341.112244285896, 6384.93615063862, 6447.3552644735155, 6619.933800662125, 6620.8013759585365, 6623.0, 6633.800985970429, 6641.037049627874, 5387.299651045339, 6324.272708638566, 6386.0, 6404.391582799627, 6411.4229719325485, 6193.907091393543, 6266.091416744638, 6274.152989346379, 6297.0, 6353.0, 6220.7511699531515, 6288.339724328879, 6317.96841015792, 6323.272823625342, 6323.9683801580695, 6399.64801935898, 6403.167588213502, 6406.359364063556, 6408.0, 6440.516961228021, 6208.534359922934, 6234.0, 6252.656103914328, 6286.088517164894, 6287.93105172131], "value": 5998.099335375258, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.6402572803648, 798.5727635714893, 811.4725428471493, 1133.6315697398347, 1144.273386399636, 25290.0, 35256.471152932696, 35266.0, 35544.0, 35548.0, 37343.4398484023, 37721.43417848735, 37813.62186378135, 37840.62159378405, 37841.24317513648, 5861.677607731573, 6874.415674667647, 8275.420720549564, 8286.668533258662, 8294.294984926288, 5896.911546326808, 5990.400959903996, 5990.790322338719, 5999.340072592011, 7341.669624866867, 8251.669933202666, 8280.875786863202, 8293.21214484623, 8341.374396920224, 8348.41561090724, 6963.268856770017, 8189.95905020477, 8260.4217704761, 8262.42163048587, 8265.049519305237, 5813.215215945836, 5907.02534081876, 6987.231404545496, 7877.606119693993, 8215.876761848518, 34961.85343319088, 35102.82448587766, 35196.12009699778, 35202.0, 35220.47169292463, 3551.3607550640872, 3598.676119149263, 3598.838052287653, 3599.532060832075, 3599.9280014399565, 29003.839846406117, 29100.0, 29111.41777164466, 29111.85444072787, 29276.853615731787, 3050.771192160597, 3102.6431960324626, 3143.6699146589735, 3152.7793054486197, 3154.274516861135, 283098.0, 287474.0, 287688.0, 287861.0, 289168.0, 47990.0, 48137.0, 48161.03677926422, 48230.03539929182, 48336.0, 50035.49964500381, 50102.49897501051, 50182.74543136451, 50275.0, 50292.74268143252, 13819.170849749047, 13918.09532380392, 13933.790993135111, 13936.930315348358, 13942.442302307794, 11320.150988675883, 11327.093832493381, 11330.263532870502, 11335.263207891625, 11345.376004319842, 10423.166146708245, 10427.791444171075, 10469.581216751398, 10502.73743156412, 12278.447469863877, 4564.109998550317, 4587.128445595326, 5006.4743201964, 5167.483251674803, 5176.715280659525, 845.9492430454193, 1011.4841430870309, 1019.495349801838, 1042.7758032023, 1052.3370276725766, 18228.544286392946, 22963.88518057399, 23607.173748918707, 23618.527629447657, 23771.881140594185, 996.928302075272, 1008.8940661230612, 1156.2426610569921, 1157.386585109893, 1158.71611455194, 10635.46822658864, 12136.696582585506, 12152.939235303767, 12154.088443366783, 12179.634610961508, 308823.0, 309194.0, 309272.0, 309420.0, 309747.0, 51712.707182320744, 51902.74048629733, 51932.74033629808, 52104.47895520996, 52132.69668258248, 1476.5570328901288, 1493.8431464696268, 1545.042073914158, 1552.774847647085, 1569.9529014129594, 21168.78831211668, 22022.678639281694, 22867.199648012906, 22991.54016919686, 23032.272579556266, 36261.45607815832, 36315.72894948664, 36328.81835590911, 36349.63650363568, 36361.0, 274249.0, 275414.0, 275641.0, 275826.0, 275990.0, 32469.7012119522, 32565.34869302648, 32609.836950815094, 32634.34731305313, 32644.34711305808, 40164.39753403643, 40720.0, 42117.57882421136, 42246.0, 42680.5731942689, 27917.441651167268, 28892.711072889844, 29442.411151776418, 29608.407831843673, 30053.699463005963, 27991.860040699667, 28111.859440702665, 28838.855805720836, 29479.705202947694, 29787.851060744557, 35816.0, 35822.56709731581, 35866.74466393664, 35873.82063089668, 35943.64056359507, 28232.85883570569, 30351.392972140875, 30374.0, 30392.696073038987, 30413.543796843507, 23325.950332234752, 23532.29403117909, 23645.527089458457, 23848.761512385347, 23930.40173995664], "value": 16569.031205916704, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.1205874723125, 434.6045098959947, 437.37574552683895, 443.8712773295744, 448.8832903445103, 17826.376076837296, 21201.939903004866, 22139.557208855815, 22918.197863074816, 23305.533889322203, 22943.42641433965, 23284.41788955277, 23666.0, 23947.760522394816, 24009.0, 5539.335279766434, 5826.388229235933, 5946.672932988673, 6085.9653858843885, 6170.753169873222, 6009.549283803711, 6088.6042407243385, 6134.049222370518, 6135.938640613604, 6140.447359737623, 5774.0, 5971.134185543116, 6053.303870054934, 6056.152138700585, 6056.212692349988, 5997.460228579449, 6004.219451471303, 6012.518998480111, 6078.996965500709, 6093.360197179299, 5278.551323137538, 5356.544693701001, 5376.51611354976, 5383.703896285702, 5387.4612538746205, 27287.727122728716, 27703.753331099946, 27805.582916256273, 28066.158015259574, 28212.0, 2219.1678120704737, 2261.8642881427168, 2262.468319944812, 2263.807576356012, 2265.4223173090923, 15330.540083797503, 16894.74657880133, 17796.02121883296, 17966.820331796647, 18311.54221144469, 2087.7912208779153, 2096.6121267565395, 2152.526444182293, 2156.0, 2166.7833216678364, 149922.25038874737, 151597.0, 152339.0, 152488.0, 155264.223678882, 9215.493147876798, 11855.822162667568, 11970.700732481584, 12618.558350457874, 12706.0, 14975.251237438302, 15372.769408458882, 15412.998155119882, 15415.306311216022, 16308.67382652364, 8947.821043579092, 9015.053419391, 9042.412243204171, 9069.093090690878, 9186.816263674822, 8574.0, 8595.616009297975, 8665.740027799176, 8755.0, 8863.867041994376, 7790.766277011699, 7935.444518883738, 7940.205979402129, 7943.483673561315, 7959.124496305401, 2163.3942496101117, 2170.305502239268, 2170.533335332911, 2181.2692747929623, 2189.3618190080915, 908.355067901784, 978.9265805064506, 993.5677980078689, 996.3225006995168, 1029.4235228272041, 21163.470913226985, 21851.890740546514, 21853.016614252396, 21876.781232187794, 21918.013689384017, 885.4112015509743, 1001.379144930148, 1037.1288117980932, 1042.8487869258915, 1050.7320633238476, 9277.0, 10469.487323042567, 10620.734481638021, 10969.23215374916, 11025.117990560735, 556126.0, 566007.0, 567169.0, 571672.0, 579014.0, 123874.76125238813, 127774.0, 134753.0, 138373.0, 144037.5596244024, 1196.8144937534696, 1243.9813402798966, 1248.4007676315418, 1252.2549083295426, 1262.4192871279315, 21065.15739370408, 21874.0, 22686.432839179153, 23190.028847547474, 23228.0, 59687.0, 60146.0, 60423.77033607605, 60544.0, 61238.10095192463, 72929.27070729225, 76220.23779762132, 82924.17075829164, 83962.58018709868, 84563.154368458, 10546.894531054591, 12695.80956285675, 13152.342382881008, 13274.0, 13529.72940541164, 14334.068285561405, 14343.354549045109, 14514.927425362806, 14695.0, 14743.041702289316, 9138.811954445879, 11514.30914145154, 11664.825027624423, 11685.824712629148, 12187.939060304641, 11342.829857551978, 11528.769424611628, 11534.423278835857, 11878.287302761863, 11879.0, 12011.0, 12053.011253143133, 12231.75536489283, 12336.938315308365, 12340.0, 12829.807552886528, 13086.411111499814, 13159.0, 13258.270795106362, 13305.004249362286, 11824.1131915105, 11971.880281197076, 12119.45462454174, 12148.817767733668, 12211.633650990485], "value": 10760.399364777086, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.591856524478, 1388.0769288423198, 1442.6970336229392, 1463.3268696399655, 1470.1032370254143, 11121.777564448706, 11238.044766194862, 11696.941515292423, 11701.70745731357, 11731.882681173196, 11430.25703329283, 11634.767304653902, 11640.476178571964, 11641.70895727605, 11649.242799218046, 2482.875856207187, 2519.5716728156167, 2526.68416447944, 2532.4555220627562, 2533.0121252711456, 2102.5794841031857, 2183.912643494258, 2430.0644251962945, 2512.170983575425, 2518.4837108392735, 1846.7876194237701, 2408.6266628672583, 2507.323022783847, 2518.44594189279, 2525.217182673368, 2472.765087316702, 2489.166129346671, 2520.6849143856925, 2521.7478252174637, 2525.785308248801, 2131.031091197202, 2382.0114652419225, 2416.9637455438183, 2442.914497992562, 2446.645236440724, 11129.332240065622, 11328.886711132865, 11334.093272538177, 11340.20618556693, 11357.943210283896, 5694.174344719992, 6431.0, 6670.666466676646, 6693.665316734193, 6702.128723265993, 3895.7467764595212, 4223.683223758199, 4292.699511034229, 4313.698041137121, 4317.76252306123, 1243.1422318600137, 1244.3156264054733, 1274.1654216488214, 1280.673428275793, 1290.812832139344, 15943.760843587355, 16271.0, 16286.592835179097, 16287.846599117149, 16308.836911630851, 5736.282964629421, 5866.120081987736, 5953.642781433128, 5974.0, 6070.969645151746, 13527.0, 13533.052686311863, 13563.11839730434, 13576.93211533936, 13591.116577422634, 6718.294579069225, 6737.0, 6737.225219099768, 6739.5619284746335, 6740.831479213059, 5025.849224523197, 6017.909731354033, 6097.176881120992, 6211.565190436626, 6216.191895053614, 3739.9439008414897, 4569.109023740339, 4633.328167415706, 4681.9297710534365, 4688.296755486636, 1133.18410744264, 1402.6212922510865, 1447.5223176351778, 1462.7659574468246, 1469.669324402001, 665.3432951949611, 722.7217521254249, 794.7059587952494, 806.5765473126538, 818.2349503214514, 5863.0, 5868.149118377812, 5953.529285346533, 6303.716332765037, 6637.701303441357, 957.1003256938455, 968.932174747775, 969.5007071358256, 970.8058388322364, 972.9318947673736, 3684.0053185639727, 4025.9194816103513, 4220.577942205816, 4226.873193804191, 4385.627221686193, 14338.56984290473, 14609.342579583945, 14868.851311486964, 14888.553343399499, 14959.476418325306, 14340.56978290653, 14583.22918645454, 14750.262486875614, 14761.40954361835, 14817.851821481863, 7225.385842203366, 8393.160683931557, 8602.91397086021, 8661.30709543235, 8673.739787806377, 6486.610803351814, 6557.114789503356, 6677.2988836173445, 6739.966300168468, 6743.0, 10464.215183861394, 12912.677183070498, 13284.80072798928, 13481.2585307809, 13566.050376473946, 13029.739405212033, 13491.730165396833, 13528.797068044185, 13552.728945421233, 13588.524401645895, 8351.0, 9340.159385655325, 9347.112024357675, 9370.156685898302, 9373.20327772147, 9272.350935434455, 10107.444090574794, 10198.43908585035, 10535.525901334306, 10657.0, 8892.599833007402, 9088.500132492776, 9101.044390339184, 9136.817263654822, 9480.146786789222, 8727.127287271222, 8848.1151884811, 8893.866592000995, 8943.329250306384, 8960.820783584422, 7846.568438735924, 8016.278534931884, 8019.0, 8023.398245131755, 8025.799355016171, 5930.584859059824, 5962.612430192024, 6077.149199112039, 6131.386861314011, 6185.041318595625, 5984.850378740392, 6116.66358350295, 6165.198524191826, 6179.62922224668, 6347.270063942706], "value": 5349.333481722738, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.8883118311282, 395.55697618667097, 397.30471674569503, 400.26950814763063, 403.7436227995223, 5302.78788848446, 5847.941520584793, 5855.0, 5939.1291743101, 5939.613925094865, 5702.572307076975, 5715.971420142904, 5793.565482588801, 5795.971020144893, 5823.621464604808, 4302.01053757636, 4462.237416220591, 4469.843555475559, 4475.239209334405, 4476.418065651461, 3861.0, 4182.581741825808, 4476.149531588988, 4489.663275254353, 4491.483479399862, 4191.119864828374, 4298.484181898177, 4412.955870441287, 4453.2206863798865, 4456.509783923766, 4393.7363758174615, 4407.757573333465, 4422.159789639958, 4423.0, 4463.308187231, 3333.8833140839956, 3928.5874983126932, 3940.290747665419, 3944.684425245973, 3959.46547216125, 4253.447051883266, 4623.768811559442, 4687.320338550891, 5448.727563621803, 5700.572457065695, 5288.588860185159, 5322.680639161663, 5328.280682107905, 5343.305370301875, 5352.330958630171, 5891.909996650634, 6183.6598987055695, 6262.718177682015, 6278.968605156945, 6282.277538083134, 4017.4174744662155, 4320.956790432087, 4407.338899165119, 4475.283954567253, 4499.122671079141, 6233.781817636361, 6367.235931688227, 6436.485081193493, 6494.0, 6654.467642588581, 4021.1153546219784, 4684.0, 4755.809767609257, 4790.209615413488, 4795.976020119877, 4606.539346065434, 4677.929831052537, 4712.811487540528, 4858.732769697701, 4867.367242258484, 4681.391419115561, 5068.594512438996, 5305.44292849253, 5346.465353464622, 5445.809396671098, 5428.158635411518, 6290.867643824064, 6341.746330146742, 6401.64790936503, 6420.261669907927, 1410.605030591436, 1475.9188244646432, 1499.0930487055393, 1499.8500149985132, 1589.6423304756338, 811.0065170166499, 828.1139181076311, 954.0030667951868, 983.306768728045, 984.2038280138776, 5151.2530683050745, 5797.8550536236935, 5951.940480595139, 5968.7015649217365, 5986.0, 5282.550983166474, 5789.681567513827, 6535.725533520917, 6565.67171641416, 6584.670766461658, 4744.715317080917, 4783.425988881357, 5038.949610503922, 5399.352077750695, 5741.25363702716, 2802.9159125226274, 3045.193023848682, 3443.364401909073, 3484.982575087159, 3501.0197144799495, 5803.82588522345, 6649.966750166315, 6717.783131566572, 6817.829554261183, 6891.241963384024, 6186.958303759839, 6696.330366963362, 6804.217514985741, 6817.659117044227, 6835.624040677811, 5732.512736417368, 6576.80269591913, 6824.010518474794, 6860.897086543706, 6875.037494750739, 5471.8084867029465, 5851.94148058531, 6187.566870319034, 6587.242467116152, 6611.933880661132, 5065.442801291929, 5651.745671444877, 6202.689865506797, 6359.523035772243, 6575.942186883122, 4899.020195960751, 5160.987214986233, 6608.281846719983, 6803.9319606805275, 6839.487038472035, 3789.77261364319, 4247.638950689103, 4281.779692787541, 4303.967047908417, 4306.514252582854, 5525.972370138124, 6449.161608990801, 6465.838354041187, 6536.477081833537, 6585.6377899216, 5843.558895239734, 6342.7780027700655, 6350.269718982377, 6366.013267943476, 6456.2575303838785, 6031.763488484762, 6356.491480681627, 6374.681265936777, 6389.840253993687, 6412.551121421456, 5341.78632854697, 6170.290416602148, 6292.276388215414, 6308.400701933309, 6325.462335701517, 6088.756449741961, 6334.556581039283, 6430.967845160744, 6453.870572649674, 6488.124103246001, 6189.195404597373, 6223.0, 6238.251409830849, 6247.937850565324, 6258.873402787542], "value": 4609.389826099995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [849.8427790858696, 857.2327766648848, 871.6513394642143, 875.6935072724551, 884.1247165306345, 13773.655658608539, 15839.524814255576, 16528.917355413236, 16535.842491025633, 16569.0, 16924.746128808078, 17158.0, 17310.913445432787, 17350.652986940284, 17365.479035628952, 844.5945945945949, 988.1650005745134, 988.2588058955783, 988.367444835306, 990.4997976022099, 978.0317485689187, 980.186445250444, 981.2542467724545, 983.7589790501302, 1019.7348689340788, 1013.6198925402974, 1021.9131373833233, 1024.1192574386023, 1024.692592222334, 1062.3041907550567, 1014.0265345268551, 1016.3495362967707, 1018.4703953943982, 1020.4846552490944, 1058.9417582032986, 778.1284960843891, 779.2207792207793, 779.5478622399052, 779.9548604869502, 783.0642382353091, 16398.26207820639, 16616.169191540375, 16815.41146059894, 17059.658806823794, 17402.39091631787, 16837.0, 16922.27709903819, 17130.91434542832, 17279.74080388795, 17379.21793519282, 13634.659133521642, 13684.589462316146, 13829.861701382957, 13898.444062237499, 13976.371063302176, 3486.738494612914, 3494.1264683829045, 3504.0714210734177, 3505.6143824179576, 3508.210652603144, 62819.37180628181, 96758.51620741922, 109679.0, 113069.0, 130930.69069309281, 11030.889691103146, 13416.12795168311, 13617.727645447036, 13624.04631675773, 13672.453101875813, 24453.144139955017, 24838.875805620857, 24986.750132498804, 25258.873705631355, 25278.873605631856, 7699.153093159748, 7733.381329493627, 7874.330681891989, 7877.803054923558, 7884.526928384315, 3805.296020236265, 3920.157166209279, 3922.646961773454, 3923.588023257517, 3927.23418933311, 4236.724612900151, 4269.0, 4309.245881970643, 4310.374995625617, 4314.395984562164, 917.5962576466334, 929.939553929006, 954.2223088183108, 955.4840386191518, 956.6556039825797, 629.3140476880222, 663.2571519897746, 670.6009924095156, 673.3233100733829, 690.6788343420334, 10769.515371808287, 11354.48904799286, 11719.765604687858, 11983.76032479363, 12136.271823690608, 634.0710858592092, 634.1280738983897, 634.7651368993502, 645.1773988165127, 674.275154209227, 5207.557357624644, 5208.817691380784, 5306.071437498423, 5350.785968561292, 5435.076037073707, 97758.02241977632, 100796.48805267927, 101690.49154754326, 101938.98061019443, 103891.0, 67495.0, 90415.54792225997, 90908.18183636422, 91282.0, 93369.99816012941, 41066.1786764263, 62302.37697622966, 75169.49661006859, 78054.0, 78977.60511197522, 10618.575256989634, 11515.596954106566, 11560.710982225512, 11921.880781192078, 12185.878141218474, 39250.0, 39408.21183576255, 39755.801220994865, 39989.800305990866, 40174.59825401825, 66247.0, 68433.31566684469, 69844.0, 70186.0, 72903.63548182225, 16279.837201627832, 16871.91564042172, 16875.662486749952, 17034.403795867085, 17114.743278851078, 17525.824741752418, 17955.922644641363, 18374.63250734951, 18388.724169137742, 18455.72316415228, 16497.67004659876, 17258.395956141474, 17340.219690114227, 17360.39238626642, 17459.95240285587, 16259.837401626306, 16557.91721041387, 17225.22486488086, 17337.653246935242, 17644.647107058045, 16217.675646486769, 16363.263653135875, 16700.49898503047, 16817.159142043092, 16928.23822927996, 9471.10024547667, 9688.612455501701, 10217.591296348062, 11527.88472115268, 12206.877931220573, 12046.75906481883, 12077.456514456695, 12271.877281227431, 12594.87405125937, 12887.484500620245], "value": 8262.165152387548, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1404.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 110.0, 110.0, 110.0, 112.0, 110.0, 110.0, 109.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 111.0, 108.0, 149.0, 110.0, 107.0, 106.0, 114.0, 150.0, 88.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 181.0, 184.0, 183.0, 184.0, 186.0, 181.0, 186.0, 183.0, 181.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 56.0, 59.0, 91.0, 57.0, 60.0, 59.0, 56.0, 81.0, 58.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 84.0, 67.0, 68.0, 68.0, 89.0, 69.0, 68.0, 90.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 86.0, 86.0, 86.0, 86.0, 86.0, 85.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 92.0, 94.0, 93.0, 93.0, 92.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 85.0, 84.0, 86.0, 86.0, 83.0, 84.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 47.0, 47.0, 47.0, 50.0, 50.0, 49.0, 49.0], "value": 48.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 62.0, 66.0, 63.0, 60.0, 58.0, 57.0, 56.0, 57.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 85.0, 88.0, 82.0, 82.0, 82.0, 82.0, 82.0, 81.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 144.0, 132.0, 140.0, 150.0, 134.0, 136.0, 135.0, 172.0, 144.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 184.0, 184.0, 184.0, 182.0, 185.0, 182.0, 202.0, 184.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 72.0, 67.0, 67.0, 68.0, 66.0, 68.0, 70.0], "value": 68.1, "unit": "score"}]}]} 02:50:33 INFO - Return code: 0 02:50:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:50:33 INFO - # TBPL SUCCESS # 02:50:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:50:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:50:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:50:33 INFO - 2017-05-08 02:50:33.420 screenresolution[1665:12098] starting screenresolution argv=screenresolution get 02:50:33 INFO - 2017-05-08 02:50:33.423 screenresolution[1665:12098] Display 0: 1600x1200x32@60 02:50:33 INFO - 2017-05-08 02:50:33.429 screenresolution[1666:12101] starting screenresolution argv=screenresolution list 02:50:33 INFO - Available Modes on Display 0 02:50:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:50:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:50:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:50:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:50:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:50:33 INFO - Intel Iris: 02:50:33 INFO - Chipset Model: Intel Iris 02:50:33 INFO - Type: GPU 02:50:33 INFO - Bus: Built-In 02:50:33 INFO - VRAM (Dynamic, Max): 1536 MB 02:50:33 INFO - Vendor: Intel (0x8086) 02:50:33 INFO - Device ID: 0x0a2e 02:50:33 INFO - Revision ID: 0x0009 02:50:33 INFO - Displays: 02:50:33 INFO - Display: 02:50:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:50:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:50:33 INFO - Display Serial Number: 1600x1200 60 02:50:33 INFO - Main Display: Yes 02:50:33 INFO - Mirror: Off 02:50:33 INFO - Online: Yes 02:50:33 INFO - Rotation: Supported 02:50:33 INFO - Return code: 0 02:50:33 INFO - Running post-action listener: _resource_record_post_action 02:50:33 INFO - [mozharness: 2017-05-08 09:50:33.672975Z] Finished run-tests step (success) 02:50:33 INFO - Running post-run listener: _resource_record_post_run 02:50:33 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 34229760; Write bytes: 529440256; Read time: 313; Write time: 1697 02:50:33 INFO - TinderboxPrint: CPU usage
53.5% 02:50:33 INFO - TinderboxPrint: I/O read bytes / time
34,229,760 / 313 02:50:33 INFO - TinderboxPrint: I/O write bytes / time
529,440,256 / 1,697 02:50:33 INFO - TinderboxPrint: CPU idle
2,226.2 (46.5%) 02:50:33 INFO - TinderboxPrint: CPU system
958.5 (20.0%) 02:50:33 INFO - TinderboxPrint: CPU user
1,604.9 (33.5%) 02:50:33 INFO - TinderboxPrint: Swap in / out
238,428,160 / 0 02:50:33 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 197001728; Write bytes: 193912320; Read time: 15088; Write time: 432 02:50:33 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 33246720; Write bytes: 318681088; Read time: 297; Write time: 1214 02:50:34 INFO - Running post-run listener: _upload_blobber_files 02:50:34 INFO - Blob upload gear active. 02:50:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:50:34 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 02:50:34 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'] 02:50:34 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 02:50:34 INFO - (blobuploader) - INFO - Open directory for files ... 02:50:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:50:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:50:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:35 INFO - (blobuploader) - INFO - Done attempting. 02:50:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:50:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:50:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:36 INFO - (blobuploader) - INFO - Done attempting. 02:50:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:50:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:50:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:37 INFO - (blobuploader) - INFO - Done attempting. 02:50:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:50:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:50:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:39 INFO - (blobuploader) - INFO - Done attempting. 02:50:39 INFO - (blobuploader) - INFO - Iteration through files over. 02:50:39 INFO - Return code: 0 02:50:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:50:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:50:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/042fea19e954cb69a3cf37d8db2964cc441085b50f0747d8c74107bf33b473abefffe128a8032755aa6aa8bc5ddbde4f09c0c3b9250c7c3ded388bb6dbae133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a5d580258cf7d927b00e5a5d51ce86a9bd7aa5ed3fe5d89b54367b5d570d1d27d0b9fdaccffa91646fab073837c0235242c60773a74f865caf67eed0072523", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cb2e715897dc905274ba07e15a7cb6e66d6d8062f648a6d769fd86cc81a933cba1e59e1d410652890b2d5fcbb01fbb9867c914679b2b5ef80fe9c8921071540", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47d8e607f9730cc1b1b1b12a4a7c772a2f257512eb96ff0b3a15785ed79e93fa31b4acbce18a759d468fc7981150f12643d7da4753113071049bb28be6b82595"} 02:50:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:50:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:50:39 INFO - Contents: 02:50:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/042fea19e954cb69a3cf37d8db2964cc441085b50f0747d8c74107bf33b473abefffe128a8032755aa6aa8bc5ddbde4f09c0c3b9250c7c3ded388bb6dbae133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a5d580258cf7d927b00e5a5d51ce86a9bd7aa5ed3fe5d89b54367b5d570d1d27d0b9fdaccffa91646fab073837c0235242c60773a74f865caf67eed0072523", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cb2e715897dc905274ba07e15a7cb6e66d6d8062f648a6d769fd86cc81a933cba1e59e1d410652890b2d5fcbb01fbb9867c914679b2b5ef80fe9c8921071540", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47d8e607f9730cc1b1b1b12a4a7c772a2f257512eb96ff0b3a15785ed79e93fa31b4acbce18a759d468fc7981150f12643d7da4753113071049bb28be6b82595"} 02:50:39 INFO - Running post-run listener: copy_logs_to_upload_dir 02:50:39 INFO - Copying logs to upload dir... 02:50:39 INFO - mkdir: /builds/slave/test/build/upload/logs 02:50:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.648098 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-08 02:50:39.950081) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 02:50:39.953552) ========= 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.ECFHN87aOy/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.4EQvvhMeV1/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/042fea19e954cb69a3cf37d8db2964cc441085b50f0747d8c74107bf33b473abefffe128a8032755aa6aa8bc5ddbde4f09c0c3b9250c7c3ded388bb6dbae133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a5d580258cf7d927b00e5a5d51ce86a9bd7aa5ed3fe5d89b54367b5d570d1d27d0b9fdaccffa91646fab073837c0235242c60773a74f865caf67eed0072523", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cb2e715897dc905274ba07e15a7cb6e66d6d8062f648a6d769fd86cc81a933cba1e59e1d410652890b2d5fcbb01fbb9867c914679b2b5ef80fe9c8921071540", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47d8e607f9730cc1b1b1b12a4a7c772a2f257512eb96ff0b3a15785ed79e93fa31b4acbce18a759d468fc7981150f12643d7da4753113071049bb28be6b82595"} build_url:https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011152 build_url: 'https://queue.taskcluster.net/v1/task/bMIhXzSwQDiVtHnTgELn-w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/042fea19e954cb69a3cf37d8db2964cc441085b50f0747d8c74107bf33b473abefffe128a8032755aa6aa8bc5ddbde4f09c0c3b9250c7c3ded388bb6dbae133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a5d580258cf7d927b00e5a5d51ce86a9bd7aa5ed3fe5d89b54367b5d570d1d27d0b9fdaccffa91646fab073837c0235242c60773a74f865caf67eed0072523", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cb2e715897dc905274ba07e15a7cb6e66d6d8062f648a6d769fd86cc81a933cba1e59e1d410652890b2d5fcbb01fbb9867c914679b2b5ef80fe9c8921071540", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47d8e607f9730cc1b1b1b12a4a7c772a2f257512eb96ff0b3a15785ed79e93fa31b4acbce18a759d468fc7981150f12643d7da4753113071049bb28be6b82595"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 02:50:39.988806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:50:39.989194) ========= 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.ECFHN87aOy/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.4EQvvhMeV1/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005266 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:50:40.037799) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 02:50:40.038151) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 02:50:40.038484) ========= ========= Total master_lag: 0.18 =========