builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0244 starttime: 1490142870.89 results: success (0) buildid: 20170321165836 builduid: e739ee1fe81c4f7882c86808c4dcc4f5 revision: 193666d30eab34c7dcf9798b7f83d8d9dd5bf38c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:30.895111) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:30.895545) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:30.895814) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:31.162003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:31.162319) ========= 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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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-21 17:34:31-- 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% 21.0M=0.001s 2017-03-21 17:34:31 (21.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.510985 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:31.710887) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:31.711179) ========= 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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.039069 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:31.772268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 17:34:31.772571) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 193666d30eab34c7dcf9798b7f83d8d9dd5bf38c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 193666d30eab34c7dcf9798b7f83d8d9dd5bf38c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EyLfYLJVQW/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.VsgEDrp0k1/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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-21 17:34:31,884 truncating revision to first 12 chars 2017-03-21 17:34:31,884 Setting DEBUG logging. 2017-03-21 17:34:31,884 attempt 1/10 2017-03-21 17:34:31,884 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/193666d30eab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 17:34:33,102 unpacking tar archive at: pine-193666d30eab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.494366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 17:34:33.291763) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:33.292257) ========= script_repo_revision: 193666d30eab34c7dcf9798b7f83d8d9dd5bf38c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:33.292770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:33.293152) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 17:34:33.313872) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-21 17:34:33.314405) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EyLfYLJVQW/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.VsgEDrp0k1/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:34:33 INFO - MultiFileLogger online at 20170321 17:34:33 in /builds/slave/test 17:34:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 17:34:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:34:33 INFO - {'append_to_log': False, 17:34:33 INFO - 'base_work_dir': '/builds/slave/test', 17:34:33 INFO - 'blob_upload_branch': 'Pine', 17:34:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:34:33 INFO - 'branch': 'Pine', 17:34:33 INFO - 'buildbot_json_path': 'buildprops.json', 17:34:33 INFO - 'config_files': ('talos/mac_config.py',), 17:34:33 INFO - 'default_actions': ('clobber', 17:34:33 INFO - 'read-buildbot-config', 17:34:33 INFO - 'download-and-extract', 17:34:33 INFO - 'populate-webroot', 17:34:33 INFO - 'create-virtualenv', 17:34:33 INFO - 'install', 17:34:33 INFO - 'run-tests'), 17:34:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:34:33 INFO - 'download_minidump_stackwalk': True, 17:34:33 INFO - 'download_symbols': 'ondemand', 17:34:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:34:33 INFO - 'tooltool.py': '/tools/tooltool.py', 17:34:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:34:33 INFO - '/tools/misc-python/virtualenv.py')}, 17:34:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:34:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:34:33 INFO - 'gecko_profile': False, 17:34:33 INFO - 'gecko_profile_interval': 0, 17:34:33 INFO - 'installer_path': 'installer.exe', 17:34:33 INFO - 'log_level': 'info', 17:34:33 INFO - 'log_name': 'talos', 17:34:33 INFO - 'log_to_console': True, 17:34:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:34:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:34:33 INFO - 'opt_config_files': (), 17:34:33 INFO - 'pip_index': False, 17:34:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:34:33 INFO - 'cmd': ('bash', 17:34:33 INFO - '-c', 17:34:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:34:33 INFO - 'enabled': True, 17:34:33 INFO - 'halt_on_failure': False, 17:34:33 INFO - 'name': 'check_screen_resolution'},), 17:34:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:34:33 INFO - 'cmd': ('bash', 17:34:33 INFO - '-c', 17:34:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:34:33 INFO - 'enabled': True, 17:34:33 INFO - 'halt_on_failure': False, 17:34:33 INFO - 'name': 'check_screen_resolution'},), 17:34:33 INFO - 'run_cmd_checks_enabled': True, 17:34:33 INFO - 'suite': 'dromaeojs', 17:34:33 INFO - 'system_bits': '32', 17:34:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:34:33 INFO - 'title': 't-yosemite-r7-0244', 17:34:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:34:33 INFO - 'use_talos_json': True, 17:34:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:34:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:34:33 INFO - 'work_dir': 'build'} 17:34:33 INFO - [mozharness: 2017-03-22 00:34:33.536910Z] Running clobber step. 17:34:33 INFO - Running pre-action listener: _resource_record_pre_action 17:34:33 INFO - Running main action method: clobber 17:34:33 INFO - rmtree: /builds/slave/test/build 17:34:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:34:35 INFO - Running post-action listener: _resource_record_post_action 17:34:35 INFO - [mozharness: 2017-03-22 00:34:35.914059Z] Finished clobber step (success) 17:34:35 INFO - [mozharness: 2017-03-22 00:34:35.914223Z] Running read-buildbot-config step. 17:34:35 INFO - Running pre-action listener: _resource_record_pre_action 17:34:35 INFO - Running main action method: read_buildbot_config 17:34:35 INFO - Using buildbot properties: 17:34:35 INFO - { 17:34:35 INFO - "project": "", 17:34:35 INFO - "product": "firefox", 17:34:35 INFO - "script_repo_revision": "default", 17:34:35 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 17:34:35 INFO - "repository": "", 17:34:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 17:34:35 INFO - "buildid": "20170321165836", 17:34:35 INFO - "pgo_build": "False", 17:34:35 INFO - "basedir": "/builds/slave/test", 17:34:35 INFO - "buildnumber": 3, 17:34:35 INFO - "slavename": "t-yosemite-r7-0244", 17:34:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:34:35 INFO - "platform": "yosemite_r7", 17:34:35 INFO - "scheduler": "tests-pine-macosx64-talos", 17:34:35 INFO - "branch": "pine", 17:34:35 INFO - "repo_path": "projects/pine", 17:34:35 INFO - "revision": "193666d30eab34c7dcf9798b7f83d8d9dd5bf38c", 17:34:35 INFO - "stage_platform": "macosx64", 17:34:35 INFO - "builduid": "e739ee1fe81c4f7882c86808c4dcc4f5", 17:34:35 INFO - "slavebuilddir": "test" 17:34:35 INFO - } 17:34:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:34:35 INFO - Running post-action listener: _resource_record_post_action 17:34:35 INFO - [mozharness: 2017-03-22 00:34:35.917682Z] Finished read-buildbot-config step (success) 17:34:35 INFO - [mozharness: 2017-03-22 00:34:35.917806Z] Running download-and-extract step. 17:34:35 INFO - Running pre-action listener: _resource_record_pre_action 17:34:35 INFO - Running main action method: download_and_extract 17:34:35 INFO - mkdir: /builds/slave/test/build/tests 17:34:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:35 INFO - trying https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:34:35 INFO - Downloading https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/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 17:34:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/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 17:34:36 INFO - Downloaded 1325 bytes. 17:34:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:34:36 INFO - Using the following test package requirements: 17:34:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:34:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:34:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:34:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:34:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'jsshell-mac.zip'], 17:34:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:34:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:34:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:34:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:34:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:34:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:34:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:34:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:34:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:34:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:34:36 INFO - Fetch https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:34:38 INFO - Expected file size: 52476060 17:34:38 INFO - Obtained file size: 52476060 17:34:54 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 17:34:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:34:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:34:54 INFO - Fetch https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:34:56 INFO - Expected file size: 52476060 17:34:56 INFO - Obtained file size: 52476060 17:35:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:35:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:35:11 INFO - Fetch https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:35:12 INFO - Expected file size: 14404452 17:35:12 INFO - Obtained file size: 14404452 17:35:12 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')]} 17:35:12 INFO - trying https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:35:12 INFO - Downloading https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:35:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/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 17:35:15 INFO - Downloaded 75086679 bytes. 17:35:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:35:15 INFO - mkdir: /builds/slave/test/properties 17:35:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:35:15 INFO - Writing to file /builds/slave/test/properties/build_url 17:35:15 INFO - Contents: 17:35:15 INFO - build_url:https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:35:15 INFO - Running post-action listener: _resource_record_post_action 17:35:15 INFO - Running post-action listener: set_extra_try_arguments 17:35:15 INFO - [mozharness: 2017-03-22 00:35:15.888080Z] Finished download-and-extract step (success) 17:35:15 INFO - [mozharness: 2017-03-22 00:35:15.888200Z] Running populate-webroot step. 17:35:15 INFO - Running pre-action listener: _resource_record_pre_action 17:35:15 INFO - Running main action method: populate_webroot 17:35:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:35:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:35:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:35:15 INFO - '--tppagecycles', 17:35:15 INFO - '7'], 17:35:15 INFO - 'tests': ['tsvgm']}}, 17:35:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:35:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:35:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:35:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:35:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:35:15 INFO - 'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:35:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:35:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:35:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:35:15 INFO - 'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['damp', 'tps']}, 17:35:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:35:15 INFO - 'tests': ['damp', 'tps']}, 17:35:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['dromaeo_dom']}, 17:35:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:35:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['basic_compositor_video']}, 17:35:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:35:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['a11yr', 17:35:15 INFO - 'ts_paint', 17:35:15 INFO - 'tpaint', 17:35:15 INFO - 'sessionrestore', 17:35:15 INFO - 'sessionrestore_no_auto_restore', 17:35:15 INFO - 'tabpaint']}, 17:35:15 INFO - 'other-e10s': {'tests': ['a11yr', 17:35:15 INFO - 'ts_paint', 17:35:15 INFO - 'tpaint', 17:35:15 INFO - 'sessionrestore', 17:35:15 INFO - 'sessionrestore_no_auto_restore', 17:35:15 INFO - 'tabpaint']}, 17:35:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:35:15 INFO - 'ts_paint', 17:35:15 INFO - 'tpaint', 17:35:15 INFO - 'sessionrestore', 17:35:15 INFO - 'sessionrestore_no_auto_restore', 17:35:15 INFO - 'tabpaint']}, 17:35:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:35:15 INFO - 'ts_paint', 17:35:15 INFO - 'tpaint', 17:35:15 INFO - 'sessionrestore', 17:35:15 INFO - 'sessionrestore_no_auto_restore', 17:35:15 INFO - 'tabpaint']}, 17:35:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['a11yr', 17:35:15 INFO - 'ts_paint', 17:35:15 INFO - 'tpaint', 17:35:15 INFO - 'sessionrestore', 17:35:15 INFO - 'sessionrestore_no_auto_restore', 17:35:15 INFO - 'tabpaint']}, 17:35:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['a11yr', 17:35:15 INFO - 'ts_paint', 17:35:15 INFO - 'tpaint', 17:35:15 INFO - 'sessionrestore', 17:35:15 INFO - 'sessionrestore_no_auto_restore', 17:35:15 INFO - 'tabpaint']}, 17:35:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['tsvgx', 17:35:15 INFO - 'tsvgr_opacity', 17:35:15 INFO - 'tart', 17:35:15 INFO - 'tscrollx', 17:35:15 INFO - 'cart', 17:35:15 INFO - 'tsvg_static']}, 17:35:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:35:15 INFO - 'tsvgr_opacity', 17:35:15 INFO - 'tart', 17:35:15 INFO - 'tscrollx', 17:35:15 INFO - 'cart', 17:35:15 INFO - 'tsvg_static']}, 17:35:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:35:15 INFO - 'talos_options': ['--disable-e10s'], 17:35:15 INFO - 'tests': ['tp5o']}, 17:35:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:35:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:35:15 INFO - 'talos_options': ['--disable-e10s', 17:35:15 INFO - '--xperf_path', 17:35:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:35:15 INFO - 'tests': ['tp5n']}, 17:35:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:35:15 INFO - 'talos_options': ['--xperf_path', 17:35:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:35:15 INFO - 'tests': ['tp5n']}}, 17:35:15 INFO - 'talos.zip': {'path': '', 17:35:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:35:15 INFO - Running post-action listener: _resource_record_post_action 17:35:15 INFO - [mozharness: 2017-03-22 00:35:15.906504Z] Finished populate-webroot step (success) 17:35:15 INFO - [mozharness: 2017-03-22 00:35:15.906637Z] Running create-virtualenv step. 17:35:15 INFO - Running pre-action listener: _resource_record_pre_action 17:35:15 INFO - Running main action method: create_virtualenv 17:35:15 INFO - Creating virtualenv /builds/slave/test/build/venv 17:35:15 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 17:35:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:35:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:35:16 INFO - Using real prefix '/tools/python27' 17:35:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:35:16 INFO - Installing distribute.............................................................................................................................................................................................done. 17:35:19 INFO - Installing pip.................done. 17:35:19 INFO - Return code: 0 17:35:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:35:19 INFO - Reading from file tmpfile_stdout 17:35:19 INFO - Output received: 17:35:19 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) 17:35:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:19 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:19 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 17:35:19 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 17:35:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:19 INFO - 'HOME': '/Users/cltbld', 17:35:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:19 INFO - 'LOGNAME': 'cltbld', 17:35:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:19 INFO - 'MOZ_NO_REMOTE': '1', 17:35:19 INFO - 'NO_EM_RESTART': '1', 17:35:19 INFO - 'PAGER': '/bin/cat', 17:35:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:19 INFO - 'PWD': '/builds/slave/test', 17:35:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:19 INFO - 'SHELL': '/bin/bash', 17:35:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:19 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:19 INFO - 'USER': 'cltbld', 17:35:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:19 INFO - 'XPC_FLAGS': '0x0', 17:35:19 INFO - 'XPC_SERVICE_NAME': '0', 17:35:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:20 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 17:35:20 INFO - Cleaning up... 17:35:20 INFO - Return code: 0 17:35:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:35:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:35:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:20 INFO - 'HOME': '/Users/cltbld', 17:35:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:20 INFO - 'LOGNAME': 'cltbld', 17:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:20 INFO - 'MOZ_NO_REMOTE': '1', 17:35:20 INFO - 'NO_EM_RESTART': '1', 17:35:20 INFO - 'PAGER': '/bin/cat', 17:35:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:20 INFO - 'PWD': '/builds/slave/test', 17:35:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:20 INFO - 'SHELL': '/bin/bash', 17:35:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:20 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:20 INFO - 'USER': 'cltbld', 17:35:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:20 INFO - 'XPC_FLAGS': '0x0', 17:35:20 INFO - 'XPC_SERVICE_NAME': '0', 17:35:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:20 INFO - Downloading/unpacking psutil>=3.1.1 17:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:35:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:35:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:35:24 INFO - Installing collected packages: psutil 17:35:24 INFO - Running setup.py install for psutil 17:35:24 INFO - building 'psutil._psutil_osx' extension 17:35:24 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 17:35:25 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 17:35:25 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 17:35:25 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 17:35:25 INFO - building 'psutil._psutil_posix' extension 17:35:25 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 17:35:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:35:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:35:25 INFO - ^ 17:35:25 INFO - 1 warning generated. 17:35:25 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 17:35:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:35:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:35:25 INFO - Successfully installed psutil 17:35:25 INFO - Cleaning up... 17:35:25 INFO - Return code: 0 17:35:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:25 INFO - 'HOME': '/Users/cltbld', 17:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:25 INFO - 'LOGNAME': 'cltbld', 17:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:25 INFO - 'MOZ_NO_REMOTE': '1', 17:35:25 INFO - 'NO_EM_RESTART': '1', 17:35:25 INFO - 'PAGER': '/bin/cat', 17:35:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:25 INFO - 'PWD': '/builds/slave/test', 17:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:25 INFO - 'SHELL': '/bin/bash', 17:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:25 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:25 INFO - 'USER': 'cltbld', 17:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:25 INFO - 'XPC_FLAGS': '0x0', 17:35:25 INFO - 'XPC_SERVICE_NAME': '0', 17:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:35:25 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 17:35:25 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 17:35:25 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 17:35:25 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 17:35:25 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 17:35:25 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 17:35:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:35:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:35:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:35:28 INFO - Installing collected packages: mozsystemmonitor 17:35:28 INFO - Running setup.py install for mozsystemmonitor 17:35:28 INFO - Successfully installed mozsystemmonitor 17:35:28 INFO - Cleaning up... 17:35:28 INFO - Return code: 0 17:35:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:35:28 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')]} 17:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:28 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')]} 17:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:28 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:28 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 17:35:28 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 17:35:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:28 INFO - 'HOME': '/Users/cltbld', 17:35:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:28 INFO - 'LOGNAME': 'cltbld', 17:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:28 INFO - 'MOZ_NO_REMOTE': '1', 17:35:28 INFO - 'NO_EM_RESTART': '1', 17:35:28 INFO - 'PAGER': '/bin/cat', 17:35:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:28 INFO - 'PWD': '/builds/slave/test', 17:35:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:28 INFO - 'SHELL': '/bin/bash', 17:35:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:28 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:28 INFO - 'USER': 'cltbld', 17:35:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:28 INFO - 'XPC_FLAGS': '0x0', 17:35:28 INFO - 'XPC_SERVICE_NAME': '0', 17:35:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:29 INFO - Downloading/unpacking jsonschema==2.5.1 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:35:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35:32 INFO - Downloading functools32-3.2.3-2.tar.gz 17:35:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:35:32 INFO - warning: no files found matching '*.txt' 17:35:32 INFO - no previously-included directories found matching 'build' 17:35:32 INFO - no previously-included directories found matching 'dist' 17:35:32 INFO - no previously-included directories found matching '.git*' 17:35:32 INFO - Installing collected packages: jsonschema, functools32 17:35:32 INFO - Running setup.py install for functools32 17:35:32 INFO - warning: no files found matching '*.txt' 17:35:32 INFO - no previously-included directories found matching 'build' 17:35:32 INFO - no previously-included directories found matching 'dist' 17:35:32 INFO - no previously-included directories found matching '.git*' 17:35:32 INFO - Successfully installed jsonschema functools32 17:35:32 INFO - Cleaning up... 17:35:32 INFO - Return code: 0 17:35:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:35: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')]} 17:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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')]} 17:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:32 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:32 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 17:35:32 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 17:35:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:32 INFO - 'HOME': '/Users/cltbld', 17:35:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:32 INFO - 'LOGNAME': 'cltbld', 17:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:32 INFO - 'MOZ_NO_REMOTE': '1', 17:35:32 INFO - 'NO_EM_RESTART': '1', 17:35:32 INFO - 'PAGER': '/bin/cat', 17:35:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:32 INFO - 'PWD': '/builds/slave/test', 17:35:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:32 INFO - 'SHELL': '/bin/bash', 17:35:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:32 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:32 INFO - 'USER': 'cltbld', 17:35:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:32 INFO - 'XPC_FLAGS': '0x0', 17:35:32 INFO - 'XPC_SERVICE_NAME': '0', 17:35:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:35:32 INFO - Cleaning up... 17:35:32 INFO - Return code: 0 17:35:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:35: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')]} 17:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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')]} 17:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:32 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:32 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 17:35:32 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 17:35:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:32 INFO - 'HOME': '/Users/cltbld', 17:35:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:32 INFO - 'LOGNAME': 'cltbld', 17:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:32 INFO - 'MOZ_NO_REMOTE': '1', 17:35:32 INFO - 'NO_EM_RESTART': '1', 17:35:32 INFO - 'PAGER': '/bin/cat', 17:35:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:32 INFO - 'PWD': '/builds/slave/test', 17:35:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:32 INFO - 'SHELL': '/bin/bash', 17:35:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:32 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:32 INFO - 'USER': 'cltbld', 17:35:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:32 INFO - 'XPC_FLAGS': '0x0', 17:35:32 INFO - 'XPC_SERVICE_NAME': '0', 17:35:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:33 INFO - Downloading/unpacking blobuploader==1.2.4 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35:35 INFO - Downloading blobuploader-1.2.4.tar.gz 17:35:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:35:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:35:35 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 17:35:35 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 17:35:35 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 17:35:35 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 17:35:35 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 17:35:35 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 17:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:35:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:35:36 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 17:35:36 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 17:35:36 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 17:35:36 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 17:35:36 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 17:35:36 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 17:35:36 INFO - Downloading docopt-0.6.1.tar.gz 17:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:35:37 INFO - Installing collected packages: blobuploader, requests, docopt 17:35:37 INFO - Running setup.py install for blobuploader 17:35:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:35:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:35:37 INFO - Running setup.py install for requests 17:35:37 INFO - Running setup.py install for docopt 17:35:37 INFO - Successfully installed blobuploader requests docopt 17:35:37 INFO - Cleaning up... 17:35:37 INFO - Return code: 0 17:35:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:37 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')]} 17:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:37 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')]} 17:35:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:37 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:37 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 17:35:37 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 17:35:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:37 INFO - 'HOME': '/Users/cltbld', 17:35:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:37 INFO - 'LOGNAME': 'cltbld', 17:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:37 INFO - 'MOZ_NO_REMOTE': '1', 17:35:37 INFO - 'NO_EM_RESTART': '1', 17:35:37 INFO - 'PAGER': '/bin/cat', 17:35:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:37 INFO - 'PWD': '/builds/slave/test', 17:35:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:37 INFO - 'SHELL': '/bin/bash', 17:35:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:37 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:37 INFO - 'USER': 'cltbld', 17:35:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:37 INFO - 'XPC_FLAGS': '0x0', 17:35:37 INFO - 'XPC_SERVICE_NAME': '0', 17:35:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-TWWyda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-_4gDyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-ceKeqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-o8QFYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-iPvW41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Gi7weQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-xdgBBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-UXl0cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-usQZ6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-a9GZca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-hjXWIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-HQDNsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-1PQ6xU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-v4L0wB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-thFgjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-AOKYeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:35:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-vRXwvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:35:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:35:39 INFO - Running setup.py install for manifestparser 17:35:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:35:40 INFO - Running setup.py install for mozcrash 17:35:40 INFO - Running setup.py install for mozdebug 17:35:40 INFO - Running setup.py install for mozdevice 17:35:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:35:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:35:40 INFO - Running setup.py install for mozfile 17:35:40 INFO - Running setup.py install for mozhttpd 17:35:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:35:40 INFO - Running setup.py install for mozinfo 17:35:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Running setup.py install for mozInstall 17:35:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Running setup.py install for mozleak 17:35:41 INFO - Running setup.py install for mozlog 17:35:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Running setup.py install for moznetwork 17:35:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Running setup.py install for mozprocess 17:35:41 INFO - Running setup.py install for mozprofile 17:35:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:35:41 INFO - Running setup.py install for mozrunner 17:35:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:35:42 INFO - Running setup.py install for mozscreenshot 17:35:42 INFO - Running setup.py install for moztest 17:35:42 INFO - Running setup.py install for mozversion 17:35:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:35:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:35:42 INFO - Cleaning up... 17:35:42 INFO - Return code: 0 17:35:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:42 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')]} 17:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:42 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')]} 17:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:42 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:42 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 17:35:42 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 17:35:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:42 INFO - 'HOME': '/Users/cltbld', 17:35:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:42 INFO - 'LOGNAME': 'cltbld', 17:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:42 INFO - 'MOZ_NO_REMOTE': '1', 17:35:42 INFO - 'NO_EM_RESTART': '1', 17:35:42 INFO - 'PAGER': '/bin/cat', 17:35:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:42 INFO - 'PWD': '/builds/slave/test', 17:35:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:42 INFO - 'SHELL': '/bin/bash', 17:35:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:42 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:42 INFO - 'USER': 'cltbld', 17:35:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:42 INFO - 'XPC_FLAGS': '0x0', 17:35:42 INFO - 'XPC_SERVICE_NAME': '0', 17:35:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:42 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-61vtYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:42 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)) 17:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:42 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-yPGLqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-YxLN3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-JQWwDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-ScG2r8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-PQj6fQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-eDFjjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-uSRm7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-0zeAwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-9OP73V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:43 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)) 17:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:43 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-TmJl23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:44 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)) 17:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:44 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-N4oSlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:44 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)) 17:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:44 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Ydoa8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:35:44 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)) 17:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:35:44 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-aRQsZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:35:44 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)) 17:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:44 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-bt8pnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:44 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)) 17:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:35:44 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-x7nS6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:35:44 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)) 17:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:35:44 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Iv_a8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:35:44 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)) 17:35:44 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)) 17:35:44 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)) 17:35:44 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)) 17:35:44 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)) 17:35:44 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)) 17:35:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:35:44 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 17:35:44 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 17:35:44 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 17:35:44 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 17:35:44 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 17:35:44 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 17:35:47 INFO - Downloading blessings-1.6.tar.gz 17:35:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:35:47 INFO - Installing collected packages: blessings 17:35:47 INFO - Running setup.py install for blessings 17:35:47 INFO - Successfully installed blessings 17:35:47 INFO - Cleaning up... 17:35:47 INFO - Return code: 0 17:35:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:35:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:35:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:35:47 INFO - Reading from file tmpfile_stdout 17:35:47 INFO - Current package versions: 17:35:47 INFO - blessings == 1.6 17:35:47 INFO - blobuploader == 1.2.4 17:35:47 INFO - docopt == 0.6.1 17:35:47 INFO - functools32 == 3.2.3-2 17:35:47 INFO - jsonschema == 2.5.1 17:35:47 INFO - manifestparser == 1.1 17:35:47 INFO - mozInstall == 1.12 17:35:47 INFO - mozcrash == 1.0 17:35:47 INFO - mozdebug == 0.1 17:35:47 INFO - mozdevice == 0.49 17:35:47 INFO - mozfile == 1.2 17:35:47 INFO - mozhttpd == 0.7 17:35:47 INFO - mozinfo == 0.9 17:35:47 INFO - mozleak == 0.1 17:35:47 INFO - mozlog == 3.4 17:35:47 INFO - moznetwork == 0.27 17:35:47 INFO - mozprocess == 0.25 17:35:47 INFO - mozprofile == 0.28 17:35:47 INFO - mozrunner == 6.13 17:35:47 INFO - mozscreenshot == 0.1 17:35:47 INFO - mozsystemmonitor == 0.3 17:35:47 INFO - moztest == 0.8 17:35:47 INFO - mozversion == 1.4 17:35:47 INFO - psutil == 3.1.1 17:35:47 INFO - requests == 1.2.3 17:35:47 INFO - wsgiref == 0.1.2 17:35:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:47 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')]} 17:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:47 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')]} 17:35:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:47 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:47 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 17:35:47 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 17:35:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:47 INFO - 'HOME': '/Users/cltbld', 17:35:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:47 INFO - 'LOGNAME': 'cltbld', 17:35:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:47 INFO - 'MOZ_NO_REMOTE': '1', 17:35:47 INFO - 'NO_EM_RESTART': '1', 17:35:47 INFO - 'PAGER': '/bin/cat', 17:35:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:47 INFO - 'PWD': '/builds/slave/test', 17:35:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:47 INFO - 'SHELL': '/bin/bash', 17:35:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:47 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:47 INFO - 'USER': 'cltbld', 17:35:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:47 INFO - 'XPC_FLAGS': '0x0', 17:35:47 INFO - 'XPC_SERVICE_NAME': '0', 17:35:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 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)) 17:35:48 INFO - Cleaning up... 17:35:48 INFO - Return code: 0 17:35:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:35:48 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')]} 17:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:48 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')]} 17:35:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:48 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 0x10e61ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff509c56200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, '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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 17:35:48 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 17:35:48 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 17:35:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:35:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:48 INFO - 'HOME': '/Users/cltbld', 17:35:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:48 INFO - 'LOGNAME': 'cltbld', 17:35:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:48 INFO - 'MOZ_NO_REMOTE': '1', 17:35:48 INFO - 'NO_EM_RESTART': '1', 17:35:48 INFO - 'PAGER': '/bin/cat', 17:35:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:48 INFO - 'PWD': '/builds/slave/test', 17:35:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:48 INFO - 'SHELL': '/bin/bash', 17:35:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:35:48 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:35:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:48 INFO - 'USER': 'cltbld', 17:35:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:48 INFO - 'XPC_FLAGS': '0x0', 17:35:48 INFO - 'XPC_SERVICE_NAME': '0', 17:35:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:35:48 INFO - Cleaning up... 17:35:48 INFO - Return code: 0 17:35:48 INFO - Running post-action listener: _resource_record_post_action 17:35:48 INFO - Running post-action listener: _start_resource_monitoring 17:35:48 INFO - Starting resource monitoring. 17:35:48 INFO - [mozharness: 2017-03-22 00:35:48.493641Z] Finished create-virtualenv step (success) 17:35:48 INFO - [mozharness: 2017-03-22 00:35:48.494607Z] Running install step. 17:35:48 INFO - Running pre-action listener: _resource_record_pre_action 17:35:48 INFO - Running main action method: install 17:35:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:35:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:35:48 INFO - Reading from file tmpfile_stdout 17:35:48 INFO - Detecting whether we're running mozinstall >=1.0... 17:35:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:35:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:35:48 INFO - Reading from file tmpfile_stdout 17:35:48 INFO - Output received: 17:35:48 INFO - Usage: mozinstall [options] installer 17:35:48 INFO - Options: 17:35:48 INFO - -h, --help show this help message and exit 17:35:48 INFO - -d DEST, --destination=DEST 17:35:48 INFO - Directory to install application into. [default: 17:35:48 INFO - "/builds/slave/test"] 17:35:48 INFO - --app=APP Application being installed. [default: firefox] 17:35:48 INFO - mkdir: /builds/slave/test/build/application 17:35:48 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'] 17:35:48 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 17:36:09 INFO - Reading from file tmpfile_stdout 17:36:09 INFO - Output received: 17:36:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:36:09 INFO - Running post-action listener: _resource_record_post_action 17:36:09 INFO - [mozharness: 2017-03-22 00:36:09.085854Z] Finished install step (success) 17:36:09 INFO - [mozharness: 2017-03-22 00:36:09.086031Z] Running run-tests step. 17:36:09 INFO - Running pre-action listener: _resource_record_pre_action 17:36:09 INFO - Running main action method: run_tests 17:36:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:36:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:36:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:36:09 INFO - 2017-03-21 17:36:09.100 screenresolution[1626:7824] starting screenresolution argv=screenresolution get 17:36:09 INFO - 2017-03-21 17:36:09.103 screenresolution[1626:7824] Display 0: 1600x1200x32@60 17:36:09 INFO - 2017-03-21 17:36:09.108 screenresolution[1627:7827] starting screenresolution argv=screenresolution list 17:36:09 INFO - Available Modes on Display 0 17:36:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:36:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:36:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:36:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:36:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:36:09 INFO - Intel Iris: 17:36:09 INFO - Chipset Model: Intel Iris 17:36:09 INFO - Type: GPU 17:36:09 INFO - Bus: Built-In 17:36:09 INFO - VRAM (Dynamic, Max): 1536 MB 17:36:09 INFO - Vendor: Intel (0x8086) 17:36:09 INFO - Device ID: 0x0a2e 17:36:09 INFO - Revision ID: 0x0009 17:36:09 INFO - Displays: 17:36:09 INFO - Display: 17:36:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:36:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:36:09 INFO - Display Serial Number: 1600x1200 60 17:36:09 INFO - Main Display: Yes 17:36:09 INFO - Mirror: Off 17:36:09 INFO - Online: Yes 17:36:09 INFO - Rotation: Supported 17:36:09 INFO - Return code: 0 17:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:36:09 INFO - Python 2.7.3 17:36:09 INFO - Return code: 0 17:36:09 INFO - grabbing minidump binary from tooltool 17:36: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')]} 17:36:09 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 0x10e3f8d30>, '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 0x10e31fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3edbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:36:09 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 17:36:09 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 17:36:09 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 17:36:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:36:09 INFO - Return code: 0 17:36:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:36:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:36:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:36:09 INFO - ENV: RUST_BACKTRACE is now 1 17:36:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:36:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 17:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0244 --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 17:36:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EyLfYLJVQW/Render', 17:36:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:36:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:36:09 INFO - 'HOME': '/Users/cltbld', 17:36:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:36:09 INFO - 'LOGNAME': 'cltbld', 17:36:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:36:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:36:09 INFO - 'MOZ_NO_REMOTE': '1', 17:36:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:36:09 INFO - 'NO_EM_RESTART': '1', 17:36:09 INFO - 'PAGER': '/bin/cat', 17:36:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:36:09 INFO - 'PWD': '/builds/slave/test', 17:36:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:36:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:36:09 INFO - 'RUST_BACKTRACE': '1', 17:36:09 INFO - 'SHELL': '/bin/bash', 17:36:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VsgEDrp0k1/Listeners', 17:36:09 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 17:36:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:36:09 INFO - 'USER': 'cltbld', 17:36:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:36:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:36:09 INFO - 'XPC_FLAGS': '0x0', 17:36:09 INFO - 'XPC_SERVICE_NAME': '0', 17:36:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:36:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 17:36:09 INFO - mozversion application_buildid: 20170321165836 17:36:09 INFO - mozversion application_changeset: 193666d30eab34c7dcf9798b7f83d8d9dd5bf38c 17:36:09 INFO - mozversion application_display_name: Nightly 17:36:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:36:09 INFO - mozversion application_name: Firefox 17:36:09 INFO - mozversion application_remotingname: firefox 17:36:09 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 17:36:09 INFO - mozversion application_vendor: Mozilla 17:36:09 INFO - mozversion application_version: 55.0a1 17:36:09 INFO - mozversion platform_buildid: 20170321165836 17:36:09 INFO - mozversion platform_changeset: 193666d30eab34c7dcf9798b7f83d8d9dd5bf38c 17:36:09 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 17:36:09 INFO - mozversion platform_version: 55.0a1 17:36:09 INFO - using testdate: 1490142969 17:36:09 INFO - actual date: 1490142969 17:36:09 INFO - starting webserver on 'localhost:49245' 17:36:09 INFO - SUITE-START | Running 2 tests 17:36:09 INFO - TEST-START | dromaeo_css 17:36:09 INFO - operating with platform_type : mac_ 17:36:09 INFO - Initialising browser for dromaeo_css test... 17:36:09 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmp6PPglm/profile http://localhost:49245/getInfo.html) 17:36:12 INFO - PID 1637 | __metrics Screen width/height:1600/1200 17:36:12 INFO - PID 1637 | colorDepth:24 17:36:12 INFO - PID 1637 | Browser inner width/height: 1024/689 17:36:12 INFO - PID 1637 | __metrics 17:36:12 INFO - PID 1637 | 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" 17:36:12 INFO - PID 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:36:13 INFO - TEST-INFO | 1637: exit 0 17:36:13 INFO - Browser initialized. 17:36:13 INFO - Running cycle 1/1 for dromaeo_css test... 17:36:13 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmp6PPglm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:25 INFO - PID 1640 | RSS: Main: 233336832 17:36:25 INFO - PID 1640 | 17:39:28 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 17:39:31 INFO - PID 1640 | RSS: Main: 361058304 17:39:31 INFO - PID 1640 | 17:41:15 INFO - PID 1640 | ************************* 17:41:15 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 17:41:15 INFO - PID 1640 | 17:41:15 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:41:15 INFO - PID 1640 | Full stack: 17:41:15 INFO - PID 1640 | ************************* 17:42:34 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 17:42:34 INFO - PID 1640 | RSS: Main: 378773504 17:42:34 INFO - PID 1640 | 17:45:37 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 17:45:38 INFO - PID 1640 | RSS: Main: 351723520 17:45:38 INFO - PID 1640 | 17:48:40 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 17:48:41 INFO - PID 1640 | RSS: Main: 335818752 17:48:41 INFO - PID 1640 | 17:51:45 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 17:51:45 INFO - PID 1640 | RSS: Main: 335654912 17:51:45 INFO - PID 1640 | 17:54:48 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 17:54:49 INFO - PID 1640 | RSS: Main: 541364224 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | __start_tp_report 17:54:49 INFO - PID 1640 | _x_x_mozilla_page_load 17:54:49 INFO - PID 1640 | _x_x_mozilla_page_load_details 17:54:49 INFO - PID 1640 | |i|pagename|runs| 17:54:49 INFO - PID 1640 | |0;dojo.html;3292.32507335996;3319.8340082995846;3320.651331610181;3346;3351.0784534253094;5576.275084239043;5624.606277560572;5706.457886500786;5722.914156287654;5741.942580574193;5643.435656434354;5690.857728556778;5765.942340576593;5769.336526299477;5773.364929857723;5092.159793634046;5150.845474635767;5155.200943853709;5180.352455943007;5181.274621552967;5259;5284.339457567804;5287.524122828944;5303.939212157584;5331.173668081454;5119.692818430869;5151.716655583941;5170.482951704799;5182.196759502283;5241.8427447176455;4348.760818155;5128.743562821844;5170.508801663836;5177.042247184283;5214.06146893559;5097.668651537675;5136.04983078128;5148.433672296044;5151.922721159186;5160.8967820643375;5590.413006634286;5595.664260144405;5599.216109744639;5604.579656525736;5608.355039170507;5839.532837373;6104.345061887806;6111.40942266768;6112.169143789106;6122.489639783254;6149.477294429978;6193.783217587408;6218.440340369343;6278.026905829558;6286.842828929268;5839.474447299721;5847.444492773222;5861.062230043172;5862.736176872051;5874.030784920483;6203.6587987660205;6251.906221406683;6273.059041143865;6273.404026617464;6280.434760871543;6046.032634778414;6132.6627035513475;6143.771245750868;6162.661053642093;6170.167027451326;6262.874742505215;6274.121622972787;6277.8744425111245;6286.214223222098;6306.684665766693;6239.875202495925;6245.18812554365;6255.718492667841;6257.530685198628;6265.279492858289;6094.299155597163;6108.038540470994;6116.944513560587;6182.876342473125;6204.410580994799;4947.332110165082;4959.801607935642;4975.029869175476;4998.575121114746;4999.900001999939;4408.58118478744;4411.602955733999;4416.227160246944;4423.955760442354;4448.510663826976;5539.113741801373;5603.187537806995;5641.887162256732;5691.3170419549915;5725.370209276976;6136.693165341804;6183.659898705614;6204.348543402878;6227.782027629011;6250.031245157008;5673.602847800615;5673.858153546194;5679.119736440858;5682.801101961411;5692.345380281321;5417.322834645669;5433;5433.483819037264;5435.130379139318;5450.073487507133;6181.3200547938795;6203.038529028008;6220.595661282002;6225.128482012522;6280.340564240689;6101.420365065312;6172.444479996821;6280.905786413116;6283.120363149072;6283.277423096402;6261.874762504815;6270.059491076193;6280.466160376236;6287.622742635456;6297.590656607306;6002.489788367863;6116.235470566179;6120.357362476876;6128.448439640454;6132.264128304453;6137.499563105221;6145.832291864532;6146.938530614815;6154.3845615438095;6181.072839074175;6104.853288833144;6127.9837278401355;6129.939558357133;6131.468419844818;6145.754169833157;6108.524204008529;6126.365000174342;6136.764169924236;6142.85000249909;6146.678464130234;5790.913136302875;5857.092150716646;5961.7615295387695;5993.400659933972;6005.759769609167;5039.420466646383;6080.543959202989;6117.15760190703;6120.051392034242;6130.049842274455;5934.762609495572;5949.881002380015;5960.97019514917;5962.403759624003;5964.075568286922 17:54:49 INFO - PID 1640 | |1;ext.html;700.1947760075914;861.7027125641654;875.5884734174936;880.8502554565728;952.9714108576745;36921;37107.628923710756;38545.61454385452;38555.42166867492;38559;37101.257974840555;37379.87860364193;37401.438978415346;37413.625863741356;37487;7182.676779544907;7723.575203363826;7859.135495095564;7867.488613240121;7883.6058197089915;7200.675969581355;10692.625758098467;10929.726756831064;10934.234603577714;10941.179411544166;11155.38645374504;11178.27341222822;11201.215914885961;11206.159538034639;11233.66299011031;7193.064901562816;11059.778804423906;11134.665960021171;11145.777084458265;11153.776924461547;7340.082489688789;10526.42104684242;10762.73093172677;10764.73088172794;10781.191410644155;37262.4410633838;37569;37571;37642;37811.62188378109;3708;3722.5160729105314;3732;3734.309152806712;3769.7738135711943;27386.45227095467;27469.038583649675;27885.721142788516;28028.439431211467;28932.13203603895;3068.2789544457037;3112.7354174895163;3192.409404260219;3230.2731885325616;3247.0421225738464;324689;324861;325597;326030;327022;40027.59972400297;40771.59228407678;49538.54153126236;50188.49811501838;50364.748176259614;40099.39850902239;41807.79096104561;48391.51608483941;48587.757061215176;48963.26555101676;14016.439342426393;14162.787558186636;14171.362288697033;14177.291135443187;14204.218767967861;11495.252808567584;11540.769184616262;11545.942270288595;11549.711257218469;11560.537578496935;12323.260604363766;12362.690932726573;12378.75242495145;12383.19509231897;12480.251184928933;5415.241866138744;5415.268938693226;5416.377116631637;5432.864178395572;5443.891122177613;901.9278457723366;902.179017094441;917.9036201198778;946.6260826973445;948.3598570964572;26642.86678566595;27015.729842701716;27304;27448.313792154957;27607;990.6829814459304;1035.8549803027581;1039.4127318065268;1045.451138152476;1094.5129417409175;13909.374078166507;14031;14294.928525357514;14313.141211527342;14414.063085899592;327540;331985;332262.338688305;334517;354399;52501.7374913123;52821.67945801309;52824.47175528196;52940.73529632327;52993.470065298854;1383.8201033865532;1421.289355322339;1426.7289215049316;1438.180237264749;1452.4771082410323;24029.639555407033;27016.967925083754;27125.321866953484;27411.588826168023;28465.737537741665;42033.36949945692;42048.15903681971;42072.789636051624;42224;42315.730528084205;346331;347771;348394;348618;348718.5128148686;24589.50820983606;26456.338591534597;30225.639846206534;30293.69706302997;30727.692723072483;38739;38847;40940;41077.79461102675;41097.38353924634;27482;31796.6820331803;32410.02769916812;33845.83077084599;34380.96857094291;26495.60256596191;32619;33119.33761324712;34200.14499637429;34253.972380828614;33138.5029224557;33424.1643958903;33889.83055084709;34058.97823065312;34184.658153418146;27101.1869643911;29566.704332956397;29739;30027.849860750557;30213.546796797626;19139.138738756512;21035.158593656695;22355.217567385713;22543.323700289016;22733.886330568243 17:54:49 INFO - PID 1640 | |2;jquery.html;380.0555619286072;398.19564479750903;404.0968435546553;409.6415636318222;430.1483063534202;13038.152520086187;14738.852611473882;17430;20135.093920773565;20644.587108257827;19889.900550497263;21185.470363240926;21469.248576299793;22063.779362206373;22350.21774237902;6379.489640828746;6433.581817181892;6591.274959754423;6697.062411262427;6751.797446076601;3651.123730304734;3784.2053168834577;6435.903461448082;6575.638339891304;6590.406863382294;7131.074609855336;7450.031495905552;7474.215207403226;7483.989661395696;7494.550326980371;7253.4197264219;7504;7556.584387858666;7576.9621151894435;7595.962020189919;7017.82455438613;7232.493725439222;7320.633968301564;7343.41252699788;7355.338019578264;26900;27144.592831107548;27262.45475090487;27299.90800367983;27605.447891042266;2904.8402337871407;2934.4424559333656;2948.469275530425;3004.1137864329858;3009.978733845912;15989.600259993476;16378.672426551522;16461.423850165305;16841.242144103544;16922.323107075703;1953.8339241164517;1980.2079168332639;1999.2203040814095;2028.2596852148858;2046.2633451957283;125928;145017.27491362582;149845.25386865306;152105.47894521023;155228;18228.544286392946;20815;21109.78890211109;21925.451863703536;22552.21067262638;35384.46923296153;35516.93449196528;35552.644473555454;35676.82161589227;35806.104847378505;8596.140385961478;10197.133243674223;10374.066334029974;10394.948025259826;10545.89268126851;8301.667933282723;8325.25072743456;8487.448315859448;8495.532745699045;8495.752123938031;8595.5702214889;9884.654037108667;10273.743156421;10401.739956501147;10465.319754215952;3972.2651309507833;4018.578049304839;4055.5741647127215;4055.655269302142;4059.878203653895;806.26629766913;833.6165363932658;870.7474832298689;875.8511053120857;877.6571845077042;21495.032723527478;22497;23756;24005.879970600035;24049.879750601485;868.3660927522943;884.1688812516214;895.6596493332622;895.9104089591119;904.6924045824451;12140.271583705005;12414.937925310316;12624.621261362174;12660.594487047616;12687.873121268669;521298;528667.3566632143;531181;610086;636376;134051;135852;141201;150827;165092.17453912654;1277.7955527115616;1314.9737005259842;1342.1142046249624;1353.7360214758028;1356.1524047470332;16454.588635283733;19002.619947601248;21725.951991080845;21799.891000544896;22263.332100037023;61086.38913610807;63272.367276326644;63771.04343434759;63940;64143.75496857588;86380.27239455302;86659.56670216861;86699.56650216709;87256;87712;11238.213325067167;12089;12535.310557919402;12578.811317830423;12663.050271229878;13621.38703758353;13769.31153442344;13987.300634968007;13991.790123148365;14211.573652790434;11614.477348519506;11862.525498979945;11996.820047699117;12082.39588020613;12093.637190884288;11379.886201138213;11603.12976526747;11619.186656934271;11935.522579097085;11986.220895641756;11617.6514704559;11874.881251187378;11977.448569543518;12119.151659383759;12169;11943.701407464883;11952.983996360406;12141.210821296587;12248.816267756169;12264.509419623471;11645.59240426581;11888.762224755283;12024.579139730067;12061.577844775391;12094 17:54:49 INFO - PID 1640 | |3;mootools.html;1595.3538816779208;1598.1210334316124;1624.610093577541;1631.6247263129483;1686.5109118355676;10309.742256443584;10781.784364312709;10783.730406739836;10810.243282970196;10856.240063195579;10244;10645.946770266157;10648.520816563254;10674.146068314536;10674.78650426991;2315.9305220843403;2357.1396440299254;2357.3870793593705;2358.9764102359013;2371.95256094878;2319.8376113672048;2352.647102934565;2354.693889794324;2362.728286247078;2363.09021026905;2315.3979965209082;2334.3697201755517;2342.402687314741;2347.4600841806396;2347.80043696286;1988.1848442138653;2014.9421553684285;2183.5851188274337;2278.8632682039047;2290.438842483594;2243.6298010828195;2301.7237931448335;2304.0783686525356;2314.895829687668;2316.6872472216205;10671.893281067167;10773.730656733565;10802.405867677275;10808;10812.297200681931;5560.7219639017885;5808.273965754281;5823.82528524145;5827.650340979555;5828.9417105828825;3252.203210213489;3336.3827691876836;3569.464580312948;3591.982040089809;3603.7297202709638;1072.9570817167382;1141.5547936304765;1187.0207079159727;1192.0940085535055;1193.9761204775855;13505.594832155051;13846.723065538632;13885.37515811791;14891.47879824201;14953.177575233358;4537.659675524349;4697.6241900647865;4719.646026548023;4729.6452766042685;4733.721895088307;11164.776704465865;11172.385518796544;11182.888171118348;11213.55145794159;11216.102711783038;4768.213244814636;4946.678465899705;4955.108080545464;5650.74571644277;5676.403977582377;4995.925061124013;5197.272381866542;5225.790968361223;5227.921581176285;5247.947520524746;3763.1909139535132;3855.5951625079524;3860.9227815444096;3864.8260828262796;3868.5357757069332;913.6847787513298;1026.035526604989;1035.1304903880775;1041.4792603698152;1046.8173857507184;803.9879401808978;833.2084519706303;907.5689047702289;909.077286554148;946.1106559833735;6155.815325540241;6192.318844927055;6438.227412710411;6443.097966284724;6561.836716391052;902.5712786426395;1013.341328136717;1017.4302390661253;1028.2031425645184;1060.8514807926897;3510.052285882797;4195.91608167835;4216.430781844473;4232.492100947844;4243.405923170759;12555.686107847194;12952.740945181044;12957.157784743962;12990.935045324903;13039.087263891626;12607.810882836764;13097.21416714981;13106.410211540504;13114.04933889867;13126.868731312565;9526.047395260419;9821.607135714492;9839.704808855746;9847.45838978863;9851;5509.256250406143;6319.8104056878365;6482.35176482348;6542.084108224757;6598.439132673777;11519;11904;11905.226160299553;11923;11944.163908526667;10573;10818.459077046273;11116.777664446827;11986.280823150639;12021.038316934799;9337.859932100888;9466.148046675833;9820.852687209555;9908.801823963624;9915.058069483388;9773.755656108655;9777.335489488742;9890.159336456194;10014;10055.446950417798;8946.37375383743;8989.325800564853;9063.68277110298;9122.77193070152;9174.174324310578;8684.2184203422;8747.081556436737;8754.08082151365;8851.159139881958;8861.424007439497;7677.500962437647;7732.79615309634;7778.105517865518;7811.296983271533;7847.9607601961625;5782.335031471434;5900.734466948912;5906.084556893688;5997.190379298739;6017.127516510169;5809.477147056785;5890.882182356243;5915.792947246826;5920.230370051866;6037.486813620716 17:54:49 INFO - PID 1640 | |4;prototype.html;376.33952413514277;382.1897577136471;386.75634350359286;387.5272167955094;388.44646378910056;5539.113741801312;5558.194061861028;5614.9157762633495;5638.323401191858;5805.187273781674;4441.180584697166;5515.669059856412;5662.207290979256;5672.971635141829;5678.063119585264;3970.8808735737975;4551.156781503496;4635.172544747701;4678.836240731575;4711.93981354196;4698.859034228979;4698.882527936795;4703.412073490814;4706.22347312693;4727.881802954919;3899.805009749515;4660.743659098748;4685.484596694361;4704.764761761915;4744.956109655887;4525.216991550205;4607.838725644587;4711.623070154379;4716.490722968651;4753.16819556578;4249.660027197817;4265.3815196796295;4283.243870013311;4299.462567179103;4304.246756817544;5533.557315414757;5535.557155427556;5547.25112109864;5563.748156664758;5572.916406253909;4401.537838526972;4626.4910859805395;5387.4073851876265;5435.021696094701;5468;5771.711414429262;6049.304330002062;6074.362191969868;6099.298580663191;6114.908276375813;4813.170995021905;4819.481863213076;4835.927461088087;4863.829765958175;4868.902621947576;5253.86865328366;5437.13005919051;5527.866787308592;6055.212822333113;6266.154069200599;4177.352510360899;4704.9059018819435;4794.95205047952;4857.562819346276;4922.827701030446;3862.9570016095495;4280.357946308017;4875.95124048762;5050.166722490748;5052.595792336604;5295.391030031596;5405.189221616789;5438.184272359178;5445.210444485528;5461.371942226695;5322.813701520428;6041.697915104227;6054.424829641177;6060.484858786965;6093.421124993119;1536.5083173384633;1635.5420482264985;1722.6296346285608;1725.6462425202803;1731.2642127095935;1245.8318127052733;1496.4687535924809;1547.3887814313284;1553.223388305847;1564.8043994500688;4783.9475315430545;4793.976030119827;5656.91514627281;5664.660120392789;5667.659940403589;4863.781129849165;5699;6174;6246.6876656167915;6269.49844012487;4383.276759334674;5043.965986972662;5095.0828850806465;5413.512783849473;5630.5214056805635;2754.476649436601;3241.011491495117;3310.1062713067818;3313.353895990259;3328.1513214130728;5694.5159661428415;6400.263969643551;6486.513511486657;6513.837154071186;6529.967350163219;6038.818835434944;6065.787697430569;6433.815261445614;6455.289918109004;6460.676966151674;6455.419012288917;6496.740130394731;6507.479401647857;6512.73949042033;6526;5836.474717275466;5965.34381218057;6169.290531588925;6258.624482531062;6264.49884009269;5404.8108316208745;5900.911486327623;6065.909011364744;6178.783742568989;6252.781152659635;5352.518273355261;5906.586538942404;6273.560850740587;6336.275401577366;6381.3937675920715;4012.254669218899;4111.239420707059;4884.48712885142;4902.926456103232;4906.582940450102;5990.580659353804;6251.374862513894;6292.496600271876;6343.460805831556;6351.936480635134;5342.679439233503;5826.737796799239;6097.146399503984;6128.233970753656;6152.415520525543;5765.365809761007;6031.793641271675;6094.653558379967;6125.816225513242;6138.5396095292135;4963.007398520383;4998.550130488274;5929.911051334319;5930.406959304035;5949.167116603766;5976.9103463448955;6056.121862329849;6057.848553786191;6174.660393678211;6248.250209974832;5675.404082571271;5707.571932105192;5913.142594323885;5946.940530594638;5962.791302304398 17:54:49 INFO - PID 1640 | |5;yui.html;756.3117563017655;761.9619019049046;813.7802793245822;822.4982760516084;841.3689732700475;14545.41818327267;15253.847461525382;15289.694206115872;15296.311665975043;15326.92336538317;14066.92966535166;14287.571372858805;14340.354684039217;14354.856451435482;14379;953.818774432859;965.666844938495;986.0041358228206;987.2052997337141;989.3519744567284;995.7162208770502;998.4658207858797;1010.656376831877;1011.3426272922586;1013.8732658417698;1053.752368193474;1054.5096530113497;1058.7194393485731;1059.1156384419019;1059.1579694143174;1046.905778479933;1047.4919663962971;1047.7537778622018;1048.1457612046197;1051.4217180550743;779.0145465985532;780.0989856715503;781.86708259596;784.8744200927881;786.202004964961;15721.68556628861;15930.522084337488;16164.434244801492;16375.263113159936;16435.83564164355;14433.422663093465;14494.710105797825;14706.926465367711;14725.484608038667;14820.258987050713;11569.305841649528;11757.235779674294;11878.762424751543;11880.168388212829;12162.027037836951;3605.369060414443;3711.3505136601257;3728.589855115962;3731;3854.2676891390824;106877;107661;107809;108090.45954770254;109291.45354273258;10582.57669693219;10862.129909440739;11198.888011119785;11403.144764142722;11802.173847830569;24332.635010474856;24667.3833154169;24856;24920.5015899681;24945.62581561278;7688.500247483896;7764.883526747103;7877.054753429625;7924.683012679428;8050.074241462306;4093.181363727207;4116.670666346685;4202.579742025834;4299.78501074945;4378.08060307339;3491.720662347057;3584.551931008624;3585.6414358563934;3589.3000864831106;3594.0475773919934;501.633807320658;664.58131377233;669.2337273821447;670.9329067093349;671.684308375063;643.488426700774;653.8724948635065;673.9966300168468;688.2016860441863;689.741346994877;7931.405144614062;8939.865902011476;10009.099181073738;10240;10359.274850760374;555.7304707216997;575.3843387575255;668.4552090046597;684.5071548485083;725.300085417564;4125.608067233608;4846.539578740014;4886.169351210303;4974.950250497449;5004.574611158092;73180;79550.44270794265;88863.55568222118;89426.76433089224;89432.5528372367;79735.60132199302;80735.59632201801;82440;83890.58054709688;83963;70100.2989970104;71779.28220717725;71783;73204;73284.99999999894;9398.718038458857;9435.386699864486;9473.810523789623;9482.85775713351;9547.952260238655;44259.77870110629;44912.77543612261;45372.092558149314;45440.31839522476;46363;59198.11202831875;59979.50051248754;60008.699956499935;60467;61043;10552.314099583502;11518.712032198926;11594.362310073027;11867.228630139014;12080.275183489019;12344;12628.747425051632;12635.305058221886;12756.107072504836;12820.974322054026;11344.659660210207;11445.885541144482;11719.941400292944;11990.75253032129;12061.819072714092;11345.716357091138;11548.280597044748;11655;12062.155649104478;12314.692132696397;11472.082233421139;11605;11757.823632645346;11995.820062698893;12037;10104.725313504978;10215.131424928219;10231.908741632622;10428.165746740091;10478.64433653775;8483.600205965946;9217.76955576116;9452.291078169303;9906.801863962824;9957.302988790716 17:54:49 INFO - PID 1640 | __end_tp_report 17:54:49 INFO - PID 1640 | __start_cc_report 17:54:49 INFO - PID 1640 | _x_x_mozilla_cycle_collect,2506 17:54:49 INFO - PID 1640 | __end_cc_report 17:54:49 INFO - PID 1640 | __startTimestamp1490144089140__endTimestamp 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | ------- Summary: start ------- 17:54:49 INFO - PID 1640 | Number of tests: 6 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:5717.05 Median:5961.76 stddev:642.51 (10.8%) stddev-sans-first:615.68 17:54:49 INFO - PID 1640 | Values: 3292.3 3319.8 3320.7 3346.0 3351.1 5576.3 5624.6 5706.5 5722.9 5741.9 5643.4 5690.9 5765.9 5769.3 5773.4 5092.2 5150.8 5155.2 5180.4 5181.3 5259.0 5284.3 5287.5 5303.9 5331.2 5119.7 5151.7 5170.5 5182.2 5241.8 4348.8 5128.7 5170.5 5177.0 5214.1 5097.7 5136.0 5148.4 5151.9 5160.9 5590.4 5595.7 5599.2 5604.6 5608.4 5839.5 6104.3 6111.4 6112.2 6122.5 6149.5 6193.8 6218.4 6278.0 6286.8 5839.5 5847.4 5861.1 5862.7 5874.0 6203.7 6251.9 6273.1 6273.4 6280.4 6046.0 6132.7 6143.8 6162.7 6170.2 6262.9 6274.1 6277.9 6286.2 6306.7 6239.9 6245.2 6255.7 6257.5 6265.3 6094.3 6108.0 6116.9 6182.9 6204.4 4947.3 4959.8 4975.0 4998.6 4999.9 4408.6 4411.6 4416.2 4424.0 4448.5 5539.1 5603.2 5641.9 5691.3 5725.4 6136.7 6183.7 6204.3 6227.8 6250.0 5673.6 5673.9 5679.1 5682.8 5692.3 5417.3 5433.0 5433.5 5435.1 5450.1 6181.3 6203.0 6220.6 6225.1 6280.3 6101.4 6172.4 6280.9 6283.1 6283.3 6261.9 6270.1 6280.5 6287.6 6297.6 6002.5 6116.2 6120.4 6128.4 6132.3 6137.5 6145.8 6146.9 6154.4 6181.1 6104.9 6128.0 6129.9 6131.5 6145.8 6108.5 6126.4 6136.8 6142.9 6146.7 5790.9 5857.1 5961.8 5993.4 6005.8 5039.4 6080.5 6117.2 6120.1 6130.0 5934.8 5949.9 5961.0 5962.4 5964.1 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:47968.45 Median:327281.00 stddev:88576.72 (27.1%) stddev-sans-first:88754.10 17:54:49 INFO - PID 1640 | Values: 700.2 861.7 875.6 880.9 953.0 36921.0 37107.6 38545.6 38555.4 38559.0 37101.3 37379.9 37401.4 37413.6 37487.0 7182.7 7723.6 7859.1 7867.5 7883.6 7200.7 10692.6 10929.7 10934.2 10941.2 11155.4 11178.3 11201.2 11206.2 11233.7 7193.1 11059.8 11134.7 11145.8 11153.8 7340.1 10526.4 10762.7 10764.7 10781.2 37262.4 37569.0 37571.0 37642.0 37811.6 3708.0 3722.5 3732.0 3734.3 3769.8 27386.5 27469.0 27885.7 28028.4 28932.1 3068.3 3112.7 3192.4 3230.3 3247.0 324689.0 324861.0 325597.0 326030.0 327022.0 40027.6 40771.6 49538.5 50188.5 50364.7 40099.4 41807.8 48391.5 48587.8 48963.3 14016.4 14162.8 14171.4 14177.3 14204.2 11495.3 11540.8 11545.9 11549.7 11560.5 12323.3 12362.7 12378.8 12383.2 12480.3 5415.2 5415.3 5416.4 5432.9 5443.9 901.9 902.2 917.9 946.6 948.4 26642.9 27015.7 27304.0 27448.3 27607.0 990.7 1035.9 1039.4 1045.5 1094.5 13909.4 14031.0 14294.9 14313.1 14414.1 327540.0 331985.0 332262.3 334517.0 354399.0 52501.7 52821.7 52824.5 52940.7 52993.5 1383.8 1421.3 1426.7 1438.2 1452.5 24029.6 27017.0 27125.3 27411.6 28465.7 42033.4 42048.2 42072.8 42224.0 42315.7 346331.0 347771.0 348394.0 348618.0 348718.5 24589.5 26456.3 30225.6 30293.7 30727.7 38739.0 38847.0 40940.0 41077.8 41097.4 27482.0 31796.7 32410.0 33845.8 34381.0 26495.6 32619.0 33119.3 34200.1 34254.0 33138.5 33424.2 33889.8 34059.0 34184.7 27101.2 29566.7 29739.0 30027.8 30213.5 19139.1 21035.2 22355.2 22543.3 22733.9 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:37840.69 Median:22306.77 stddev:96141.12 (431.0%) stddev-sans-first:96369.68 17:54:49 INFO - PID 1640 | Values: 380.1 398.2 404.1 409.6 430.1 13038.2 14738.9 17430.0 20135.1 20644.6 19889.9 21185.5 21469.2 22063.8 22350.2 6379.5 6433.6 6591.3 6697.1 6751.8 3651.1 3784.2 6435.9 6575.6 6590.4 7131.1 7450.0 7474.2 7484.0 7494.6 7253.4 7504.0 7556.6 7577.0 7596.0 7017.8 7232.5 7320.6 7343.4 7355.3 26900.0 27144.6 27262.5 27299.9 27605.4 2904.8 2934.4 2948.5 3004.1 3010.0 15989.6 16378.7 16461.4 16841.2 16922.3 1953.8 1980.2 1999.2 2028.3 2046.3 125928.0 145017.3 149845.3 152105.5 155228.0 18228.5 20815.0 21109.8 21925.5 22552.2 35384.5 35516.9 35552.6 35676.8 35806.1 8596.1 10197.1 10374.1 10394.9 10545.9 8301.7 8325.3 8487.4 8495.5 8495.8 8595.6 9884.7 10273.7 10401.7 10465.3 3972.3 4018.6 4055.6 4055.7 4059.9 806.3 833.6 870.7 875.9 877.7 21495.0 22497.0 23756.0 24005.9 24049.9 868.4 884.2 895.7 895.9 904.7 12140.3 12414.9 12624.6 12660.6 12687.9 521298.0 528667.4 531181.0 610086.0 636376.0 134051.0 135852.0 141201.0 150827.0 165092.2 1277.8 1315.0 1342.1 1353.7 1356.2 16454.6 19002.6 21726.0 21799.9 22263.3 61086.4 63272.4 63771.0 63940.0 64143.8 86380.3 86659.6 86699.6 87256.0 87712.0 11238.2 12089.0 12535.3 12578.8 12663.1 13621.4 13769.3 13987.3 13991.8 14211.6 11614.5 11862.5 11996.8 12082.4 12093.6 11379.9 11603.1 11619.2 11935.5 11986.2 11617.7 11874.9 11977.4 12119.2 12169.0 11943.7 11953.0 12141.2 12248.8 12264.5 11645.6 11888.8 12024.6 12061.6 12094.0 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6538.16 Median:3294.29 stddev:4021.41 (122.1%) stddev-sans-first:4015.54 17:54:49 INFO - PID 1640 | Values: 1595.4 1598.1 1624.6 1631.6 1686.5 10309.7 10781.8 10783.7 10810.2 10856.2 10244.0 10645.9 10648.5 10674.1 10674.8 2315.9 2357.1 2357.4 2359.0 2372.0 2319.8 2352.6 2354.7 2362.7 2363.1 2315.4 2334.4 2342.4 2347.5 2347.8 1988.2 2014.9 2183.6 2278.9 2290.4 2243.6 2301.7 2304.1 2314.9 2316.7 10671.9 10773.7 10802.4 10808.0 10812.3 5560.7 5808.3 5823.8 5827.7 5828.9 3252.2 3336.4 3569.5 3592.0 3603.7 1073.0 1141.6 1187.0 1192.1 1194.0 13505.6 13846.7 13885.4 14891.5 14953.2 4537.7 4697.6 4719.6 4729.6 4733.7 11164.8 11172.4 11182.9 11213.6 11216.1 4768.2 4946.7 4955.1 5650.7 5676.4 4995.9 5197.3 5225.8 5227.9 5247.9 3763.2 3855.6 3860.9 3864.8 3868.5 913.7 1026.0 1035.1 1041.5 1046.8 804.0 833.2 907.6 909.1 946.1 6155.8 6192.3 6438.2 6443.1 6561.8 902.6 1013.3 1017.4 1028.2 1060.9 3510.1 4195.9 4216.4 4232.5 4243.4 12555.7 12952.7 12957.2 12990.9 13039.1 12607.8 13097.2 13106.4 13114.0 13126.9 9526.0 9821.6 9839.7 9847.5 9851.0 5509.3 6319.8 6482.4 6542.1 6598.4 11519.0 11904.0 11905.2 11923.0 11944.2 10573.0 10818.5 11116.8 11986.3 12021.0 9337.9 9466.1 9820.9 9908.8 9915.1 9773.8 9777.3 9890.2 10014.0 10055.4 8946.4 8989.3 9063.7 9122.8 9174.2 8684.2 8747.1 8754.1 8851.2 8861.4 7677.5 7732.8 7778.1 7811.3 7848.0 5782.3 5900.7 5906.1 5997.2 6017.1 5809.5 5890.9 5915.8 5920.2 6037.5 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5056.24 Median:5491.83 stddev:1410.64 (25.7%) stddev-sans-first:1370.16 17:54:49 INFO - PID 1640 | Values: 376.3 382.2 386.8 387.5 388.4 5539.1 5558.2 5614.9 5638.3 5805.2 4441.2 5515.7 5662.2 5673.0 5678.1 3970.9 4551.2 4635.2 4678.8 4711.9 4698.9 4698.9 4703.4 4706.2 4727.9 3899.8 4660.7 4685.5 4704.8 4745.0 4525.2 4607.8 4711.6 4716.5 4753.2 4249.7 4265.4 4283.2 4299.5 4304.2 5533.6 5535.6 5547.3 5563.7 5572.9 4401.5 4626.5 5387.4 5435.0 5468.0 5771.7 6049.3 6074.4 6099.3 6114.9 4813.2 4819.5 4835.9 4863.8 4868.9 5253.9 5437.1 5527.9 6055.2 6266.2 4177.4 4704.9 4795.0 4857.6 4922.8 3863.0 4280.4 4876.0 5050.2 5052.6 5295.4 5405.2 5438.2 5445.2 5461.4 5322.8 6041.7 6054.4 6060.5 6093.4 1536.5 1635.5 1722.6 1725.6 1731.3 1245.8 1496.5 1547.4 1553.2 1564.8 4783.9 4794.0 5656.9 5664.7 5667.7 4863.8 5699.0 6174.0 6246.7 6269.5 4383.3 5044.0 5095.1 5413.5 5630.5 2754.5 3241.0 3310.1 3313.4 3328.2 5694.5 6400.3 6486.5 6513.8 6530.0 6038.8 6065.8 6433.8 6455.3 6460.7 6455.4 6496.7 6507.5 6512.7 6526.0 5836.5 5965.3 6169.3 6258.6 6264.5 5404.8 5900.9 6065.9 6178.8 6252.8 5352.5 5906.6 6273.6 6336.3 6381.4 4012.3 4111.2 4884.5 4902.9 4906.6 5990.6 6251.4 6292.5 6343.5 6351.9 5342.7 5826.7 6097.1 6128.2 6152.4 5765.4 6031.8 6094.7 6125.8 6138.5 4963.0 4998.6 5929.9 5930.4 5949.2 5976.9 6056.1 6057.8 6174.7 6248.3 5675.4 5707.6 5913.1 5946.9 5962.8 17:54:49 INFO - PID 1640 | 17:54:49 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:19150.85 Median:3599.71 stddev:27041.16 (751.2%) stddev-sans-first:27081.74 17:54:49 INFO - PID 1640 | Values: 756.3 762.0 813.8 822.5 841.4 14545.4 15253.8 15289.7 15296.3 15326.9 14066.9 14287.6 14340.4 14354.9 14379.0 953.8 965.7 986.0 987.2 989.4 995.7 998.5 1010.7 1011.3 1013.9 1053.8 1054.5 1058.7 1059.1 1059.2 1046.9 1047.5 1047.8 1048.1 1051.4 779.0 780.1 781.9 784.9 786.2 15721.7 15930.5 16164.4 16375.3 16435.8 14433.4 14494.7 14706.9 14725.5 14820.3 11569.3 11757.2 11878.8 11880.2 12162.0 3605.4 3711.4 3728.6 3731.0 3854.3 106877.0 107661.0 107809.0 108090.5 109291.5 10582.6 10862.1 11198.9 11403.1 11802.2 24332.6 24667.4 24856.0 24920.5 24945.6 7688.5 7764.9 7877.1 7924.7 8050.1 4093.2 4116.7 4202.6 4299.8 4378.1 3491.7 3584.6 3585.6 3589.3 3594.0 501.6 664.6 669.2 670.9 671.7 643.5 653.9 674.0 688.2 689.7 7931.4 8939.9 10009.1 10240.0 10359.3 555.7 575.4 668.5 684.5 725.3 4125.6 4846.5 4886.2 4975.0 5004.6 73180.0 79550.4 88863.6 89426.8 89432.6 79735.6 80735.6 82440.0 83890.6 83963.0 70100.3 71779.3 71783.0 73204.0 73285.0 9398.7 9435.4 9473.8 9482.9 9548.0 44259.8 44912.8 45372.1 45440.3 46363.0 59198.1 59979.5 60008.7 60467.0 61043.0 10552.3 11518.7 11594.4 11867.2 12080.3 12344.0 12628.7 12635.3 12756.1 12821.0 11344.7 11445.9 11719.9 11990.8 12061.8 11345.7 11548.3 11655.0 12062.2 12314.7 11472.1 11605.0 11757.8 11995.8 12037.0 10104.7 10215.1 10231.9 10428.2 10478.6 8483.6 9217.8 9452.3 9906.8 9957.3 17:54:49 INFO - PID 1640 | -------- Summary: end -------- 17:54:49 INFO - PID 1640 | 17:54:49 INFO - TEST-INFO | 1640: exit 0 17:54:49 INFO - TEST-OK | dromaeo_css | took 1120162ms 17:54:49 INFO - TEST-START | kraken 17:54:49 INFO - operating with platform_type : mac_ 17:54:49 INFO - Initialising browser for kraken test... 17:54:49 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpNiQkLZ/profile http://localhost:49245/getInfo.html) 17:54:52 INFO - PID 1645 | __metrics Screen width/height:1600/1200 17:54:52 INFO - PID 1645 | colorDepth:24 17:54:52 INFO - PID 1645 | Browser inner width/height: 1024/689 17:54:52 INFO - PID 1645 | __metrics 17:54:52 INFO - PID 1645 | 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" 17:54:52 INFO - PID 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:54:53 INFO - TEST-INFO | 1645: exit 0 17:54:53 INFO - Browser initialized. 17:54:53 INFO - Running cycle 1/1 for kraken test... 17:54:53 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpNiQkLZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:55:05 INFO - PID 1646 | RSS: Main: 234061824 17:55:05 INFO - PID 1646 | 17:55:08 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 17:55:09 INFO - PID 1646 | RSS: Main: 284143616 17:55:09 INFO - PID 1646 | 17:55:11 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 17:55:12 INFO - PID 1646 | RSS: Main: 285220864 17:55:12 INFO - PID 1646 | 17:55:15 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 17:55:16 INFO - PID 1646 | RSS: Main: 378707968 17:55:16 INFO - PID 1646 | 17:55:18 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 17:55:19 INFO - PID 1646 | RSS: Main: 280723456 17:55:19 INFO - PID 1646 | 17:55:21 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:55:21 INFO - PID 1646 | RSS: Main: 284725248 17:55:21 INFO - PID 1646 | 17:55:24 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 17:55:25 INFO - PID 1646 | RSS: Main: 323362816 17:55:25 INFO - PID 1646 | 17:55:28 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 17:55:28 INFO - PID 1646 | RSS: Main: 339103744 17:55:28 INFO - PID 1646 | 17:55:31 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 17:55:32 INFO - PID 1646 | RSS: Main: 350535680 17:55:32 INFO - PID 1646 | 17:55:34 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:55:34 INFO - PID 1646 | RSS: Main: 301613056 17:55:34 INFO - PID 1646 | 17:55:36 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:55:37 INFO - PID 1646 | RSS: Main: 305565696 17:55:37 INFO - PID 1646 | 17:55:39 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:55:40 INFO - PID 1646 | RSS: Main: 307904512 17:55:40 INFO - PID 1646 | 17:55:42 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:55:43 INFO - PID 1646 | RSS: Main: 326762496 17:55:43 INFO - PID 1646 | 17:55:47 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:55:47 INFO - PID 1646 | RSS: Main: 310087680 17:55:47 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 17:55:49 INFO - PID 1646 | RSS: Main: 376164352 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | __start_tp_report 17:55:49 INFO - PID 1646 | _x_x_mozilla_page_load 17:55:49 INFO - PID 1646 | _x_x_mozilla_page_load_details 17:55:49 INFO - PID 1646 | |i|pagename|runs| 17:55:49 INFO - PID 1646 | |0;ai-astar;116;116;115;114;116;116;117;115;115;114 17:55:49 INFO - PID 1646 | |1;audio-beat-detection;158;105;116;153;136;141;139;142;135;113 17:55:49 INFO - PID 1646 | |2;audio-dft;193;181;186;187;187;183;183;185;182;184 17:55:49 INFO - PID 1646 | |3;audio-fft;82;61;58;89;62;59;72;78;105;81 17:55:49 INFO - PID 1646 | |4;audio-oscillator;89;69;88;68;70;67;82;54;64;91 17:55:49 INFO - PID 1646 | |5;imaging-gaussian-blur;86;86;86;86;86;87;85;85;86;86 17:55:49 INFO - PID 1646 | |6;imaging-darkroom;94;93;95;94;94;99;93;95;93;95 17:55:49 INFO - PID 1646 | |7;imaging-desaturate;83;86;86;85;86;83;85;84;86;86 17:55:49 INFO - PID 1646 | |8;json-parse-financial;64;61;61;62;61;62;63;61;61;60 17:55:49 INFO - PID 1646 | |9;json-stringify-tinderbox;55;58;63;63;64;59;59;58;61;59 17:55:49 INFO - PID 1646 | |10;stanford-crypto-aes;99;101;87;83;84;83;84;83;83;82 17:55:49 INFO - PID 1646 | |11;stanford-crypto-ccm;141;139;137;137;134;145;149;144;177;152 17:55:49 INFO - PID 1646 | |12;stanford-crypto-pbkdf2;218;214;189;188;207;230;233;186;184;184 17:55:49 INFO - PID 1646 | |13;stanford-crypto-sha256-iterative;71;68;69;67;68;67;68;67;68;66 17:55:49 INFO - PID 1646 | __end_tp_report 17:55:49 INFO - PID 1646 | __start_cc_report 17:55:49 INFO - PID 1646 | _x_x_mozilla_cycle_collect,2284 17:55:49 INFO - PID 1646 | __end_cc_report 17:55:49 INFO - PID 1646 | __startTimestamp1490144149833__endTimestamp 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | ------- Summary: start ------- 17:55:49 INFO - PID 1646 | Number of tests: 14 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#0] ai-astar Cycles:10 Average:115.40 Median:116.00 stddev:0.97 (0.8%) stddev-sans-first:1.00 17:55:49 INFO - PID 1646 | Values: 116.0 116.0 115.0 114.0 116.0 116.0 117.0 115.0 115.0 114.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:140.00 stddev:17.27 (12.3%) stddev-sans-first:15.95 17:55:49 INFO - PID 1646 | Values: 158.0 105.0 116.0 153.0 136.0 141.0 139.0 142.0 135.0 113.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.50 stddev:3.45 (1.9%) stddev-sans-first:2.17 17:55:49 INFO - PID 1646 | Values: 193.0 181.0 186.0 187.0 187.0 183.0 183.0 185.0 182.0 184.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#3] audio-fft Cycles:10 Average:74.70 Median:75.00 stddev:15.30 (20.4%) stddev-sans-first:16.00 17:55:49 INFO - PID 1646 | Values: 82.0 61.0 58.0 89.0 62.0 59.0 72.0 78.0 105.0 81.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#4] audio-oscillator Cycles:10 Average:74.20 Median:76.00 stddev:12.47 (16.4%) stddev-sans-first:12.02 17:55:49 INFO - PID 1646 | Values: 89.0 69.0 88.0 68.0 70.0 67.0 82.0 54.0 64.0 91.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 17:55:49 INFO - PID 1646 | Values: 86.0 86.0 86.0 86.0 86.0 87.0 85.0 85.0 86.0 86.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:1.78 (1.9%) stddev-sans-first:1.88 17:55:49 INFO - PID 1646 | Values: 94.0 93.0 95.0 94.0 94.0 99.0 93.0 95.0 93.0 95.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:86.00 stddev:1.25 (1.5%) stddev-sans-first:1.09 17:55:49 INFO - PID 1646 | Values: 83.0 86.0 86.0 85.0 86.0 83.0 85.0 84.0 86.0 86.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:61.50 stddev:1.17 (1.9%) stddev-sans-first:0.87 17:55:49 INFO - PID 1646 | Values: 64.0 61.0 61.0 62.0 61.0 62.0 63.0 61.0 61.0 60.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:2.81 (4.7%) stddev-sans-first:2.35 17:55:49 INFO - PID 1646 | Values: 55.0 58.0 63.0 63.0 64.0 59.0 59.0 58.0 61.0 59.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:83.50 stddev:7.05 (8.4%) stddev-sans-first:5.96 17:55:49 INFO - PID 1646 | Values: 99.0 101.0 87.0 83.0 84.0 83.0 84.0 83.0 83.0 82.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:144.50 stddev:12.42 (8.6%) stddev-sans-first:13.07 17:55:49 INFO - PID 1646 | Values: 141.0 139.0 137.0 137.0 134.0 145.0 149.0 144.0 177.0 152.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.30 Median:210.50 stddev:19.50 (9.3%) stddev-sans-first:19.94 17:55:49 INFO - PID 1646 | Values: 218.0 214.0 189.0 188.0 207.0 230.0 233.0 186.0 184.0 184.0 17:55:49 INFO - PID 1646 | 17:55:49 INFO - PID 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.37 (2.0%) stddev-sans-first:0.88 17:55:49 INFO - PID 1646 | Values: 71.0 68.0 69.0 67.0 68.0 67.0 68.0 67.0 68.0 66.0 17:55:49 INFO - PID 1646 | -------- Summary: end -------- 17:55:49 INFO - PID 1646 | 17:55:50 INFO - TEST-INFO | 1646: exit 0 17:55:50 INFO - TEST-OK | kraken | took 60904ms 17:55:50 INFO - SUITE-END | took 1181s 17:55:50 INFO - Completed test suite (00:19:41) 17:55:50 INFO - javascript benchmark 17:55:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7659.116516622751, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.32507335996, 3319.8340082995846, 3320.651331610181, 3346.0, 3351.0784534253094, 5576.275084239043, 5624.606277560572, 5706.457886500786, 5722.914156287654, 5741.942580574193, 5643.435656434354, 5690.857728556778, 5765.942340576593, 5769.336526299477, 5773.364929857723, 5092.159793634046, 5150.845474635767, 5155.200943853709, 5180.352455943007, 5181.274621552967, 5259.0, 5284.339457567804, 5287.524122828944, 5303.939212157584, 5331.173668081454, 5119.692818430869, 5151.716655583941, 5170.482951704799, 5182.196759502283, 5241.8427447176455, 4348.760818155, 5128.743562821844, 5170.508801663836, 5177.042247184283, 5214.06146893559, 5097.668651537675, 5136.04983078128, 5148.433672296044, 5151.922721159186, 5160.8967820643375, 5590.413006634286, 5595.664260144405, 5599.216109744639, 5604.579656525736, 5608.355039170507, 5839.532837373, 6104.345061887806, 6111.40942266768, 6112.169143789106, 6122.489639783254, 6149.477294429978, 6193.783217587408, 6218.440340369343, 6278.026905829558, 6286.842828929268, 5839.474447299721, 5847.444492773222, 5861.062230043172, 5862.736176872051, 5874.030784920483, 6203.6587987660205, 6251.906221406683, 6273.059041143865, 6273.404026617464, 6280.434760871543, 6046.032634778414, 6132.6627035513475, 6143.771245750868, 6162.661053642093, 6170.167027451326, 6262.874742505215, 6274.121622972787, 6277.8744425111245, 6286.214223222098, 6306.684665766693, 6239.875202495925, 6245.18812554365, 6255.718492667841, 6257.530685198628, 6265.279492858289, 6094.299155597163, 6108.038540470994, 6116.944513560587, 6182.876342473125, 6204.410580994799, 4947.332110165082, 4959.801607935642, 4975.029869175476, 4998.575121114746, 4999.900001999939, 4408.58118478744, 4411.602955733999, 4416.227160246944, 4423.955760442354, 4448.510663826976, 5539.113741801373, 5603.187537806995, 5641.887162256732, 5691.3170419549915, 5725.370209276976, 6136.693165341804, 6183.659898705614, 6204.348543402878, 6227.782027629011, 6250.031245157008, 5673.602847800615, 5673.858153546194, 5679.119736440858, 5682.801101961411, 5692.345380281321, 5417.322834645669, 5433.0, 5433.483819037264, 5435.130379139318, 5450.073487507133, 6181.3200547938795, 6203.038529028008, 6220.595661282002, 6225.128482012522, 6280.340564240689, 6101.420365065312, 6172.444479996821, 6280.905786413116, 6283.120363149072, 6283.277423096402, 6261.874762504815, 6270.059491076193, 6280.466160376236, 6287.622742635456, 6297.590656607306, 6002.489788367863, 6116.235470566179, 6120.357362476876, 6128.448439640454, 6132.264128304453, 6137.499563105221, 6145.832291864532, 6146.938530614815, 6154.3845615438095, 6181.072839074175, 6104.853288833144, 6127.9837278401355, 6129.939558357133, 6131.468419844818, 6145.754169833157, 6108.524204008529, 6126.365000174342, 6136.764169924236, 6142.85000249909, 6146.678464130234, 5790.913136302875, 5857.092150716646, 5961.7615295387695, 5993.400659933972, 6005.759769609167, 5039.420466646383, 6080.543959202989, 6117.15760190703, 6120.051392034242, 6130.049842274455, 5934.762609495572, 5949.881002380015, 5960.97019514917, 5962.403759624003, 5964.075568286922], "value": 5675.219069521175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.1947760075914, 861.7027125641654, 875.5884734174936, 880.8502554565728, 952.9714108576745, 36921.0, 37107.628923710756, 38545.61454385452, 38555.42166867492, 38559.0, 37101.257974840555, 37379.87860364193, 37401.438978415346, 37413.625863741356, 37487.0, 7182.676779544907, 7723.575203363826, 7859.135495095564, 7867.488613240121, 7883.6058197089915, 7200.675969581355, 10692.625758098467, 10929.726756831064, 10934.234603577714, 10941.179411544166, 11155.38645374504, 11178.27341222822, 11201.215914885961, 11206.159538034639, 11233.66299011031, 7193.064901562816, 11059.778804423906, 11134.665960021171, 11145.777084458265, 11153.776924461547, 7340.082489688789, 10526.42104684242, 10762.73093172677, 10764.73088172794, 10781.191410644155, 37262.4410633838, 37569.0, 37571.0, 37642.0, 37811.62188378109, 3708.0, 3722.5160729105314, 3732.0, 3734.309152806712, 3769.7738135711943, 27386.45227095467, 27469.038583649675, 27885.721142788516, 28028.439431211467, 28932.13203603895, 3068.2789544457037, 3112.7354174895163, 3192.409404260219, 3230.2731885325616, 3247.0421225738464, 324689.0, 324861.0, 325597.0, 326030.0, 327022.0, 40027.59972400297, 40771.59228407678, 49538.54153126236, 50188.49811501838, 50364.748176259614, 40099.39850902239, 41807.79096104561, 48391.51608483941, 48587.757061215176, 48963.26555101676, 14016.439342426393, 14162.787558186636, 14171.362288697033, 14177.291135443187, 14204.218767967861, 11495.252808567584, 11540.769184616262, 11545.942270288595, 11549.711257218469, 11560.537578496935, 12323.260604363766, 12362.690932726573, 12378.75242495145, 12383.19509231897, 12480.251184928933, 5415.241866138744, 5415.268938693226, 5416.377116631637, 5432.864178395572, 5443.891122177613, 901.9278457723366, 902.179017094441, 917.9036201198778, 946.6260826973445, 948.3598570964572, 26642.86678566595, 27015.729842701716, 27304.0, 27448.313792154957, 27607.0, 990.6829814459304, 1035.8549803027581, 1039.4127318065268, 1045.451138152476, 1094.5129417409175, 13909.374078166507, 14031.0, 14294.928525357514, 14313.141211527342, 14414.063085899592, 327540.0, 331985.0, 332262.338688305, 334517.0, 354399.0, 52501.7374913123, 52821.67945801309, 52824.47175528196, 52940.73529632327, 52993.470065298854, 1383.8201033865532, 1421.289355322339, 1426.7289215049316, 1438.180237264749, 1452.4771082410323, 24029.639555407033, 27016.967925083754, 27125.321866953484, 27411.588826168023, 28465.737537741665, 42033.36949945692, 42048.15903681971, 42072.789636051624, 42224.0, 42315.730528084205, 346331.0, 347771.0, 348394.0, 348618.0, 348718.5128148686, 24589.50820983606, 26456.338591534597, 30225.639846206534, 30293.69706302997, 30727.692723072483, 38739.0, 38847.0, 40940.0, 41077.79461102675, 41097.38353924634, 27482.0, 31796.6820331803, 32410.02769916812, 33845.83077084599, 34380.96857094291, 26495.60256596191, 32619.0, 33119.33761324712, 34200.14499637429, 34253.972380828614, 33138.5029224557, 33424.1643958903, 33889.83055084709, 34058.97823065312, 34184.658153418146, 27101.1869643911, 29566.704332956397, 29739.0, 30027.849860750557, 30213.546796797626, 19139.138738756512, 21035.158593656695, 22355.217567385713, 22543.323700289016, 22733.886330568243], "value": 17758.397891579018, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.0555619286072, 398.19564479750903, 404.0968435546553, 409.6415636318222, 430.1483063534202, 13038.152520086187, 14738.852611473882, 17430.0, 20135.093920773565, 20644.587108257827, 19889.900550497263, 21185.470363240926, 21469.248576299793, 22063.779362206373, 22350.21774237902, 6379.489640828746, 6433.581817181892, 6591.274959754423, 6697.062411262427, 6751.797446076601, 3651.123730304734, 3784.2053168834577, 6435.903461448082, 6575.638339891304, 6590.406863382294, 7131.074609855336, 7450.031495905552, 7474.215207403226, 7483.989661395696, 7494.550326980371, 7253.4197264219, 7504.0, 7556.584387858666, 7576.9621151894435, 7595.962020189919, 7017.82455438613, 7232.493725439222, 7320.633968301564, 7343.41252699788, 7355.338019578264, 26900.0, 27144.592831107548, 27262.45475090487, 27299.90800367983, 27605.447891042266, 2904.8402337871407, 2934.4424559333656, 2948.469275530425, 3004.1137864329858, 3009.978733845912, 15989.600259993476, 16378.672426551522, 16461.423850165305, 16841.242144103544, 16922.323107075703, 1953.8339241164517, 1980.2079168332639, 1999.2203040814095, 2028.2596852148858, 2046.2633451957283, 125928.0, 145017.27491362582, 149845.25386865306, 152105.47894521023, 155228.0, 18228.544286392946, 20815.0, 21109.78890211109, 21925.451863703536, 22552.21067262638, 35384.46923296153, 35516.93449196528, 35552.644473555454, 35676.82161589227, 35806.104847378505, 8596.140385961478, 10197.133243674223, 10374.066334029974, 10394.948025259826, 10545.89268126851, 8301.667933282723, 8325.25072743456, 8487.448315859448, 8495.532745699045, 8495.752123938031, 8595.5702214889, 9884.654037108667, 10273.743156421, 10401.739956501147, 10465.319754215952, 3972.2651309507833, 4018.578049304839, 4055.5741647127215, 4055.655269302142, 4059.878203653895, 806.26629766913, 833.6165363932658, 870.7474832298689, 875.8511053120857, 877.6571845077042, 21495.032723527478, 22497.0, 23756.0, 24005.879970600035, 24049.879750601485, 868.3660927522943, 884.1688812516214, 895.6596493332622, 895.9104089591119, 904.6924045824451, 12140.271583705005, 12414.937925310316, 12624.621261362174, 12660.594487047616, 12687.873121268669, 521298.0, 528667.3566632143, 531181.0, 610086.0, 636376.0, 134051.0, 135852.0, 141201.0, 150827.0, 165092.17453912654, 1277.7955527115616, 1314.9737005259842, 1342.1142046249624, 1353.7360214758028, 1356.1524047470332, 16454.588635283733, 19002.619947601248, 21725.951991080845, 21799.891000544896, 22263.332100037023, 61086.38913610807, 63272.367276326644, 63771.04343434759, 63940.0, 64143.75496857588, 86380.27239455302, 86659.56670216861, 86699.56650216709, 87256.0, 87712.0, 11238.213325067167, 12089.0, 12535.310557919402, 12578.811317830423, 12663.050271229878, 13621.38703758353, 13769.31153442344, 13987.300634968007, 13991.790123148365, 14211.573652790434, 11614.477348519506, 11862.525498979945, 11996.820047699117, 12082.39588020613, 12093.637190884288, 11379.886201138213, 11603.12976526747, 11619.186656934271, 11935.522579097085, 11986.220895641756, 11617.6514704559, 11874.881251187378, 11977.448569543518, 12119.151659383759, 12169.0, 11943.701407464883, 11952.983996360406, 12141.210821296587, 12248.816267756169, 12264.509419623471, 11645.59240426581, 11888.762224755283, 12024.579139730067, 12061.577844775391, 12094.0], "value": 11630.044189913404, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1595.3538816779208, 1598.1210334316124, 1624.610093577541, 1631.6247263129483, 1686.5109118355676, 10309.742256443584, 10781.784364312709, 10783.730406739836, 10810.243282970196, 10856.240063195579, 10244.0, 10645.946770266157, 10648.520816563254, 10674.146068314536, 10674.78650426991, 2315.9305220843403, 2357.1396440299254, 2357.3870793593705, 2358.9764102359013, 2371.95256094878, 2319.8376113672048, 2352.647102934565, 2354.693889794324, 2362.728286247078, 2363.09021026905, 2315.3979965209082, 2334.3697201755517, 2342.402687314741, 2347.4600841806396, 2347.80043696286, 1988.1848442138653, 2014.9421553684285, 2183.5851188274337, 2278.8632682039047, 2290.438842483594, 2243.6298010828195, 2301.7237931448335, 2304.0783686525356, 2314.895829687668, 2316.6872472216205, 10671.893281067167, 10773.730656733565, 10802.405867677275, 10808.0, 10812.297200681931, 5560.7219639017885, 5808.273965754281, 5823.82528524145, 5827.650340979555, 5828.9417105828825, 3252.203210213489, 3336.3827691876836, 3569.464580312948, 3591.982040089809, 3603.7297202709638, 1072.9570817167382, 1141.5547936304765, 1187.0207079159727, 1192.0940085535055, 1193.9761204775855, 13505.594832155051, 13846.723065538632, 13885.37515811791, 14891.47879824201, 14953.177575233358, 4537.659675524349, 4697.6241900647865, 4719.646026548023, 4729.6452766042685, 4733.721895088307, 11164.776704465865, 11172.385518796544, 11182.888171118348, 11213.55145794159, 11216.102711783038, 4768.213244814636, 4946.678465899705, 4955.108080545464, 5650.74571644277, 5676.403977582377, 4995.925061124013, 5197.272381866542, 5225.790968361223, 5227.921581176285, 5247.947520524746, 3763.1909139535132, 3855.5951625079524, 3860.9227815444096, 3864.8260828262796, 3868.5357757069332, 913.6847787513298, 1026.035526604989, 1035.1304903880775, 1041.4792603698152, 1046.8173857507184, 803.9879401808978, 833.2084519706303, 907.5689047702289, 909.077286554148, 946.1106559833735, 6155.815325540241, 6192.318844927055, 6438.227412710411, 6443.097966284724, 6561.836716391052, 902.5712786426395, 1013.341328136717, 1017.4302390661253, 1028.2031425645184, 1060.8514807926897, 3510.052285882797, 4195.91608167835, 4216.430781844473, 4232.492100947844, 4243.405923170759, 12555.686107847194, 12952.740945181044, 12957.157784743962, 12990.935045324903, 13039.087263891626, 12607.810882836764, 13097.21416714981, 13106.410211540504, 13114.04933889867, 13126.868731312565, 9526.047395260419, 9821.607135714492, 9839.704808855746, 9847.45838978863, 9851.0, 5509.256250406143, 6319.8104056878365, 6482.35176482348, 6542.084108224757, 6598.439132673777, 11519.0, 11904.0, 11905.226160299553, 11923.0, 11944.163908526667, 10573.0, 10818.459077046273, 11116.777664446827, 11986.280823150639, 12021.038316934799, 9337.859932100888, 9466.148046675833, 9820.852687209555, 9908.801823963624, 9915.058069483388, 9773.755656108655, 9777.335489488742, 9890.159336456194, 10014.0, 10055.446950417798, 8946.37375383743, 8989.325800564853, 9063.68277110298, 9122.77193070152, 9174.174324310578, 8684.2184203422, 8747.081556436737, 8754.08082151365, 8851.159139881958, 8861.424007439497, 7677.500962437647, 7732.79615309634, 7778.105517865518, 7811.296983271533, 7847.9607601961625, 5782.335031471434, 5900.734466948912, 5906.084556893688, 5997.190379298739, 6017.127516510169, 5809.477147056785, 5890.882182356243, 5915.792947246826, 5920.230370051866, 6037.486813620716], "value": 5005.461023850035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.33952413514277, 382.1897577136471, 386.75634350359286, 387.5272167955094, 388.44646378910056, 5539.113741801312, 5558.194061861028, 5614.9157762633495, 5638.323401191858, 5805.187273781674, 4441.180584697166, 5515.669059856412, 5662.207290979256, 5672.971635141829, 5678.063119585264, 3970.8808735737975, 4551.156781503496, 4635.172544747701, 4678.836240731575, 4711.93981354196, 4698.859034228979, 4698.882527936795, 4703.412073490814, 4706.22347312693, 4727.881802954919, 3899.805009749515, 4660.743659098748, 4685.484596694361, 4704.764761761915, 4744.956109655887, 4525.216991550205, 4607.838725644587, 4711.623070154379, 4716.490722968651, 4753.16819556578, 4249.660027197817, 4265.3815196796295, 4283.243870013311, 4299.462567179103, 4304.246756817544, 5533.557315414757, 5535.557155427556, 5547.25112109864, 5563.748156664758, 5572.916406253909, 4401.537838526972, 4626.4910859805395, 5387.4073851876265, 5435.021696094701, 5468.0, 5771.711414429262, 6049.304330002062, 6074.362191969868, 6099.298580663191, 6114.908276375813, 4813.170995021905, 4819.481863213076, 4835.927461088087, 4863.829765958175, 4868.902621947576, 5253.86865328366, 5437.13005919051, 5527.866787308592, 6055.212822333113, 6266.154069200599, 4177.352510360899, 4704.9059018819435, 4794.95205047952, 4857.562819346276, 4922.827701030446, 3862.9570016095495, 4280.357946308017, 4875.95124048762, 5050.166722490748, 5052.595792336604, 5295.391030031596, 5405.189221616789, 5438.184272359178, 5445.210444485528, 5461.371942226695, 5322.813701520428, 6041.697915104227, 6054.424829641177, 6060.484858786965, 6093.421124993119, 1536.5083173384633, 1635.5420482264985, 1722.6296346285608, 1725.6462425202803, 1731.2642127095935, 1245.8318127052733, 1496.4687535924809, 1547.3887814313284, 1553.223388305847, 1564.8043994500688, 4783.9475315430545, 4793.976030119827, 5656.91514627281, 5664.660120392789, 5667.659940403589, 4863.781129849165, 5699.0, 6174.0, 6246.6876656167915, 6269.49844012487, 4383.276759334674, 5043.965986972662, 5095.0828850806465, 5413.512783849473, 5630.5214056805635, 2754.476649436601, 3241.011491495117, 3310.1062713067818, 3313.353895990259, 3328.1513214130728, 5694.5159661428415, 6400.263969643551, 6486.513511486657, 6513.837154071186, 6529.967350163219, 6038.818835434944, 6065.787697430569, 6433.815261445614, 6455.289918109004, 6460.676966151674, 6455.419012288917, 6496.740130394731, 6507.479401647857, 6512.73949042033, 6526.0, 5836.474717275466, 5965.34381218057, 6169.290531588925, 6258.624482531062, 6264.49884009269, 5404.8108316208745, 5900.911486327623, 6065.909011364744, 6178.783742568989, 6252.781152659635, 5352.518273355261, 5906.586538942404, 6273.560850740587, 6336.275401577366, 6381.3937675920715, 4012.254669218899, 4111.239420707059, 4884.48712885142, 4902.926456103232, 4906.582940450102, 5990.580659353804, 6251.374862513894, 6292.496600271876, 6343.460805831556, 6351.936480635134, 5342.679439233503, 5826.737796799239, 6097.146399503984, 6128.233970753656, 6152.415520525543, 5765.365809761007, 6031.793641271675, 6094.653558379967, 6125.816225513242, 6138.5396095292135, 4963.007398520383, 4998.550130488274, 5929.911051334319, 5930.406959304035, 5949.167116603766, 5976.9103463448955, 6056.121862329849, 6057.848553786191, 6174.660393678211, 6248.250209974832, 5675.404082571271, 5707.571932105192, 5913.142594323885, 5946.940530594638, 5962.791302304398], "value": 4647.657768764007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.3117563017655, 761.9619019049046, 813.7802793245822, 822.4982760516084, 841.3689732700475, 14545.41818327267, 15253.847461525382, 15289.694206115872, 15296.311665975043, 15326.92336538317, 14066.92966535166, 14287.571372858805, 14340.354684039217, 14354.856451435482, 14379.0, 953.818774432859, 965.666844938495, 986.0041358228206, 987.2052997337141, 989.3519744567284, 995.7162208770502, 998.4658207858797, 1010.656376831877, 1011.3426272922586, 1013.8732658417698, 1053.752368193474, 1054.5096530113497, 1058.7194393485731, 1059.1156384419019, 1059.1579694143174, 1046.905778479933, 1047.4919663962971, 1047.7537778622018, 1048.1457612046197, 1051.4217180550743, 779.0145465985532, 780.0989856715503, 781.86708259596, 784.8744200927881, 786.202004964961, 15721.68556628861, 15930.522084337488, 16164.434244801492, 16375.263113159936, 16435.83564164355, 14433.422663093465, 14494.710105797825, 14706.926465367711, 14725.484608038667, 14820.258987050713, 11569.305841649528, 11757.235779674294, 11878.762424751543, 11880.168388212829, 12162.027037836951, 3605.369060414443, 3711.3505136601257, 3728.589855115962, 3731.0, 3854.2676891390824, 106877.0, 107661.0, 107809.0, 108090.45954770254, 109291.45354273258, 10582.57669693219, 10862.129909440739, 11198.888011119785, 11403.144764142722, 11802.173847830569, 24332.635010474856, 24667.3833154169, 24856.0, 24920.5015899681, 24945.62581561278, 7688.500247483896, 7764.883526747103, 7877.054753429625, 7924.683012679428, 8050.074241462306, 4093.181363727207, 4116.670666346685, 4202.579742025834, 4299.78501074945, 4378.08060307339, 3491.720662347057, 3584.551931008624, 3585.6414358563934, 3589.3000864831106, 3594.0475773919934, 501.633807320658, 664.58131377233, 669.2337273821447, 670.9329067093349, 671.684308375063, 643.488426700774, 653.8724948635065, 673.9966300168468, 688.2016860441863, 689.741346994877, 7931.405144614062, 8939.865902011476, 10009.099181073738, 10240.0, 10359.274850760374, 555.7304707216997, 575.3843387575255, 668.4552090046597, 684.5071548485083, 725.300085417564, 4125.608067233608, 4846.539578740014, 4886.169351210303, 4974.950250497449, 5004.574611158092, 73180.0, 79550.44270794265, 88863.55568222118, 89426.76433089224, 89432.5528372367, 79735.60132199302, 80735.59632201801, 82440.0, 83890.58054709688, 83963.0, 70100.2989970104, 71779.28220717725, 71783.0, 73204.0, 73284.99999999894, 9398.718038458857, 9435.386699864486, 9473.810523789623, 9482.85775713351, 9547.952260238655, 44259.77870110629, 44912.77543612261, 45372.092558149314, 45440.31839522476, 46363.0, 59198.11202831875, 59979.50051248754, 60008.699956499935, 60467.0, 61043.0, 10552.314099583502, 11518.712032198926, 11594.362310073027, 11867.228630139014, 12080.275183489019, 12344.0, 12628.747425051632, 12635.305058221886, 12756.107072504836, 12820.974322054026, 11344.659660210207, 11445.885541144482, 11719.941400292944, 11990.75253032129, 12061.819072714092, 11345.716357091138, 11548.280597044748, 11655.0, 12062.155649104478, 12314.692132696397, 11472.082233421139, 11605.0, 11757.823632645346, 11995.820062698893, 12037.0, 10104.725313504978, 10215.131424928219, 10231.908741632622, 10428.165746740091, 10478.64433653775, 8483.600205965946, 9217.76955576116, 9452.291078169303, 9906.801863962824, 9957.302988790716], "value": 7402.681848400985, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1473.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 114.0, 116.0, 116.0, 117.0, 115.0, 115.0, 114.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 105.0, 116.0, 153.0, 136.0, 141.0, 139.0, 142.0, 135.0, 113.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 181.0, 186.0, 187.0, 187.0, 183.0, 183.0, 185.0, 182.0, 184.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 58.0, 89.0, 62.0, 59.0, 72.0, 78.0, 105.0, 81.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 88.0, 68.0, 70.0, 67.0, 82.0, 54.0, 64.0, 91.0], "value": 74.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 86.0, 86.0, 87.0, 85.0, 85.0, 86.0, 86.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 94.0, 94.0, 99.0, 93.0, 95.0, 93.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 85.0, 86.0, 83.0, 85.0, 84.0, 86.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 61.0, 62.0, 61.0, 62.0, 63.0, 61.0, 61.0, 60.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 63.0, 63.0, 64.0, 59.0, 59.0, 58.0, 61.0, 59.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 101.0, 87.0, 83.0, 84.0, 83.0, 84.0, 83.0, 83.0, 82.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 137.0, 137.0, 134.0, 145.0, 149.0, 144.0, 177.0, 152.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 214.0, 189.0, 188.0, 207.0, 230.0, 233.0, 186.0, 184.0, 184.0], "value": 203.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 69.0, 67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 66.0], "value": 67.9, "unit": "score"}]}]} 17:55:51 INFO - Return code: 0 17:55:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:55:51 ERROR - # TBPL SUCCESS # 17:55:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:55:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:55:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:55:51 INFO - 2017-03-21 17:55:51.193 screenresolution[1650:9967] starting screenresolution argv=screenresolution get 17:55:51 INFO - 2017-03-21 17:55:51.195 screenresolution[1650:9967] Display 0: 1600x1200x32@60 17:55:51 INFO - 2017-03-21 17:55:51.201 screenresolution[1651:9970] starting screenresolution argv=screenresolution list 17:55:51 INFO - Available Modes on Display 0 17:55:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:55:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:55:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:55:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:55:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:55:51 INFO - Intel Iris: 17:55:51 INFO - Chipset Model: Intel Iris 17:55:51 INFO - Type: GPU 17:55:51 INFO - Bus: Built-In 17:55:51 INFO - VRAM (Dynamic, Max): 1536 MB 17:55:51 INFO - Vendor: Intel (0x8086) 17:55:51 INFO - Device ID: 0x0a2e 17:55:51 INFO - Revision ID: 0x0009 17:55:51 INFO - Displays: 17:55:51 INFO - Display: 17:55:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:55:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:55:51 INFO - Display Serial Number: 1600x1200 60 17:55:51 INFO - Main Display: Yes 17:55:51 INFO - Mirror: Off 17:55:51 INFO - Online: Yes 17:55:51 INFO - Rotation: Supported 17:55:51 INFO - Return code: 0 17:55:51 INFO - Running post-action listener: _resource_record_post_action 17:55:51 INFO - [mozharness: 2017-03-22 00:55:51.435514Z] Finished run-tests step (success) 17:55:51 INFO - Running post-run listener: _resource_record_post_run 17:55:51 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32717824; Write bytes: 512586752; Read time: 200; Write time: 1550 17:55:51 INFO - TinderboxPrint: CPU usage
53.6% 17:55:51 INFO - TinderboxPrint: I/O read bytes / time
32,717,824 / 200 17:55:51 INFO - TinderboxPrint: I/O write bytes / time
512,586,752 / 1,550 17:55:51 INFO - TinderboxPrint: CPU idle
2,231.4 (46.4%) 17:55:51 INFO - TinderboxPrint: CPU system
959.1 (19.9%) 17:55:51 INFO - TinderboxPrint: CPU user
1,619.2 (33.7%) 17:55:51 INFO - TinderboxPrint: Swap in / out
238,518,272 / 0 17:55:51 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203584000; Write bytes: 201138176; Read time: 15799; Write time: 419 17:55:51 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 30931968; Write bytes: 300643328; Read time: 186; Write time: 1100 17:55:51 INFO - Running post-run listener: _upload_blobber_files 17:55:51 INFO - Blob upload gear active. 17:55:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:55:51 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 17:55:51 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:55:51 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:55:52 INFO - (blobuploader) - INFO - Open directory for files ... 17:55:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:55:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:55:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:53 INFO - (blobuploader) - INFO - Done attempting. 17:55:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:55:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:55:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:54 INFO - (blobuploader) - INFO - Done attempting. 17:55:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:55:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:55:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:55 INFO - (blobuploader) - INFO - Done attempting. 17:55:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:55:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:55:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:57 INFO - (blobuploader) - INFO - Done attempting. 17:55:57 INFO - (blobuploader) - INFO - Iteration through files over. 17:55:57 INFO - Return code: 0 17:55:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:55:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:55:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/813062beab495aa7f9cfba58dbc340912cff5508339a33b9e470712a67ddd8bd316979f670214a00d6533efa1d1cd5884ad91446d8f014cf966fd53546f08a70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/047fb5e28a3b652edb04113b0589990ebf7b8552f664e2f984e0690819c39bd7258ddae0712e6a507e33dc3e8e94b9fce71eb0280ab16e9ad67568300e1b56c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/24e43b867a18643aa9701413bf43d7bec6eea58619d28bcc229f396659ae4fe79d95724150daed9e52704407932613f5e7c174e8bce0eb60c2e79335c8eff58b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8903d984b5f750c9e4b4c30d9620a2b38299c8b90c99add08cd61b187de48f304df6c25a34230613e1c5a0949fc0ce5d1e698c70cee2e183a65b8f742ceff871"} 17:55:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:55:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:55:57 INFO - Contents: 17:55:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/813062beab495aa7f9cfba58dbc340912cff5508339a33b9e470712a67ddd8bd316979f670214a00d6533efa1d1cd5884ad91446d8f014cf966fd53546f08a70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/047fb5e28a3b652edb04113b0589990ebf7b8552f664e2f984e0690819c39bd7258ddae0712e6a507e33dc3e8e94b9fce71eb0280ab16e9ad67568300e1b56c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/24e43b867a18643aa9701413bf43d7bec6eea58619d28bcc229f396659ae4fe79d95724150daed9e52704407932613f5e7c174e8bce0eb60c2e79335c8eff58b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8903d984b5f750c9e4b4c30d9620a2b38299c8b90c99add08cd61b187de48f304df6c25a34230613e1c5a0949fc0ce5d1e698c70cee2e183a65b8f742ceff871"} 17:55:57 INFO - Running post-run listener: copy_logs_to_upload_dir 17:55:57 INFO - Copying logs to upload dir... 17:55:57 INFO - mkdir: /builds/slave/test/build/upload/logs 17:55:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.777340 ========= master_lag: 0.78 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-21 17:55:57.872244) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 17:55:57.877434) ========= 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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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/Pine/sha512/813062beab495aa7f9cfba58dbc340912cff5508339a33b9e470712a67ddd8bd316979f670214a00d6533efa1d1cd5884ad91446d8f014cf966fd53546f08a70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/047fb5e28a3b652edb04113b0589990ebf7b8552f664e2f984e0690819c39bd7258ddae0712e6a507e33dc3e8e94b9fce71eb0280ab16e9ad67568300e1b56c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/24e43b867a18643aa9701413bf43d7bec6eea58619d28bcc229f396659ae4fe79d95724150daed9e52704407932613f5e7c174e8bce0eb60c2e79335c8eff58b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8903d984b5f750c9e4b4c30d9620a2b38299c8b90c99add08cd61b187de48f304df6c25a34230613e1c5a0949fc0ce5d1e698c70cee2e183a65b8f742ceff871"} build_url:https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011154 build_url: 'https://queue.taskcluster.net/v1/task/S5EkVy5BSvy1uNkt6DvTyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/813062beab495aa7f9cfba58dbc340912cff5508339a33b9e470712a67ddd8bd316979f670214a00d6533efa1d1cd5884ad91446d8f014cf966fd53546f08a70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/047fb5e28a3b652edb04113b0589990ebf7b8552f664e2f984e0690819c39bd7258ddae0712e6a507e33dc3e8e94b9fce71eb0280ab16e9ad67568300e1b56c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/24e43b867a18643aa9701413bf43d7bec6eea58619d28bcc229f396659ae4fe79d95724150daed9e52704407932613f5e7c174e8bce0eb60c2e79335c8eff58b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8903d984b5f750c9e4b4c30d9620a2b38299c8b90c99add08cd61b187de48f304df6c25a34230613e1c5a0949fc0ce5d1e698c70cee2e183a65b8f742ceff871"}' ========= master_lag: 0.12 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 17:55:58.004072) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:55:58.004417) ========= 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.EyLfYLJVQW/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.VsgEDrp0k1/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.005061 ========= master_lag: 0.60 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:55:58.608966) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 17:55:58.609266) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 17:55:58.609746) ========= ========= Total master_lag: 1.58 =========