builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0380 starttime: 1489702690.47 results: success (0) buildid: 20170316134821 builduid: 6706c0955cbc48ce944675fd54c46f34 revision: 39607304b774591fa6e32c4b06158d869483c312 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.472475) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.472902) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.473184) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.499149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.499453) ========= 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.hLlROprWsZ/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.kC6L23gKVO/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-16 15:18:10-- 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% 18.8M=0.001s 2017-03-16 15:18:10 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.170512 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.693667) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.693999) ========= 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.hLlROprWsZ/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.kC6L23gKVO/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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.026556 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:10.742171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 15:18:10.742502) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39607304b774591fa6e32c4b06158d869483c312 --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 39607304b774591fa6e32c4b06158d869483c312 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hLlROprWsZ/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.kC6L23gKVO/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-16 15:18:10,852 truncating revision to first 12 chars 2017-03-16 15:18:10,852 Setting DEBUG logging. 2017-03-16 15:18:10,852 attempt 1/10 2017-03-16 15:18:10,853 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39607304b774?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-16 15:18:12,434 unpacking tar archive at: mozilla-central-39607304b774/testing/mozharness/ program finished with exit code 0 elapsedTime=1.864274 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 15:18:12.634877) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:12.635221) ========= script_repo_revision: 39607304b774591fa6e32c4b06158d869483c312 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:12.635643) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:12.635926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 15:18:12.656306) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-16 15:18:12.657047) ========= /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.hLlROprWsZ/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.kC6L23gKVO/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:18:12 INFO - MultiFileLogger online at 20170316 15:18:12 in /builds/slave/test 15:18:12 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 15:18:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:18:12 INFO - {'append_to_log': False, 15:18:12 INFO - 'base_work_dir': '/builds/slave/test', 15:18:12 INFO - 'blob_upload_branch': 'Firefox', 15:18:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:18:12 INFO - 'branch': 'Firefox', 15:18:12 INFO - 'buildbot_json_path': 'buildprops.json', 15:18:12 INFO - 'config_files': ('talos/mac_config.py',), 15:18:12 INFO - 'default_actions': ('clobber', 15:18:12 INFO - 'read-buildbot-config', 15:18:12 INFO - 'download-and-extract', 15:18:12 INFO - 'populate-webroot', 15:18:12 INFO - 'create-virtualenv', 15:18:12 INFO - 'install', 15:18:12 INFO - 'run-tests'), 15:18:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:18:12 INFO - 'download_minidump_stackwalk': True, 15:18:12 INFO - 'download_symbols': 'ondemand', 15:18:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:18:12 INFO - 'tooltool.py': '/tools/tooltool.py', 15:18:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:18:12 INFO - '/tools/misc-python/virtualenv.py')}, 15:18:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:18:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:18:12 INFO - 'gecko_profile': False, 15:18:12 INFO - 'gecko_profile_interval': 0, 15:18:12 INFO - 'installer_path': 'installer.exe', 15:18:12 INFO - 'log_level': 'info', 15:18:12 INFO - 'log_name': 'talos', 15:18:12 INFO - 'log_to_console': True, 15:18:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:18:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:18:12 INFO - 'opt_config_files': (), 15:18:12 INFO - 'pip_index': False, 15:18:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:12 INFO - 'cmd': ('bash', 15:18:12 INFO - '-c', 15:18:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:12 INFO - 'enabled': True, 15:18:12 INFO - 'halt_on_failure': False, 15:18:12 INFO - 'name': 'check_screen_resolution'},), 15:18:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:12 INFO - 'cmd': ('bash', 15:18:12 INFO - '-c', 15:18:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:12 INFO - 'enabled': True, 15:18:12 INFO - 'halt_on_failure': False, 15:18:12 INFO - 'name': 'check_screen_resolution'},), 15:18:12 INFO - 'run_cmd_checks_enabled': True, 15:18:12 INFO - 'suite': 'dromaeojs', 15:18:12 INFO - 'system_bits': '32', 15:18:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:18:12 INFO - 'title': 't-yosemite-r7-0380', 15:18:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:18:12 INFO - 'use_talos_json': True, 15:18:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:18:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:18:12 INFO - 'work_dir': 'build'} 15:18:12 INFO - [mozharness: 2017-03-16 22:18:12.874357Z] Running clobber step. 15:18:12 INFO - Running pre-action listener: _resource_record_pre_action 15:18:12 INFO - Running main action method: clobber 15:18:12 INFO - rmtree: /builds/slave/test/build 15:18:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:18:15 INFO - Running post-action listener: _resource_record_post_action 15:18:15 INFO - [mozharness: 2017-03-16 22:18:15.128782Z] Finished clobber step (success) 15:18:15 INFO - [mozharness: 2017-03-16 22:18:15.128911Z] Running read-buildbot-config step. 15:18:15 INFO - Running pre-action listener: _resource_record_pre_action 15:18:15 INFO - Running main action method: read_buildbot_config 15:18:15 INFO - Using buildbot properties: 15:18:15 INFO - { 15:18:15 INFO - "project": "", 15:18:15 INFO - "product": "firefox", 15:18:15 INFO - "script_repo_revision": "production", 15:18:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:18:15 INFO - "repository": "", 15:18:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:18:15 INFO - "buildid": "20170316134821", 15:18:15 INFO - "pgo_build": "False", 15:18:15 INFO - "basedir": "/builds/slave/test", 15:18:15 INFO - "buildnumber": 71, 15:18:15 INFO - "slavename": "t-yosemite-r7-0380", 15:18:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:18:15 INFO - "platform": "yosemite_r7", 15:18:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:18:15 INFO - "branch": "mozilla-central", 15:18:15 INFO - "repo_path": "mozilla-central", 15:18:15 INFO - "revision": "39607304b774591fa6e32c4b06158d869483c312", 15:18:15 INFO - "stage_platform": "macosx64", 15:18:15 INFO - "builduid": "6706c0955cbc48ce944675fd54c46f34", 15:18:15 INFO - "slavebuilddir": "test" 15:18:15 INFO - } 15:18:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:18:15 INFO - Running post-action listener: _resource_record_post_action 15:18:15 INFO - [mozharness: 2017-03-16 22:18:15.132120Z] Finished read-buildbot-config step (success) 15:18:15 INFO - [mozharness: 2017-03-16 22:18:15.132252Z] Running download-and-extract step. 15:18:15 INFO - Running pre-action listener: _resource_record_pre_action 15:18:15 INFO - Running main action method: download_and_extract 15:18:15 INFO - mkdir: /builds/slave/test/build/tests 15:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:15 INFO - trying https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:18:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/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 15:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/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 15:18:16 INFO - Downloaded 1325 bytes. 15:18:16 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:18:16 INFO - Using the following test package requirements: 15:18:16 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:18:16 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:18:16 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:18:16 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:18:16 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'jsshell-mac.zip'], 15:18:16 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:18:16 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:18:16 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:18:16 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:18:16 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:18:16 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:18:16 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:18:16 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:18:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:18:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:18:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:18:17 INFO - Expected file size: 52472680 15:18:17 INFO - Obtained file size: 52472680 15:18:34 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 15:18:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:18:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:18:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:18:35 INFO - Expected file size: 52472680 15:18:35 INFO - Obtained file size: 52472680 15:18:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:18:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:18:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:18:51 INFO - Expected file size: 14404452 15:18:51 INFO - Obtained file size: 14404452 15:18:52 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')]} 15:18:52 INFO - trying https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:18:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:18:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/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 15:18:54 INFO - Downloaded 75073670 bytes. 15:18:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:18:54 INFO - mkdir: /builds/slave/test/properties 15:18:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:18:54 INFO - Writing to file /builds/slave/test/properties/build_url 15:18:54 INFO - Contents: 15:18:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:18:55 INFO - Running post-action listener: _resource_record_post_action 15:18:55 INFO - Running post-action listener: set_extra_try_arguments 15:18:55 INFO - [mozharness: 2017-03-16 22:18:55.180553Z] Finished download-and-extract step (success) 15:18:55 INFO - [mozharness: 2017-03-16 22:18:55.180701Z] Running populate-webroot step. 15:18:55 INFO - Running pre-action listener: _resource_record_pre_action 15:18:55 INFO - Running main action method: populate_webroot 15:18:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:18:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:18:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:18:55 INFO - '--tppagecycles', 15:18:55 INFO - '7'], 15:18:55 INFO - 'tests': ['tsvgm']}}, 15:18:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:18:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:18:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:18:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:18:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:18:55 INFO - 'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:18:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:18:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:18:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:18:55 INFO - 'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['damp', 'tps']}, 15:18:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:18:55 INFO - 'tests': ['damp', 'tps']}, 15:18:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['dromaeo_dom']}, 15:18:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:18:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['basic_compositor_video']}, 15:18:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:18:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['a11yr', 15:18:55 INFO - 'ts_paint', 15:18:55 INFO - 'tpaint', 15:18:55 INFO - 'sessionrestore', 15:18:55 INFO - 'sessionrestore_no_auto_restore', 15:18:55 INFO - 'tabpaint']}, 15:18:55 INFO - 'other-e10s': {'tests': ['a11yr', 15:18:55 INFO - 'ts_paint', 15:18:55 INFO - 'tpaint', 15:18:55 INFO - 'sessionrestore', 15:18:55 INFO - 'sessionrestore_no_auto_restore', 15:18:55 INFO - 'tabpaint']}, 15:18:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:18:55 INFO - 'ts_paint', 15:18:55 INFO - 'tpaint', 15:18:55 INFO - 'sessionrestore', 15:18:55 INFO - 'sessionrestore_no_auto_restore', 15:18:55 INFO - 'tabpaint']}, 15:18:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:18:55 INFO - 'ts_paint', 15:18:55 INFO - 'tpaint', 15:18:55 INFO - 'sessionrestore', 15:18:55 INFO - 'sessionrestore_no_auto_restore', 15:18:55 INFO - 'tabpaint']}, 15:18:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['a11yr', 15:18:55 INFO - 'ts_paint', 15:18:55 INFO - 'tpaint', 15:18:55 INFO - 'sessionrestore', 15:18:55 INFO - 'sessionrestore_no_auto_restore', 15:18:55 INFO - 'tabpaint']}, 15:18:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['a11yr', 15:18:55 INFO - 'ts_paint', 15:18:55 INFO - 'tpaint', 15:18:55 INFO - 'sessionrestore', 15:18:55 INFO - 'sessionrestore_no_auto_restore', 15:18:55 INFO - 'tabpaint']}, 15:18:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['tsvgx', 15:18:55 INFO - 'tsvgr_opacity', 15:18:55 INFO - 'tart', 15:18:55 INFO - 'tscrollx', 15:18:55 INFO - 'cart', 15:18:55 INFO - 'tsvg_static']}, 15:18:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:18:55 INFO - 'tsvgr_opacity', 15:18:55 INFO - 'tart', 15:18:55 INFO - 'tscrollx', 15:18:55 INFO - 'cart', 15:18:55 INFO - 'tsvg_static']}, 15:18:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:18:55 INFO - 'talos_options': ['--disable-e10s'], 15:18:55 INFO - 'tests': ['tp5o']}, 15:18:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:18:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:18:55 INFO - 'talos_options': ['--disable-e10s', 15:18:55 INFO - '--xperf_path', 15:18:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:18:55 INFO - 'tests': ['tp5n']}, 15:18:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:18:55 INFO - 'talos_options': ['--xperf_path', 15:18:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:18:55 INFO - 'tests': ['tp5n']}}, 15:18:55 INFO - 'talos.zip': {'path': '', 15:18:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:18:55 INFO - Running post-action listener: _resource_record_post_action 15:18:55 INFO - [mozharness: 2017-03-16 22:18:55.198912Z] Finished populate-webroot step (success) 15:18:55 INFO - [mozharness: 2017-03-16 22:18:55.199019Z] Running create-virtualenv step. 15:18:55 INFO - Running pre-action listener: _resource_record_pre_action 15:18:55 INFO - Running main action method: create_virtualenv 15:18:55 INFO - Creating virtualenv /builds/slave/test/build/venv 15:18:55 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 15:18:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:18:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:18:55 INFO - Using real prefix '/tools/python27' 15:18:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:18:56 INFO - Installing distribute.............................................................................................................................................................................................done. 15:18:59 INFO - Installing pip.................done. 15:18:59 INFO - Return code: 0 15:18:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:18:59 INFO - Reading from file tmpfile_stdout 15:18:59 INFO - Output received: 15:18:59 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) 15:18:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:18: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')]} 15:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18: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')]} 15:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:18: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', 'pip>=1.5'] in /builds/slave/test/build 15:18: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 pip>=1.5 15:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:18:59 INFO - 'HOME': '/Users/cltbld', 15:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:18:59 INFO - 'LOGNAME': 'cltbld', 15:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:18:59 INFO - 'MOZ_NO_REMOTE': '1', 15:18:59 INFO - 'NO_EM_RESTART': '1', 15:18:59 INFO - 'PAGER': '/bin/cat', 15:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:18:59 INFO - 'PWD': '/builds/slave/test', 15:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:18:59 INFO - 'SHELL': '/bin/bash', 15:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:18:59 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:18:59 INFO - 'USER': 'cltbld', 15:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:18:59 INFO - 'XPC_FLAGS': '0x0', 15:18:59 INFO - 'XPC_SERVICE_NAME': '0', 15:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:18:59 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 15:18:59 INFO - Cleaning up... 15:18:59 INFO - Return code: 0 15:18:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:18: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')]} 15:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18: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')]} 15:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18: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', '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 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:18: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:18: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 psutil>=3.1.1 15:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:18:59 INFO - 'HOME': '/Users/cltbld', 15:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:18:59 INFO - 'LOGNAME': 'cltbld', 15:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:18:59 INFO - 'MOZ_NO_REMOTE': '1', 15:18:59 INFO - 'NO_EM_RESTART': '1', 15:18:59 INFO - 'PAGER': '/bin/cat', 15:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:18:59 INFO - 'PWD': '/builds/slave/test', 15:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:18:59 INFO - 'SHELL': '/bin/bash', 15:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:18:59 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:18:59 INFO - 'USER': 'cltbld', 15:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:18:59 INFO - 'XPC_FLAGS': '0x0', 15:18:59 INFO - 'XPC_SERVICE_NAME': '0', 15:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:18:59 INFO - Downloading/unpacking psutil>=3.1.1 15:18: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 15:18: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 15:18: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 15:18: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 15:18: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 15:18: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 15:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:19:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:03 INFO - Installing collected packages: psutil 15:19:03 INFO - Running setup.py install for psutil 15:19:03 INFO - building 'psutil._psutil_osx' extension 15:19:03 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 15:19:03 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 15:19:03 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 15:19:03 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 15:19:04 INFO - building 'psutil._psutil_posix' extension 15:19:04 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 15:19:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:19:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:19:04 INFO - ^ 15:19:04 INFO - 1 warning generated. 15:19:04 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 15:19:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:04 INFO - Successfully installed psutil 15:19:04 INFO - Cleaning up... 15:19:04 INFO - Return code: 0 15:19:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:19: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')]} 15:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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')]} 15:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:19: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 mozsystemmonitor==0.3 15:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:04 INFO - 'HOME': '/Users/cltbld', 15:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:04 INFO - 'LOGNAME': 'cltbld', 15:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:04 INFO - 'MOZ_NO_REMOTE': '1', 15:19:04 INFO - 'NO_EM_RESTART': '1', 15:19:04 INFO - 'PAGER': '/bin/cat', 15:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:04 INFO - 'PWD': '/builds/slave/test', 15:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:04 INFO - 'SHELL': '/bin/bash', 15:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:04 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:04 INFO - 'USER': 'cltbld', 15:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:04 INFO - 'XPC_FLAGS': '0x0', 15:19:04 INFO - 'XPC_SERVICE_NAME': '0', 15:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:19:04 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 15:19:04 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 15:19:04 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 15:19:04 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 15:19:04 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 15:19:04 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 15:19:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:19:07 INFO - Installing collected packages: mozsystemmonitor 15:19:07 INFO - Running setup.py install for mozsystemmonitor 15:19:07 INFO - Successfully installed mozsystemmonitor 15:19:07 INFO - Cleaning up... 15:19:07 INFO - Return code: 0 15:19:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19:08 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 15:19:08 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 15:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:08 INFO - 'HOME': '/Users/cltbld', 15:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:08 INFO - 'LOGNAME': 'cltbld', 15:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:08 INFO - 'MOZ_NO_REMOTE': '1', 15:19:08 INFO - 'NO_EM_RESTART': '1', 15:19:08 INFO - 'PAGER': '/bin/cat', 15:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:08 INFO - 'PWD': '/builds/slave/test', 15:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:08 INFO - 'SHELL': '/bin/bash', 15:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:08 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:08 INFO - 'USER': 'cltbld', 15:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:08 INFO - 'XPC_FLAGS': '0x0', 15:19:08 INFO - 'XPC_SERVICE_NAME': '0', 15:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:08 INFO - Downloading/unpacking jsonschema==2.5.1 15:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:19:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:19:11 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 15:19:11 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 15:19:11 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 15:19:11 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 15:19:11 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 15:19:11 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 15:19:11 INFO - Downloading functools32-3.2.3-2.tar.gz 15:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:19:12 INFO - warning: no files found matching '*.txt' 15:19:12 INFO - no previously-included directories found matching 'build' 15:19:12 INFO - no previously-included directories found matching 'dist' 15:19:12 INFO - no previously-included directories found matching '.git*' 15:19:12 INFO - Installing collected packages: jsonschema, functools32 15:19:12 INFO - Running setup.py install for functools32 15:19:12 INFO - warning: no files found matching '*.txt' 15:19:12 INFO - no previously-included directories found matching 'build' 15:19:12 INFO - no previously-included directories found matching 'dist' 15:19:12 INFO - no previously-included directories found matching '.git*' 15:19:12 INFO - Successfully installed jsonschema functools32 15:19:12 INFO - Cleaning up... 15:19:12 INFO - Return code: 0 15:19:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:12 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 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19:12 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 15:19:12 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 15:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:12 INFO - 'HOME': '/Users/cltbld', 15:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:12 INFO - 'LOGNAME': 'cltbld', 15:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:12 INFO - 'MOZ_NO_REMOTE': '1', 15:19:12 INFO - 'NO_EM_RESTART': '1', 15:19:12 INFO - 'PAGER': '/bin/cat', 15:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:12 INFO - 'PWD': '/builds/slave/test', 15:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:12 INFO - 'SHELL': '/bin/bash', 15:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:12 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:12 INFO - 'USER': 'cltbld', 15:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:12 INFO - 'XPC_FLAGS': '0x0', 15:19:12 INFO - 'XPC_SERVICE_NAME': '0', 15:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:19:12 INFO - Cleaning up... 15:19:12 INFO - Return code: 0 15:19:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:12 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 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19:12 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 15:19:12 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 15:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:12 INFO - 'HOME': '/Users/cltbld', 15:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:12 INFO - 'LOGNAME': 'cltbld', 15:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:12 INFO - 'MOZ_NO_REMOTE': '1', 15:19:12 INFO - 'NO_EM_RESTART': '1', 15:19:12 INFO - 'PAGER': '/bin/cat', 15:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:12 INFO - 'PWD': '/builds/slave/test', 15:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:12 INFO - 'SHELL': '/bin/bash', 15:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:12 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:12 INFO - 'USER': 'cltbld', 15:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:12 INFO - 'XPC_FLAGS': '0x0', 15:19:12 INFO - 'XPC_SERVICE_NAME': '0', 15:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:12 INFO - Downloading/unpacking blobuploader==1.2.4 15:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:15 INFO - Downloading blobuploader-1.2.4.tar.gz 15:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:19:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:19:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:19:16 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 15:19:16 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 15:19:16 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 15:19:16 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 15:19:16 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 15:19:16 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 15:19:16 INFO - Downloading docopt-0.6.1.tar.gz 15:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:19:16 INFO - Installing collected packages: blobuploader, requests, docopt 15:19:16 INFO - Running setup.py install for blobuploader 15:19:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:19:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:19:16 INFO - Running setup.py install for requests 15:19:17 INFO - Running setup.py install for docopt 15:19:17 INFO - Successfully installed blobuploader requests docopt 15:19:17 INFO - Cleaning up... 15:19:17 INFO - Return code: 0 15:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19: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')]} 15:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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')]} 15:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:17 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 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19:17 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 15:19:17 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 15:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:17 INFO - 'HOME': '/Users/cltbld', 15:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:17 INFO - 'LOGNAME': 'cltbld', 15:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:17 INFO - 'MOZ_NO_REMOTE': '1', 15:19:17 INFO - 'NO_EM_RESTART': '1', 15:19:17 INFO - 'PAGER': '/bin/cat', 15:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:17 INFO - 'PWD': '/builds/slave/test', 15:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:17 INFO - 'SHELL': '/bin/bash', 15:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:17 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:17 INFO - 'USER': 'cltbld', 15:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:17 INFO - 'XPC_FLAGS': '0x0', 15:19:17 INFO - 'XPC_SERVICE_NAME': '0', 15:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-ZyKaNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-48fROI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-skO3TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-z0Avut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-4JrOB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-4JjsqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-7uiphW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-sVDLQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-05hhtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-dscKGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-iTqXct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:19 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-QlHTlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:19 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-YzErfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:19 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-qgETTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:19 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-L2862g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:19 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-aBEQM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:19 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-iS8bJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:19:19 INFO - Running setup.py install for manifestparser 15:19:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:19:19 INFO - Running setup.py install for mozcrash 15:19:19 INFO - Running setup.py install for mozdebug 15:19:20 INFO - Running setup.py install for mozdevice 15:19:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Running setup.py install for mozfile 15:19:20 INFO - Running setup.py install for mozhttpd 15:19:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Running setup.py install for mozinfo 15:19:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Running setup.py install for mozInstall 15:19:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:19:20 INFO - Running setup.py install for mozleak 15:19:20 INFO - Running setup.py install for mozlog 15:19:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Running setup.py install for moznetwork 15:19:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Running setup.py install for mozprocess 15:19:21 INFO - Running setup.py install for mozprofile 15:19:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Running setup.py install for mozrunner 15:19:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Running setup.py install for mozscreenshot 15:19:21 INFO - Running setup.py install for moztest 15:19:22 INFO - Running setup.py install for mozversion 15:19:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:19:22 INFO - Cleaning up... 15:19:22 INFO - Return code: 0 15:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:22 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 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19:22 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 15:19:22 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 15:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:22 INFO - 'HOME': '/Users/cltbld', 15:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:22 INFO - 'LOGNAME': 'cltbld', 15:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:22 INFO - 'MOZ_NO_REMOTE': '1', 15:19:22 INFO - 'NO_EM_RESTART': '1', 15:19:22 INFO - 'PAGER': '/bin/cat', 15:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:22 INFO - 'PWD': '/builds/slave/test', 15:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:22 INFO - 'SHELL': '/bin/bash', 15:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:22 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:22 INFO - 'USER': 'cltbld', 15:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:22 INFO - 'XPC_FLAGS': '0x0', 15:19:22 INFO - 'XPC_SERVICE_NAME': '0', 15:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-H4CEJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:22 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)) 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-wLgkMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:22 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)) 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-LdSR8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:22 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)) 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-Y2XBGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-hEk5i7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-AR4P2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-ULEhS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-BOK5hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-7Z1_uG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-Wwblgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-x1ngHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-dEiX4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-p2RDNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-VkdTnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:24 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-V6DHh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:24 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-WAIGD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:24 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-TU6r_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:24 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 15:19:24 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 15:19:24 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 15:19:24 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 15:19:24 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 15:19:24 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 15:19:26 INFO - Downloading blessings-1.6.tar.gz 15:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:19:27 INFO - Installing collected packages: blessings 15:19:27 INFO - Running setup.py install for blessings 15:19:27 INFO - Successfully installed blessings 15:19:27 INFO - Cleaning up... 15:19:27 INFO - Return code: 0 15:19:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:27 INFO - Reading from file tmpfile_stdout 15:19:27 INFO - Current package versions: 15:19:27 INFO - blessings == 1.6 15:19:27 INFO - blobuploader == 1.2.4 15:19:27 INFO - docopt == 0.6.1 15:19:27 INFO - functools32 == 3.2.3-2 15:19:27 INFO - jsonschema == 2.5.1 15:19:27 INFO - manifestparser == 1.1 15:19:27 INFO - mozInstall == 1.12 15:19:27 INFO - mozcrash == 1.0 15:19:27 INFO - mozdebug == 0.1 15:19:27 INFO - mozdevice == 0.49 15:19:27 INFO - mozfile == 1.2 15:19:27 INFO - mozhttpd == 0.7 15:19:27 INFO - mozinfo == 0.9 15:19:27 INFO - mozleak == 0.1 15:19:27 INFO - mozlog == 3.4 15:19:27 INFO - moznetwork == 0.27 15:19:27 INFO - mozprocess == 0.25 15:19:27 INFO - mozprofile == 0.28 15:19:27 INFO - mozrunner == 6.13 15:19:27 INFO - mozscreenshot == 0.1 15:19:27 INFO - mozsystemmonitor == 0.3 15:19:27 INFO - moztest == 0.8 15:19:27 INFO - mozversion == 1.4 15:19:27 INFO - psutil == 3.1.1 15:19:27 INFO - requests == 1.2.3 15:19:27 INFO - wsgiref == 0.1.2 15:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:27 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 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19:27 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 15:19:27 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 15:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:27 INFO - 'HOME': '/Users/cltbld', 15:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:27 INFO - 'LOGNAME': 'cltbld', 15:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:27 INFO - 'MOZ_NO_REMOTE': '1', 15:19:27 INFO - 'NO_EM_RESTART': '1', 15:19:27 INFO - 'PAGER': '/bin/cat', 15:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:27 INFO - 'PWD': '/builds/slave/test', 15:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:27 INFO - 'SHELL': '/bin/bash', 15:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:27 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:27 INFO - 'USER': 'cltbld', 15:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:27 INFO - 'XPC_FLAGS': '0x0', 15:19:27 INFO - 'XPC_SERVICE_NAME': '0', 15:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 INFO - Cleaning up... 15:19:27 INFO - Return code: 0 15:19:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6bbc616a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, '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.hLlROprWsZ/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.kC6L23gKVO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 15:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:27 INFO - 'HOME': '/Users/cltbld', 15:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:27 INFO - 'LOGNAME': 'cltbld', 15:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:27 INFO - 'MOZ_NO_REMOTE': '1', 15:19:27 INFO - 'NO_EM_RESTART': '1', 15:19:27 INFO - 'PAGER': '/bin/cat', 15:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:27 INFO - 'PWD': '/builds/slave/test', 15:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:27 INFO - 'SHELL': '/bin/bash', 15:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:27 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:27 INFO - 'USER': 'cltbld', 15:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:27 INFO - 'XPC_FLAGS': '0x0', 15:19:27 INFO - 'XPC_SERVICE_NAME': '0', 15:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:19:28 INFO - Cleaning up... 15:19:28 INFO - Return code: 0 15:19:28 INFO - Running post-action listener: _resource_record_post_action 15:19:28 INFO - Running post-action listener: _start_resource_monitoring 15:19:28 INFO - Starting resource monitoring. 15:19:28 INFO - [mozharness: 2017-03-16 22:19:28.205113Z] Finished create-virtualenv step (success) 15:19:28 INFO - [mozharness: 2017-03-16 22:19:28.206062Z] Running install step. 15:19:28 INFO - Running pre-action listener: _resource_record_pre_action 15:19:28 INFO - Running main action method: install 15:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:28 INFO - Reading from file tmpfile_stdout 15:19:28 INFO - Detecting whether we're running mozinstall >=1.0... 15:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:19:28 INFO - Reading from file tmpfile_stdout 15:19:28 INFO - Output received: 15:19:28 INFO - Usage: mozinstall [options] installer 15:19:28 INFO - Options: 15:19:28 INFO - -h, --help show this help message and exit 15:19:28 INFO - -d DEST, --destination=DEST 15:19:28 INFO - Directory to install application into. [default: 15:19:28 INFO - "/builds/slave/test"] 15:19:28 INFO - --app=APP Application being installed. [default: firefox] 15:19:28 INFO - mkdir: /builds/slave/test/build/application 15:19:28 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'] 15:19:28 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 15:19:49 INFO - Reading from file tmpfile_stdout 15:19:49 INFO - Output received: 15:19:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:19:49 INFO - Running post-action listener: _resource_record_post_action 15:19:49 INFO - [mozharness: 2017-03-16 22:19:49.138687Z] Finished install step (success) 15:19:49 INFO - [mozharness: 2017-03-16 22:19:49.138829Z] Running run-tests step. 15:19:49 INFO - Running pre-action listener: _resource_record_pre_action 15:19:49 INFO - Running main action method: run_tests 15:19:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:19:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:19:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:19:49 INFO - 2017-03-16 15:19:49.153 screenresolution[1639:7380] starting screenresolution argv=screenresolution get 15:19:49 INFO - 2017-03-16 15:19:49.155 screenresolution[1639:7380] Display 0: 1600x1200x32@60 15:19:49 INFO - 2017-03-16 15:19:49.161 screenresolution[1640:7382] starting screenresolution argv=screenresolution list 15:19:49 INFO - Available Modes on Display 0 15:19:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:19:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:19:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:19:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:19:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:19:49 INFO - Intel Iris: 15:19:49 INFO - Chipset Model: Intel Iris 15:19:49 INFO - Type: GPU 15:19:49 INFO - Bus: Built-In 15:19:49 INFO - VRAM (Dynamic, Max): 1536 MB 15:19:49 INFO - Vendor: Intel (0x8086) 15:19:49 INFO - Device ID: 0x0a2e 15:19:49 INFO - Revision ID: 0x0009 15:19:49 INFO - Displays: 15:19:49 INFO - Display: 15:19:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:19:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:19:49 INFO - Display Serial Number: 1600x1200 60 15:19:49 INFO - Main Display: Yes 15:19:49 INFO - Mirror: Off 15:19:49 INFO - Online: Yes 15:19:49 INFO - Rotation: Supported 15:19:49 INFO - Return code: 0 15:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:19:49 INFO - Python 2.7.3 15:19:49 INFO - Return code: 0 15:19:49 INFO - grabbing minidump binary from tooltool 15:19: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')]} 15:19:49 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 0x100485d30>, '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 0x1003acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10047abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:19:49 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 15:19:49 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 15:19:49 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 15:19:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:19:49 INFO - Return code: 0 15:19:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:19:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:19:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:19:49 INFO - ENV: RUST_BACKTRACE is now 1 15:19:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:19:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:19:49 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/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0380', '--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 15:19:49 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/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0380 --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 15:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLlROprWsZ/Render', 15:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:49 INFO - 'HOME': '/Users/cltbld', 15:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:49 INFO - 'LOGNAME': 'cltbld', 15:19:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:19:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:49 INFO - 'MOZ_NO_REMOTE': '1', 15:19:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:19:49 INFO - 'NO_EM_RESTART': '1', 15:19:49 INFO - 'PAGER': '/bin/cat', 15:19:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:49 INFO - 'PWD': '/builds/slave/test', 15:19:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:49 INFO - 'RUST_BACKTRACE': '1', 15:19:49 INFO - 'SHELL': '/bin/bash', 15:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kC6L23gKVO/Listeners', 15:19:49 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 15:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:49 INFO - 'USER': 'cltbld', 15:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:49 INFO - 'XPC_FLAGS': '0x0', 15:19:49 INFO - 'XPC_SERVICE_NAME': '0', 15:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:49 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/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0380', '--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 15:19:49 INFO - mozversion application_buildid: 20170316134821 15:19:49 INFO - mozversion application_changeset: 39607304b774591fa6e32c4b06158d869483c312 15:19:49 INFO - mozversion application_display_name: Nightly 15:19:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:19:49 INFO - mozversion application_name: Firefox 15:19:49 INFO - mozversion application_remotingname: firefox 15:19:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:19:49 INFO - mozversion application_vendor: Mozilla 15:19:49 INFO - mozversion application_version: 55.0a1 15:19:49 INFO - mozversion platform_buildid: 20170316134821 15:19:49 INFO - mozversion platform_changeset: 39607304b774591fa6e32c4b06158d869483c312 15:19:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:19:49 INFO - mozversion platform_version: 55.0a1 15:19:49 INFO - using testdate: 1489702789 15:19:49 INFO - actual date: 1489702789 15:19:49 INFO - starting webserver on 'localhost:49240' 15:19:49 INFO - SUITE-START | Running 2 tests 15:19:49 INFO - TEST-START | dromaeo_css 15:19:49 INFO - operating with platform_type : mac_ 15:19:49 INFO - Initialising browser for dromaeo_css test... 15:19:49 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpYCwe4Z/profile http://localhost:49240/getInfo.html) 15:19:52 INFO - PID 1650 | __metrics Screen width/height:1600/1200 15:19:52 INFO - PID 1650 | colorDepth:24 15:19:52 INFO - PID 1650 | Browser inner width/height: 1024/689 15:19:52 INFO - PID 1650 | __metrics 15:19:52 INFO - PID 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:19:52 INFO - PID 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:19:53 INFO - TEST-INFO | 1650: exit 0 15:19:53 INFO - Browser initialized. 15:19:53 INFO - Running cycle 1/1 for dromaeo_css test... 15:19:53 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpYCwe4Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:05 INFO - PID 1653 | RSS: Main: 233877504 15:20:05 INFO - PID 1653 | 15:23:10 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 15:23:12 INFO - PID 1653 | RSS: Main: 411971584 15:23:12 INFO - PID 1653 | 15:24:54 INFO - PID 1653 | ************************* 15:24:54 INFO - PID 1653 | A coding exception was thrown and uncaught in a Task. 15:24:54 INFO - PID 1653 | 15:24:54 ERROR - PID 1653 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:24:54 INFO - PID 1653 | Full stack: 15:24:54 INFO - PID 1653 | ************************* 15:26:15 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 15:26:16 INFO - PID 1653 | RSS: Main: 367063040 15:26:16 INFO - PID 1653 | 15:29:18 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 15:29:19 INFO - PID 1653 | RSS: Main: 346423296 15:29:19 INFO - PID 1653 | 15:32:22 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 15:32:22 INFO - PID 1653 | RSS: Main: 334082048 15:32:22 INFO - PID 1653 | 15:35:26 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 15:35:27 INFO - PID 1653 | RSS: Main: 334094336 15:35:27 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 15:38:30 INFO - PID 1653 | RSS: Main: 483467264 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | __start_tp_report 15:38:30 INFO - PID 1653 | _x_x_mozilla_page_load 15:38:30 INFO - PID 1653 | _x_x_mozilla_page_load_details 15:38:30 INFO - PID 1653 | |i|pagename|runs| 15:38:30 INFO - PID 1653 | |0;dojo.html;3183.299674071704;3379.678930501603;3379.831008449577;3403.5745531808525;3431;5528.44715528448;5626.887462250753;5805.651660900344;5846.444587764161;5851.561132915032;5730.68481233532;5817.1565123057135;5856.502197313233;5867.061270196769;5870.471657550819;4752.881177970544;4894.730789806559;5146.433892271847;5230.110881150214;5231.973840130813;5324.387695415038;5360.222767698681;5368.8389348319615;5385.973070134643;5400.945990540084;5169.922451163236;5231.372235331746;5235.267062611199;5261.394939581959;5323.680579165262;5285;5303.15149576066;5319.680819150824;5323.627346085776;5406.297181366436;5227.3988491323225;5240.633155679104;5277.86805329866;5285.154375299933;5340.839774806762;5652.830415087513;5693.174489699012;5808.767649294024;5830.4169583041785;5837.153612726173;6255.249370075575;6257.624542527463;6263.812085637438;6268.373162683695;6278.843028924268;4714.504976977437;6266.874662506724;6325.924592819232;6350.587211831263;6351.460125889305;5896.705164741746;5901.881962360814;5926.940730592682;5963.4334738199805;5964.910526342065;5710.172025056344;6243.7814676486105;6447.677616119269;6453;6464.127342808754;6131.602448649625;6185.505159587222;6199.256089269226;6225.501959843202;6297.968510157511;6323.936760632427;6327.841803954938;6348.936510634927;6362.6500542469685;6363.268224154189;6326.936730632727;6331.398517140864;6349.111124442582;6354.046892965999;6354.872902541923;6173.259208894961;6201.1938448002375;6260.40526150015;6345.175127233522;6363.809085727436;5031.144705400091;5059.949400506021;5095.719735414514;5118.667286626357;5171.896562068813;4515.209838278288;4517.5482451755215;4522.389477420572;4528.660350473728;4565.338025986213;5773.278340207474;5936.1986131871645;5944.762209511571;5949.6430214187285;5951.613145145551;6269.216347956505;6270.7491700331475;6291.433770960635;6292.811215663537;6337.112804207414;5949.10763385487;5991.58058935879;6004.54965877561;6005.879882402328;6013.548983826143;5554.555635549146;5554.694491802989;5559.388467268597;5562.638428502134;5568.303962004749;6244.875102498016;6336.809895703136;6338.271098823694;6425.132607097982;6513.218413790375;6278.4035516625845;6287.842803929939;6309.621422714652;6312.495000399865;6357.618542887441;6270.937290627217;6315.021171718391;6336.049592561153;6429.678516074084;6486.805395838132;5155.587552995677;6143.416375444325;6256.624602523863;6327.987521996459;6328.525360598066;5588.944110559005;6197.938020619917;6258.812235632939;6323.051542268697;6327.620342779448;6111.07520520229;6121.0262130506535;6122.764110201706;6133.1510485532735;6133.8855759873895;6125.968084364015;6210.472109870711;6213.1922850029205;6267.435930766253;6283.2460104787715;6130.306415576139;6141.7543298267565;6146.938530614815;6153.384661533811;6154.599951003171;6189.938100618936;6202.7208775606105;6250.937490625036;6268;6272.717727702356;5884.323302820059;5930.970345148246;5954.612950158225;6102.298235702951;6121.785737499166 15:38:30 INFO - PID 1653 | |1;ext.html;636.914016607758;766.70098661522;821.3757544266356;837.4682076881179;841.6254766628845;35420.82289588552;36087.278254434925;36211.456828147566;36301.9109426717;36337.636623633756;37215.81392093043;37650.24699506008;37664.81167594158;37671;37672.81163594178;7094.467914906377;7285;8914.375993680462;9209.401388909733;9234.261259099256;10208;10328.741781455448;10592.25854190207;10607.522661480252;10628.681139565826;9536.141747242747;9717.659881904136;10368.585256589728;10887.020168184861;10899.945500272488;7096.219415864251;7280.781576552712;10807.891921080767;10855.40295283759;10863.728406789814;10211.234157438223;10437.843432348522;10451.059404653617;10453.163746900229;10466.738331541696;35947.4607880882;36283.09292267688;36359.636403636156;36372.45441318356;36400.27199456022;3754.5494540655022;3762.755420897633;3788.886333410002;3832.3868181090893;3941.9947913282217;25942.610860837103;27600.757965891582;27629.861850690617;27641.756120974605;27710;2997.2956355256497;3200.487921932503;3220.0983724557163;3222.9033129006166;3224.193951512122;298419;298424;299073;299087;303565;38214;38891.80554097211;38904.80547597244;49123.75438122787;49215.015699685806;46785;47379.763101184275;47409.52590474051;47422.39176845238;49886.503404897914;13908;13926.303684815719;14130.081544699562;14157.85842141586;14165.575032749033;11531.827022594667;11564;11579.363135027486;11580.594679186357;11588.47851846652;10116.898831011742;12440.377981100908;12463.688407789878;12500.874991250153;12683.936580317039;4474.977625111854;5183.50756678115;5224.895502089937;5242.636914402283;5244.344456942882;903.9050899655441;907.7912080221586;908.68650315641;919.4253591505309;940.2102234123232;27459.31351716223;28033.299167520567;28071.298217544725;28118.015869444473;28206.435871282458;897.6229983406985;960.3037797596686;996.4917891875051;1022.192467950311;1043.1915265669015;13746.862531374758;14478.782818257734;14531.927340363442;14582.343794529272;14595.635109122357;393428;395896;398054;398109;408168;52009.7399513;52032.95934081297;52079;52120;52192;1372.1286982766017;1414.589768966988;1415.5541004583524;1421.1970236816167;1427.8286605607393;24290.878545607866;26747.578827433867;26988.325291867073;27124.72875271222;27209.31976700598;43102;43144.78427607842;43147.56852431561;43210.78394608133;43276.56723432725;364675;366288;366579;367003;367465;25319;25322.493550129264;25476.72616369211;30610.77556897699;32002.67997319997;30527.542086869158;37476.62523374731;37824.62175378211;38125.80937095297;39095.6090439092;31993.840030799696;33207.169820753705;34347.65652343544;34381;34614.82692586521;33398.332033359686;33435.498467523495;33486.497702534965;34468.48297275593;34824.65175348214;29669.851650742472;32516.83741581277;33283.167920802174;33334.66665333413;33421;21759.347219583437;27057.72942270552;28157.43685126245;28390.29024274327;29320.560191596716;20331.49171270671;22569.5486090274;22592.66111008369;22916.770832291462;22973.540529189657 15:38:30 INFO - PID 1653 | |2;jquery.html;372.8862696877452;400.83966413434604;400.98195581198826;418.1615860200298;430.9719868208566;11834.526618935231;16865.747013794804;20916;20918.16327346908;20941.16235350586;15283.694326113498;15380.846191538081;18363.714539982207;19069.713954290663;20634.690479642817;5241.449647786994;6477.125588045624;6546.18172728409;6549.443297319734;6563.212414510265;6237.313895471494;6427.614343139427;6493.448056915168;6522.380373864499;6554.836129096763;6585.736570537197;6591.340865913418;6601;6604.075429439858;6612.338766123397;6316.336784637616;6529.738810447577;6584.868302633944;6607.900881486781;6620.569662971891;6156.292026416974;6374.521910856704;6409.5578494838755;6409.711562979677;6423.164988551505;25055.12307069262;25382.36544086394;25673.486530269478;26011.60982585263;26626.33434164142;3292.934141317184;3303.900882973515;3304.8512816923294;3332.6334103248623;3387.779794313362;14882.330295136744;15437.691246175125;15498.30257638409;16234.75647865283;16252.674946501003;1719.8796084274106;1929.6237233739425;1976.6442040432717;2026.523766914774;2048.549319149785;125268;144379.27810360986;146196;147969.52030479666;149588.50411495854;16718.331266749436;19249.711254331196;20232.291869784487;20298.492537686678;20451.795482045287;33796.66203337984;33829.83085084559;33911.32177356439;33947.66052339445;33958;8736.117081363134;9119.772005699779;9142.314326425412;9153.725388238363;9715.757106072404;7421.406287496988;7447.590382528904;7468.290512401313;7469.290417410337;7470;7463.820260654051;7858.5677787722225;8415.621297041647;8517.935258092739;9937.354071985297;3996.760194388346;4120.793960301973;4130.339145736668;4132.380142978577;4153.356229784388;975.5756246032856;999.1706883286854;1007.1338648762058;1007.7984403119406;1044.9164066874632;20389.59220815605;21010;21020.054097565648;21086;21102.788972110084;973.3527204408989;976.1117383181271;1007.4207330784828;1012.2357619996856;1014;10258.692239232834;10625.574977000833;11131.888681113085;11151.38667373286;11205.103591712643;444847.77576111915;486684;502050;606147;613880;123732;148393;150402;155601;155660.2216988908;1219.9451024703733;1240.3612139748036;1288.6134159752037;1295.941682624284;1300.9869901301056;18459.984700841047;19358.903205483883;19640.705389419454;19798.50503737418;20246.79753202449;57407.42592574188;57906.710466447395;58679;59341.813163737344;59638.80722385441;79247.60376198082;81522.18477815384;82539;83526;84714.15285847062;10821.458927053458;11301.943490282825;11649.301041937511;11663.941680291544;11733.589324373606;12425.440855161358;12561.497540098293;12851.807222891852;12866.742665146832;13015.869841301466;10855;11109.444677745283;11168.66494005181;11230.989210970725;11252.549898003988;8848.690295839615;10885;11008.284461509977;11405.828912566152;11411.37237451915;11006.394648294421;11241.66275011751;11417.37204453763;11605.88394116048;11833.230839995373;11200.38397888124;11343.546258149581;11633.941830290794;11713.414329283672;11771.52913883435;8397.664093436195;10904.127669786556;11031.779364412507;11285.71785705364;11319.717007074889 15:38:30 INFO - PID 1653 | |3;mootools.html;1530.403142774319;1544.9073055616664;1573.09547010469;1574.0555666600035;1636.3291050669227;10174.491275436236;10623.15014798816;10629.096526795232;10632.681019569405;10635.893641063587;10319.226058045639;10426.322289051206;10657.307275027119;10672.786544269109;10697;2330.2193765088673;2367.396313939943;2369.526094781042;2370.5021945391404;2375.964360534593;2301.2175860207526;2342.96485552716;2346.756219203825;2353.52929414117;2355.8350915435926;2310.8151347892126;2317.374308936586;2328.313147621447;2336.042222688698;2337.1586228957563;2260.8417410781253;2284.451731584416;2291.083566573384;2291.4385975435957;2299.0458959531775;2249.30271615798;2301.827362947786;2308.988455057731;2309.5380923815137;2311.248844125649;10737.355758654428;10793.190510711727;10816;10822.296550724177;10832;5573.637713548606;5801;5815.313559067865;5826.82519524415;5833.82498525045;3380.932381352384;3452.689257966795;3682.2819550187724;3694.685951694109;3696.482492451059;1114.8606424196976;1193.7373777768878;1236.8453943257093;1237.7338872142443;1750.5448583368288;13561.796573051412;13943.581692549138;13975.301234938313;14899.329530171168;14964.850351496563;3895.3183192941124;4939.950600493949;4972.055309491185;4975.502449754996;4988.077205716954;12331.75336493265;12438.502459901683;12445.875541244654;12446.25322480654;12476.688082798002;4980.551750342486;5177.922331165036;5767.7404516796005;5814.825555233349;5838.474537291664;4732;5189.714565698847;5233.2935053768015;5235.685858848481;5239.73801309933;3914.197589494147;3945.072907866649;4065.207284579538;4093.0176757577965;4115.1975364803575;946.9337146399823;1139.7549526851767;1142.331735934471;1161.5005547614608;1163.6567212672282;802.3420794948203;814.8343684784654;816.6733306677377;837.0541288344132;841.6927821345165;6301.747930082837;6309.053641953744;6328.019157030575;6447.871042579122;6471.352864713585;992.2607657295362;1009.6617633092849;1011.5751384418562;1044.8021095052054;1074.258761454594;3647.835847386874;4018.477597912253;4250.703535421726;4260.829566817292;4300.182965236658;11043.613473528389;12711.745765084646;12857.935710321388;13140.474381024653;13210.33948302581;12336.75326493465;12767.361631918553;13013.544525941546;13059.21644701321;13096.803547946789;9485.762855928519;9534.237261019101;9760.316777825627;9824.4105353679;9846.950765246127;5341.332333458317;6107.297660769068;6474.449671777952;6509.414152726277;6636.303188165173;10009.649662261785;12117.27296362221;12148.635540933787;12213.755724885275;12224.44989975471;10894.400807955639;11272.929071738172;12183.634490965285;12198.268103913793;12217.816732748837;7595.734149304748;8001.079875814356;8893.110688931056;8895.4662720237;9061.77345566366;9836.213102951604;9994.250431217833;9998.150157236716;10005.249606279704;10008;8527.914720852712;8713.695020674246;8761.868571971298;8777.780555486164;8791.692290769792;8423.4945903246;8514.531700756517;8534.82930341402;8550.315974721883;8617.69838055665;7490.887636685345;7640.541567505968;7672.6930922764695;7675.846483070419;7687.346575540918;5547.41752116022;5606.635568688022;5675.801346952837;5748.195252664546;5809.5352371811;5689.573282003784;5803.912941305968;5817.0983497557945;5847.678377689268;5902.4097590240635 15:38:30 INFO - PID 1653 | |4;prototype.html;313.82269018004826;379.4403255198582;385.86880460643386;388.31462468742694;389.0526567807389;5823;5830.154627578999;5914.378990206025;5920;5929.673867937262;4724;5382.3541175059045;5709.743061562229;5749.872547157547;5802.738876750545;4875.293082503026;4959.975200123994;4993.825216117437;5018.397792264934;5048.6465947383695;4073.857414990461;5004.949950500503;5008.499150084999;5023.3971923369245;5037.370328708911;4507.895565586419;4770.952290477103;5000.449950505442;5004.199328107504;5050.7222102784335;4968.056069346812;4975.7512124394;4997.250412438127;4997.425296090923;5041.470645582216;4754.429468463806;4756.334113224151;4767.308740232682;4774.450938142123;4776.164171270048;4363.956360436387;4604.937716418578;4879.268109783561;5062.974685126551;5626.874625075001;5363.115086010804;5770.9422905770825;5879.647221166743;5883.676397798119;5902.32123305821;5127.769250383742;6278.959338215099;6310.842228944267;6312.526560507934;6345.777897773556;4386.34204869269;5070.518300761421;5088.847334579968;5332.600054995891;5417.997670430983;5879.265616643101;6047.129836904384;6204.348543402968;6381.617102973836;6389.744410223632;5312.2297266896085;5520;5550.27846379968;5556.777728890799;5560.304961879765;4639.466461356988;4861.343718598015;5252.684838909678;5652.236948012048;5660.830175094754;4786.617070634341;4900.534449227318;5738.167965644958;5801.82594522165;5853.414658534198;6254.3745625438005;6264.7063231569255;6267.67247839333;6284.277308109535;6291.339409362043;1766.6819972404833;1884.0862181841796;1941.4369832748628;1956.403296994402;1960.4118764370633;1321.5440672967811;1529.839366866463;1538.8153421589482;1569.0350434482712;1577.5819407856927;4958.925616115761;5173.663711858717;5660.830175094754;5894.233749612523;5894.970525147347;5827.125931110368;6245.220876709821;6391.169148010822;6418.582792118497;6432.935670643327;5283.943211357667;5606.6636001840025;5766.106253530709;5866.325372582122;5887.4406931341455;3193.7764356495286;3311.658778194835;3348.8158151301914;3350.6816852398983;3355.429576971872;6176.702892392558;6559.508036897252;6573.244076931214;6637.1703537057865;6661.635119532593;6144.477719393813;6364.586301890362;6531.8693626127215;6549.541532092803;6569.441597464173;5129.410117836347;5473.452654734495;6581.2760596334365;6593.5384523083885;6704.530682852153;5743.913841292468;6307.747690092345;6329.6518691470565;6377.298497165215;6400.903986440114;4972.776225069808;5642.492175704042;5655.406182350958;5829.621074630136;6065.696715164312;5517.613767036269;5586.664800112007;6456.580322279036;6554.934450655433;6560.179977502812;4811.917318603286;4863.95136048635;4988.852563910248;5369;5464.672119672673;6129.84675383119;6339.651319177305;6432.646204458612;6447.806565803034;6453.45145662624;5443.869774879381;6248.718807653576;6254.781082662267;6264.906026409699;6287.71705273273;4934.432540257772;5421.701806400686;5631.352394474524;5889.204957330706;6170.969145154246;5370.2213179089595;5781.5663825214115;6096.116063170727;6103.328633850362;6125.295591007091;5889.617174883619;6212.720427580679;6248.656323902229;6253.812385628438;6278.6546739929745;5981.7607295707685;6063.5452341073715;6063.848403789764;6068.969655151696;6080.939190608215 15:38:30 INFO - PID 1653 | |5;yui.html;673.2224280955498;713.8001359619309;757.9621018949053;760.1676164599761;835.9707410240643;14629.561113166608;15515.456959006437;15521.534353969371;15583.610409739762;15590.844091559082;14521.709565808678;14647.707045859102;14668.926655366708;14672.266386680676;14681.412743490248;909.0954500272222;926.6571368593629;931.9767005824875;962.9374090684119;965.8406362950104;966.096699585886;968.4431451915142;969.6218474794836;970.088117169858;971.1696499492938;1008.8335424654923;1016.4358780876611;1017.0541396501251;1017.5777052523194;1017.9440130792804;1003.7440452684555;1008.6318493749802;1009.1018993096151;1011.2718842433436;1011.6712068577668;743.4015617427968;746.559529877375;749.5877267502851;749.7450866705346;750.107372227048;15649.843501565067;15673.92163039189;15858.969167004223;16346.673066538602;16483.91758041214;13017.928884091829;13663.590092297247;13909.443622255098;14451.83800623053;14477.638059048502;11510.269754876601;11652.76694466106;11686.88313116875;12012.519499220021;12308.26150430977;3597.784132952005;3719.702423806089;3727.7204209684382;3835.654791068789;3844.75009124406;101029.494852526;104886.12590102747;105766.47116764444;105891;105963;11132.630157122136;11167.944160279309;11195.160362972809;11387.544498219979;11448.656540303804;24293.878530607588;24574.877125614257;25004.499910001698;25053.62419563708;25072.874635626704;7778;7788.026496687914;7847.607619619111;7855.921440785519;8026.518408895485;4109.022200286133;4173.9582604174175;4195.58044195578;4249.511306199826;4273.337632666941;3427.657234276602;3455.568053993251;3509.9122521937156;3517.753757236969;3519.7536172468194;622.7975907829973;626.3517259636254;626.6115008694638;642.2838535033404;644.1014784375864;662.6752891083431;667.2626747444101;681.6251061916025;688.2876223109058;688.2876223109058;9652.27607929394;9728.951355243178;9836.213102951746;10318.933471343713;10407.791844163074;631.6873147791781;640.7148818775694;663.9535232533676;664.2285262429593;667.7165980236823;4845.176320025534;4852.660313778072;4868.610511159099;4896.436909755353;4904.0682270369225;87590;90642.54678726564;91346.54326728324;92079.53960230156;94816.52591736997;77186.22813771902;78149;78435.60782196166;79187;80195;67614.66192669005;70000.64999675071;70460.94308585273;70908.64545677342;71424;9324.91066236601;9667.033296670277;10505;10783.245172837826;11186.440677966231;43566;44828.32757508705;45038;46587;48431.757841210565;61391.693041534505;62000.68999654973;62318;62669.43326416694;62796.372036279055;13495.932520337336;13555.932220338835;13615.345007723796;13637.454501819817;14225.023574370773;12284.38578071075;14425.85574144287;14487.492937747129;14546.636334091732;14624.268786560417;13802.65493362674;13808.378622961789;13864;13866.376013079638;14290.714185716435;11512.53949841997;12804.783545563156;13534.729305414034;13610.455581777014;14138.293085345897;12785.744285114431;13041.478340866259;13256.403461844047;13390.665233369244;13961.371738271599;10773.245872789143;10931.234813562975;11082.279651822606;11166.274192177807;11293.716854911956;8049.557274349967;9600.807983840425;11028.503717332578;11269.154813388865;11280.605178818701 15:38:30 INFO - PID 1653 | __end_tp_report 15:38:30 INFO - PID 1653 | __start_cc_report 15:38:30 INFO - PID 1653 | _x_x_mozilla_cycle_collect,2086 15:38:30 INFO - PID 1653 | __end_cc_report 15:38:30 INFO - PID 1653 | __startTimestamp1489703910803__endTimestamp 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | ------- Summary: start ------- 15:38:30 INFO - PID 1653 | Number of tests: 6 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | [#0] dojo.html Cycles:165 Average:5815.44 Median:6102.30 stddev:655.02 (10.7%) stddev-sans-first:623.64 15:38:30 INFO - PID 1653 | Values: 3183.3 3379.7 3379.8 3403.6 3431.0 5528.4 5626.9 5805.7 5846.4 5851.6 5730.7 5817.2 5856.5 5867.1 5870.5 4752.9 4894.7 5146.4 5230.1 5232.0 5324.4 5360.2 5368.8 5386.0 5400.9 5169.9 5231.4 5235.3 5261.4 5323.7 5285.0 5303.2 5319.7 5323.6 5406.3 5227.4 5240.6 5277.9 5285.2 5340.8 5652.8 5693.2 5808.8 5830.4 5837.2 6255.2 6257.6 6263.8 6268.4 6278.8 4714.5 6266.9 6325.9 6350.6 6351.5 5896.7 5901.9 5926.9 5963.4 5964.9 5710.2 6243.8 6447.7 6453.0 6464.1 6131.6 6185.5 6199.3 6225.5 6298.0 6323.9 6327.8 6348.9 6362.7 6363.3 6326.9 6331.4 6349.1 6354.0 6354.9 6173.3 6201.2 6260.4 6345.2 6363.8 5031.1 5059.9 5095.7 5118.7 5171.9 4515.2 4517.5 4522.4 4528.7 4565.3 5773.3 5936.2 5944.8 5949.6 5951.6 6269.2 6270.7 6291.4 6292.8 6337.1 5949.1 5991.6 6004.5 6005.9 6013.5 5554.6 5554.7 5559.4 5562.6 5568.3 6244.9 6336.8 6338.3 6425.1 6513.2 6278.4 6287.8 6309.6 6312.5 6357.6 6270.9 6315.0 6336.0 6429.7 6486.8 5155.6 6143.4 6256.6 6328.0 6328.5 5588.9 6197.9 6258.8 6323.1 6327.6 6111.1 6121.0 6122.8 6133.2 6133.9 6126.0 6210.5 6213.2 6267.4 6283.2 6130.3 6141.8 6146.9 6153.4 6154.6 6189.9 6202.7 6250.9 6268.0 6272.7 5884.3 5931.0 5954.6 6102.3 6121.8 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | [#1] ext.html Cycles:180 Average:49254.82 Median:33409.67 stddev:94347.13 (282.4%) stddev-sans-first:94541.18 15:38:30 INFO - PID 1653 | Values: 636.9 766.7 821.4 837.5 841.6 35420.8 36087.3 36211.5 36301.9 36337.6 37215.8 37650.2 37664.8 37671.0 37672.8 7094.5 7285.0 8914.4 9209.4 9234.3 10208.0 10328.7 10592.3 10607.5 10628.7 9536.1 9717.7 10368.6 10887.0 10899.9 7096.2 7280.8 10807.9 10855.4 10863.7 10211.2 10437.8 10451.1 10453.2 10466.7 35947.5 36283.1 36359.6 36372.5 36400.3 3754.5 3762.8 3788.9 3832.4 3942.0 25942.6 27600.8 27629.9 27641.8 27710.0 2997.3 3200.5 3220.1 3222.9 3224.2 298419.0 298424.0 299073.0 299087.0 303565.0 38214.0 38891.8 38904.8 49123.8 49215.0 46785.0 47379.8 47409.5 47422.4 49886.5 13908.0 13926.3 14130.1 14157.9 14165.6 11531.8 11564.0 11579.4 11580.6 11588.5 10116.9 12440.4 12463.7 12500.9 12683.9 4475.0 5183.5 5224.9 5242.6 5244.3 903.9 907.8 908.7 919.4 940.2 27459.3 28033.3 28071.3 28118.0 28206.4 897.6 960.3 996.5 1022.2 1043.2 13746.9 14478.8 14531.9 14582.3 14595.6 393428.0 395896.0 398054.0 398109.0 408168.0 52009.7 52033.0 52079.0 52120.0 52192.0 1372.1 1414.6 1415.6 1421.2 1427.8 24290.9 26747.6 26988.3 27124.7 27209.3 43102.0 43144.8 43147.6 43210.8 43276.6 364675.0 366288.0 366579.0 367003.0 367465.0 25319.0 25322.5 25476.7 30610.8 32002.7 30527.5 37476.6 37824.6 38125.8 39095.6 31993.8 33207.2 34347.7 34381.0 34614.8 33398.3 33435.5 33486.5 34468.5 34824.7 29669.9 32516.8 33283.2 33334.7 33421.0 21759.3 27057.7 28157.4 28390.3 29320.6 20331.5 22569.5 22592.7 22916.8 22973.5 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | [#2] jquery.html Cycles:180 Average:35915.89 Median:21015.03 stddev:91160.51 (433.8%) stddev-sans-first:91377.18 15:38:30 INFO - PID 1653 | Values: 372.9 400.8 401.0 418.2 431.0 11834.5 16865.7 20916.0 20918.2 20941.2 15283.7 15380.8 18363.7 19069.7 20634.7 5241.4 6477.1 6546.2 6549.4 6563.2 6237.3 6427.6 6493.4 6522.4 6554.8 6585.7 6591.3 6601.0 6604.1 6612.3 6316.3 6529.7 6584.9 6607.9 6620.6 6156.3 6374.5 6409.6 6409.7 6423.2 25055.1 25382.4 25673.5 26011.6 26626.3 3292.9 3303.9 3304.9 3332.6 3387.8 14882.3 15437.7 15498.3 16234.8 16252.7 1719.9 1929.6 1976.6 2026.5 2048.5 125268.0 144379.3 146196.0 147969.5 149588.5 16718.3 19249.7 20232.3 20298.5 20451.8 33796.7 33829.8 33911.3 33947.7 33958.0 8736.1 9119.8 9142.3 9153.7 9715.8 7421.4 7447.6 7468.3 7469.3 7470.0 7463.8 7858.6 8415.6 8517.9 9937.4 3996.8 4120.8 4130.3 4132.4 4153.4 975.6 999.2 1007.1 1007.8 1044.9 20389.6 21010.0 21020.1 21086.0 21102.8 973.4 976.1 1007.4 1012.2 1014.0 10258.7 10625.6 11131.9 11151.4 11205.1 444847.8 486684.0 502050.0 606147.0 613880.0 123732.0 148393.0 150402.0 155601.0 155660.2 1219.9 1240.4 1288.6 1295.9 1301.0 18460.0 19358.9 19640.7 19798.5 20246.8 57407.4 57906.7 58679.0 59341.8 59638.8 79247.6 81522.2 82539.0 83526.0 84714.2 10821.5 11301.9 11649.3 11663.9 11733.6 12425.4 12561.5 12851.8 12866.7 13015.9 10855.0 11109.4 11168.7 11231.0 11252.5 8848.7 10885.0 11008.3 11405.8 11411.4 11006.4 11241.7 11417.4 11605.9 11833.2 11200.4 11343.5 11633.9 11713.4 11771.5 8397.7 10904.1 11031.8 11285.7 11319.7 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | [#3] mootools.html Cycles:180 Average:6526.45 Median:3416.81 stddev:4014.43 (117.5%) stddev-sans-first:4008.14 15:38:30 INFO - PID 1653 | Values: 1530.4 1544.9 1573.1 1574.1 1636.3 10174.5 10623.2 10629.1 10632.7 10635.9 10319.2 10426.3 10657.3 10672.8 10697.0 2330.2 2367.4 2369.5 2370.5 2376.0 2301.2 2343.0 2346.8 2353.5 2355.8 2310.8 2317.4 2328.3 2336.0 2337.2 2260.8 2284.5 2291.1 2291.4 2299.0 2249.3 2301.8 2309.0 2309.5 2311.2 10737.4 10793.2 10816.0 10822.3 10832.0 5573.6 5801.0 5815.3 5826.8 5833.8 3380.9 3452.7 3682.3 3694.7 3696.5 1114.9 1193.7 1236.8 1237.7 1750.5 13561.8 13943.6 13975.3 14899.3 14964.9 3895.3 4940.0 4972.1 4975.5 4988.1 12331.8 12438.5 12445.9 12446.3 12476.7 4980.6 5177.9 5767.7 5814.8 5838.5 4732.0 5189.7 5233.3 5235.7 5239.7 3914.2 3945.1 4065.2 4093.0 4115.2 946.9 1139.8 1142.3 1161.5 1163.7 802.3 814.8 816.7 837.1 841.7 6301.7 6309.1 6328.0 6447.9 6471.4 992.3 1009.7 1011.6 1044.8 1074.3 3647.8 4018.5 4250.7 4260.8 4300.2 11043.6 12711.7 12857.9 13140.5 13210.3 12336.8 12767.4 13013.5 13059.2 13096.8 9485.8 9534.2 9760.3 9824.4 9847.0 5341.3 6107.3 6474.4 6509.4 6636.3 10009.6 12117.3 12148.6 12213.8 12224.4 10894.4 11272.9 12183.6 12198.3 12217.8 7595.7 8001.1 8893.1 8895.5 9061.8 9836.2 9994.3 9998.2 10005.2 10008.0 8527.9 8713.7 8761.9 8777.8 8791.7 8423.5 8514.5 8534.8 8550.3 8617.7 7490.9 7640.5 7672.7 7675.8 7687.3 5547.4 5606.6 5675.8 5748.2 5809.5 5689.6 5803.9 5817.1 5847.7 5902.4 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | [#4] prototype.html Cycles:180 Average:5252.14 Median:5658.12 stddev:1408.17 (24.9%) stddev-sans-first:1362.47 15:38:30 INFO - PID 1653 | Values: 313.8 379.4 385.9 388.3 389.1 5823.0 5830.2 5914.4 5920.0 5929.7 4724.0 5382.4 5709.7 5749.9 5802.7 4875.3 4960.0 4993.8 5018.4 5048.6 4073.9 5004.9 5008.5 5023.4 5037.4 4507.9 4771.0 5000.4 5004.2 5050.7 4968.1 4975.8 4997.3 4997.4 5041.5 4754.4 4756.3 4767.3 4774.5 4776.2 4364.0 4604.9 4879.3 5063.0 5626.9 5363.1 5770.9 5879.6 5883.7 5902.3 5127.8 6279.0 6310.8 6312.5 6345.8 4386.3 5070.5 5088.8 5332.6 5418.0 5879.3 6047.1 6204.3 6381.6 6389.7 5312.2 5520.0 5550.3 5556.8 5560.3 4639.5 4861.3 5252.7 5652.2 5660.8 4786.6 4900.5 5738.2 5801.8 5853.4 6254.4 6264.7 6267.7 6284.3 6291.3 1766.7 1884.1 1941.4 1956.4 1960.4 1321.5 1529.8 1538.8 1569.0 1577.6 4958.9 5173.7 5660.8 5894.2 5895.0 5827.1 6245.2 6391.2 6418.6 6432.9 5283.9 5606.7 5766.1 5866.3 5887.4 3193.8 3311.7 3348.8 3350.7 3355.4 6176.7 6559.5 6573.2 6637.2 6661.6 6144.5 6364.6 6531.9 6549.5 6569.4 5129.4 5473.5 6581.3 6593.5 6704.5 5743.9 6307.7 6329.7 6377.3 6400.9 4972.8 5642.5 5655.4 5829.6 6065.7 5517.6 5586.7 6456.6 6554.9 6560.2 4811.9 4864.0 4988.9 5369.0 5464.7 6129.8 6339.7 6432.6 6447.8 6453.5 5443.9 6248.7 6254.8 6264.9 6287.7 4934.4 5421.7 5631.4 5889.2 6171.0 5370.2 5781.6 6096.1 6103.3 6125.3 5889.6 6212.7 6248.7 6253.8 6278.7 5981.8 6063.5 6063.8 6069.0 6080.9 15:38:30 INFO - PID 1653 | 15:38:30 INFO - PID 1653 | [#5] yui.html Cycles:180 Average:19491.70 Median:3513.83 stddev:26935.98 (766.6%) stddev-sans-first:26974.48 15:38:30 INFO - PID 1653 | Values: 673.2 713.8 758.0 760.2 836.0 14629.6 15515.5 15521.5 15583.6 15590.8 14521.7 14647.7 14668.9 14672.3 14681.4 909.1 926.7 932.0 962.9 965.8 966.1 968.4 969.6 970.1 971.2 1008.8 1016.4 1017.1 1017.6 1017.9 1003.7 1008.6 1009.1 1011.3 1011.7 743.4 746.6 749.6 749.7 750.1 15649.8 15673.9 15859.0 16346.7 16483.9 13017.9 13663.6 13909.4 14451.8 14477.6 11510.3 11652.8 11686.9 12012.5 12308.3 3597.8 3719.7 3727.7 3835.7 3844.8 101029.5 104886.1 105766.5 105891.0 105963.0 11132.6 11167.9 11195.2 11387.5 11448.7 24293.9 24574.9 25004.5 25053.6 25072.9 7778.0 7788.0 7847.6 7855.9 8026.5 4109.0 4174.0 4195.6 4249.5 4273.3 3427.7 3455.6 3509.9 3517.8 3519.8 622.8 626.4 626.6 642.3 644.1 662.7 667.3 681.6 688.3 688.3 9652.3 9729.0 9836.2 10318.9 10407.8 631.7 640.7 664.0 664.2 667.7 4845.2 4852.7 4868.6 4896.4 4904.1 87590.0 90642.5 91346.5 92079.5 94816.5 77186.2 78149.0 78435.6 79187.0 80195.0 67614.7 70000.6 70460.9 70908.6 71424.0 9324.9 9667.0 10505.0 10783.2 11186.4 43566.0 44828.3 45038.0 46587.0 48431.8 61391.7 62000.7 62318.0 62669.4 62796.4 13495.9 13555.9 13615.3 13637.5 14225.0 12284.4 14425.9 14487.5 14546.6 14624.3 13802.7 13808.4 13864.0 13866.4 14290.7 11512.5 12804.8 13534.7 13610.5 14138.3 12785.7 13041.5 13256.4 13390.7 13961.4 10773.2 10931.2 11082.3 11166.3 11293.7 8049.6 9600.8 11028.5 11269.2 11280.6 15:38:30 INFO - PID 1653 | -------- Summary: end -------- 15:38:30 INFO - PID 1653 | 15:38:31 INFO - TEST-INFO | 1653: exit 0 15:38:31 INFO - TEST-OK | dromaeo_css | took 1121762ms 15:38:31 INFO - TEST-START | kraken 15:38:31 INFO - operating with platform_type : mac_ 15:38:31 INFO - Initialising browser for kraken test... 15:38:31 INFO - TEST-INFO | started process 1774 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpv0VAU8/profile http://localhost:49240/getInfo.html) 15:38:34 INFO - PID 1774 | __metrics Screen width/height:1600/1200 15:38:34 INFO - PID 1774 | colorDepth:24 15:38:34 INFO - PID 1774 | Browser inner width/height: 1024/689 15:38:34 INFO - PID 1774 | __metrics 15:38:34 INFO - PID 1774 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:38:34 INFO - PID 1774 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:38:35 INFO - TEST-INFO | 1774: exit 0 15:38:35 INFO - Browser initialized. 15:38:35 INFO - Running cycle 1/1 for kraken test... 15:38:35 INFO - TEST-INFO | started process 1776 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpv0VAU8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:47 INFO - PID 1776 | RSS: Main: 233730048 15:38:47 INFO - PID 1776 | 15:38:49 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 15:38:50 INFO - PID 1776 | RSS: Main: 284393472 15:38:50 INFO - PID 1776 | 15:38:53 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 15:38:54 INFO - PID 1776 | RSS: Main: 285724672 15:38:54 INFO - PID 1776 | 15:38:57 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 15:38:58 INFO - PID 1776 | RSS: Main: 377425920 15:38:58 INFO - PID 1776 | 15:39:00 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 15:39:00 INFO - PID 1776 | RSS: Main: 281538560 15:39:00 INFO - PID 1776 | 15:39:02 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:39:03 INFO - PID 1776 | RSS: Main: 283521024 15:39:03 INFO - PID 1776 | 15:39:06 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:06 INFO - PID 1776 | RSS: Main: 326086656 15:39:06 INFO - PID 1776 | 15:39:10 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:10 INFO - PID 1776 | RSS: Main: 332623872 15:39:10 INFO - PID 1776 | 15:39:13 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 15:39:14 INFO - PID 1776 | RSS: Main: 332476416 15:39:14 INFO - PID 1776 | 15:39:15 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:16 INFO - PID 1776 | RSS: Main: 303947776 15:39:16 INFO - PID 1776 | 15:39:18 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:39:18 INFO - PID 1776 | RSS: Main: 308117504 15:39:18 INFO - PID 1776 | 15:39:21 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:39:21 INFO - PID 1776 | RSS: Main: 309374976 15:39:21 INFO - PID 1776 | 15:39:24 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:39:25 INFO - PID 1776 | RSS: Main: 316715008 15:39:25 INFO - PID 1776 | 15:39:28 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:39:29 INFO - PID 1776 | RSS: Main: 316280832 15:39:29 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 15:39:31 INFO - PID 1776 | RSS: Main: 378679296 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | __start_tp_report 15:39:31 INFO - PID 1776 | _x_x_mozilla_page_load 15:39:31 INFO - PID 1776 | _x_x_mozilla_page_load_details 15:39:31 INFO - PID 1776 | |i|pagename|runs| 15:39:31 INFO - PID 1776 | |0;ai-astar;116;116;114;115;115;117;115;116;115;116 15:39:31 INFO - PID 1776 | |1;audio-beat-detection;143;110;109;157;111;113;111;112;134;112 15:39:31 INFO - PID 1776 | |2;audio-dft;185;185;187;184;184;183;186;226;255;183 15:39:31 INFO - PID 1776 | |3;audio-fft;81;60;60;87;59;64;57;60;76;59 15:39:31 INFO - PID 1776 | |4;audio-oscillator;90;71;87;69;70;71;84;70;69;84 15:39:31 INFO - PID 1776 | |5;imaging-gaussian-blur;90;89;89;89;90;89;90;90;90;90 15:39:31 INFO - PID 1776 | |6;imaging-darkroom;133;96;96;113;150;100;97;96;96;95 15:39:31 INFO - PID 1776 | |7;imaging-desaturate;86;83;86;86;83;83;86;84;85;86 15:39:31 INFO - PID 1776 | |8;json-parse-financial;64;63;62;62;62;63;62;63;62;63 15:39:31 INFO - PID 1776 | |9;json-stringify-tinderbox;57;73;64;64;61;58;59;60;59;59 15:39:31 INFO - PID 1776 | |10;stanford-crypto-aes;83;83;84;84;83;83;83;81;84;82 15:39:31 INFO - PID 1776 | |11;stanford-crypto-ccm;142;145;139;136;134;138;139;140;229;134 15:39:31 INFO - PID 1776 | |12;stanford-crypto-pbkdf2;175;244;188;186;185;186;182;177;160;220 15:39:31 INFO - PID 1776 | |13;stanford-crypto-sha256-iterative;69;63;90;94;93;94;68;68;66;67 15:39:31 INFO - PID 1776 | __end_tp_report 15:39:31 INFO - PID 1776 | __start_cc_report 15:39:31 INFO - PID 1776 | _x_x_mozilla_cycle_collect,2137 15:39:31 INFO - PID 1776 | __end_cc_report 15:39:31 INFO - PID 1776 | __startTimestamp1489703971315__endTimestamp 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | ------- Summary: start ------- 15:39:31 INFO - PID 1776 | Number of tests: 14 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#0] ai-astar Cycles:10 Average:115.50 Median:116.00 stddev:0.85 (0.7%) stddev-sans-first:0.88 15:39:31 INFO - PID 1776 | Values: 116.0 116.0 114.0 115.0 115.0 117.0 115.0 116.0 115.0 116.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:112.50 stddev:17.13 (15.2%) stddev-sans-first:16.25 15:39:31 INFO - PID 1776 | Values: 143.0 110.0 109.0 157.0 111.0 113.0 111.0 112.0 134.0 112.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#2] audio-dft Cycles:10 Average:195.80 Median:185.50 stddev:24.56 (13.2%) stddev-sans-first:25.74 15:39:31 INFO - PID 1776 | Values: 185.0 185.0 187.0 184.0 184.0 183.0 186.0 226.0 255.0 183.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#3] audio-fft Cycles:10 Average:66.30 Median:62.00 stddev:10.83 (17.5%) stddev-sans-first:10.10 15:39:31 INFO - PID 1776 | Values: 81.0 60.0 60.0 87.0 59.0 64.0 57.0 60.0 76.0 59.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:77.50 stddev:8.58 (11.1%) stddev-sans-first:7.58 15:39:31 INFO - PID 1776 | Values: 90.0 71.0 87.0 69.0 70.0 71.0 84.0 70.0 69.0 84.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:90.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 15:39:31 INFO - PID 1776 | Values: 90.0 89.0 89.0 89.0 90.0 89.0 90.0 90.0 90.0 90.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#6] imaging-darkroom Cycles:10 Average:107.20 Median:96.00 stddev:19.26 (20.1%) stddev-sans-first:18.02 15:39:31 INFO - PID 1776 | Values: 133.0 96.0 96.0 113.0 150.0 100.0 97.0 96.0 96.0 95.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:86.00 stddev:1.40 (1.6%) stddev-sans-first:1.41 15:39:31 INFO - PID 1776 | Values: 86.0 83.0 86.0 86.0 83.0 83.0 86.0 84.0 85.0 86.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 15:39:31 INFO - PID 1776 | Values: 64.0 63.0 62.0 62.0 62.0 63.0 62.0 63.0 62.0 63.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:60.50 stddev:4.70 (7.8%) stddev-sans-first:4.70 15:39:31 INFO - PID 1776 | Values: 57.0 73.0 64.0 64.0 61.0 58.0 59.0 60.0 59.0 59.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:0.94 (1.1%) stddev-sans-first:1.00 15:39:31 INFO - PID 1776 | Values: 83.0 83.0 84.0 84.0 83.0 83.0 83.0 81.0 84.0 82.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:139.50 stddev:28.80 (20.6%) stddev-sans-first:30.48 15:39:31 INFO - PID 1776 | Values: 142.0 145.0 139.0 136.0 134.0 138.0 139.0 140.0 229.0 134.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.30 Median:186.00 stddev:24.12 (13.0%) stddev-sans-first:24.93 15:39:31 INFO - PID 1776 | Values: 175.0 244.0 188.0 186.0 185.0 186.0 182.0 177.0 160.0 220.0 15:39:31 INFO - PID 1776 | 15:39:31 INFO - PID 1776 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.20 Median:79.50 stddev:13.52 (17.0%) stddev-sans-first:14.01 15:39:31 INFO - PID 1776 | Values: 69.0 63.0 90.0 94.0 93.0 94.0 68.0 68.0 66.0 67.0 15:39:31 INFO - PID 1776 | -------- Summary: end -------- 15:39:31 INFO - PID 1776 | 15:39:32 INFO - TEST-INFO | 1776: exit 0 15:39:32 INFO - TEST-OK | kraken | took 60871ms 15:39:32 INFO - SUITE-END | took 1182s 15:39:32 INFO - Completed test suite (00:19:42) 15:39:32 INFO - javascript benchmark 15:39:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7692.51663617956, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3183.299674071704, 3379.678930501603, 3379.831008449577, 3403.5745531808525, 3431.0, 5528.44715528448, 5626.887462250753, 5805.651660900344, 5846.444587764161, 5851.561132915032, 5730.68481233532, 5817.1565123057135, 5856.502197313233, 5867.061270196769, 5870.471657550819, 4752.881177970544, 4894.730789806559, 5146.433892271847, 5230.110881150214, 5231.973840130813, 5324.387695415038, 5360.222767698681, 5368.8389348319615, 5385.973070134643, 5400.945990540084, 5169.922451163236, 5231.372235331746, 5235.267062611199, 5261.394939581959, 5323.680579165262, 5285.0, 5303.15149576066, 5319.680819150824, 5323.627346085776, 5406.297181366436, 5227.3988491323225, 5240.633155679104, 5277.86805329866, 5285.154375299933, 5340.839774806762, 5652.830415087513, 5693.174489699012, 5808.767649294024, 5830.4169583041785, 5837.153612726173, 6255.249370075575, 6257.624542527463, 6263.812085637438, 6268.373162683695, 6278.843028924268, 4714.504976977437, 6266.874662506724, 6325.924592819232, 6350.587211831263, 6351.460125889305, 5896.705164741746, 5901.881962360814, 5926.940730592682, 5963.4334738199805, 5964.910526342065, 5710.172025056344, 6243.7814676486105, 6447.677616119269, 6453.0, 6464.127342808754, 6131.602448649625, 6185.505159587222, 6199.256089269226, 6225.501959843202, 6297.968510157511, 6323.936760632427, 6327.841803954938, 6348.936510634927, 6362.6500542469685, 6363.268224154189, 6326.936730632727, 6331.398517140864, 6349.111124442582, 6354.046892965999, 6354.872902541923, 6173.259208894961, 6201.1938448002375, 6260.40526150015, 6345.175127233522, 6363.809085727436, 5031.144705400091, 5059.949400506021, 5095.719735414514, 5118.667286626357, 5171.896562068813, 4515.209838278288, 4517.5482451755215, 4522.389477420572, 4528.660350473728, 4565.338025986213, 5773.278340207474, 5936.1986131871645, 5944.762209511571, 5949.6430214187285, 5951.613145145551, 6269.216347956505, 6270.7491700331475, 6291.433770960635, 6292.811215663537, 6337.112804207414, 5949.10763385487, 5991.58058935879, 6004.54965877561, 6005.879882402328, 6013.548983826143, 5554.555635549146, 5554.694491802989, 5559.388467268597, 5562.638428502134, 5568.303962004749, 6244.875102498016, 6336.809895703136, 6338.271098823694, 6425.132607097982, 6513.218413790375, 6278.4035516625845, 6287.842803929939, 6309.621422714652, 6312.495000399865, 6357.618542887441, 6270.937290627217, 6315.021171718391, 6336.049592561153, 6429.678516074084, 6486.805395838132, 5155.587552995677, 6143.416375444325, 6256.624602523863, 6327.987521996459, 6328.525360598066, 5588.944110559005, 6197.938020619917, 6258.812235632939, 6323.051542268697, 6327.620342779448, 6111.07520520229, 6121.0262130506535, 6122.764110201706, 6133.1510485532735, 6133.8855759873895, 6125.968084364015, 6210.472109870711, 6213.1922850029205, 6267.435930766253, 6283.2460104787715, 6130.306415576139, 6141.7543298267565, 6146.938530614815, 6153.384661533811, 6154.599951003171, 6189.938100618936, 6202.7208775606105, 6250.937490625036, 6268.0, 6272.717727702356, 5884.323302820059, 5930.970345148246, 5954.612950158225, 6102.298235702951, 6121.785737499166], "value": 5773.881703580807, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.914016607758, 766.70098661522, 821.3757544266356, 837.4682076881179, 841.6254766628845, 35420.82289588552, 36087.278254434925, 36211.456828147566, 36301.9109426717, 36337.636623633756, 37215.81392093043, 37650.24699506008, 37664.81167594158, 37671.0, 37672.81163594178, 7094.467914906377, 7285.0, 8914.375993680462, 9209.401388909733, 9234.261259099256, 10208.0, 10328.741781455448, 10592.25854190207, 10607.522661480252, 10628.681139565826, 9536.141747242747, 9717.659881904136, 10368.585256589728, 10887.020168184861, 10899.945500272488, 7096.219415864251, 7280.781576552712, 10807.891921080767, 10855.40295283759, 10863.728406789814, 10211.234157438223, 10437.843432348522, 10451.059404653617, 10453.163746900229, 10466.738331541696, 35947.4607880882, 36283.09292267688, 36359.636403636156, 36372.45441318356, 36400.27199456022, 3754.5494540655022, 3762.755420897633, 3788.886333410002, 3832.3868181090893, 3941.9947913282217, 25942.610860837103, 27600.757965891582, 27629.861850690617, 27641.756120974605, 27710.0, 2997.2956355256497, 3200.487921932503, 3220.0983724557163, 3222.9033129006166, 3224.193951512122, 298419.0, 298424.0, 299073.0, 299087.0, 303565.0, 38214.0, 38891.80554097211, 38904.80547597244, 49123.75438122787, 49215.015699685806, 46785.0, 47379.763101184275, 47409.52590474051, 47422.39176845238, 49886.503404897914, 13908.0, 13926.303684815719, 14130.081544699562, 14157.85842141586, 14165.575032749033, 11531.827022594667, 11564.0, 11579.363135027486, 11580.594679186357, 11588.47851846652, 10116.898831011742, 12440.377981100908, 12463.688407789878, 12500.874991250153, 12683.936580317039, 4474.977625111854, 5183.50756678115, 5224.895502089937, 5242.636914402283, 5244.344456942882, 903.9050899655441, 907.7912080221586, 908.68650315641, 919.4253591505309, 940.2102234123232, 27459.31351716223, 28033.299167520567, 28071.298217544725, 28118.015869444473, 28206.435871282458, 897.6229983406985, 960.3037797596686, 996.4917891875051, 1022.192467950311, 1043.1915265669015, 13746.862531374758, 14478.782818257734, 14531.927340363442, 14582.343794529272, 14595.635109122357, 393428.0, 395896.0, 398054.0, 398109.0, 408168.0, 52009.7399513, 52032.95934081297, 52079.0, 52120.0, 52192.0, 1372.1286982766017, 1414.589768966988, 1415.5541004583524, 1421.1970236816167, 1427.8286605607393, 24290.878545607866, 26747.578827433867, 26988.325291867073, 27124.72875271222, 27209.31976700598, 43102.0, 43144.78427607842, 43147.56852431561, 43210.78394608133, 43276.56723432725, 364675.0, 366288.0, 366579.0, 367003.0, 367465.0, 25319.0, 25322.493550129264, 25476.72616369211, 30610.77556897699, 32002.67997319997, 30527.542086869158, 37476.62523374731, 37824.62175378211, 38125.80937095297, 39095.6090439092, 31993.840030799696, 33207.169820753705, 34347.65652343544, 34381.0, 34614.82692586521, 33398.332033359686, 33435.498467523495, 33486.497702534965, 34468.48297275593, 34824.65175348214, 29669.851650742472, 32516.83741581277, 33283.167920802174, 33334.66665333413, 33421.0, 21759.347219583437, 27057.72942270552, 28157.43685126245, 28390.29024274327, 29320.560191596716, 20331.49171270671, 22569.5486090274, 22592.66111008369, 22916.770832291462, 22973.540529189657], "value": 17597.835571698546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.8862696877452, 400.83966413434604, 400.98195581198826, 418.1615860200298, 430.9719868208566, 11834.526618935231, 16865.747013794804, 20916.0, 20918.16327346908, 20941.16235350586, 15283.694326113498, 15380.846191538081, 18363.714539982207, 19069.713954290663, 20634.690479642817, 5241.449647786994, 6477.125588045624, 6546.18172728409, 6549.443297319734, 6563.212414510265, 6237.313895471494, 6427.614343139427, 6493.448056915168, 6522.380373864499, 6554.836129096763, 6585.736570537197, 6591.340865913418, 6601.0, 6604.075429439858, 6612.338766123397, 6316.336784637616, 6529.738810447577, 6584.868302633944, 6607.900881486781, 6620.569662971891, 6156.292026416974, 6374.521910856704, 6409.5578494838755, 6409.711562979677, 6423.164988551505, 25055.12307069262, 25382.36544086394, 25673.486530269478, 26011.60982585263, 26626.33434164142, 3292.934141317184, 3303.900882973515, 3304.8512816923294, 3332.6334103248623, 3387.779794313362, 14882.330295136744, 15437.691246175125, 15498.30257638409, 16234.75647865283, 16252.674946501003, 1719.8796084274106, 1929.6237233739425, 1976.6442040432717, 2026.523766914774, 2048.549319149785, 125268.0, 144379.27810360986, 146196.0, 147969.52030479666, 149588.50411495854, 16718.331266749436, 19249.711254331196, 20232.291869784487, 20298.492537686678, 20451.795482045287, 33796.66203337984, 33829.83085084559, 33911.32177356439, 33947.66052339445, 33958.0, 8736.117081363134, 9119.772005699779, 9142.314326425412, 9153.725388238363, 9715.757106072404, 7421.406287496988, 7447.590382528904, 7468.290512401313, 7469.290417410337, 7470.0, 7463.820260654051, 7858.5677787722225, 8415.621297041647, 8517.935258092739, 9937.354071985297, 3996.760194388346, 4120.793960301973, 4130.339145736668, 4132.380142978577, 4153.356229784388, 975.5756246032856, 999.1706883286854, 1007.1338648762058, 1007.7984403119406, 1044.9164066874632, 20389.59220815605, 21010.0, 21020.054097565648, 21086.0, 21102.788972110084, 973.3527204408989, 976.1117383181271, 1007.4207330784828, 1012.2357619996856, 1014.0, 10258.692239232834, 10625.574977000833, 11131.888681113085, 11151.38667373286, 11205.103591712643, 444847.77576111915, 486684.0, 502050.0, 606147.0, 613880.0, 123732.0, 148393.0, 150402.0, 155601.0, 155660.2216988908, 1219.9451024703733, 1240.3612139748036, 1288.6134159752037, 1295.941682624284, 1300.9869901301056, 18459.984700841047, 19358.903205483883, 19640.705389419454, 19798.50503737418, 20246.79753202449, 57407.42592574188, 57906.710466447395, 58679.0, 59341.813163737344, 59638.80722385441, 79247.60376198082, 81522.18477815384, 82539.0, 83526.0, 84714.15285847062, 10821.458927053458, 11301.943490282825, 11649.301041937511, 11663.941680291544, 11733.589324373606, 12425.440855161358, 12561.497540098293, 12851.807222891852, 12866.742665146832, 13015.869841301466, 10855.0, 11109.444677745283, 11168.66494005181, 11230.989210970725, 11252.549898003988, 8848.690295839615, 10885.0, 11008.284461509977, 11405.828912566152, 11411.37237451915, 11006.394648294421, 11241.66275011751, 11417.37204453763, 11605.88394116048, 11833.230839995373, 11200.38397888124, 11343.546258149581, 11633.941830290794, 11713.414329283672, 11771.52913883435, 8397.664093436195, 10904.127669786556, 11031.779364412507, 11285.71785705364, 11319.717007074889], "value": 11104.858511442315, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1530.403142774319, 1544.9073055616664, 1573.09547010469, 1574.0555666600035, 1636.3291050669227, 10174.491275436236, 10623.15014798816, 10629.096526795232, 10632.681019569405, 10635.893641063587, 10319.226058045639, 10426.322289051206, 10657.307275027119, 10672.786544269109, 10697.0, 2330.2193765088673, 2367.396313939943, 2369.526094781042, 2370.5021945391404, 2375.964360534593, 2301.2175860207526, 2342.96485552716, 2346.756219203825, 2353.52929414117, 2355.8350915435926, 2310.8151347892126, 2317.374308936586, 2328.313147621447, 2336.042222688698, 2337.1586228957563, 2260.8417410781253, 2284.451731584416, 2291.083566573384, 2291.4385975435957, 2299.0458959531775, 2249.30271615798, 2301.827362947786, 2308.988455057731, 2309.5380923815137, 2311.248844125649, 10737.355758654428, 10793.190510711727, 10816.0, 10822.296550724177, 10832.0, 5573.637713548606, 5801.0, 5815.313559067865, 5826.82519524415, 5833.82498525045, 3380.932381352384, 3452.689257966795, 3682.2819550187724, 3694.685951694109, 3696.482492451059, 1114.8606424196976, 1193.7373777768878, 1236.8453943257093, 1237.7338872142443, 1750.5448583368288, 13561.796573051412, 13943.581692549138, 13975.301234938313, 14899.329530171168, 14964.850351496563, 3895.3183192941124, 4939.950600493949, 4972.055309491185, 4975.502449754996, 4988.077205716954, 12331.75336493265, 12438.502459901683, 12445.875541244654, 12446.25322480654, 12476.688082798002, 4980.551750342486, 5177.922331165036, 5767.7404516796005, 5814.825555233349, 5838.474537291664, 4732.0, 5189.714565698847, 5233.2935053768015, 5235.685858848481, 5239.73801309933, 3914.197589494147, 3945.072907866649, 4065.207284579538, 4093.0176757577965, 4115.1975364803575, 946.9337146399823, 1139.7549526851767, 1142.331735934471, 1161.5005547614608, 1163.6567212672282, 802.3420794948203, 814.8343684784654, 816.6733306677377, 837.0541288344132, 841.6927821345165, 6301.747930082837, 6309.053641953744, 6328.019157030575, 6447.871042579122, 6471.352864713585, 992.2607657295362, 1009.6617633092849, 1011.5751384418562, 1044.8021095052054, 1074.258761454594, 3647.835847386874, 4018.477597912253, 4250.703535421726, 4260.829566817292, 4300.182965236658, 11043.613473528389, 12711.745765084646, 12857.935710321388, 13140.474381024653, 13210.33948302581, 12336.75326493465, 12767.361631918553, 13013.544525941546, 13059.21644701321, 13096.803547946789, 9485.762855928519, 9534.237261019101, 9760.316777825627, 9824.4105353679, 9846.950765246127, 5341.332333458317, 6107.297660769068, 6474.449671777952, 6509.414152726277, 6636.303188165173, 10009.649662261785, 12117.27296362221, 12148.635540933787, 12213.755724885275, 12224.44989975471, 10894.400807955639, 11272.929071738172, 12183.634490965285, 12198.268103913793, 12217.816732748837, 7595.734149304748, 8001.079875814356, 8893.110688931056, 8895.4662720237, 9061.77345566366, 9836.213102951604, 9994.250431217833, 9998.150157236716, 10005.249606279704, 10008.0, 8527.914720852712, 8713.695020674246, 8761.868571971298, 8777.780555486164, 8791.692290769792, 8423.4945903246, 8514.531700756517, 8534.82930341402, 8550.315974721883, 8617.69838055665, 7490.887636685345, 7640.541567505968, 7672.6930922764695, 7675.846483070419, 7687.346575540918, 5547.41752116022, 5606.635568688022, 5675.801346952837, 5748.195252664546, 5809.5352371811, 5689.573282003784, 5803.912941305968, 5817.0983497557945, 5847.678377689268, 5902.4097590240635], "value": 5023.940968275755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.82269018004826, 379.4403255198582, 385.86880460643386, 388.31462468742694, 389.0526567807389, 5823.0, 5830.154627578999, 5914.378990206025, 5920.0, 5929.673867937262, 4724.0, 5382.3541175059045, 5709.743061562229, 5749.872547157547, 5802.738876750545, 4875.293082503026, 4959.975200123994, 4993.825216117437, 5018.397792264934, 5048.6465947383695, 4073.857414990461, 5004.949950500503, 5008.499150084999, 5023.3971923369245, 5037.370328708911, 4507.895565586419, 4770.952290477103, 5000.449950505442, 5004.199328107504, 5050.7222102784335, 4968.056069346812, 4975.7512124394, 4997.250412438127, 4997.425296090923, 5041.470645582216, 4754.429468463806, 4756.334113224151, 4767.308740232682, 4774.450938142123, 4776.164171270048, 4363.956360436387, 4604.937716418578, 4879.268109783561, 5062.974685126551, 5626.874625075001, 5363.115086010804, 5770.9422905770825, 5879.647221166743, 5883.676397798119, 5902.32123305821, 5127.769250383742, 6278.959338215099, 6310.842228944267, 6312.526560507934, 6345.777897773556, 4386.34204869269, 5070.518300761421, 5088.847334579968, 5332.600054995891, 5417.997670430983, 5879.265616643101, 6047.129836904384, 6204.348543402968, 6381.617102973836, 6389.744410223632, 5312.2297266896085, 5520.0, 5550.27846379968, 5556.777728890799, 5560.304961879765, 4639.466461356988, 4861.343718598015, 5252.684838909678, 5652.236948012048, 5660.830175094754, 4786.617070634341, 4900.534449227318, 5738.167965644958, 5801.82594522165, 5853.414658534198, 6254.3745625438005, 6264.7063231569255, 6267.67247839333, 6284.277308109535, 6291.339409362043, 1766.6819972404833, 1884.0862181841796, 1941.4369832748628, 1956.403296994402, 1960.4118764370633, 1321.5440672967811, 1529.839366866463, 1538.8153421589482, 1569.0350434482712, 1577.5819407856927, 4958.925616115761, 5173.663711858717, 5660.830175094754, 5894.233749612523, 5894.970525147347, 5827.125931110368, 6245.220876709821, 6391.169148010822, 6418.582792118497, 6432.935670643327, 5283.943211357667, 5606.6636001840025, 5766.106253530709, 5866.325372582122, 5887.4406931341455, 3193.7764356495286, 3311.658778194835, 3348.8158151301914, 3350.6816852398983, 3355.429576971872, 6176.702892392558, 6559.508036897252, 6573.244076931214, 6637.1703537057865, 6661.635119532593, 6144.477719393813, 6364.586301890362, 6531.8693626127215, 6549.541532092803, 6569.441597464173, 5129.410117836347, 5473.452654734495, 6581.2760596334365, 6593.5384523083885, 6704.530682852153, 5743.913841292468, 6307.747690092345, 6329.6518691470565, 6377.298497165215, 6400.903986440114, 4972.776225069808, 5642.492175704042, 5655.406182350958, 5829.621074630136, 6065.696715164312, 5517.613767036269, 5586.664800112007, 6456.580322279036, 6554.934450655433, 6560.179977502812, 4811.917318603286, 4863.95136048635, 4988.852563910248, 5369.0, 5464.672119672673, 6129.84675383119, 6339.651319177305, 6432.646204458612, 6447.806565803034, 6453.45145662624, 5443.869774879381, 6248.718807653576, 6254.781082662267, 6264.906026409699, 6287.71705273273, 4934.432540257772, 5421.701806400686, 5631.352394474524, 5889.204957330706, 6170.969145154246, 5370.2213179089595, 5781.5663825214115, 6096.116063170727, 6103.328633850362, 6125.295591007091, 5889.617174883619, 6212.720427580679, 6248.656323902229, 6253.812385628438, 6278.6546739929745, 5981.7607295707685, 6063.5452341073715, 6063.848403789764, 6068.969655151696, 6080.939190608215], "value": 4836.209803743436, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.2224280955498, 713.8001359619309, 757.9621018949053, 760.1676164599761, 835.9707410240643, 14629.561113166608, 15515.456959006437, 15521.534353969371, 15583.610409739762, 15590.844091559082, 14521.709565808678, 14647.707045859102, 14668.926655366708, 14672.266386680676, 14681.412743490248, 909.0954500272222, 926.6571368593629, 931.9767005824875, 962.9374090684119, 965.8406362950104, 966.096699585886, 968.4431451915142, 969.6218474794836, 970.088117169858, 971.1696499492938, 1008.8335424654923, 1016.4358780876611, 1017.0541396501251, 1017.5777052523194, 1017.9440130792804, 1003.7440452684555, 1008.6318493749802, 1009.1018993096151, 1011.2718842433436, 1011.6712068577668, 743.4015617427968, 746.559529877375, 749.5877267502851, 749.7450866705346, 750.107372227048, 15649.843501565067, 15673.92163039189, 15858.969167004223, 16346.673066538602, 16483.91758041214, 13017.928884091829, 13663.590092297247, 13909.443622255098, 14451.83800623053, 14477.638059048502, 11510.269754876601, 11652.76694466106, 11686.88313116875, 12012.519499220021, 12308.26150430977, 3597.784132952005, 3719.702423806089, 3727.7204209684382, 3835.654791068789, 3844.75009124406, 101029.494852526, 104886.12590102747, 105766.47116764444, 105891.0, 105963.0, 11132.630157122136, 11167.944160279309, 11195.160362972809, 11387.544498219979, 11448.656540303804, 24293.878530607588, 24574.877125614257, 25004.499910001698, 25053.62419563708, 25072.874635626704, 7778.0, 7788.026496687914, 7847.607619619111, 7855.921440785519, 8026.518408895485, 4109.022200286133, 4173.9582604174175, 4195.58044195578, 4249.511306199826, 4273.337632666941, 3427.657234276602, 3455.568053993251, 3509.9122521937156, 3517.753757236969, 3519.7536172468194, 622.7975907829973, 626.3517259636254, 626.6115008694638, 642.2838535033404, 644.1014784375864, 662.6752891083431, 667.2626747444101, 681.6251061916025, 688.2876223109058, 688.2876223109058, 9652.27607929394, 9728.951355243178, 9836.213102951746, 10318.933471343713, 10407.791844163074, 631.6873147791781, 640.7148818775694, 663.9535232533676, 664.2285262429593, 667.7165980236823, 4845.176320025534, 4852.660313778072, 4868.610511159099, 4896.436909755353, 4904.0682270369225, 87590.0, 90642.54678726564, 91346.54326728324, 92079.53960230156, 94816.52591736997, 77186.22813771902, 78149.0, 78435.60782196166, 79187.0, 80195.0, 67614.66192669005, 70000.64999675071, 70460.94308585273, 70908.64545677342, 71424.0, 9324.91066236601, 9667.033296670277, 10505.0, 10783.245172837826, 11186.440677966231, 43566.0, 44828.32757508705, 45038.0, 46587.0, 48431.757841210565, 61391.693041534505, 62000.68999654973, 62318.0, 62669.43326416694, 62796.372036279055, 13495.932520337336, 13555.932220338835, 13615.345007723796, 13637.454501819817, 14225.023574370773, 12284.38578071075, 14425.85574144287, 14487.492937747129, 14546.636334091732, 14624.268786560417, 13802.65493362674, 13808.378622961789, 13864.0, 13866.376013079638, 14290.714185716435, 11512.53949841997, 12804.783545563156, 13534.729305414034, 13610.455581777014, 14138.293085345897, 12785.744285114431, 13041.478340866259, 13256.403461844047, 13390.665233369244, 13961.371738271599, 10773.245872789143, 10931.234813562975, 11082.279651822606, 11166.274192177807, 11293.716854911956, 8049.557274349967, 9600.807983840425, 11028.503717332578, 11269.154813388865, 11280.605178818701], "value": 7557.651681802171, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1479.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 114.0, 115.0, 115.0, 117.0, 115.0, 116.0, 115.0, 116.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 109.0, 157.0, 111.0, 113.0, 111.0, 112.0, 134.0, 112.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 187.0, 184.0, 184.0, 183.0, 186.0, 226.0, 255.0, 183.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 87.0, 59.0, 64.0, 57.0, 60.0, 76.0, 59.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 87.0, 69.0, 70.0, 71.0, 84.0, 70.0, 69.0, 84.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 89.0, 90.0, 89.0, 90.0, 90.0, 90.0, 90.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 96.0, 96.0, 113.0, 150.0, 100.0, 97.0, 96.0, 96.0, 95.0], "value": 107.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 86.0, 83.0, 83.0, 86.0, 84.0, 85.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 62.0, 62.0, 63.0, 62.0, 63.0, 62.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 73.0, 64.0, 64.0, 61.0, 58.0, 59.0, 60.0, 59.0, 59.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 84.0, 83.0, 83.0, 83.0, 81.0, 84.0, 82.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 139.0, 136.0, 134.0, 138.0, 139.0, 140.0, 229.0, 134.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 244.0, 188.0, 186.0, 185.0, 186.0, 182.0, 177.0, 160.0, 220.0], "value": 190.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 63.0, 90.0, 94.0, 93.0, 94.0, 68.0, 68.0, 66.0, 67.0], "value": 77.2, "unit": "score"}]}]} 15:39:32 INFO - Return code: 0 15:39:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:39:32 ERROR - # TBPL SUCCESS # 15:39:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:39:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:39:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:39:32 INFO - 2017-03-16 15:39:32.619 screenresolution[1785:9920] starting screenresolution argv=screenresolution get 15:39:32 INFO - 2017-03-16 15:39:32.621 screenresolution[1785:9920] Display 0: 1600x1200x32@60 15:39:32 INFO - 2017-03-16 15:39:32.627 screenresolution[1786:9923] starting screenresolution argv=screenresolution list 15:39:32 INFO - Available Modes on Display 0 15:39:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:39:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:39:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:39:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:39:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:39:32 INFO - Intel Iris: 15:39:32 INFO - Chipset Model: Intel Iris 15:39:32 INFO - Type: GPU 15:39:32 INFO - Bus: Built-In 15:39:32 INFO - VRAM (Dynamic, Max): 1536 MB 15:39:32 INFO - Vendor: Intel (0x8086) 15:39:32 INFO - Device ID: 0x0a2e 15:39:32 INFO - Revision ID: 0x0009 15:39:32 INFO - Displays: 15:39:32 INFO - Display: 15:39:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:39:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:39:32 INFO - Display Serial Number: 1600x1200 60 15:39:32 INFO - Main Display: Yes 15:39:32 INFO - Mirror: Off 15:39:32 INFO - Online: Yes 15:39:32 INFO - Rotation: Supported 15:39:32 INFO - Return code: 0 15:39:32 INFO - Running post-action listener: _resource_record_post_action 15:39:32 INFO - [mozharness: 2017-03-16 22:39:32.872960Z] Finished run-tests step (success) 15:39:32 INFO - Running post-run listener: _resource_record_post_run 15:39:32 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 38204416; Write bytes: 517126144; Read time: 246; Write time: 1609 15:39:32 INFO - TinderboxPrint: CPU usage
53.3% 15:39:32 INFO - TinderboxPrint: I/O read bytes / time
38,204,416 / 246 15:39:32 INFO - TinderboxPrint: I/O write bytes / time
517,126,144 / 1,609 15:39:32 INFO - TinderboxPrint: CPU idle
2,250.5 (46.7%) 15:39:32 INFO - TinderboxPrint: CPU system
957.8 (19.9%) 15:39:32 INFO - TinderboxPrint: CPU user
1,609.2 (33.4%) 15:39:32 INFO - TinderboxPrint: Swap in / out
247,504,896 / 0 15:39:32 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201171456; Write bytes: 196154880; Read time: 15585; Write time: 409 15:39:32 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 37209088; Write bytes: 303264256; Read time: 231; Write time: 1146 15:39:33 INFO - Running post-run listener: _upload_blobber_files 15:39:33 INFO - Blob upload gear active. 15:39:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:39:33 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:39:33 INFO - (blobuploader) - INFO - Open directory for files ... 15:39:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:39:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:35 INFO - (blobuploader) - INFO - Done attempting. 15:39:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:39:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:39:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:36 INFO - (blobuploader) - INFO - Done attempting. 15:39:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:39:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:39:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:37 INFO - (blobuploader) - INFO - Done attempting. 15:39:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:39:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:39:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:39 INFO - (blobuploader) - INFO - Done attempting. 15:39:39 INFO - (blobuploader) - INFO - Iteration through files over. 15:39:39 INFO - Return code: 0 15:39:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:39:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:39:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75b37c46c05e5dbf637950ce6ba05ed597bdcd31fb131596bdd8063c680cffdf825081a152abb25dbf76c3a39c15c01838eaa3a5f5dd895466608d306b2aed57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fffa5e9f4b4cdca7c3aaba2afffce68bfee02b56a366b1ca0ab5a582e2d6b085708c149c596507ee905940570eb2f6be5dd5a3fe188dd9deafb4883a9f7a6fd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e793fb5af2d98e664fc103220a8cb2301eee02963f414d8ae3c86f16b190aee0c9c6054c6d9f3b53f88517f1bfd0e59ad25fbaed04e4023063b1beaea595c49f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9183cfe2171b252bab12553bce9670eed33ee19eae1f9aa6d060cd5e01cb410c31edca2bde19fabe2d42427cf5b053dfd6d83818c24bbc172fdcc4a86c290d"} 15:39:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:39:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:39:39 INFO - Contents: 15:39:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75b37c46c05e5dbf637950ce6ba05ed597bdcd31fb131596bdd8063c680cffdf825081a152abb25dbf76c3a39c15c01838eaa3a5f5dd895466608d306b2aed57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fffa5e9f4b4cdca7c3aaba2afffce68bfee02b56a366b1ca0ab5a582e2d6b085708c149c596507ee905940570eb2f6be5dd5a3fe188dd9deafb4883a9f7a6fd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e793fb5af2d98e664fc103220a8cb2301eee02963f414d8ae3c86f16b190aee0c9c6054c6d9f3b53f88517f1bfd0e59ad25fbaed04e4023063b1beaea595c49f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9183cfe2171b252bab12553bce9670eed33ee19eae1f9aa6d060cd5e01cb410c31edca2bde19fabe2d42427cf5b053dfd6d83818c24bbc172fdcc4a86c290d"} 15:39:39 INFO - Running post-run listener: copy_logs_to_upload_dir 15:39:39 INFO - Copying logs to upload dir... 15:39:39 INFO - mkdir: /builds/slave/test/build/upload/logs 15:39:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.748827 ========= master_lag: -0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-16 15:39:39.284097) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 15:39:39.287880) ========= 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.hLlROprWsZ/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.kC6L23gKVO/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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/75b37c46c05e5dbf637950ce6ba05ed597bdcd31fb131596bdd8063c680cffdf825081a152abb25dbf76c3a39c15c01838eaa3a5f5dd895466608d306b2aed57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fffa5e9f4b4cdca7c3aaba2afffce68bfee02b56a366b1ca0ab5a582e2d6b085708c149c596507ee905940570eb2f6be5dd5a3fe188dd9deafb4883a9f7a6fd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e793fb5af2d98e664fc103220a8cb2301eee02963f414d8ae3c86f16b190aee0c9c6054c6d9f3b53f88517f1bfd0e59ad25fbaed04e4023063b1beaea595c49f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9183cfe2171b252bab12553bce9670eed33ee19eae1f9aa6d060cd5e01cb410c31edca2bde19fabe2d42427cf5b053dfd6d83818c24bbc172fdcc4a86c290d"} build_url:https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011132 build_url: 'https://queue.taskcluster.net/v1/task/Zm-o439BR5GkBETKmaltwg/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/75b37c46c05e5dbf637950ce6ba05ed597bdcd31fb131596bdd8063c680cffdf825081a152abb25dbf76c3a39c15c01838eaa3a5f5dd895466608d306b2aed57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fffa5e9f4b4cdca7c3aaba2afffce68bfee02b56a366b1ca0ab5a582e2d6b085708c149c596507ee905940570eb2f6be5dd5a3fe188dd9deafb4883a9f7a6fd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e793fb5af2d98e664fc103220a8cb2301eee02963f414d8ae3c86f16b190aee0c9c6054c6d9f3b53f88517f1bfd0e59ad25fbaed04e4023063b1beaea595c49f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9183cfe2171b252bab12553bce9670eed33ee19eae1f9aa6d060cd5e01cb410c31edca2bde19fabe2d42427cf5b053dfd6d83818c24bbc172fdcc4a86c290d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 15:39:39.322830) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 15:39:39.323496) ========= 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.hLlROprWsZ/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.kC6L23gKVO/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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.005241 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 15:39:39.376562) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 15:39:39.377059) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 15:39:39.377452) ========= ========= Total master_lag: 0.02 =========