builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0381 starttime: 1490725865.83 results: success (0) buildid: 20170328095415 builduid: f53979a631b14ddd8716d40d7addd8f2 revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:05.832542) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:05.833116) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:05.833424) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:05.861358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:05.861773) ========= 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.07QEcarQbQ/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.vReoAfXBLW/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-28 11:31:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-03-28 11:31:05 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091175 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:06.076660) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:06.077022) ========= 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.07QEcarQbQ/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.vReoAfXBLW/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.027365 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:06.127291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-28 11:31:06.127731) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --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 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.07QEcarQbQ/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.vReoAfXBLW/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-28 11:31:06,235 truncating revision to first 12 chars 2017-03-28 11:31:06,235 Setting DEBUG logging. 2017-03-28 11:31:06,235 attempt 1/10 2017-03-28 11:31:06,235 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0e0eb96528a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 11:31:08,356 unpacking tar archive at: mozilla-central-0e0eb96528a1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.400924 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-28 11:31:08.554088) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.554429) ========= script_repo_revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.554846) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.555126) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.575876) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-28 11:31:08.576285) ========= /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.07QEcarQbQ/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.vReoAfXBLW/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:31:08 INFO - MultiFileLogger online at 20170328 11:31:08 in /builds/slave/test 11:31:08 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 11:31:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:31:08 INFO - {'append_to_log': False, 11:31:08 INFO - 'base_work_dir': '/builds/slave/test', 11:31:08 INFO - 'blob_upload_branch': 'Firefox', 11:31:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:31:08 INFO - 'branch': 'Firefox', 11:31:08 INFO - 'buildbot_json_path': 'buildprops.json', 11:31:08 INFO - 'config_files': ('talos/mac_config.py',), 11:31:08 INFO - 'default_actions': ('clobber', 11:31:08 INFO - 'read-buildbot-config', 11:31:08 INFO - 'download-and-extract', 11:31:08 INFO - 'populate-webroot', 11:31:08 INFO - 'create-virtualenv', 11:31:08 INFO - 'install', 11:31:08 INFO - 'run-tests'), 11:31:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:31:08 INFO - 'download_minidump_stackwalk': True, 11:31:08 INFO - 'download_symbols': 'ondemand', 11:31:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:31:08 INFO - 'tooltool.py': '/tools/tooltool.py', 11:31:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:31:08 INFO - '/tools/misc-python/virtualenv.py')}, 11:31:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:31:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:31:08 INFO - 'gecko_profile': False, 11:31:08 INFO - 'gecko_profile_interval': 0, 11:31:08 INFO - 'installer_path': 'installer.exe', 11:31:08 INFO - 'log_level': 'info', 11:31:08 INFO - 'log_name': 'talos', 11:31:08 INFO - 'log_to_console': True, 11:31:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:31:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:31:08 INFO - 'opt_config_files': (), 11:31:08 INFO - 'pip_index': False, 11:31:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:31:08 INFO - 'cmd': ('bash', 11:31:08 INFO - '-c', 11:31:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:31:08 INFO - 'enabled': True, 11:31:08 INFO - 'halt_on_failure': False, 11:31:08 INFO - 'name': 'check_screen_resolution'},), 11:31:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:31:08 INFO - 'cmd': ('bash', 11:31:08 INFO - '-c', 11:31:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:31:08 INFO - 'enabled': True, 11:31:08 INFO - 'halt_on_failure': False, 11:31:08 INFO - 'name': 'check_screen_resolution'},), 11:31:08 INFO - 'run_cmd_checks_enabled': True, 11:31:08 INFO - 'suite': 'dromaeojs', 11:31:08 INFO - 'system_bits': '32', 11:31:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:31:08 INFO - 'title': 't-yosemite-r7-0381', 11:31:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:31:08 INFO - 'use_talos_json': True, 11:31:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:31:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:31:08 INFO - 'work_dir': 'build'} 11:31:08 INFO - [mozharness: 2017-03-28 18:31:08.790769Z] Running clobber step. 11:31:08 INFO - Running pre-action listener: _resource_record_pre_action 11:31:08 INFO - Running main action method: clobber 11:31:08 INFO - rmtree: /builds/slave/test/build 11:31:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:31:11 INFO - Running post-action listener: _resource_record_post_action 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.172187Z] Finished clobber step (success) 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.172356Z] Running read-buildbot-config step. 11:31:11 INFO - Running pre-action listener: _resource_record_pre_action 11:31:11 INFO - Running main action method: read_buildbot_config 11:31:11 INFO - Using buildbot properties: 11:31:11 INFO - { 11:31:11 INFO - "project": "", 11:31:11 INFO - "product": "firefox", 11:31:11 INFO - "script_repo_revision": "production", 11:31:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:31:11 INFO - "repository": "", 11:31:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:31:11 INFO - "buildid": "20170328095415", 11:31:11 INFO - "stage_platform": "macosx64", 11:31:11 INFO - "basedir": "/builds/slave/test", 11:31:11 INFO - "nightly_build": "True", 11:31:11 INFO - "buildnumber": 39, 11:31:11 INFO - "slavename": "t-yosemite-r7-0381", 11:31:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:31:11 INFO - "platform": "yosemite_r7", 11:31:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:31:11 INFO - "branch": "mozilla-central", 11:31:11 INFO - "repo_path": "mozilla-central", 11:31:11 INFO - "revision": "0e0eb96528a1d032fe6ed54f67d32290d533fbfd", 11:31:11 INFO - "pgo_build": "False", 11:31:11 INFO - "builduid": "f53979a631b14ddd8716d40d7addd8f2", 11:31:11 INFO - "slavebuilddir": "test" 11:31:11 INFO - } 11:31:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 11:31:11 INFO - Running post-action listener: _resource_record_post_action 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.175964Z] Finished read-buildbot-config step (success) 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.176084Z] Running download-and-extract step. 11:31:11 INFO - Running pre-action listener: _resource_record_pre_action 11:31:11 INFO - Running main action method: download_and_extract 11:31:11 INFO - mkdir: /builds/slave/test/build/tests 11:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:11 INFO - trying https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:31:11 INFO - Downloading https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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 11:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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 11:31:12 INFO - Downloaded 1325 bytes. 11:31:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:31:12 INFO - Using the following test package requirements: 11:31:12 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:31:12 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:31:12 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:31:12 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:31:12 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'jsshell-mac.zip'], 11:31:12 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:31:12 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:31:12 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:31:12 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:31:12 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:31:12 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:31:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:31:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:31:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:31:12 INFO - Fetch https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:31:14 INFO - Expected file size: 52503867 11:31:14 INFO - Obtained file size: 52503867 11:31:30 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 11:31:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:31:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:31:30 INFO - Fetch https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:31:32 INFO - Expected file size: 52503867 11:31:32 INFO - Obtained file size: 52503867 11:31:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:31:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:31:47 INFO - Fetch https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:31:48 INFO - Expected file size: 14404171 11:31:48 INFO - Obtained file size: 14404171 11:31:49 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')]} 11:31:49 INFO - trying https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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 11:31:51 INFO - Downloaded 75645540 bytes. 11:31:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:31:51 INFO - mkdir: /builds/slave/test/properties 11:31:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:31:51 INFO - Writing to file /builds/slave/test/properties/build_url 11:31:51 INFO - Contents: 11:31:51 INFO - build_url:https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:31:51 INFO - Running post-action listener: _resource_record_post_action 11:31:51 INFO - Running post-action listener: set_extra_try_arguments 11:31:51 INFO - [mozharness: 2017-03-28 18:31:51.980258Z] Finished download-and-extract step (success) 11:31:51 INFO - [mozharness: 2017-03-28 18:31:51.980405Z] Running populate-webroot step. 11:31:51 INFO - Running pre-action listener: _resource_record_pre_action 11:31:51 INFO - Running main action method: populate_webroot 11:31:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:31:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:31:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:31:51 INFO - '--tppagecycles', 11:31:51 INFO - '7'], 11:31:51 INFO - 'tests': ['tsvgm']}}, 11:31:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:31:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:31:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:31:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:31:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:31:51 INFO - 'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:31:51 INFO - 'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['damp', 'tps']}, 11:31:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:31:51 INFO - 'tests': ['damp', 'tps']}, 11:31:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['dromaeo_dom']}, 11:31:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:31:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['basic_compositor_video']}, 11:31:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:31:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['a11yr', 11:31:51 INFO - 'ts_paint', 11:31:51 INFO - 'tpaint', 11:31:51 INFO - 'sessionrestore', 11:31:51 INFO - 'sessionrestore_no_auto_restore', 11:31:51 INFO - 'tabpaint']}, 11:31:51 INFO - 'other-e10s': {'tests': ['a11yr', 11:31:51 INFO - 'ts_paint', 11:31:51 INFO - 'tpaint', 11:31:51 INFO - 'sessionrestore', 11:31:51 INFO - 'sessionrestore_no_auto_restore', 11:31:51 INFO - 'tabpaint']}, 11:31:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:31:51 INFO - 'ts_paint', 11:31:51 INFO - 'tpaint', 11:31:51 INFO - 'sessionrestore', 11:31:51 INFO - 'sessionrestore_no_auto_restore', 11:31:51 INFO - 'tabpaint']}, 11:31:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:31:51 INFO - 'ts_paint', 11:31:51 INFO - 'tpaint', 11:31:51 INFO - 'sessionrestore', 11:31:51 INFO - 'sessionrestore_no_auto_restore', 11:31:51 INFO - 'tabpaint']}, 11:31:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['a11yr', 11:31:51 INFO - 'ts_paint', 11:31:51 INFO - 'tpaint', 11:31:51 INFO - 'sessionrestore', 11:31:51 INFO - 'sessionrestore_no_auto_restore', 11:31:51 INFO - 'tabpaint']}, 11:31:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['a11yr', 11:31:51 INFO - 'ts_paint', 11:31:51 INFO - 'tpaint', 11:31:51 INFO - 'sessionrestore', 11:31:51 INFO - 'sessionrestore_no_auto_restore', 11:31:51 INFO - 'tabpaint']}, 11:31:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['tsvgx', 11:31:51 INFO - 'tsvgr_opacity', 11:31:51 INFO - 'tart', 11:31:51 INFO - 'tscrollx', 11:31:51 INFO - 'cart', 11:31:51 INFO - 'tsvg_static']}, 11:31:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:31:51 INFO - 'tsvgr_opacity', 11:31:51 INFO - 'tart', 11:31:51 INFO - 'tscrollx', 11:31:51 INFO - 'cart', 11:31:51 INFO - 'tsvg_static']}, 11:31:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:31:51 INFO - 'talos_options': ['--disable-e10s'], 11:31:51 INFO - 'tests': ['tp5o']}, 11:31:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:31:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:31:51 INFO - 'talos_options': ['--disable-e10s', 11:31:51 INFO - '--xperf_path', 11:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:51 INFO - 'tests': ['tp5n']}, 11:31:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:31:51 INFO - 'talos_options': ['--xperf_path', 11:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:51 INFO - 'tests': ['tp5n']}}, 11:31:51 INFO - 'talos.zip': {'path': '', 11:31:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:31:51 INFO - Running post-action listener: _resource_record_post_action 11:31:51 INFO - [mozharness: 2017-03-28 18:31:51.998733Z] Finished populate-webroot step (success) 11:31:51 INFO - [mozharness: 2017-03-28 18:31:51.998871Z] Running create-virtualenv step. 11:31:51 INFO - Running pre-action listener: _resource_record_pre_action 11:31:51 INFO - Running main action method: create_virtualenv 11:31:51 INFO - Creating virtualenv /builds/slave/test/build/venv 11:31:51 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 11:31:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:31:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:31:52 INFO - Using real prefix '/tools/python27' 11:31:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:31:52 INFO - Installing distribute.............................................................................................................................................................................................done. 11:31:55 INFO - Installing pip.................done. 11:31:55 INFO - Return code: 0 11:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:31:55 INFO - Reading from file tmpfile_stdout 11:31:55 INFO - Output received: 11:31:55 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) 11:31:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31: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')]} 11:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:55 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 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:31:55 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 11:31:55 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 11:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:56 INFO - 'HOME': '/Users/cltbld', 11:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:56 INFO - 'LOGNAME': 'cltbld', 11:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:56 INFO - 'MOZ_NO_REMOTE': '1', 11:31:56 INFO - 'NO_EM_RESTART': '1', 11:31:56 INFO - 'PAGER': '/bin/cat', 11:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:56 INFO - 'PWD': '/builds/slave/test', 11:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:56 INFO - 'SHELL': '/bin/bash', 11:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:31:56 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:56 INFO - 'USER': 'cltbld', 11:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:56 INFO - 'XPC_FLAGS': '0x0', 11:31:56 INFO - 'XPC_SERVICE_NAME': '0', 11:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:31:56 INFO - Cleaning up... 11:31:56 INFO - Return code: 0 11:31:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:56 INFO - 'HOME': '/Users/cltbld', 11:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:56 INFO - 'LOGNAME': 'cltbld', 11:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:56 INFO - 'MOZ_NO_REMOTE': '1', 11:31:56 INFO - 'NO_EM_RESTART': '1', 11:31:56 INFO - 'PAGER': '/bin/cat', 11:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:56 INFO - 'PWD': '/builds/slave/test', 11:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:56 INFO - 'SHELL': '/bin/bash', 11:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:31:56 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:56 INFO - 'USER': 'cltbld', 11:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:56 INFO - 'XPC_FLAGS': '0x0', 11:31:56 INFO - 'XPC_SERVICE_NAME': '0', 11:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:56 INFO - Downloading/unpacking psutil>=3.1.1 11:31:56 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 11:31:56 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 11:31:56 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 11:31:56 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 11:31:56 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 11:31:56 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 11:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:31:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:31:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:31:59 INFO - Installing collected packages: psutil 11:31:59 INFO - Running setup.py install for psutil 11:32:00 INFO - building 'psutil._psutil_osx' extension 11:32:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:32:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:32:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:32:00 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 11:32:00 INFO - building 'psutil._psutil_posix' extension 11:32:00 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 11:32:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:32:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:32:00 INFO - ^ 11:32:00 INFO - 1 warning generated. 11:32:00 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 11:32:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:01 INFO - Successfully installed psutil 11:32:01 INFO - Cleaning up... 11:32:01 INFO - Return code: 0 11:32:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:01 INFO - 'HOME': '/Users/cltbld', 11:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:01 INFO - 'LOGNAME': 'cltbld', 11:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:01 INFO - 'MOZ_NO_REMOTE': '1', 11:32:01 INFO - 'NO_EM_RESTART': '1', 11:32:01 INFO - 'PAGER': '/bin/cat', 11:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:01 INFO - 'PWD': '/builds/slave/test', 11:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:01 INFO - 'SHELL': '/bin/bash', 11:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:01 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:01 INFO - 'USER': 'cltbld', 11:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:01 INFO - 'XPC_FLAGS': '0x0', 11:32:01 INFO - 'XPC_SERVICE_NAME': '0', 11:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:32:02 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 11:32:02 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 11:32:02 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 11:32:02 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 11:32:02 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 11:32:02 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 11:32:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:32:05 INFO - Installing collected packages: mozsystemmonitor 11:32:05 INFO - Running setup.py install for mozsystemmonitor 11:32:05 INFO - Successfully installed mozsystemmonitor 11:32:05 INFO - Cleaning up... 11:32:05 INFO - Return code: 0 11:32:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:05 INFO - 'HOME': '/Users/cltbld', 11:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:05 INFO - 'LOGNAME': 'cltbld', 11:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:05 INFO - 'MOZ_NO_REMOTE': '1', 11:32:05 INFO - 'NO_EM_RESTART': '1', 11:32:05 INFO - 'PAGER': '/bin/cat', 11:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:05 INFO - 'PWD': '/builds/slave/test', 11:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:05 INFO - 'SHELL': '/bin/bash', 11:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:05 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:05 INFO - 'USER': 'cltbld', 11:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:05 INFO - 'XPC_FLAGS': '0x0', 11:32:05 INFO - 'XPC_SERVICE_NAME': '0', 11:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:05 INFO - Downloading/unpacking jsonschema==2.5.1 11:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:32:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:09 INFO - Downloading functools32-3.2.3-2.tar.gz 11:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:32:09 INFO - warning: no files found matching '*.txt' 11:32:09 INFO - no previously-included directories found matching 'build' 11:32:09 INFO - no previously-included directories found matching 'dist' 11:32:09 INFO - no previously-included directories found matching '.git*' 11:32:09 INFO - Installing collected packages: jsonschema, functools32 11:32:09 INFO - Running setup.py install for functools32 11:32:09 INFO - warning: no files found matching '*.txt' 11:32:09 INFO - no previously-included directories found matching 'build' 11:32:09 INFO - no previously-included directories found matching 'dist' 11:32:09 INFO - no previously-included directories found matching '.git*' 11:32:09 INFO - Successfully installed jsonschema functools32 11:32:09 INFO - Cleaning up... 11:32:09 INFO - Return code: 0 11:32:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:09 INFO - 'HOME': '/Users/cltbld', 11:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:09 INFO - 'LOGNAME': 'cltbld', 11:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:09 INFO - 'MOZ_NO_REMOTE': '1', 11:32:09 INFO - 'NO_EM_RESTART': '1', 11:32:09 INFO - 'PAGER': '/bin/cat', 11:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:09 INFO - 'PWD': '/builds/slave/test', 11:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:09 INFO - 'SHELL': '/bin/bash', 11:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:09 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:09 INFO - 'USER': 'cltbld', 11:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:09 INFO - 'XPC_FLAGS': '0x0', 11:32:09 INFO - 'XPC_SERVICE_NAME': '0', 11:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:32:09 INFO - Cleaning up... 11:32:09 INFO - Return code: 0 11:32:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:09 INFO - 'HOME': '/Users/cltbld', 11:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:09 INFO - 'LOGNAME': 'cltbld', 11:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:09 INFO - 'MOZ_NO_REMOTE': '1', 11:32:09 INFO - 'NO_EM_RESTART': '1', 11:32:09 INFO - 'PAGER': '/bin/cat', 11:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:09 INFO - 'PWD': '/builds/slave/test', 11:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:09 INFO - 'SHELL': '/bin/bash', 11:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:09 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:09 INFO - 'USER': 'cltbld', 11:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:09 INFO - 'XPC_FLAGS': '0x0', 11:32:09 INFO - 'XPC_SERVICE_NAME': '0', 11:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:09 INFO - Downloading/unpacking blobuploader==1.2.4 11:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:12 INFO - Downloading blobuploader-1.2.4.tar.gz 11:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:32:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:32:12 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 11:32:12 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 11:32:12 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 11:32:12 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 11:32:12 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 11:32:12 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 11:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:32:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:13 INFO - Downloading docopt-0.6.1.tar.gz 11:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:32:13 INFO - Installing collected packages: blobuploader, requests, docopt 11:32:13 INFO - Running setup.py install for blobuploader 11:32:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:32:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:32:14 INFO - Running setup.py install for requests 11:32:14 INFO - Running setup.py install for docopt 11:32:14 INFO - Successfully installed blobuploader requests docopt 11:32:14 INFO - Cleaning up... 11:32:14 INFO - Return code: 0 11:32:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32: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')]} 11:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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')]} 11:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:14 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 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:14 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 11:32:14 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 11:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:14 INFO - 'HOME': '/Users/cltbld', 11:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:14 INFO - 'LOGNAME': 'cltbld', 11:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:14 INFO - 'MOZ_NO_REMOTE': '1', 11:32:14 INFO - 'NO_EM_RESTART': '1', 11:32:14 INFO - 'PAGER': '/bin/cat', 11:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:14 INFO - 'PWD': '/builds/slave/test', 11:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:14 INFO - 'SHELL': '/bin/bash', 11:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:14 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:14 INFO - 'USER': 'cltbld', 11:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:14 INFO - 'XPC_FLAGS': '0x0', 11:32:14 INFO - 'XPC_SERVICE_NAME': '0', 11:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-3nMffz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-MIdUPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-mXZFjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-JD0TAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-KpUsOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-tEjq69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-0tWDRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-eceTys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-7M9717-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:15 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-D7y0yW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:16 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-GOwn5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:16 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-eu6iHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:16 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-frLQmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:16 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-A5N9lI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:16 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-UjrzVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:16 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-R1ekk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:16 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-5OdpVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:32:16 INFO - Running setup.py install for manifestparser 11:32:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:32:16 INFO - Running setup.py install for mozcrash 11:32:17 INFO - Running setup.py install for mozdebug 11:32:17 INFO - Running setup.py install for mozdevice 11:32:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Running setup.py install for mozfile 11:32:17 INFO - Running setup.py install for mozhttpd 11:32:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Running setup.py install for mozinfo 11:32:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Running setup.py install for mozInstall 11:32:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Running setup.py install for mozleak 11:32:18 INFO - Running setup.py install for mozlog 11:32:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for moznetwork 11:32:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for mozprocess 11:32:18 INFO - Running setup.py install for mozprofile 11:32:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for mozrunner 11:32:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for mozscreenshot 11:32:18 INFO - Running setup.py install for moztest 11:32:19 INFO - Running setup.py install for mozversion 11:32:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:32:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:32:19 INFO - Cleaning up... 11:32:19 INFO - Return code: 0 11:32:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:19 INFO - 'HOME': '/Users/cltbld', 11:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:19 INFO - 'LOGNAME': 'cltbld', 11:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:19 INFO - 'MOZ_NO_REMOTE': '1', 11:32:19 INFO - 'NO_EM_RESTART': '1', 11:32:19 INFO - 'PAGER': '/bin/cat', 11:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:19 INFO - 'PWD': '/builds/slave/test', 11:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:19 INFO - 'SHELL': '/bin/bash', 11:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:19 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:19 INFO - 'USER': 'cltbld', 11:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:19 INFO - 'XPC_FLAGS': '0x0', 11:32:19 INFO - 'XPC_SERVICE_NAME': '0', 11:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:19 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-OdpnGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:19 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)) 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:19 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-hq8ljf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:19 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)) 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:19 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-0dQ74p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:19 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)) 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:19 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-x76yh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:19 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-l9kZFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-JD_Ess-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Wj4DVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-b_NWEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-rKxesV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-RNl2fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-UH55qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-PIUFdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-kkV37S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:20 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-oPloGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:21 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-QhLWCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:21 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-z4g3HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:21 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-THlmWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:32:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32:23 INFO - Downloading blessings-1.6.tar.gz 11:32:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:32:24 INFO - Installing collected packages: blessings 11:32:24 INFO - Running setup.py install for blessings 11:32:24 INFO - Successfully installed blessings 11:32:24 INFO - Cleaning up... 11:32:24 INFO - Return code: 0 11:32:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:24 INFO - Reading from file tmpfile_stdout 11:32:24 INFO - Current package versions: 11:32:24 INFO - blessings == 1.6 11:32:24 INFO - blobuploader == 1.2.4 11:32:24 INFO - docopt == 0.6.1 11:32:24 INFO - functools32 == 3.2.3-2 11:32:24 INFO - jsonschema == 2.5.1 11:32:24 INFO - manifestparser == 1.1 11:32:24 INFO - mozInstall == 1.12 11:32:24 INFO - mozcrash == 1.0 11:32:24 INFO - mozdebug == 0.1 11:32:24 INFO - mozdevice == 0.49 11:32:24 INFO - mozfile == 1.2 11:32:24 INFO - mozhttpd == 0.7 11:32:24 INFO - mozinfo == 0.9 11:32:24 INFO - mozleak == 0.1 11:32:24 INFO - mozlog == 3.4 11:32:24 INFO - moznetwork == 0.27 11:32:24 INFO - mozprocess == 0.25 11:32:24 INFO - mozprofile == 0.28 11:32:24 INFO - mozrunner == 6.13 11:32:24 INFO - mozscreenshot == 0.1 11:32:24 INFO - mozsystemmonitor == 0.3 11:32:24 INFO - moztest == 0.8 11:32:24 INFO - mozversion == 1.4 11:32:24 INFO - psutil == 3.1.1 11:32:24 INFO - requests == 1.2.3 11:32:24 INFO - wsgiref == 0.1.2 11:32:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:24 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')]} 11:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:24 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')]} 11:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:24 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 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:24 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 11:32:24 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 11:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:24 INFO - 'HOME': '/Users/cltbld', 11:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:24 INFO - 'LOGNAME': 'cltbld', 11:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:24 INFO - 'MOZ_NO_REMOTE': '1', 11:32:24 INFO - 'NO_EM_RESTART': '1', 11:32:24 INFO - 'PAGER': '/bin/cat', 11:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:24 INFO - 'PWD': '/builds/slave/test', 11:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:24 INFO - 'SHELL': '/bin/bash', 11:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:24 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:24 INFO - 'USER': 'cltbld', 11:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:24 INFO - 'XPC_FLAGS': '0x0', 11:32:24 INFO - 'XPC_SERVICE_NAME': '0', 11:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 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)) 11:32:24 INFO - Cleaning up... 11:32:24 INFO - Return code: 0 11:32:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:32:24 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')]} 11:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:24 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')]} 11:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:24 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 0x110540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadab59e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, '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.07QEcarQbQ/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.vReoAfXBLW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 11:32:24 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 11:32:24 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 11:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:24 INFO - 'HOME': '/Users/cltbld', 11:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:24 INFO - 'LOGNAME': 'cltbld', 11:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:24 INFO - 'MOZ_NO_REMOTE': '1', 11:32:24 INFO - 'NO_EM_RESTART': '1', 11:32:24 INFO - 'PAGER': '/bin/cat', 11:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:24 INFO - 'PWD': '/builds/slave/test', 11:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:24 INFO - 'SHELL': '/bin/bash', 11:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:24 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:24 INFO - 'USER': 'cltbld', 11:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:24 INFO - 'XPC_FLAGS': '0x0', 11:32:24 INFO - 'XPC_SERVICE_NAME': '0', 11:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:32:25 INFO - Cleaning up... 11:32:25 INFO - Return code: 0 11:32:25 INFO - Running post-action listener: _resource_record_post_action 11:32:25 INFO - Running post-action listener: _start_resource_monitoring 11:32:25 INFO - Starting resource monitoring. 11:32:25 INFO - [mozharness: 2017-03-28 18:32:25.153794Z] Finished create-virtualenv step (success) 11:32:25 INFO - [mozharness: 2017-03-28 18:32:25.154768Z] Running install step. 11:32:25 INFO - Running pre-action listener: _resource_record_pre_action 11:32:25 INFO - Running main action method: install 11:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:25 INFO - Reading from file tmpfile_stdout 11:32:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:32:25 INFO - Reading from file tmpfile_stdout 11:32:25 INFO - Output received: 11:32:25 INFO - Usage: mozinstall [options] installer 11:32:25 INFO - Options: 11:32:25 INFO - -h, --help show this help message and exit 11:32:25 INFO - -d DEST, --destination=DEST 11:32:25 INFO - Directory to install application into. [default: 11:32:25 INFO - "/builds/slave/test"] 11:32:25 INFO - --app=APP Application being installed. [default: firefox] 11:32:25 INFO - mkdir: /builds/slave/test/build/application 11:32:25 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'] 11:32:25 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 11:32:45 INFO - Reading from file tmpfile_stdout 11:32:45 INFO - Output received: 11:32:45 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 11:32:45 INFO - Running post-action listener: _resource_record_post_action 11:32:45 INFO - [mozharness: 2017-03-28 18:32:45.846730Z] Finished install step (success) 11:32:45 INFO - [mozharness: 2017-03-28 18:32:45.846863Z] Running run-tests step. 11:32:45 INFO - Running pre-action listener: _resource_record_pre_action 11:32:45 INFO - Running main action method: run_tests 11:32:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:32:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:32:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:32:45 INFO - 2017-03-28 11:32:45.859 screenresolution[1629:7397] starting screenresolution argv=screenresolution get 11:32:45 INFO - 2017-03-28 11:32:45.862 screenresolution[1629:7397] Display 0: 1600x1200x32@60 11:32:45 INFO - 2017-03-28 11:32:45.867 screenresolution[1630:7399] starting screenresolution argv=screenresolution list 11:32:45 INFO - Available Modes on Display 0 11:32:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:32:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:32:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:32:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:32:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:32:46 INFO - Intel Iris: 11:32:46 INFO - Chipset Model: Intel Iris 11:32:46 INFO - Type: GPU 11:32:46 INFO - Bus: Built-In 11:32:46 INFO - VRAM (Dynamic, Max): 1536 MB 11:32:46 INFO - Vendor: Intel (0x8086) 11:32:46 INFO - Device ID: 0x0a2e 11:32:46 INFO - Revision ID: 0x0009 11:32:46 INFO - Displays: 11:32:46 INFO - Display: 11:32:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:32:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:32:46 INFO - Display Serial Number: 1600x1200 60 11:32:46 INFO - Main Display: Yes 11:32:46 INFO - Mirror: Off 11:32:46 INFO - Online: Yes 11:32:46 INFO - Rotation: Supported 11:32:46 INFO - Return code: 0 11:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:32:46 INFO - Python 2.7.3 11:32:46 INFO - Return code: 0 11:32:46 INFO - grabbing minidump binary from tooltool 11:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:46 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 0x11031ed30>, '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 0x110245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110313be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:32:46 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 11:32:46 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 11:32:46 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 11:32:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:32:46 INFO - Return code: 0 11:32:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:32:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:32:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:32:46 INFO - ENV: RUST_BACKTRACE is now 1 11:32:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:32:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:32:46 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--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 11:32:46 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0381 --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 11:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07QEcarQbQ/Render', 11:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:46 INFO - 'HOME': '/Users/cltbld', 11:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:46 INFO - 'LOGNAME': 'cltbld', 11:32:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:32:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:46 INFO - 'MOZ_NO_REMOTE': '1', 11:32:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:32:46 INFO - 'NO_EM_RESTART': '1', 11:32:46 INFO - 'PAGER': '/bin/cat', 11:32:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:46 INFO - 'PWD': '/builds/slave/test', 11:32:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:46 INFO - 'RUST_BACKTRACE': '1', 11:32:46 INFO - 'SHELL': '/bin/bash', 11:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vReoAfXBLW/Listeners', 11:32:46 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 11:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:46 INFO - 'USER': 'cltbld', 11:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:46 INFO - 'XPC_FLAGS': '0x0', 11:32:46 INFO - 'XPC_SERVICE_NAME': '0', 11:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:46 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--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 11:32:46 INFO - mozversion application_buildid: 20170328095415 11:32:46 INFO - mozversion application_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 11:32:46 INFO - mozversion application_display_name: Nightly 11:32:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:32:46 INFO - mozversion application_name: Firefox 11:32:46 INFO - mozversion application_remotingname: firefox 11:32:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:32:46 INFO - mozversion application_vendor: Mozilla 11:32:46 INFO - mozversion application_version: 55.0a1 11:32:46 INFO - mozversion platform_buildid: 20170328095415 11:32:46 INFO - mozversion platform_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 11:32:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:32:46 INFO - mozversion platform_version: 55.0a1 11:32:46 INFO - using testdate: 1490725966 11:32:46 INFO - actual date: 1490725966 11:32:46 INFO - starting webserver on 'localhost:49237' 11:32:46 INFO - SUITE-START | Running 2 tests 11:32:46 INFO - TEST-START | dromaeo_css 11:32:46 INFO - operating with platform_type : mac_ 11:32:46 INFO - Initialising browser for dromaeo_css test... 11:32:46 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpGMHRbQ/profile http://localhost:49237/getInfo.html) 11:32:49 INFO - PID 1640 | __metrics Screen width/height:1600/1200 11:32:49 INFO - PID 1640 | colorDepth:24 11:32:49 INFO - PID 1640 | Browser inner width/height: 1024/689 11:32:49 INFO - PID 1640 | __metrics 11:32:49 ERROR - PID 1640 | 1490725969100 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 11:32:49 INFO - PID 1640 | 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" 11:32:49 INFO - PID 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:32:49 INFO - TEST-INFO | 1640: exit 0 11:32:49 INFO - Browser initialized. 11:32:49 INFO - Running cycle 1/1 for dromaeo_css test... 11:32:49 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpGMHRbQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:33:02 INFO - PID 1643 | RSS: Main: 233586688 11:33:02 INFO - PID 1643 | 11:36:07 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 11:36:09 INFO - PID 1643 | RSS: Main: 429142016 11:36:09 INFO - PID 1643 | 11:37:51 INFO - PID 1643 | ************************* 11:37:51 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1643 | 11:37:51 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1643 | Full stack: 11:37:51 INFO - PID 1643 | ************************* 11:37:51 INFO - PID 1643 | ************************* 11:37:51 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1643 | 11:37:51 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1643 | Full stack: 11:37:51 INFO - PID 1643 | ************************* 11:37:51 INFO - PID 1643 | ************************* 11:37:51 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1643 | 11:37:51 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1643 | Full stack: 11:37:51 INFO - PID 1643 | ************************* 11:37:51 INFO - PID 1643 | ************************* 11:37:51 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1643 | 11:37:51 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1643 | Full stack: 11:37:51 INFO - PID 1643 | ************************* 11:39:12 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 11:39:13 INFO - PID 1643 | RSS: Main: 364720128 11:39:13 INFO - PID 1643 | 11:42:15 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 11:42:16 INFO - PID 1643 | RSS: Main: 327790592 11:42:16 INFO - PID 1643 | 11:45:18 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 11:45:19 INFO - PID 1643 | RSS: Main: 331575296 11:45:19 INFO - PID 1643 | 11:48:23 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 11:48:23 INFO - PID 1643 | RSS: Main: 333996032 11:48:23 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 11:51:27 INFO - PID 1643 | RSS: Main: 421249024 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | __start_tp_report 11:51:27 INFO - PID 1643 | _x_x_mozilla_page_load 11:51:27 INFO - PID 1643 | _x_x_mozilla_page_load_details 11:51:27 INFO - PID 1643 | |i|pagename|runs| 11:51:27 INFO - PID 1643 | |0;dojo.html;2742.8765705543246;3502.597201321847;3516.0682419158943;3527.523784289121;3560.074380661027;5834.5040671542865;6045.818625441233;6109.266887973444;6130.570860039799;6150.354212807652;4999.3000979862845;6073.878522429549;6091.420869013632;6124.938750612492;6133.242854861887;5347.144456886899;5409.6213265071465;5416.864578385533;5450.890982180354;5479.315085614298;5523.972380138093;5551.500364967153;5554.166874968747;5566.165075238727;5615.438456154393;5336.4129945705945;5354.170103633943;5371.81198658049;5373.865653358658;5498.3127109111565;5385;5397.082495975655;5417.214503896913;5420.728963551846;5438.864028399282;5335;5382.273393091921;5385.461453854622;5399.325084364455;5402.108652072403;5972.641641501524;5992.610480318809;5993.550483713695;6033.155358249848;6102.481289090432;5322.627416080876;6352.237731472207;6352.46004089653;6362.745490180433;6421.743130274784;5125.362526849274;6522.902156467657;6527.281998980108;6549.148610680629;6581.605103693793;6282.089097080989;6283.717232724538;6288.151099601541;6294.653794041325;6295.244570651551;6435.7747478838955;6478.319776423502;6737.258901520829;6751.088603038527;6753.932460675429;6304.810855674337;6314.71583778731;6319.399657032666;6352.618842869442;6355.809325720235;5517.531009864127;6223.284322302993;6554.377334153248;6584.7366105356205;6596.175478065242;6445.16212892321;6461.644609546426;6468.741250350027;6476.319986401454;6490.902636460457;6276.780312689034;6312.0531920211415;6329.019002054689;6349.428551430394;6395.680215989182;4453.955460445419;5214.7914083437;5217.9478205217465;5227.555657769132;5246;4655.045715628288;4673.579377855941;4683.297505374221;4697.31888876111;4736.668433209711;6057.606255593372;6118.418750218722;6119.357467465941;6194.163787888668;6239.812805615838;6421.646809425526;6432.195975503062;6468.870622587522;6478.805635830932;6503.512236582275;6271.090691849657;6272.435480806749;6278.623282603058;6283.622982620966;6301.936980630135;5790.971045144747;5803.187553742395;5806.216160818235;5818.854528636818;5822.272215973004;6492.448141907991;6500.154979852589;6659.2008958925235;6698.430633396216;6705.22889867652;6295.622262664255;6298.401651843067;6329.208848893888;6476.838079048061;6528;5638.5489160867855;6680.699368528525;6684.866302674017;6692.631905245258;6749.223839258548;6428.228612566522;6428.935710643021;6444.677766111769;6452.870747619242;6457.4511166551365;5426.972865135649;6315.557910946373;6333.145025421693;6345.016522439029;6520.771772987922;5286.867828304323;6243.7814676486105;6295.464885484785;6317.4630156437215;6343.777967771106;6302.338254483213;6313.621182728867;6321.557490975588;6329.272133704683;6334.271558770801;6252.499800015897;6328.841778955379;6329.936700632935;6334.429901308904;6340.524460665376;6258.186435763321;6327.683615819283;6330.620162790247;6334.778282760081;6345.1116843641;6203.720832562455;6241.126242326169;6245.3754624539;6295.685215739286;6336.809895703136 11:51:27 INFO - PID 1643 | |1;ext.html;798.9161138080501;817.4768148385035;1133.0822034152338;1156.5547264303252;1156.710822294426;35333.76331828386;35891.74378896739;36053.63946360536;36064.45903311452;36155.9153225403;37520;37915.620843791556;37940.24119517608;37949.430758538576;37994.81002594983;6113.969430152843;6252.6561039142825;8499.915000850006;8614.267787238092;8620.353473489482;8398.664053437855;8597.742067737978;8605.31157507401;8609.741707748777;8614.396992210548;7320;8630.87053694192;8699.565021748918;8704.564771761417;8707.912920870773;8696.782580435476;8759.824803503894;8764.474131552128;8779.253763430115;8782.692605758768;8582.270507006911;8632.438891472035;8636.26591739703;8638.006629237656;8651.134886511361;37358.813205933795;37517.812410938044;37602.43596346057;37654.62345376566;37671.81164094162;3007.1129016940054;3013.5329024001317;3026.1678038539358;3030.5454181872674;3033.1507177990197;27102.728972710218;27120.32199195016;27220;27263.591046134323;27471.313217169532;2760.875760590778;2798.174538511144;2832.1685244213895;2862.8139170954028;2863.599096126544;306899;308276;309208;309889;309975.5036744484;40410;41057;41088.38367424491;48801.75599121982;49050.13298138264;48305.03389932252;49255.768605784426;49538.50461495339;49703.75148124309;49741.25388119181;12380.752384952431;14122.011459197964;14259;14263.714725705635;14342.641433964234;10054.044865737744;11104.722381940355;11258.324500529994;11260.493277802518;11266.887331126583;13064.804027939588;13070.346482675828;13140.671483212996;13211.537596184087;13227.867721322857;4980.306695723471;5776.3068431787615;5800.158976948319;5821.243238378984;5842.620229685142;944.4380593546829;954.9331546791797;973.3819024919227;993.0813997052697;1011.6054738651932;24015.292569698013;24024.062970759063;24069;24134.758652413602;24190.87904560466;1076.6500887211687;1077.6874706335232;1114.3759494683004;1115.676453828397;1128.1651577832345;10335.534900929477;10704.785904282027;12132.45403956824;12203.572874949334;12288.324142172087;347350;381135.98072202574;382415;394941;396401;53610.73194634002;53854.73072634612;54020.729896351055;54071.45928540743;54134.72932635312;1487.3084015932643;1535.8003459550187;1544.1352842408287;1548.8373720759384;1554.7434673279008;23789.405264868517;23857.880710596335;23863.403414914766;23894.880525597262;23985.400364991016;37970;39262.80368598139;49731.502684972686;49829.50170498249;49946.75133121701;305445.4727726347;467103;471479;473681;473682;28901.7109828899;29090.709092909645;30813.691863081083;32589.837050814593;32980;39897.2020559593;40026.39960400538;40259.99350016273;40292.59707402888;40412;28282.57576136401;28493.430131397672;29107.708922911348;29364;29366;27188.504632245418;28853.42293154167;28863.56704649474;29513.557296640138;29810.254743631584;34675.47986780251;35564.644353556134;35742.821285893406;35743;35863.28273434569;28376.29059273452;29047.56428653614;30129.849350753106;30406.087817365515;30460.695393046673;23297.76702232956;23635.763642363356;23653.290401287988;23768;23925.64111538363 11:51:27 INFO - PID 1643 | |2;jquery.html;420.2156792385125;426.3902619254465;430.8728924967135;433.28291677274103;447.8678789757021;19387.806121938775;20669.793302066973;21242.46893827655;21460.89269553654;21533;20537.691934620994;22377.216797412053;22655.886720566414;22870.085196592117;22941.311760647164;6082.513398928075;6280.152179455784;6308;6320.74717011319;6321.5890967087225;5976.133460648203;6151.323354431009;6202.9069563956355;6214.316425193225;6226.564140510166;5882.823515294526;6180.351063138351;6185.597936134137;6194.907076393858;6197.1324014637985;6057.909131363011;6148.292946311164;6188.133661287423;6196.318404975495;6198.876022479571;5291.867703307448;5436.238926550286;5501.559875209973;5514.724263786795;5517.200005999108;26324.736752632612;26532.86733566339;26811.463770724673;26909.32726681829;27404.588931166047;1815.1049076722607;1922.826945574891;1958.2950137950327;1962.2641509433963;1978.3174804692503;15955.840441595668;16213;16313.91843040789;17145.05702186379;17574.648507029917;1600.911949842758;1616.111138873615;1629.9266533005925;1639.5573195237218;1644.6792875389303;156133;164708.56711960345;164792;165608;167514;12097.57658481949;12143.696407589881;12188.695282617828;12282.938585307016;12433.191842530206;16361.263743131587;16402.343906243856;16402.589935251475;16415.753763693556;16434.917825410797;8509.65961361551;8851.46891186531;8865.113488651212;8981.326400519856;9003.639854405881;8344.874826877602;8362.749117526484;8386.832263354821;8537.957310213533;8553.572321383906;7586.848263034819;7700.383969282444;7755.30202281786;7758.456908016497;7767.805804854924;1843.6861738518073;2078.324544523036;2079.490524821398;2091.937241882746;2275.6586512023;894.3560636341823;896.6996056321075;912.6440688131634;932.2681694869494;943.6697155995347;19072;19153.904230478758;20200.494987625134;20264.696029559567;20361.846375046203;935.3078721745995;938.6057855700623;966.8259713251682;971.1647982734843;972.863799068131;10029.398236105857;10316;10474.004969527883;10491.16070714341;10492.21308401873;681391;696447;700360;708146;713944;100191;113372.43313783378;117382.41308793573;119601.40199299122;152702;1027.609508386823;1074.6507385099874;1093.8796732359433;1237.6534570320325;1241.7951038078797;17848.732269016236;20203.262396456703;20691;21064.841433720678;21275.04262308169;63351.683241583494;63624.363756363695;64053.03920441104;64714.02928955975;65158.348416517125;84170;86900;88344;89968.5501572488;92276;7122.60825654594;8452.870631781982;8754.387192896436;8793.736187914374;8958;13536.323183840965;13557.728845423233;13765.174089554659;14021.439142434188;14165.504207352693;9945.403275803475;11528.654140375802;11673.883261167279;11811.704707382385;11965.940170299093;10766.46167691628;10930.398828064534;11004.944975275072;11241.943790280997;11605.593804216813;10530.104941080093;11668.183227174017;12012.519499220283;12149.514019439124;12150.088743344108;8161.510309381456;8500.70247541333;8549.786255343666;8998.290324838259;9013.684521041732;11010.955208577196;11502.712432189262;11714;11867.287962721919;11878.643640690792 11:51:27 INFO - PID 1643 | |3;mootools.html;1336.8729970652796;1380.882624976877;1461;1480.422635172283;1480.541032279993;9458.574364153612;10601.20490963177;10606.893931060697;10607.78784424311;10617.522211500482;10341.689749307532;10372.792544149113;10620.362778233311;10634.415107169103;10649.84025239622;2340.6137987232128;2379.5478859016816;2387.379281386843;2394.1381102803066;2399.448126930807;2272.681824544565;2412.588635648148;2435.208557218902;2436.537057958982;2438.3782135555407;2023.767266764319;2100.0339843671927;2100.59038487495;2103.347962131744;2105.69467427223;2090.5296308330658;2118.724565806443;2352.8117750579913;2435.403326185096;2439.914602988887;2420.165043060141;2421.7336093029753;2423.1034517228563;2423.3820375804057;2424.527217192648;10588.841167382496;10632.30889992148;10653;10665.573377064908;10685.572577096907;4606.976965115153;5268.5521730652945;5433.157860531624;6016.217891674054;6104.175936248638;3659.9085022874374;3699.8335074921692;3756.1360886996144;3762.6613604775703;3856.3058649443087;980.4215512847454;1183.3255044624568;1184.2480025183984;1190.3453100794616;1224.1125184241444;12727;14645.707085858223;14869.330880110421;14891.180985045818;15026.323815428332;5106.821261255913;5248.816291429858;5300.655457395257;5300.92048619271;5343.973280133652;11264.493097810619;11273.098152147808;11274.943625281821;11275.66173014811;11284.322940623424;6226.315105338409;6237.064440333987;6243.531735119885;6252.374762523711;6263.6241825490615;5599.496045355938;5772.624779389326;5800.332961709458;5809.2738407699035;5847.795327163529;3615.837287322077;4016.979915100406;4166.8749937501925;4176.206520761046;4233.11104668017;1252.5929073051295;1253.3518423273367;1253.6176466177722;1269.4160686084504;1271.0594160321295;650.5381179362611;766.5630590563326;792.4888446951684;793.2067932067932;821.9013718353835;5689.715514224272;5796.855078623068;5925.170476133345;5980.700964951735;5991.670458124758;465.9138059459011;572.461885827321;588.3116753398534;595.9135925290808;596.6539407143846;3487.5640544931885;3569.464580312974;4036.858709945138;4090.9590904091174;4168.145530166369;13130.671733206746;13434.395452204673;13448.663783405493;13520.25638589887;13863.514776982756;13300.800487992494;13728.862711372958;13740.175589464465;13745.862541374458;13951.511697090553;9968.451735154636;9969.40183588987;10209.74475638115;10231.59073637061;10243.59025638981;6226.937730622636;6337.8732425352155;6379.649119298298;6398.168238129014;6417.454516366161;12134.939325303318;12266.141370104364;12373.75252494963;12381.380930953235;12539.122261441611;11974.760504790029;12313.50745970187;12378.319192444142;12461.75076498483;12623.87376126227;7168.569885806868;8864.246539044254;8964.506952117696;8970;8972.730818075468;9674.467904265333;9790;9794.461304628028;9888.406695598287;9928.305018648625;6707.966460167668;8322.750317490485;8472.44929079608;8534.658613655385;8619.741407757778;8182.877256841033;8318.209770071833;8367.58162091905;8404.033536143423;8429.283510901396;7588.8482230356185;7742.7677169685;7749.457537972513;7754.340881025176;7756.340711039399;5548.361938377138;5730.856728581819;5747.310322761128;5877.617954832922;5944.405559444021;5668.26312579362;5828;5932.417879634464;5996.88006239864;6013.579049466495 11:51:27 INFO - PID 1643 | |4;prototype.html;381.33837791431864;386.524574773029;388.2041814280724;395.06369903329113;396.8710169195011;4699.248120300753;4879.853234489901;5471.878264955684;5737.2828396450495;5768.221290125832;4913.656044076916;5538.169274608801;5634.774609015644;5800.506956908657;5818.5926985111055;4508.797104130322;4582.450105987286;4597.678162528624;4605.723656580616;4625.375574297461;3583.2654305867236;3750.587435382106;4137.855175068873;4376.848668958361;4526.026904215593;4494.97752511237;4503.504614492404;4509.571590698878;4512.210363186446;4512.30059340801;4517.864464066067;4522.299043648207;4524.276115821469;4527.637788976906;4536.931946020813;2990.5215165573622;3666.633336666312;3860.575336712959;3877.3408520551575;3934.704897132698;4991.500849915015;5710.628809127435;5720.942790572082;5746.569007324468;5792.623479473863;4292.9141417171795;5147.536721695028;5171.7672704728375;5171.818986335498;5174.508421699932;5029.497050294978;6193.074739084854;6283.937160628427;6303.3066362700065;6328.145700330442;4250.766207858566;4253.6392302272225;4261.143664983195;4270.743755374688;4282.5075116361395;5802.593818432753;6403.2956374799205;6614.2393624733295;6708.974615230823;6770.661466926683;3862.439946207784;4330.675199360061;4346.869593912188;4347.956460463577;4352.891177720519;4402.669799765031;4418.9337159942625;4425.778711064434;4434.512203657595;4442.53353397895;4548.840790572314;5166.793328266903;5180.637355385087;5215.269862219292;5231.71225582589;4972.403311602631;5886.852063847508;6129.632222066691;6401.519886008568;6414.679266036773;1238.2756087688622;1379.9448022079005;1388.8680575345327;1491.8582734640192;1504.428317239464;951.6399047416866;979.9412035277763;991.6033586565287;991.8016396720685;1003.7340104872214;5218.66078704883;5940.34656187819;5993.160957465958;5999.250093738283;6008.759649613967;6432.163818703632;6500.414962653384;6507.414332710079;6559.72085443334;6584.242812076577;5509.862253443615;5838.007538718428;5929.851753706192;6004.53813936284;6014.548908831767;2692.623032775413;3401.302732939791;3401.8809341672923;3411.8123503207066;3418.538497302882;5679.460451257124;6393.529488217736;6574.704138313788;6798;6800;5001.721806049798;6320.968395157995;6616.834579135464;6758.560693554903;6758.594484330857;5271.736413179402;6496.252930913006;6592.208934927743;6604.900926486107;6623.834404139935;5807.767689292381;6218.06728990655;6552.984287435455;6577.671116444063;6594.901076483952;6283.622982621057;6414.422701956846;6478.384553467413;6503;6507.837304067436;6042.667653279112;6217.533684973555;6832.487563432663;6852.663730572491;6870.27862074495;4358.629516491007;4499.752513611652;4696.8590942271785;4704.341392205026;4733.798783565659;6470.708818103102;6551.901721474277;6568.704408301543;6571.441427478718;6590;5527.778888844401;6096.29892562361;6363.7772677956045;6368.490520758421;6402.103705481143;6298.519847835846;6340.556161068681;6375.776847810304;6388.840278993062;6417.97312430009;6197.504199664107;6225.190725205694;6233.812985610439;6238.719257633508;6271.96864015677;5322.973385133049;6018.909716354171;6272.5922815016875;6360.300366959538;6381.298057213796;4963.801447942042;6198.318184999556;6214.358897468648;6222.253329600477;6245.375462453719 11:51:27 INFO - PID 1643 | |5;yui.html;719.6545658084123;726.4515290955328;741.6699568691931;742.1835980421536;771.3520642660164;15407.845921540782;16214.918925405385;16313.347466101372;16359.918200408996;16369.42707005255;14222.786658200135;15305.92347038266;15312.617184570392;15360.231988400592;15387.153706546163;927.1701826864969;956.6077908057698;961.5336561767538;963.7253382785907;963.7831487915233;927.6845872403381;957.0907637744136;957.8467445208768;959.0984474593894;959.1559427703611;1000.6447711062541;1025.523131743739;1029.1869423155697;1029.2332212501697;1029.5058371981415;1009.6415772400766;1020.9744756381076;1022.049493970607;1022.8107800056939;1023.5956797065155;749.2769477454228;756.6935391166586;756.7880993321878;761.2159475739996;761.2996043639864;16516.587085322964;16956.915215423967;17048.2328295227;17162.22769975354;17237.138143092794;14586;14695.70608587833;14837;14883.70232595342;15656.373745050185;11264.267822591506;11669.533218671242;11879.465424055938;12016.63950081499;12060.879391206063;3022.184010317202;3025.9243518911985;3049.634043914722;3050.4966680497914;3141.6701246369257;112134;112357;115023;115686.84313156929;117645.82354176434;11225.719357015976;11418.543258269576;11462.312261264351;11633.47649355764;11828.940855295668;25316.493670126863;25552.616710749353;25582.488350233263;25583.87208063985;25585.23244302674;7509.146524320723;7700.614969251627;7723.304902558797;7771.339436147878;7787.844243115106;3992.5608183099835;4111;4115.609017143366;4118.478126161854;4174.666026717855;4126.360414135814;4183.6653067754505;4224.936625950613;4230.936535951963;4378.036831896978;864.6930339729305;866.5017614871474;867.8280883371289;876.3383645347722;879.4811061473761;556.5630979681011;566.3204155013917;576.7145263094711;578.7684926029538;584.9837642217998;8608.311335093178;9390.90609093914;10082.941291164469;10271.7432064199;10455.529501172465;490.33314692018826;575.9078547432391;581.2327727399876;582.1907548507578;595.1459655394523;4488.618467430239;4520.321951707205;4602.010567727954;4788.162071637449;4793.832215872428;72964.63517682484;76557.6172119147;86939;93263;93293;78446.82329765058;79560.80658790123;81394;82627.17372826194;85732.57133714417;74809.25190748132;75760;75849;76775.23224767792;78371.21628783754;10552.366857988545;10707.518161682587;10847.45762711877;10921.453927303444;10938.835917461085;33753.493697594065;33791.98624041283;34229.65770342364;34421.483677744356;35003.47494787631;43565.56434435615;43968.90077748082;44080.11839763251;44394.334084988106;44647.55352446564;13065.678114289605;13161.276129812952;13372.93313533426;13666.043376963516;14091.956666283126;14082.78875816843;14116.082454640415;14374.928125359726;14632.341544630304;14928.776068358766;13063;13530.93234533821;13555.525556605471;13696.31518424055;13806.723865522834;12233.449494772934;12736.426860791473;13256.668583285495;13427.060105792501;13438.798418023542;13164.47342106305;13193.60419187426;13328.533501327795;13413.530526431528;13728.45086196541;9375.718728438158;9566.378185417925;9675.322727409013;9738.659146929824;10036.749081272734;11013.339199648046;11161.776764464828;11188.888111118784;11189;11245.887541124484 11:51:27 INFO - PID 1643 | __end_tp_report 11:51:27 INFO - PID 1643 | __start_cc_report 11:51:27 INFO - PID 1643 | _x_x_mozilla_cycle_collect,2147 11:51:27 INFO - PID 1643 | __end_cc_report 11:51:27 INFO - PID 1643 | __startTimestamp1490727087480__endTimestamp 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | ------- Summary: start ------- 11:51:27 INFO - PID 1643 | Number of tests: 6 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:5974.33 Median:6282.09 stddev:689.34 (11.0%) stddev-sans-first:643.16 11:51:27 INFO - PID 1643 | Values: 2742.9 3502.6 3516.1 3527.5 3560.1 5834.5 6045.8 6109.3 6130.6 6150.4 4999.3 6073.9 6091.4 6124.9 6133.2 5347.1 5409.6 5416.9 5450.9 5479.3 5524.0 5551.5 5554.2 5566.2 5615.4 5336.4 5354.2 5371.8 5373.9 5498.3 5385.0 5397.1 5417.2 5420.7 5438.9 5335.0 5382.3 5385.5 5399.3 5402.1 5972.6 5992.6 5993.6 6033.2 6102.5 5322.6 6352.2 6352.5 6362.7 6421.7 5125.4 6522.9 6527.3 6549.1 6581.6 6282.1 6283.7 6288.2 6294.7 6295.2 6435.8 6478.3 6737.3 6751.1 6753.9 6304.8 6314.7 6319.4 6352.6 6355.8 5517.5 6223.3 6554.4 6584.7 6596.2 6445.2 6461.6 6468.7 6476.3 6490.9 6276.8 6312.1 6329.0 6349.4 6395.7 4454.0 5214.8 5217.9 5227.6 5246.0 4655.0 4673.6 4683.3 4697.3 4736.7 6057.6 6118.4 6119.4 6194.2 6239.8 6421.6 6432.2 6468.9 6478.8 6503.5 6271.1 6272.4 6278.6 6283.6 6301.9 5791.0 5803.2 5806.2 5818.9 5822.3 6492.4 6500.2 6659.2 6698.4 6705.2 6295.6 6298.4 6329.2 6476.8 6528.0 5638.5 6680.7 6684.9 6692.6 6749.2 6428.2 6428.9 6444.7 6452.9 6457.5 5427.0 6315.6 6333.1 6345.0 6520.8 5286.9 6243.8 6295.5 6317.5 6343.8 6302.3 6313.6 6321.6 6329.3 6334.3 6252.5 6328.8 6329.9 6334.4 6340.5 6258.2 6327.7 6330.6 6334.8 6345.1 6203.7 6241.1 6245.4 6295.7 6336.8 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:50891.49 Median:191012.74 stddev:101262.38 (53.0%) stddev-sans-first:101476.60 11:51:27 INFO - PID 1643 | Values: 798.9 817.5 1133.1 1156.6 1156.7 35333.8 35891.7 36053.6 36064.5 36155.9 37520.0 37915.6 37940.2 37949.4 37994.8 6114.0 6252.7 8499.9 8614.3 8620.4 8398.7 8597.7 8605.3 8609.7 8614.4 7320.0 8630.9 8699.6 8704.6 8707.9 8696.8 8759.8 8764.5 8779.3 8782.7 8582.3 8632.4 8636.3 8638.0 8651.1 37358.8 37517.8 37602.4 37654.6 37671.8 3007.1 3013.5 3026.2 3030.5 3033.2 27102.7 27120.3 27220.0 27263.6 27471.3 2760.9 2798.2 2832.2 2862.8 2863.6 306899.0 308276.0 309208.0 309889.0 309975.5 40410.0 41057.0 41088.4 48801.8 49050.1 48305.0 49255.8 49538.5 49703.8 49741.3 12380.8 14122.0 14259.0 14263.7 14342.6 10054.0 11104.7 11258.3 11260.5 11266.9 13064.8 13070.3 13140.7 13211.5 13227.9 4980.3 5776.3 5800.2 5821.2 5842.6 944.4 954.9 973.4 993.1 1011.6 24015.3 24024.1 24069.0 24134.8 24190.9 1076.7 1077.7 1114.4 1115.7 1128.2 10335.5 10704.8 12132.5 12203.6 12288.3 347350.0 381136.0 382415.0 394941.0 396401.0 53610.7 53854.7 54020.7 54071.5 54134.7 1487.3 1535.8 1544.1 1548.8 1554.7 23789.4 23857.9 23863.4 23894.9 23985.4 37970.0 39262.8 49731.5 49829.5 49946.8 305445.5 467103.0 471479.0 473681.0 473682.0 28901.7 29090.7 30813.7 32589.8 32980.0 39897.2 40026.4 40260.0 40292.6 40412.0 28282.6 28493.4 29107.7 29364.0 29366.0 27188.5 28853.4 28863.6 29513.6 29810.3 34675.5 35564.6 35742.8 35743.0 35863.3 28376.3 29047.6 30129.8 30406.1 30460.7 23297.8 23635.8 23653.3 23768.0 23925.6 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:40095.58 Median:12465.77 stddev:117048.42 (939.0%) stddev-sans-first:117338.86 11:51:27 INFO - PID 1643 | Values: 420.2 426.4 430.9 433.3 447.9 19387.8 20669.8 21242.5 21460.9 21533.0 20537.7 22377.2 22655.9 22870.1 22941.3 6082.5 6280.2 6308.0 6320.7 6321.6 5976.1 6151.3 6202.9 6214.3 6226.6 5882.8 6180.4 6185.6 6194.9 6197.1 6057.9 6148.3 6188.1 6196.3 6198.9 5291.9 5436.2 5501.6 5514.7 5517.2 26324.7 26532.9 26811.5 26909.3 27404.6 1815.1 1922.8 1958.3 1962.3 1978.3 15955.8 16213.0 16313.9 17145.1 17574.6 1600.9 1616.1 1629.9 1639.6 1644.7 156133.0 164708.6 164792.0 165608.0 167514.0 12097.6 12143.7 12188.7 12282.9 12433.2 16361.3 16402.3 16402.6 16415.8 16434.9 8509.7 8851.5 8865.1 8981.3 9003.6 8344.9 8362.7 8386.8 8538.0 8553.6 7586.8 7700.4 7755.3 7758.5 7767.8 1843.7 2078.3 2079.5 2091.9 2275.7 894.4 896.7 912.6 932.3 943.7 19072.0 19153.9 20200.5 20264.7 20361.8 935.3 938.6 966.8 971.2 972.9 10029.4 10316.0 10474.0 10491.2 10492.2 681391.0 696447.0 700360.0 708146.0 713944.0 100191.0 113372.4 117382.4 119601.4 152702.0 1027.6 1074.7 1093.9 1237.7 1241.8 17848.7 20203.3 20691.0 21064.8 21275.0 63351.7 63624.4 64053.0 64714.0 65158.3 84170.0 86900.0 88344.0 89968.6 92276.0 7122.6 8452.9 8754.4 8793.7 8958.0 13536.3 13557.7 13765.2 14021.4 14165.5 9945.4 11528.7 11673.9 11811.7 11965.9 10766.5 10930.4 11004.9 11241.9 11605.6 10530.1 11668.2 12012.5 12149.5 12150.1 8161.5 8500.7 8549.8 8998.3 9013.7 11011.0 11502.7 11714.0 11867.3 11878.6 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:6580.05 Median:3727.98 stddev:4107.52 (110.2%) stddev-sans-first:4100.15 11:51:27 INFO - PID 1643 | Values: 1336.9 1380.9 1461.0 1480.4 1480.5 9458.6 10601.2 10606.9 10607.8 10617.5 10341.7 10372.8 10620.4 10634.4 10649.8 2340.6 2379.5 2387.4 2394.1 2399.4 2272.7 2412.6 2435.2 2436.5 2438.4 2023.8 2100.0 2100.6 2103.3 2105.7 2090.5 2118.7 2352.8 2435.4 2439.9 2420.2 2421.7 2423.1 2423.4 2424.5 10588.8 10632.3 10653.0 10665.6 10685.6 4607.0 5268.6 5433.2 6016.2 6104.2 3659.9 3699.8 3756.1 3762.7 3856.3 980.4 1183.3 1184.2 1190.3 1224.1 12727.0 14645.7 14869.3 14891.2 15026.3 5106.8 5248.8 5300.7 5300.9 5344.0 11264.5 11273.1 11274.9 11275.7 11284.3 6226.3 6237.1 6243.5 6252.4 6263.6 5599.5 5772.6 5800.3 5809.3 5847.8 3615.8 4017.0 4166.9 4176.2 4233.1 1252.6 1253.4 1253.6 1269.4 1271.1 650.5 766.6 792.5 793.2 821.9 5689.7 5796.9 5925.2 5980.7 5991.7 465.9 572.5 588.3 595.9 596.7 3487.6 3569.5 4036.9 4091.0 4168.1 13130.7 13434.4 13448.7 13520.3 13863.5 13300.8 13728.9 13740.2 13745.9 13951.5 9968.5 9969.4 10209.7 10231.6 10243.6 6226.9 6337.9 6379.6 6398.2 6417.5 12134.9 12266.1 12373.8 12381.4 12539.1 11974.8 12313.5 12378.3 12461.8 12623.9 7168.6 8864.2 8964.5 8970.0 8972.7 9674.5 9790.0 9794.5 9888.4 9928.3 6708.0 8322.8 8472.4 8534.7 8619.7 8182.9 8318.2 8367.6 8404.0 8429.3 7588.8 7742.8 7749.5 7754.3 7756.3 5548.4 5730.9 5747.3 5877.6 5944.4 5668.3 5828.0 5932.4 5996.9 6013.6 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5106.75 Median:5741.93 stddev:1593.09 (27.7%) stddev-sans-first:1557.58 11:51:27 INFO - PID 1643 | Values: 381.3 386.5 388.2 395.1 396.9 4699.2 4879.9 5471.9 5737.3 5768.2 4913.7 5538.2 5634.8 5800.5 5818.6 4508.8 4582.5 4597.7 4605.7 4625.4 3583.3 3750.6 4137.9 4376.8 4526.0 4495.0 4503.5 4509.6 4512.2 4512.3 4517.9 4522.3 4524.3 4527.6 4536.9 2990.5 3666.6 3860.6 3877.3 3934.7 4991.5 5710.6 5720.9 5746.6 5792.6 4292.9 5147.5 5171.8 5171.8 5174.5 5029.5 6193.1 6283.9 6303.3 6328.1 4250.8 4253.6 4261.1 4270.7 4282.5 5802.6 6403.3 6614.2 6709.0 6770.7 3862.4 4330.7 4346.9 4348.0 4352.9 4402.7 4418.9 4425.8 4434.5 4442.5 4548.8 5166.8 5180.6 5215.3 5231.7 4972.4 5886.9 6129.6 6401.5 6414.7 1238.3 1379.9 1388.9 1491.9 1504.4 951.6 979.9 991.6 991.8 1003.7 5218.7 5940.3 5993.2 5999.3 6008.8 6432.2 6500.4 6507.4 6559.7 6584.2 5509.9 5838.0 5929.9 6004.5 6014.5 2692.6 3401.3 3401.9 3411.8 3418.5 5679.5 6393.5 6574.7 6798.0 6800.0 5001.7 6321.0 6616.8 6758.6 6758.6 5271.7 6496.3 6592.2 6604.9 6623.8 5807.8 6218.1 6553.0 6577.7 6594.9 6283.6 6414.4 6478.4 6503.0 6507.8 6042.7 6217.5 6832.5 6852.7 6870.3 4358.6 4499.8 4696.9 4704.3 4733.8 6470.7 6551.9 6568.7 6571.4 6590.0 5527.8 6096.3 6363.8 6368.5 6402.1 6298.5 6340.6 6375.8 6388.8 6418.0 6197.5 6225.2 6233.8 6238.7 6272.0 5323.0 6018.9 6272.6 6360.3 6381.3 4963.8 6198.3 6214.4 6222.3 6245.4 11:51:27 INFO - PID 1643 | 11:51:27 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:19105.02 Median:3037.78 stddev:27067.56 (891.0%) stddev-sans-first:27108.29 11:51:27 INFO - PID 1643 | Values: 719.7 726.5 741.7 742.2 771.4 15407.8 16214.9 16313.3 16359.9 16369.4 14222.8 15305.9 15312.6 15360.2 15387.2 927.2 956.6 961.5 963.7 963.8 927.7 957.1 957.8 959.1 959.2 1000.6 1025.5 1029.2 1029.2 1029.5 1009.6 1021.0 1022.0 1022.8 1023.6 749.3 756.7 756.8 761.2 761.3 16516.6 16956.9 17048.2 17162.2 17237.1 14586.0 14695.7 14837.0 14883.7 15656.4 11264.3 11669.5 11879.5 12016.6 12060.9 3022.2 3025.9 3049.6 3050.5 3141.7 112134.0 112357.0 115023.0 115686.8 117645.8 11225.7 11418.5 11462.3 11633.5 11828.9 25316.5 25552.6 25582.5 25583.9 25585.2 7509.1 7700.6 7723.3 7771.3 7787.8 3992.6 4111.0 4115.6 4118.5 4174.7 4126.4 4183.7 4224.9 4230.9 4378.0 864.7 866.5 867.8 876.3 879.5 556.6 566.3 576.7 578.8 585.0 8608.3 9390.9 10082.9 10271.7 10455.5 490.3 575.9 581.2 582.2 595.1 4488.6 4520.3 4602.0 4788.2 4793.8 72964.6 76557.6 86939.0 93263.0 93293.0 78446.8 79560.8 81394.0 82627.2 85732.6 74809.3 75760.0 75849.0 76775.2 78371.2 10552.4 10707.5 10847.5 10921.5 10938.8 33753.5 33792.0 34229.7 34421.5 35003.5 43565.6 43968.9 44080.1 44394.3 44647.6 13065.7 13161.3 13372.9 13666.0 14092.0 14082.8 14116.1 14374.9 14632.3 14928.8 13063.0 13530.9 13555.5 13696.3 13806.7 12233.4 12736.4 13256.7 13427.1 13438.8 13164.5 13193.6 13328.5 13413.5 13728.5 9375.7 9566.4 9675.3 9738.7 10036.7 11013.3 11161.8 11188.9 11189.0 11245.9 11:51:27 INFO - PID 1643 | -------- Summary: end -------- 11:51:27 INFO - PID 1643 | 11:51:28 INFO - TEST-INFO | 1643: exit 0 11:51:28 INFO - TEST-OK | dromaeo_css | took 1121925ms 11:51:28 INFO - TEST-START | kraken 11:51:28 INFO - operating with platform_type : mac_ 11:51:28 INFO - Initialising browser for kraken test... 11:51:28 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpj550c5/profile http://localhost:49237/getInfo.html) 11:51:30 INFO - PID 1652 | __metrics Screen width/height:1600/1200 11:51:30 INFO - PID 1652 | colorDepth:24 11:51:30 INFO - PID 1652 | Browser inner width/height: 1024/689 11:51:30 INFO - PID 1652 | __metrics 11:51:30 ERROR - PID 1652 | 1490727090941 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 11:51:30 INFO - PID 1652 | 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" 11:51:30 INFO - PID 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:51:31 INFO - TEST-INFO | 1652: exit 0 11:51:31 INFO - Browser initialized. 11:51:31 INFO - Running cycle 1/1 for kraken test... 11:51:31 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpj550c5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:51:43 INFO - PID 1654 | RSS: Main: 234242048 11:51:43 INFO - PID 1654 | 11:51:46 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 11:51:47 INFO - PID 1654 | RSS: Main: 286732288 11:51:47 INFO - PID 1654 | 11:51:50 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 11:51:50 INFO - PID 1654 | RSS: Main: 290394112 11:51:50 INFO - PID 1654 | 11:51:54 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 11:51:55 INFO - PID 1654 | RSS: Main: 381726720 11:51:55 INFO - PID 1654 | 11:51:57 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 11:51:57 INFO - PID 1654 | RSS: Main: 284483584 11:51:57 INFO - PID 1654 | 11:52:00 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:52:00 INFO - PID 1654 | RSS: Main: 287625216 11:52:00 INFO - PID 1654 | 11:52:03 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 11:52:04 INFO - PID 1654 | RSS: Main: 328458240 11:52:04 INFO - PID 1654 | 11:52:07 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 11:52:08 INFO - PID 1654 | RSS: Main: 343937024 11:52:08 INFO - PID 1654 | 11:52:11 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 11:52:12 INFO - PID 1654 | RSS: Main: 350478336 11:52:12 INFO - PID 1654 | 11:52:13 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:52:14 INFO - PID 1654 | RSS: Main: 303509504 11:52:14 INFO - PID 1654 | 11:52:16 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:52:17 INFO - PID 1654 | RSS: Main: 308383744 11:52:17 INFO - PID 1654 | 11:52:19 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:52:19 INFO - PID 1654 | RSS: Main: 310792192 11:52:19 INFO - PID 1654 | 11:52:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:52:23 INFO - PID 1654 | RSS: Main: 327512064 11:52:23 INFO - PID 1654 | 11:52:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:52:27 INFO - PID 1654 | RSS: Main: 321839104 11:52:27 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 11:52:29 INFO - PID 1654 | RSS: Main: 383909888 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | __start_tp_report 11:52:29 INFO - PID 1654 | _x_x_mozilla_page_load 11:52:29 INFO - PID 1654 | _x_x_mozilla_page_load_details 11:52:29 INFO - PID 1654 | |i|pagename|runs| 11:52:29 INFO - PID 1654 | |0;ai-astar;112;121;124;120;122;121;122;121;121;121 11:52:29 INFO - PID 1654 | |1;audio-beat-detection;161;117;119;161;120;121;129;118;144;119 11:52:29 INFO - PID 1654 | |2;audio-dft;216;210;216;209;215;208;211;210;216;211 11:52:29 INFO - PID 1654 | |3;audio-fft;86;62;65;101;68;64;58;60;80;63 11:52:29 INFO - PID 1654 | |4;audio-oscillator;97;77;95;75;77;75;92;74;75;93 11:52:29 INFO - PID 1654 | |5;imaging-gaussian-blur;91;91;92;90;92;92;91;98;92;91 11:52:29 INFO - PID 1654 | |6;imaging-darkroom;99;100;101;101;100;98;100;124;160;101 11:52:29 INFO - PID 1654 | |7;imaging-desaturate;102;100;101;136;100;159;152;100;100;102 11:52:29 INFO - PID 1654 | |8;json-parse-financial;63;63;65;64;65;64;64;65;64;63 11:52:29 INFO - PID 1654 | |9;json-stringify-tinderbox;58;58;68;60;68;71;66;63;65;64 11:52:29 INFO - PID 1654 | |10;stanford-crypto-aes;89;93;89;89;88;90;92;90;88;90 11:52:29 INFO - PID 1654 | |11;stanford-crypto-ccm;151;150;151;150;154;164;150;150;192;180 11:52:29 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;200;200;204;203;207;200;200;203;196;200 11:52:29 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;71;75;74;72;73;71;74;72;75;76 11:52:29 INFO - PID 1654 | __end_tp_report 11:52:29 INFO - PID 1654 | __start_cc_report 11:52:29 INFO - PID 1654 | _x_x_mozilla_cycle_collect,2162 11:52:29 INFO - PID 1654 | __end_cc_report 11:52:29 INFO - PID 1654 | __startTimestamp1490727149848__endTimestamp 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | ------- Summary: start ------- 11:52:29 INFO - PID 1654 | Number of tests: 14 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:120.50 Median:121.00 stddev:3.17 (2.6%) stddev-sans-first:1.13 11:52:29 INFO - PID 1654 | Values: 112.0 121.0 124.0 120.0 122.0 121.0 122.0 121.0 121.0 121.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:125.00 stddev:17.79 (14.2%) stddev-sans-first:15.17 11:52:29 INFO - PID 1654 | Values: 161.0 117.0 119.0 161.0 120.0 121.0 129.0 118.0 144.0 119.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:212.20 Median:213.00 stddev:3.19 (1.5%) stddev-sans-first:3.07 11:52:29 INFO - PID 1654 | Values: 216.0 210.0 216.0 209.0 215.0 208.0 211.0 210.0 216.0 211.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:70.70 Median:64.50 stddev:13.88 (21.5%) stddev-sans-first:13.57 11:52:29 INFO - PID 1654 | Values: 86.0 62.0 65.0 101.0 68.0 64.0 58.0 60.0 80.0 63.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:83.00 Median:84.50 stddev:9.81 (11.6%) stddev-sans-first:9.00 11:52:29 INFO - PID 1654 | Values: 97.0 77.0 95.0 75.0 77.0 75.0 92.0 74.0 75.0 93.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:92.00 Median:92.00 stddev:2.21 (2.4%) stddev-sans-first:2.32 11:52:29 INFO - PID 1654 | Values: 91.0 91.0 92.0 90.0 92.0 92.0 91.0 98.0 92.0 91.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:112.50 stddev:19.66 (17.5%) stddev-sans-first:20.56 11:52:29 INFO - PID 1654 | Values: 99.0 100.0 101.0 101.0 100.0 98.0 100.0 124.0 160.0 101.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:115.20 Median:102.00 stddev:23.99 (23.5%) stddev-sans-first:24.96 11:52:29 INFO - PID 1654 | Values: 102.0 100.0 101.0 136.0 100.0 159.0 152.0 100.0 100.0 102.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 11:52:29 INFO - PID 1654 | Values: 63.0 63.0 65.0 64.0 65.0 64.0 64.0 65.0 64.0 63.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:65.50 stddev:4.41 (6.7%) stddev-sans-first:4.09 11:52:29 INFO - PID 1654 | Values: 58.0 58.0 68.0 60.0 68.0 71.0 66.0 63.0 65.0 64.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:90.00 stddev:1.62 (1.8%) stddev-sans-first:1.69 11:52:29 INFO - PID 1654 | Values: 89.0 93.0 89.0 89.0 88.0 90.0 92.0 90.0 88.0 90.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:159.20 Median:152.50 stddev:15.02 (9.9%) stddev-sans-first:15.64 11:52:29 INFO - PID 1654 | Values: 151.0 150.0 151.0 150.0 154.0 164.0 150.0 150.0 192.0 180.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.30 Median:201.50 stddev:3.02 (1.5%) stddev-sans-first:3.17 11:52:29 INFO - PID 1654 | Values: 200.0 200.0 204.0 203.0 207.0 200.0 200.0 203.0 196.0 200.0 11:52:29 INFO - PID 1654 | 11:52:29 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:74.00 stddev:1.77 (2.4%) stddev-sans-first:1.67 11:52:29 INFO - PID 1654 | Values: 71.0 75.0 74.0 72.0 73.0 71.0 74.0 72.0 75.0 76.0 11:52:29 INFO - PID 1654 | -------- Summary: end -------- 11:52:29 INFO - PID 1654 | 11:52:30 INFO - TEST-INFO | 1654: exit 0 11:52:30 INFO - TEST-OK | kraken | took 62424ms 11:52:30 INFO - SUITE-END | took 1184s 11:52:30 INFO - Completed test suite (00:19:44) 11:52:30 INFO - javascript benchmark 11:52:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7526.183899843552, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2742.8765705543246, 3502.597201321847, 3516.0682419158943, 3527.523784289121, 3560.074380661027, 5834.5040671542865, 6045.818625441233, 6109.266887973444, 6130.570860039799, 6150.354212807652, 4999.3000979862845, 6073.878522429549, 6091.420869013632, 6124.938750612492, 6133.242854861887, 5347.144456886899, 5409.6213265071465, 5416.864578385533, 5450.890982180354, 5479.315085614298, 5523.972380138093, 5551.500364967153, 5554.166874968747, 5566.165075238727, 5615.438456154393, 5336.4129945705945, 5354.170103633943, 5371.81198658049, 5373.865653358658, 5498.3127109111565, 5385.0, 5397.082495975655, 5417.214503896913, 5420.728963551846, 5438.864028399282, 5335.0, 5382.273393091921, 5385.461453854622, 5399.325084364455, 5402.108652072403, 5972.641641501524, 5992.610480318809, 5993.550483713695, 6033.155358249848, 6102.481289090432, 5322.627416080876, 6352.237731472207, 6352.46004089653, 6362.745490180433, 6421.743130274784, 5125.362526849274, 6522.902156467657, 6527.281998980108, 6549.148610680629, 6581.605103693793, 6282.089097080989, 6283.717232724538, 6288.151099601541, 6294.653794041325, 6295.244570651551, 6435.7747478838955, 6478.319776423502, 6737.258901520829, 6751.088603038527, 6753.932460675429, 6304.810855674337, 6314.71583778731, 6319.399657032666, 6352.618842869442, 6355.809325720235, 5517.531009864127, 6223.284322302993, 6554.377334153248, 6584.7366105356205, 6596.175478065242, 6445.16212892321, 6461.644609546426, 6468.741250350027, 6476.319986401454, 6490.902636460457, 6276.780312689034, 6312.0531920211415, 6329.019002054689, 6349.428551430394, 6395.680215989182, 4453.955460445419, 5214.7914083437, 5217.9478205217465, 5227.555657769132, 5246.0, 4655.045715628288, 4673.579377855941, 4683.297505374221, 4697.31888876111, 4736.668433209711, 6057.606255593372, 6118.418750218722, 6119.357467465941, 6194.163787888668, 6239.812805615838, 6421.646809425526, 6432.195975503062, 6468.870622587522, 6478.805635830932, 6503.512236582275, 6271.090691849657, 6272.435480806749, 6278.623282603058, 6283.622982620966, 6301.936980630135, 5790.971045144747, 5803.187553742395, 5806.216160818235, 5818.854528636818, 5822.272215973004, 6492.448141907991, 6500.154979852589, 6659.2008958925235, 6698.430633396216, 6705.22889867652, 6295.622262664255, 6298.401651843067, 6329.208848893888, 6476.838079048061, 6528.0, 5638.5489160867855, 6680.699368528525, 6684.866302674017, 6692.631905245258, 6749.223839258548, 6428.228612566522, 6428.935710643021, 6444.677766111769, 6452.870747619242, 6457.4511166551365, 5426.972865135649, 6315.557910946373, 6333.145025421693, 6345.016522439029, 6520.771772987922, 5286.867828304323, 6243.7814676486105, 6295.464885484785, 6317.4630156437215, 6343.777967771106, 6302.338254483213, 6313.621182728867, 6321.557490975588, 6329.272133704683, 6334.271558770801, 6252.499800015897, 6328.841778955379, 6329.936700632935, 6334.429901308904, 6340.524460665376, 6258.186435763321, 6327.683615819283, 6330.620162790247, 6334.778282760081, 6345.1116843641, 6203.720832562455, 6241.126242326169, 6245.3754624539, 6295.685215739286, 6336.809895703136], "value": 5930.752251572807, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.9161138080501, 817.4768148385035, 1133.0822034152338, 1156.5547264303252, 1156.710822294426, 35333.76331828386, 35891.74378896739, 36053.63946360536, 36064.45903311452, 36155.9153225403, 37520.0, 37915.620843791556, 37940.24119517608, 37949.430758538576, 37994.81002594983, 6113.969430152843, 6252.6561039142825, 8499.915000850006, 8614.267787238092, 8620.353473489482, 8398.664053437855, 8597.742067737978, 8605.31157507401, 8609.741707748777, 8614.396992210548, 7320.0, 8630.87053694192, 8699.565021748918, 8704.564771761417, 8707.912920870773, 8696.782580435476, 8759.824803503894, 8764.474131552128, 8779.253763430115, 8782.692605758768, 8582.270507006911, 8632.438891472035, 8636.26591739703, 8638.006629237656, 8651.134886511361, 37358.813205933795, 37517.812410938044, 37602.43596346057, 37654.62345376566, 37671.81164094162, 3007.1129016940054, 3013.5329024001317, 3026.1678038539358, 3030.5454181872674, 3033.1507177990197, 27102.728972710218, 27120.32199195016, 27220.0, 27263.591046134323, 27471.313217169532, 2760.875760590778, 2798.174538511144, 2832.1685244213895, 2862.8139170954028, 2863.599096126544, 306899.0, 308276.0, 309208.0, 309889.0, 309975.5036744484, 40410.0, 41057.0, 41088.38367424491, 48801.75599121982, 49050.13298138264, 48305.03389932252, 49255.768605784426, 49538.50461495339, 49703.75148124309, 49741.25388119181, 12380.752384952431, 14122.011459197964, 14259.0, 14263.714725705635, 14342.641433964234, 10054.044865737744, 11104.722381940355, 11258.324500529994, 11260.493277802518, 11266.887331126583, 13064.804027939588, 13070.346482675828, 13140.671483212996, 13211.537596184087, 13227.867721322857, 4980.306695723471, 5776.3068431787615, 5800.158976948319, 5821.243238378984, 5842.620229685142, 944.4380593546829, 954.9331546791797, 973.3819024919227, 993.0813997052697, 1011.6054738651932, 24015.292569698013, 24024.062970759063, 24069.0, 24134.758652413602, 24190.87904560466, 1076.6500887211687, 1077.6874706335232, 1114.3759494683004, 1115.676453828397, 1128.1651577832345, 10335.534900929477, 10704.785904282027, 12132.45403956824, 12203.572874949334, 12288.324142172087, 347350.0, 381135.98072202574, 382415.0, 394941.0, 396401.0, 53610.73194634002, 53854.73072634612, 54020.729896351055, 54071.45928540743, 54134.72932635312, 1487.3084015932643, 1535.8003459550187, 1544.1352842408287, 1548.8373720759384, 1554.7434673279008, 23789.405264868517, 23857.880710596335, 23863.403414914766, 23894.880525597262, 23985.400364991016, 37970.0, 39262.80368598139, 49731.502684972686, 49829.50170498249, 49946.75133121701, 305445.4727726347, 467103.0, 471479.0, 473681.0, 473682.0, 28901.7109828899, 29090.709092909645, 30813.691863081083, 32589.837050814593, 32980.0, 39897.2020559593, 40026.39960400538, 40259.99350016273, 40292.59707402888, 40412.0, 28282.57576136401, 28493.430131397672, 29107.708922911348, 29364.0, 29366.0, 27188.504632245418, 28853.42293154167, 28863.56704649474, 29513.557296640138, 29810.254743631584, 34675.47986780251, 35564.644353556134, 35742.821285893406, 35743.0, 35863.28273434569, 28376.29059273452, 29047.56428653614, 30129.849350753106, 30406.087817365515, 30460.695393046673, 23297.76702232956, 23635.763642363356, 23653.290401287988, 23768.0, 23925.64111538363], "value": 17379.27735704344, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.2156792385125, 426.3902619254465, 430.8728924967135, 433.28291677274103, 447.8678789757021, 19387.806121938775, 20669.793302066973, 21242.46893827655, 21460.89269553654, 21533.0, 20537.691934620994, 22377.216797412053, 22655.886720566414, 22870.085196592117, 22941.311760647164, 6082.513398928075, 6280.152179455784, 6308.0, 6320.74717011319, 6321.5890967087225, 5976.133460648203, 6151.323354431009, 6202.9069563956355, 6214.316425193225, 6226.564140510166, 5882.823515294526, 6180.351063138351, 6185.597936134137, 6194.907076393858, 6197.1324014637985, 6057.909131363011, 6148.292946311164, 6188.133661287423, 6196.318404975495, 6198.876022479571, 5291.867703307448, 5436.238926550286, 5501.559875209973, 5514.724263786795, 5517.200005999108, 26324.736752632612, 26532.86733566339, 26811.463770724673, 26909.32726681829, 27404.588931166047, 1815.1049076722607, 1922.826945574891, 1958.2950137950327, 1962.2641509433963, 1978.3174804692503, 15955.840441595668, 16213.0, 16313.91843040789, 17145.05702186379, 17574.648507029917, 1600.911949842758, 1616.111138873615, 1629.9266533005925, 1639.5573195237218, 1644.6792875389303, 156133.0, 164708.56711960345, 164792.0, 165608.0, 167514.0, 12097.57658481949, 12143.696407589881, 12188.695282617828, 12282.938585307016, 12433.191842530206, 16361.263743131587, 16402.343906243856, 16402.589935251475, 16415.753763693556, 16434.917825410797, 8509.65961361551, 8851.46891186531, 8865.113488651212, 8981.326400519856, 9003.639854405881, 8344.874826877602, 8362.749117526484, 8386.832263354821, 8537.957310213533, 8553.572321383906, 7586.848263034819, 7700.383969282444, 7755.30202281786, 7758.456908016497, 7767.805804854924, 1843.6861738518073, 2078.324544523036, 2079.490524821398, 2091.937241882746, 2275.6586512023, 894.3560636341823, 896.6996056321075, 912.6440688131634, 932.2681694869494, 943.6697155995347, 19072.0, 19153.904230478758, 20200.494987625134, 20264.696029559567, 20361.846375046203, 935.3078721745995, 938.6057855700623, 966.8259713251682, 971.1647982734843, 972.863799068131, 10029.398236105857, 10316.0, 10474.004969527883, 10491.16070714341, 10492.21308401873, 681391.0, 696447.0, 700360.0, 708146.0, 713944.0, 100191.0, 113372.43313783378, 117382.41308793573, 119601.40199299122, 152702.0, 1027.609508386823, 1074.6507385099874, 1093.8796732359433, 1237.6534570320325, 1241.7951038078797, 17848.732269016236, 20203.262396456703, 20691.0, 21064.841433720678, 21275.04262308169, 63351.683241583494, 63624.363756363695, 64053.03920441104, 64714.02928955975, 65158.348416517125, 84170.0, 86900.0, 88344.0, 89968.5501572488, 92276.0, 7122.60825654594, 8452.870631781982, 8754.387192896436, 8793.736187914374, 8958.0, 13536.323183840965, 13557.728845423233, 13765.174089554659, 14021.439142434188, 14165.504207352693, 9945.403275803475, 11528.654140375802, 11673.883261167279, 11811.704707382385, 11965.940170299093, 10766.46167691628, 10930.398828064534, 11004.944975275072, 11241.943790280997, 11605.593804216813, 10530.104941080093, 11668.183227174017, 12012.519499220283, 12149.514019439124, 12150.088743344108, 8161.510309381456, 8500.70247541333, 8549.786255343666, 8998.290324838259, 9013.684521041732, 11010.955208577196, 11502.712432189262, 11714.0, 11867.287962721919, 11878.643640690792], "value": 10308.773148445049, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.8729970652796, 1380.882624976877, 1461.0, 1480.422635172283, 1480.541032279993, 9458.574364153612, 10601.20490963177, 10606.893931060697, 10607.78784424311, 10617.522211500482, 10341.689749307532, 10372.792544149113, 10620.362778233311, 10634.415107169103, 10649.84025239622, 2340.6137987232128, 2379.5478859016816, 2387.379281386843, 2394.1381102803066, 2399.448126930807, 2272.681824544565, 2412.588635648148, 2435.208557218902, 2436.537057958982, 2438.3782135555407, 2023.767266764319, 2100.0339843671927, 2100.59038487495, 2103.347962131744, 2105.69467427223, 2090.5296308330658, 2118.724565806443, 2352.8117750579913, 2435.403326185096, 2439.914602988887, 2420.165043060141, 2421.7336093029753, 2423.1034517228563, 2423.3820375804057, 2424.527217192648, 10588.841167382496, 10632.30889992148, 10653.0, 10665.573377064908, 10685.572577096907, 4606.976965115153, 5268.5521730652945, 5433.157860531624, 6016.217891674054, 6104.175936248638, 3659.9085022874374, 3699.8335074921692, 3756.1360886996144, 3762.6613604775703, 3856.3058649443087, 980.4215512847454, 1183.3255044624568, 1184.2480025183984, 1190.3453100794616, 1224.1125184241444, 12727.0, 14645.707085858223, 14869.330880110421, 14891.180985045818, 15026.323815428332, 5106.821261255913, 5248.816291429858, 5300.655457395257, 5300.92048619271, 5343.973280133652, 11264.493097810619, 11273.098152147808, 11274.943625281821, 11275.66173014811, 11284.322940623424, 6226.315105338409, 6237.064440333987, 6243.531735119885, 6252.374762523711, 6263.6241825490615, 5599.496045355938, 5772.624779389326, 5800.332961709458, 5809.2738407699035, 5847.795327163529, 3615.837287322077, 4016.979915100406, 4166.8749937501925, 4176.206520761046, 4233.11104668017, 1252.5929073051295, 1253.3518423273367, 1253.6176466177722, 1269.4160686084504, 1271.0594160321295, 650.5381179362611, 766.5630590563326, 792.4888446951684, 793.2067932067932, 821.9013718353835, 5689.715514224272, 5796.855078623068, 5925.170476133345, 5980.700964951735, 5991.670458124758, 465.9138059459011, 572.461885827321, 588.3116753398534, 595.9135925290808, 596.6539407143846, 3487.5640544931885, 3569.464580312974, 4036.858709945138, 4090.9590904091174, 4168.145530166369, 13130.671733206746, 13434.395452204673, 13448.663783405493, 13520.25638589887, 13863.514776982756, 13300.800487992494, 13728.862711372958, 13740.175589464465, 13745.862541374458, 13951.511697090553, 9968.451735154636, 9969.40183588987, 10209.74475638115, 10231.59073637061, 10243.59025638981, 6226.937730622636, 6337.8732425352155, 6379.649119298298, 6398.168238129014, 6417.454516366161, 12134.939325303318, 12266.141370104364, 12373.75252494963, 12381.380930953235, 12539.122261441611, 11974.760504790029, 12313.50745970187, 12378.319192444142, 12461.75076498483, 12623.87376126227, 7168.569885806868, 8864.246539044254, 8964.506952117696, 8970.0, 8972.730818075468, 9674.467904265333, 9790.0, 9794.461304628028, 9888.406695598287, 9928.305018648625, 6707.966460167668, 8322.750317490485, 8472.44929079608, 8534.658613655385, 8619.741407757778, 8182.877256841033, 8318.209770071833, 8367.58162091905, 8404.033536143423, 8429.283510901396, 7588.8482230356185, 7742.7677169685, 7749.457537972513, 7754.340881025176, 7756.340711039399, 5548.361938377138, 5730.856728581819, 5747.310322761128, 5877.617954832922, 5944.405559444021, 5668.26312579362, 5828.0, 5932.417879634464, 5996.88006239864, 6013.579049466495], "value": 4959.84892329209, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.33837791431864, 386.524574773029, 388.2041814280724, 395.06369903329113, 396.8710169195011, 4699.248120300753, 4879.853234489901, 5471.878264955684, 5737.2828396450495, 5768.221290125832, 4913.656044076916, 5538.169274608801, 5634.774609015644, 5800.506956908657, 5818.5926985111055, 4508.797104130322, 4582.450105987286, 4597.678162528624, 4605.723656580616, 4625.375574297461, 3583.2654305867236, 3750.587435382106, 4137.855175068873, 4376.848668958361, 4526.026904215593, 4494.97752511237, 4503.504614492404, 4509.571590698878, 4512.210363186446, 4512.30059340801, 4517.864464066067, 4522.299043648207, 4524.276115821469, 4527.637788976906, 4536.931946020813, 2990.5215165573622, 3666.633336666312, 3860.575336712959, 3877.3408520551575, 3934.704897132698, 4991.500849915015, 5710.628809127435, 5720.942790572082, 5746.569007324468, 5792.623479473863, 4292.9141417171795, 5147.536721695028, 5171.7672704728375, 5171.818986335498, 5174.508421699932, 5029.497050294978, 6193.074739084854, 6283.937160628427, 6303.3066362700065, 6328.145700330442, 4250.766207858566, 4253.6392302272225, 4261.143664983195, 4270.743755374688, 4282.5075116361395, 5802.593818432753, 6403.2956374799205, 6614.2393624733295, 6708.974615230823, 6770.661466926683, 3862.439946207784, 4330.675199360061, 4346.869593912188, 4347.956460463577, 4352.891177720519, 4402.669799765031, 4418.9337159942625, 4425.778711064434, 4434.512203657595, 4442.53353397895, 4548.840790572314, 5166.793328266903, 5180.637355385087, 5215.269862219292, 5231.71225582589, 4972.403311602631, 5886.852063847508, 6129.632222066691, 6401.519886008568, 6414.679266036773, 1238.2756087688622, 1379.9448022079005, 1388.8680575345327, 1491.8582734640192, 1504.428317239464, 951.6399047416866, 979.9412035277763, 991.6033586565287, 991.8016396720685, 1003.7340104872214, 5218.66078704883, 5940.34656187819, 5993.160957465958, 5999.250093738283, 6008.759649613967, 6432.163818703632, 6500.414962653384, 6507.414332710079, 6559.72085443334, 6584.242812076577, 5509.862253443615, 5838.007538718428, 5929.851753706192, 6004.53813936284, 6014.548908831767, 2692.623032775413, 3401.302732939791, 3401.8809341672923, 3411.8123503207066, 3418.538497302882, 5679.460451257124, 6393.529488217736, 6574.704138313788, 6798.0, 6800.0, 5001.721806049798, 6320.968395157995, 6616.834579135464, 6758.560693554903, 6758.594484330857, 5271.736413179402, 6496.252930913006, 6592.208934927743, 6604.900926486107, 6623.834404139935, 5807.767689292381, 6218.06728990655, 6552.984287435455, 6577.671116444063, 6594.901076483952, 6283.622982621057, 6414.422701956846, 6478.384553467413, 6503.0, 6507.837304067436, 6042.667653279112, 6217.533684973555, 6832.487563432663, 6852.663730572491, 6870.27862074495, 4358.629516491007, 4499.752513611652, 4696.8590942271785, 4704.341392205026, 4733.798783565659, 6470.708818103102, 6551.901721474277, 6568.704408301543, 6571.441427478718, 6590.0, 5527.778888844401, 6096.29892562361, 6363.7772677956045, 6368.490520758421, 6402.103705481143, 6298.519847835846, 6340.556161068681, 6375.776847810304, 6388.840278993062, 6417.97312430009, 6197.504199664107, 6225.190725205694, 6233.812985610439, 6238.719257633508, 6271.96864015677, 5322.973385133049, 6018.909716354171, 6272.5922815016875, 6360.300366959538, 6381.298057213796, 4963.801447942042, 6198.318184999556, 6214.358897468648, 6222.253329600477, 6245.375462453719], "value": 4608.99757334509, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.6545658084123, 726.4515290955328, 741.6699568691931, 742.1835980421536, 771.3520642660164, 15407.845921540782, 16214.918925405385, 16313.347466101372, 16359.918200408996, 16369.42707005255, 14222.786658200135, 15305.92347038266, 15312.617184570392, 15360.231988400592, 15387.153706546163, 927.1701826864969, 956.6077908057698, 961.5336561767538, 963.7253382785907, 963.7831487915233, 927.6845872403381, 957.0907637744136, 957.8467445208768, 959.0984474593894, 959.1559427703611, 1000.6447711062541, 1025.523131743739, 1029.1869423155697, 1029.2332212501697, 1029.5058371981415, 1009.6415772400766, 1020.9744756381076, 1022.049493970607, 1022.8107800056939, 1023.5956797065155, 749.2769477454228, 756.6935391166586, 756.7880993321878, 761.2159475739996, 761.2996043639864, 16516.587085322964, 16956.915215423967, 17048.2328295227, 17162.22769975354, 17237.138143092794, 14586.0, 14695.70608587833, 14837.0, 14883.70232595342, 15656.373745050185, 11264.267822591506, 11669.533218671242, 11879.465424055938, 12016.63950081499, 12060.879391206063, 3022.184010317202, 3025.9243518911985, 3049.634043914722, 3050.4966680497914, 3141.6701246369257, 112134.0, 112357.0, 115023.0, 115686.84313156929, 117645.82354176434, 11225.719357015976, 11418.543258269576, 11462.312261264351, 11633.47649355764, 11828.940855295668, 25316.493670126863, 25552.616710749353, 25582.488350233263, 25583.87208063985, 25585.23244302674, 7509.146524320723, 7700.614969251627, 7723.304902558797, 7771.339436147878, 7787.844243115106, 3992.5608183099835, 4111.0, 4115.609017143366, 4118.478126161854, 4174.666026717855, 4126.360414135814, 4183.6653067754505, 4224.936625950613, 4230.936535951963, 4378.036831896978, 864.6930339729305, 866.5017614871474, 867.8280883371289, 876.3383645347722, 879.4811061473761, 556.5630979681011, 566.3204155013917, 576.7145263094711, 578.7684926029538, 584.9837642217998, 8608.311335093178, 9390.90609093914, 10082.941291164469, 10271.7432064199, 10455.529501172465, 490.33314692018826, 575.9078547432391, 581.2327727399876, 582.1907548507578, 595.1459655394523, 4488.618467430239, 4520.321951707205, 4602.010567727954, 4788.162071637449, 4793.832215872428, 72964.63517682484, 76557.6172119147, 86939.0, 93263.0, 93293.0, 78446.82329765058, 79560.80658790123, 81394.0, 82627.17372826194, 85732.57133714417, 74809.25190748132, 75760.0, 75849.0, 76775.23224767792, 78371.21628783754, 10552.366857988545, 10707.518161682587, 10847.45762711877, 10921.453927303444, 10938.835917461085, 33753.493697594065, 33791.98624041283, 34229.65770342364, 34421.483677744356, 35003.47494787631, 43565.56434435615, 43968.90077748082, 44080.11839763251, 44394.334084988106, 44647.55352446564, 13065.678114289605, 13161.276129812952, 13372.93313533426, 13666.043376963516, 14091.956666283126, 14082.78875816843, 14116.082454640415, 14374.928125359726, 14632.341544630304, 14928.776068358766, 13063.0, 13530.93234533821, 13555.525556605471, 13696.31518424055, 13806.723865522834, 12233.449494772934, 12736.426860791473, 13256.668583285495, 13427.060105792501, 13438.798418023542, 13164.47342106305, 13193.60419187426, 13328.533501327795, 13413.530526431528, 13728.45086196541, 9375.718728438158, 9566.378185417925, 9675.322727409013, 9738.659146929824, 10036.749081272734, 11013.339199648046, 11161.776764464828, 11188.888111118784, 11189.0, 11245.887541124484], "value": 7481.550311132968, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1584.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 121.0, 124.0, 120.0, 122.0, 121.0, 122.0, 121.0, 121.0, 121.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 117.0, 119.0, 161.0, 120.0, 121.0, 129.0, 118.0, 144.0, 119.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 210.0, 216.0, 209.0, 215.0, 208.0, 211.0, 210.0, 216.0, 211.0], "value": 212.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 62.0, 65.0, 101.0, 68.0, 64.0, 58.0, 60.0, 80.0, 63.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 77.0, 95.0, 75.0, 77.0, 75.0, 92.0, 74.0, 75.0, 93.0], "value": 83.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 90.0, 92.0, 92.0, 91.0, 98.0, 92.0, 91.0], "value": 92.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 101.0, 101.0, 100.0, 98.0, 100.0, 124.0, 160.0, 101.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 100.0, 101.0, 136.0, 100.0, 159.0, 152.0, 100.0, 100.0, 102.0], "value": 115.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 65.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 63.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 68.0, 60.0, 68.0, 71.0, 66.0, 63.0, 65.0, 64.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 89.0, 89.0, 88.0, 90.0, 92.0, 90.0, 88.0, 90.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 150.0, 154.0, 164.0, 150.0, 150.0, 192.0, 180.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 204.0, 203.0, 207.0, 200.0, 200.0, 203.0, 196.0, 200.0], "value": 201.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 74.0, 72.0, 73.0, 71.0, 74.0, 72.0, 75.0, 76.0], "value": 73.3, "unit": "score"}]}]} 11:52:31 INFO - Return code: 0 11:52:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:52:31 ERROR - # TBPL SUCCESS # 11:52:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:52:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:52:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:52:31 INFO - 2017-03-28 11:52:31.178 screenresolution[1658:9761] starting screenresolution argv=screenresolution get 11:52:31 INFO - 2017-03-28 11:52:31.181 screenresolution[1658:9761] Display 0: 1600x1200x32@60 11:52:31 INFO - 2017-03-28 11:52:31.188 screenresolution[1659:9764] starting screenresolution argv=screenresolution list 11:52:31 INFO - Available Modes on Display 0 11:52:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:52:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:52:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:52:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:52:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:52:31 INFO - Intel Iris: 11:52:31 INFO - Chipset Model: Intel Iris 11:52:31 INFO - Type: GPU 11:52:31 INFO - Bus: Built-In 11:52:31 INFO - VRAM (Dynamic, Max): 1536 MB 11:52:31 INFO - Vendor: Intel (0x8086) 11:52:31 INFO - Device ID: 0x0a2e 11:52:31 INFO - Revision ID: 0x0009 11:52:31 INFO - Displays: 11:52:31 INFO - Display: 11:52:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:52:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:52:31 INFO - Display Serial Number: 1600x1200 60 11:52:31 INFO - Main Display: Yes 11:52:31 INFO - Mirror: Off 11:52:31 INFO - Online: Yes 11:52:31 INFO - Rotation: Supported 11:52:31 INFO - Return code: 0 11:52:31 INFO - Running post-action listener: _resource_record_post_action 11:52:31 INFO - [mozharness: 2017-03-28 18:52:31.436130Z] Finished run-tests step (success) 11:52:31 INFO - Running post-run listener: _resource_record_post_run 11:52:31 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 38110208; Write bytes: 512006144; Read time: 241; Write time: 1564 11:52:31 INFO - TinderboxPrint: CPU usage
53.4% 11:52:31 INFO - TinderboxPrint: I/O read bytes / time
38,110,208 / 241 11:52:31 INFO - TinderboxPrint: I/O write bytes / time
512,006,144 / 1,564 11:52:31 INFO - TinderboxPrint: CPU idle
2,248.3 (46.6%) 11:52:31 INFO - TinderboxPrint: CPU system
960.5 (19.9%) 11:52:31 INFO - TinderboxPrint: CPU user
1,612.9 (33.5%) 11:52:31 INFO - TinderboxPrint: Swap in / out
249,421,824 / 0 11:52:31 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204964352; Write bytes: 204924928; Read time: 15802; Write time: 448 11:52:31 INFO - run-tests - Wall time: 1186s; CPU: 53.0%; Read bytes: 37155840; Write bytes: 297111552; Read time: 228; Write time: 1082 11:52:31 INFO - Running post-run listener: _upload_blobber_files 11:52:31 INFO - Blob upload gear active. 11:52:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:52:31 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 11:52:31 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'] 11:52:31 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 11:52:32 INFO - (blobuploader) - INFO - Open directory for files ... 11:52:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:52:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:52:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:33 INFO - (blobuploader) - INFO - Done attempting. 11:52:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:52:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:33 INFO - (blobuploader) - INFO - Done attempting. 11:52:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:52:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:35 INFO - (blobuploader) - INFO - Done attempting. 11:52:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:52:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:52:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:37 INFO - (blobuploader) - INFO - Done attempting. 11:52:37 INFO - (blobuploader) - INFO - Iteration through files over. 11:52:37 INFO - Return code: 0 11:52:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:52:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:52:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58fbbbe7612b32bd87fffe224c50acce32b650e79a25d65ded19d3475dcdea426791b7990e40eb6f8e28dc5ff62feeb1e174e2b29d937d2d04d915614f0d1e9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3a188d1538d883a67685441f78341a1b5af8af8a3de2be201508784667b138a8847cf54a4a0a46cdd7be8312255c2908af0ab7ce8252e096a6ee52fe969b5fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ff8d434a6ee206d6053a5b681f7fb65164d738c765a2da14aec63df7adbe449d639198e3ce6bc7757f606fe9a8d82506775e75a2667a3e7c7788b0c6ca665ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/478fe57255fe240d6a1b3c9a31ef993ea87e3aab25211d67f8f85986f194cfff9f1745172de1aebe468c2c18fbf6a0ad6b5632a8a8aaf04a884db49efd7e67da"} 11:52:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:52:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:52:37 INFO - Contents: 11:52:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58fbbbe7612b32bd87fffe224c50acce32b650e79a25d65ded19d3475dcdea426791b7990e40eb6f8e28dc5ff62feeb1e174e2b29d937d2d04d915614f0d1e9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3a188d1538d883a67685441f78341a1b5af8af8a3de2be201508784667b138a8847cf54a4a0a46cdd7be8312255c2908af0ab7ce8252e096a6ee52fe969b5fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ff8d434a6ee206d6053a5b681f7fb65164d738c765a2da14aec63df7adbe449d639198e3ce6bc7757f606fe9a8d82506775e75a2667a3e7c7788b0c6ca665ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/478fe57255fe240d6a1b3c9a31ef993ea87e3aab25211d67f8f85986f194cfff9f1745172de1aebe468c2c18fbf6a0ad6b5632a8a8aaf04a884db49efd7e67da"} 11:52:37 INFO - Running post-run listener: copy_logs_to_upload_dir 11:52:37 INFO - Copying logs to upload dir... 11:52:37 INFO - mkdir: /builds/slave/test/build/upload/logs 11:52:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.128225 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-28 11:52:37.753314) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:37.757261) ========= 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.07QEcarQbQ/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.vReoAfXBLW/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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/58fbbbe7612b32bd87fffe224c50acce32b650e79a25d65ded19d3475dcdea426791b7990e40eb6f8e28dc5ff62feeb1e174e2b29d937d2d04d915614f0d1e9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3a188d1538d883a67685441f78341a1b5af8af8a3de2be201508784667b138a8847cf54a4a0a46cdd7be8312255c2908af0ab7ce8252e096a6ee52fe969b5fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ff8d434a6ee206d6053a5b681f7fb65164d738c765a2da14aec63df7adbe449d639198e3ce6bc7757f606fe9a8d82506775e75a2667a3e7c7788b0c6ca665ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/478fe57255fe240d6a1b3c9a31ef993ea87e3aab25211d67f8f85986f194cfff9f1745172de1aebe468c2c18fbf6a0ad6b5632a8a8aaf04a884db49efd7e67da"} build_url:https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011157 build_url: 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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/58fbbbe7612b32bd87fffe224c50acce32b650e79a25d65ded19d3475dcdea426791b7990e40eb6f8e28dc5ff62feeb1e174e2b29d937d2d04d915614f0d1e9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3a188d1538d883a67685441f78341a1b5af8af8a3de2be201508784667b138a8847cf54a4a0a46cdd7be8312255c2908af0ab7ce8252e096a6ee52fe969b5fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ff8d434a6ee206d6053a5b681f7fb65164d738c765a2da14aec63df7adbe449d639198e3ce6bc7757f606fe9a8d82506775e75a2667a3e7c7788b0c6ca665ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/478fe57255fe240d6a1b3c9a31ef993ea87e3aab25211d67f8f85986f194cfff9f1745172de1aebe468c2c18fbf6a0ad6b5632a8a8aaf04a884db49efd7e67da"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:37.796517) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:37.796869) ========= 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.07QEcarQbQ/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.vReoAfXBLW/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.005098 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:37.849879) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 11:52:37.850602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 11:52:37.851276) ========= ========= Total master_lag: 0.30 =========