builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0248 starttime: 1490738245.27 results: success (0) buildid: 20170328133121 builduid: 13a996dcbaa34cf6adce8adcfe7813ec revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.269518) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.270040) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.270376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.296390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.296716) ========= 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.MB40PqHZwx/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.u9OIP3T1nU/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:57:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-03-28 14:57:25 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129919 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.530680) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.531033) ========= 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.MB40PqHZwx/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.u9OIP3T1nU/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.027139 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:25.580564) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 14:57:25.580959) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --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 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MB40PqHZwx/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.u9OIP3T1nU/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:57:25,661 truncating revision to first 12 chars 2017-03-28 14:57:25,662 Setting DEBUG logging. 2017-03-28 14:57:25,662 attempt 1/10 2017-03-28 14:57:25,662 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/272ce6c25721?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 14:57:27,725 unpacking tar archive at: mozilla-central-272ce6c25721/testing/mozharness/ program finished with exit code 0 elapsedTime=3.956801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 14:57:29.561524) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:29.561956) ========= script_repo_revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:29.562408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:29.562735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:29.582872) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-28 14:57:29.583336) ========= /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.MB40PqHZwx/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.u9OIP3T1nU/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:57:29 INFO - MultiFileLogger online at 20170328 14:57:29 in /builds/slave/test 14:57:29 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 14:57:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:57:29 INFO - {'append_to_log': False, 14:57:29 INFO - 'base_work_dir': '/builds/slave/test', 14:57:29 INFO - 'blob_upload_branch': 'Firefox', 14:57:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:57:29 INFO - 'branch': 'Firefox', 14:57:29 INFO - 'buildbot_json_path': 'buildprops.json', 14:57:29 INFO - 'config_files': ('talos/mac_config.py',), 14:57:29 INFO - 'default_actions': ('clobber', 14:57:29 INFO - 'read-buildbot-config', 14:57:29 INFO - 'download-and-extract', 14:57:29 INFO - 'populate-webroot', 14:57:29 INFO - 'create-virtualenv', 14:57:29 INFO - 'install', 14:57:29 INFO - 'run-tests'), 14:57:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:57:29 INFO - 'download_minidump_stackwalk': True, 14:57:29 INFO - 'download_symbols': 'ondemand', 14:57:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:57:29 INFO - 'tooltool.py': '/tools/tooltool.py', 14:57:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:57:29 INFO - '/tools/misc-python/virtualenv.py')}, 14:57:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:57:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:57:29 INFO - 'gecko_profile': False, 14:57:29 INFO - 'gecko_profile_interval': 0, 14:57:29 INFO - 'installer_path': 'installer.exe', 14:57:29 INFO - 'log_level': 'info', 14:57:29 INFO - 'log_name': 'talos', 14:57:29 INFO - 'log_to_console': True, 14:57:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:57:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:57:29 INFO - 'opt_config_files': (), 14:57:29 INFO - 'pip_index': False, 14:57:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:57:29 INFO - 'cmd': ('bash', 14:57:29 INFO - '-c', 14:57:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:57:29 INFO - 'enabled': True, 14:57:29 INFO - 'halt_on_failure': False, 14:57:29 INFO - 'name': 'check_screen_resolution'},), 14:57:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:57:29 INFO - 'cmd': ('bash', 14:57:29 INFO - '-c', 14:57:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:57:29 INFO - 'enabled': True, 14:57:29 INFO - 'halt_on_failure': False, 14:57:29 INFO - 'name': 'check_screen_resolution'},), 14:57:29 INFO - 'run_cmd_checks_enabled': True, 14:57:29 INFO - 'suite': 'dromaeojs', 14:57:29 INFO - 'system_bits': '32', 14:57:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:57:29 INFO - 'title': 't-yosemite-r7-0248', 14:57:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:57:29 INFO - 'use_talos_json': True, 14:57:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:57:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:57:29 INFO - 'work_dir': 'build'} 14:57:29 INFO - [mozharness: 2017-03-28 21:57:29.771182Z] Running clobber step. 14:57:29 INFO - Running pre-action listener: _resource_record_pre_action 14:57:29 INFO - Running main action method: clobber 14:57:29 INFO - rmtree: /builds/slave/test/build 14:57:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:57:32 INFO - Running post-action listener: _resource_record_post_action 14:57:32 INFO - [mozharness: 2017-03-28 21:57:32.203264Z] Finished clobber step (success) 14:57:32 INFO - [mozharness: 2017-03-28 21:57:32.203481Z] Running read-buildbot-config step. 14:57:32 INFO - Running pre-action listener: _resource_record_pre_action 14:57:32 INFO - Running main action method: read_buildbot_config 14:57:32 INFO - Using buildbot properties: 14:57:32 INFO - { 14:57:32 INFO - "project": "", 14:57:32 INFO - "product": "firefox", 14:57:32 INFO - "script_repo_revision": "production", 14:57:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:57:32 INFO - "repository": "", 14:57:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:57:32 INFO - "buildid": "20170328133121", 14:57:32 INFO - "pgo_build": "False", 14:57:32 INFO - "basedir": "/builds/slave/test", 14:57:32 INFO - "buildnumber": 62, 14:57:32 INFO - "slavename": "t-yosemite-r7-0248", 14:57:32 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:57:32 INFO - "platform": "yosemite_r7", 14:57:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:57:32 INFO - "branch": "mozilla-central", 14:57:32 INFO - "repo_path": "mozilla-central", 14:57:32 INFO - "revision": "272ce6c2572164f5f6a9fba2a980ba9ccf50770c", 14:57:32 INFO - "stage_platform": "macosx64", 14:57:32 INFO - "builduid": "13a996dcbaa34cf6adce8adcfe7813ec", 14:57:32 INFO - "slavebuilddir": "test" 14:57:32 INFO - } 14:57:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:57:32 INFO - Running post-action listener: _resource_record_post_action 14:57:32 INFO - [mozharness: 2017-03-28 21:57:32.208501Z] Finished read-buildbot-config step (success) 14:57:32 INFO - [mozharness: 2017-03-28 21:57:32.208617Z] Running download-and-extract step. 14:57:32 INFO - Running pre-action listener: _resource_record_pre_action 14:57:32 INFO - Running main action method: download_and_extract 14:57:32 INFO - mkdir: /builds/slave/test/build/tests 14:57:32 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')]} 14:57:32 INFO - trying https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:57:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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 14:57:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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 14:57:33 INFO - Downloaded 1325 bytes. 14:57:33 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:57:33 INFO - Using the following test package requirements: 14:57:33 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:57:33 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:57:33 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:57:33 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:57:33 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'jsshell-mac.zip'], 14:57:33 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:57:33 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:57:33 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:57:33 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:57:33 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:57:33 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:33 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:57:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:57:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:57:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:57:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:57:34 INFO - Expected file size: 52507148 14:57:34 INFO - Obtained file size: 52507148 14:57:50 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 14:57:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:57:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:57:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:57:53 INFO - Expected file size: 52507148 14:57:53 INFO - Obtained file size: 52507148 14:58:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:58:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:58:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:58:09 INFO - Expected file size: 14404171 14:58:09 INFO - Obtained file size: 14404171 14:58: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')]} 14:58:09 INFO - trying https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:58:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:58:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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 14:58:12 INFO - Downloaded 75590826 bytes. 14:58:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:58:12 INFO - mkdir: /builds/slave/test/properties 14:58:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:58:12 INFO - Writing to file /builds/slave/test/properties/build_url 14:58:12 INFO - Contents: 14:58:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:58:13 INFO - Running post-action listener: _resource_record_post_action 14:58:13 INFO - Running post-action listener: set_extra_try_arguments 14:58:13 INFO - [mozharness: 2017-03-28 21:58:13.535474Z] Finished download-and-extract step (success) 14:58:13 INFO - [mozharness: 2017-03-28 21:58:13.535618Z] Running populate-webroot step. 14:58:13 INFO - Running pre-action listener: _resource_record_pre_action 14:58:13 INFO - Running main action method: populate_webroot 14:58:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:58:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:58:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:58:13 INFO - '--tppagecycles', 14:58:13 INFO - '7'], 14:58:13 INFO - 'tests': ['tsvgm']}}, 14:58:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:58:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:58:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:58:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:58:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:58:13 INFO - 'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:58:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:58:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:58:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:58:13 INFO - 'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['damp', 'tps']}, 14:58:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:58:13 INFO - 'tests': ['damp', 'tps']}, 14:58:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['dromaeo_dom']}, 14:58:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:58:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['basic_compositor_video']}, 14:58:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:58:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['a11yr', 14:58:13 INFO - 'ts_paint', 14:58:13 INFO - 'tpaint', 14:58:13 INFO - 'sessionrestore', 14:58:13 INFO - 'sessionrestore_no_auto_restore', 14:58:13 INFO - 'tabpaint']}, 14:58:13 INFO - 'other-e10s': {'tests': ['a11yr', 14:58:13 INFO - 'ts_paint', 14:58:13 INFO - 'tpaint', 14:58:13 INFO - 'sessionrestore', 14:58:13 INFO - 'sessionrestore_no_auto_restore', 14:58:13 INFO - 'tabpaint']}, 14:58:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:58:13 INFO - 'ts_paint', 14:58:13 INFO - 'tpaint', 14:58:13 INFO - 'sessionrestore', 14:58:13 INFO - 'sessionrestore_no_auto_restore', 14:58:13 INFO - 'tabpaint']}, 14:58:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:58:13 INFO - 'ts_paint', 14:58:13 INFO - 'tpaint', 14:58:13 INFO - 'sessionrestore', 14:58:13 INFO - 'sessionrestore_no_auto_restore', 14:58:13 INFO - 'tabpaint']}, 14:58:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['a11yr', 14:58:13 INFO - 'ts_paint', 14:58:13 INFO - 'tpaint', 14:58:13 INFO - 'sessionrestore', 14:58:13 INFO - 'sessionrestore_no_auto_restore', 14:58:13 INFO - 'tabpaint']}, 14:58:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['a11yr', 14:58:13 INFO - 'ts_paint', 14:58:13 INFO - 'tpaint', 14:58:13 INFO - 'sessionrestore', 14:58:13 INFO - 'sessionrestore_no_auto_restore', 14:58:13 INFO - 'tabpaint']}, 14:58:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['tsvgx', 14:58:13 INFO - 'tsvgr_opacity', 14:58:13 INFO - 'tart', 14:58:13 INFO - 'tscrollx', 14:58:13 INFO - 'cart', 14:58:13 INFO - 'tsvg_static']}, 14:58:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:58:13 INFO - 'tsvgr_opacity', 14:58:13 INFO - 'tart', 14:58:13 INFO - 'tscrollx', 14:58:13 INFO - 'cart', 14:58:13 INFO - 'tsvg_static']}, 14:58:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:58:13 INFO - 'talos_options': ['--disable-e10s'], 14:58:13 INFO - 'tests': ['tp5o']}, 14:58:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:58:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:58:13 INFO - 'talos_options': ['--disable-e10s', 14:58:13 INFO - '--xperf_path', 14:58:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:58:13 INFO - 'tests': ['tp5n']}, 14:58:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:58:13 INFO - 'talos_options': ['--xperf_path', 14:58:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:58:13 INFO - 'tests': ['tp5n']}}, 14:58:13 INFO - 'talos.zip': {'path': '', 14:58:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:58:13 INFO - Running post-action listener: _resource_record_post_action 14:58:13 INFO - [mozharness: 2017-03-28 21:58:13.553717Z] Finished populate-webroot step (success) 14:58:13 INFO - [mozharness: 2017-03-28 21:58:13.553855Z] Running create-virtualenv step. 14:58:13 INFO - Running pre-action listener: _resource_record_pre_action 14:58:13 INFO - Running main action method: create_virtualenv 14:58:13 INFO - Creating virtualenv /builds/slave/test/build/venv 14:58:13 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 14:58:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:58:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:58:13 INFO - Using real prefix '/tools/python27' 14:58:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:58:14 INFO - Installing distribute.............................................................................................................................................................................................done. 14:58:17 INFO - Installing pip.................done. 14:58:17 INFO - Return code: 0 14:58:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:58:17 INFO - Reading from file tmpfile_stdout 14:58:17 INFO - Output received: 14:58:17 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) 14:58:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:17 INFO - 'HOME': '/Users/cltbld', 14:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:17 INFO - 'LOGNAME': 'cltbld', 14:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:17 INFO - 'MOZ_NO_REMOTE': '1', 14:58:17 INFO - 'NO_EM_RESTART': '1', 14:58:17 INFO - 'PAGER': '/bin/cat', 14:58:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:17 INFO - 'PWD': '/builds/slave/test', 14:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:17 INFO - 'SHELL': '/bin/bash', 14:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:17 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:17 INFO - 'USER': 'cltbld', 14:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:17 INFO - 'XPC_FLAGS': '0x0', 14:58:17 INFO - 'XPC_SERVICE_NAME': '0', 14:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:17 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 14:58:17 INFO - Cleaning up... 14:58:17 INFO - Return code: 0 14:58:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:17 INFO - 'HOME': '/Users/cltbld', 14:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:17 INFO - 'LOGNAME': 'cltbld', 14:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:17 INFO - 'MOZ_NO_REMOTE': '1', 14:58:17 INFO - 'NO_EM_RESTART': '1', 14:58:17 INFO - 'PAGER': '/bin/cat', 14:58:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:17 INFO - 'PWD': '/builds/slave/test', 14:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:17 INFO - 'SHELL': '/bin/bash', 14:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:17 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:17 INFO - 'USER': 'cltbld', 14:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:17 INFO - 'XPC_FLAGS': '0x0', 14:58:17 INFO - 'XPC_SERVICE_NAME': '0', 14:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:18 INFO - Downloading/unpacking psutil>=3.1.1 14:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:58:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:58:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:58:21 INFO - Installing collected packages: psutil 14:58:21 INFO - Running setup.py install for psutil 14:58:21 INFO - building 'psutil._psutil_osx' extension 14:58:21 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 14:58:22 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 14:58:22 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 14:58:22 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 14:58:22 INFO - building 'psutil._psutil_posix' extension 14:58:22 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 14:58:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:58:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:58:22 INFO - ^ 14:58:22 INFO - 1 warning generated. 14:58:22 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 14:58:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:58:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:58:22 INFO - Successfully installed psutil 14:58:22 INFO - Cleaning up... 14:58:22 INFO - Return code: 0 14:58:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:23 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')]} 14:58:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:23 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:23 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 14:58:23 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 14:58:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:23 INFO - 'HOME': '/Users/cltbld', 14:58:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:23 INFO - 'LOGNAME': 'cltbld', 14:58:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:23 INFO - 'MOZ_NO_REMOTE': '1', 14:58:23 INFO - 'NO_EM_RESTART': '1', 14:58:23 INFO - 'PAGER': '/bin/cat', 14:58:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:23 INFO - 'PWD': '/builds/slave/test', 14:58:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:23 INFO - 'SHELL': '/bin/bash', 14:58:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:23 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:23 INFO - 'USER': 'cltbld', 14:58:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:23 INFO - 'XPC_FLAGS': '0x0', 14:58:23 INFO - 'XPC_SERVICE_NAME': '0', 14:58:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:58:26 INFO - Installing collected packages: mozsystemmonitor 14:58:26 INFO - Running setup.py install for mozsystemmonitor 14:58:26 INFO - Successfully installed mozsystemmonitor 14:58:26 INFO - Cleaning up... 14:58:27 INFO - Return code: 0 14:58:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:58:27 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')]} 14:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:27 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')]} 14:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:27 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:27 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 14:58:27 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 14:58:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:27 INFO - 'HOME': '/Users/cltbld', 14:58:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:27 INFO - 'LOGNAME': 'cltbld', 14:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:27 INFO - 'MOZ_NO_REMOTE': '1', 14:58:27 INFO - 'NO_EM_RESTART': '1', 14:58:27 INFO - 'PAGER': '/bin/cat', 14:58:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:27 INFO - 'PWD': '/builds/slave/test', 14:58:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:27 INFO - 'SHELL': '/bin/bash', 14:58:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:27 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:27 INFO - 'USER': 'cltbld', 14:58:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:27 INFO - 'XPC_FLAGS': '0x0', 14:58:27 INFO - 'XPC_SERVICE_NAME': '0', 14:58:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:27 INFO - Downloading/unpacking jsonschema==2.5.1 14:58:27 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 14:58:27 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 14:58:27 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 14:58:27 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 14:58:27 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 14:58:27 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 14:58:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:58:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:58:29 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 14:58:29 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 14:58:29 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 14:58:29 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 14:58:29 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 14:58:29 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 14:58:30 INFO - Downloading functools32-3.2.3-2.tar.gz 14:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:58:30 INFO - warning: no files found matching '*.txt' 14:58:30 INFO - no previously-included directories found matching 'build' 14:58:30 INFO - no previously-included directories found matching 'dist' 14:58:30 INFO - no previously-included directories found matching '.git*' 14:58:30 INFO - Installing collected packages: jsonschema, functools32 14:58:30 INFO - Running setup.py install for functools32 14:58:30 INFO - warning: no files found matching '*.txt' 14:58:30 INFO - no previously-included directories found matching 'build' 14:58:30 INFO - no previously-included directories found matching 'dist' 14:58:30 INFO - no previously-included directories found matching '.git*' 14:58:30 INFO - Successfully installed jsonschema functools32 14:58:30 INFO - Cleaning up... 14:58:30 INFO - Return code: 0 14:58:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:30 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:30 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 14:58:30 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 14:58:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:30 INFO - 'HOME': '/Users/cltbld', 14:58:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:30 INFO - 'LOGNAME': 'cltbld', 14:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:30 INFO - 'MOZ_NO_REMOTE': '1', 14:58:30 INFO - 'NO_EM_RESTART': '1', 14:58:30 INFO - 'PAGER': '/bin/cat', 14:58:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:30 INFO - 'PWD': '/builds/slave/test', 14:58:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:30 INFO - 'SHELL': '/bin/bash', 14:58:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:30 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:30 INFO - 'USER': 'cltbld', 14:58:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:30 INFO - 'XPC_FLAGS': '0x0', 14:58:30 INFO - 'XPC_SERVICE_NAME': '0', 14:58:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:58:30 INFO - Cleaning up... 14:58:31 INFO - Return code: 0 14:58:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:58:31 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')]} 14:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:31 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')]} 14:58:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:31 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:31 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 14:58:31 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 14:58:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:31 INFO - 'HOME': '/Users/cltbld', 14:58:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:31 INFO - 'LOGNAME': 'cltbld', 14:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:31 INFO - 'MOZ_NO_REMOTE': '1', 14:58:31 INFO - 'NO_EM_RESTART': '1', 14:58:31 INFO - 'PAGER': '/bin/cat', 14:58:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:31 INFO - 'PWD': '/builds/slave/test', 14:58:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:31 INFO - 'SHELL': '/bin/bash', 14:58:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:31 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:31 INFO - 'USER': 'cltbld', 14:58:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:31 INFO - 'XPC_FLAGS': '0x0', 14:58:31 INFO - 'XPC_SERVICE_NAME': '0', 14:58:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:31 INFO - Downloading/unpacking blobuploader==1.2.4 14:58:31 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 14:58:31 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 14:58:31 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 14:58:31 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 14:58:31 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 14:58:31 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 14:58:33 INFO - Downloading blobuploader-1.2.4.tar.gz 14:58:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:58:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:58:33 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 14:58:33 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 14:58:33 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 14:58:33 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 14:58:33 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 14:58:33 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 14:58:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:58:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:58:34 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 14:58:34 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 14:58:34 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 14:58:34 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 14:58:34 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 14:58:34 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 14:58:35 INFO - Downloading docopt-0.6.1.tar.gz 14:58:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:58:35 INFO - Installing collected packages: blobuploader, requests, docopt 14:58:35 INFO - Running setup.py install for blobuploader 14:58:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:58:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:58:35 INFO - Running setup.py install for requests 14:58:35 INFO - Running setup.py install for docopt 14:58:35 INFO - Successfully installed blobuploader requests docopt 14:58:35 INFO - Cleaning up... 14:58:36 INFO - Return code: 0 14:58:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:36 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:36 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 14:58:36 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 14:58:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:36 INFO - 'HOME': '/Users/cltbld', 14:58:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:36 INFO - 'LOGNAME': 'cltbld', 14:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:36 INFO - 'MOZ_NO_REMOTE': '1', 14:58:36 INFO - 'NO_EM_RESTART': '1', 14:58:36 INFO - 'PAGER': '/bin/cat', 14:58:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:36 INFO - 'PWD': '/builds/slave/test', 14:58:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:36 INFO - 'SHELL': '/bin/bash', 14:58:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:36 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:36 INFO - 'USER': 'cltbld', 14:58:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:36 INFO - 'XPC_FLAGS': '0x0', 14:58:36 INFO - 'XPC_SERVICE_NAME': '0', 14:58:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:58:36 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-bVElw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:58:36 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ii3j2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:58:36 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-aqATU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:58:36 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-IPE5BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:58:36 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-JEwCDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:58:36 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-R1gWXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:58:36 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-2NHF9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-9BteuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-RrBebZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-AiEr3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-drG_gR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-h7J8yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-BlaCTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-r4Eemd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-hYq8Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:58:37 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-EAmizk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:58:38 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-PolZhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:58:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:58:38 INFO - Running setup.py install for manifestparser 14:58:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:58:38 INFO - Running setup.py install for mozcrash 14:58:38 INFO - Running setup.py install for mozdebug 14:58:38 INFO - Running setup.py install for mozdevice 14:58:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:58:38 INFO - Running setup.py install for mozfile 14:58:38 INFO - Running setup.py install for mozhttpd 14:58:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:58:38 INFO - Running setup.py install for mozinfo 14:58:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:58:39 INFO - Running setup.py install for mozInstall 14:58:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:58:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:58:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:58:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:58:39 INFO - Running setup.py install for mozleak 14:58:39 INFO - Running setup.py install for mozlog 14:58:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:58:39 INFO - Running setup.py install for moznetwork 14:58:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:58:39 INFO - Running setup.py install for mozprocess 14:58:39 INFO - Running setup.py install for mozprofile 14:58:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:58:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:58:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:58:40 INFO - Running setup.py install for mozrunner 14:58:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:58:40 INFO - Running setup.py install for mozscreenshot 14:58:40 INFO - Running setup.py install for moztest 14:58:40 INFO - Running setup.py install for mozversion 14:58:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:58:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:58:40 INFO - Cleaning up... 14:58:40 INFO - Return code: 0 14:58:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58:40 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')]} 14:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:40 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')]} 14:58:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:40 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:40 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 14:58:40 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 14:58:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:40 INFO - 'HOME': '/Users/cltbld', 14:58:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:40 INFO - 'LOGNAME': 'cltbld', 14:58:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:40 INFO - 'MOZ_NO_REMOTE': '1', 14:58:40 INFO - 'NO_EM_RESTART': '1', 14:58:40 INFO - 'PAGER': '/bin/cat', 14:58:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:40 INFO - 'PWD': '/builds/slave/test', 14:58:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:40 INFO - 'SHELL': '/bin/bash', 14:58:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:40 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:40 INFO - 'USER': 'cltbld', 14:58:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:40 INFO - 'XPC_FLAGS': '0x0', 14:58:40 INFO - 'XPC_SERVICE_NAME': '0', 14:58:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:58:40 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Hh1_ng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-UEGtAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-7h12du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-_BotJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-qZNYV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-MRbkLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-aDQvRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-8Gebg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-yZcZAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-e6AdMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:58:41 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)) 14:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:58:41 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-eXHW_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:58:42 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)) 14:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:58:42 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-qNrOmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:58:42 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)) 14:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:58:42 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-BR96Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:58:42 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)) 14:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:58:42 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-uH71ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:58:42 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)) 14:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:42 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-xacszP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:42 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)) 14:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:58:42 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-U1awOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:58:42 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)) 14:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:58:42 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-miVXjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:58:42 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)) 14:58:42 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)) 14:58:42 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)) 14:58:42 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)) 14:58:42 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)) 14:58:42 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)) 14:58:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:58:42 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 14:58:42 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 14:58:42 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 14:58:42 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 14:58:42 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 14:58:42 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 14:58:45 INFO - Downloading blessings-1.6.tar.gz 14:58:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:58:45 INFO - Installing collected packages: blessings 14:58:45 INFO - Running setup.py install for blessings 14:58:45 INFO - Successfully installed blessings 14:58:45 INFO - Cleaning up... 14:58:45 INFO - Return code: 0 14:58:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:58:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:58:46 INFO - Reading from file tmpfile_stdout 14:58:46 INFO - Current package versions: 14:58:46 INFO - blessings == 1.6 14:58:46 INFO - blobuploader == 1.2.4 14:58:46 INFO - docopt == 0.6.1 14:58:46 INFO - functools32 == 3.2.3-2 14:58:46 INFO - jsonschema == 2.5.1 14:58:46 INFO - manifestparser == 1.1 14:58:46 INFO - mozInstall == 1.12 14:58:46 INFO - mozcrash == 1.0 14:58:46 INFO - mozdebug == 0.1 14:58:46 INFO - mozdevice == 0.49 14:58:46 INFO - mozfile == 1.2 14:58:46 INFO - mozhttpd == 0.7 14:58:46 INFO - mozinfo == 0.9 14:58:46 INFO - mozleak == 0.1 14:58:46 INFO - mozlog == 3.4 14:58:46 INFO - moznetwork == 0.27 14:58:46 INFO - mozprocess == 0.25 14:58:46 INFO - mozprofile == 0.28 14:58:46 INFO - mozrunner == 6.13 14:58:46 INFO - mozscreenshot == 0.1 14:58:46 INFO - mozsystemmonitor == 0.3 14:58:46 INFO - moztest == 0.8 14:58:46 INFO - mozversion == 1.4 14:58:46 INFO - psutil == 3.1.1 14:58:46 INFO - requests == 1.2.3 14:58:46 INFO - wsgiref == 0.1.2 14:58:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58: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')]} 14:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:46 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:46 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 14:58:46 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 14:58:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:46 INFO - 'HOME': '/Users/cltbld', 14:58:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:46 INFO - 'LOGNAME': 'cltbld', 14:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:46 INFO - 'MOZ_NO_REMOTE': '1', 14:58:46 INFO - 'NO_EM_RESTART': '1', 14:58:46 INFO - 'PAGER': '/bin/cat', 14:58:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:46 INFO - 'PWD': '/builds/slave/test', 14:58:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:46 INFO - 'SHELL': '/bin/bash', 14:58:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:46 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:46 INFO - 'USER': 'cltbld', 14:58:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:46 INFO - 'XPC_FLAGS': '0x0', 14:58:46 INFO - 'XPC_SERVICE_NAME': '0', 14:58:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 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)) 14:58:46 INFO - Cleaning up... 14:58:46 INFO - Return code: 0 14:58:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:58: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')]} 14:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:46 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 0x100686b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100689768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d8468fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, '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.MB40PqHZwx/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.u9OIP3T1nU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:58:46 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 14:58:46 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 14:58:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:58:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:46 INFO - 'HOME': '/Users/cltbld', 14:58:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:46 INFO - 'LOGNAME': 'cltbld', 14:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:46 INFO - 'MOZ_NO_REMOTE': '1', 14:58:46 INFO - 'NO_EM_RESTART': '1', 14:58:46 INFO - 'PAGER': '/bin/cat', 14:58:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:46 INFO - 'PWD': '/builds/slave/test', 14:58:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:46 INFO - 'SHELL': '/bin/bash', 14:58:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:58:46 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:58:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:46 INFO - 'USER': 'cltbld', 14:58:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:46 INFO - 'XPC_FLAGS': '0x0', 14:58:46 INFO - 'XPC_SERVICE_NAME': '0', 14:58:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:58:46 INFO - Cleaning up... 14:58:46 INFO - Return code: 0 14:58:46 INFO - Running post-action listener: _resource_record_post_action 14:58:46 INFO - Running post-action listener: _start_resource_monitoring 14:58:46 INFO - Starting resource monitoring. 14:58:46 INFO - [mozharness: 2017-03-28 21:58:46.606762Z] Finished create-virtualenv step (success) 14:58:46 INFO - [mozharness: 2017-03-28 21:58:46.607736Z] Running install step. 14:58:46 INFO - Running pre-action listener: _resource_record_pre_action 14:58:46 INFO - Running main action method: install 14:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:58:46 INFO - Reading from file tmpfile_stdout 14:58:46 INFO - Detecting whether we're running mozinstall >=1.0... 14:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:58:46 INFO - Reading from file tmpfile_stdout 14:58:46 INFO - Output received: 14:58:46 INFO - Usage: mozinstall [options] installer 14:58:46 INFO - Options: 14:58:46 INFO - -h, --help show this help message and exit 14:58:46 INFO - -d DEST, --destination=DEST 14:58:46 INFO - Directory to install application into. [default: 14:58:46 INFO - "/builds/slave/test"] 14:58:46 INFO - --app=APP Application being installed. [default: firefox] 14:58:46 INFO - mkdir: /builds/slave/test/build/application 14:58:46 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'] 14:58:46 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 14:59:07 INFO - Reading from file tmpfile_stdout 14:59:07 INFO - Output received: 14:59:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:59:07 INFO - Running post-action listener: _resource_record_post_action 14:59:07 INFO - [mozharness: 2017-03-28 21:59:07.312528Z] Finished install step (success) 14:59:07 INFO - [mozharness: 2017-03-28 21:59:07.312632Z] Running run-tests step. 14:59:07 INFO - Running pre-action listener: _resource_record_pre_action 14:59:07 INFO - Running main action method: run_tests 14:59:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:59:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:59:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:59:07 INFO - 2017-03-28 14:59:07.327 screenresolution[1629:7427] starting screenresolution argv=screenresolution get 14:59:07 INFO - 2017-03-28 14:59:07.330 screenresolution[1629:7427] Display 0: 1600x1200x32@60 14:59:07 INFO - 2017-03-28 14:59:07.335 screenresolution[1630:7430] starting screenresolution argv=screenresolution list 14:59:07 INFO - Available Modes on Display 0 14:59:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:59:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:59:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:59:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:59:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:59:07 INFO - Intel Iris: 14:59:07 INFO - Chipset Model: Intel Iris 14:59:07 INFO - Type: GPU 14:59:07 INFO - Bus: Built-In 14:59:07 INFO - VRAM (Dynamic, Max): 1536 MB 14:59:07 INFO - Vendor: Intel (0x8086) 14:59:07 INFO - Device ID: 0x0a2e 14:59:07 INFO - Revision ID: 0x0009 14:59:07 INFO - Displays: 14:59:07 INFO - Display: 14:59:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:59:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:59:07 INFO - Display Serial Number: 1600x1200 60 14:59:07 INFO - Main Display: Yes 14:59:07 INFO - Mirror: Off 14:59:07 INFO - Online: Yes 14:59:07 INFO - Rotation: Supported 14:59:07 INFO - Return code: 0 14:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:59:07 INFO - Python 2.7.3 14:59:07 INFO - Return code: 0 14:59:07 INFO - grabbing minidump binary from tooltool 14:59:07 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')]} 14:59:07 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 0x100464d30>, '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 0x10038bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100459be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:59:07 INFO - Return code: 0 14:59:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:59:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:59:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:59:07 INFO - ENV: RUST_BACKTRACE is now 1 14:59:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:59:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 14:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --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 14:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MB40PqHZwx/Render', 14:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:59:07 INFO - 'HOME': '/Users/cltbld', 14:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:59:07 INFO - 'LOGNAME': 'cltbld', 14:59:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:59:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:07 INFO - 'MOZ_NO_REMOTE': '1', 14:59:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:59:07 INFO - 'NO_EM_RESTART': '1', 14:59:07 INFO - 'PAGER': '/bin/cat', 14:59:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:07 INFO - 'PWD': '/builds/slave/test', 14:59:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:59:07 INFO - 'RUST_BACKTRACE': '1', 14:59:07 INFO - 'SHELL': '/bin/bash', 14:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u9OIP3T1nU/Listeners', 14:59:07 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:59:07 INFO - 'USER': 'cltbld', 14:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:07 INFO - 'XPC_FLAGS': '0x0', 14:59:07 INFO - 'XPC_SERVICE_NAME': '0', 14:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:59:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 14:59:07 INFO - mozversion application_buildid: 20170328133121 14:59:07 INFO - mozversion application_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 14:59:07 INFO - mozversion application_display_name: Nightly 14:59:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:59:07 INFO - mozversion application_name: Firefox 14:59:07 INFO - mozversion application_remotingname: firefox 14:59:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:59:07 INFO - mozversion application_vendor: Mozilla 14:59:07 INFO - mozversion application_version: 55.0a1 14:59:07 INFO - mozversion platform_buildid: 20170328133121 14:59:07 INFO - mozversion platform_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 14:59:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:59:07 INFO - mozversion platform_version: 55.0a1 14:59:07 INFO - using testdate: 1490738347 14:59:07 INFO - actual date: 1490738347 14:59:07 INFO - starting webserver on 'localhost:49240' 14:59:07 INFO - SUITE-START | Running 2 tests 14:59:07 INFO - TEST-START | dromaeo_css 14:59:07 INFO - operating with platform_type : mac_ 14:59:07 INFO - Initialising browser for dromaeo_css test... 14:59:07 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpUVua7b/profile http://localhost:49240/getInfo.html) 14:59:10 INFO - PID 1640 | __metrics Screen width/height:1600/1200 14:59:10 INFO - PID 1640 | colorDepth:24 14:59:10 INFO - PID 1640 | Browser inner width/height: 1024/689 14:59:10 INFO - PID 1640 | __metrics 14:59:10 ERROR - PID 1640 | 1490738350622 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/Nightly.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/Nightly.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 14:59:10 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" 14:59:10 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] 14:59:11 INFO - TEST-INFO | 1640: exit 0 14:59:11 INFO - Browser initialized. 14:59:11 INFO - Running cycle 1/1 for dromaeo_css test... 14:59:11 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpUVua7b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:59:23 INFO - PID 1642 | RSS: Main: 233766912 14:59:23 INFO - PID 1642 | 15:02:30 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 15:02:31 INFO - PID 1642 | RSS: Main: 360493056 15:02:31 INFO - PID 1642 | 15:04:13 INFO - PID 1642 | ************************* 15:04:13 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 15:04:13 INFO - PID 1642 | 15:04:13 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:04:13 INFO - PID 1642 | Full stack: 15:04:13 INFO - PID 1642 | ************************* 15:04:13 INFO - PID 1642 | ************************* 15:04:13 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 15:04:13 INFO - PID 1642 | 15:04:13 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:04:13 INFO - PID 1642 | Full stack: 15:04:13 INFO - PID 1642 | ************************* 15:04:13 INFO - PID 1642 | ************************* 15:04:13 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 15:04:13 INFO - PID 1642 | 15:04:13 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:04:13 INFO - PID 1642 | Full stack: 15:04:13 INFO - PID 1642 | ************************* 15:04:13 INFO - PID 1642 | ************************* 15:04:13 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 15:04:13 INFO - PID 1642 | 15:04:13 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:04:13 INFO - PID 1642 | Full stack: 15:04:13 INFO - PID 1642 | ************************* 15:05:34 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 15:05:34 INFO - PID 1642 | RSS: Main: 365498368 15:05:34 INFO - PID 1642 | 15:08:37 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 15:08:37 INFO - PID 1642 | RSS: Main: 337321984 15:08:37 INFO - PID 1642 | 15:11:40 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 15:11:40 INFO - PID 1642 | RSS: Main: 334344192 15:11:40 INFO - PID 1642 | 15:14:44 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 15:14:45 INFO - PID 1642 | RSS: Main: 339021824 15:14:45 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 15:17:48 INFO - PID 1642 | RSS: Main: 512335872 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | __start_tp_report 15:17:48 INFO - PID 1642 | _x_x_mozilla_page_load 15:17:48 INFO - PID 1642 | _x_x_mozilla_page_load_details 15:17:48 INFO - PID 1642 | |i|pagename|runs| 15:17:48 INFO - PID 1642 | |0;dojo.html;3390.7287417006637;3472.9826350868243;3473.5310733051047;3511.508388825564;3527.6472352764727;5971.193888825007;6130.141780150782;6172.598781079238;6182.505399568035;6221.129041934121;5026.648134630577;6095.710389288371;6194.566380353377;6196.752129914798;6223.315435302113;5328.706921119337;5508.504234618884;5508.889822203553;5531.861703457405;5546.973809845172;4893.706377617336;5581.6371935824245;5592.245046918675;5642.04085305499;5689.743961521742;5401.810936617219;5430.619856610039;5452.536534394541;5471.644343117685;5483.259759932438;5461.399246082928;5484.890302193934;5495.2856128703415;5499.972500137514;5561.4716601923155;5497.780088796443;5499.257600223958;5555.2778138842095;5586.944130558723;5606.383297837235;6161;6195.2255968004;6199.225096862892;6205.658688772116;6215.502759779207;6289.654069026202;6489.837754056186;6570.776550839489;6585.769498067592;6598.010298455222;5616.382197958222;6367.458766004896;6400.0719895614875;6653.966730166367;6663.800085997379;6179;6275.96862015687;6280.842978925518;6281.340459251713;6306.779262725874;6825.624590647462;6847.9999999999;6850.143732033496;6866.34769696878;6870.656467176621;6270.49836013127;6320.778772743024;6331.68341582919;6332.271788744261;6464.482841372679;6391.776287829904;6415;6552.115464412243;6623.801285961429;6661.833454163685;6495.642739649365;6511.967440162769;6541.934580654132;6554.4101030906495;6559.8360040998405;6470.1588793457495;6555;6559.540832141704;6563.113979612786;6565.934340656628;5168.457311982263;5222.791088356499;5232.947670523322;5293.497117773805;5303.761330740126;4639.536046395334;4641.744704041243;4677.976610116928;4680.765961701902;4724.00795832879;6231.376862313823;6243.43809057187;6266.122742816009;6287.056941458726;6303.968480157661;6676.432503237182;6683.064370988066;6685.565438246498;6686.431653309426;6691.464682825363;6131.6321020738005;6272.3100458949475;6282.465990390868;6288.339724328879;6289.150964619717;5732.254806875163;5744.942550574524;5812.941870581325;5874.853128671817;5894.2926848778425;6837.1111755471475;6892.655367231619;6911.412529935012;6912.308769123048;6918.654067296716;6649.168853893263;6702.765403210864;6702.83242918931;6734.797956061326;6735.932640673531;6559.7376104955265;6691.364320389555;6693.09643198162;6732.730690772314;6883.827904302432;6497.675116244263;6525.086487891604;6547.181602299713;6552.6068435894;6552.934470655233;6447.097406363019;6590.90113648286;6609.20689517261;6662.866742665023;6667.833304167435;6261.24865016201;6300.180976473029;6355.459785918249;6426.132472116205;6445.387688169616;5879.470847623735;6395.6482393468805;6406.295307516077;6419.005054216604;6441.613503189824;6156.969215153896;6261.655608941552;6271.404216599416;6293.811185664437;6318.842028949313;6277.309495955533;6379.7129129190225;6407.551471396958;6428.4214420702365;6438.3883531064475;6289.811305660837;6290.874182516232;6302.1492098566105;6314.116023756769;6317.873642527215 15:17:48 INFO - PID 1642 | |1;ext.html;762.4967521435854;790.9248621380968;812.1553584272357;1131.513449216837;1160.1125139268465;27534.449311013766;36499.63500364996;37040.888773336774;37158.81420592893;37443.438348424796;38173.236535269345;38484;38546.22907541847;38654.420183697264;38667;6167.814965551041;8430.620622072007;8605.95697021514;8617.138286171365;8622.956885215597;6192.504599632019;8364.57802173629;8554.144585541428;8583.742487725347;8588.570571471402;8568.186022327869;8623.870641940344;8627.870581941275;8643.827123457528;8653.610587523544;8552;8609.096044915319;8624.913750862537;8640.65437382504;8641.481511109321;8354.624041918129;8440.493570385735;8498.107698691672;8518.105598912149;8522.190391912758;38223;38397.77624461335;38487;38520.03699907497;38543;3069.6009518762417;3076.8153910765427;3081.260497480611;3090.335577850773;3117.703818137273;27135.592966105523;27669.307818807036;28135;28385;28627;2821.083147976916;2833.835116900657;2849.102532702213;2849.415869746697;2943.882244710209;319087;319844;320040;320727;321687;40801.387979180334;49774;49846;49935;50098.74950625296;52865.47134528682;52880.20679689808;52902.470975289754;52908.73545632247;53029.204561931605;14404.423823047171;14610.342534585969;14625.634359141106;14650.974431789886;14652.340644671016;8526.573671316533;10101.44442055694;11898.702532436759;11908.642740717793;11931.88068119325;11947.163698541193;12173.965212956999;12179.269243845398;12191;12192.451339689735;4841.744407120248;5111.3355263815465;5154.30416893722;5158.819441319536;5159.716215608178;916.7387294621001;964.5900492290748;978.7895602445366;986.2307400227834;1006.1598565197995;22149.557008859734;23585.528289434114;24191.274261772174;24226.63660045101;24262.272131836075;1058.2909450667917;1060.7772367802847;1101.0750969185922;1101.6199411203122;1111.0944580167138;10628.410385561001;11674.474648640831;12187.878121218851;12311.815322770166;12334;410550;411853;412748.9362553228;412855;416295;43959.340609890874;54804.90390192254;54812.725936370065;54839.45160548343;54840;1457.6501639606631;1475.2771142140275;1503.827059888105;1509.4113295814423;1515.3256800723793;17847.821521784615;21026.2640807571;23550.528989419774;23709.762902370756;23776.286711398687;38224.23551528898;38231.2353752929;38298.80850595729;38345.808270959584;38437.84686459411;414170;420538.2542483923;420817;421221;421416;30963.535546967265;33456.1635959103;33474.33051339008;33484.665153348156;33518.66481335253;31783.442337201817;32064.83967580147;32610;44174.11651767011;44355.77822110869;26427.60358594661;27050.188494345202;27464.450710986068;27545;28795.712042879302;29132.27169320784;29303.85348073246;30002.24994375158;30290.69709302967;30353.93761218347;34184.316313674084;35344;35936.82031589825;36355.45466817947;36366.45450318194;29945.401091978474;30136.84931575328;30693;30713.09122675652;30820;22147.345504923316;23650;23847.64228536608;24001;24132.39669008289 15:17:48 INFO - PID 1642 | |2;jquery.html;379.31344266876937;380.5243445692884;394.483226972666;413.78069623099765;431.3831221353465;11831.881681183186;17245.396411125614;21019.579608407843;22045.448863778412;22346.21788237412;18076;18336.816631833677;20569;22224.999375140585;22719.886400568015;6073.757049718006;6281.591696539733;6282.717277722513;6290.433860952513;6293.4335909768115;5053.620978426633;6259.217597800275;6343.8414039648915;6345.238571371442;6365.58623689461;6137.32489426165;6272;6349.71426285816;6351.237851457809;6370.394812492806;5893.233879595647;6300;6350.682465876687;6354.3010268870385;6356.237251529799;5497.670139791625;5612.943870561283;5667.3482549506925;5686.9763442580315;5689.175069614883;22263.666045009337;27612.309692257655;28036.579451308247;28178.71821281802;28352.14943551676;2006.2376297007045;2021.8079282468143;2026.7061276115028;2034.3591768592846;2047.938561843147;15884.36462541497;16105.758413623806;17191.656166876593;17203.91398043014;17913.641727165385;1689.4002629066626;1694.3900195929455;1702.2595171100488;1724.8188940161353;1736.97394539082;147088;155417.22291388584;158003;158310;159891;9370.95314523423;11981.700457488458;12008.639740807615;12542.498300067895;12823.422945967453;14286.28568571588;14698.412063517553;14709.55871323862;14709.632259193606;14741.410343586136;6587.769428069994;7022.1924478685605;8871.379003469696;8955.955220223857;8968;8206.507609543447;8403;8412.200840920103;8576.656933722707;8596.8710469343;7566.31903128721;7667.386609071373;7714.267144621252;7747.806304842311;7755.728549500854;2166.696662467256;2167.133146741316;2168.620491413996;2172.989135054346;2398.662974880911;874.5889431966965;875.6672464463463;878.6836738774036;879.1867522541623;909.6361455417886;19878.006099694958;20686;21035.368938931857;23544.764552354256;25632;929.3123088914155;944.367273926471;949.2074118111316;976.4336684722844;981.906718861707;8325.209105135124;10285.537150828231;10448.895511044791;10576.947115264375;10578.682639520826;600202;721579;730903;734290;741231;102290.48854755679;130028;139226.30386848;140194;148568;1100.9889901100887;1202.7113492761848;1204.6626944455566;1239.5909349914507;1241.6709571963436;17229.052402118003;17722.73415898737;17734.645307094044;17826.643467130325;17871.28514859391;58128.41871581399;58159.14306816118;59383.70308148432;59482.70258648679;59740.70129649324;67720;80967.19032809832;81201;82971.58514207632;86382.70425943742;8419.494830310201;8512.233898949124;8717.346199034972;8785.550384186537;8820.558972051243;13488.05583609138;13640.931795340959;13700.109492882932;14199.716005679622;14239.572812815632;11482.138839586898;11607.227651013623;11785.528578856749;11959.282443053444;11975;10896;10911.727206819893;11254.662360129209;11273.267237629529;11376.886231137583;11534.423278836193;11747;12050.638480845588;12051.397430128283;12145.392730363623;8277.95861020691;8395.370347224105;8494.660213591635;8836.248918841713;8985.685501007434;11247.325160490396;11446.141539384413;11731;11780.882191178322;11875 15:17:48 INFO - PID 1642 | |3;mootools.html;1342.6576223063119;1418.0711633879805;1435.4688765156886;1437.2957250947036;1437.647776294808;10478.423686697228;10902.291351062175;10916.99999999999;10920.617778377758;10936.125109991202;10689.465526723672;10727.83908241377;11070.612528561502;11071.612493562727;11095.389753563572;2447.3602686200243;2480.342709182068;2487.129504673368;2491;2493.7132229793533;2091.6339640562915;2224.73303203615;2418.3107814272935;2418.9516209675885;2419.129113519132;2064.1846470644164;2140.218820130649;2140.689600007998;2378.6194208926577;2461.4461746107163;2237.194609940412;2248.4266512039326;2424.5878200705833;2454.7603460252635;2457.6436416719475;2423.151896836111;2423.285130886393;2445.6698345723275;2467.580511313063;2467.8519288842726;10820.945895270552;10955.83566246507;11096.944515277453;11098.944505277503;11106.777864442747;6239.688015599202;6442.710078046499;6459.5478316517865;6462.806115816533;6465.288818229991;3827.138893748912;3899.337112690849;4004.6996475264473;4134.937975930364;4146.606072423115;1198.6763573835017;1224.1736827641396;1519.7720341948686;1588.5393235961558;1642.5072478256475;14993;15175.696486070327;15203.543893683096;15225.771613425808;15225.999999999889;4353.869383918487;5211.765470553834;5390.164524498709;5426.050441172779;5441.483059109379;11564.768704626029;11566.826497602542;11569.421528923689;11569.88430115705;11581.247218930743;6313.242410910629;6330.145430366933;6336.144620476177;6346.682665866688;6349.492040636738;5759.712014399263;5965.13505541694;5972.671503067373;5992.790252341147;6040.637561746309;4049.3521036633997;4222.5988531090115;4235.470566179228;4285.892852678646;4355.912881742347;1261.4701825233424;1268.4926179114755;1279.7824369857146;1280.3726174174587;1282.6152154353842;588.4644973074567;624.9031400132986;737.4800765460337;781.538892053691;793.6785601831267;5185.559227465632;5567.4432556744005;5719.28508936383;5826.475617194473;6105.870413973429;736.5286216821239;843.3169133002287;886.0917559501434;894.190757364586;902.7968707040994;3708.517892673935;4444.422225110779;4444.911101777946;4581.404417425713;4620.7227566345455;13417.463301467831;13744.725105497833;13764.793528097087;13907.304634768221;13960.511382101578;13369;13528.93235533816;13885.166889986633;13901.860981390259;13931.930340348234;7689.038870141232;10294.279400441897;10423.739406514745;10447.634332798467;10450.216233782345;6273.749050037947;6486.351364863476;6604.504662150454;6778.559393639398;6790.388865002174;12474.43865026058;12501.687457813627;12608.432620532281;12629.11596188258;12668.619941401772;13139.277339746406;13140.934295328845;13207.207567545978;13370.06409551322;13441.395137218653;7262.491625586158;9306.72079837606;9307.673081729568;9308.022657620852;9315.068493150631;10036.598536058476;10222.335548189343;10351.482425878827;10351.63769268072;10386.948065259625;8255.504669719836;8507.702230421906;8654.956725216334;8780.253678437404;8905.599248033725;8421;8452.408331416987;8540.44487108336;8596.570171491525;8726.345524085593;7508.511946723445;7649.655765490677;7764.107127680389;7776.7278145264645;7783.455158138876;5787.508061814625;5818.878830464012;5930.9110363343725;5953.732082056405;6038.6678732670125;4535.3650488932235;5855.795047173328;5976.491998180203;6033.18551995475;6058.000429929012 15:17:48 INFO - PID 1642 | |4;prototype.html;384.8306745032186;386.9322868498012;392.46232661254066;393.2076865116792;396.94641223434826;4644.976775116128;5763.942360576393;5788.5658575606785;5861.648301101932;5971.820845374635;5702.971485142569;5819.912701309484;5830.621009634371;5831.446012628793;5864.824055278348;4399.51605323415;4445.599896009358;4514.774261286939;4641.976790116045;4697.553732395434;4595.151006376052;4644.86065418038;4657.627389808824;4664.673472856902;4681.361523466793;3750;3807.9809600952094;4626.8380606678775;4649.86050418488;4663.8834029149375;4503.48209955856;4600.539946005406;4637.721736695809;4658.464276608182;4670.462896766848;4068.227036863016;4080.6327430531396;4086.9182616347803;4089.568650972136;4090.3455447128317;4180.958190418088;4705.200115980291;5389.649672771257;5789.392113828072;5795.609053827094;4294.9502178422845;5043.495650434964;5271.367435907716;5380.24676545284;5457.945420545783;5790.7683692652245;6317.4314311711705;6340.178620513873;6346.9682651586445;6358.42774150324;3990.7805070721097;4568.428946381702;4597.4712908015335;4603.332516785081;4613.331066995267;6422.8216634660685;6708.429783468411;6771.476417806053;6786.626735529593;6786.864262714718;4148.8133034013545;4443.311286750559;4680.3915490985955;4696.647751418657;4703.918098837286;4541.545845415432;4629.4676112247525;4778.283257511401;4786.712797232177;4810.5670489655395;5325.680459172462;5413.2150838128255;5415.512603865593;5416.025115479173;5422.75597598109;5532.446755324436;5895.705214739246;6040.976272948524;6054.880791722861;6410;1498.7077519883735;1531.5635044012404;1622.2618708487846;1637.525117715873;1650.0512205481896;1115.1691989467736;1136.6192325570862;1137.7155711072232;1143.3882872663085;1145.9713507162387;5887.8233652990475;5954.255718035246;5983.910241346383;5994.250718660168;5997.730102145327;5317.41508434072;5969.701514924236;6429.48907006853;6440.005397490096;6547.476201903836;5676.205331253628;5893.911591326134;5895.263092113486;5940.821775346746;5978.790742324085;3079.615048118985;3147.945438278157;3391.270876761508;3424.503447000171;3426.2633533790354;5867.970660146757;6246.781362652286;6648.5678430901835;6719.7488841237755;6740.831479213059;6352.872942541123;6663.733450662016;6678.432333251631;6748.325167483311;6766.729330826712;4818.616156477819;6623.973284141062;6637.269900310962;6671.4967397007795;6701.798946031627;6443.2590252119735;6451.290358060516;6476.838079048061;6568.573042752206;6574.8356291093105;5856.619319744203;6464.773732919513;6474.838129046623;6491.967540162269;6510.707018184288;5174.586033117417;6005.399460054135;6278.937210628018;6644.3389152711825;6703.944113811269;3818.5417749870194;4220.691585608349;4279.758869927686;4285.528591854956;4311.461067366579;5753.280839895013;6239.469645080219;6506.3493650634555;6535.411812936668;6561.278259391559;6287.088372186099;6362.073446214608;6367.777127800505;6373;6384.585001974857;6057.000594901792;6160.229971253593;6353.841153971188;6362;6401.935980640134;5144.6913185209005;6041.969790151021;6188.9690551546955;6227.377262273736;6279.304587761188;5745.109508026263;5954.910676339945;6387.776427825004;6418.358164183544;6426.839329016812;5930.258717660293;6137.324894261538;6213.285472170577;6223.595466294676;6226.937730622636 15:17:48 INFO - PID 1642 | |5;yui.html;715.181117620325;722.7434260837406;725.2623131111969;729.3618084176344;757.7044952468535;12705.491780328788;15912.602184945383;16148.354065837353;16184.514464566068;16184.676306473864;14424.639384015378;15255.466058687949;15463.30415131319;15542.300596473158;15550.300236489356;984.1044649369076;1009.0010889219657;1012.559536601577;1014.8477728340771;1015.0407864567986;987.2644879564734;1022.0545994954674;1022.0903395977585;1025.261811495722;1041;1070.4968664727567;1072.142286171064;1072.3565860483686;1074.2641290715846;1075.1989767623131;1053.1522124689623;1055.319319039223;1060.8992145746104;1062.5484169228048;1063.4416931111152;792.7502836606451;793.2266040610416;794.031281836159;794.4637369775379;800.0759123212698;16157;16438.013719176888;16591.91704041472;16811.74782378253;17050.573735656708;14661;14687;14735.484258051023;14743.926280368636;14777.704445911022;11807.58673446434;11814.586489472827;12137;12315.69210769729;12382.380880955916;2965.1993961630455;3043.756499480058;3058.740007099399;3066.432709948667;3091.5053591425262;111770;117009;117367;117552.41223793826;118591.40704296509;8661.480311181349;11374.374409407397;11519.942400287944;11565.595204167814;11938.88061119395;25420.110296139916;25563.36091597688;25586.104486342887;25842;25980.74019259821;7794.025746781653;7891.408144389194;7897.328727058264;7912.0901096374655;7980.481268717631;4165.396017577495;4199.643030342394;4223.366495025709;4242.406063151161;4251.914961700811;3699.5560532736245;3985.844105209515;4074.0018695419813;4174.102567947906;4403.427554417905;844.2528362399198;852.808118173406;859.5315553023618;860.8908174971345;862.943908645936;485.461138136668;557.768526061688;558.4276116980128;568.9516391106803;574.2276637921988;10011.849822252672;10448.634297799541;10527.684169474775;10571.101456376295;10699.73250668724;541.258475888036;542.7503348459674;577.2092233639856;594.2898236607213;604.1753007145215;4715.858524244278;4827.831025914076;4835.782389792468;4844.370231869905;4844.515548445127;97544.51227743957;104967;105447.47276263723;106258;107585;88510.55744721236;95574.04425955791;97102;97195;97262.51368743253;80749.59625201837;82131;83881;85610.71583926122;85674.57162714271;9215.848018997625;9707.805843883225;9744.17174540172;9755.80488390214;9943.801123977624;38385.616143839325;38399.04002399962;39534.40698389469;39748.60251397449;40376.1924761509;49883.50116498789;50309.49690503048;50713.23930140977;51145.7442712784;51613;10344.793104138025;11380.349849271975;11852.466639001437;11937.701557461132;12195.207311524722;12656.810147847973;12694.936525317315;12810.359482025675;12974.416151273404;13013.739725205254;11803.468843902217;11835.733227589215;11974.22167559106;12015;12118.878811211775;11743.657602204305;11913.702157445787;11914.22000537592;11976.46105925218;12454.190477618926;11709.473073711873;11833.112516561465;11962.46168922418;12044.51821927113;12092;8448.493090414595;8692.174243446863;8783;8823.69117080899;9166.770830729285;6479.218214990915;9741.318107732392;9957.502124893872;9968;9969.30214884951 15:17:48 INFO - PID 1642 | __end_tp_report 15:17:48 INFO - PID 1642 | __start_cc_report 15:17:48 INFO - PID 1642 | _x_x_mozilla_cycle_collect,1395 15:17:48 INFO - PID 1642 | __end_cc_report 15:17:48 INFO - PID 1642 | __startTimestamp1490739468438__endTimestamp 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | ------- Summary: start ------- 15:17:48 INFO - PID 1642 | Number of tests: 6 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6094.53 Median:6289.81 stddev:700.97 (11.1%) stddev-sans-first:670.26 15:17:48 INFO - PID 1642 | Values: 3390.7 3473.0 3473.5 3511.5 3527.6 5971.2 6130.1 6172.6 6182.5 6221.1 5026.6 6095.7 6194.6 6196.8 6223.3 5328.7 5508.5 5508.9 5531.9 5547.0 4893.7 5581.6 5592.2 5642.0 5689.7 5401.8 5430.6 5452.5 5471.6 5483.3 5461.4 5484.9 5495.3 5500.0 5561.5 5497.8 5499.3 5555.3 5586.9 5606.4 6161.0 6195.2 6199.2 6205.7 6215.5 6289.7 6489.8 6570.8 6585.8 6598.0 5616.4 6367.5 6400.1 6654.0 6663.8 6179.0 6276.0 6280.8 6281.3 6306.8 6825.6 6848.0 6850.1 6866.3 6870.7 6270.5 6320.8 6331.7 6332.3 6464.5 6391.8 6415.0 6552.1 6623.8 6661.8 6495.6 6512.0 6541.9 6554.4 6559.8 6470.2 6555.0 6559.5 6563.1 6565.9 5168.5 5222.8 5232.9 5293.5 5303.8 4639.5 4641.7 4678.0 4680.8 4724.0 6231.4 6243.4 6266.1 6287.1 6304.0 6676.4 6683.1 6685.6 6686.4 6691.5 6131.6 6272.3 6282.5 6288.3 6289.2 5732.3 5744.9 5812.9 5874.9 5894.3 6837.1 6892.7 6911.4 6912.3 6918.7 6649.2 6702.8 6702.8 6734.8 6735.9 6559.7 6691.4 6693.1 6732.7 6883.8 6497.7 6525.1 6547.2 6552.6 6552.9 6447.1 6590.9 6609.2 6662.9 6667.8 6261.2 6300.2 6355.5 6426.1 6445.4 5879.5 6395.6 6406.3 6419.0 6441.6 6157.0 6261.7 6271.4 6293.8 6318.8 6277.3 6379.7 6407.6 6428.4 6438.4 6289.8 6290.9 6302.1 6314.1 6317.9 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:51504.46 Median:33851.49 stddev:102616.17 (303.1%) stddev-sans-first:102833.32 15:17:48 INFO - PID 1642 | Values: 762.5 790.9 812.2 1131.5 1160.1 27534.4 36499.6 37040.9 37158.8 37443.4 38173.2 38484.0 38546.2 38654.4 38667.0 6167.8 8430.6 8606.0 8617.1 8623.0 6192.5 8364.6 8554.1 8583.7 8588.6 8568.2 8623.9 8627.9 8643.8 8653.6 8552.0 8609.1 8624.9 8640.7 8641.5 8354.6 8440.5 8498.1 8518.1 8522.2 38223.0 38397.8 38487.0 38520.0 38543.0 3069.6 3076.8 3081.3 3090.3 3117.7 27135.6 27669.3 28135.0 28385.0 28627.0 2821.1 2833.8 2849.1 2849.4 2943.9 319087.0 319844.0 320040.0 320727.0 321687.0 40801.4 49774.0 49846.0 49935.0 50098.7 52865.5 52880.2 52902.5 52908.7 53029.2 14404.4 14610.3 14625.6 14651.0 14652.3 8526.6 10101.4 11898.7 11908.6 11931.9 11947.2 12174.0 12179.3 12191.0 12192.5 4841.7 5111.3 5154.3 5158.8 5159.7 916.7 964.6 978.8 986.2 1006.2 22149.6 23585.5 24191.3 24226.6 24262.3 1058.3 1060.8 1101.1 1101.6 1111.1 10628.4 11674.5 12187.9 12311.8 12334.0 410550.0 411853.0 412748.9 412855.0 416295.0 43959.3 54804.9 54812.7 54839.5 54840.0 1457.7 1475.3 1503.8 1509.4 1515.3 17847.8 21026.3 23550.5 23709.8 23776.3 38224.2 38231.2 38298.8 38345.8 38437.8 414170.0 420538.3 420817.0 421221.0 421416.0 30963.5 33456.2 33474.3 33484.7 33518.7 31783.4 32064.8 32610.0 44174.1 44355.8 26427.6 27050.2 27464.5 27545.0 28795.7 29132.3 29303.9 30002.2 30290.7 30353.9 34184.3 35344.0 35936.8 36355.5 36366.5 29945.4 30136.8 30693.0 30713.1 30820.0 22147.3 23650.0 23847.6 24001.0 24132.4 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:39810.48 Median:23132.33 stddev:118169.03 (510.8%) stddev-sans-first:118463.43 15:17:48 INFO - PID 1642 | Values: 379.3 380.5 394.5 413.8 431.4 11831.9 17245.4 21019.6 22045.4 22346.2 18076.0 18336.8 20569.0 22225.0 22719.9 6073.8 6281.6 6282.7 6290.4 6293.4 5053.6 6259.2 6343.8 6345.2 6365.6 6137.3 6272.0 6349.7 6351.2 6370.4 5893.2 6300.0 6350.7 6354.3 6356.2 5497.7 5612.9 5667.3 5687.0 5689.2 22263.7 27612.3 28036.6 28178.7 28352.1 2006.2 2021.8 2026.7 2034.4 2047.9 15884.4 16105.8 17191.7 17203.9 17913.6 1689.4 1694.4 1702.3 1724.8 1737.0 147088.0 155417.2 158003.0 158310.0 159891.0 9371.0 11981.7 12008.6 12542.5 12823.4 14286.3 14698.4 14709.6 14709.6 14741.4 6587.8 7022.2 8871.4 8956.0 8968.0 8206.5 8403.0 8412.2 8576.7 8596.9 7566.3 7667.4 7714.3 7747.8 7755.7 2166.7 2167.1 2168.6 2173.0 2398.7 874.6 875.7 878.7 879.2 909.6 19878.0 20686.0 21035.4 23544.8 25632.0 929.3 944.4 949.2 976.4 981.9 8325.2 10285.5 10448.9 10576.9 10578.7 600202.0 721579.0 730903.0 734290.0 741231.0 102290.5 130028.0 139226.3 140194.0 148568.0 1101.0 1202.7 1204.7 1239.6 1241.7 17229.1 17722.7 17734.6 17826.6 17871.3 58128.4 58159.1 59383.7 59482.7 59740.7 67720.0 80967.2 81201.0 82971.6 86382.7 8419.5 8512.2 8717.3 8785.6 8820.6 13488.1 13640.9 13700.1 14199.7 14239.6 11482.1 11607.2 11785.5 11959.3 11975.0 10896.0 10911.7 11254.7 11273.3 11376.9 11534.4 11747.0 12050.6 12051.4 12145.4 8278.0 8395.4 8494.7 8836.2 8985.7 11247.3 11446.1 11731.0 11780.9 11875.0 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:6793.54 Median:2483.74 stddev:4221.05 (169.9%) stddev-sans-first:4213.02 15:17:48 INFO - PID 1642 | Values: 1342.7 1418.1 1435.5 1437.3 1437.6 10478.4 10902.3 10917.0 10920.6 10936.1 10689.5 10727.8 11070.6 11071.6 11095.4 2447.4 2480.3 2487.1 2491.0 2493.7 2091.6 2224.7 2418.3 2419.0 2419.1 2064.2 2140.2 2140.7 2378.6 2461.4 2237.2 2248.4 2424.6 2454.8 2457.6 2423.2 2423.3 2445.7 2467.6 2467.9 10820.9 10955.8 11096.9 11098.9 11106.8 6239.7 6442.7 6459.5 6462.8 6465.3 3827.1 3899.3 4004.7 4134.9 4146.6 1198.7 1224.2 1519.8 1588.5 1642.5 14993.0 15175.7 15203.5 15225.8 15226.0 4353.9 5211.8 5390.2 5426.1 5441.5 11564.8 11566.8 11569.4 11569.9 11581.2 6313.2 6330.1 6336.1 6346.7 6349.5 5759.7 5965.1 5972.7 5992.8 6040.6 4049.4 4222.6 4235.5 4285.9 4355.9 1261.5 1268.5 1279.8 1280.4 1282.6 588.5 624.9 737.5 781.5 793.7 5185.6 5567.4 5719.3 5826.5 6105.9 736.5 843.3 886.1 894.2 902.8 3708.5 4444.4 4444.9 4581.4 4620.7 13417.5 13744.7 13764.8 13907.3 13960.5 13369.0 13528.9 13885.2 13901.9 13931.9 7689.0 10294.3 10423.7 10447.6 10450.2 6273.7 6486.4 6604.5 6778.6 6790.4 12474.4 12501.7 12608.4 12629.1 12668.6 13139.3 13140.9 13207.2 13370.1 13441.4 7262.5 9306.7 9307.7 9308.0 9315.1 10036.6 10222.3 10351.5 10351.6 10386.9 8255.5 8507.7 8655.0 8780.3 8905.6 8421.0 8452.4 8540.4 8596.6 8726.3 7508.5 7649.7 7764.1 7776.7 7783.5 5787.5 5818.9 5930.9 5953.7 6038.7 4535.4 5855.8 5976.5 6033.2 6058.0 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5169.97 Median:5790.08 stddev:1550.81 (26.8%) stddev-sans-first:1513.00 15:17:48 INFO - PID 1642 | Values: 384.8 386.9 392.5 393.2 396.9 4645.0 5763.9 5788.6 5861.6 5971.8 5703.0 5819.9 5830.6 5831.4 5864.8 4399.5 4445.6 4514.8 4642.0 4697.6 4595.2 4644.9 4657.6 4664.7 4681.4 3750.0 3808.0 4626.8 4649.9 4663.9 4503.5 4600.5 4637.7 4658.5 4670.5 4068.2 4080.6 4086.9 4089.6 4090.3 4181.0 4705.2 5389.6 5789.4 5795.6 4295.0 5043.5 5271.4 5380.2 5457.9 5790.8 6317.4 6340.2 6347.0 6358.4 3990.8 4568.4 4597.5 4603.3 4613.3 6422.8 6708.4 6771.5 6786.6 6786.9 4148.8 4443.3 4680.4 4696.6 4703.9 4541.5 4629.5 4778.3 4786.7 4810.6 5325.7 5413.2 5415.5 5416.0 5422.8 5532.4 5895.7 6041.0 6054.9 6410.0 1498.7 1531.6 1622.3 1637.5 1650.1 1115.2 1136.6 1137.7 1143.4 1146.0 5887.8 5954.3 5983.9 5994.3 5997.7 5317.4 5969.7 6429.5 6440.0 6547.5 5676.2 5893.9 5895.3 5940.8 5978.8 3079.6 3147.9 3391.3 3424.5 3426.3 5868.0 6246.8 6648.6 6719.7 6740.8 6352.9 6663.7 6678.4 6748.3 6766.7 4818.6 6624.0 6637.3 6671.5 6701.8 6443.3 6451.3 6476.8 6568.6 6574.8 5856.6 6464.8 6474.8 6492.0 6510.7 5174.6 6005.4 6278.9 6644.3 6703.9 3818.5 4220.7 4279.8 4285.5 4311.5 5753.3 6239.5 6506.3 6535.4 6561.3 6287.1 6362.1 6367.8 6373.0 6384.6 6057.0 6160.2 6353.8 6362.0 6401.9 5144.7 6042.0 6189.0 6227.4 6279.3 5745.1 5954.9 6387.8 6418.4 6426.8 5930.3 6137.3 6213.3 6223.6 6226.9 15:17:48 INFO - PID 1642 | 15:17:48 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:20290.15 Median:14472.97 stddev:30337.27 (209.6%) stddev-sans-first:30386.77 15:17:48 INFO - PID 1642 | Values: 715.2 722.7 725.3 729.4 757.7 12705.5 15912.6 16148.4 16184.5 16184.7 14424.6 15255.5 15463.3 15542.3 15550.3 984.1 1009.0 1012.6 1014.8 1015.0 987.3 1022.1 1022.1 1025.3 1041.0 1070.5 1072.1 1072.4 1074.3 1075.2 1053.2 1055.3 1060.9 1062.5 1063.4 792.8 793.2 794.0 794.5 800.1 16157.0 16438.0 16591.9 16811.7 17050.6 14661.0 14687.0 14735.5 14743.9 14777.7 11807.6 11814.6 12137.0 12315.7 12382.4 2965.2 3043.8 3058.7 3066.4 3091.5 111770.0 117009.0 117367.0 117552.4 118591.4 8661.5 11374.4 11519.9 11565.6 11938.9 25420.1 25563.4 25586.1 25842.0 25980.7 7794.0 7891.4 7897.3 7912.1 7980.5 4165.4 4199.6 4223.4 4242.4 4251.9 3699.6 3985.8 4074.0 4174.1 4403.4 844.3 852.8 859.5 860.9 862.9 485.5 557.8 558.4 569.0 574.2 10011.8 10448.6 10527.7 10571.1 10699.7 541.3 542.8 577.2 594.3 604.2 4715.9 4827.8 4835.8 4844.4 4844.5 97544.5 104967.0 105447.5 106258.0 107585.0 88510.6 95574.0 97102.0 97195.0 97262.5 80749.6 82131.0 83881.0 85610.7 85674.6 9215.8 9707.8 9744.2 9755.8 9943.8 38385.6 38399.0 39534.4 39748.6 40376.2 49883.5 50309.5 50713.2 51145.7 51613.0 10344.8 11380.3 11852.5 11937.7 12195.2 12656.8 12694.9 12810.4 12974.4 13013.7 11803.5 11835.7 11974.2 12015.0 12118.9 11743.7 11913.7 11914.2 11976.5 12454.2 11709.5 11833.1 11962.5 12044.5 12092.0 8448.5 8692.2 8783.0 8823.7 9166.8 6479.2 9741.3 9957.5 9968.0 9969.3 15:17:48 INFO - PID 1642 | -------- Summary: end -------- 15:17:48 INFO - PID 1642 | 15:17:49 INFO - TEST-INFO | 1642: exit 0 15:17:49 INFO - TEST-OK | dromaeo_css | took 1121338ms 15:17:49 INFO - TEST-START | kraken 15:17:49 INFO - operating with platform_type : mac_ 15:17:49 INFO - Initialising browser for kraken test... 15:17:49 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpOA_q5c/profile http://localhost:49240/getInfo.html) 15:17:51 INFO - PID 1651 | __metrics Screen width/height:1600/1200 15:17:51 INFO - PID 1651 | colorDepth:24 15:17:51 INFO - PID 1651 | Browser inner width/height: 1024/689 15:17:51 INFO - PID 1651 | __metrics 15:17:51 ERROR - PID 1651 | 1490739471790 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/Nightly.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/Nightly.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 15:17:51 INFO - PID 1651 | 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" 15:17:51 INFO - PID 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:17:52 INFO - TEST-INFO | 1651: exit 0 15:17:52 INFO - Browser initialized. 15:17:52 INFO - Running cycle 1/1 for kraken test... 15:17:52 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpOA_q5c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:18:04 INFO - PID 1652 | RSS: Main: 234213376 15:18:04 INFO - PID 1652 | 15:18:07 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 15:18:08 INFO - PID 1652 | RSS: Main: 286896128 15:18:08 INFO - PID 1652 | 15:18:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 15:18:12 INFO - PID 1652 | RSS: Main: 287924224 15:18:12 INFO - PID 1652 | 15:18:16 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 15:18:16 INFO - PID 1652 | RSS: Main: 380764160 15:18:16 INFO - PID 1652 | 15:18:18 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 15:18:19 INFO - PID 1652 | RSS: Main: 285196288 15:18:19 INFO - PID 1652 | 15:18:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:18:22 INFO - PID 1652 | RSS: Main: 287399936 15:18:22 INFO - PID 1652 | 15:18:25 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 15:18:26 INFO - PID 1652 | RSS: Main: 329428992 15:18:26 INFO - PID 1652 | 15:18:29 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 15:18:29 INFO - PID 1652 | RSS: Main: 335974400 15:18:29 INFO - PID 1652 | 15:18:33 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 15:18:33 INFO - PID 1652 | RSS: Main: 341803008 15:18:33 INFO - PID 1652 | 15:18:35 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:18:36 INFO - PID 1652 | RSS: Main: 305770496 15:18:36 INFO - PID 1652 | 15:18:38 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:18:38 INFO - PID 1652 | RSS: Main: 309256192 15:18:38 INFO - PID 1652 | 15:18:41 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:18:41 INFO - PID 1652 | RSS: Main: 310837248 15:18:41 INFO - PID 1652 | 15:18:44 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:18:45 INFO - PID 1652 | RSS: Main: 328622080 15:18:45 INFO - PID 1652 | 15:18:48 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:18:49 INFO - PID 1652 | RSS: Main: 311209984 15:18:49 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 15:18:51 INFO - PID 1652 | RSS: Main: 377667584 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | __start_tp_report 15:18:51 INFO - PID 1652 | _x_x_mozilla_page_load 15:18:51 INFO - PID 1652 | _x_x_mozilla_page_load_details 15:18:51 INFO - PID 1652 | |i|pagename|runs| 15:18:51 INFO - PID 1652 | |0;ai-astar;115;177;168;123;122;124;122;124;121;122 15:18:51 INFO - PID 1652 | |1;audio-beat-detection;164;120;121;165;122;123;130;109;181;159 15:18:51 INFO - PID 1652 | |2;audio-dft;208;209;212;214;211;264;267;223;208;211 15:18:51 INFO - PID 1652 | |3;audio-fft;97;52;65;115;82;83;87;50;100;88 15:18:51 INFO - PID 1652 | |4;audio-oscillator;97;75;95;73;74;76;88;74;75;91 15:18:51 INFO - PID 1652 | |5;imaging-gaussian-blur;91;92;91;91;91;93;92;94;91;91 15:18:51 INFO - PID 1652 | |6;imaging-darkroom;102;101;131;141;126;101;100;99;100;100 15:18:51 INFO - PID 1652 | |7;imaging-desaturate;159;100;100;101;100;100;100;100;102;99 15:18:51 INFO - PID 1652 | |8;json-parse-financial;66;65;64;65;64;65;66;64;66;66 15:18:51 INFO - PID 1652 | |9;json-stringify-tinderbox;60;61;71;75;71;68;65;68;66;68 15:18:51 INFO - PID 1652 | |10;stanford-crypto-aes;92;90;90;90;90;90;87;88;92;88 15:18:51 INFO - PID 1652 | |11;stanford-crypto-ccm;153;152;156;153;157;150;153;168;227;162 15:18:51 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;208;204;201;202;210;198;198;199;199;196 15:18:51 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;72;74;71;75;71;73;72;71;74;71 15:18:51 INFO - PID 1652 | __end_tp_report 15:18:51 INFO - PID 1652 | __start_cc_report 15:18:51 INFO - PID 1652 | _x_x_mozilla_cycle_collect,2316 15:18:51 INFO - PID 1652 | __end_cc_report 15:18:51 INFO - PID 1652 | __startTimestamp1490739531710__endTimestamp 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | ------- Summary: start ------- 15:18:51 INFO - PID 1652 | Number of tests: 14 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:131.80 Median:123.50 stddev:21.70 (17.6%) stddev-sans-first:22.15 15:18:51 INFO - PID 1652 | Values: 115.0 177.0 168.0 123.0 122.0 124.0 122.0 124.0 121.0 122.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:144.50 stddev:25.11 (17.4%) stddev-sans-first:25.00 15:18:51 INFO - PID 1652 | Values: 164.0 120.0 121.0 165.0 122.0 123.0 130.0 109.0 181.0 159.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:222.70 Median:213.00 stddev:22.98 (10.8%) stddev-sans-first:23.75 15:18:51 INFO - PID 1652 | Values: 208.0 209.0 212.0 214.0 211.0 264.0 267.0 223.0 208.0 211.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:81.90 Median:82.50 stddev:20.85 (25.3%) stddev-sans-first:21.39 15:18:51 INFO - PID 1652 | Values: 97.0 52.0 65.0 115.0 82.0 83.0 87.0 50.0 100.0 88.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:82.00 stddev:9.74 (11.9%) stddev-sans-first:8.64 15:18:51 INFO - PID 1652 | Values: 97.0 75.0 95.0 73.0 74.0 76.0 88.0 74.0 75.0 91.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:91.50 stddev:1.06 (1.2%) stddev-sans-first:1.09 15:18:51 INFO - PID 1652 | Values: 91.0 92.0 91.0 91.0 91.0 93.0 92.0 94.0 91.0 91.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:110.10 Median:114.00 stddev:16.00 (14.0%) stddev-sans-first:16.70 15:18:51 INFO - PID 1652 | Values: 102.0 101.0 131.0 141.0 126.0 101.0 100.0 99.0 100.0 100.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:106.10 Median:100.50 stddev:18.60 (18.5%) stddev-sans-first:0.83 15:18:51 INFO - PID 1652 | Values: 159.0 100.0 100.0 101.0 100.0 100.0 100.0 100.0 102.0 99.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.50 stddev:0.88 (1.3%) stddev-sans-first:0.87 15:18:51 INFO - PID 1652 | Values: 66.0 65.0 64.0 65.0 64.0 65.0 66.0 64.0 66.0 66.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:68.00 stddev:4.57 (6.7%) stddev-sans-first:4.01 15:18:51 INFO - PID 1652 | Values: 60.0 61.0 71.0 75.0 71.0 68.0 65.0 68.0 66.0 68.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:90.00 stddev:1.64 (1.8%) stddev-sans-first:1.51 15:18:51 INFO - PID 1652 | Values: 92.0 90.0 90.0 90.0 90.0 90.0 87.0 88.0 92.0 88.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:163.10 Median:156.50 stddev:23.09 (14.8%) stddev-sans-first:24.20 15:18:51 INFO - PID 1652 | Values: 153.0 152.0 156.0 153.0 157.0 150.0 153.0 168.0 227.0 162.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.50 Median:201.50 stddev:4.58 (2.3%) stddev-sans-first:4.21 15:18:51 INFO - PID 1652 | Values: 208.0 204.0 201.0 202.0 210.0 198.0 198.0 199.0 199.0 196.0 15:18:51 INFO - PID 1652 | 15:18:51 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:72.50 stddev:1.51 (2.1%) stddev-sans-first:1.59 15:18:51 INFO - PID 1652 | Values: 72.0 74.0 71.0 75.0 71.0 73.0 72.0 71.0 74.0 71.0 15:18:51 INFO - PID 1652 | -------- Summary: end -------- 15:18:51 INFO - PID 1652 | 15:18:52 INFO - TEST-INFO | 1652: exit 0 15:18:52 INFO - TEST-OK | kraken | took 63350ms 15:18:52 INFO - SUITE-END | took 1184s 15:18:52 INFO - Completed test suite (00:19:44) 15:18:52 INFO - javascript benchmark 15:18:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7606.000052228824, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.7287417006637, 3472.9826350868243, 3473.5310733051047, 3511.508388825564, 3527.6472352764727, 5971.193888825007, 6130.141780150782, 6172.598781079238, 6182.505399568035, 6221.129041934121, 5026.648134630577, 6095.710389288371, 6194.566380353377, 6196.752129914798, 6223.315435302113, 5328.706921119337, 5508.504234618884, 5508.889822203553, 5531.861703457405, 5546.973809845172, 4893.706377617336, 5581.6371935824245, 5592.245046918675, 5642.04085305499, 5689.743961521742, 5401.810936617219, 5430.619856610039, 5452.536534394541, 5471.644343117685, 5483.259759932438, 5461.399246082928, 5484.890302193934, 5495.2856128703415, 5499.972500137514, 5561.4716601923155, 5497.780088796443, 5499.257600223958, 5555.2778138842095, 5586.944130558723, 5606.383297837235, 6161.0, 6195.2255968004, 6199.225096862892, 6205.658688772116, 6215.502759779207, 6289.654069026202, 6489.837754056186, 6570.776550839489, 6585.769498067592, 6598.010298455222, 5616.382197958222, 6367.458766004896, 6400.0719895614875, 6653.966730166367, 6663.800085997379, 6179.0, 6275.96862015687, 6280.842978925518, 6281.340459251713, 6306.779262725874, 6825.624590647462, 6847.9999999999, 6850.143732033496, 6866.34769696878, 6870.656467176621, 6270.49836013127, 6320.778772743024, 6331.68341582919, 6332.271788744261, 6464.482841372679, 6391.776287829904, 6415.0, 6552.115464412243, 6623.801285961429, 6661.833454163685, 6495.642739649365, 6511.967440162769, 6541.934580654132, 6554.4101030906495, 6559.8360040998405, 6470.1588793457495, 6555.0, 6559.540832141704, 6563.113979612786, 6565.934340656628, 5168.457311982263, 5222.791088356499, 5232.947670523322, 5293.497117773805, 5303.761330740126, 4639.536046395334, 4641.744704041243, 4677.976610116928, 4680.765961701902, 4724.00795832879, 6231.376862313823, 6243.43809057187, 6266.122742816009, 6287.056941458726, 6303.968480157661, 6676.432503237182, 6683.064370988066, 6685.565438246498, 6686.431653309426, 6691.464682825363, 6131.6321020738005, 6272.3100458949475, 6282.465990390868, 6288.339724328879, 6289.150964619717, 5732.254806875163, 5744.942550574524, 5812.941870581325, 5874.853128671817, 5894.2926848778425, 6837.1111755471475, 6892.655367231619, 6911.412529935012, 6912.308769123048, 6918.654067296716, 6649.168853893263, 6702.765403210864, 6702.83242918931, 6734.797956061326, 6735.932640673531, 6559.7376104955265, 6691.364320389555, 6693.09643198162, 6732.730690772314, 6883.827904302432, 6497.675116244263, 6525.086487891604, 6547.181602299713, 6552.6068435894, 6552.934470655233, 6447.097406363019, 6590.90113648286, 6609.20689517261, 6662.866742665023, 6667.833304167435, 6261.24865016201, 6300.180976473029, 6355.459785918249, 6426.132472116205, 6445.387688169616, 5879.470847623735, 6395.6482393468805, 6406.295307516077, 6419.005054216604, 6441.613503189824, 6156.969215153896, 6261.655608941552, 6271.404216599416, 6293.811185664437, 6318.842028949313, 6277.309495955533, 6379.7129129190225, 6407.551471396958, 6428.4214420702365, 6438.3883531064475, 6289.811305660837, 6290.874182516232, 6302.1492098566105, 6314.116023756769, 6317.873642527215], "value": 6047.911534855544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.4967521435854, 790.9248621380968, 812.1553584272357, 1131.513449216837, 1160.1125139268465, 27534.449311013766, 36499.63500364996, 37040.888773336774, 37158.81420592893, 37443.438348424796, 38173.236535269345, 38484.0, 38546.22907541847, 38654.420183697264, 38667.0, 6167.814965551041, 8430.620622072007, 8605.95697021514, 8617.138286171365, 8622.956885215597, 6192.504599632019, 8364.57802173629, 8554.144585541428, 8583.742487725347, 8588.570571471402, 8568.186022327869, 8623.870641940344, 8627.870581941275, 8643.827123457528, 8653.610587523544, 8552.0, 8609.096044915319, 8624.913750862537, 8640.65437382504, 8641.481511109321, 8354.624041918129, 8440.493570385735, 8498.107698691672, 8518.105598912149, 8522.190391912758, 38223.0, 38397.77624461335, 38487.0, 38520.03699907497, 38543.0, 3069.6009518762417, 3076.8153910765427, 3081.260497480611, 3090.335577850773, 3117.703818137273, 27135.592966105523, 27669.307818807036, 28135.0, 28385.0, 28627.0, 2821.083147976916, 2833.835116900657, 2849.102532702213, 2849.415869746697, 2943.882244710209, 319087.0, 319844.0, 320040.0, 320727.0, 321687.0, 40801.387979180334, 49774.0, 49846.0, 49935.0, 50098.74950625296, 52865.47134528682, 52880.20679689808, 52902.470975289754, 52908.73545632247, 53029.204561931605, 14404.423823047171, 14610.342534585969, 14625.634359141106, 14650.974431789886, 14652.340644671016, 8526.573671316533, 10101.44442055694, 11898.702532436759, 11908.642740717793, 11931.88068119325, 11947.163698541193, 12173.965212956999, 12179.269243845398, 12191.0, 12192.451339689735, 4841.744407120248, 5111.3355263815465, 5154.30416893722, 5158.819441319536, 5159.716215608178, 916.7387294621001, 964.5900492290748, 978.7895602445366, 986.2307400227834, 1006.1598565197995, 22149.557008859734, 23585.528289434114, 24191.274261772174, 24226.63660045101, 24262.272131836075, 1058.2909450667917, 1060.7772367802847, 1101.0750969185922, 1101.6199411203122, 1111.0944580167138, 10628.410385561001, 11674.474648640831, 12187.878121218851, 12311.815322770166, 12334.0, 410550.0, 411853.0, 412748.9362553228, 412855.0, 416295.0, 43959.340609890874, 54804.90390192254, 54812.725936370065, 54839.45160548343, 54840.0, 1457.6501639606631, 1475.2771142140275, 1503.827059888105, 1509.4113295814423, 1515.3256800723793, 17847.821521784615, 21026.2640807571, 23550.528989419774, 23709.762902370756, 23776.286711398687, 38224.23551528898, 38231.2353752929, 38298.80850595729, 38345.808270959584, 38437.84686459411, 414170.0, 420538.2542483923, 420817.0, 421221.0, 421416.0, 30963.535546967265, 33456.1635959103, 33474.33051339008, 33484.665153348156, 33518.66481335253, 31783.442337201817, 32064.83967580147, 32610.0, 44174.11651767011, 44355.77822110869, 26427.60358594661, 27050.188494345202, 27464.450710986068, 27545.0, 28795.712042879302, 29132.27169320784, 29303.85348073246, 30002.24994375158, 30290.69709302967, 30353.93761218347, 34184.316313674084, 35344.0, 35936.82031589825, 36355.45466817947, 36366.45450318194, 29945.401091978474, 30136.84931575328, 30693.0, 30713.09122675652, 30820.0, 22147.345504923316, 23650.0, 23847.64228536608, 24001.0, 24132.39669008289], "value": 17257.55217469959, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.31344266876937, 380.5243445692884, 394.483226972666, 413.78069623099765, 431.3831221353465, 11831.881681183186, 17245.396411125614, 21019.579608407843, 22045.448863778412, 22346.21788237412, 18076.0, 18336.816631833677, 20569.0, 22224.999375140585, 22719.886400568015, 6073.757049718006, 6281.591696539733, 6282.717277722513, 6290.433860952513, 6293.4335909768115, 5053.620978426633, 6259.217597800275, 6343.8414039648915, 6345.238571371442, 6365.58623689461, 6137.32489426165, 6272.0, 6349.71426285816, 6351.237851457809, 6370.394812492806, 5893.233879595647, 6300.0, 6350.682465876687, 6354.3010268870385, 6356.237251529799, 5497.670139791625, 5612.943870561283, 5667.3482549506925, 5686.9763442580315, 5689.175069614883, 22263.666045009337, 27612.309692257655, 28036.579451308247, 28178.71821281802, 28352.14943551676, 2006.2376297007045, 2021.8079282468143, 2026.7061276115028, 2034.3591768592846, 2047.938561843147, 15884.36462541497, 16105.758413623806, 17191.656166876593, 17203.91398043014, 17913.641727165385, 1689.4002629066626, 1694.3900195929455, 1702.2595171100488, 1724.8188940161353, 1736.97394539082, 147088.0, 155417.22291388584, 158003.0, 158310.0, 159891.0, 9370.95314523423, 11981.700457488458, 12008.639740807615, 12542.498300067895, 12823.422945967453, 14286.28568571588, 14698.412063517553, 14709.55871323862, 14709.632259193606, 14741.410343586136, 6587.769428069994, 7022.1924478685605, 8871.379003469696, 8955.955220223857, 8968.0, 8206.507609543447, 8403.0, 8412.200840920103, 8576.656933722707, 8596.8710469343, 7566.31903128721, 7667.386609071373, 7714.267144621252, 7747.806304842311, 7755.728549500854, 2166.696662467256, 2167.133146741316, 2168.620491413996, 2172.989135054346, 2398.662974880911, 874.5889431966965, 875.6672464463463, 878.6836738774036, 879.1867522541623, 909.6361455417886, 19878.006099694958, 20686.0, 21035.368938931857, 23544.764552354256, 25632.0, 929.3123088914155, 944.367273926471, 949.2074118111316, 976.4336684722844, 981.906718861707, 8325.209105135124, 10285.537150828231, 10448.895511044791, 10576.947115264375, 10578.682639520826, 600202.0, 721579.0, 730903.0, 734290.0, 741231.0, 102290.48854755679, 130028.0, 139226.30386848, 140194.0, 148568.0, 1100.9889901100887, 1202.7113492761848, 1204.6626944455566, 1239.5909349914507, 1241.6709571963436, 17229.052402118003, 17722.73415898737, 17734.645307094044, 17826.643467130325, 17871.28514859391, 58128.41871581399, 58159.14306816118, 59383.70308148432, 59482.70258648679, 59740.70129649324, 67720.0, 80967.19032809832, 81201.0, 82971.58514207632, 86382.70425943742, 8419.494830310201, 8512.233898949124, 8717.346199034972, 8785.550384186537, 8820.558972051243, 13488.05583609138, 13640.931795340959, 13700.109492882932, 14199.716005679622, 14239.572812815632, 11482.138839586898, 11607.227651013623, 11785.528578856749, 11959.282443053444, 11975.0, 10896.0, 10911.727206819893, 11254.662360129209, 11273.267237629529, 11376.886231137583, 11534.423278836193, 11747.0, 12050.638480845588, 12051.397430128283, 12145.392730363623, 8277.95861020691, 8395.370347224105, 8494.660213591635, 8836.248918841713, 8985.685501007434, 11247.325160490396, 11446.141539384413, 11731.0, 11780.882191178322, 11875.0], "value": 10204.93538857377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.6576223063119, 1418.0711633879805, 1435.4688765156886, 1437.2957250947036, 1437.647776294808, 10478.423686697228, 10902.291351062175, 10916.99999999999, 10920.617778377758, 10936.125109991202, 10689.465526723672, 10727.83908241377, 11070.612528561502, 11071.612493562727, 11095.389753563572, 2447.3602686200243, 2480.342709182068, 2487.129504673368, 2491.0, 2493.7132229793533, 2091.6339640562915, 2224.73303203615, 2418.3107814272935, 2418.9516209675885, 2419.129113519132, 2064.1846470644164, 2140.218820130649, 2140.689600007998, 2378.6194208926577, 2461.4461746107163, 2237.194609940412, 2248.4266512039326, 2424.5878200705833, 2454.7603460252635, 2457.6436416719475, 2423.151896836111, 2423.285130886393, 2445.6698345723275, 2467.580511313063, 2467.8519288842726, 10820.945895270552, 10955.83566246507, 11096.944515277453, 11098.944505277503, 11106.777864442747, 6239.688015599202, 6442.710078046499, 6459.5478316517865, 6462.806115816533, 6465.288818229991, 3827.138893748912, 3899.337112690849, 4004.6996475264473, 4134.937975930364, 4146.606072423115, 1198.6763573835017, 1224.1736827641396, 1519.7720341948686, 1588.5393235961558, 1642.5072478256475, 14993.0, 15175.696486070327, 15203.543893683096, 15225.771613425808, 15225.999999999889, 4353.869383918487, 5211.765470553834, 5390.164524498709, 5426.050441172779, 5441.483059109379, 11564.768704626029, 11566.826497602542, 11569.421528923689, 11569.88430115705, 11581.247218930743, 6313.242410910629, 6330.145430366933, 6336.144620476177, 6346.682665866688, 6349.492040636738, 5759.712014399263, 5965.13505541694, 5972.671503067373, 5992.790252341147, 6040.637561746309, 4049.3521036633997, 4222.5988531090115, 4235.470566179228, 4285.892852678646, 4355.912881742347, 1261.4701825233424, 1268.4926179114755, 1279.7824369857146, 1280.3726174174587, 1282.6152154353842, 588.4644973074567, 624.9031400132986, 737.4800765460337, 781.538892053691, 793.6785601831267, 5185.559227465632, 5567.4432556744005, 5719.28508936383, 5826.475617194473, 6105.870413973429, 736.5286216821239, 843.3169133002287, 886.0917559501434, 894.190757364586, 902.7968707040994, 3708.517892673935, 4444.422225110779, 4444.911101777946, 4581.404417425713, 4620.7227566345455, 13417.463301467831, 13744.725105497833, 13764.793528097087, 13907.304634768221, 13960.511382101578, 13369.0, 13528.93235533816, 13885.166889986633, 13901.860981390259, 13931.930340348234, 7689.038870141232, 10294.279400441897, 10423.739406514745, 10447.634332798467, 10450.216233782345, 6273.749050037947, 6486.351364863476, 6604.504662150454, 6778.559393639398, 6790.388865002174, 12474.43865026058, 12501.687457813627, 12608.432620532281, 12629.11596188258, 12668.619941401772, 13139.277339746406, 13140.934295328845, 13207.207567545978, 13370.06409551322, 13441.395137218653, 7262.491625586158, 9306.72079837606, 9307.673081729568, 9308.022657620852, 9315.068493150631, 10036.598536058476, 10222.335548189343, 10351.482425878827, 10351.63769268072, 10386.948065259625, 8255.504669719836, 8507.702230421906, 8654.956725216334, 8780.253678437404, 8905.599248033725, 8421.0, 8452.408331416987, 8540.44487108336, 8596.570171491525, 8726.345524085593, 7508.511946723445, 7649.655765490677, 7764.107127680389, 7776.7278145264645, 7783.455158138876, 5787.508061814625, 5818.878830464012, 5930.9110363343725, 5953.732082056405, 6038.6678732670125, 4535.3650488932235, 5855.795047173328, 5976.491998180203, 6033.18551995475, 6058.000429929012], "value": 5172.7651221359965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.8306745032186, 386.9322868498012, 392.46232661254066, 393.2076865116792, 396.94641223434826, 4644.976775116128, 5763.942360576393, 5788.5658575606785, 5861.648301101932, 5971.820845374635, 5702.971485142569, 5819.912701309484, 5830.621009634371, 5831.446012628793, 5864.824055278348, 4399.51605323415, 4445.599896009358, 4514.774261286939, 4641.976790116045, 4697.553732395434, 4595.151006376052, 4644.86065418038, 4657.627389808824, 4664.673472856902, 4681.361523466793, 3750.0, 3807.9809600952094, 4626.8380606678775, 4649.86050418488, 4663.8834029149375, 4503.48209955856, 4600.539946005406, 4637.721736695809, 4658.464276608182, 4670.462896766848, 4068.227036863016, 4080.6327430531396, 4086.9182616347803, 4089.568650972136, 4090.3455447128317, 4180.958190418088, 4705.200115980291, 5389.649672771257, 5789.392113828072, 5795.609053827094, 4294.9502178422845, 5043.495650434964, 5271.367435907716, 5380.24676545284, 5457.945420545783, 5790.7683692652245, 6317.4314311711705, 6340.178620513873, 6346.9682651586445, 6358.42774150324, 3990.7805070721097, 4568.428946381702, 4597.4712908015335, 4603.332516785081, 4613.331066995267, 6422.8216634660685, 6708.429783468411, 6771.476417806053, 6786.626735529593, 6786.864262714718, 4148.8133034013545, 4443.311286750559, 4680.3915490985955, 4696.647751418657, 4703.918098837286, 4541.545845415432, 4629.4676112247525, 4778.283257511401, 4786.712797232177, 4810.5670489655395, 5325.680459172462, 5413.2150838128255, 5415.512603865593, 5416.025115479173, 5422.75597598109, 5532.446755324436, 5895.705214739246, 6040.976272948524, 6054.880791722861, 6410.0, 1498.7077519883735, 1531.5635044012404, 1622.2618708487846, 1637.525117715873, 1650.0512205481896, 1115.1691989467736, 1136.6192325570862, 1137.7155711072232, 1143.3882872663085, 1145.9713507162387, 5887.8233652990475, 5954.255718035246, 5983.910241346383, 5994.250718660168, 5997.730102145327, 5317.41508434072, 5969.701514924236, 6429.48907006853, 6440.005397490096, 6547.476201903836, 5676.205331253628, 5893.911591326134, 5895.263092113486, 5940.821775346746, 5978.790742324085, 3079.615048118985, 3147.945438278157, 3391.270876761508, 3424.503447000171, 3426.2633533790354, 5867.970660146757, 6246.781362652286, 6648.5678430901835, 6719.7488841237755, 6740.831479213059, 6352.872942541123, 6663.733450662016, 6678.432333251631, 6748.325167483311, 6766.729330826712, 4818.616156477819, 6623.973284141062, 6637.269900310962, 6671.4967397007795, 6701.798946031627, 6443.2590252119735, 6451.290358060516, 6476.838079048061, 6568.573042752206, 6574.8356291093105, 5856.619319744203, 6464.773732919513, 6474.838129046623, 6491.967540162269, 6510.707018184288, 5174.586033117417, 6005.399460054135, 6278.937210628018, 6644.3389152711825, 6703.944113811269, 3818.5417749870194, 4220.691585608349, 4279.758869927686, 4285.528591854956, 4311.461067366579, 5753.280839895013, 6239.469645080219, 6506.3493650634555, 6535.411812936668, 6561.278259391559, 6287.088372186099, 6362.073446214608, 6367.777127800505, 6373.0, 6384.585001974857, 6057.000594901792, 6160.229971253593, 6353.841153971188, 6362.0, 6401.935980640134, 5144.6913185209005, 6041.969790151021, 6188.9690551546955, 6227.377262273736, 6279.304587761188, 5745.109508026263, 5954.910676339945, 6387.776427825004, 6418.358164183544, 6426.839329016812, 5930.258717660293, 6137.324894261538, 6213.285472170577, 6223.595466294676, 6226.937730622636], "value": 4698.736884458037, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.181117620325, 722.7434260837406, 725.2623131111969, 729.3618084176344, 757.7044952468535, 12705.491780328788, 15912.602184945383, 16148.354065837353, 16184.514464566068, 16184.676306473864, 14424.639384015378, 15255.466058687949, 15463.30415131319, 15542.300596473158, 15550.300236489356, 984.1044649369076, 1009.0010889219657, 1012.559536601577, 1014.8477728340771, 1015.0407864567986, 987.2644879564734, 1022.0545994954674, 1022.0903395977585, 1025.261811495722, 1041.0, 1070.4968664727567, 1072.142286171064, 1072.3565860483686, 1074.2641290715846, 1075.1989767623131, 1053.1522124689623, 1055.319319039223, 1060.8992145746104, 1062.5484169228048, 1063.4416931111152, 792.7502836606451, 793.2266040610416, 794.031281836159, 794.4637369775379, 800.0759123212698, 16157.0, 16438.013719176888, 16591.91704041472, 16811.74782378253, 17050.573735656708, 14661.0, 14687.0, 14735.484258051023, 14743.926280368636, 14777.704445911022, 11807.58673446434, 11814.586489472827, 12137.0, 12315.69210769729, 12382.380880955916, 2965.1993961630455, 3043.756499480058, 3058.740007099399, 3066.432709948667, 3091.5053591425262, 111770.0, 117009.0, 117367.0, 117552.41223793826, 118591.40704296509, 8661.480311181349, 11374.374409407397, 11519.942400287944, 11565.595204167814, 11938.88061119395, 25420.110296139916, 25563.36091597688, 25586.104486342887, 25842.0, 25980.74019259821, 7794.025746781653, 7891.408144389194, 7897.328727058264, 7912.0901096374655, 7980.481268717631, 4165.396017577495, 4199.643030342394, 4223.366495025709, 4242.406063151161, 4251.914961700811, 3699.5560532736245, 3985.844105209515, 4074.0018695419813, 4174.102567947906, 4403.427554417905, 844.2528362399198, 852.808118173406, 859.5315553023618, 860.8908174971345, 862.943908645936, 485.461138136668, 557.768526061688, 558.4276116980128, 568.9516391106803, 574.2276637921988, 10011.849822252672, 10448.634297799541, 10527.684169474775, 10571.101456376295, 10699.73250668724, 541.258475888036, 542.7503348459674, 577.2092233639856, 594.2898236607213, 604.1753007145215, 4715.858524244278, 4827.831025914076, 4835.782389792468, 4844.370231869905, 4844.515548445127, 97544.51227743957, 104967.0, 105447.47276263723, 106258.0, 107585.0, 88510.55744721236, 95574.04425955791, 97102.0, 97195.0, 97262.51368743253, 80749.59625201837, 82131.0, 83881.0, 85610.71583926122, 85674.57162714271, 9215.848018997625, 9707.805843883225, 9744.17174540172, 9755.80488390214, 9943.801123977624, 38385.616143839325, 38399.04002399962, 39534.40698389469, 39748.60251397449, 40376.1924761509, 49883.50116498789, 50309.49690503048, 50713.23930140977, 51145.7442712784, 51613.0, 10344.793104138025, 11380.349849271975, 11852.466639001437, 11937.701557461132, 12195.207311524722, 12656.810147847973, 12694.936525317315, 12810.359482025675, 12974.416151273404, 13013.739725205254, 11803.468843902217, 11835.733227589215, 11974.22167559106, 12015.0, 12118.878811211775, 11743.657602204305, 11913.702157445787, 11914.22000537592, 11976.46105925218, 12454.190477618926, 11709.473073711873, 11833.112516561465, 11962.46168922418, 12044.51821927113, 12092.0, 8448.493090414595, 8692.174243446863, 8783.0, 8823.69117080899, 9166.770830729285, 6479.218214990915, 9741.318107732392, 9957.502124893872, 9968.0, 9969.30214884951], "value": 7478.3844080825675, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1624.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 177.0, 168.0, 123.0, 122.0, 124.0, 122.0, 124.0, 121.0, 122.0], "value": 131.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 120.0, 121.0, 165.0, 122.0, 123.0, 130.0, 109.0, 181.0, 159.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 209.0, 212.0, 214.0, 211.0, 264.0, 267.0, 223.0, 208.0, 211.0], "value": 222.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 52.0, 65.0, 115.0, 82.0, 83.0, 87.0, 50.0, 100.0, 88.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 75.0, 95.0, 73.0, 74.0, 76.0, 88.0, 74.0, 75.0, 91.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 91.0, 91.0, 93.0, 92.0, 94.0, 91.0, 91.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 131.0, 141.0, 126.0, 101.0, 100.0, 99.0, 100.0, 100.0], "value": 110.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 100.0, 100.0, 101.0, 100.0, 100.0, 100.0, 100.0, 102.0, 99.0], "value": 106.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 65.0, 64.0, 65.0, 66.0, 64.0, 66.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 71.0, 75.0, 71.0, 68.0, 65.0, 68.0, 66.0, 68.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 90.0, 90.0, 90.0, 90.0, 87.0, 88.0, 92.0, 88.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 156.0, 153.0, 157.0, 150.0, 153.0, 168.0, 227.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 204.0, 201.0, 202.0, 210.0, 198.0, 198.0, 199.0, 199.0, 196.0], "value": 201.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 71.0, 75.0, 71.0, 73.0, 72.0, 71.0, 74.0, 71.0], "value": 72.4, "unit": "score"}]}]} 15:18:52 INFO - Return code: 0 15:18:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:18:53 ERROR - # TBPL SUCCESS # 15:18:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:18:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:18:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:18:53 INFO - 2017-03-28 15:18:53.033 screenresolution[1656:9824] starting screenresolution argv=screenresolution get 15:18:53 INFO - 2017-03-28 15:18:53.036 screenresolution[1656:9824] Display 0: 1600x1200x32@60 15:18:53 INFO - 2017-03-28 15:18:53.042 screenresolution[1657:9827] starting screenresolution argv=screenresolution list 15:18:53 INFO - Available Modes on Display 0 15:18:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:18:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:18:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:18:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:18:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:18:53 INFO - Intel Iris: 15:18:53 INFO - Chipset Model: Intel Iris 15:18:53 INFO - Type: GPU 15:18:53 INFO - Bus: Built-In 15:18:53 INFO - VRAM (Dynamic, Max): 1536 MB 15:18:53 INFO - Vendor: Intel (0x8086) 15:18:53 INFO - Device ID: 0x0a2e 15:18:53 INFO - Revision ID: 0x0009 15:18:53 INFO - Displays: 15:18:53 INFO - Display: 15:18:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:18:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:18:53 INFO - Display Serial Number: 1600x1200 60 15:18:53 INFO - Main Display: Yes 15:18:53 INFO - Mirror: Off 15:18:53 INFO - Online: Yes 15:18:53 INFO - Rotation: Supported 15:18:53 INFO - Return code: 0 15:18:53 INFO - Running post-action listener: _resource_record_post_action 15:18:53 INFO - [mozharness: 2017-03-28 22:18:53.278384Z] Finished run-tests step (success) 15:18:53 INFO - Running post-run listener: _resource_record_post_run 15:18:53 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 37360640; Write bytes: 514931712; Read time: 243; Write time: 1592 15:18:53 INFO - TinderboxPrint: CPU usage
53.6% 15:18:53 INFO - TinderboxPrint: I/O read bytes / time
37,360,640 / 243 15:18:53 INFO - TinderboxPrint: I/O write bytes / time
514,931,712 / 1,592 15:18:53 INFO - TinderboxPrint: CPU idle
2,240.8 (46.4%) 15:18:53 INFO - TinderboxPrint: CPU system
964.8 (20.0%) 15:18:53 INFO - TinderboxPrint: CPU user
1,620.0 (33.6%) 15:18:53 INFO - TinderboxPrint: Swap in / out
248,541,184 / 0 15:18:53 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204005888; Write bytes: 205285376; Read time: 15682; Write time: 447 15:18:53 INFO - run-tests - Wall time: 1186s; CPU: 54.0%; Read bytes: 36406272; Write bytes: 299627520; Read time: 231; Write time: 1109 15:18:53 INFO - Running post-run listener: _upload_blobber_files 15:18:53 INFO - Blob upload gear active. 15:18:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:18:53 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 15:18:53 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'] 15:18:53 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 15:18:54 INFO - (blobuploader) - INFO - Open directory for files ... 15:18:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:18:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:18:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:55 INFO - (blobuploader) - INFO - Done attempting. 15:18:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:18:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:18:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:56 INFO - (blobuploader) - INFO - Done attempting. 15:18:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:18:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:57 INFO - (blobuploader) - INFO - Done attempting. 15:18:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:18:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:18:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:59 INFO - (blobuploader) - INFO - Done attempting. 15:18:59 INFO - (blobuploader) - INFO - Iteration through files over. 15:18:59 INFO - Return code: 0 15:18:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:18:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:18:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50addf7ff9088d2840cebe6628a9f7fcd3814e34897980b9988b5ff73d669d2f4f6efd286f3fd09be3be248274aa39fbe7619f104bc6be7b6fd78bd88a80053d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461670c244634f924f4a91e7ae942ad707abe3e413bccf3bfab51d09b5af871a2d20473a9b27994806d76511ef0f10969ceb0b5970587f509fb107550cb7e04a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93be270da4cfb05831d7db988f6b761d2d54e524af5de000c752ab0fdf6e040dfcc589bc6eae5d9c48ea1df54fb9aa9dba6320c6b5054d8b58298b2226402caa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612dc0deafefc7aba5b13329bfa05e8ec22ea17437826f365fbf18c571527e33066bbac86d37cb50b5cee6ce1eacddfaec002355b04a3f1739cf94bb194571f3"} 15:18:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:18:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:18:59 INFO - Contents: 15:18:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50addf7ff9088d2840cebe6628a9f7fcd3814e34897980b9988b5ff73d669d2f4f6efd286f3fd09be3be248274aa39fbe7619f104bc6be7b6fd78bd88a80053d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461670c244634f924f4a91e7ae942ad707abe3e413bccf3bfab51d09b5af871a2d20473a9b27994806d76511ef0f10969ceb0b5970587f509fb107550cb7e04a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93be270da4cfb05831d7db988f6b761d2d54e524af5de000c752ab0fdf6e040dfcc589bc6eae5d9c48ea1df54fb9aa9dba6320c6b5054d8b58298b2226402caa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612dc0deafefc7aba5b13329bfa05e8ec22ea17437826f365fbf18c571527e33066bbac86d37cb50b5cee6ce1eacddfaec002355b04a3f1739cf94bb194571f3"} 15:18:59 INFO - Running post-run listener: copy_logs_to_upload_dir 15:18:59 INFO - Copying logs to upload dir... 15:18:59 INFO - mkdir: /builds/slave/test/build/upload/logs 15:18:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.675185 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-28 15:18:59.320188) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:59.324204) ========= 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.MB40PqHZwx/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.u9OIP3T1nU/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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/50addf7ff9088d2840cebe6628a9f7fcd3814e34897980b9988b5ff73d669d2f4f6efd286f3fd09be3be248274aa39fbe7619f104bc6be7b6fd78bd88a80053d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461670c244634f924f4a91e7ae942ad707abe3e413bccf3bfab51d09b5af871a2d20473a9b27994806d76511ef0f10969ceb0b5970587f509fb107550cb7e04a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93be270da4cfb05831d7db988f6b761d2d54e524af5de000c752ab0fdf6e040dfcc589bc6eae5d9c48ea1df54fb9aa9dba6320c6b5054d8b58298b2226402caa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612dc0deafefc7aba5b13329bfa05e8ec22ea17437826f365fbf18c571527e33066bbac86d37cb50b5cee6ce1eacddfaec002355b04a3f1739cf94bb194571f3"} build_url:https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011055 build_url: 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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/50addf7ff9088d2840cebe6628a9f7fcd3814e34897980b9988b5ff73d669d2f4f6efd286f3fd09be3be248274aa39fbe7619f104bc6be7b6fd78bd88a80053d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461670c244634f924f4a91e7ae942ad707abe3e413bccf3bfab51d09b5af871a2d20473a9b27994806d76511ef0f10969ceb0b5970587f509fb107550cb7e04a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93be270da4cfb05831d7db988f6b761d2d54e524af5de000c752ab0fdf6e040dfcc589bc6eae5d9c48ea1df54fb9aa9dba6320c6b5054d8b58298b2226402caa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612dc0deafefc7aba5b13329bfa05e8ec22ea17437826f365fbf18c571527e33066bbac86d37cb50b5cee6ce1eacddfaec002355b04a3f1739cf94bb194571f3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:59.359136) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:59.359509) ========= 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.MB40PqHZwx/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.u9OIP3T1nU/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.005114 ========= master_lag: 0.37 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:59.736542) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 15:18:59.736962) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 15:18:59.737664) ========= ========= Total master_lag: 0.61 =========