builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0308 starttime: 1493872266.9 results: success (0) buildid: 20170503201922 builduid: ac67df80deed47a18214dc6749d92291 revision: 4a6a71f4aa22e4dc3961884ce505ce34bdd799a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:06.898773) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:06.899297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:06.899573) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:06.928216) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:06.928571) ========= 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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-03 21:31:06-- 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% 7.75M=0.001s 2017-05-03 21:31:07 (7.75 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127476 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:07.080095) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:07.080417) ========= 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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.023584 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:07.129411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 21:31:07.129808) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4a6a71f4aa22e4dc3961884ce505ce34bdd799a2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4a6a71f4aa22e4dc3961884ce505ce34bdd799a2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-03 21:31:07,246 truncating revision to first 12 chars 2017-05-03 21:31:07,246 Setting DEBUG logging. 2017-05-03 21:31:07,246 attempt 1/10 2017-05-03 21:31:07,246 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4a6a71f4aa22?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 21:31:08,235 unpacking tar archive at: mozilla-central-4a6a71f4aa22/testing/mozharness/ program finished with exit code 0 elapsedTime=1.253305 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 21:31:08.407553) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:08.407871) ========= script_repo_revision: 4a6a71f4aa22e4dc3961884ce505ce34bdd799a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:08.408263) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:08.408548) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 21:31:08.428772) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-03 21:31:08.429153) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:31:08 INFO - MultiFileLogger online at 20170503 21:31:08 in /builds/slave/test 21:31:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:31:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:31:08 INFO - {'append_to_log': False, 21:31:08 INFO - 'base_work_dir': '/builds/slave/test', 21:31:08 INFO - 'blob_upload_branch': 'Firefox', 21:31:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:31:08 INFO - 'branch': 'Firefox', 21:31:08 INFO - 'buildbot_json_path': 'buildprops.json', 21:31:08 INFO - 'config_files': ('talos/mac_config.py',), 21:31:08 INFO - 'default_actions': ('clobber', 21:31:08 INFO - 'read-buildbot-config', 21:31:08 INFO - 'download-and-extract', 21:31:08 INFO - 'populate-webroot', 21:31:08 INFO - 'create-virtualenv', 21:31:08 INFO - 'install', 21:31:08 INFO - 'run-tests'), 21:31:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:31:08 INFO - 'download_minidump_stackwalk': True, 21:31:08 INFO - 'download_symbols': 'ondemand', 21:31:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:31:08 INFO - 'tooltool.py': '/tools/tooltool.py', 21:31:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:31:08 INFO - '/tools/misc-python/virtualenv.py')}, 21:31:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:31:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:31:08 INFO - 'gecko_profile': False, 21:31:08 INFO - 'gecko_profile_interval': 0, 21:31:08 INFO - 'installer_path': 'installer.exe', 21:31:08 INFO - 'log_level': 'info', 21:31:08 INFO - 'log_name': 'talos', 21:31:08 INFO - 'log_to_console': True, 21:31:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:31:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:31:08 INFO - 'opt_config_files': (), 21:31:08 INFO - 'pip_index': False, 21:31:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:31:08 INFO - 'cmd': ('bash', 21:31:08 INFO - '-c', 21:31:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:31:08 INFO - 'enabled': True, 21:31:08 INFO - 'halt_on_failure': False, 21:31:08 INFO - 'name': 'check_screen_resolution'},), 21:31:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:31:08 INFO - 'cmd': ('bash', 21:31:08 INFO - '-c', 21:31:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:31:08 INFO - 'enabled': True, 21:31:08 INFO - 'halt_on_failure': False, 21:31:08 INFO - 'name': 'check_screen_resolution'},), 21:31:08 INFO - 'run_cmd_checks_enabled': True, 21:31:08 INFO - 'suite': 'dromaeojs', 21:31:08 INFO - 'system_bits': '32', 21:31:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:31:08 INFO - 'title': 't-yosemite-r7-0308', 21:31:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:31:08 INFO - 'use_talos_json': True, 21:31:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:31:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:31:08 INFO - 'work_dir': 'build'} 21:31:08 INFO - [mozharness: 2017-05-04 04:31:08.667704Z] Running clobber step. 21:31:08 INFO - Running pre-action listener: _resource_record_pre_action 21:31:08 INFO - Running main action method: clobber 21:31:08 INFO - rmtree: /builds/slave/test/build 21:31:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:31:10 INFO - Running post-action listener: _resource_record_post_action 21:31:10 INFO - [mozharness: 2017-05-04 04:31:10.644330Z] Finished clobber step (success) 21:31:10 INFO - [mozharness: 2017-05-04 04:31:10.644457Z] Running read-buildbot-config step. 21:31:10 INFO - Running pre-action listener: _resource_record_pre_action 21:31:10 INFO - Running main action method: read_buildbot_config 21:31:10 INFO - Using buildbot properties: 21:31:10 INFO - { 21:31:10 INFO - "project": "", 21:31:10 INFO - "product": "firefox", 21:31:10 INFO - "script_repo_revision": "production", 21:31:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 21:31:10 INFO - "repository": "", 21:31:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 21:31:10 INFO - "buildid": "20170503201922", 21:31:10 INFO - "pgo_build": "False", 21:31:10 INFO - "basedir": "/builds/slave/test", 21:31:10 INFO - "buildnumber": 34, 21:31:10 INFO - "slavename": "t-yosemite-r7-0308", 21:31:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 21:31:10 INFO - "platform": "yosemite_r7", 21:31:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:31:10 INFO - "branch": "mozilla-central", 21:31:10 INFO - "repo_path": "mozilla-central", 21:31:10 INFO - "revision": "4a6a71f4aa22e4dc3961884ce505ce34bdd799a2", 21:31:10 INFO - "stage_platform": "macosx64", 21:31:10 INFO - "builduid": "ac67df80deed47a18214dc6749d92291", 21:31:10 INFO - "slavebuilddir": "test" 21:31:10 INFO - } 21:31:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 21:31:10 INFO - Running post-action listener: _resource_record_post_action 21:31:10 INFO - [mozharness: 2017-05-04 04:31:10.648021Z] Finished read-buildbot-config step (success) 21:31:10 INFO - [mozharness: 2017-05-04 04:31:10.648137Z] Running download-and-extract step. 21:31:10 INFO - Running pre-action listener: _resource_record_pre_action 21:31:10 INFO - Running main action method: download_and_extract 21:31:10 INFO - mkdir: /builds/slave/test/build/tests 21:31:10 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')]} 21:31:10 INFO - trying https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 21:31:10 INFO - Downloading https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 21:31:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 21:31:11 INFO - Downloaded 1325 bytes. 21:31:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 21:31:11 INFO - Using the following test package requirements: 21:31:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 21:31:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:31:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 21:31:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 21:31:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'jsshell-mac.zip'], 21:31:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 21:31:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:31:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 21:31:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 21:31:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 21:31:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:31:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 21:31:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:31:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:31:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:31:11 INFO - Fetch https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:31:13 INFO - Content-Length response header: 52700528 21:31:13 INFO - Bytes received: 52700528 21:31:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:31:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:31:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:31:30 INFO - Fetch https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:31:32 INFO - Content-Length response header: 52700528 21:31:32 INFO - Bytes received: 52700528 21:31:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 21:31:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:31:47 INFO - Fetch https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 21:31:48 INFO - Content-Length response header: 14408809 21:31:48 INFO - Bytes received: 14408809 21:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:49 INFO - trying https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 21:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 21:31:51 INFO - Downloaded 70883965 bytes. 21:31:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:31:51 INFO - mkdir: /builds/slave/test/properties 21:31:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:31:51 INFO - Writing to file /builds/slave/test/properties/build_url 21:31:51 INFO - Contents: 21:31:51 INFO - build_url:https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:31:51 INFO - Running post-action listener: _resource_record_post_action 21:31:51 INFO - Running post-action listener: set_extra_try_arguments 21:31:51 INFO - [mozharness: 2017-05-04 04:31:51.680233Z] Finished download-and-extract step (success) 21:31:51 INFO - [mozharness: 2017-05-04 04:31:51.680376Z] Running populate-webroot step. 21:31:51 INFO - Running pre-action listener: _resource_record_pre_action 21:31:51 INFO - Running main action method: populate_webroot 21:31:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:31:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:31:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:31:51 INFO - '--tppagecycles', 21:31:51 INFO - '7'], 21:31:51 INFO - 'tests': ['tsvgm']}}, 21:31:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:31:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:31:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:31:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:31:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:31:51 INFO - 'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:31:51 INFO - 'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['damp', 'tps']}, 21:31:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:31:51 INFO - 'tests': ['damp', 'tps']}, 21:31:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['dromaeo_dom']}, 21:31:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:31:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 21:31:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:31:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['a11yr', 21:31:51 INFO - 'ts_paint', 21:31:51 INFO - 'tpaint', 21:31:51 INFO - 'sessionrestore', 21:31:51 INFO - 'sessionrestore_no_auto_restore', 21:31:51 INFO - 'tabpaint']}, 21:31:51 INFO - 'other-e10s': {'tests': ['a11yr', 21:31:51 INFO - 'ts_paint', 21:31:51 INFO - 'tpaint', 21:31:51 INFO - 'sessionrestore', 21:31:51 INFO - 'sessionrestore_no_auto_restore', 21:31:51 INFO - 'tabpaint']}, 21:31:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:31:51 INFO - 'ts_paint', 21:31:51 INFO - 'tpaint', 21:31:51 INFO - 'sessionrestore', 21:31:51 INFO - 'sessionrestore_no_auto_restore', 21:31:51 INFO - 'tabpaint']}, 21:31:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:31:51 INFO - 'ts_paint', 21:31:51 INFO - 'tpaint', 21:31:51 INFO - 'sessionrestore', 21:31:51 INFO - 'sessionrestore_no_auto_restore', 21:31:51 INFO - 'tabpaint']}, 21:31:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['a11yr', 21:31:51 INFO - 'ts_paint', 21:31:51 INFO - 'tpaint', 21:31:51 INFO - 'sessionrestore', 21:31:51 INFO - 'sessionrestore_no_auto_restore', 21:31:51 INFO - 'tabpaint']}, 21:31:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['a11yr', 21:31:51 INFO - 'ts_paint', 21:31:51 INFO - 'tpaint', 21:31:51 INFO - 'sessionrestore', 21:31:51 INFO - 'sessionrestore_no_auto_restore', 21:31:51 INFO - 'tabpaint']}, 21:31:51 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 21:31:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 21:31:51 INFO - 'bloom_basic_ref']}, 21:31:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['tsvgx', 21:31:51 INFO - 'tsvgr_opacity', 21:31:51 INFO - 'tart', 21:31:51 INFO - 'tscrollx', 21:31:51 INFO - 'cart', 21:31:51 INFO - 'tsvg_static']}, 21:31:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:31:51 INFO - 'tsvgr_opacity', 21:31:51 INFO - 'tart', 21:31:51 INFO - 'tscrollx', 21:31:51 INFO - 'cart', 21:31:51 INFO - 'tsvg_static']}, 21:31:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:31:51 INFO - 'talos_options': ['--disable-e10s'], 21:31:51 INFO - 'tests': ['tp5o']}, 21:31:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:31:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:31:51 INFO - 'talos_options': ['--disable-e10s', 21:31:51 INFO - '--xperf_path', 21:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:51 INFO - 'tests': ['tp5n']}, 21:31:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:31:51 INFO - 'talos_options': ['--xperf_path', 21:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:51 INFO - 'tests': ['tp5n']}}, 21:31:51 INFO - 'talos.zip': {'path': '', 21:31:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:31:51 INFO - Running post-action listener: _resource_record_post_action 21:31:51 INFO - [mozharness: 2017-05-04 04:31:51.699578Z] Finished populate-webroot step (success) 21:31:51 INFO - [mozharness: 2017-05-04 04:31:51.699695Z] Running create-virtualenv step. 21:31:51 INFO - Running pre-action listener: _resource_record_pre_action 21:31:51 INFO - Running main action method: create_virtualenv 21:31:51 INFO - Creating virtualenv /builds/slave/test/build/venv 21:31:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:31:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:31:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:31:51 INFO - Using real prefix '/tools/python27' 21:31:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:31:52 INFO - Installing distribute.............................................................................................................................................................................................done. 21:31:55 INFO - Installing pip.................done. 21:31:55 INFO - Return code: 0 21:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:31:55 INFO - Reading from file tmpfile_stdout 21:31:55 INFO - Output received: 21:31:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:31:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:31:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:55 INFO - 'HOME': '/Users/cltbld', 21:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:55 INFO - 'LOGNAME': 'cltbld', 21:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:55 INFO - 'MOZ_NO_REMOTE': '1', 21:31:55 INFO - 'NO_EM_RESTART': '1', 21:31:55 INFO - 'PAGER': '/bin/cat', 21:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:55 INFO - 'PWD': '/builds/slave/test', 21:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:55 INFO - 'SHELL': '/bin/bash', 21:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:31:55 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:55 INFO - 'USER': 'cltbld', 21:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:55 INFO - 'XPC_FLAGS': '0x0', 21:31:55 INFO - 'XPC_SERVICE_NAME': '0', 21:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:55 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 21:31:55 INFO - Cleaning up... 21:31:55 INFO - Return code: 0 21:31:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:31:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:55 INFO - 'HOME': '/Users/cltbld', 21:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:55 INFO - 'LOGNAME': 'cltbld', 21:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:55 INFO - 'MOZ_NO_REMOTE': '1', 21:31:55 INFO - 'NO_EM_RESTART': '1', 21:31:55 INFO - 'PAGER': '/bin/cat', 21:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:55 INFO - 'PWD': '/builds/slave/test', 21:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:55 INFO - 'SHELL': '/bin/bash', 21:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:31:55 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:55 INFO - 'USER': 'cltbld', 21:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:55 INFO - 'XPC_FLAGS': '0x0', 21:31:55 INFO - 'XPC_SERVICE_NAME': '0', 21:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:56 INFO - Downloading/unpacking psutil>=3.1.1 21:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:31:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:58 INFO - Installing collected packages: psutil 21:31:58 INFO - Running setup.py install for psutil 21:31:58 INFO - building 'psutil._psutil_osx' extension 21:31:58 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 21:31:58 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 21:31:58 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 21:31:59 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 21:31:59 INFO - building 'psutil._psutil_posix' extension 21:31:59 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 21:31:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:31:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:31:59 INFO - ^ 21:31:59 INFO - 1 warning generated. 21:31:59 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 21:31:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:59 INFO - Successfully installed psutil 21:31:59 INFO - Cleaning up... 21:31:59 INFO - Return code: 0 21:31:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:31:59 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')]} 21:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:59 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')]} 21:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:59 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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:31:59 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 21:31:59 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 21:31:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:31:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:59 INFO - 'HOME': '/Users/cltbld', 21:31:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:59 INFO - 'LOGNAME': 'cltbld', 21:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:59 INFO - 'MOZ_NO_REMOTE': '1', 21:31:59 INFO - 'NO_EM_RESTART': '1', 21:31:59 INFO - 'PAGER': '/bin/cat', 21:31:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:59 INFO - 'PWD': '/builds/slave/test', 21:31:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:59 INFO - 'SHELL': '/bin/bash', 21:31:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:31:59 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:31:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:59 INFO - 'USER': 'cltbld', 21:31:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:59 INFO - 'XPC_FLAGS': '0x0', 21:31:59 INFO - 'XPC_SERVICE_NAME': '0', 21:31:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:31:59 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 21:31:59 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 21:31:59 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 21:31:59 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 21:31:59 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 21:31:59 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 21:32:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:32:01 INFO - Installing collected packages: mozsystemmonitor 21:32:01 INFO - Running setup.py install for mozsystemmonitor 21:32:01 INFO - Successfully installed mozsystemmonitor 21:32:01 INFO - Cleaning up... 21:32:01 INFO - Return code: 0 21:32:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:32:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:01 INFO - 'HOME': '/Users/cltbld', 21:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:01 INFO - 'LOGNAME': 'cltbld', 21:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:01 INFO - 'MOZ_NO_REMOTE': '1', 21:32:01 INFO - 'NO_EM_RESTART': '1', 21:32:01 INFO - 'PAGER': '/bin/cat', 21:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:01 INFO - 'PWD': '/builds/slave/test', 21:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:01 INFO - 'SHELL': '/bin/bash', 21:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:01 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:01 INFO - 'USER': 'cltbld', 21:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:01 INFO - 'XPC_FLAGS': '0x0', 21:32:01 INFO - 'XPC_SERVICE_NAME': '0', 21:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:01 INFO - Downloading/unpacking jsonschema==2.5.1 21:32:01 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 21:32:01 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 21:32:01 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 21:32:01 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 21:32:01 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 21:32:01 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 21:32:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:32:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:32:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:32:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:32:03 INFO - Downloading functools32-3.2.3-2.tar.gz 21:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:32:03 INFO - warning: no files found matching '*.txt' 21:32:03 INFO - no previously-included directories found matching 'build' 21:32:03 INFO - no previously-included directories found matching 'dist' 21:32:03 INFO - no previously-included directories found matching '.git*' 21:32:03 INFO - Installing collected packages: jsonschema, functools32 21:32:03 INFO - Running setup.py install for functools32 21:32:03 INFO - warning: no files found matching '*.txt' 21:32:03 INFO - no previously-included directories found matching 'build' 21:32:03 INFO - no previously-included directories found matching 'dist' 21:32:03 INFO - no previously-included directories found matching '.git*' 21:32:03 INFO - Successfully installed jsonschema functools32 21:32:03 INFO - Cleaning up... 21:32:03 INFO - Return code: 0 21:32:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:32:03 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')]} 21:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:03 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')]} 21:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:04 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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:32:04 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 21:32:04 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 21:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:04 INFO - 'HOME': '/Users/cltbld', 21:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:04 INFO - 'LOGNAME': 'cltbld', 21:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:04 INFO - 'MOZ_NO_REMOTE': '1', 21:32:04 INFO - 'NO_EM_RESTART': '1', 21:32:04 INFO - 'PAGER': '/bin/cat', 21:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:04 INFO - 'PWD': '/builds/slave/test', 21:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:04 INFO - 'SHELL': '/bin/bash', 21:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:04 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:04 INFO - 'USER': 'cltbld', 21:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:04 INFO - 'XPC_FLAGS': '0x0', 21:32:04 INFO - 'XPC_SERVICE_NAME': '0', 21:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:32:04 INFO - Cleaning up... 21:32:04 INFO - Return code: 0 21:32:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:32:04 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')]} 21:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:04 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')]} 21:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:05 INFO - 'HOME': '/Users/cltbld', 21:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:05 INFO - 'LOGNAME': 'cltbld', 21:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:05 INFO - 'MOZ_NO_REMOTE': '1', 21:32:05 INFO - 'NO_EM_RESTART': '1', 21:32:05 INFO - 'PAGER': '/bin/cat', 21:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:05 INFO - 'PWD': '/builds/slave/test', 21:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:05 INFO - 'SHELL': '/bin/bash', 21:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:05 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:05 INFO - 'USER': 'cltbld', 21:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:05 INFO - 'XPC_FLAGS': '0x0', 21:32:05 INFO - 'XPC_SERVICE_NAME': '0', 21:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:05 INFO - Downloading/unpacking blobuploader==1.2.4 21:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:32:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:32:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:32:06 INFO - Downloading blobuploader-1.2.4.tar.gz 21:32:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:32:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:32:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 INFO - Downloading docopt-0.6.1.tar.gz 21:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:32:07 INFO - Installing collected packages: blobuploader, requests, docopt 21:32:07 INFO - Running setup.py install for blobuploader 21:32:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:32:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:32:08 INFO - Running setup.py install for requests 21:32:08 INFO - Running setup.py install for docopt 21:32:08 INFO - Successfully installed blobuploader requests docopt 21:32:08 INFO - Cleaning up... 21:32:08 INFO - Return code: 0 21:32:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:32:08 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')]} 21:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:08 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')]} 21:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:08 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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:32:08 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 21:32:08 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 21:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:08 INFO - 'HOME': '/Users/cltbld', 21:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:08 INFO - 'LOGNAME': 'cltbld', 21:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:08 INFO - 'MOZ_NO_REMOTE': '1', 21:32:08 INFO - 'NO_EM_RESTART': '1', 21:32:08 INFO - 'PAGER': '/bin/cat', 21:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:08 INFO - 'PWD': '/builds/slave/test', 21:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:08 INFO - 'SHELL': '/bin/bash', 21:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:08 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:08 INFO - 'USER': 'cltbld', 21:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:08 INFO - 'XPC_FLAGS': '0x0', 21:32:08 INFO - 'XPC_SERVICE_NAME': '0', 21:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-5spk2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-Gs8xGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-j2Uuq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-KxJvL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-WmxrtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-mxXMLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-c66_XL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-2FBFio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-310q29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:32:09 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-XiAyhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:32:10 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-GtdMs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:32:10 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-tbejDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:32:10 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-9nIXPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:32:10 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-Fk119j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:10 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-9pYnR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:32:10 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-grfFTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:32:10 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-uYtEbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:32:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:32:10 INFO - Running setup.py install for manifestparser 21:32:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:32:10 INFO - Running setup.py install for mozcrash 21:32:11 INFO - Running setup.py install for mozdebug 21:32:11 INFO - Running setup.py install for mozdevice 21:32:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Running setup.py install for mozfile 21:32:11 INFO - Running setup.py install for mozhttpd 21:32:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Running setup.py install for mozinfo 21:32:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Running setup.py install for mozInstall 21:32:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Running setup.py install for mozleak 21:32:12 INFO - Running setup.py install for mozlog 21:32:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:32:12 INFO - Running setup.py install for moznetwork 21:32:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:32:12 INFO - Running setup.py install for mozprocess 21:32:12 INFO - Running setup.py install for mozprofile 21:32:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:32:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:32:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:32:12 INFO - Running setup.py install for mozrunner 21:32:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:32:12 INFO - Running setup.py install for mozscreenshot 21:32:13 INFO - Running setup.py install for moztest 21:32:13 INFO - Running setup.py install for mozversion 21:32:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:32:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:32:13 INFO - Cleaning up... 21:32:13 INFO - Return code: 0 21:32:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:13 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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:32:13 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 21:32:13 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 21:32:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:13 INFO - 'HOME': '/Users/cltbld', 21:32:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:13 INFO - 'LOGNAME': 'cltbld', 21:32:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:13 INFO - 'MOZ_NO_REMOTE': '1', 21:32:13 INFO - 'NO_EM_RESTART': '1', 21:32:13 INFO - 'PAGER': '/bin/cat', 21:32:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:13 INFO - 'PWD': '/builds/slave/test', 21:32:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:13 INFO - 'SHELL': '/bin/bash', 21:32:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:13 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:13 INFO - 'USER': 'cltbld', 21:32:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:13 INFO - 'XPC_FLAGS': '0x0', 21:32:13 INFO - 'XPC_SERVICE_NAME': '0', 21:32:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:32:13 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ZA9aRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:32:13 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)) 21:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:32:13 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-cp8NcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:32:13 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-CpzbHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:32:13 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)) 21:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:32:13 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-xJdYbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-9B0Zn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:32:14 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)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-I8coRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:32:14 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)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-YCAGHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:32:14 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)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ftqLBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:32:14 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)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-kOBPAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:32:14 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)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-K19ghz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:32:14 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)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-QVfntG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:32:14 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)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-qAdpg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:32:14 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-CpOUPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:32:15 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)) 21:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:32:15 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ikKKQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:15 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-iQTQjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:15 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)) 21:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:32:15 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-7NFbaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:32:15 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-7978Ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:32:15 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)) 21:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:32:15 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)) 21:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:32:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:32: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 21:32: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 21:32: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 21:32: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 21:32: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 21:32: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 21:32:16 INFO - Downloading blessings-1.6.tar.gz 21:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:32:16 INFO - Installing collected packages: blessings 21:32:16 INFO - Running setup.py install for blessings 21:32:17 INFO - Successfully installed blessings 21:32:17 INFO - Cleaning up... 21:32:17 INFO - Return code: 0 21:32:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:32:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:32:17 INFO - Reading from file tmpfile_stdout 21:32:17 INFO - Current package versions: 21:32:17 INFO - blessings == 1.6 21:32:17 INFO - blobuploader == 1.2.4 21:32:17 INFO - docopt == 0.6.1 21:32:17 INFO - functools32 == 3.2.3-2 21:32:17 INFO - jsonschema == 2.5.1 21:32:17 INFO - manifestparser == 1.1 21:32:17 INFO - mozInstall == 1.12 21:32:17 INFO - mozcrash == 1.0 21:32:17 INFO - mozdebug == 0.1 21:32:17 INFO - mozdevice == 0.50 21:32:17 INFO - mozfile == 1.2 21:32:17 INFO - mozhttpd == 0.7 21:32:17 INFO - mozinfo == 0.9 21:32:17 INFO - mozleak == 0.1 21:32:17 INFO - mozlog == 3.4 21:32:17 INFO - moznetwork == 0.27 21:32:17 INFO - mozprocess == 0.25 21:32:17 INFO - mozprofile == 0.28 21:32:17 INFO - mozrunner == 6.13 21:32:17 INFO - mozscreenshot == 0.1 21:32:17 INFO - mozsystemmonitor == 0.3 21:32:17 INFO - moztest == 0.8 21:32:17 INFO - mozversion == 1.4 21:32:17 INFO - psutil == 3.1.1 21:32:17 INFO - requests == 1.2.3 21:32:17 INFO - wsgiref == 0.1.2 21:32:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:32: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')]} 21:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32: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')]} 21:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:17 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 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:32:17 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 21:32:17 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 21:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:17 INFO - 'HOME': '/Users/cltbld', 21:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:17 INFO - 'LOGNAME': 'cltbld', 21:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:17 INFO - 'MOZ_NO_REMOTE': '1', 21:32:17 INFO - 'NO_EM_RESTART': '1', 21:32:17 INFO - 'PAGER': '/bin/cat', 21:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:17 INFO - 'PWD': '/builds/slave/test', 21:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:17 INFO - 'SHELL': '/bin/bash', 21:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:17 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:17 INFO - 'USER': 'cltbld', 21:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:17 INFO - 'XPC_FLAGS': '0x0', 21:32:17 INFO - 'XPC_SERVICE_NAME': '0', 21:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 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)) 21:32:17 INFO - Cleaning up... 21:32:17 INFO - Return code: 0 21:32:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:32: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')]} 21:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32: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')]} 21:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa920d76840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, '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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 21:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:17 INFO - 'HOME': '/Users/cltbld', 21:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:17 INFO - 'LOGNAME': 'cltbld', 21:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:17 INFO - 'MOZ_NO_REMOTE': '1', 21:32:17 INFO - 'NO_EM_RESTART': '1', 21:32:17 INFO - 'PAGER': '/bin/cat', 21:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:17 INFO - 'PWD': '/builds/slave/test', 21:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:17 INFO - 'SHELL': '/bin/bash', 21:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:17 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:17 INFO - 'USER': 'cltbld', 21:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:17 INFO - 'XPC_FLAGS': '0x0', 21:32:17 INFO - 'XPC_SERVICE_NAME': '0', 21:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:32:17 INFO - Cleaning up... 21:32:17 INFO - Return code: 0 21:32:17 INFO - Running post-action listener: _resource_record_post_action 21:32:17 INFO - Running post-action listener: _start_resource_monitoring 21:32:17 INFO - Starting resource monitoring. 21:32:17 INFO - [mozharness: 2017-05-04 04:32:17.979204Z] Finished create-virtualenv step (success) 21:32:17 INFO - [mozharness: 2017-05-04 04:32:17.980223Z] Running install step. 21:32:17 INFO - Running pre-action listener: _resource_record_pre_action 21:32:17 INFO - Running main action method: install 21:32:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:32:18 INFO - Reading from file tmpfile_stdout 21:32:18 INFO - Detecting whether we're running mozinstall >=1.0... 21:32:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:32:18 INFO - Reading from file tmpfile_stdout 21:32:18 INFO - Output received: 21:32:18 INFO - Usage: mozinstall [options] installer 21:32:18 INFO - Options: 21:32:18 INFO - -h, --help show this help message and exit 21:32:18 INFO - -d DEST, --destination=DEST 21:32:18 INFO - Directory to install application into. [default: 21:32:18 INFO - "/builds/slave/test"] 21:32:18 INFO - --app=APP Application being installed. [default: firefox] 21:32:18 INFO - mkdir: /builds/slave/test/build/application 21:32:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:32:37 INFO - Reading from file tmpfile_stdout 21:32:37 INFO - Output received: 21:32:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:32:37 INFO - Running post-action listener: _resource_record_post_action 21:32:37 INFO - [mozharness: 2017-05-04 04:32:37.788434Z] Finished install step (success) 21:32:37 INFO - [mozharness: 2017-05-04 04:32:37.788585Z] Running run-tests step. 21:32:37 INFO - Running pre-action listener: _resource_record_pre_action 21:32:37 INFO - Running main action method: run_tests 21:32:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:32:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:32:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:32:37 INFO - 2017-05-03 21:32:37.802 screenresolution[2966:89778] starting screenresolution argv=screenresolution get 21:32:37 INFO - 2017-05-03 21:32:37.805 screenresolution[2966:89778] Display 0: 1600x1200x32@60 21:32:37 INFO - 2017-05-03 21:32:37.811 screenresolution[2967:89781] starting screenresolution argv=screenresolution list 21:32:37 INFO - Available Modes on Display 0 21:32:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:32:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:32:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:32:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:32:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:32:38 INFO - Intel Iris: 21:32:38 INFO - Chipset Model: Intel Iris 21:32:38 INFO - Type: GPU 21:32:38 INFO - Bus: Built-In 21:32:38 INFO - VRAM (Dynamic, Max): 1536 MB 21:32:38 INFO - Vendor: Intel (0x8086) 21:32:38 INFO - Device ID: 0x0a2e 21:32:38 INFO - Revision ID: 0x0009 21:32:38 INFO - Displays: 21:32:38 INFO - Display: 21:32:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:32:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:32:38 INFO - Display Serial Number: 1600x1200 60 21:32:38 INFO - Main Display: Yes 21:32:38 INFO - Mirror: Off 21:32:38 INFO - Online: Yes 21:32:38 INFO - Rotation: Supported 21:32:38 INFO - Return code: 0 21:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:32:38 INFO - Python 2.7.3 21:32:38 INFO - Return code: 0 21:32:38 INFO - grabbing minidump binary from tooltool 21:32:38 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')]} 21:32:38 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 0x1101c7d30>, '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 0x1100eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101bcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:32:38 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 21:32:38 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 21:32:38 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 21:32:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:32:38 INFO - Return code: 0 21:32:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:32:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:32:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:32:38 INFO - ENV: RUST_BACKTRACE is now 1 21:32:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:32:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 21:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0308 --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 21:32:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iFxMdi4zZm/Render', 21:32:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:38 INFO - 'HOME': '/Users/cltbld', 21:32:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:38 INFO - 'LOGNAME': 'cltbld', 21:32:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:32:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:38 INFO - 'MOZ_NO_REMOTE': '1', 21:32:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:32:38 INFO - 'NO_EM_RESTART': '1', 21:32:38 INFO - 'PAGER': '/bin/cat', 21:32:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:38 INFO - 'PWD': '/builds/slave/test', 21:32:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:32:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:38 INFO - 'RUST_BACKTRACE': '1', 21:32:38 INFO - 'SHELL': '/bin/bash', 21:32:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mli4HCQQ3H/Listeners', 21:32:38 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 21:32:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:38 INFO - 'USER': 'cltbld', 21:32:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:38 INFO - 'XPC_FLAGS': '0x0', 21:32:38 INFO - 'XPC_SERVICE_NAME': '0', 21:32:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 21:32:38 INFO - mozversion application_buildid: 20170503201922 21:32:38 INFO - mozversion application_changeset: 4a6a71f4aa22e4dc3961884ce505ce34bdd799a2 21:32:38 INFO - mozversion application_display_name: Nightly 21:32:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:32:38 INFO - mozversion application_name: Firefox 21:32:38 INFO - mozversion application_remotingname: firefox 21:32:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:32:38 INFO - mozversion application_vendor: Mozilla 21:32:38 INFO - mozversion application_version: 55.0a1 21:32:38 INFO - mozversion platform_buildid: 20170503201922 21:32:38 INFO - mozversion platform_changeset: 4a6a71f4aa22e4dc3961884ce505ce34bdd799a2 21:32:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:32:38 INFO - mozversion platform_version: 55.0a1 21:32:38 INFO - using testdate: 1493872358 21:32:38 INFO - actual date: 1493872358 21:32:38 INFO - starting webserver on 'localhost:49917' 21:32:38 INFO - SUITE-START | Running 2 tests 21:32:38 INFO - TEST-START | dromaeo_css 21:32:38 INFO - operating with platform_type : mac_ 21:32:38 INFO - Initialising browser for dromaeo_css test... 21:32:38 INFO - TEST-INFO | started process 2977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpuEWutG/profile http://localhost:49917/getInfo.html) 21:32:40 INFO - PID 2977 | __metrics Screen width/height:1600/1200 21:32:40 INFO - PID 2977 | colorDepth:24 21:32:40 INFO - PID 2977 | Browser inner width/height: 1024/691 21:32:40 INFO - PID 2977 | __metrics 21:32:41 INFO - PID 2977 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:32:41 INFO - PID 2977 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:32:41 INFO - TEST-INFO | 2977: exit 0 21:32:41 INFO - Browser initialized. 21:32:41 INFO - Running cycle 1/1 for dromaeo_css test... 21:32:41 INFO - TEST-INFO | started process 2979 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpuEWutG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:32:54 INFO - PID 2979 | RSS: Main: 240263168 21:32:54 INFO - PID 2979 | 21:35:58 INFO - PID 2979 | Cycle 1(1): loaded http://localhost:49917/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49917/tests/dromaeo/cssquery-ext.html) 21:36:00 INFO - PID 2979 | RSS: Main: 391872512 21:36:00 INFO - PID 2979 | 21:39:02 INFO - PID 2979 | Cycle 1(1): loaded http://localhost:49917/tests/dromaeo/cssquery-ext.html (next: http://localhost:49917/tests/dromaeo/cssquery-jquery.html) 21:39:03 INFO - PID 2979 | RSS: Main: 368930816 21:39:03 INFO - PID 2979 | 21:42:05 INFO - PID 2979 | Cycle 1(1): loaded http://localhost:49917/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49917/tests/dromaeo/cssquery-mootools.html) 21:42:06 INFO - PID 2979 | RSS: Main: 320036864 21:42:06 INFO - PID 2979 | 21:45:08 INFO - PID 2979 | Cycle 1(1): loaded http://localhost:49917/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49917/tests/dromaeo/cssquery-prototype.html) 21:45:09 INFO - PID 2979 | RSS: Main: 357122048 21:45:09 INFO - PID 2979 | 21:48:13 INFO - PID 2979 | Cycle 1(1): loaded http://localhost:49917/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49917/tests/dromaeo/cssquery-yui.html) 21:48:13 INFO - PID 2979 | RSS: Main: 325898240 21:48:13 INFO - PID 2979 | 21:51:15 INFO - PID 2979 | Cycle 1(1): loaded http://localhost:49917/tests/dromaeo/cssquery-yui.html (next: http://localhost:49917/tests/dromaeo/cssquery-dojo.html) 21:51:16 INFO - PID 2979 | RSS: Main: 374267904 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | __start_tp_report 21:51:16 INFO - PID 2979 | _x_x_mozilla_page_load 21:51:16 INFO - PID 2979 | _x_x_mozilla_page_load_details 21:51:16 INFO - PID 2979 | |i|pagename|runs| 21:51:16 INFO - PID 2979 | |0;dojo.html;3439.656034396559;3449.9827500862493;3499.177693242088;3512.9648703512958;3518.0852978225653;6009.639421634705;6112.8986537897235;6169.383061693834;6170.352113028135;6172.475339596134;6102.156009074801;6145.108959200925;6183.381661833814;6191.411815877495;6225.906611400821;4844.467108618049;5157.742112894359;5286.894262114755;5343.385510666265;5417;4440.578145076229;5379.435159308256;5389.595780316472;5434.483724046209;5440;5239.659422137568;5344.278522399465;5351.143816989302;5365.195220716884;5370.489803468664;5315.574754019669;5328.573714102901;5331.360236771573;5343.893122137574;5352.464753524655;5238.554722848562;5304.973475132639;5309.814156504503;5309.973450132725;5321.49445802652;6042.274927008743;6055.878882422327;6071.483923866476;6080.30076541199;6083.6045657032155;6392.4886009119155;6529.2164940207;6572.441342485894;6602.1747281589805;6608.702608382634;6633.20401551812;6659.9001014984815;6676.766313179064;6676.833079173011;6677;5104.3109180260935;6225.097360882647;6256.562040657202;6263.937360626335;6265.843353916143;6571.507136964747;6657.833554161185;6658.101156343929;6745.224299205556;6848.7260509579055;6457;6458.418742313214;6468.159139311953;6480.157579514633;6501.47988160946;6656.172700697119;6662.233843108104;6675.532712710161;6676.298988606223;6676.899846502306;6334.524910631629;6412.5190610704385;6537.575057621335;6547.443467305332;6565.146530950946;6394.136791533177;6397.936020639827;6424.743010279536;6430.80707578764;6488.935110648928;5191.32512773337;5262.421133675292;5277.366715994105;5280.6303558750515;5290.576753859682;4653.255479123324;4664.300354946718;4665.2768820832825;4691.648126390466;4698.718076915327;6157.876842463215;6168.1056246843955;6171;6175.536834737412;6175.84560385994;6438.742450301936;6523.282438931714;6550.246721626979;6665.833354166185;6675.833104172434;6133.478654314344;6150.876982460326;6159.199304090529;6163.137160797492;6177.2587289524645;5784.681842498703;5799.39106393831;5812.041013232853;5814.302283725895;5817.650940943557;6597.6701164940605;6689.331066893272;6792.456603471612;6849.24658287578;6861.4510839133745;6645.0696902434665;6673.366030227121;6702.229243637044;6703.4972377070935;6715.597064176165;6834.829129271609;6850.38346548813;6852.109225800614;6868.862622747417;6877.037214789833;6321.11504389395;6388.425041746265;6483.805485835433;6532.934670653232;6582.637954912526;5833.9416605835095;6537;6609.702563384563;6648.301928297653;6675.766348177791;6460.967695161494;6473.126127972663;6483.124778154889;6483.610983341015;6491.870162596816;6260.154879091265;6343.5876668016435;6461.903071453838;6465.9331210349765;6477.255115661759;6134.4113693488025;6193.318734939063;6254.499640028877;6309.1167236586;6324.924762790429;6451.677416129269;6452.838679033061;6462.289148193789;6462.935370646233;6465.353464653497;6217.96891015542;6265.718042687999;6298.7480500781285;6321.652309123042;6329.588576742497 21:51:16 INFO - PID 2979 | |1;ext.html;678.5385937562457;762.4167511853433;803.3412601666633;1113.827356759702;1121.2879821313463;24907.37731556712;31053.757849686015;34492.96521104368;34517.48223776645;34552.65447345526;35582.644173558256;36254.818725906334;36585.08537286562;36609.08477288069;36655.266894662156;5814.912776308359;7936.365090792723;8061.758147255562;8089.393295502831;8092.393070519705;5812.186293918834;7961.60191990401;8113.350931925432;8116.310113640318;8131.105578386373;8124.796880077986;8205.33326668832;8213.917860821375;8216.753497395088;8259.297959673406;5759.049756790125;8111.513309201467;8177.754667359989;8185.140560241149;8188.14024527428;5803.129530570406;7869;8030.718924837603;8053.355731541521;8064.596770161468;34438.82780586081;34683;34708.82645586781;34738;34799.826000869834;3452.9482057769155;3483.512308276843;3484.878029268989;3485.1635607454027;3499.965000349989;28703;28738.856305718546;28740;28805.711942880724;28820.279493012633;3029.091272618206;3077.2460747117098;3078.0765770269054;3123.500239961595;3131.9060428186963;221683;273071;273244;273364;273932;46089.30866037012;46246.768766155954;46302.76848615735;46389.76805115953;46498;47454.5254547457;47538.524614754104;47547;47622;47637.28544071842;13570.253636050113;13594.592162235149;13630.318484075955;13642.317884105754;13646.727065458635;9724.319297649097;9727.56225969833;11274.406886416005;11289.71775705614;11340.262882912584;10988.450577471094;11088.611898583511;11124.61063862761;11141.777164456666;11156.497957591928;4071.491063617048;4836.419629644395;4843.15244832153;4851.854444366674;4875.1224779540025;976.1605019683067;998.9001099889953;1028.8816786069697;1036.8911264317137;1038.2420832791954;22639.88680056589;23433.531329373316;23509.41226469352;23524.52950941006;23914.163004294765;1005.8591797148405;1082.9566817327377;1107.0977153619774;1118.3513562133942;1141.8058929981923;9724;11844.230125041844;12034.472760720171;12085.15403921717;12094.092943029133;297611;297779;298125;298480;298666;49113;49153.26270105951;49165;49178.50821491739;49214.507854920994;1440.337444775415;1451.753201955649;1501.9474318398804;1503.669192777587;1512.3572481695235;23231.419214519774;23237.3028809136;23300.883495583093;23341.649875251544;23408.6488702673;28476.85761571262;35427.64572354243;35444.113897152776;35467;35509.57961681504;260135;260794;260955;261417;261878;31446.371072578877;32374.676253237165;32389.19027024343;32404.787377640754;32421.18947026343;42428.151436971704;42507.57492425036;42678.359824602034;42749.145017100105;42786.572134278256;27515.31211719723;28533.001344952827;28710.856445717636;29788.255293616967;29981.250468738457;28869.566956496088;29492.705072948997;29538.556921645762;29673;29992;33869.661303387635;34515.13712157216;35036.299274013865;35492.82253588819;35508.64491355053;28837.99067032644;28964.855175723987;29765.85117074401;29891.701082988893;30379.392412151192;17333.826661733725;19274.80725192786;19674.819510829395;21066.052027658487;22790.658140128242 21:51:16 INFO - PID 2979 | |2;jquery.html;382.32710429643816;415.9026031722026;432.47886297012093;436.2474731088872;446.1278201116817;15984.520464386072;20579.27972520962;20977.58044839106;21009.36971890842;21451.034703438345;20432.387028389174;21554.676679849814;21678.458038549004;21899.452513687167;22605.886970565123;5441.347038355383;5629.746661400247;5900.144479050535;6073.240844894388;6132.172156758825;5890.263717035371;6032.728527216286;6062;6066.120412540179;6084.391560843925;5896.086106653454;5964.433378829026;6015.067664511986;6027.939720602782;6047.304559975615;5074.82238121664;5907.6750778707365;6033.215681961361;6036.3360030396625;6038.426349496791;5270.2358158067245;5286.920696189561;5353;5355.0896347621;5361.544268737162;27106.186814395598;27281.317967050785;27287.044953426535;27327.863360683266;27337.726622733717;1977.2980591889816;2039.4901274681329;2041;2042.5710600773855;2229.710137682091;17551.912240438844;17593.912030439893;17746.290148394048;17845.910770446066;18098.819011809977;2065.163608738463;2088.937331880046;2151;2153.06341741343;2154.353693891842;146053.88146475682;146218;147390.10439582405;148674.51325486714;148775;11082.279651822768;11666.70833229176;11717.88282117168;12279.263244205376;12300.446479908425;14435.783463248059;14816.555503334917;15201.087934723952;15233.162176080423;15259.771103433457;8971.237444817132;8993;8996.59515321799;9003.234725048313;9129.589168487433;7568.518481498462;8306.878571392785;8673.392862499564;8793.692220772242;8840.646374145092;6300.621962682254;6412.839679007969;7595.227741874176;7634.265866876981;7720.652570634335;1894.0340426382368;1986.632472992501;2095.3609149209638;2110.989445052765;2125.9255926042515;858.5149390594295;979.4319294808994;982.872226610536;1022.1822541966397;1024.3853687787268;16173.595660108356;20051.29820456277;20539.691904621443;21151.836648984452;21517.246896358865;935.0462528221176;980.887197972228;1020.7958408318366;1030.304544432505;1035.321864178964;9320.72037838866;11087.881692183106;11124.993752811268;11129.524988519019;11132.276402033842;527940;534694;536350;545938;548414.9639798655;119740;120818.3959080199;123422;124181.8563586728;126244;1154.249737670521;1185.8102703567388;1214.9331786751814;1218.4334284557724;1239.2007155384727;19465.770953295378;22007.559848802615;22258.443538911655;22549.323520294416;22599.20902768395;44758.77620611876;47163.528364715916;52825.999999998465;58393.540161496305;58572.4142758584;75297.62351188209;76233.61883190549;76916;79030.07274454554;79107.11161104862;8741.650333986569;8763.167499087576;8885.289176865706;8948.910510895068;8961.865572016295;13570.93214533921;13647.931760341135;13684.521041763888;13916.860831391556;14020.579382618538;10964.725881853017;11709.297442153498;11866.940665296619;12029.4586743595;12148.39258037077;11180.329180249211;11191.776164476827;11253.043491303331;11348.148888833206;11511.884881151416;12014.75970480603;12070.879291207328;12118.091143164122;12157;12197.085218608818;8585.699500517452;8677.956610217161;8730.519821409629;9002.59488323014;9038.005819359734;11181.72045698864;11785;11984.940075299568;12065.939670301592;12152.21010634306 21:51:16 INFO - PID 2979 | |3;mootools.html;1328.8936885049197;1475.6679747056914;1476.375522780129;1501.4369611395728;1507.9698406031875;11140.387278699678;11610.593629222958;11615.303081815096;11629.651110466699;11641.301521908681;11414.657560273185;11732.00277976373;11737.47181376838;11744.060475161989;11750.353730544817;2512.9371765705914;2543.9618405723927;2549.069589599792;2550.744925507453;2558.6545816314747;2224.2993457060975;2494.3764058985253;2546;2548.2355293411997;2548.515782001423;2353.5528249632603;2501.649109480878;2526.936826579341;2537.4290784573413;2540.5300019496453;2406.602910519762;2434.8295619306655;2467;2470.0860681547942;2470.2712699753706;2436.378723425533;2452.460458699083;2461.9753802461923;2481.4788894332214;2495.9251222463354;9538.761530961712;9612.327137100407;11396.772064558663;11656.30062196271;11665.941670291679;6546.574472659309;6745.089412929241;6768.323167683192;6782.796516104476;6787.490938179607;3829.215010922754;4159.750414975111;4208;4209.116085622023;4233.555476674936;1172.929624222541;1176.8058270385377;1189.1794661683412;1227.3188380448848;1241.509603706531;15263.847361526354;15586.532404027897;15601;16329.755053674204;16513.256903439375;4215.662746980234;5391.595630327741;5709.371969083398;5715.257016587817;5742.942570574324;11720.238184517979;11735.706607334885;11737.178397512263;11747.647570572897;11777.882221177679;5652.236948012048;5793.449622285876;6586.571872828251;6600.669966501751;6603.900941485882;5299.516135482071;6029.728662210211;6237.594556353823;6238.625682458976;6272.40412160844;4137;4320.2223599751715;4352.39066530686;4387.846425375161;4392.692511524227;1071.4696225368493;1266.0567876931746;1266.5756971414496;1269.6571925580042;1280.8655091215473;806.9515829050275;813.877918312246;833.3000279764906;838.7441830241713;862.7498025572639;6139.539534534928;6168.1056246844855;6344.143540622049;6354.809355719243;6433.228012638513;593.5933885288505;615.258613370887;617.4227097663698;624.3039011502142;629.087822657149;3797.7911214883447;3818.6754125899056;3971;3977.900552486153;3980.3233450313514;14530.273486325852;14753.33609987553;15310.004849684736;15314.081155130505;15318.310676019606;14836.035657682432;14950.925245373703;15204.163770992702;15215;15243.314050867737;7510.849783004419;8400.579971001425;8705;8708.608112634947;8719;5780.94219057804;7030.367266945795;7082.185548661764;7227.566346019255;7307;12505.249685018927;12910.870891291343;12983.805242921553;13007.089503734647;13064.21614703121;13692.657683557592;13754.65613359674;13873.791893121814;14063;14105.294735263402;8227.917720822954;9087.863682044906;9219.110082433219;9331.813363732823;9494.24046076326;10787.622433215114;10896.673099807018;10903.964123408263;10909.236353455182;10914.563417463212;8839.301674313201;9121.726348209564;9213.12475314844;9221.446713197229;9223;7305.561666300039;7308.707651694085;9094.408863423856;9107.590158442754;9129.178373946377;8040.87938680932;8241.464304820167;8356.665733370597;8393.916060839558;8401.03388110375;5072.923906141485;6085.056816193497;6113.5414843886;6222.408871157233;6229.688515574113;6036.094585812163;6101.964290148714;6231.22109736283;6269.153664255449;6341.714622841891 21:51:16 INFO - PID 2979 | |4;prototype.html;341.56791658551936;397.85279446604756;401.32577270186096;405.1653593597188;408.89777555611107;4995.450500444953;5327.467253274671;5995;6042.27492700876;6071.453569178773;4531.048479819242;5821.417858214177;5822.883542329151;5893.145493903381;5989;3962.7424217425773;4079.7552146871135;4599.379083823691;4607.354970304152;4626.560476754703;4464.531224221459;4580.152671755719;4607.539246075399;4632.235681112629;4652.930206046912;4236.75015870319;4333.6598236952;4350.651947844165;4575.908481830362;4592.74739889306;3737.682297004758;4203.348480985437;4462.308342206947;4622.768861556909;4640.744759038252;3574.410222313315;4118.6499147572495;4139.441175441337;4141.295979683461;4157.5010998680045;4829.6860704054125;4863.319135321058;4988.65079444439;5687.118496633028;5900.734466948955;4543.863684089482;5406.351237851443;5483.616146869721;5486.753096110685;5525.861853453655;5019;6038.607490513103;6041.275046994345;6424.71088801005;6468.1268028815975;3840.846366145379;4051.8379264829373;4213.30480470722;4523.2988886722615;4742.5731684148595;6645.368689974444;6714.966425167843;6726.730930762764;6734.326567343275;6760.492963027793;4275.0594869128745;4292.720973136736;4311.590398912098;4314.848980285675;4321.827126914951;3567.304375646724;4325.9134817303475;4366.388705581221;4390.956090439118;4405.163019026375;4451.77741112943;5111.539961403491;5113.437521872591;5121.2574176744165;5123.130057528975;5509.3664228613425;6082.422169893853;6260.561760676709;6372.808815735535;6386.489080873519;1221.096388672394;1374.8556401577891;1418.3333833098427;1486.814148231471;1493.8804895608325;824.54237897966;951.6431338248157;964.6334392930642;972.4943029624555;981.2444417798256;4445.777711114431;4893.828715994923;5609.326880774333;5849.970750146222;5949.553783466257;5989.52083833301;6415.454686351713;6502.24439401359;6515.381038801306;6538.738450462023;5563.471470210324;5747.252857128547;5816.185733997244;5840.649561026352;5914.9704251479325;2644.2728249731476;2843.943121137566;3128.405602935435;3376.848041838074;3393.898183054512;5678.659280443187;6686.231083425371;6731.865362692816;6752.257251702309;6766.086578311963;6782.220044694924;6783.846746053183;6784.864302713918;6785.966070169717;6791.777480053638;6680.432163266079;6814.897776533356;6818.931810681929;6831.760888368884;6865.759698410631;6233.127362169391;6502.187226596676;6579.671016449254;6581.2102547694585;6589.835254118494;5102.846914592568;5759.8560035999435;6395.42441180296;6517.576357536935;6518.478521718346;5579.944200557942;5664.546836253172;6808.693608787716;6832.965835170792;6850.486213533905;3603.8017909015257;4301.634361079344;4308.583701705777;4328.117268988;4342.762312740853;5253.737313134405;6317.178766760292;6568.211814582281;6634.303398143318;6652.501062420434;4990.451050384528;6221.253449586078;6242.469390101711;6245.657878295639;6384.297727249906;4691.287679996708;6323.683815809283;6356.16931132352;6382.6170429774365;6399.520035997412;5613.663180209201;5852.073963018491;6205.5345849060595;6255.96872015637;6299.716512757029;6365.427111559982;6384.840378990562;6405.7117429714845;6408.61548307103;6438.903416448663;5928.970355148197;6214.130021796862;6227.661052873654;6254.874902502015;6263.906041409291 21:51:16 INFO - PID 2979 | |5;yui.html;823.3166471828383;846.547097302943;847.9236868681818;855.824555966027;855.8459477294089;16265.999999999985;16272.674546509063;16314.592135196612;16603.750943735824;16679.666406671873;15347.769783453257;15459.690806183899;15544.06735595865;15639.530814075568;15648.530544083667;985.5762022330395;994.8457989011715;1000.9549570269319;1001.4842356186533;1001.529281237817;980.9117179453848;1000.0649392817738;1000.3447741729143;1001.6193846338381;1008.0625018732576;1056.873175218975;1057.9947100264487;1059.041567381517;1060.4538662588773;1061.9309744866598;1051.8159322118674;1054.4516851237352;1054.915606751458;1057.376148072641;1066.0245875024343;746.6266866566716;776.1656219563991;777.766669998998;778.852018116556;784.7841843493028;15750.76373854393;16229.837701622951;16473.423430180006;16610.833891661172;16744.079075650836;16762.66474670512;16813.495595132164;16998.405055822986;17175.91412042932;17277.222524986406;13634.113782604198;13855.722885542233;13891.4443422263;14024.579262622137;14064.367103480368;3450.585929688428;3473.808940508271;3476.8261586920808;3478.1478537758157;3478.4956181353564;101295.49352253265;129129.70870291271;129963.40073198483;131397;132316.33841830827;12991.220526768235;13030.283334416508;13205.537806176737;13372.598822035354;13451.730965380637;20091.39725808228;24268.63597046046;24928;26028.219153425427;26070.34824129374;7665.770026899202;7715.729949451742;7789.33790627803;7886.842263154705;7892.210778922062;3827.5981021992848;3885.261800257942;3901.122247494291;3906.394508851076;3914.4715463412645;4244.830206791694;4276.764777937181;4312.978435107804;4334.349847522897;4336.804843782037;902.6840605787921;922.5295099499303;939.2016785732073;939.8214339275515;941.3833938770116;637.5951270942892;646.7703965092325;649.5031301054697;658.1805651963241;679.9898001529981;10268.537915793808;10767.78464430707;11169.497372618252;11549.845015498382;12165.93917030427;645.3901063494952;647.4431988489989;656.8390744267685;667.562746401108;685.266764561914;4704.964907720296;5322.92015619766;5444;5460.989716902306;5475.835724928259;100691;110350.89649103406;110610;111189.44405277922;112818;93527;94861;95751.08497830004;96910;97156;50517.948205179186;71725.49820442224;75998.74017007633;84503;88665;10884.129269658566;11564.47959841826;11808.291502509877;12372;12457.81650743178;25708.228753137435;35035.59857605667;37780.811095944344;40336.3949540763;40710.982225444604;42876.785616072964;52424.95150097053;52661.21008184804;52922;53448;14141;15417.845821541641;15695.607609809846;16116.516504504883;16339.428120015742;17149.742753858452;17828.91085544616;17906.91046544759;17944.91027544854;18167.27330906749;13846.10000349974;15858.731301496084;15869.365225390855;16393.672126557165;16588.170591470618;16065.598360040625;16091.75862362089;16272;16489.175541223132;16612.667746644758;13036.869631303565;16097.436589719304;16120.27458764335;16130.838691613402;16251.431199907947;8707.390482666406;11607.535698571963;11846.763064738829;11859.466324015613;12475.313857737912;11784.646460606196;13049.217046977212;13065.020123490965;13171.802422963472;13517.391717372546 21:51:16 INFO - PID 2979 | __end_tp_report 21:51:16 INFO - PID 2979 | __start_cc_report 21:51:16 INFO - PID 2979 | _x_x_mozilla_cycle_collect,1485 21:51:16 INFO - PID 2979 | __end_cc_report 21:51:16 INFO - PID 2979 | __startTimestamp1493873476194__endTimestamp 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | ------- Summary: start ------- 21:51:16 INFO - PID 2979 | Number of tests: 6 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | [#0] dojo.html Cycles:165 Average:6058.98 Median:6298.75 stddev:729.21 (11.6%) stddev-sans-first:701.90 21:51:16 INFO - PID 2979 | Values: 3439.7 3450.0 3499.2 3513.0 3518.1 6009.6 6112.9 6169.4 6170.4 6172.5 6102.2 6145.1 6183.4 6191.4 6225.9 4844.5 5157.7 5286.9 5343.4 5417.0 4440.6 5379.4 5389.6 5434.5 5440.0 5239.7 5344.3 5351.1 5365.2 5370.5 5315.6 5328.6 5331.4 5343.9 5352.5 5238.6 5305.0 5309.8 5310.0 5321.5 6042.3 6055.9 6071.5 6080.3 6083.6 6392.5 6529.2 6572.4 6602.2 6608.7 6633.2 6659.9 6676.8 6676.8 6677.0 5104.3 6225.1 6256.6 6263.9 6265.8 6571.5 6657.8 6658.1 6745.2 6848.7 6457.0 6458.4 6468.2 6480.2 6501.5 6656.2 6662.2 6675.5 6676.3 6676.9 6334.5 6412.5 6537.6 6547.4 6565.1 6394.1 6397.9 6424.7 6430.8 6488.9 5191.3 5262.4 5277.4 5280.6 5290.6 4653.3 4664.3 4665.3 4691.6 4698.7 6157.9 6168.1 6171.0 6175.5 6175.8 6438.7 6523.3 6550.2 6665.8 6675.8 6133.5 6150.9 6159.2 6163.1 6177.3 5784.7 5799.4 5812.0 5814.3 5817.7 6597.7 6689.3 6792.5 6849.2 6861.5 6645.1 6673.4 6702.2 6703.5 6715.6 6834.8 6850.4 6852.1 6868.9 6877.0 6321.1 6388.4 6483.8 6532.9 6582.6 5833.9 6537.0 6609.7 6648.3 6675.8 6461.0 6473.1 6483.1 6483.6 6491.9 6260.2 6343.6 6461.9 6465.9 6477.3 6134.4 6193.3 6254.5 6309.1 6324.9 6451.7 6452.8 6462.3 6462.9 6465.4 6218.0 6265.7 6298.7 6321.7 6329.6 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | [#1] ext.html Cycles:180 Average:41578.25 Median:31910.52 stddev:72050.70 (225.8%) stddev-sans-first:72187.38 21:51:16 INFO - PID 2979 | Values: 678.5 762.4 803.3 1113.8 1121.3 24907.4 31053.8 34493.0 34517.5 34552.7 35582.6 36254.8 36585.1 36609.1 36655.3 5814.9 7936.4 8061.8 8089.4 8092.4 5812.2 7961.6 8113.4 8116.3 8131.1 8124.8 8205.3 8213.9 8216.8 8259.3 5759.0 8111.5 8177.8 8185.1 8188.1 5803.1 7869.0 8030.7 8053.4 8064.6 34438.8 34683.0 34708.8 34738.0 34799.8 3452.9 3483.5 3484.9 3485.2 3500.0 28703.0 28738.9 28740.0 28805.7 28820.3 3029.1 3077.2 3078.1 3123.5 3131.9 221683.0 273071.0 273244.0 273364.0 273932.0 46089.3 46246.8 46302.8 46389.8 46498.0 47454.5 47538.5 47547.0 47622.0 47637.3 13570.3 13594.6 13630.3 13642.3 13646.7 9724.3 9727.6 11274.4 11289.7 11340.3 10988.5 11088.6 11124.6 11141.8 11156.5 4071.5 4836.4 4843.2 4851.9 4875.1 976.2 998.9 1028.9 1036.9 1038.2 22639.9 23433.5 23509.4 23524.5 23914.2 1005.9 1083.0 1107.1 1118.4 1141.8 9724.0 11844.2 12034.5 12085.2 12094.1 297611.0 297779.0 298125.0 298480.0 298666.0 49113.0 49153.3 49165.0 49178.5 49214.5 1440.3 1451.8 1501.9 1503.7 1512.4 23231.4 23237.3 23300.9 23341.6 23408.6 28476.9 35427.6 35444.1 35467.0 35509.6 260135.0 260794.0 260955.0 261417.0 261878.0 31446.4 32374.7 32389.2 32404.8 32421.2 42428.2 42507.6 42678.4 42749.1 42786.6 27515.3 28533.0 28710.9 29788.3 29981.3 28869.6 29492.7 29538.6 29673.0 29992.0 33869.7 34515.1 35036.3 35492.8 35508.6 28838.0 28964.9 29765.9 29891.7 30379.4 17333.8 19274.8 19674.8 21066.1 22790.7 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | [#2] jquery.html Cycles:180 Average:34621.03 Median:21616.57 stddev:91119.07 (421.5%) stddev-sans-first:91338.42 21:51:16 INFO - PID 2979 | Values: 382.3 415.9 432.5 436.2 446.1 15984.5 20579.3 20977.6 21009.4 21451.0 20432.4 21554.7 21678.5 21899.5 22605.9 5441.3 5629.7 5900.1 6073.2 6132.2 5890.3 6032.7 6062.0 6066.1 6084.4 5896.1 5964.4 6015.1 6027.9 6047.3 5074.8 5907.7 6033.2 6036.3 6038.4 5270.2 5286.9 5353.0 5355.1 5361.5 27106.2 27281.3 27287.0 27327.9 27337.7 1977.3 2039.5 2041.0 2042.6 2229.7 17551.9 17593.9 17746.3 17845.9 18098.8 2065.2 2088.9 2151.0 2153.1 2154.4 146053.9 146218.0 147390.1 148674.5 148775.0 11082.3 11666.7 11717.9 12279.3 12300.4 14435.8 14816.6 15201.1 15233.2 15259.8 8971.2 8993.0 8996.6 9003.2 9129.6 7568.5 8306.9 8673.4 8793.7 8840.6 6300.6 6412.8 7595.2 7634.3 7720.7 1894.0 1986.6 2095.4 2111.0 2125.9 858.5 979.4 982.9 1022.2 1024.4 16173.6 20051.3 20539.7 21151.8 21517.2 935.0 980.9 1020.8 1030.3 1035.3 9320.7 11087.9 11125.0 11129.5 11132.3 527940.0 534694.0 536350.0 545938.0 548415.0 119740.0 120818.4 123422.0 124181.9 126244.0 1154.2 1185.8 1214.9 1218.4 1239.2 19465.8 22007.6 22258.4 22549.3 22599.2 44758.8 47163.5 52826.0 58393.5 58572.4 75297.6 76233.6 76916.0 79030.1 79107.1 8741.7 8763.2 8885.3 8948.9 8961.9 13570.9 13647.9 13684.5 13916.9 14020.6 10964.7 11709.3 11866.9 12029.5 12148.4 11180.3 11191.8 11253.0 11348.1 11511.9 12014.8 12070.9 12118.1 12157.0 12197.1 8585.7 8678.0 8730.5 9002.6 9038.0 11181.7 11785.0 11984.9 12065.9 12152.2 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | [#3] mootools.html Cycles:180 Average:7012.33 Median:3900.11 stddev:4464.21 (114.5%) stddev-sans-first:4456.31 21:51:16 INFO - PID 2979 | Values: 1328.9 1475.7 1476.4 1501.4 1508.0 11140.4 11610.6 11615.3 11629.7 11641.3 11414.7 11732.0 11737.5 11744.1 11750.4 2512.9 2544.0 2549.1 2550.7 2558.7 2224.3 2494.4 2546.0 2548.2 2548.5 2353.6 2501.6 2526.9 2537.4 2540.5 2406.6 2434.8 2467.0 2470.1 2470.3 2436.4 2452.5 2462.0 2481.5 2495.9 9538.8 9612.3 11396.8 11656.3 11665.9 6546.6 6745.1 6768.3 6782.8 6787.5 3829.2 4159.8 4208.0 4209.1 4233.6 1172.9 1176.8 1189.2 1227.3 1241.5 15263.8 15586.5 15601.0 16329.8 16513.3 4215.7 5391.6 5709.4 5715.3 5742.9 11720.2 11735.7 11737.2 11747.6 11777.9 5652.2 5793.4 6586.6 6600.7 6603.9 5299.5 6029.7 6237.6 6238.6 6272.4 4137.0 4320.2 4352.4 4387.8 4392.7 1071.5 1266.1 1266.6 1269.7 1280.9 807.0 813.9 833.3 838.7 862.7 6139.5 6168.1 6344.1 6354.8 6433.2 593.6 615.3 617.4 624.3 629.1 3797.8 3818.7 3971.0 3977.9 3980.3 14530.3 14753.3 15310.0 15314.1 15318.3 14836.0 14950.9 15204.2 15215.0 15243.3 7510.8 8400.6 8705.0 8708.6 8719.0 5780.9 7030.4 7082.2 7227.6 7307.0 12505.2 12910.9 12983.8 13007.1 13064.2 13692.7 13754.7 13873.8 14063.0 14105.3 8227.9 9087.9 9219.1 9331.8 9494.2 10787.6 10896.7 10904.0 10909.2 10914.6 8839.3 9121.7 9213.1 9221.4 9223.0 7305.6 7308.7 9094.4 9107.6 9129.2 8040.9 8241.5 8356.7 8393.9 8401.0 5072.9 6085.1 6113.5 6222.4 6229.7 6036.1 6102.0 6231.2 6269.2 6341.7 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | [#4] prototype.html Cycles:180 Average:5101.78 Median:5717.19 stddev:1619.59 (28.3%) stddev-sans-first:1584.24 21:51:16 INFO - PID 2979 | Values: 341.6 397.9 401.3 405.2 408.9 4995.5 5327.5 5995.0 6042.3 6071.5 4531.0 5821.4 5822.9 5893.1 5989.0 3962.7 4079.8 4599.4 4607.4 4626.6 4464.5 4580.2 4607.5 4632.2 4652.9 4236.8 4333.7 4350.7 4575.9 4592.7 3737.7 4203.3 4462.3 4622.8 4640.7 3574.4 4118.6 4139.4 4141.3 4157.5 4829.7 4863.3 4988.7 5687.1 5900.7 4543.9 5406.4 5483.6 5486.8 5525.9 5019.0 6038.6 6041.3 6424.7 6468.1 3840.8 4051.8 4213.3 4523.3 4742.6 6645.4 6715.0 6726.7 6734.3 6760.5 4275.1 4292.7 4311.6 4314.8 4321.8 3567.3 4325.9 4366.4 4391.0 4405.2 4451.8 5111.5 5113.4 5121.3 5123.1 5509.4 6082.4 6260.6 6372.8 6386.5 1221.1 1374.9 1418.3 1486.8 1493.9 824.5 951.6 964.6 972.5 981.2 4445.8 4893.8 5609.3 5850.0 5949.6 5989.5 6415.5 6502.2 6515.4 6538.7 5563.5 5747.3 5816.2 5840.6 5915.0 2644.3 2843.9 3128.4 3376.8 3393.9 5678.7 6686.2 6731.9 6752.3 6766.1 6782.2 6783.8 6784.9 6786.0 6791.8 6680.4 6814.9 6818.9 6831.8 6865.8 6233.1 6502.2 6579.7 6581.2 6589.8 5102.8 5759.9 6395.4 6517.6 6518.5 5579.9 5664.5 6808.7 6833.0 6850.5 3603.8 4301.6 4308.6 4328.1 4342.8 5253.7 6317.2 6568.2 6634.3 6652.5 4990.5 6221.3 6242.5 6245.7 6384.3 4691.3 6323.7 6356.2 6382.6 6399.5 5613.7 5852.1 6205.5 6256.0 6299.7 6365.4 6384.8 6405.7 6408.6 6438.9 5929.0 6214.1 6227.7 6254.9 6263.9 21:51:16 INFO - PID 2979 | 21:51:16 INFO - PID 2979 | [#5] yui.html Cycles:180 Average:21338.08 Median:16753.37 stddev:30850.76 (184.1%) stddev-sans-first:30898.84 21:51:16 INFO - PID 2979 | Values: 823.3 846.5 847.9 855.8 855.8 16266.0 16272.7 16314.6 16603.8 16679.7 15347.8 15459.7 15544.1 15639.5 15648.5 985.6 994.8 1001.0 1001.5 1001.5 980.9 1000.1 1000.3 1001.6 1008.1 1056.9 1058.0 1059.0 1060.5 1061.9 1051.8 1054.5 1054.9 1057.4 1066.0 746.6 776.2 777.8 778.9 784.8 15750.8 16229.8 16473.4 16610.8 16744.1 16762.7 16813.5 16998.4 17175.9 17277.2 13634.1 13855.7 13891.4 14024.6 14064.4 3450.6 3473.8 3476.8 3478.1 3478.5 101295.5 129129.7 129963.4 131397.0 132316.3 12991.2 13030.3 13205.5 13372.6 13451.7 20091.4 24268.6 24928.0 26028.2 26070.3 7665.8 7715.7 7789.3 7886.8 7892.2 3827.6 3885.3 3901.1 3906.4 3914.5 4244.8 4276.8 4313.0 4334.3 4336.8 902.7 922.5 939.2 939.8 941.4 637.6 646.8 649.5 658.2 680.0 10268.5 10767.8 11169.5 11549.8 12165.9 645.4 647.4 656.8 667.6 685.3 4705.0 5322.9 5444.0 5461.0 5475.8 100691.0 110350.9 110610.0 111189.4 112818.0 93527.0 94861.0 95751.1 96910.0 97156.0 50517.9 71725.5 75998.7 84503.0 88665.0 10884.1 11564.5 11808.3 12372.0 12457.8 25708.2 35035.6 37780.8 40336.4 40711.0 42876.8 52425.0 52661.2 52922.0 53448.0 14141.0 15417.8 15695.6 16116.5 16339.4 17149.7 17828.9 17906.9 17944.9 18167.3 13846.1 15858.7 15869.4 16393.7 16588.2 16065.6 16091.8 16272.0 16489.2 16612.7 13036.9 16097.4 16120.3 16130.8 16251.4 8707.4 11607.5 11846.8 11859.5 12475.3 11784.6 13049.2 13065.0 13171.8 13517.4 21:51:16 INFO - PID 2979 | -------- Summary: end -------- 21:51:16 INFO - PID 2979 | 21:51:16 INFO - TEST-INFO | 2979: exit 0 21:51:16 INFO - TEST-OK | dromaeo_css | took 1118494ms 21:51:16 INFO - TEST-START | kraken 21:51:16 INFO - operating with platform_type : mac_ 21:51:16 INFO - Initialising browser for kraken test... 21:51:16 INFO - TEST-INFO | started process 2983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpc9Qx_h/profile http://localhost:49917/getInfo.html) 21:51:19 INFO - PID 2983 | __metrics Screen width/height:1600/1200 21:51:19 INFO - PID 2983 | colorDepth:24 21:51:19 INFO - PID 2983 | Browser inner width/height: 1024/691 21:51:19 INFO - PID 2983 | __metrics 21:51:19 INFO - PID 2983 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:51:19 INFO - PID 2983 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:51:20 INFO - TEST-INFO | 2983: exit 0 21:51:20 INFO - Browser initialized. 21:51:20 INFO - Running cycle 1/1 for kraken test... 21:51:20 INFO - TEST-INFO | started process 2984 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpc9Qx_h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:51:32 INFO - PID 2984 | RSS: Main: 239599616 21:51:32 INFO - PID 2984 | 21:51:35 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49917/tests/kraken/driver.html?testName=audio-beat-detection) 21:51:36 INFO - PID 2984 | RSS: Main: 308346880 21:51:36 INFO - PID 2984 | 21:51:38 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49917/tests/kraken/driver.html?testName=audio-dft) 21:51:39 INFO - PID 2984 | RSS: Main: 290955264 21:51:39 INFO - PID 2984 | 21:51:42 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49917/tests/kraken/driver.html?testName=audio-fft) 21:51:43 INFO - PID 2984 | RSS: Main: 381341696 21:51:43 INFO - PID 2984 | 21:51:45 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49917/tests/kraken/driver.html?testName=audio-oscillator) 21:51:45 INFO - PID 2984 | RSS: Main: 286081024 21:51:45 INFO - PID 2984 | 21:51:47 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49917/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:51:48 INFO - PID 2984 | RSS: Main: 287551488 21:51:48 INFO - PID 2984 | 21:51:51 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49917/tests/kraken/driver.html?testName=imaging-darkroom) 21:51:51 INFO - PID 2984 | RSS: Main: 351158272 21:51:51 INFO - PID 2984 | 21:51:54 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49917/tests/kraken/driver.html?testName=imaging-desaturate) 21:51:55 INFO - PID 2984 | RSS: Main: 372240384 21:51:55 INFO - PID 2984 | 21:51:58 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49917/tests/kraken/driver.html?testName=json-parse-financial) 21:51:58 INFO - PID 2984 | RSS: Main: 383516672 21:51:58 INFO - PID 2984 | 21:52:00 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49917/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:52:01 INFO - PID 2984 | RSS: Main: 328118272 21:52:01 INFO - PID 2984 | 21:52:02 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:52:03 INFO - PID 2984 | RSS: Main: 331055104 21:52:03 INFO - PID 2984 | 21:52:05 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:52:06 INFO - PID 2984 | RSS: Main: 333053952 21:52:06 INFO - PID 2984 | 21:52:09 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:52:09 INFO - PID 2984 | RSS: Main: 351318016 21:52:09 INFO - PID 2984 | 21:52:12 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:52:13 INFO - PID 2984 | RSS: Main: 344109056 21:52:13 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:49917/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49917/tests/kraken/driver.html?testName=ai-astar) 21:52:15 INFO - PID 2984 | RSS: Main: 386977792 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | __start_tp_report 21:52:15 INFO - PID 2984 | _x_x_mozilla_page_load 21:52:15 INFO - PID 2984 | _x_x_mozilla_page_load_details 21:52:15 INFO - PID 2984 | |i|pagename|runs| 21:52:15 INFO - PID 2984 | |0;ai-astar;110;109;117;108;110;110;109;112;110;110 21:52:15 INFO - PID 2984 | |1;audio-beat-detection;165;143;112;151;109;109;110;108;136;111 21:52:15 INFO - PID 2984 | |2;audio-dft;183;184;184;186;183;186;181;185;188;181 21:52:15 INFO - PID 2984 | |3;audio-fft;82;58;61;96;57;59;56;61;84;60 21:52:15 INFO - PID 2984 | |4;audio-oscillator;89;71;85;68;69;68;99;103;102;89 21:52:15 INFO - PID 2984 | |5;imaging-gaussian-blur;86;87;86;86;87;87;86;86;88;84 21:52:15 INFO - PID 2984 | |6;imaging-darkroom;93;95;94;94;94;94;94;94;94;94 21:52:15 INFO - PID 2984 | |7;imaging-desaturate;86;85;83;84;111;87;85;84;83;85 21:52:15 INFO - PID 2984 | |8;json-parse-financial;49;48;47;47;49;46;59;60;48;49 21:52:15 INFO - PID 2984 | |9;json-stringify-tinderbox;55;54;61;60;64;60;59;58;61;59 21:52:15 INFO - PID 2984 | |10;stanford-crypto-aes;83;83;82;83;84;82;83;80;82;80 21:52:15 INFO - PID 2984 | |11;stanford-crypto-ccm;137;135;135;138;136;136;151;147;193;176 21:52:15 INFO - PID 2984 | |12;stanford-crypto-pbkdf2;184;183;186;186;181;183;190;183;183;183 21:52:15 INFO - PID 2984 | |13;stanford-crypto-sha256-iterative;72;67;66;67;68;68;67;68;67;66 21:52:15 INFO - PID 2984 | __end_tp_report 21:52:15 INFO - PID 2984 | __start_cc_report 21:52:15 INFO - PID 2984 | _x_x_mozilla_cycle_collect,2101 21:52:15 INFO - PID 2984 | __end_cc_report 21:52:15 INFO - PID 2984 | __startTimestamp1493873535513__endTimestamp 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | ------- Summary: start ------- 21:52:15 INFO - PID 2984 | Number of tests: 14 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#0] ai-astar Cycles:10 Average:110.50 Median:110.00 stddev:2.51 (2.3%) stddev-sans-first:2.65 21:52:15 INFO - PID 2984 | Values: 110.0 109.0 117.0 108.0 110.0 110.0 109.0 112.0 110.0 110.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:124.00 stddev:21.37 (17.2%) stddev-sans-first:17.20 21:52:15 INFO - PID 2984 | Values: 165.0 143.0 112.0 151.0 109.0 109.0 110.0 108.0 136.0 111.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:2.23 (1.2%) stddev-sans-first:2.33 21:52:15 INFO - PID 2984 | Values: 183.0 184.0 184.0 186.0 183.0 186.0 181.0 185.0 188.0 181.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#3] audio-fft Cycles:10 Average:67.40 Median:61.00 stddev:14.30 (23.4%) stddev-sans-first:14.16 21:52:15 INFO - PID 2984 | Values: 82.0 58.0 61.0 96.0 57.0 59.0 56.0 61.0 84.0 60.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:78.00 stddev:14.40 (18.5%) stddev-sans-first:15.17 21:52:15 INFO - PID 2984 | Values: 89.0 71.0 85.0 68.0 69.0 68.0 99.0 103.0 102.0 89.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:86.50 stddev:1.06 (1.2%) stddev-sans-first:1.12 21:52:15 INFO - PID 2984 | Values: 86.0 87.0 86.0 86.0 87.0 87.0 86.0 86.0 88.0 84.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 21:52:15 INFO - PID 2984 | Values: 93.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#7] imaging-desaturate Cycles:10 Average:87.30 Median:85.00 stddev:8.42 (9.9%) stddev-sans-first:8.92 21:52:15 INFO - PID 2984 | Values: 86.0 85.0 83.0 84.0 111.0 87.0 85.0 84.0 83.0 85.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:49.00 stddev:5.01 (10.2%) stddev-sans-first:5.29 21:52:15 INFO - PID 2984 | Values: 49.0 48.0 47.0 47.0 49.0 46.0 59.0 60.0 48.0 49.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:60.00 stddev:2.92 (4.9%) stddev-sans-first:2.70 21:52:15 INFO - PID 2984 | Values: 55.0 54.0 61.0 60.0 64.0 60.0 59.0 58.0 61.0 59.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:83.00 stddev:1.32 (1.6%) stddev-sans-first:1.36 21:52:15 INFO - PID 2984 | Values: 83.0 83.0 82.0 83.0 84.0 82.0 83.0 80.0 82.0 80.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:142.50 stddev:20.18 (14.2%) stddev-sans-first:20.98 21:52:15 INFO - PID 2984 | Values: 137.0 135.0 135.0 138.0 136.0 136.0 151.0 147.0 193.0 176.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:183.50 stddev:2.53 (1.4%) stddev-sans-first:2.68 21:52:15 INFO - PID 2984 | Values: 184.0 183.0 186.0 186.0 181.0 183.0 190.0 183.0 183.0 183.0 21:52:15 INFO - PID 2984 | 21:52:15 INFO - PID 2984 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:1.71 (2.5%) stddev-sans-first:0.78 21:52:15 INFO - PID 2984 | Values: 72.0 67.0 66.0 67.0 68.0 68.0 67.0 68.0 67.0 66.0 21:52:15 INFO - PID 2984 | -------- Summary: end -------- 21:52:15 INFO - PID 2984 | 21:52:16 INFO - TEST-INFO | 2984: exit 0 21:52:16 INFO - TEST-OK | kraken | took 59700ms 21:52:16 INFO - SUITE-END | took 1178s 21:52:16 INFO - Completed test suite (00:19:38) 21:52:16 INFO - javascript benchmark 21:52:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7640.541202096339, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3439.656034396559, 3449.9827500862493, 3499.177693242088, 3512.9648703512958, 3518.0852978225653, 6009.639421634705, 6112.8986537897235, 6169.383061693834, 6170.352113028135, 6172.475339596134, 6102.156009074801, 6145.108959200925, 6183.381661833814, 6191.411815877495, 6225.906611400821, 4844.467108618049, 5157.742112894359, 5286.894262114755, 5343.385510666265, 5417.0, 4440.578145076229, 5379.435159308256, 5389.595780316472, 5434.483724046209, 5440.0, 5239.659422137568, 5344.278522399465, 5351.143816989302, 5365.195220716884, 5370.489803468664, 5315.574754019669, 5328.573714102901, 5331.360236771573, 5343.893122137574, 5352.464753524655, 5238.554722848562, 5304.973475132639, 5309.814156504503, 5309.973450132725, 5321.49445802652, 6042.274927008743, 6055.878882422327, 6071.483923866476, 6080.30076541199, 6083.6045657032155, 6392.4886009119155, 6529.2164940207, 6572.441342485894, 6602.1747281589805, 6608.702608382634, 6633.20401551812, 6659.9001014984815, 6676.766313179064, 6676.833079173011, 6677.0, 5104.3109180260935, 6225.097360882647, 6256.562040657202, 6263.937360626335, 6265.843353916143, 6571.507136964747, 6657.833554161185, 6658.101156343929, 6745.224299205556, 6848.7260509579055, 6457.0, 6458.418742313214, 6468.159139311953, 6480.157579514633, 6501.47988160946, 6656.172700697119, 6662.233843108104, 6675.532712710161, 6676.298988606223, 6676.899846502306, 6334.524910631629, 6412.5190610704385, 6537.575057621335, 6547.443467305332, 6565.146530950946, 6394.136791533177, 6397.936020639827, 6424.743010279536, 6430.80707578764, 6488.935110648928, 5191.32512773337, 5262.421133675292, 5277.366715994105, 5280.6303558750515, 5290.576753859682, 4653.255479123324, 4664.300354946718, 4665.2768820832825, 4691.648126390466, 4698.718076915327, 6157.876842463215, 6168.1056246843955, 6171.0, 6175.536834737412, 6175.84560385994, 6438.742450301936, 6523.282438931714, 6550.246721626979, 6665.833354166185, 6675.833104172434, 6133.478654314344, 6150.876982460326, 6159.199304090529, 6163.137160797492, 6177.2587289524645, 5784.681842498703, 5799.39106393831, 5812.041013232853, 5814.302283725895, 5817.650940943557, 6597.6701164940605, 6689.331066893272, 6792.456603471612, 6849.24658287578, 6861.4510839133745, 6645.0696902434665, 6673.366030227121, 6702.229243637044, 6703.4972377070935, 6715.597064176165, 6834.829129271609, 6850.38346548813, 6852.109225800614, 6868.862622747417, 6877.037214789833, 6321.11504389395, 6388.425041746265, 6483.805485835433, 6532.934670653232, 6582.637954912526, 5833.9416605835095, 6537.0, 6609.702563384563, 6648.301928297653, 6675.766348177791, 6460.967695161494, 6473.126127972663, 6483.124778154889, 6483.610983341015, 6491.870162596816, 6260.154879091265, 6343.5876668016435, 6461.903071453838, 6465.9331210349765, 6477.255115661759, 6134.4113693488025, 6193.318734939063, 6254.499640028877, 6309.1167236586, 6324.924762790429, 6451.677416129269, 6452.838679033061, 6462.289148193789, 6462.935370646233, 6465.353464653497, 6217.96891015542, 6265.718042687999, 6298.7480500781285, 6321.652309123042, 6329.588576742497], "value": 6008.93589688035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.5385937562457, 762.4167511853433, 803.3412601666633, 1113.827356759702, 1121.2879821313463, 24907.37731556712, 31053.757849686015, 34492.96521104368, 34517.48223776645, 34552.65447345526, 35582.644173558256, 36254.818725906334, 36585.08537286562, 36609.08477288069, 36655.266894662156, 5814.912776308359, 7936.365090792723, 8061.758147255562, 8089.393295502831, 8092.393070519705, 5812.186293918834, 7961.60191990401, 8113.350931925432, 8116.310113640318, 8131.105578386373, 8124.796880077986, 8205.33326668832, 8213.917860821375, 8216.753497395088, 8259.297959673406, 5759.049756790125, 8111.513309201467, 8177.754667359989, 8185.140560241149, 8188.14024527428, 5803.129530570406, 7869.0, 8030.718924837603, 8053.355731541521, 8064.596770161468, 34438.82780586081, 34683.0, 34708.82645586781, 34738.0, 34799.826000869834, 3452.9482057769155, 3483.512308276843, 3484.878029268989, 3485.1635607454027, 3499.965000349989, 28703.0, 28738.856305718546, 28740.0, 28805.711942880724, 28820.279493012633, 3029.091272618206, 3077.2460747117098, 3078.0765770269054, 3123.500239961595, 3131.9060428186963, 221683.0, 273071.0, 273244.0, 273364.0, 273932.0, 46089.30866037012, 46246.768766155954, 46302.76848615735, 46389.76805115953, 46498.0, 47454.5254547457, 47538.524614754104, 47547.0, 47622.0, 47637.28544071842, 13570.253636050113, 13594.592162235149, 13630.318484075955, 13642.317884105754, 13646.727065458635, 9724.319297649097, 9727.56225969833, 11274.406886416005, 11289.71775705614, 11340.262882912584, 10988.450577471094, 11088.611898583511, 11124.61063862761, 11141.777164456666, 11156.497957591928, 4071.491063617048, 4836.419629644395, 4843.15244832153, 4851.854444366674, 4875.1224779540025, 976.1605019683067, 998.9001099889953, 1028.8816786069697, 1036.8911264317137, 1038.2420832791954, 22639.88680056589, 23433.531329373316, 23509.41226469352, 23524.52950941006, 23914.163004294765, 1005.8591797148405, 1082.9566817327377, 1107.0977153619774, 1118.3513562133942, 1141.8058929981923, 9724.0, 11844.230125041844, 12034.472760720171, 12085.15403921717, 12094.092943029133, 297611.0, 297779.0, 298125.0, 298480.0, 298666.0, 49113.0, 49153.26270105951, 49165.0, 49178.50821491739, 49214.507854920994, 1440.337444775415, 1451.753201955649, 1501.9474318398804, 1503.669192777587, 1512.3572481695235, 23231.419214519774, 23237.3028809136, 23300.883495583093, 23341.649875251544, 23408.6488702673, 28476.85761571262, 35427.64572354243, 35444.113897152776, 35467.0, 35509.57961681504, 260135.0, 260794.0, 260955.0, 261417.0, 261878.0, 31446.371072578877, 32374.676253237165, 32389.19027024343, 32404.787377640754, 32421.18947026343, 42428.151436971704, 42507.57492425036, 42678.359824602034, 42749.145017100105, 42786.572134278256, 27515.31211719723, 28533.001344952827, 28710.856445717636, 29788.255293616967, 29981.250468738457, 28869.566956496088, 29492.705072948997, 29538.556921645762, 29673.0, 29992.0, 33869.661303387635, 34515.13712157216, 35036.299274013865, 35492.82253588819, 35508.64491355053, 28837.99067032644, 28964.855175723987, 29765.85117074401, 29891.701082988893, 30379.392412151192, 17333.826661733725, 19274.80725192786, 19674.819510829395, 21066.052027658487, 22790.658140128242], "value": 16307.598895820242, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.32710429643816, 415.9026031722026, 432.47886297012093, 436.2474731088872, 446.1278201116817, 15984.520464386072, 20579.27972520962, 20977.58044839106, 21009.36971890842, 21451.034703438345, 20432.387028389174, 21554.676679849814, 21678.458038549004, 21899.452513687167, 22605.886970565123, 5441.347038355383, 5629.746661400247, 5900.144479050535, 6073.240844894388, 6132.172156758825, 5890.263717035371, 6032.728527216286, 6062.0, 6066.120412540179, 6084.391560843925, 5896.086106653454, 5964.433378829026, 6015.067664511986, 6027.939720602782, 6047.304559975615, 5074.82238121664, 5907.6750778707365, 6033.215681961361, 6036.3360030396625, 6038.426349496791, 5270.2358158067245, 5286.920696189561, 5353.0, 5355.0896347621, 5361.544268737162, 27106.186814395598, 27281.317967050785, 27287.044953426535, 27327.863360683266, 27337.726622733717, 1977.2980591889816, 2039.4901274681329, 2041.0, 2042.5710600773855, 2229.710137682091, 17551.912240438844, 17593.912030439893, 17746.290148394048, 17845.910770446066, 18098.819011809977, 2065.163608738463, 2088.937331880046, 2151.0, 2153.06341741343, 2154.353693891842, 146053.88146475682, 146218.0, 147390.10439582405, 148674.51325486714, 148775.0, 11082.279651822768, 11666.70833229176, 11717.88282117168, 12279.263244205376, 12300.446479908425, 14435.783463248059, 14816.555503334917, 15201.087934723952, 15233.162176080423, 15259.771103433457, 8971.237444817132, 8993.0, 8996.59515321799, 9003.234725048313, 9129.589168487433, 7568.518481498462, 8306.878571392785, 8673.392862499564, 8793.692220772242, 8840.646374145092, 6300.621962682254, 6412.839679007969, 7595.227741874176, 7634.265866876981, 7720.652570634335, 1894.0340426382368, 1986.632472992501, 2095.3609149209638, 2110.989445052765, 2125.9255926042515, 858.5149390594295, 979.4319294808994, 982.872226610536, 1022.1822541966397, 1024.3853687787268, 16173.595660108356, 20051.29820456277, 20539.691904621443, 21151.836648984452, 21517.246896358865, 935.0462528221176, 980.887197972228, 1020.7958408318366, 1030.304544432505, 1035.321864178964, 9320.72037838866, 11087.881692183106, 11124.993752811268, 11129.524988519019, 11132.276402033842, 527940.0, 534694.0, 536350.0, 545938.0, 548414.9639798655, 119740.0, 120818.3959080199, 123422.0, 124181.8563586728, 126244.0, 1154.249737670521, 1185.8102703567388, 1214.9331786751814, 1218.4334284557724, 1239.2007155384727, 19465.770953295378, 22007.559848802615, 22258.443538911655, 22549.323520294416, 22599.20902768395, 44758.77620611876, 47163.528364715916, 52825.999999998465, 58393.540161496305, 58572.4142758584, 75297.62351188209, 76233.61883190549, 76916.0, 79030.07274454554, 79107.11161104862, 8741.650333986569, 8763.167499087576, 8885.289176865706, 8948.910510895068, 8961.865572016295, 13570.93214533921, 13647.931760341135, 13684.521041763888, 13916.860831391556, 14020.579382618538, 10964.725881853017, 11709.297442153498, 11866.940665296619, 12029.4586743595, 12148.39258037077, 11180.329180249211, 11191.776164476827, 11253.043491303331, 11348.148888833206, 11511.884881151416, 12014.75970480603, 12070.879291207328, 12118.091143164122, 12157.0, 12197.085218608818, 8585.699500517452, 8677.956610217161, 8730.519821409629, 9002.59488323014, 9038.005819359734, 11181.72045698864, 11785.0, 11984.940075299568, 12065.939670301592, 12152.21010634306], "value": 10221.810276386217, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.8936885049197, 1475.6679747056914, 1476.375522780129, 1501.4369611395728, 1507.9698406031875, 11140.387278699678, 11610.593629222958, 11615.303081815096, 11629.651110466699, 11641.301521908681, 11414.657560273185, 11732.00277976373, 11737.47181376838, 11744.060475161989, 11750.353730544817, 2512.9371765705914, 2543.9618405723927, 2549.069589599792, 2550.744925507453, 2558.6545816314747, 2224.2993457060975, 2494.3764058985253, 2546.0, 2548.2355293411997, 2548.515782001423, 2353.5528249632603, 2501.649109480878, 2526.936826579341, 2537.4290784573413, 2540.5300019496453, 2406.602910519762, 2434.8295619306655, 2467.0, 2470.0860681547942, 2470.2712699753706, 2436.378723425533, 2452.460458699083, 2461.9753802461923, 2481.4788894332214, 2495.9251222463354, 9538.761530961712, 9612.327137100407, 11396.772064558663, 11656.30062196271, 11665.941670291679, 6546.574472659309, 6745.089412929241, 6768.323167683192, 6782.796516104476, 6787.490938179607, 3829.215010922754, 4159.750414975111, 4208.0, 4209.116085622023, 4233.555476674936, 1172.929624222541, 1176.8058270385377, 1189.1794661683412, 1227.3188380448848, 1241.509603706531, 15263.847361526354, 15586.532404027897, 15601.0, 16329.755053674204, 16513.256903439375, 4215.662746980234, 5391.595630327741, 5709.371969083398, 5715.257016587817, 5742.942570574324, 11720.238184517979, 11735.706607334885, 11737.178397512263, 11747.647570572897, 11777.882221177679, 5652.236948012048, 5793.449622285876, 6586.571872828251, 6600.669966501751, 6603.900941485882, 5299.516135482071, 6029.728662210211, 6237.594556353823, 6238.625682458976, 6272.40412160844, 4137.0, 4320.2223599751715, 4352.39066530686, 4387.846425375161, 4392.692511524227, 1071.4696225368493, 1266.0567876931746, 1266.5756971414496, 1269.6571925580042, 1280.8655091215473, 806.9515829050275, 813.877918312246, 833.3000279764906, 838.7441830241713, 862.7498025572639, 6139.539534534928, 6168.1056246844855, 6344.143540622049, 6354.809355719243, 6433.228012638513, 593.5933885288505, 615.258613370887, 617.4227097663698, 624.3039011502142, 629.087822657149, 3797.7911214883447, 3818.6754125899056, 3971.0, 3977.900552486153, 3980.3233450313514, 14530.273486325852, 14753.33609987553, 15310.004849684736, 15314.081155130505, 15318.310676019606, 14836.035657682432, 14950.925245373703, 15204.163770992702, 15215.0, 15243.314050867737, 7510.849783004419, 8400.579971001425, 8705.0, 8708.608112634947, 8719.0, 5780.94219057804, 7030.367266945795, 7082.185548661764, 7227.566346019255, 7307.0, 12505.249685018927, 12910.870891291343, 12983.805242921553, 13007.089503734647, 13064.21614703121, 13692.657683557592, 13754.65613359674, 13873.791893121814, 14063.0, 14105.294735263402, 8227.917720822954, 9087.863682044906, 9219.110082433219, 9331.813363732823, 9494.24046076326, 10787.622433215114, 10896.673099807018, 10903.964123408263, 10909.236353455182, 10914.563417463212, 8839.301674313201, 9121.726348209564, 9213.12475314844, 9221.446713197229, 9223.0, 7305.561666300039, 7308.707651694085, 9094.408863423856, 9107.590158442754, 9129.178373946377, 8040.87938680932, 8241.464304820167, 8356.665733370597, 8393.916060839558, 8401.03388110375, 5072.923906141485, 6085.056816193497, 6113.5414843886, 6222.408871157233, 6229.688515574113, 6036.094585812163, 6101.964290148714, 6231.22109736283, 6269.153664255449, 6341.714622841891], "value": 5261.606538590633, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.56791658551936, 397.85279446604756, 401.32577270186096, 405.1653593597188, 408.89777555611107, 4995.450500444953, 5327.467253274671, 5995.0, 6042.27492700876, 6071.453569178773, 4531.048479819242, 5821.417858214177, 5822.883542329151, 5893.145493903381, 5989.0, 3962.7424217425773, 4079.7552146871135, 4599.379083823691, 4607.354970304152, 4626.560476754703, 4464.531224221459, 4580.152671755719, 4607.539246075399, 4632.235681112629, 4652.930206046912, 4236.75015870319, 4333.6598236952, 4350.651947844165, 4575.908481830362, 4592.74739889306, 3737.682297004758, 4203.348480985437, 4462.308342206947, 4622.768861556909, 4640.744759038252, 3574.410222313315, 4118.6499147572495, 4139.441175441337, 4141.295979683461, 4157.5010998680045, 4829.6860704054125, 4863.319135321058, 4988.65079444439, 5687.118496633028, 5900.734466948955, 4543.863684089482, 5406.351237851443, 5483.616146869721, 5486.753096110685, 5525.861853453655, 5019.0, 6038.607490513103, 6041.275046994345, 6424.71088801005, 6468.1268028815975, 3840.846366145379, 4051.8379264829373, 4213.30480470722, 4523.2988886722615, 4742.5731684148595, 6645.368689974444, 6714.966425167843, 6726.730930762764, 6734.326567343275, 6760.492963027793, 4275.0594869128745, 4292.720973136736, 4311.590398912098, 4314.848980285675, 4321.827126914951, 3567.304375646724, 4325.9134817303475, 4366.388705581221, 4390.956090439118, 4405.163019026375, 4451.77741112943, 5111.539961403491, 5113.437521872591, 5121.2574176744165, 5123.130057528975, 5509.3664228613425, 6082.422169893853, 6260.561760676709, 6372.808815735535, 6386.489080873519, 1221.096388672394, 1374.8556401577891, 1418.3333833098427, 1486.814148231471, 1493.8804895608325, 824.54237897966, 951.6431338248157, 964.6334392930642, 972.4943029624555, 981.2444417798256, 4445.777711114431, 4893.828715994923, 5609.326880774333, 5849.970750146222, 5949.553783466257, 5989.52083833301, 6415.454686351713, 6502.24439401359, 6515.381038801306, 6538.738450462023, 5563.471470210324, 5747.252857128547, 5816.185733997244, 5840.649561026352, 5914.9704251479325, 2644.2728249731476, 2843.943121137566, 3128.405602935435, 3376.848041838074, 3393.898183054512, 5678.659280443187, 6686.231083425371, 6731.865362692816, 6752.257251702309, 6766.086578311963, 6782.220044694924, 6783.846746053183, 6784.864302713918, 6785.966070169717, 6791.777480053638, 6680.432163266079, 6814.897776533356, 6818.931810681929, 6831.760888368884, 6865.759698410631, 6233.127362169391, 6502.187226596676, 6579.671016449254, 6581.2102547694585, 6589.835254118494, 5102.846914592568, 5759.8560035999435, 6395.42441180296, 6517.576357536935, 6518.478521718346, 5579.944200557942, 5664.546836253172, 6808.693608787716, 6832.965835170792, 6850.486213533905, 3603.8017909015257, 4301.634361079344, 4308.583701705777, 4328.117268988, 4342.762312740853, 5253.737313134405, 6317.178766760292, 6568.211814582281, 6634.303398143318, 6652.501062420434, 4990.451050384528, 6221.253449586078, 6242.469390101711, 6245.657878295639, 6384.297727249906, 4691.287679996708, 6323.683815809283, 6356.16931132352, 6382.6170429774365, 6399.520035997412, 5613.663180209201, 5852.073963018491, 6205.5345849060595, 6255.96872015637, 6299.716512757029, 6365.427111559982, 6384.840378990562, 6405.7117429714845, 6408.61548307103, 6438.903416448663, 5928.970355148197, 6214.130021796862, 6227.661052873654, 6254.874902502015, 6263.906041409291], "value": 4595.941685375803, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.3166471828383, 846.547097302943, 847.9236868681818, 855.824555966027, 855.8459477294089, 16265.999999999985, 16272.674546509063, 16314.592135196612, 16603.750943735824, 16679.666406671873, 15347.769783453257, 15459.690806183899, 15544.06735595865, 15639.530814075568, 15648.530544083667, 985.5762022330395, 994.8457989011715, 1000.9549570269319, 1001.4842356186533, 1001.529281237817, 980.9117179453848, 1000.0649392817738, 1000.3447741729143, 1001.6193846338381, 1008.0625018732576, 1056.873175218975, 1057.9947100264487, 1059.041567381517, 1060.4538662588773, 1061.9309744866598, 1051.8159322118674, 1054.4516851237352, 1054.915606751458, 1057.376148072641, 1066.0245875024343, 746.6266866566716, 776.1656219563991, 777.766669998998, 778.852018116556, 784.7841843493028, 15750.76373854393, 16229.837701622951, 16473.423430180006, 16610.833891661172, 16744.079075650836, 16762.66474670512, 16813.495595132164, 16998.405055822986, 17175.91412042932, 17277.222524986406, 13634.113782604198, 13855.722885542233, 13891.4443422263, 14024.579262622137, 14064.367103480368, 3450.585929688428, 3473.808940508271, 3476.8261586920808, 3478.1478537758157, 3478.4956181353564, 101295.49352253265, 129129.70870291271, 129963.40073198483, 131397.0, 132316.33841830827, 12991.220526768235, 13030.283334416508, 13205.537806176737, 13372.598822035354, 13451.730965380637, 20091.39725808228, 24268.63597046046, 24928.0, 26028.219153425427, 26070.34824129374, 7665.770026899202, 7715.729949451742, 7789.33790627803, 7886.842263154705, 7892.210778922062, 3827.5981021992848, 3885.261800257942, 3901.122247494291, 3906.394508851076, 3914.4715463412645, 4244.830206791694, 4276.764777937181, 4312.978435107804, 4334.349847522897, 4336.804843782037, 902.6840605787921, 922.5295099499303, 939.2016785732073, 939.8214339275515, 941.3833938770116, 637.5951270942892, 646.7703965092325, 649.5031301054697, 658.1805651963241, 679.9898001529981, 10268.537915793808, 10767.78464430707, 11169.497372618252, 11549.845015498382, 12165.93917030427, 645.3901063494952, 647.4431988489989, 656.8390744267685, 667.562746401108, 685.266764561914, 4704.964907720296, 5322.92015619766, 5444.0, 5460.989716902306, 5475.835724928259, 100691.0, 110350.89649103406, 110610.0, 111189.44405277922, 112818.0, 93527.0, 94861.0, 95751.08497830004, 96910.0, 97156.0, 50517.948205179186, 71725.49820442224, 75998.74017007633, 84503.0, 88665.0, 10884.129269658566, 11564.47959841826, 11808.291502509877, 12372.0, 12457.81650743178, 25708.228753137435, 35035.59857605667, 37780.811095944344, 40336.3949540763, 40710.982225444604, 42876.785616072964, 52424.95150097053, 52661.21008184804, 52922.0, 53448.0, 14141.0, 15417.845821541641, 15695.607609809846, 16116.516504504883, 16339.428120015742, 17149.742753858452, 17828.91085544616, 17906.91046544759, 17944.91027544854, 18167.27330906749, 13846.10000349974, 15858.731301496084, 15869.365225390855, 16393.672126557165, 16588.170591470618, 16065.598360040625, 16091.75862362089, 16272.0, 16489.175541223132, 16612.667746644758, 13036.869631303565, 16097.436589719304, 16120.27458764335, 16130.838691613402, 16251.431199907947, 8707.390482666406, 11607.535698571963, 11846.763064738829, 11859.466324015613, 12475.313857737912, 11784.646460606196, 13049.217046977212, 13065.020123490965, 13171.802422963472, 13517.391717372546], "value": 8213.082622746122, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 117.0, 108.0, 110.0, 110.0, 109.0, 112.0, 110.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 143.0, 112.0, 151.0, 109.0, 109.0, 110.0, 108.0, 136.0, 111.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 186.0, 183.0, 186.0, 181.0, 185.0, 188.0, 181.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 61.0, 96.0, 57.0, 59.0, 56.0, 61.0, 84.0, 60.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 85.0, 68.0, 69.0, 68.0, 99.0, 103.0, 102.0, 89.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 86.0, 87.0, 87.0, 86.0, 86.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 84.0, 111.0, 87.0, 85.0, 84.0, 83.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 47.0, 47.0, 49.0, 46.0, 59.0, 60.0, 48.0, 49.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 61.0, 60.0, 64.0, 60.0, 59.0, 58.0, 61.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 83.0, 84.0, 82.0, 83.0, 80.0, 82.0, 80.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 135.0, 138.0, 136.0, 136.0, 151.0, 147.0, 193.0, 176.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 186.0, 186.0, 181.0, 183.0, 190.0, 183.0, 183.0, 183.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 66.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 66.0], "value": 67.6, "unit": "score"}]}]} 21:52:16 INFO - Return code: 0 21:52:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:52:16 INFO - # TBPL SUCCESS # 21:52:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:52:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:52:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:52:16 INFO - 2017-05-03 21:52:16.847 screenresolution[2989:91923] starting screenresolution argv=screenresolution get 21:52:16 INFO - 2017-05-03 21:52:16.849 screenresolution[2989:91923] Display 0: 1600x1200x32@60 21:52:16 INFO - 2017-05-03 21:52:16.855 screenresolution[2990:91926] starting screenresolution argv=screenresolution list 21:52:16 INFO - Available Modes on Display 0 21:52:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:52:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:52:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:52:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:52:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:52:17 INFO - Intel Iris: 21:52:17 INFO - Chipset Model: Intel Iris 21:52:17 INFO - Type: GPU 21:52:17 INFO - Bus: Built-In 21:52:17 INFO - VRAM (Dynamic, Max): 1536 MB 21:52:17 INFO - Vendor: Intel (0x8086) 21:52:17 INFO - Device ID: 0x0a2e 21:52:17 INFO - Revision ID: 0x0009 21:52:17 INFO - Displays: 21:52:17 INFO - Display: 21:52:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:52:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:52:17 INFO - Display Serial Number: 1600x1200 60 21:52:17 INFO - Main Display: Yes 21:52:17 INFO - Mirror: Off 21:52:17 INFO - Online: Yes 21:52:17 INFO - Rotation: Supported 21:52:17 INFO - Return code: 0 21:52:17 INFO - Running post-action listener: _resource_record_post_action 21:52:17 INFO - [mozharness: 2017-05-04 04:52:17.098864Z] Finished run-tests step (success) 21:52:17 INFO - Running post-run listener: _resource_record_post_run 21:52:17 INFO - Total resource usage - Wall time: 1198s; CPU: 53.0%; Read bytes: 0; Write bytes: 505758208; Read time: 0; Write time: 1561 21:52:17 INFO - TinderboxPrint: CPU usage
53.1% 21:52:17 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:52:17 INFO - TinderboxPrint: I/O write bytes / time
505,758,208 / 1,561 21:52:17 INFO - TinderboxPrint: CPU idle
2,247.5 (46.9%) 21:52:17 INFO - TinderboxPrint: CPU system
950.9 (19.8%) 21:52:17 INFO - TinderboxPrint: CPU user
1,595.4 (33.3%) 21:52:17 INFO - TinderboxPrint: Swap in / out
207,613,952 / 0 21:52:17 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 196663808; Write bytes: 196126208; Read time: 15076; Write time: 417 21:52:17 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 0; Write bytes: 292789248; Read time: 0; Write time: 1091 21:52:17 INFO - Running post-run listener: _upload_blobber_files 21:52:17 INFO - Blob upload gear active. 21:52:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:52:17 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 21:52:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:52:17 INFO - (blobuploader) - INFO - Open directory for files ... 21:52:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:52:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:52:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:19 INFO - (blobuploader) - INFO - Done attempting. 21:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:52:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:19 INFO - (blobuploader) - INFO - Done attempting. 21:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:52:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:21 INFO - (blobuploader) - INFO - Done attempting. 21:52:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:52:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:52:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:23 INFO - (blobuploader) - INFO - Done attempting. 21:52:23 INFO - (blobuploader) - INFO - Iteration through files over. 21:52:23 INFO - Return code: 0 21:52:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:52:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:52:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a7f973e8158a59adb68e20e2c7d1ff620a0308e8333368b242b9b726f8a738de781536e927774caf5b463577a4c525932110e065d37bc085607e1e4f7c6ee3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00861cf2d8f630266a99f6a7b4d83d112034f2388a98385778946d62685bacd368151238d3b4a733c737dd7c88ace2b9ef3789a6ada72f881333b051dfcef465", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92adc55ca7bf4739991337499a50f000f63f29578e38d937b6b8df0b87cdb8086afa6f6b5fb4227ee69a173d6e74b2b4d77643a158ddd864861e7bf4d2d986f6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d989a95f7f650c598c1a23817dd2ce84a981dc2d1e97a10503ba958bd515dcf093edbd042f1e11323312c2f259ec2527071553f77294e9979a3611adcde828"} 21:52:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:52:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:52:23 INFO - Contents: 21:52:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a7f973e8158a59adb68e20e2c7d1ff620a0308e8333368b242b9b726f8a738de781536e927774caf5b463577a4c525932110e065d37bc085607e1e4f7c6ee3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00861cf2d8f630266a99f6a7b4d83d112034f2388a98385778946d62685bacd368151238d3b4a733c737dd7c88ace2b9ef3789a6ada72f881333b051dfcef465", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92adc55ca7bf4739991337499a50f000f63f29578e38d937b6b8df0b87cdb8086afa6f6b5fb4227ee69a173d6e74b2b4d77643a158ddd864861e7bf4d2d986f6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d989a95f7f650c598c1a23817dd2ce84a981dc2d1e97a10503ba958bd515dcf093edbd042f1e11323312c2f259ec2527071553f77294e9979a3611adcde828"} 21:52:23 INFO - Running post-run listener: copy_logs_to_upload_dir 21:52:23 INFO - Copying logs to upload dir... 21:52:23 INFO - mkdir: /builds/slave/test/build/upload/logs 21:52:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.924628 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-03 21:52:23.414552) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 21:52:23.422373) ========= 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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a7f973e8158a59adb68e20e2c7d1ff620a0308e8333368b242b9b726f8a738de781536e927774caf5b463577a4c525932110e065d37bc085607e1e4f7c6ee3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00861cf2d8f630266a99f6a7b4d83d112034f2388a98385778946d62685bacd368151238d3b4a733c737dd7c88ace2b9ef3789a6ada72f881333b051dfcef465", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92adc55ca7bf4739991337499a50f000f63f29578e38d937b6b8df0b87cdb8086afa6f6b5fb4227ee69a173d6e74b2b4d77643a158ddd864861e7bf4d2d986f6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d989a95f7f650c598c1a23817dd2ce84a981dc2d1e97a10503ba958bd515dcf093edbd042f1e11323312c2f259ec2527071553f77294e9979a3611adcde828"} build_url:https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011354 build_url: 'https://queue.taskcluster.net/v1/task/BtvgTKwERZKsxB5T0HYY9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a7f973e8158a59adb68e20e2c7d1ff620a0308e8333368b242b9b726f8a738de781536e927774caf5b463577a4c525932110e065d37bc085607e1e4f7c6ee3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00861cf2d8f630266a99f6a7b4d83d112034f2388a98385778946d62685bacd368151238d3b4a733c737dd7c88ace2b9ef3789a6ada72f881333b051dfcef465", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92adc55ca7bf4739991337499a50f000f63f29578e38d937b6b8df0b87cdb8086afa6f6b5fb4227ee69a173d6e74b2b4d77643a158ddd864861e7bf4d2d986f6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d989a95f7f650c598c1a23817dd2ce84a981dc2d1e97a10503ba958bd515dcf093edbd042f1e11323312c2f259ec2527071553f77294e9979a3611adcde828"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 21:52:23.461806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:52:23.462152) ========= 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.iFxMdi4zZm/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.Mli4HCQQ3H/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.005248 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:52:23.511882) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 21:52:23.512211) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 21:52:23.512547) ========= ========= Total master_lag: 0.21 =========