builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0238 starttime: 1478267926.8 results: success (0) buildid: 20161104040321 builduid: 055d803641b843589f666e7e0aa02bd9 revision: fbf61bcc0fa52b238d0a88e567a189d00b407613 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:46.796909) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:46.797441) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:46.797900) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:46.823955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:46.824524) ========= 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.gMvYmmvMSS/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.iM2jq0htKk/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-04 06:58:46-- 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% 27.8M=0s 2016-11-04 06:58:46 (27.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113057 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:46.962939) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:46.963506) ========= 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.gMvYmmvMSS/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.iM2jq0htKk/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.025192 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:47.013004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:47.013369) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev fbf61bcc0fa52b238d0a88e567a189d00b407613 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev fbf61bcc0fa52b238d0a88e567a189d00b407613 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gMvYmmvMSS/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.iM2jq0htKk/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-04 06:58:47,094 truncating revision to first 12 chars 2016-11-04 06:58:47,094 Setting DEBUG logging. 2016-11-04 06:58:47,094 attempt 1/10 2016-11-04 06:58:47,094 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/fbf61bcc0fa5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-04 06:58:47,635 unpacking tar archive at: larch-fbf61bcc0fa5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.804070 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:47.839262) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:47.839581) ========= script_repo_revision: fbf61bcc0fa52b238d0a88e567a189d00b407613 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:47.839955) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:47.840237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 06:58:47.858740) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-04 06:58:47.859169) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gMvYmmvMSS/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.iM2jq0htKk/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:58:48 INFO - MultiFileLogger online at 20161104 06:58:48 in /builds/slave/test 06:58:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 06:58:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:58:48 INFO - {'append_to_log': False, 06:58:48 INFO - 'base_work_dir': '/builds/slave/test', 06:58:48 INFO - 'blob_upload_branch': 'Larch', 06:58:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:58:48 INFO - 'branch': 'Larch', 06:58:48 INFO - 'buildbot_json_path': 'buildprops.json', 06:58:48 INFO - 'config_files': ('talos/mac_config.py',), 06:58:48 INFO - 'default_actions': ('clobber', 06:58:48 INFO - 'read-buildbot-config', 06:58:48 INFO - 'download-and-extract', 06:58:48 INFO - 'populate-webroot', 06:58:48 INFO - 'create-virtualenv', 06:58:48 INFO - 'install', 06:58:48 INFO - 'run-tests'), 06:58:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:58:48 INFO - 'download_minidump_stackwalk': True, 06:58:48 INFO - 'download_symbols': 'ondemand', 06:58:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:58:48 INFO - 'tooltool.py': '/tools/tooltool.py', 06:58:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:58:48 INFO - '/tools/misc-python/virtualenv.py')}, 06:58:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:58:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:58:48 INFO - 'installer_path': 'installer.exe', 06:58:48 INFO - 'log_level': 'info', 06:58:48 INFO - 'log_name': 'talos', 06:58:48 INFO - 'log_to_console': True, 06:58:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:58:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:58:48 INFO - 'opt_config_files': (), 06:58:48 INFO - 'pip_index': False, 06:58:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:58:48 INFO - 'cmd': ('bash', 06:58:48 INFO - '-c', 06:58:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:58:48 INFO - 'enabled': True, 06:58:48 INFO - 'halt_on_failure': False, 06:58:48 INFO - 'name': 'check_screen_resolution'},), 06:58:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:58:48 INFO - 'cmd': ('bash', 06:58:48 INFO - '-c', 06:58:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:58:48 INFO - 'enabled': True, 06:58:48 INFO - 'halt_on_failure': False, 06:58:48 INFO - 'name': 'check_screen_resolution'},), 06:58:48 INFO - 'run_cmd_checks_enabled': True, 06:58:48 INFO - 'sps_profile': False, 06:58:48 INFO - 'sps_profile_interval': 0, 06:58:48 INFO - 'suite': 'dromaeojs', 06:58:48 INFO - 'system_bits': '32', 06:58:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:58:48 INFO - 'title': 't-yosemite-r7-0238', 06:58:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:58:48 INFO - 'use_talos_json': True, 06:58:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:58:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:58:48 INFO - 'work_dir': 'build'} 06:58:48 INFO - [mozharness: 2016-11-04 13:58:48.061764Z] Running clobber step. 06:58:48 INFO - Running pre-action listener: _resource_record_pre_action 06:58:48 INFO - Running main action method: clobber 06:58:48 INFO - rmtree: /builds/slave/test/build 06:58:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:58:50 INFO - Running post-action listener: _resource_record_post_action 06:58:50 INFO - [mozharness: 2016-11-04 13:58:50.220227Z] Finished clobber step (success) 06:58:50 INFO - [mozharness: 2016-11-04 13:58:50.220391Z] Running read-buildbot-config step. 06:58:50 INFO - Running pre-action listener: _resource_record_pre_action 06:58:50 INFO - Running main action method: read_buildbot_config 06:58:50 INFO - Using buildbot properties: 06:58:50 INFO - { 06:58:50 INFO - "project": "", 06:58:50 INFO - "product": "firefox", 06:58:50 INFO - "script_repo_revision": "production", 06:58:50 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 06:58:50 INFO - "repository": "", 06:58:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 06:58:50 INFO - "buildid": "20161104040321", 06:58:50 INFO - "pgo_build": "False", 06:58:50 INFO - "basedir": "/builds/slave/test", 06:58:50 INFO - "buildnumber": 8, 06:58:50 INFO - "slavename": "t-yosemite-r7-0238", 06:58:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:58:50 INFO - "platform": "yosemite_r7", 06:58:50 INFO - "scheduler": "tests-larch-macosx64-talos", 06:58:50 INFO - "branch": "larch", 06:58:50 INFO - "repo_path": "projects/larch", 06:58:50 INFO - "revision": "fbf61bcc0fa52b238d0a88e567a189d00b407613", 06:58:50 INFO - "stage_platform": "macosx64", 06:58:50 INFO - "builduid": "055d803641b843589f666e7e0aa02bd9", 06:58:50 INFO - "slavebuilddir": "test" 06:58:50 INFO - } 06:58:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:58:50 INFO - Running post-action listener: _resource_record_post_action 06:58:50 INFO - [mozharness: 2016-11-04 13:58:50.223721Z] Finished read-buildbot-config step (success) 06:58:50 INFO - [mozharness: 2016-11-04 13:58:50.223829Z] Running download-and-extract step. 06:58:50 INFO - Running pre-action listener: _resource_record_pre_action 06:58:50 INFO - Running main action method: download_and_extract 06:58:50 INFO - mkdir: /builds/slave/test/build/tests 06:58:50 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')]} 06:58:50 INFO - trying https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:58:50 INFO - Downloading https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:58:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 06:58:51 INFO - Downloaded 1199 bytes. 06:58:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:58:51 INFO - Using the following test package requirements: 06:58:51 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:58:51 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:58:51 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:58:51 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'jsshell-mac.zip'], 06:58:51 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:58:51 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:58:51 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:58:51 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:58:51 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:58:51 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:58:51 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:58:51 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:58:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:58:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:58:51 INFO - Fetch https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:58:52 INFO - Http code: 200 06:58:52 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 06:58:52 INFO - x-amz-cf-id: jEkFB__VEMgXPwGvJJTKEuATKAFK6EYDhFbTgnnl2_KyuMGpwG9JLQ== 06:58:52 INFO - x-amz-version-id: i2R4eh4fTZIeT6JtEV6hxlRj4LFlaaFG 06:58:52 INFO - Expected file size: 16404836 06:58:52 INFO - Obtained file size: 16404836 06:58:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:58:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:58:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:58:59 INFO - Fetch https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:58:59 INFO - Http code: 200 06:58:59 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 06:58:59 INFO - x-amz-cf-id: o0jhy6pfC4KbEjbMwN7Fn9LVyXsWYEB9kSsKurDA1IcymiGdIXsnYA== 06:58:59 INFO - x-amz-version-id: i2R4eh4fTZIeT6JtEV6hxlRj4LFlaaFG 06:59:00 INFO - Expected file size: 16404836 06:59:00 INFO - Obtained file size: 16404836 06:59:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:59:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:59:06 INFO - Fetch https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:59:06 INFO - Http code: 200 06:59:06 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 06:59:06 INFO - x-amz-cf-id: 8pPVB-sf4UexrGNpcggoiRxrMRMj3ZCoqiw7l-8HMUK6TRQl7jofEA== 06:59:06 INFO - x-amz-version-id: sbHDIdb_3w90uuHClZ4cEyA4xoiAbN0B 06:59:07 INFO - Expected file size: 14409038 06:59:07 INFO - Obtained file size: 14409038 06:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:07 INFO - trying https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:59:07 INFO - Downloading https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:59:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 06:59:10 INFO - Downloaded 112113715 bytes. 06:59:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:59:10 INFO - mkdir: /builds/slave/test/properties 06:59:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:59:10 INFO - Writing to file /builds/slave/test/properties/build_url 06:59:10 INFO - Contents: 06:59:10 INFO - build_url:https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:59:11 INFO - Running post-action listener: _resource_record_post_action 06:59:11 INFO - Running post-action listener: set_extra_try_arguments 06:59:11 INFO - [mozharness: 2016-11-04 13:59:11.057020Z] Finished download-and-extract step (success) 06:59:11 INFO - [mozharness: 2016-11-04 13:59:11.057144Z] Running populate-webroot step. 06:59:11 INFO - Running pre-action listener: _resource_record_pre_action 06:59:11 INFO - Running main action method: populate_webroot 06:59:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:59:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:59:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:59:11 INFO - '--tppagecycles', 06:59:11 INFO - '7'], 06:59:11 INFO - 'tests': ['tsvgm']}}, 06:59:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:59:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:59:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:59:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:59:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:59:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:59:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['damp', 'tps']}, 06:59:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'tests': ['damp', 'tps']}, 06:59:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['dromaeo_dom']}, 06:59:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:59:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['basic_compositor_video']}, 06:59:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:59:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['a11yr', 06:59:11 INFO - 'ts_paint', 06:59:11 INFO - 'tpaint', 06:59:11 INFO - 'sessionrestore', 06:59:11 INFO - 'sessionrestore_no_auto_restore', 06:59:11 INFO - 'tabpaint']}, 06:59:11 INFO - 'other-e10s': {'tests': ['a11yr', 06:59:11 INFO - 'ts_paint', 06:59:11 INFO - 'tpaint', 06:59:11 INFO - 'sessionrestore', 06:59:11 INFO - 'sessionrestore_no_auto_restore', 06:59:11 INFO - 'tabpaint']}, 06:59:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:59:11 INFO - 'ts_paint', 06:59:11 INFO - 'tpaint', 06:59:11 INFO - 'sessionrestore', 06:59:11 INFO - 'sessionrestore_no_auto_restore', 06:59:11 INFO - 'tabpaint']}, 06:59:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:59:11 INFO - 'ts_paint', 06:59:11 INFO - 'tpaint', 06:59:11 INFO - 'sessionrestore', 06:59:11 INFO - 'sessionrestore_no_auto_restore', 06:59:11 INFO - 'tabpaint']}, 06:59:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['a11yr', 06:59:11 INFO - 'ts_paint', 06:59:11 INFO - 'tpaint', 06:59:11 INFO - 'sessionrestore', 06:59:11 INFO - 'sessionrestore_no_auto_restore', 06:59:11 INFO - 'tabpaint']}, 06:59:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['a11yr', 06:59:11 INFO - 'ts_paint', 06:59:11 INFO - 'tpaint', 06:59:11 INFO - 'sessionrestore', 06:59:11 INFO - 'sessionrestore_no_auto_restore', 06:59:11 INFO - 'tabpaint']}, 06:59:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['tsvgx', 06:59:11 INFO - 'tsvgr_opacity', 06:59:11 INFO - 'tart', 06:59:11 INFO - 'tscrollx', 06:59:11 INFO - 'cart']}, 06:59:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:59:11 INFO - 'tsvgr_opacity', 06:59:11 INFO - 'tart', 06:59:11 INFO - 'tscrollx', 06:59:11 INFO - 'cart']}, 06:59:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'talos_options': ['--disable-e10s'], 06:59:11 INFO - 'tests': ['tp5o']}, 06:59:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'tests': ['tp5o']}, 06:59:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'talos_options': ['--disable-e10s', 06:59:11 INFO - '--xperf_path', 06:59:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:59:11 INFO - 'tests': ['tp5n']}, 06:59:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:59:11 INFO - 'talos_options': ['--xperf_path', 06:59:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:59:11 INFO - 'tests': ['tp5n']}}, 06:59:11 INFO - 'talos.zip': {'path': '', 06:59:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:59:11 INFO - Running post-action listener: _resource_record_post_action 06:59:11 INFO - [mozharness: 2016-11-04 13:59:11.076068Z] Finished populate-webroot step (success) 06:59:11 INFO - [mozharness: 2016-11-04 13:59:11.076203Z] Running create-virtualenv step. 06:59:11 INFO - Running pre-action listener: _resource_record_pre_action 06:59:11 INFO - Running main action method: create_virtualenv 06:59:11 INFO - Creating virtualenv /builds/slave/test/build/venv 06:59:11 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 06:59:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:59:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:59:11 INFO - Using real prefix '/tools/python27' 06:59:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:59:11 INFO - Installing distribute.............................................................................................................................................................................................done. 06:59:14 INFO - Installing pip.................done. 06:59:14 INFO - Return code: 0 06:59:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:59:15 INFO - Reading from file tmpfile_stdout 06:59:15 INFO - Output received: 06:59:15 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) 06:59:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:59:15 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')]} 06:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:15 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')]} 06:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:15 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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:15 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 06:59:15 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 06:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:15 INFO - 'HOME': '/Users/cltbld', 06:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:15 INFO - 'LOGNAME': 'cltbld', 06:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:15 INFO - 'MOZ_NO_REMOTE': '1', 06:59:15 INFO - 'NO_EM_RESTART': '1', 06:59:15 INFO - 'PAGER': '/bin/cat', 06:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:15 INFO - 'PWD': '/builds/slave/test', 06:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:15 INFO - 'SHELL': '/bin/bash', 06:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:15 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:15 INFO - 'USER': 'cltbld', 06:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:15 INFO - 'XPC_FLAGS': '0x0', 06:59:15 INFO - 'XPC_SERVICE_NAME': '0', 06:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:15 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 06:59:15 INFO - Cleaning up... 06:59:15 INFO - Return code: 0 06:59:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:59:15 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')]} 06:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:15 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')]} 06:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:15 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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:15 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 06:59:15 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 06:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:15 INFO - 'HOME': '/Users/cltbld', 06:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:15 INFO - 'LOGNAME': 'cltbld', 06:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:15 INFO - 'MOZ_NO_REMOTE': '1', 06:59:15 INFO - 'NO_EM_RESTART': '1', 06:59:15 INFO - 'PAGER': '/bin/cat', 06:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:15 INFO - 'PWD': '/builds/slave/test', 06:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:15 INFO - 'SHELL': '/bin/bash', 06:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:15 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:15 INFO - 'USER': 'cltbld', 06:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:15 INFO - 'XPC_FLAGS': '0x0', 06:59:15 INFO - 'XPC_SERVICE_NAME': '0', 06:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:15 INFO - Downloading/unpacking psutil>=3.1.1 06:59:15 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 06:59:15 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 06:59:15 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 06:59:15 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 06:59:15 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 06:59:15 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 06:59:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:59:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:59:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:59:18 INFO - Installing collected packages: psutil 06:59:18 INFO - Running setup.py install for psutil 06:59:19 INFO - building 'psutil._psutil_osx' extension 06:59:19 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 06:59:19 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 06:59:19 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 06:59:19 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 06:59:19 INFO - building 'psutil._psutil_posix' extension 06:59:19 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 06:59:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:59:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:59:19 INFO - ^ 06:59:19 INFO - 1 warning generated. 06:59:19 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 06:59:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:59:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:59:20 INFO - Successfully installed psutil 06:59:20 INFO - Cleaning up... 06:59:20 INFO - Return code: 0 06:59:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:59: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')]} 06:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59: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')]} 06:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:59: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 mozsystemmonitor==0.3 06:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:20 INFO - 'HOME': '/Users/cltbld', 06:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:20 INFO - 'LOGNAME': 'cltbld', 06:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:20 INFO - 'MOZ_NO_REMOTE': '1', 06:59:20 INFO - 'NO_EM_RESTART': '1', 06:59:20 INFO - 'PAGER': '/bin/cat', 06:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:20 INFO - 'PWD': '/builds/slave/test', 06:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:20 INFO - 'SHELL': '/bin/bash', 06:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:20 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:20 INFO - 'USER': 'cltbld', 06:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:20 INFO - 'XPC_FLAGS': '0x0', 06:59:20 INFO - 'XPC_SERVICE_NAME': '0', 06:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:59: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 06:59: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 06:59: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 06:59: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 06:59: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 06:59: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 06:59:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:59:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:59:23 INFO - Installing collected packages: mozsystemmonitor 06:59:23 INFO - Running setup.py install for mozsystemmonitor 06:59:23 INFO - Successfully installed mozsystemmonitor 06:59:23 INFO - Cleaning up... 06:59:23 INFO - Return code: 0 06:59:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:59:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:59:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:23 INFO - 'HOME': '/Users/cltbld', 06:59:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:23 INFO - 'LOGNAME': 'cltbld', 06:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:23 INFO - 'MOZ_NO_REMOTE': '1', 06:59:23 INFO - 'NO_EM_RESTART': '1', 06:59:23 INFO - 'PAGER': '/bin/cat', 06:59:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:23 INFO - 'PWD': '/builds/slave/test', 06:59:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:23 INFO - 'SHELL': '/bin/bash', 06:59:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:23 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:23 INFO - 'USER': 'cltbld', 06:59:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:23 INFO - 'XPC_FLAGS': '0x0', 06:59:23 INFO - 'XPC_SERVICE_NAME': '0', 06:59:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:23 INFO - Downloading/unpacking jsonschema==2.5.1 06:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:59:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:59:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:59:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:59:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:59:26 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 06:59:26 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 06:59:26 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 06:59:26 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 06:59:26 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 06:59:26 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 06:59:26 INFO - Downloading functools32-3.2.3-2.tar.gz 06:59:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:59:26 INFO - warning: no files found matching '*.txt' 06:59:26 INFO - no previously-included directories found matching 'build' 06:59:26 INFO - no previously-included directories found matching 'dist' 06:59:26 INFO - no previously-included directories found matching '.git*' 06:59:26 INFO - Installing collected packages: jsonschema, functools32 06:59:26 INFO - Running setup.py install for functools32 06:59:26 INFO - warning: no files found matching '*.txt' 06:59:26 INFO - no previously-included directories found matching 'build' 06:59:26 INFO - no previously-included directories found matching 'dist' 06:59:26 INFO - no previously-included directories found matching '.git*' 06:59:26 INFO - Successfully installed jsonschema functools32 06:59:26 INFO - Cleaning up... 06:59:26 INFO - Return code: 0 06:59:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:26 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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:26 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 06:59:26 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 06:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:26 INFO - 'HOME': '/Users/cltbld', 06:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:26 INFO - 'LOGNAME': 'cltbld', 06:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:26 INFO - 'MOZ_NO_REMOTE': '1', 06:59:26 INFO - 'NO_EM_RESTART': '1', 06:59:26 INFO - 'PAGER': '/bin/cat', 06:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:26 INFO - 'PWD': '/builds/slave/test', 06:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:26 INFO - 'SHELL': '/bin/bash', 06:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:26 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:26 INFO - 'USER': 'cltbld', 06:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:26 INFO - 'XPC_FLAGS': '0x0', 06:59:26 INFO - 'XPC_SERVICE_NAME': '0', 06:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:59:27 INFO - Cleaning up... 06:59:27 INFO - Return code: 0 06:59:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:59:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:27 INFO - 'HOME': '/Users/cltbld', 06:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:27 INFO - 'LOGNAME': 'cltbld', 06:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:27 INFO - 'MOZ_NO_REMOTE': '1', 06:59:27 INFO - 'NO_EM_RESTART': '1', 06:59:27 INFO - 'PAGER': '/bin/cat', 06:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:27 INFO - 'PWD': '/builds/slave/test', 06:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:27 INFO - 'SHELL': '/bin/bash', 06:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:27 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:27 INFO - 'USER': 'cltbld', 06:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:27 INFO - 'XPC_FLAGS': '0x0', 06:59:27 INFO - 'XPC_SERVICE_NAME': '0', 06:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:27 INFO - Downloading/unpacking blobuploader==1.2.4 06:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:59:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:59:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:59:29 INFO - Downloading blobuploader-1.2.4.tar.gz 06:59:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:59:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:59:30 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 06:59:30 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 06:59:30 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 06:59:30 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 06:59:30 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 06:59:30 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 06:59:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:59:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:59:30 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 06:59:30 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 06:59:30 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 06:59:30 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 06:59:30 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 06:59:30 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 06:59:31 INFO - Downloading docopt-0.6.1.tar.gz 06:59:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:59:31 INFO - Installing collected packages: blobuploader, requests, docopt 06:59:31 INFO - Running setup.py install for blobuploader 06:59:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:59:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:59:31 INFO - Running setup.py install for requests 06:59:31 INFO - Running setup.py install for docopt 06:59:32 INFO - Successfully installed blobuploader requests docopt 06:59:32 INFO - Cleaning up... 06:59:32 INFO - Return code: 0 06:59:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:59: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')]} 06:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59: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')]} 06:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:32 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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:32 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 06:59:32 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 06:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:32 INFO - 'HOME': '/Users/cltbld', 06:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:32 INFO - 'LOGNAME': 'cltbld', 06:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:32 INFO - 'MOZ_NO_REMOTE': '1', 06:59:32 INFO - 'NO_EM_RESTART': '1', 06:59:32 INFO - 'PAGER': '/bin/cat', 06:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:32 INFO - 'PWD': '/builds/slave/test', 06:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:32 INFO - 'SHELL': '/bin/bash', 06:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:32 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:32 INFO - 'USER': 'cltbld', 06:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:32 INFO - 'XPC_FLAGS': '0x0', 06:59:32 INFO - 'XPC_SERVICE_NAME': '0', 06:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:59:32 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-p6MCPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:59:32 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-dhLqWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:59:32 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-tyZ_MV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:59:32 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-XvCkCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:59:32 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-1dSVtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:59:32 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-uH4JlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-1yJXMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-Rzkko9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-_rpyeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-Q_ersn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-fYsj99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-E3jVYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-DJncNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-HthhAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-ljMma7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:59:33 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-9rqjSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:59:34 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-1dkmaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:59:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:59:34 INFO - Running setup.py install for manifestparser 06:59:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:59:34 INFO - Running setup.py install for mozcrash 06:59:34 INFO - Running setup.py install for mozdebug 06:59:34 INFO - Running setup.py install for mozdevice 06:59:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:59:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:59:34 INFO - Running setup.py install for mozfile 06:59:34 INFO - Running setup.py install for mozhttpd 06:59:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Running setup.py install for mozinfo 06:59:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Running setup.py install for mozInstall 06:59:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Running setup.py install for mozleak 06:59:35 INFO - Running setup.py install for mozlog 06:59:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Running setup.py install for moznetwork 06:59:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:59:35 INFO - Running setup.py install for mozprocess 06:59:35 INFO - Running setup.py install for mozprofile 06:59:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:59:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:59:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:59:36 INFO - Running setup.py install for mozrunner 06:59:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:59:36 INFO - Running setup.py install for mozscreenshot 06:59:36 INFO - Running setup.py install for moztest 06:59:36 INFO - Running setup.py install for mozversion 06:59:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:59:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:59:36 INFO - Cleaning up... 06:59:36 INFO - Return code: 0 06:59:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:36 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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:36 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 06:59:36 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 06:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:36 INFO - 'HOME': '/Users/cltbld', 06:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:36 INFO - 'LOGNAME': 'cltbld', 06:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:36 INFO - 'MOZ_NO_REMOTE': '1', 06:59:36 INFO - 'NO_EM_RESTART': '1', 06:59:36 INFO - 'PAGER': '/bin/cat', 06:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:36 INFO - 'PWD': '/builds/slave/test', 06:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:36 INFO - 'SHELL': '/bin/bash', 06:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:36 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:36 INFO - 'USER': 'cltbld', 06:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:36 INFO - 'XPC_FLAGS': '0x0', 06:59:36 INFO - 'XPC_SERVICE_NAME': '0', 06:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-wMRHfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:59:37 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)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-e8oR8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:59:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-WLN_oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:59:37 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)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-bIdVMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:59:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-pE7Xui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:59:37 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)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-mrBNk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:59:37 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)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-NLy5U0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:59:37 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)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-DgAIJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:59:37 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)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-PyPj95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:59:37 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)) 06:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:59:37 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-KtW4xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:59:38 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)) 06:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:59:38 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-e4llc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:59:38 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)) 06:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:59:38 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-o0BMVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:59:38 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-WVNtas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:59:38 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)) 06:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:59:38 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-bh9v2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:59:38 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-rThKFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:59:38 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)) 06:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:59:38 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-pOsz5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:59:38 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-P143TX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:59:38 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)) 06:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:59:38 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)) 06:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:59:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:59:38 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 06:59:38 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 06:59:38 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 06:59:38 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 06:59:38 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 06:59:38 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 06:59:41 INFO - Downloading blessings-1.6.tar.gz 06:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:59:41 INFO - Installing collected packages: blessings 06:59:41 INFO - Running setup.py install for blessings 06:59:41 INFO - Successfully installed blessings 06:59:41 INFO - Cleaning up... 06:59:41 INFO - Return code: 0 06:59:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:59:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:59:41 INFO - Reading from file tmpfile_stdout 06:59:41 INFO - Current package versions: 06:59:41 INFO - blessings == 1.6 06:59:41 INFO - blobuploader == 1.2.4 06:59:41 INFO - docopt == 0.6.1 06:59:41 INFO - functools32 == 3.2.3-2 06:59:41 INFO - jsonschema == 2.5.1 06:59:41 INFO - manifestparser == 1.1 06:59:41 INFO - mozInstall == 1.12 06:59:41 INFO - mozcrash == 0.17 06:59:41 INFO - mozdebug == 0.1 06:59:41 INFO - mozdevice == 0.48 06:59:41 INFO - mozfile == 1.2 06:59:41 INFO - mozhttpd == 0.7 06:59:41 INFO - mozinfo == 0.9 06:59:41 INFO - mozleak == 0.1 06:59:41 INFO - mozlog == 3.4 06:59:41 INFO - moznetwork == 0.27 06:59:41 INFO - mozprocess == 0.23 06:59:41 INFO - mozprofile == 0.28 06:59:41 INFO - mozrunner == 6.12 06:59:41 INFO - mozscreenshot == 0.1 06:59:41 INFO - mozsystemmonitor == 0.3 06:59:41 INFO - moztest == 0.7 06:59:41 INFO - mozversion == 1.4 06:59:41 INFO - psutil == 3.1.1 06:59:41 INFO - requests == 1.2.3 06:59:41 INFO - wsgiref == 0.1.2 06:59:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:41 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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:41 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 06:59:41 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 06:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:41 INFO - 'HOME': '/Users/cltbld', 06:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:41 INFO - 'LOGNAME': 'cltbld', 06:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:41 INFO - 'MOZ_NO_REMOTE': '1', 06:59:41 INFO - 'NO_EM_RESTART': '1', 06:59:41 INFO - 'PAGER': '/bin/cat', 06:59:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:41 INFO - 'PWD': '/builds/slave/test', 06:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:41 INFO - 'SHELL': '/bin/bash', 06:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:41 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:41 INFO - 'USER': 'cltbld', 06:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:41 INFO - 'XPC_FLAGS': '0x0', 06:59:41 INFO - 'XPC_SERVICE_NAME': '0', 06:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 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)) 06:59:42 INFO - Cleaning up... 06:59:42 INFO - Return code: 0 06:59:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:59: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')]} 06:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59: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')]} 06:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:59:42 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 0x10c745490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3c04a01d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, '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.gMvYmmvMSS/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.iM2jq0htKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 06:59:42 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 06:59:42 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 06:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 06:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:59:42 INFO - 'HOME': '/Users/cltbld', 06:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:59:42 INFO - 'LOGNAME': 'cltbld', 06:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:42 INFO - 'MOZ_NO_REMOTE': '1', 06:59:42 INFO - 'NO_EM_RESTART': '1', 06:59:42 INFO - 'PAGER': '/bin/cat', 06:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:59:42 INFO - 'PWD': '/builds/slave/test', 06:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:59:42 INFO - 'SHELL': '/bin/bash', 06:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 06:59:42 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 06:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:59:42 INFO - 'USER': 'cltbld', 06:59:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:59:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:59:42 INFO - 'XPC_FLAGS': '0x0', 06:59:42 INFO - 'XPC_SERVICE_NAME': '0', 06:59:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:59:42 INFO - Cleaning up... 06:59:42 INFO - Return code: 0 06:59:42 INFO - Running post-action listener: _resource_record_post_action 06:59:42 INFO - Running post-action listener: _start_resource_monitoring 06:59:42 INFO - Starting resource monitoring. 06:59:42 INFO - [mozharness: 2016-11-04 13:59:42.455312Z] Finished create-virtualenv step (success) 06:59:42 INFO - [mozharness: 2016-11-04 13:59:42.456273Z] Running install step. 06:59:42 INFO - Running pre-action listener: _resource_record_pre_action 06:59:42 INFO - Running main action method: install 06:59:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:59:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:59:42 INFO - Reading from file tmpfile_stdout 06:59:42 INFO - Detecting whether we're running mozinstall >=1.0... 06:59:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:59:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:59:42 INFO - Reading from file tmpfile_stdout 06:59:42 INFO - Output received: 06:59:42 INFO - Usage: mozinstall [options] installer 06:59:42 INFO - Options: 06:59:42 INFO - -h, --help show this help message and exit 06:59:42 INFO - -d DEST, --destination=DEST 06:59:42 INFO - Directory to install application into. [default: 06:59:42 INFO - "/builds/slave/test"] 06:59:42 INFO - --app=APP Application being installed. [default: firefox] 06:59:42 INFO - mkdir: /builds/slave/test/build/application 06:59:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:59:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:00:17 INFO - Reading from file tmpfile_stdout 07:00:17 INFO - Output received: 07:00:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:00:17 INFO - Running post-action listener: _resource_record_post_action 07:00:17 INFO - [mozharness: 2016-11-04 14:00:17.012978Z] Finished install step (success) 07:00:17 INFO - [mozharness: 2016-11-04 14:00:17.013107Z] Running run-tests step. 07:00:17 INFO - Running pre-action listener: _resource_record_pre_action 07:00:17 INFO - Running main action method: run_tests 07:00:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:00:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:00:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:00:17 INFO - 2016-11-04 07:00:17.026 screenresolution[1639:7524] starting screenresolution argv=screenresolution get 07:00:17 INFO - 2016-11-04 07:00:17.028 screenresolution[1639:7524] Display 0: 1600x1200x32@60 07:00:17 INFO - 2016-11-04 07:00:17.034 screenresolution[1640:7526] starting screenresolution argv=screenresolution list 07:00:17 INFO - Available Modes on Display 0 07:00:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:00:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:00:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:00:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:00:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:00:17 INFO - Intel Iris: 07:00:17 INFO - Chipset Model: Intel Iris 07:00:17 INFO - Type: GPU 07:00:17 INFO - Bus: Built-In 07:00:17 INFO - VRAM (Dynamic, Max): 1536 MB 07:00:17 INFO - Vendor: Intel (0x8086) 07:00:17 INFO - Device ID: 0x0a2e 07:00:17 INFO - Revision ID: 0x0009 07:00:17 INFO - Displays: 07:00:17 INFO - Display: 07:00:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:00:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:00:17 INFO - Display Serial Number: 1600x1200 60 07:00:17 INFO - Main Display: Yes 07:00:17 INFO - Mirror: Off 07:00:17 INFO - Online: Yes 07:00:17 INFO - Rotation: Supported 07:00:17 INFO - Return code: 0 07:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:17 INFO - Python 2.7.3 07:00:17 INFO - Return code: 0 07:00:17 INFO - grabbing minidump binary from tooltool 07:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:00:17 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 0x10c559d30>, '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 0x10c37fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:17 INFO - Return code: 0 07:00:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:00:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:00:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--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 07:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0238 --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 07:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gMvYmmvMSS/Render', 07:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:00:17 INFO - 'HOME': '/Users/cltbld', 07:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:00:17 INFO - 'LOGNAME': 'cltbld', 07:00:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:17 INFO - 'MOZ_NO_REMOTE': '1', 07:00:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:17 INFO - 'NO_EM_RESTART': '1', 07:00:17 INFO - 'PAGER': '/bin/cat', 07:00:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:17 INFO - 'PWD': '/builds/slave/test', 07:00:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:00:17 INFO - 'SHELL': '/bin/bash', 07:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM2jq0htKk/Listeners', 07:00:17 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 07:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:00:17 INFO - 'USER': 'cltbld', 07:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:17 INFO - 'XPC_FLAGS': '0x0', 07:00:17 INFO - 'XPC_SERVICE_NAME': '0', 07:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:00:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--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 07:00:17 INFO - mozversion application_buildid: 20161104040321 07:00:17 INFO - mozversion application_changeset: fbf61bcc0fa52b238d0a88e567a189d00b407613 07:00:17 INFO - mozversion application_display_name: Nightly 07:00:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:17 INFO - mozversion application_name: Firefox 07:00:17 INFO - mozversion application_remotingname: firefox 07:00:17 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 07:00:17 INFO - mozversion application_vendor: Mozilla 07:00:17 INFO - mozversion application_version: 52.0a1 07:00:17 INFO - mozversion platform_buildid: 20161104040321 07:00:17 INFO - mozversion platform_changeset: fbf61bcc0fa52b238d0a88e567a189d00b407613 07:00:17 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 07:00:17 INFO - mozversion platform_version: 52.0a1 07:00:17 INFO - using testdate: 1478268017 07:00:17 INFO - actual date: 1478268017 07:00:17 INFO - starting webserver on 'localhost:49272' 07:00:17 INFO - SUITE-START | Running 2 tests 07:00:17 INFO - TEST-START | dromaeo_css 07:00:17 INFO - operating with platform_type : mac_ 07:00:17 INFO - Initialising browser for dromaeo_css test... 07:00:17 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpAYBw8r/profile http://localhost:49272/getInfo.html) 07:00:20 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 07:00:20 INFO - PROCESS | 1650 | colorDepth:24 07:00:20 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 07:00:20 INFO - PROCESS | 1650 | __metrics 07:00:20 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:00:20 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:00:21 INFO - TEST-INFO | 1650: exit 0 07:00:21 INFO - Browser initialized. 07:00:21 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:21 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpAYBw8r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:00:33 INFO - PROCESS | 1652 | RSS: Main: 242348032 07:00:33 INFO - PROCESS | 1652 | 07:01:24 INFO - PROCESS | 1652 | 1478268084143 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:03:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 07:03:40 INFO - PROCESS | 1652 | RSS: Main: 400220160 07:03:40 INFO - PROCESS | 1652 | 07:06:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 07:06:43 INFO - PROCESS | 1652 | RSS: Main: 371601408 07:06:43 INFO - PROCESS | 1652 | 07:09:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 07:09:46 INFO - PROCESS | 1652 | RSS: Main: 352292864 07:09:46 INFO - PROCESS | 1652 | 07:12:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 07:12:49 INFO - PROCESS | 1652 | RSS: Main: 347922432 07:12:49 INFO - PROCESS | 1652 | 07:15:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 07:15:53 INFO - PROCESS | 1652 | RSS: Main: 338083840 07:15:53 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 07:18:57 INFO - PROCESS | 1652 | RSS: Main: 484794368 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | __start_tp_report 07:18:57 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 07:18:57 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 07:18:57 INFO - PROCESS | 1652 | |i|pagename|runs| 07:18:57 INFO - PROCESS | 1652 | |0;dojo.html;3263.6899494548015;3265.232670322474;3298.191942973971;3300.290437555925;3335.2162241873157;5468.863278418032;5521.806736764214;5541.833744987652;5599.7480113394895;5636;5508.0360936836;5549.972250138743;5621.8594535136535;5628.746706398211;5639.577031722617;4511.278195488722;4767.332573439721;4997.675151115184;5033.622478314122;5078.974605126988;5057.848264552069;5170.53465188133;5171.922421163686;5181.792728290864;5226;5031.396232452093;5127.717975511346;5134.743262836881;5142.177251639739;5245.370555533342;5075;5107.336046313955;5114.846554603368;5120.692758434506;5124.769385377667;5028.245763135523;5062.645614806965;5076.36545431821;5078.898422031576;5082.440931497532;5529.142982837667;5541.030319694078;5544.805931792408;5545.972270138664;5547.306586676666;5988.820335389945;6002.339742628307;6012.729427175787;6016.187814645011;6021.849453763691;6052;6052.061930400795;6108.633481991094;6113.113598528233;6119.847003824895;5561.916571251434;5617.21359009739;5625.54995600351;5627.212190293321;5637.746301416446;6180.690965451709;6213.844653883689;6240.968795156086;6283.874322513524;6284.214473190851;5990.73041713124;6001.789937352171;6009.849753756191;6015.0676645120075;6062.575619706578;5849.181114643953;5909.6158749681135;5963.5527335450015;6033.456988870935;6093.421124993119;6004.54965877561;6025.578209525291;6042.667653279024;6059.787907423307;6078.939210607926;6000.519958403317;6059.091136329585;6080.8783824324155;6082.635041897412;6094.299155597075;4715.339852420664;4754.7147171169845;4771.880702982384;4794.017226468565;4805.0870334637;4311;4325.43769309994;4328.61042506176;4328.935065974013;4395.450568678915;5562.749676264578;5570.108782594765;5575.080111781592;5579.163125531203;5581.5255703265675;6028.758849646053;6063.090536419572;6077.908831367533;6098.390160983866;6104.603200791934;5583.357913839879;5587.357453892773;5588.0779671354585;5589.748461319169;5590.217369568264;5253.894922101536;5263.0789611818545;5263.710495922761;5264.0261551613075;5306.124489459273;6210.782622608187;6212.316645169119;6278.434940855345;6292.905606415999;6293.590916590407;6168.938310616836;6173.351798061139;6174.25908890936;6181.84545386369;6191.040388739752;6140.04415716754;6206.286277078013;6215.564910456224;6302.62184268927;6314.842128946813;5985.341612422717;6031.155638210566;6035.486983606443;6094.078451592496;6143.416375444325;6112.937642067208;6136.106557172834;6136.8772624548155;6163.691815409301;6165.383461653978;5714.314282286152;5966.284045914518;5973.402659733992;5976.6712830792985;6018.759249629966;5775.9133612995;5840.386759390203;5844.064949608042;5847.590668653153;5885.323187833454;4627.838025669085;5776.393478684846;5778.306603207642;5801.564882633904;5807.419258074159;5906.084556893688;5912.73392697321;5925.851853703692;5929.6145750526075;5936.821895343147;5659.462351076641;5706.457886500776;5727.140928860705;5736.053551164178;5774.624649397776 07:18:57 INFO - PROCESS | 1652 | |1;ext.html;488.8557875426749;501.3582614253754;513.2890946039735;515.7034705044598;547.3021897081219;29599.40801183975;30343;37811.48754049838;38556.614433855655;38778.61221387785;39089;39449;39833.00417489564;39882.60117398825;39903;8888.244499217559;9444.669436569722;9445.669401570947;9461.810763784755;9479.47862867542;9237.168654821066;9322.347435679505;9332.72001839946;9336.906630933705;9349.252059835231;9304.860427093565;9369.344145909788;9372.484513351763;9380.577873995653;9395.624175033025;9238.491882946435;9302.116298951589;9308.394954327981;9310;9315.487648179347;9227.90772092284;9308.208802251816;9312.208462280714;9314.627414903396;9349.298802589765;38474.61525384738;38771;38830.611693883264;38845.41731874024;38852.41721374181;3344.1305260632166;3471.687548120681;3610.4403817408343;3876.5154355705536;3929.3713005918917;25731.970721171132;26519.469610607874;28032.299192520146;30144;30228.244293892607;2659.391412340333;3140.7173354398215;3174.3175217328153;3176.5870436843293;3216.8391580420885;477884;480754.59622702014;481092;481207;481726;55362.16956745652;55847;55941.720291398284;55943;55976.44023559794;33156.83421582876;33246.66753332484;33845.66154338474;35007;57963.4203657958;15704.764428533581;15732.213389330487;15774.447894323643;15786.131762753159;15798.526044218463;11589.6523104307;11668.591599293984;11679;11708.531658733726;11732.765344693058;18331.908340458212;18341.34050562661;18356.898586084877;18359;18359.724604130948;7629;7778.961105194551;7814.179511151361;7845.490043147177;7860.489068210548;844.9281811046008;951.3340661536813;1060.3002018667642;1095.309954728516;1121.545773961547;28141.859290703414;28695.85652071768;28895.133146005654;29346.706532934826;29611.111666650035;969.069693094623;989.9461704320795;990.2622546202981;1044.681372181492;1098.6703988803326;12992.935035324763;14709.264536773118;14854.70290594182;15179.760319573805;15454.536363909101;647157;652287.5555577734;653393.7330313413;653884;655506;54465;54781.178282325;54920.72539637276;54929;55038.724806375714;1200.2318516149671;1233.4326209943167;1304.1523010043547;1335.5125379236704;1374.1068305601439;23621.52756944886;29812;30924.845375772977;31051.068467946;31763.68236317607;49047.754761225966;49543.75228123836;49664.751676242835;49738.00523989573;49873;517931;519701;523416;523810;525074;23283.883580582667;23458.02227633857;23597;24562.385940351633;24978.75021249837;37417.812910935274;37517;37894.431583525715;44353.89115272144;45758.54241457543;37065.444018340284;37265.6273437273;37354.6264537362;37675.62324376721;38519.614803851604;29098.41803163967;33074.66925330812;33197.83401082979;35419;37761.81119094387;36408.81795591005;36994.26011479809;37121.44317835181;37181;37250.81374593218;30930;33243.833780830944;33245.667543324256;33290.67349224113;33342.499862501594;24343.756562434148;24912.28592555089;25252.74747252504;25589.232323030337;25954.870225648752 07:18:57 INFO - PROCESS | 1652 | |2;jquery.html;349.0627664720225;370.4887255587289;371.3019523296203;378.8181672797057;380.9561900381457;14997.925010374947;15048.247587620615;16380.344786208552;20823.687644685306;20885.37343879682;18878.905605471988;19415.90292048538;19842.404727858153;20059.69910451341;20117.19531218753;5740.196372507842;5761.855953601152;6042.788502402417;6048.092786082101;6058.878822423549;4670.626349892017;5914.970425147868;5977.3723759005115;5980.461758441739;5986.790462333819;5646.858828529299;5846.938168256921;5921.230240068785;5923.17075609415;5930.495907847838;5686.971565142168;5828.854278643025;5833.970830145843;5837.270341207349;5839.795607153772;4873.463918968911;5085.745712714386;5086.847394578132;5094.235864620299;5147.922781158286;21717;24313.756862431324;24513.264602061787;24861.751382486305;25133.62299565508;2190.6604476306197;2379.7144342678816;2411.3730430088126;2986.865591048408;3052.4200401923563;13363.53227637028;14463.915206359565;15293.082415055133;15443.918925675207;15931.442399515961;1795.2639417838623;1825.8082901295438;1843.6957901946296;1866.2628261836635;1880.3606773696872;86972.13027869767;112235;120851.99999999913;123188.38405808003;125068;10406.271560990657;10416.32293900894;10669.359838409566;10700.946495267473;10876.075533579735;13139.34303284851;13309.534166304327;13336.132731462782;13417.865821341662;13435.462581496631;7523.247675232433;7750.186230445722;7861.882071768928;7947.960260198662;8001.759947201594;7365.342797870543;7516.323530882247;7522.134954480195;7646.464747467735;7703.922960770433;3688;4151.771652559078;4244.957550424518;4263.104748002954;4326.113146804897;1831.4505648305455;1885.5286178455385;1895.1282410091237;1901.9904900475685;1935.7448572413261;824.8597738384489;833.6332013913858;862.9568521573896;910.153557191818;915.9129882661136;19651.705224421647;19924.90037549832;20433.386998390073;20569.58860822775;20728;880.1946219209352;919.3656377099779;931.1805611062357;932.1610550504464;962.0427674463813;10472.947635261775;10506.579736810594;10561.683149505527;10751.408672523086;10900.454977251105;257616;277798;278317;504146;509772;128671;129156;133291.33354333165;133399.33300333435;134684;862.0991064337782;940.4874343482818;966.6036924860773;982.5627595719836;983.0660872171466;18426.539336516693;19125.61748765045;19168.945707986193;19208.71186932169;19228.423147305602;50762.74618626883;51060.23409648932;51482.48517514927;51826;51896.481035189165;71737.28262717306;71816.64091679508;74750.25249747651;77307.51553663732;77505;7781.5331080135375;7851.097123830833;7924.366050716044;7933.761987140395;8130.308923741311;10170.440625765654;12129.150959432754;12370.62888113358;12409.751804964031;12583.622491325275;10461.790764184825;10500.369977801356;10588.894111059099;10774.838377424188;10789.892101078889;5064.215046667772;7533.962330188314;9254.945867863724;9906.712127423389;10043.347182433119;10907.07289880369;11374.601888933848;11384;11400.258983166068;11513.597024104116;7244.420446364173;7786.377089832687;7889.763307100796;8071.757847264356;8212;10499.94750026245;10613.310134841211;10840.5121769519;11167.162462815159;11233.213675042669 07:18:57 INFO - PROCESS | 1652 | |3;mootools.html;1249.5439164704883;1286.286111208279;1315.32918211712;1373.8214032175815;1601.4955289083937;7322.633868306583;7702.8844567331535;7707.653155607997;7712.49868758531;7718.652660630271;7084.256153103915;7823.804404889881;8037.437379383446;8143.633536490857;8196.959015204915;2253.402848245212;2301.2981040782556;2306.4579823741406;2306.8269879759;2313.2250696016854;2274.920377786778;2324.000679707725;2329.767023297665;2332.7550607186254;2332.801711854486;1949.4054313434397;2256.3569382725927;2264.365977526287;2299;2306.850054746436;2239.9664005039936;2255.5150642611834;2273.9317820465412;2274.9090036398525;2275.772422757719;2245.8091062259728;2269.239804665439;2271.5911135995516;2271.863688178698;2274.0676322707777;6909.965450172767;8098.676052957933;8167.754967350989;8168.67325306987;8198.262156405892;4931.334269873593;4934.432540257881;5126.256692779563;5141.640085194038;5146.794128234867;3079.7844150909445;3331.666833316673;3484.4599087141532;3569.6430356964356;3574.2136729919375;1014.4065721552901;1057.8783439904432;1072.447689439939;1081.3457857995957;1085.5983286184082;10216.438095904723;10517.947410262976;10528.578856845717;10542.683719488428;10637.149028077813;4166.458360413188;4173.1862286854375;4351.715349441569;4358.6730995175485;4370.322599997005;8313.584320784057;8409.2431681149;8422.989241291085;8423.241908228289;8432.072472028072;5019.573336266385;5027.2207807789855;5027.84916452507;5029.798808047637;5040.445550989388;3999.420084087791;4242.321228603409;4316.179925814085;4396.4284642996845;4750.857474275777;3487.337405892872;3511.1046683095647;3571.1786289153642;3574.767640103385;3584.229390681016;1107.457345900503;1112.2936935046305;1113.471101226927;1125.2235957189514;1125.2348403085816;777.8288776469168;862.2670729879677;899.0263037374689;937.0301737701446;938.7512309238058;5413.102566152364;5427.755750991215;5574.944250557523;5792.073268277056;5820.563457740686;880.8282384934876;965.6716716316346;972.6790159247431;979.0454307050569;1036.8911264317137;3365.3942290387477;3563.982180089083;3656.835442405098;3705.27747089315;3819.4843696101225;9440;9916.504174791231;9928.851067233998;9931.851022234672;9942.30403871722;8207.753767386988;9836.557354919047;9849;9857.654982075594;9859.35914165577;7355.889661655079;7651.655675494616;7655.157932627407;7723.9572657691615;7733.961330193313;5397.568194544348;5414.35027796667;5582.33012038558;5643.830685079454;5687.459691329317;8835.20483156519;8902.821943561223;9024.458532488072;9049.547522623974;9142.405743626643;9103.635854566008;9254.35219534626;9354.158125768714;9358.4384936904;9382.390144640576;6985.057017302803;7122.715091396286;7124.25195354501;7127.572345659277;7138.250483699345;7483.176850546544;7621.31408173267;7680.692772289046;7687.654055567401;7697.114831794415;6591.373819487141;6918.135233095863;6931.4108300795;6993.8251543709775;6996.4209233718275;6817.795466136024;6873.518853680394;6877.415419689182;6890.586564806128;6924.238333783179;6451.3226111257645;6520.347965203442;6588.472922166311;6599.109120268702;6601.933980660132;4732.935089604949;4739.691920025188;4751.311059896227;4772.737499437425;4898.828541001048;4718.363020992122;4836.12949669063;4851.854444366674;4870.245112007503;4950.727709975842 07:18:57 INFO - PROCESS | 1652 | |4;prototype.html;297.5328733887796;301.3701364148929;309.04659126594464;312.721677706841;315.06425915032344;4100.85647002355;5149.485051494848;5149.613778966574;5191.402988656301;5200.583953283738;3850.9229815403673;4216.009237829107;5036.219385995168;5053.898922021557;5065.012322597095;3659.14010207601;3660.5607327120783;3870.632289932452;4015.9397609035886;4026.134381108061;3796.810159492028;3984.4620976168135;3993.640572348488;4030.798460076999;4034.4150098235737;3608.8015159166234;3966.7223294369405;3970.4441378207075;3983.9203215935663;3992.6207010334115;3454.136465883529;3965.8215380307956;3981.86063487778;3988.760674359548;3988.960110398888;3445.500402441657;3456.2569047654624;3460.567429071366;3482.564679415073;3487.343511831885;4405.757683327416;4861.222204447272;4865.270209468573;5039.899202015939;5064.924026139611;3808.6953043756434;3987.940180897289;4504.144212599628;4574.268117101281;4595.080983803253;4505.52691967342;4631.240128751087;5470.671759694431;5481.369642491125;5498.230247765316;2925.531914893607;3456.7753096048928;3667.7615954962844;3696.464012718168;3821.191424303135;5018.623603229772;5684.687342196177;5769.144273584602;5770.509506691937;5795.710214489301;3807.078708929807;3813.694904407641;3826.253880493277;3826.445165450994;3858.5176852893387;3676.4669122977552;3856.652901238902;3862.3240932836643;3865.536135663738;3883.4369016493015;3785.716071294609;4139.668826493873;4258.382534532476;4548.249538826123;4556.749378784199;5598.188262701886;5600.047991841397;5606.747696353674;5610.298712660918;5634.352049514358;1010.8281592129356;1249.2691775311364;1275.2667216350635;1329.335332333833;1335.1922087137336;762.7605141644827;824.8122703307218;826.7519744076753;853.5007020892723;895.7491902267376;4035.3139966205817;5114.693118412907;5151.2698269917;5198.896022079537;5201.609879259071;4840.661153719206;5158.432572417031;5553.944460555343;5556.249906262684;5600.803971860965;4320.623656988643;4893.972265824145;5133.6919784813035;5193.701574606348;5260.631755777059;2521.5965445528627;2744.4511097780523;2964.584958105867;2989.237744375214;3001.9549706754415;5080.364954380702;5318.308619879391;5484.698341591171;5530.617345663584;5888.411158884163;5001.624878134155;5079.390473143247;5088.313077734532;5726.051083691902;5800;4882.803713090316;5366.570674346043;5706.771729130788;5858.677772722541;5887.291006665773;4260.616544511009;5356.381766099457;5474.5894057945015;5524.558035357242;5578.414266501959;4204.306289462204;5328.520433161034;5464.754086066216;5580.051391263576;5598.412166722598;4448.64410847125;5309.681419114865;5440.308420642711;5809;5865.266841644794;3363.31052134307;3680.8159592019756;3686.146462361577;3703.9259214815015;3761.4733937249352;4838.709677419366;5536.861578460571;5555.444455554412;5569.888602228014;5586.888262234814;5079.314292570456;5364.007658583175;5388.973055134699;5464.890702186013;5495.477929596682;4396.670249731219;5077.416097148875;5241.528262456397;5330.520253177233;5424.403315635356;5100.974495127501;5124.590032797441;5168.689878607145;5310.6813591184655;5311.575073994149;5000.574606394632;5269.288646032736;5545.615385384323;5547.389787123332;5581.776728930798;4296.914061718686;4513.991273883091;4940.580050695731;5364.927014597019;5419.482544887438 07:18:57 INFO - PROCESS | 1652 | |5;yui.html;702.725936884615;723.3670538279006;727.7962170592233;797.5254723439551;806.2501873257871;14700.044497107681;14902.031367961075;14962.850371496283;14965.476208332711;14971.026883252582;13278.136921100122;13417.93291033546;13425.932870335659;13430.059895807297;13439.932800335984;654.9279579246279;676.8477092654138;681.8738533371318;797.6051854332085;800.4996876951891;823.9299659528917;827.210014436215;839.2740279658083;840.3235395506615;841.1252297610508;894.2622336572354;896.3232759266745;896.3725392225468;896.9327300452492;897.2821742605922;696.4393663101201;718.3821913154683;889.7775556110972;890.9643614255422;891.340408098006;640.8397900524869;656.4190691238241;659.7987613777796;660.0758937487508;661.4080398043772;13852.653683657989;13878.236696981661;13894.957878159077;13901.721965560631;13912;12645.430955606927;12768.361581920866;12874.29191394473;12929.288889111094;12941.54704585335;10930.945345273301;11258.099352051817;11740.412979351084;11943.925046745835;12003.819942700866;4287.056847493546;4322.157179350028;4341.327094300388;4444.688871778977;4447.998400719689;82782;83584;84247;86075.56962215212;86510;8583.527905965107;10265.794684106277;10298.639547615798;10335.483225838678;10759.977802108788;19222.423327300203;19261.614767704847;19267.8073219266;19302.22791088341;19307.227710891406;6691.0967019451755;7291.270872912773;7293.671784769592;7340.11918569775;7553.584552849646;4094.467719196485;4151.875443736692;4156.9792151039055;4165.97917010419;4203.558626344251;3172.2069482629345;3219.3561287742077;3258.9348213036083;3259.755518336134;3261.2662151015834;610.33168680295;623.1213988276509;626.117174783553;637.897936330185;638.2022471910112;519.9402068762141;597.9521638268928;600.7837178615695;604.7188057553259;621.9906701399483;8181.605102656629;8635.093315201939;8655.48067115975;8660.13398660129;8671.869921951176;525.5952916254539;539.3393093460479;544.5725105792013;592.6740292838921;631.1006815288288;3839.7504162229366;4143.958560414418;4179.393987871742;4195.559466255999;4238.321868501025;82098;86737;102292.48853755833;103395.4830225844;103879;82726.58636706778;84769.99999999876;95524.52237738766;98351;100027.9969203387;83116.58441707752;89656.55171724099;91300;91531.54234228787;94367.99999999863;8054.275115239657;8207.097219305992;8218.63016164262;8342.457740246864;8471.068182499797;54422;54716.72641636767;54813.1778023322;55325.170122449;56072.598185044066;53436.73281633567;57905.42094579169;61234.4691382719;68678;69910.65044674744;12273.509059637514;12299.50801967947;12475.812862807246;12502.87497125017;12592.874071259537;11917.702057448287;12177.817332740193;12242.632721018384;12816.6795830105;13728.862711373158;12820.679483013;12838.252385536774;12900.806487902877;13147.408366623333;13587.45650173982;12881.677958050748;12973.333200712235;13045.716999524855;13437.664058398619;13606.523771667944;10102.79794404093;12743.299118548199;12831;13136.539404172128;13234.933825330812;10807.649628615154;10922.344659320466;11049.558017679203;11147.386893720923;11301.943490282496;10947.452627368759;11012.587524187416;11253.887461125283;11261.549538018387;11286.887131128584 07:18:57 INFO - PROCESS | 1652 | __end_tp_report 07:18:57 INFO - PROCESS | 1652 | __start_cc_report 07:18:57 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1607 07:18:57 INFO - PROCESS | 1652 | __end_cc_report 07:18:57 INFO - PROCESS | 1652 | __startTimestamp1478269137475__endTimestamp 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | ------- Summary: start ------- 07:18:57 INFO - PROCESS | 1652 | Number of tests: 6 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5600.77 Median:5775.91 stddev:634.88 (11.0%) stddev-sans-first:609.78 07:18:57 INFO - PROCESS | 1652 | Values: 3263.7 3265.2 3298.2 3300.3 3335.2 5468.9 5521.8 5541.8 5599.7 5636.0 5508.0 5550.0 5621.9 5628.7 5639.6 4511.3 4767.3 4997.7 5033.6 5079.0 5057.8 5170.5 5171.9 5181.8 5226.0 5031.4 5127.7 5134.7 5142.2 5245.4 5075.0 5107.3 5114.8 5120.7 5124.8 5028.2 5062.6 5076.4 5078.9 5082.4 5529.1 5541.0 5544.8 5546.0 5547.3 5988.8 6002.3 6012.7 6016.2 6021.8 6052.0 6052.1 6108.6 6113.1 6119.8 5561.9 5617.2 5625.5 5627.2 5637.7 6180.7 6213.8 6241.0 6283.9 6284.2 5990.7 6001.8 6009.8 6015.1 6062.6 5849.2 5909.6 5963.6 6033.5 6093.4 6004.5 6025.6 6042.7 6059.8 6078.9 6000.5 6059.1 6080.9 6082.6 6094.3 4715.3 4754.7 4771.9 4794.0 4805.1 4311.0 4325.4 4328.6 4328.9 4395.5 5562.7 5570.1 5575.1 5579.2 5581.5 6028.8 6063.1 6077.9 6098.4 6104.6 5583.4 5587.4 5588.1 5589.7 5590.2 5253.9 5263.1 5263.7 5264.0 5306.1 6210.8 6212.3 6278.4 6292.9 6293.6 6168.9 6173.4 6174.3 6181.8 6191.0 6140.0 6206.3 6215.6 6302.6 6314.8 5985.3 6031.2 6035.5 6094.1 6143.4 6112.9 6136.1 6136.9 6163.7 6165.4 5714.3 5966.3 5973.4 5976.7 6018.8 5775.9 5840.4 5844.1 5847.6 5885.3 4627.8 5776.4 5778.3 5801.6 5807.4 5906.1 5912.7 5925.9 5929.6 5936.8 5659.5 5706.5 5727.1 5736.1 5774.6 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:66943.90 Median:37852.96 stddev:148932.31 (393.4%) stddev-sans-first:149266.52 07:18:57 INFO - PROCESS | 1652 | Values: 488.9 501.4 513.3 515.7 547.3 29599.4 30343.0 37811.5 38556.6 38778.6 39089.0 39449.0 39833.0 39882.6 39903.0 8888.2 9444.7 9445.7 9461.8 9479.5 9237.2 9322.3 9332.7 9336.9 9349.3 9304.9 9369.3 9372.5 9380.6 9395.6 9238.5 9302.1 9308.4 9310.0 9315.5 9227.9 9308.2 9312.2 9314.6 9349.3 38474.6 38771.0 38830.6 38845.4 38852.4 3344.1 3471.7 3610.4 3876.5 3929.4 25732.0 26519.5 28032.3 30144.0 30228.2 2659.4 3140.7 3174.3 3176.6 3216.8 477884.0 480754.6 481092.0 481207.0 481726.0 55362.2 55847.0 55941.7 55943.0 55976.4 33156.8 33246.7 33845.7 35007.0 57963.4 15704.8 15732.2 15774.4 15786.1 15798.5 11589.7 11668.6 11679.0 11708.5 11732.8 18331.9 18341.3 18356.9 18359.0 18359.7 7629.0 7779.0 7814.2 7845.5 7860.5 844.9 951.3 1060.3 1095.3 1121.5 28141.9 28695.9 28895.1 29346.7 29611.1 969.1 989.9 990.3 1044.7 1098.7 12992.9 14709.3 14854.7 15179.8 15454.5 647157.0 652287.6 653393.7 653884.0 655506.0 54465.0 54781.2 54920.7 54929.0 55038.7 1200.2 1233.4 1304.2 1335.5 1374.1 23621.5 29812.0 30924.8 31051.1 31763.7 49047.8 49543.8 49664.8 49738.0 49873.0 517931.0 519701.0 523416.0 523810.0 525074.0 23283.9 23458.0 23597.0 24562.4 24978.8 37417.8 37517.0 37894.4 44353.9 45758.5 37065.4 37265.6 37354.6 37675.6 38519.6 29098.4 33074.7 33197.8 35419.0 37761.8 36408.8 36994.3 37121.4 37181.0 37250.8 30930.0 33243.8 33245.7 33290.7 33342.5 24343.8 24912.3 25252.7 25589.2 25954.9 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:28150.13 Median:140651.93 stddev:67040.58 (47.7%) stddev-sans-first:67196.15 07:18:57 INFO - PROCESS | 1652 | Values: 349.1 370.5 371.3 378.8 381.0 14997.9 15048.2 16380.3 20823.7 20885.4 18878.9 19415.9 19842.4 20059.7 20117.2 5740.2 5761.9 6042.8 6048.1 6058.9 4670.6 5915.0 5977.4 5980.5 5986.8 5646.9 5846.9 5921.2 5923.2 5930.5 5687.0 5828.9 5834.0 5837.3 5839.8 4873.5 5085.7 5086.8 5094.2 5147.9 21717.0 24313.8 24513.3 24861.8 25133.6 2190.7 2379.7 2411.4 2986.9 3052.4 13363.5 14463.9 15293.1 15443.9 15931.4 1795.3 1825.8 1843.7 1866.3 1880.4 86972.1 112235.0 120852.0 123188.4 125068.0 10406.3 10416.3 10669.4 10700.9 10876.1 13139.3 13309.5 13336.1 13417.9 13435.5 7523.2 7750.2 7861.9 7948.0 8001.8 7365.3 7516.3 7522.1 7646.5 7703.9 3688.0 4151.8 4245.0 4263.1 4326.1 1831.5 1885.5 1895.1 1902.0 1935.7 824.9 833.6 863.0 910.2 915.9 19651.7 19924.9 20433.4 20569.6 20728.0 880.2 919.4 931.2 932.2 962.0 10472.9 10506.6 10561.7 10751.4 10900.5 257616.0 277798.0 278317.0 504146.0 509772.0 128671.0 129156.0 133291.3 133399.3 134684.0 862.1 940.5 966.6 982.6 983.1 18426.5 19125.6 19168.9 19208.7 19228.4 50762.7 51060.2 51482.5 51826.0 51896.5 71737.3 71816.6 74750.3 77307.5 77505.0 7781.5 7851.1 7924.4 7933.8 8130.3 10170.4 12129.2 12370.6 12409.8 12583.6 10461.8 10500.4 10588.9 10774.8 10789.9 5064.2 7534.0 9254.9 9906.7 10043.3 10907.1 11374.6 11384.0 11400.3 11513.6 7244.4 7786.4 7889.8 8071.8 8212.0 10499.9 10613.3 10840.5 11167.2 11233.2 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5261.46 Median:5272.18 stddev:2857.60 (54.2%) stddev-sans-first:2849.70 07:18:57 INFO - PROCESS | 1652 | Values: 1249.5 1286.3 1315.3 1373.8 1601.5 7322.6 7702.9 7707.7 7712.5 7718.7 7084.3 7823.8 8037.4 8143.6 8197.0 2253.4 2301.3 2306.5 2306.8 2313.2 2274.9 2324.0 2329.8 2332.8 2332.8 1949.4 2256.4 2264.4 2299.0 2306.9 2240.0 2255.5 2273.9 2274.9 2275.8 2245.8 2269.2 2271.6 2271.9 2274.1 6910.0 8098.7 8167.8 8168.7 8198.3 4931.3 4934.4 5126.3 5141.6 5146.8 3079.8 3331.7 3484.5 3569.6 3574.2 1014.4 1057.9 1072.4 1081.3 1085.6 10216.4 10517.9 10528.6 10542.7 10637.1 4166.5 4173.2 4351.7 4358.7 4370.3 8313.6 8409.2 8423.0 8423.2 8432.1 5019.6 5027.2 5027.8 5029.8 5040.4 3999.4 4242.3 4316.2 4396.4 4750.9 3487.3 3511.1 3571.2 3574.8 3584.2 1107.5 1112.3 1113.5 1125.2 1125.2 777.8 862.3 899.0 937.0 938.8 5413.1 5427.8 5574.9 5792.1 5820.6 880.8 965.7 972.7 979.0 1036.9 3365.4 3564.0 3656.8 3705.3 3819.5 9440.0 9916.5 9928.9 9931.9 9942.3 8207.8 9836.6 9849.0 9857.7 9859.4 7355.9 7651.7 7655.2 7724.0 7734.0 5397.6 5414.4 5582.3 5643.8 5687.5 8835.2 8902.8 9024.5 9049.5 9142.4 9103.6 9254.4 9354.2 9358.4 9382.4 6985.1 7122.7 7124.3 7127.6 7138.3 7483.2 7621.3 7680.7 7687.7 7697.1 6591.4 6918.1 6931.4 6993.8 6996.4 6817.8 6873.5 6877.4 6890.6 6924.2 6451.3 6520.3 6588.5 6599.1 6601.9 4732.9 4739.7 4751.3 4772.7 4898.8 4718.4 4836.1 4851.9 4870.2 4950.7 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4367.55 Median:4970.58 stddev:1342.73 (27.0%) stddev-sans-first:1311.28 07:18:57 INFO - PROCESS | 1652 | Values: 297.5 301.4 309.0 312.7 315.1 4100.9 5149.5 5149.6 5191.4 5200.6 3850.9 4216.0 5036.2 5053.9 5065.0 3659.1 3660.6 3870.6 4015.9 4026.1 3796.8 3984.5 3993.6 4030.8 4034.4 3608.8 3966.7 3970.4 3983.9 3992.6 3454.1 3965.8 3981.9 3988.8 3989.0 3445.5 3456.3 3460.6 3482.6 3487.3 4405.8 4861.2 4865.3 5039.9 5064.9 3808.7 3987.9 4504.1 4574.3 4595.1 4505.5 4631.2 5470.7 5481.4 5498.2 2925.5 3456.8 3667.8 3696.5 3821.2 5018.6 5684.7 5769.1 5770.5 5795.7 3807.1 3813.7 3826.3 3826.4 3858.5 3676.5 3856.7 3862.3 3865.5 3883.4 3785.7 4139.7 4258.4 4548.2 4556.7 5598.2 5600.0 5606.7 5610.3 5634.4 1010.8 1249.3 1275.3 1329.3 1335.2 762.8 824.8 826.8 853.5 895.7 4035.3 5114.7 5151.3 5198.9 5201.6 4840.7 5158.4 5553.9 5556.2 5600.8 4320.6 4894.0 5133.7 5193.7 5260.6 2521.6 2744.5 2964.6 2989.2 3002.0 5080.4 5318.3 5484.7 5530.6 5888.4 5001.6 5079.4 5088.3 5726.1 5800.0 4882.8 5366.6 5706.8 5858.7 5887.3 4260.6 5356.4 5474.6 5524.6 5578.4 4204.3 5328.5 5464.8 5580.1 5598.4 4448.6 5309.7 5440.3 5809.0 5865.3 3363.3 3680.8 3686.1 3703.9 3761.5 4838.7 5536.9 5555.4 5569.9 5586.9 5079.3 5364.0 5389.0 5464.9 5495.5 4396.7 5077.4 5241.5 5330.5 5424.4 5101.0 5124.6 5168.7 5310.7 5311.6 5000.6 5269.3 5545.6 5547.4 5581.8 4296.9 4514.0 4940.6 5364.9 5419.5 07:18:57 INFO - PROCESS | 1652 | 07:18:57 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:19668.47 Median:4393.01 stddev:28360.54 (645.6%) stddev-sans-first:28404.34 07:18:57 INFO - PROCESS | 1652 | Values: 702.7 723.4 727.8 797.5 806.3 14700.0 14902.0 14962.9 14965.5 14971.0 13278.1 13417.9 13425.9 13430.1 13439.9 654.9 676.8 681.9 797.6 800.5 823.9 827.2 839.3 840.3 841.1 894.3 896.3 896.4 896.9 897.3 696.4 718.4 889.8 891.0 891.3 640.8 656.4 659.8 660.1 661.4 13852.7 13878.2 13895.0 13901.7 13912.0 12645.4 12768.4 12874.3 12929.3 12941.5 10930.9 11258.1 11740.4 11943.9 12003.8 4287.1 4322.2 4341.3 4444.7 4448.0 82782.0 83584.0 84247.0 86075.6 86510.0 8583.5 10265.8 10298.6 10335.5 10760.0 19222.4 19261.6 19267.8 19302.2 19307.2 6691.1 7291.3 7293.7 7340.1 7553.6 4094.5 4151.9 4157.0 4166.0 4203.6 3172.2 3219.4 3258.9 3259.8 3261.3 610.3 623.1 626.1 637.9 638.2 519.9 598.0 600.8 604.7 622.0 8181.6 8635.1 8655.5 8660.1 8671.9 525.6 539.3 544.6 592.7 631.1 3839.8 4144.0 4179.4 4195.6 4238.3 82098.0 86737.0 102292.5 103395.5 103879.0 82726.6 84770.0 95524.5 98351.0 100028.0 83116.6 89656.6 91300.0 91531.5 94368.0 8054.3 8207.1 8218.6 8342.5 8471.1 54422.0 54716.7 54813.2 55325.2 56072.6 53436.7 57905.4 61234.5 68678.0 69910.7 12273.5 12299.5 12475.8 12502.9 12592.9 11917.7 12177.8 12242.6 12816.7 13728.9 12820.7 12838.3 12900.8 13147.4 13587.5 12881.7 12973.3 13045.7 13437.7 13606.5 10102.8 12743.3 12831.0 13136.5 13234.9 10807.6 10922.3 11049.6 11147.4 11301.9 10947.5 11012.6 11253.9 11261.5 11286.9 07:18:57 INFO - PROCESS | 1652 | -------- Summary: end -------- 07:18:57 INFO - PROCESS | 1652 | 07:18:58 INFO - TEST-INFO | 1652: exit 0 07:18:58 INFO - TEST-OK | dromaeo_css | took 1120634ms 07:18:58 INFO - TEST-START | kraken 07:18:58 INFO - operating with platform_type : mac_ 07:18:58 INFO - Initialising browser for kraken test... 07:18:58 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpGtXAdm/profile http://localhost:49272/getInfo.html) 07:19:00 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 07:19:00 INFO - PROCESS | 1662 | colorDepth:24 07:19:00 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 07:19:00 INFO - PROCESS | 1662 | __metrics 07:19:00 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:19:00 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:01 INFO - TEST-INFO | 1662: exit 0 07:19:01 INFO - Browser initialized. 07:19:01 INFO - Running cycle 1/1 for kraken test... 07:19:01 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpGtXAdm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:13 INFO - PROCESS | 1663 | RSS: Main: 242114560 07:19:13 INFO - PROCESS | 1663 | 07:19:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:16 INFO - PROCESS | 1663 | RSS: Main: 292184064 07:19:16 INFO - PROCESS | 1663 | 07:19:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 07:19:20 INFO - PROCESS | 1663 | RSS: Main: 294408192 07:19:20 INFO - PROCESS | 1663 | 07:19:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 07:19:24 INFO - PROCESS | 1663 | RSS: Main: 384004096 07:19:24 INFO - PROCESS | 1663 | 07:19:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 07:19:26 INFO - PROCESS | 1663 | RSS: Main: 286437376 07:19:26 INFO - PROCESS | 1663 | 07:19:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:29 INFO - PROCESS | 1663 | RSS: Main: 291217408 07:19:29 INFO - PROCESS | 1663 | 07:19:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:32 INFO - PROCESS | 1663 | RSS: Main: 328220672 07:19:32 INFO - PROCESS | 1663 | 07:19:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:36 INFO - PROCESS | 1663 | RSS: Main: 329859072 07:19:36 INFO - PROCESS | 1663 | 07:19:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 07:19:39 INFO - PROCESS | 1663 | RSS: Main: 326479872 07:19:39 INFO - PROCESS | 1663 | 07:19:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:19:41 INFO - PROCESS | 1663 | RSS: Main: 302866432 07:19:41 INFO - PROCESS | 1663 | 07:19:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:19:44 INFO - PROCESS | 1663 | RSS: Main: 304599040 07:19:44 INFO - PROCESS | 1663 | 07:19:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:19:47 INFO - PROCESS | 1663 | RSS: Main: 308826112 07:19:47 INFO - PROCESS | 1663 | 07:19:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:19:51 INFO - PROCESS | 1663 | RSS: Main: 322281472 07:19:51 INFO - PROCESS | 1663 | 07:19:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:19:54 INFO - PROCESS | 1663 | RSS: Main: 316026880 07:19:54 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 07:19:56 INFO - PROCESS | 1663 | RSS: Main: 360235008 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | __start_tp_report 07:19:56 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 07:19:56 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 07:19:56 INFO - PROCESS | 1663 | |i|pagename|runs| 07:19:56 INFO - PROCESS | 1663 | |0;ai-astar;113;113;113;113;113;113;113;113;112;112 07:19:56 INFO - PROCESS | 1663 | |1;audio-beat-detection;149;117;138;178;136;113;114;109;141;112 07:19:56 INFO - PROCESS | 1663 | |2;audio-dft;184;184;183;183;182;183;182;182;182;183 07:19:56 INFO - PROCESS | 1663 | |3;audio-fft;81;57;59;89;61;62;60;60;82;58 07:19:56 INFO - PROCESS | 1663 | |4;audio-oscillator;84;71;91;79;95;100;74;82;91;125 07:19:56 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;87;83;87;85;86;83;91;84;87;84 07:19:56 INFO - PROCESS | 1663 | |6;imaging-darkroom;94;93;94;101;146;93;94;95;94;93 07:19:56 INFO - PROCESS | 1663 | |7;imaging-desaturate;84;84;82;82;82;84;84;85;83;86 07:19:56 INFO - PROCESS | 1663 | |8;json-parse-financial;56;55;55;54;55;57;56;55;55;56 07:19:56 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;71;67;76;75;72;68;71;70;70;72 07:19:56 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;90;86;88;92;94;104;106;107;96 07:19:56 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;149;139;162;169;152;150;146;147;197;146 07:19:56 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;172;171;172;171;171;173;171;172;172;172 07:19:56 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;68;67;67;68;66;66;75;86;88;66 07:19:56 INFO - PROCESS | 1663 | __end_tp_report 07:19:56 INFO - PROCESS | 1663 | __start_cc_report 07:19:56 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1975 07:19:56 INFO - PROCESS | 1663 | __end_cc_report 07:19:56 INFO - PROCESS | 1663 | __startTimestamp1478269196601__endTimestamp 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | ------- Summary: start ------- 07:19:56 INFO - PROCESS | 1663 | Number of tests: 14 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:19:56 INFO - PROCESS | 1663 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 112.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:137.00 stddev:22.01 (16.1%) stddev-sans-first:22.33 07:19:56 INFO - PROCESS | 1663 | Values: 149.0 117.0 138.0 178.0 136.0 113.0 114.0 109.0 141.0 112.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:0.79 (0.4%) stddev-sans-first:0.71 07:19:56 INFO - PROCESS | 1663 | Values: 184.0 184.0 183.0 183.0 182.0 183.0 182.0 182.0 182.0 183.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:66.90 Median:61.50 stddev:12.06 (19.6%) stddev-sans-first:11.66 07:19:56 INFO - PROCESS | 1663 | Values: 81.0 57.0 59.0 89.0 61.0 62.0 60.0 60.0 82.0 58.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:83.00 stddev:15.58 (18.8%) stddev-sans-first:16.41 07:19:56 INFO - PROCESS | 1663 | Values: 84.0 71.0 91.0 79.0 95.0 100.0 74.0 82.0 91.0 125.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:2.45 (2.8%) stddev-sans-first:2.55 07:19:56 INFO - PROCESS | 1663 | Values: 87.0 83.0 87.0 85.0 86.0 83.0 91.0 84.0 87.0 84.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:94.00 stddev:16.44 (17.5%) stddev-sans-first:17.31 07:19:56 INFO - PROCESS | 1663 | Values: 94.0 93.0 94.0 101.0 146.0 93.0 94.0 95.0 94.0 93.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:83.60 Median:84.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 07:19:56 INFO - PROCESS | 1663 | Values: 84.0 84.0 82.0 82.0 82.0 84.0 84.0 85.0 83.0 86.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:0.84 (1.5%) stddev-sans-first:0.87 07:19:56 INFO - PROCESS | 1663 | Values: 56.0 55.0 55.0 54.0 55.0 57.0 56.0 55.0 55.0 56.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.50 stddev:2.78 (3.9%) stddev-sans-first:2.95 07:19:56 INFO - PROCESS | 1663 | Values: 71.0 67.0 76.0 75.0 72.0 68.0 71.0 70.0 70.0 72.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:89.00 stddev:8.12 (9.1%) stddev-sans-first:7.94 07:19:56 INFO - PROCESS | 1663 | Values: 86.0 90.0 86.0 88.0 92.0 94.0 104.0 106.0 107.0 96.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:155.70 Median:151.00 stddev:16.85 (11.2%) stddev-sans-first:17.70 07:19:56 INFO - PROCESS | 1663 | Values: 149.0 139.0 162.0 169.0 152.0 150.0 146.0 147.0 197.0 146.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 07:19:56 INFO - PROCESS | 1663 | Values: 172.0 171.0 172.0 171.0 171.0 173.0 171.0 172.0 172.0 172.0 07:19:56 INFO - PROCESS | 1663 | 07:19:56 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:68.00 stddev:8.50 (12.5%) stddev-sans-first:8.91 07:19:56 INFO - PROCESS | 1663 | Values: 68.0 67.0 67.0 68.0 66.0 66.0 75.0 86.0 88.0 66.0 07:19:56 INFO - PROCESS | 1663 | -------- Summary: end -------- 07:19:56 INFO - PROCESS | 1663 | 07:19:57 INFO - TEST-INFO | 1663: exit 0 07:19:57 INFO - TEST-OK | kraken | took 59377ms 07:19:57 INFO - SUITE-END | took 1180s 07:19:57 INFO - Completed test suite (00:19:40) 07:19:57 INFO - javascript benchmark 07:19:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3263.6899494548015, 3265.232670322474, 3298.191942973971, 3300.290437555925, 3335.2162241873157, 5468.863278418032, 5521.806736764214, 5541.833744987652, 5599.7480113394895, 5636.0, 5508.0360936836, 5549.972250138743, 5621.8594535136535, 5628.746706398211, 5639.577031722617, 4511.278195488722, 4767.332573439721, 4997.675151115184, 5033.622478314122, 5078.974605126988, 5057.848264552069, 5170.53465188133, 5171.922421163686, 5181.792728290864, 5226.0, 5031.396232452093, 5127.717975511346, 5134.743262836881, 5142.177251639739, 5245.370555533342, 5075.0, 5107.336046313955, 5114.846554603368, 5120.692758434506, 5124.769385377667, 5028.245763135523, 5062.645614806965, 5076.36545431821, 5078.898422031576, 5082.440931497532, 5529.142982837667, 5541.030319694078, 5544.805931792408, 5545.972270138664, 5547.306586676666, 5988.820335389945, 6002.339742628307, 6012.729427175787, 6016.187814645011, 6021.849453763691, 6052.0, 6052.061930400795, 6108.633481991094, 6113.113598528233, 6119.847003824895, 5561.916571251434, 5617.21359009739, 5625.54995600351, 5627.212190293321, 5637.746301416446, 6180.690965451709, 6213.844653883689, 6240.968795156086, 6283.874322513524, 6284.214473190851, 5990.73041713124, 6001.789937352171, 6009.849753756191, 6015.0676645120075, 6062.575619706578, 5849.181114643953, 5909.6158749681135, 5963.5527335450015, 6033.456988870935, 6093.421124993119, 6004.54965877561, 6025.578209525291, 6042.667653279024, 6059.787907423307, 6078.939210607926, 6000.519958403317, 6059.091136329585, 6080.8783824324155, 6082.635041897412, 6094.299155597075, 4715.339852420664, 4754.7147171169845, 4771.880702982384, 4794.017226468565, 4805.0870334637, 4311.0, 4325.43769309994, 4328.61042506176, 4328.935065974013, 4395.450568678915, 5562.749676264578, 5570.108782594765, 5575.080111781592, 5579.163125531203, 5581.5255703265675, 6028.758849646053, 6063.090536419572, 6077.908831367533, 6098.390160983866, 6104.603200791934, 5583.357913839879, 5587.357453892773, 5588.0779671354585, 5589.748461319169, 5590.217369568264, 5253.894922101536, 5263.0789611818545, 5263.710495922761, 5264.0261551613075, 5306.124489459273, 6210.782622608187, 6212.316645169119, 6278.434940855345, 6292.905606415999, 6293.590916590407, 6168.938310616836, 6173.351798061139, 6174.25908890936, 6181.84545386369, 6191.040388739752, 6140.04415716754, 6206.286277078013, 6215.564910456224, 6302.62184268927, 6314.842128946813, 5985.341612422717, 6031.155638210566, 6035.486983606443, 6094.078451592496, 6143.416375444325, 6112.937642067208, 6136.106557172834, 6136.8772624548155, 6163.691815409301, 6165.383461653978, 5714.314282286152, 5966.284045914518, 5973.402659733992, 5976.6712830792985, 6018.759249629966, 5775.9133612995, 5840.386759390203, 5844.064949608042, 5847.590668653153, 5885.323187833454, 4627.838025669085, 5776.393478684846, 5778.306603207642, 5801.564882633904, 5807.419258074159, 5906.084556893688, 5912.73392697321, 5925.851853703692, 5929.6145750526075, 5936.821895343147, 5659.462351076641, 5706.457886500776, 5727.140928860705, 5736.053551164178, 5774.624649397776], "value": 5559.507708400469, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.8557875426749, 501.3582614253754, 513.2890946039735, 515.7034705044598, 547.3021897081219, 29599.40801183975, 30343.0, 37811.48754049838, 38556.614433855655, 38778.61221387785, 39089.0, 39449.0, 39833.00417489564, 39882.60117398825, 39903.0, 8888.244499217559, 9444.669436569722, 9445.669401570947, 9461.810763784755, 9479.47862867542, 9237.168654821066, 9322.347435679505, 9332.72001839946, 9336.906630933705, 9349.252059835231, 9304.860427093565, 9369.344145909788, 9372.484513351763, 9380.577873995653, 9395.624175033025, 9238.491882946435, 9302.116298951589, 9308.394954327981, 9310.0, 9315.487648179347, 9227.90772092284, 9308.208802251816, 9312.208462280714, 9314.627414903396, 9349.298802589765, 38474.61525384738, 38771.0, 38830.611693883264, 38845.41731874024, 38852.41721374181, 3344.1305260632166, 3471.687548120681, 3610.4403817408343, 3876.5154355705536, 3929.3713005918917, 25731.970721171132, 26519.469610607874, 28032.299192520146, 30144.0, 30228.244293892607, 2659.391412340333, 3140.7173354398215, 3174.3175217328153, 3176.5870436843293, 3216.8391580420885, 477884.0, 480754.59622702014, 481092.0, 481207.0, 481726.0, 55362.16956745652, 55847.0, 55941.720291398284, 55943.0, 55976.44023559794, 33156.83421582876, 33246.66753332484, 33845.66154338474, 35007.0, 57963.4203657958, 15704.764428533581, 15732.213389330487, 15774.447894323643, 15786.131762753159, 15798.526044218463, 11589.6523104307, 11668.591599293984, 11679.0, 11708.531658733726, 11732.765344693058, 18331.908340458212, 18341.34050562661, 18356.898586084877, 18359.0, 18359.724604130948, 7629.0, 7778.961105194551, 7814.179511151361, 7845.490043147177, 7860.489068210548, 844.9281811046008, 951.3340661536813, 1060.3002018667642, 1095.309954728516, 1121.545773961547, 28141.859290703414, 28695.85652071768, 28895.133146005654, 29346.706532934826, 29611.111666650035, 969.069693094623, 989.9461704320795, 990.2622546202981, 1044.681372181492, 1098.6703988803326, 12992.935035324763, 14709.264536773118, 14854.70290594182, 15179.760319573805, 15454.536363909101, 647157.0, 652287.5555577734, 653393.7330313413, 653884.0, 655506.0, 54465.0, 54781.178282325, 54920.72539637276, 54929.0, 55038.724806375714, 1200.2318516149671, 1233.4326209943167, 1304.1523010043547, 1335.5125379236704, 1374.1068305601439, 23621.52756944886, 29812.0, 30924.845375772977, 31051.068467946, 31763.68236317607, 49047.754761225966, 49543.75228123836, 49664.751676242835, 49738.00523989573, 49873.0, 517931.0, 519701.0, 523416.0, 523810.0, 525074.0, 23283.883580582667, 23458.02227633857, 23597.0, 24562.385940351633, 24978.75021249837, 37417.812910935274, 37517.0, 37894.431583525715, 44353.89115272144, 45758.54241457543, 37065.444018340284, 37265.6273437273, 37354.6264537362, 37675.62324376721, 38519.614803851604, 29098.41803163967, 33074.66925330812, 33197.83401082979, 35419.0, 37761.81119094387, 36408.81795591005, 36994.26011479809, 37121.44317835181, 37181.0, 37250.81374593218, 30930.0, 33243.833780830944, 33245.667543324256, 33290.67349224113, 33342.499862501594, 24343.756562434148, 24912.28592555089, 25252.74747252504, 25589.232323030337, 25954.870225648752], "value": 18943.679442800323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.0627664720225, 370.4887255587289, 371.3019523296203, 378.8181672797057, 380.9561900381457, 14997.925010374947, 15048.247587620615, 16380.344786208552, 20823.687644685306, 20885.37343879682, 18878.905605471988, 19415.90292048538, 19842.404727858153, 20059.69910451341, 20117.19531218753, 5740.196372507842, 5761.855953601152, 6042.788502402417, 6048.092786082101, 6058.878822423549, 4670.626349892017, 5914.970425147868, 5977.3723759005115, 5980.461758441739, 5986.790462333819, 5646.858828529299, 5846.938168256921, 5921.230240068785, 5923.17075609415, 5930.495907847838, 5686.971565142168, 5828.854278643025, 5833.970830145843, 5837.270341207349, 5839.795607153772, 4873.463918968911, 5085.745712714386, 5086.847394578132, 5094.235864620299, 5147.922781158286, 21717.0, 24313.756862431324, 24513.264602061787, 24861.751382486305, 25133.62299565508, 2190.6604476306197, 2379.7144342678816, 2411.3730430088126, 2986.865591048408, 3052.4200401923563, 13363.53227637028, 14463.915206359565, 15293.082415055133, 15443.918925675207, 15931.442399515961, 1795.2639417838623, 1825.8082901295438, 1843.6957901946296, 1866.2628261836635, 1880.3606773696872, 86972.13027869767, 112235.0, 120851.99999999913, 123188.38405808003, 125068.0, 10406.271560990657, 10416.32293900894, 10669.359838409566, 10700.946495267473, 10876.075533579735, 13139.34303284851, 13309.534166304327, 13336.132731462782, 13417.865821341662, 13435.462581496631, 7523.247675232433, 7750.186230445722, 7861.882071768928, 7947.960260198662, 8001.759947201594, 7365.342797870543, 7516.323530882247, 7522.134954480195, 7646.464747467735, 7703.922960770433, 3688.0, 4151.771652559078, 4244.957550424518, 4263.104748002954, 4326.113146804897, 1831.4505648305455, 1885.5286178455385, 1895.1282410091237, 1901.9904900475685, 1935.7448572413261, 824.8597738384489, 833.6332013913858, 862.9568521573896, 910.153557191818, 915.9129882661136, 19651.705224421647, 19924.90037549832, 20433.386998390073, 20569.58860822775, 20728.0, 880.1946219209352, 919.3656377099779, 931.1805611062357, 932.1610550504464, 962.0427674463813, 10472.947635261775, 10506.579736810594, 10561.683149505527, 10751.408672523086, 10900.454977251105, 257616.0, 277798.0, 278317.0, 504146.0, 509772.0, 128671.0, 129156.0, 133291.33354333165, 133399.33300333435, 134684.0, 862.0991064337782, 940.4874343482818, 966.6036924860773, 982.5627595719836, 983.0660872171466, 18426.539336516693, 19125.61748765045, 19168.945707986193, 19208.71186932169, 19228.423147305602, 50762.74618626883, 51060.23409648932, 51482.48517514927, 51826.0, 51896.481035189165, 71737.28262717306, 71816.64091679508, 74750.25249747651, 77307.51553663732, 77505.0, 7781.5331080135375, 7851.097123830833, 7924.366050716044, 7933.761987140395, 8130.308923741311, 10170.440625765654, 12129.150959432754, 12370.62888113358, 12409.751804964031, 12583.622491325275, 10461.790764184825, 10500.369977801356, 10588.894111059099, 10774.838377424188, 10789.892101078889, 5064.215046667772, 7533.962330188314, 9254.945867863724, 9906.712127423389, 10043.347182433119, 10907.07289880369, 11374.601888933848, 11384.0, 11400.258983166068, 11513.597024104116, 7244.420446364173, 7786.377089832687, 7889.763307100796, 8071.757847264356, 8212.0, 10499.94750026245, 10613.310134841211, 10840.5121769519, 11167.162462815159, 11233.213675042669], "value": 9174.05070990555, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.5439164704883, 1286.286111208279, 1315.32918211712, 1373.8214032175815, 1601.4955289083937, 7322.633868306583, 7702.8844567331535, 7707.653155607997, 7712.49868758531, 7718.652660630271, 7084.256153103915, 7823.804404889881, 8037.437379383446, 8143.633536490857, 8196.959015204915, 2253.402848245212, 2301.2981040782556, 2306.4579823741406, 2306.8269879759, 2313.2250696016854, 2274.920377786778, 2324.000679707725, 2329.767023297665, 2332.7550607186254, 2332.801711854486, 1949.4054313434397, 2256.3569382725927, 2264.365977526287, 2299.0, 2306.850054746436, 2239.9664005039936, 2255.5150642611834, 2273.9317820465412, 2274.9090036398525, 2275.772422757719, 2245.8091062259728, 2269.239804665439, 2271.5911135995516, 2271.863688178698, 2274.0676322707777, 6909.965450172767, 8098.676052957933, 8167.754967350989, 8168.67325306987, 8198.262156405892, 4931.334269873593, 4934.432540257881, 5126.256692779563, 5141.640085194038, 5146.794128234867, 3079.7844150909445, 3331.666833316673, 3484.4599087141532, 3569.6430356964356, 3574.2136729919375, 1014.4065721552901, 1057.8783439904432, 1072.447689439939, 1081.3457857995957, 1085.5983286184082, 10216.438095904723, 10517.947410262976, 10528.578856845717, 10542.683719488428, 10637.149028077813, 4166.458360413188, 4173.1862286854375, 4351.715349441569, 4358.6730995175485, 4370.322599997005, 8313.584320784057, 8409.2431681149, 8422.989241291085, 8423.241908228289, 8432.072472028072, 5019.573336266385, 5027.2207807789855, 5027.84916452507, 5029.798808047637, 5040.445550989388, 3999.420084087791, 4242.321228603409, 4316.179925814085, 4396.4284642996845, 4750.857474275777, 3487.337405892872, 3511.1046683095647, 3571.1786289153642, 3574.767640103385, 3584.229390681016, 1107.457345900503, 1112.2936935046305, 1113.471101226927, 1125.2235957189514, 1125.2348403085816, 777.8288776469168, 862.2670729879677, 899.0263037374689, 937.0301737701446, 938.7512309238058, 5413.102566152364, 5427.755750991215, 5574.944250557523, 5792.073268277056, 5820.563457740686, 880.8282384934876, 965.6716716316346, 972.6790159247431, 979.0454307050569, 1036.8911264317137, 3365.3942290387477, 3563.982180089083, 3656.835442405098, 3705.27747089315, 3819.4843696101225, 9440.0, 9916.504174791231, 9928.851067233998, 9931.851022234672, 9942.30403871722, 8207.753767386988, 9836.557354919047, 9849.0, 9857.654982075594, 9859.35914165577, 7355.889661655079, 7651.655675494616, 7655.157932627407, 7723.9572657691615, 7733.961330193313, 5397.568194544348, 5414.35027796667, 5582.33012038558, 5643.830685079454, 5687.459691329317, 8835.20483156519, 8902.821943561223, 9024.458532488072, 9049.547522623974, 9142.405743626643, 9103.635854566008, 9254.35219534626, 9354.158125768714, 9358.4384936904, 9382.390144640576, 6985.057017302803, 7122.715091396286, 7124.25195354501, 7127.572345659277, 7138.250483699345, 7483.176850546544, 7621.31408173267, 7680.692772289046, 7687.654055567401, 7697.114831794415, 6591.373819487141, 6918.135233095863, 6931.4108300795, 6993.8251543709775, 6996.4209233718275, 6817.795466136024, 6873.518853680394, 6877.415419689182, 6890.586564806128, 6924.238333783179, 6451.3226111257645, 6520.347965203442, 6588.472922166311, 6599.109120268702, 6601.933980660132, 4732.935089604949, 4739.691920025188, 4751.311059896227, 4772.737499437425, 4898.828541001048, 4718.363020992122, 4836.12949669063, 4851.854444366674, 4870.245112007503, 4950.727709975842], "value": 4276.547480808568, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.5328733887796, 301.3701364148929, 309.04659126594464, 312.721677706841, 315.06425915032344, 4100.85647002355, 5149.485051494848, 5149.613778966574, 5191.402988656301, 5200.583953283738, 3850.9229815403673, 4216.009237829107, 5036.219385995168, 5053.898922021557, 5065.012322597095, 3659.14010207601, 3660.5607327120783, 3870.632289932452, 4015.9397609035886, 4026.134381108061, 3796.810159492028, 3984.4620976168135, 3993.640572348488, 4030.798460076999, 4034.4150098235737, 3608.8015159166234, 3966.7223294369405, 3970.4441378207075, 3983.9203215935663, 3992.6207010334115, 3454.136465883529, 3965.8215380307956, 3981.86063487778, 3988.760674359548, 3988.960110398888, 3445.500402441657, 3456.2569047654624, 3460.567429071366, 3482.564679415073, 3487.343511831885, 4405.757683327416, 4861.222204447272, 4865.270209468573, 5039.899202015939, 5064.924026139611, 3808.6953043756434, 3987.940180897289, 4504.144212599628, 4574.268117101281, 4595.080983803253, 4505.52691967342, 4631.240128751087, 5470.671759694431, 5481.369642491125, 5498.230247765316, 2925.531914893607, 3456.7753096048928, 3667.7615954962844, 3696.464012718168, 3821.191424303135, 5018.623603229772, 5684.687342196177, 5769.144273584602, 5770.509506691937, 5795.710214489301, 3807.078708929807, 3813.694904407641, 3826.253880493277, 3826.445165450994, 3858.5176852893387, 3676.4669122977552, 3856.652901238902, 3862.3240932836643, 3865.536135663738, 3883.4369016493015, 3785.716071294609, 4139.668826493873, 4258.382534532476, 4548.249538826123, 4556.749378784199, 5598.188262701886, 5600.047991841397, 5606.747696353674, 5610.298712660918, 5634.352049514358, 1010.8281592129356, 1249.2691775311364, 1275.2667216350635, 1329.335332333833, 1335.1922087137336, 762.7605141644827, 824.8122703307218, 826.7519744076753, 853.5007020892723, 895.7491902267376, 4035.3139966205817, 5114.693118412907, 5151.2698269917, 5198.896022079537, 5201.609879259071, 4840.661153719206, 5158.432572417031, 5553.944460555343, 5556.249906262684, 5600.803971860965, 4320.623656988643, 4893.972265824145, 5133.6919784813035, 5193.701574606348, 5260.631755777059, 2521.5965445528627, 2744.4511097780523, 2964.584958105867, 2989.237744375214, 3001.9549706754415, 5080.364954380702, 5318.308619879391, 5484.698341591171, 5530.617345663584, 5888.411158884163, 5001.624878134155, 5079.390473143247, 5088.313077734532, 5726.051083691902, 5800.0, 4882.803713090316, 5366.570674346043, 5706.771729130788, 5858.677772722541, 5887.291006665773, 4260.616544511009, 5356.381766099457, 5474.5894057945015, 5524.558035357242, 5578.414266501959, 4204.306289462204, 5328.520433161034, 5464.754086066216, 5580.051391263576, 5598.412166722598, 4448.64410847125, 5309.681419114865, 5440.308420642711, 5809.0, 5865.266841644794, 3363.31052134307, 3680.8159592019756, 3686.146462361577, 3703.9259214815015, 3761.4733937249352, 4838.709677419366, 5536.861578460571, 5555.444455554412, 5569.888602228014, 5586.888262234814, 5079.314292570456, 5364.007658583175, 5388.973055134699, 5464.890702186013, 5495.477929596682, 4396.670249731219, 5077.416097148875, 5241.528262456397, 5330.520253177233, 5424.403315635356, 5100.974495127501, 5124.590032797441, 5168.689878607145, 5310.6813591184655, 5311.575073994149, 5000.574606394632, 5269.288646032736, 5545.615385384323, 5547.389787123332, 5581.776728930798, 4296.914061718686, 4513.991273883091, 4940.580050695731, 5364.927014597019, 5419.482544887438], "value": 3943.483272633484, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.725936884615, 723.3670538279006, 727.7962170592233, 797.5254723439551, 806.2501873257871, 14700.044497107681, 14902.031367961075, 14962.850371496283, 14965.476208332711, 14971.026883252582, 13278.136921100122, 13417.93291033546, 13425.932870335659, 13430.059895807297, 13439.932800335984, 654.9279579246279, 676.8477092654138, 681.8738533371318, 797.6051854332085, 800.4996876951891, 823.9299659528917, 827.210014436215, 839.2740279658083, 840.3235395506615, 841.1252297610508, 894.2622336572354, 896.3232759266745, 896.3725392225468, 896.9327300452492, 897.2821742605922, 696.4393663101201, 718.3821913154683, 889.7775556110972, 890.9643614255422, 891.340408098006, 640.8397900524869, 656.4190691238241, 659.7987613777796, 660.0758937487508, 661.4080398043772, 13852.653683657989, 13878.236696981661, 13894.957878159077, 13901.721965560631, 13912.0, 12645.430955606927, 12768.361581920866, 12874.29191394473, 12929.288889111094, 12941.54704585335, 10930.945345273301, 11258.099352051817, 11740.412979351084, 11943.925046745835, 12003.819942700866, 4287.056847493546, 4322.157179350028, 4341.327094300388, 4444.688871778977, 4447.998400719689, 82782.0, 83584.0, 84247.0, 86075.56962215212, 86510.0, 8583.527905965107, 10265.794684106277, 10298.639547615798, 10335.483225838678, 10759.977802108788, 19222.423327300203, 19261.614767704847, 19267.8073219266, 19302.22791088341, 19307.227710891406, 6691.0967019451755, 7291.270872912773, 7293.671784769592, 7340.11918569775, 7553.584552849646, 4094.467719196485, 4151.875443736692, 4156.9792151039055, 4165.97917010419, 4203.558626344251, 3172.2069482629345, 3219.3561287742077, 3258.9348213036083, 3259.755518336134, 3261.2662151015834, 610.33168680295, 623.1213988276509, 626.117174783553, 637.897936330185, 638.2022471910112, 519.9402068762141, 597.9521638268928, 600.7837178615695, 604.7188057553259, 621.9906701399483, 8181.605102656629, 8635.093315201939, 8655.48067115975, 8660.13398660129, 8671.869921951176, 525.5952916254539, 539.3393093460479, 544.5725105792013, 592.6740292838921, 631.1006815288288, 3839.7504162229366, 4143.958560414418, 4179.393987871742, 4195.559466255999, 4238.321868501025, 82098.0, 86737.0, 102292.48853755833, 103395.4830225844, 103879.0, 82726.58636706778, 84769.99999999876, 95524.52237738766, 98351.0, 100027.9969203387, 83116.58441707752, 89656.55171724099, 91300.0, 91531.54234228787, 94367.99999999863, 8054.275115239657, 8207.097219305992, 8218.63016164262, 8342.457740246864, 8471.068182499797, 54422.0, 54716.72641636767, 54813.1778023322, 55325.170122449, 56072.598185044066, 53436.73281633567, 57905.42094579169, 61234.4691382719, 68678.0, 69910.65044674744, 12273.509059637514, 12299.50801967947, 12475.812862807246, 12502.87497125017, 12592.874071259537, 11917.702057448287, 12177.817332740193, 12242.632721018384, 12816.6795830105, 13728.862711373158, 12820.679483013, 12838.252385536774, 12900.806487902877, 13147.408366623333, 13587.45650173982, 12881.677958050748, 12973.333200712235, 13045.716999524855, 13437.664058398619, 13606.523771667944, 10102.79794404093, 12743.299118548199, 12831.0, 13136.539404172128, 13234.933825330812, 10807.649628615154, 10922.344659320466, 11049.558017679203, 11147.386893720923, 11301.943490282496, 10947.452627368759, 11012.587524187416, 11253.887461125283, 11261.549538018387, 11286.887131128584], "value": 7094.91523533271, "unit": "score"}], "name": "dromaeo_css", "value": 6979.726018192194, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 138.0, 178.0, 136.0, 113.0, 114.0, 109.0, 141.0, 112.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 183.0, 182.0, 183.0, 182.0, 182.0, 182.0, 183.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 59.0, 89.0, 61.0, 62.0, 60.0, 60.0, 82.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 91.0, 79.0, 95.0, 100.0, 74.0, 82.0, 91.0, 125.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 85.0, 86.0, 83.0, 91.0, 84.0, 87.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 101.0, 146.0, 93.0, 94.0, 95.0, 94.0, 93.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 82.0, 82.0, 82.0, 84.0, 84.0, 85.0, 83.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 55.0, 57.0, 56.0, 55.0, 55.0, 56.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 76.0, 75.0, 72.0, 68.0, 71.0, 70.0, 70.0, 72.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 86.0, 88.0, 92.0, 94.0, 104.0, 106.0, 107.0, 96.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 139.0, 162.0, 169.0, 152.0, 150.0, 146.0, 147.0, 197.0, 146.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 172.0, 171.0, 171.0, 173.0, 171.0, 172.0, 172.0, 172.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 66.0, 66.0, 75.0, 86.0, 88.0, 66.0], "value": 71.7, "unit": "score"}], "name": "kraken", "value": 1472.0000000000005, "alertThreshold": 2.0}]} 07:19:57 INFO - Return code: 0 07:19:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:19:57 INFO - # TBPL SUCCESS # 07:19:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:57 INFO - 2016-11-04 07:19:57.931 screenresolution[1667:9884] starting screenresolution argv=screenresolution get 07:19:57 INFO - 2016-11-04 07:19:57.935 screenresolution[1667:9884] Display 0: 1600x1200x32@60 07:19:57 INFO - 2016-11-04 07:19:57.941 screenresolution[1668:9887] starting screenresolution argv=screenresolution list 07:19:57 INFO - Available Modes on Display 0 07:19:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:58 INFO - Intel Iris: 07:19:58 INFO - Chipset Model: Intel Iris 07:19:58 INFO - Type: GPU 07:19:58 INFO - Bus: Built-In 07:19:58 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:58 INFO - Vendor: Intel (0x8086) 07:19:58 INFO - Device ID: 0x0a2e 07:19:58 INFO - Revision ID: 0x0009 07:19:58 INFO - Displays: 07:19:58 INFO - Display: 07:19:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:58 INFO - Display Serial Number: 1600x1200 60 07:19:58 INFO - Main Display: Yes 07:19:58 INFO - Mirror: Off 07:19:58 INFO - Online: Yes 07:19:58 INFO - Rotation: Supported 07:19:58 INFO - Return code: 0 07:19:58 INFO - Running post-action listener: _resource_record_post_action 07:19:58 INFO - [mozharness: 2016-11-04 14:19:58.182645Z] Finished run-tests step (success) 07:19:58 INFO - Running post-run listener: _resource_record_post_run 07:19:58 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 37565440; Write bytes: 707192832; Read time: 238; Write time: 1931 07:19:58 INFO - TinderboxPrint: CPU usage
53.5% 07:19:58 INFO - TinderboxPrint: I/O read bytes / time
37,565,440 / 238 07:19:58 INFO - TinderboxPrint: I/O write bytes / time
707,192,832 / 1,931 07:19:58 INFO - TinderboxPrint: CPU idle
2,262.6 (46.5%) 07:19:58 INFO - TinderboxPrint: CPU system
970.0 (20.0%) 07:19:58 INFO - TinderboxPrint: CPU user
1,629.0 (33.5%) 07:19:58 INFO - TinderboxPrint: Swap in / out
444,911,616 / 0 07:19:58 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397984256; Write bytes: 391735296; Read time: 29364; Write time: 774 07:19:58 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 35636224; Write bytes: 296902656; Read time: 220; Write time: 1110 07:19:58 INFO - Running post-run listener: _upload_blobber_files 07:19:58 INFO - Blob upload gear active. 07:19:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:19:58 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 07:19:58 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:19:58 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:19:59 INFO - (blobuploader) - INFO - Open directory for files ... 07:19:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:19:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:19:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:20:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:00 INFO - (blobuploader) - INFO - Done attempting. 07:20:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:20:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:20:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:01 INFO - (blobuploader) - INFO - Done attempting. 07:20:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:03 INFO - (blobuploader) - INFO - Done attempting. 07:20:03 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:03 INFO - Return code: 0 07:20:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cc06e103959720817958ef9cdcc0afa477e02b3f0e37aed45e6b3fd323b90ee45989a48d404c4b288b364038f0a48d0eac502e905558bba25b5e43303103a479", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/736a4340a50c439261d93f55909184236553f0707506528a207af81bb4c3cf6f6999102850577dd0e905d6cad9de1396d0f89c12d14d92cd15be97856f3b9956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cee07f733bffb6269032548fc9459580c3e71cf73e843279614abc6c4c2fc1a7e27b0d1429f3c5d7f8644a94703f980b3aa787cb0d826d36c328026c8aeb6e53"} 07:20:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:03 INFO - Contents: 07:20:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cc06e103959720817958ef9cdcc0afa477e02b3f0e37aed45e6b3fd323b90ee45989a48d404c4b288b364038f0a48d0eac502e905558bba25b5e43303103a479", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/736a4340a50c439261d93f55909184236553f0707506528a207af81bb4c3cf6f6999102850577dd0e905d6cad9de1396d0f89c12d14d92cd15be97856f3b9956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cee07f733bffb6269032548fc9459580c3e71cf73e843279614abc6c4c2fc1a7e27b0d1429f3c5d7f8644a94703f980b3aa787cb0d826d36c328026c8aeb6e53"} 07:20:03 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:03 INFO - Copying logs to upload dir... 07:20:03 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.377779 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-04 07:20:03.276417) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 07:20:03.279561) ========= 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.gMvYmmvMSS/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.iM2jq0htKk/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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/Larch/sha512/cc06e103959720817958ef9cdcc0afa477e02b3f0e37aed45e6b3fd323b90ee45989a48d404c4b288b364038f0a48d0eac502e905558bba25b5e43303103a479", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/736a4340a50c439261d93f55909184236553f0707506528a207af81bb4c3cf6f6999102850577dd0e905d6cad9de1396d0f89c12d14d92cd15be97856f3b9956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cee07f733bffb6269032548fc9459580c3e71cf73e843279614abc6c4c2fc1a7e27b0d1429f3c5d7f8644a94703f980b3aa787cb0d826d36c328026c8aeb6e53"} build_url:https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011076 build_url: 'https://queue.taskcluster.net/v1/task/LJ19FAhjRUCiv5O-Pg6IvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cc06e103959720817958ef9cdcc0afa477e02b3f0e37aed45e6b3fd323b90ee45989a48d404c4b288b364038f0a48d0eac502e905558bba25b5e43303103a479", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/736a4340a50c439261d93f55909184236553f0707506528a207af81bb4c3cf6f6999102850577dd0e905d6cad9de1396d0f89c12d14d92cd15be97856f3b9956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cee07f733bffb6269032548fc9459580c3e71cf73e843279614abc6c4c2fc1a7e27b0d1429f3c5d7f8644a94703f980b3aa787cb0d826d36c328026c8aeb6e53"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 07:20:03.312982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 07:20:03.313319) ========= 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.gMvYmmvMSS/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.iM2jq0htKk/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.005017 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 07:20:03.363096) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-04 07:20:03.363418) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-04 07:20:03.363869) ========= ========= Total master_lag: 0.18 =========