builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0336 starttime: 1492685019.81 results: success (0) buildid: 20170420021428 builduid: 87fb75dabc6245bd97edcff5f648503f revision: 27311156637f9b5d4504373967e01c4241902ae7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:39.815014) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:39.815457) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:39.815738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:39.841933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:39.842320) ========= 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.op8LGRd1nl/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.dpXC4JWuck/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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-04-20 03:43:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.3M=0.001s 2017-04-20 03:43:40 (19.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.345291 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:40.211680) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:40.212000) ========= 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.op8LGRd1nl/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.dpXC4JWuck/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.027290 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:40.261603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-20 03:43:40.261980) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 27311156637f9b5d4504373967e01c4241902ae7 --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 27311156637f9b5d4504373967e01c4241902ae7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.op8LGRd1nl/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.dpXC4JWuck/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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-04-20 03:43:40,372 truncating revision to first 12 chars 2017-04-20 03:43:40,372 Setting DEBUG logging. 2017-04-20 03:43:40,372 attempt 1/10 2017-04-20 03:43:40,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/27311156637f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-20 03:43:42,546 unpacking tar archive at: mozilla-central-27311156637f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.457308 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-20 03:43:42.744918) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:42.745497) ========= script_repo_revision: 27311156637f9b5d4504373967e01c4241902ae7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:42.746135) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:42.746575) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 03:43:42.766506) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-20 03:43:42.766866) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.op8LGRd1nl/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.dpXC4JWuck/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:43:42 INFO - MultiFileLogger online at 20170420 03:43:42 in /builds/slave/test 03:43:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:43:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:43:42 INFO - {'append_to_log': False, 03:43:42 INFO - 'base_work_dir': '/builds/slave/test', 03:43:42 INFO - 'blob_upload_branch': 'Firefox', 03:43:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:43:42 INFO - 'branch': 'Firefox', 03:43:42 INFO - 'buildbot_json_path': 'buildprops.json', 03:43:42 INFO - 'config_files': ('talos/mac_config.py',), 03:43:42 INFO - 'default_actions': ('clobber', 03:43:42 INFO - 'read-buildbot-config', 03:43:42 INFO - 'download-and-extract', 03:43:42 INFO - 'populate-webroot', 03:43:42 INFO - 'create-virtualenv', 03:43:42 INFO - 'install', 03:43:42 INFO - 'run-tests'), 03:43:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:43:42 INFO - 'download_minidump_stackwalk': True, 03:43:42 INFO - 'download_symbols': 'ondemand', 03:43:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:43:42 INFO - 'tooltool.py': '/tools/tooltool.py', 03:43:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:43:42 INFO - '/tools/misc-python/virtualenv.py')}, 03:43:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:43:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:43:42 INFO - 'gecko_profile': False, 03:43:42 INFO - 'gecko_profile_interval': 0, 03:43:42 INFO - 'installer_path': 'installer.exe', 03:43:42 INFO - 'log_level': 'info', 03:43:42 INFO - 'log_name': 'talos', 03:43:42 INFO - 'log_to_console': True, 03:43:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:43:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:43:42 INFO - 'opt_config_files': (), 03:43:42 INFO - 'pip_index': False, 03:43:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:43:42 INFO - 'cmd': ('bash', 03:43:42 INFO - '-c', 03:43:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:43:42 INFO - 'enabled': True, 03:43:42 INFO - 'halt_on_failure': False, 03:43:42 INFO - 'name': 'check_screen_resolution'},), 03:43:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:43:42 INFO - 'cmd': ('bash', 03:43:42 INFO - '-c', 03:43:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:43:42 INFO - 'enabled': True, 03:43:42 INFO - 'halt_on_failure': False, 03:43:42 INFO - 'name': 'check_screen_resolution'},), 03:43:42 INFO - 'run_cmd_checks_enabled': True, 03:43:42 INFO - 'suite': 'dromaeojs-e10s', 03:43:42 INFO - 'system_bits': '32', 03:43:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:43:42 INFO - 'title': 't-yosemite-r7-0336', 03:43:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:43:42 INFO - 'use_talos_json': True, 03:43:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:43:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:43:42 INFO - 'work_dir': 'build'} 03:43:42 INFO - [mozharness: 2017-04-20 10:43:42.985564Z] Running clobber step. 03:43:42 INFO - Running pre-action listener: _resource_record_pre_action 03:43:42 INFO - Running main action method: clobber 03:43:42 INFO - rmtree: /builds/slave/test/build 03:43:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:43:45 INFO - Running post-action listener: _resource_record_post_action 03:43:45 INFO - [mozharness: 2017-04-20 10:43:45.476318Z] Finished clobber step (success) 03:43:45 INFO - [mozharness: 2017-04-20 10:43:45.476499Z] Running read-buildbot-config step. 03:43:45 INFO - Running pre-action listener: _resource_record_pre_action 03:43:45 INFO - Running main action method: read_buildbot_config 03:43:45 INFO - Using buildbot properties: 03:43:45 INFO - { 03:43:45 INFO - "project": "", 03:43:45 INFO - "product": "firefox", 03:43:45 INFO - "script_repo_revision": "production", 03:43:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:43:45 INFO - "repository": "", 03:43:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:43:45 INFO - "buildid": "20170420021428", 03:43:45 INFO - "pgo_build": "False", 03:43:45 INFO - "basedir": "/builds/slave/test", 03:43:45 INFO - "buildnumber": 194, 03:43:45 INFO - "slavename": "t-yosemite-r7-0336", 03:43:45 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:43:45 INFO - "platform": "yosemite_r7", 03:43:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:43:45 INFO - "branch": "mozilla-central", 03:43:45 INFO - "repo_path": "mozilla-central", 03:43:45 INFO - "revision": "27311156637f9b5d4504373967e01c4241902ae7", 03:43:45 INFO - "stage_platform": "macosx64", 03:43:45 INFO - "builduid": "87fb75dabc6245bd97edcff5f648503f", 03:43:45 INFO - "slavebuilddir": "test" 03:43:45 INFO - } 03:43:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 03:43:45 INFO - Running post-action listener: _resource_record_post_action 03:43:45 INFO - [mozharness: 2017-04-20 10:43:45.480642Z] Finished read-buildbot-config step (success) 03:43:45 INFO - [mozharness: 2017-04-20 10:43:45.480838Z] Running download-and-extract step. 03:43:45 INFO - Running pre-action listener: _resource_record_pre_action 03:43:45 INFO - Running main action method: download_and_extract 03:43:45 INFO - mkdir: /builds/slave/test/build/tests 03:43:45 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')]} 03:43:45 INFO - trying https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:43:45 INFO - Downloading https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/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 03:43:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/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 03:43:46 INFO - Downloaded 1325 bytes. 03:43:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:43:46 INFO - Using the following test package requirements: 03:43:46 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:43:46 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:43:46 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:43:46 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:43:46 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'jsshell-mac.zip'], 03:43:46 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:43:46 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:43:46 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:43:46 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:43:46 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:43:46 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:46 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:43:46 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:43:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:43:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:43:46 INFO - Fetch https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:43:48 INFO - Expected file size: 52521305 03:43:48 INFO - Obtained file size: 52521305 03:44:04 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 03:44:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:44:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:44:04 INFO - Fetch https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:44:06 INFO - Expected file size: 52521305 03:44:06 INFO - Obtained file size: 52521305 03:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:44:22 INFO - Expected file size: 14408811 03:44:22 INFO - Obtained file size: 14408811 03:44:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:23 INFO - trying https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:44:23 INFO - Downloading https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:44:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/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 03:44:25 INFO - Downloaded 70990879 bytes. 03:44:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:44:25 INFO - mkdir: /builds/slave/test/properties 03:44:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:44:25 INFO - Writing to file /builds/slave/test/properties/build_url 03:44:25 INFO - Contents: 03:44:25 INFO - build_url:https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:44:25 INFO - Running post-action listener: _resource_record_post_action 03:44:25 INFO - Running post-action listener: set_extra_try_arguments 03:44:25 INFO - [mozharness: 2017-04-20 10:44:25.758841Z] Finished download-and-extract step (success) 03:44:25 INFO - [mozharness: 2017-04-20 10:44:25.758992Z] Running populate-webroot step. 03:44:25 INFO - Running pre-action listener: _resource_record_pre_action 03:44:25 INFO - Running main action method: populate_webroot 03:44:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:44:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:44:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:44:25 INFO - '--tppagecycles', 03:44:25 INFO - '7'], 03:44:25 INFO - 'tests': ['tsvgm']}}, 03:44:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:44:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:44:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:44:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:44:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:44:25 INFO - 'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:44:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:44:25 INFO - 'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['damp', 'tps']}, 03:44:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:44:25 INFO - 'tests': ['damp', 'tps']}, 03:44:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['dromaeo_dom']}, 03:44:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:44:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:44:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:44:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['a11yr', 03:44:25 INFO - 'ts_paint', 03:44:25 INFO - 'tpaint', 03:44:25 INFO - 'sessionrestore', 03:44:25 INFO - 'sessionrestore_no_auto_restore', 03:44:25 INFO - 'tabpaint']}, 03:44:25 INFO - 'other-e10s': {'tests': ['a11yr', 03:44:25 INFO - 'ts_paint', 03:44:25 INFO - 'tpaint', 03:44:25 INFO - 'sessionrestore', 03:44:25 INFO - 'sessionrestore_no_auto_restore', 03:44:25 INFO - 'tabpaint']}, 03:44:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:44:25 INFO - 'ts_paint', 03:44:25 INFO - 'tpaint', 03:44:25 INFO - 'sessionrestore', 03:44:25 INFO - 'sessionrestore_no_auto_restore', 03:44:25 INFO - 'tabpaint']}, 03:44:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:44:25 INFO - 'ts_paint', 03:44:25 INFO - 'tpaint', 03:44:25 INFO - 'sessionrestore', 03:44:25 INFO - 'sessionrestore_no_auto_restore', 03:44:25 INFO - 'tabpaint']}, 03:44:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['a11yr', 03:44:25 INFO - 'ts_paint', 03:44:25 INFO - 'tpaint', 03:44:25 INFO - 'sessionrestore', 03:44:25 INFO - 'sessionrestore_no_auto_restore', 03:44:25 INFO - 'tabpaint']}, 03:44:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['a11yr', 03:44:25 INFO - 'ts_paint', 03:44:25 INFO - 'tpaint', 03:44:25 INFO - 'sessionrestore', 03:44:25 INFO - 'sessionrestore_no_auto_restore', 03:44:25 INFO - 'tabpaint']}, 03:44:25 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:44:25 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:44:25 INFO - 'bloom_basic_ref']}, 03:44:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['tsvgx', 03:44:25 INFO - 'tsvgr_opacity', 03:44:25 INFO - 'tart', 03:44:25 INFO - 'tscrollx', 03:44:25 INFO - 'cart', 03:44:25 INFO - 'tsvg_static']}, 03:44:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:44:25 INFO - 'tsvgr_opacity', 03:44:25 INFO - 'tart', 03:44:25 INFO - 'tscrollx', 03:44:25 INFO - 'cart', 03:44:25 INFO - 'tsvg_static']}, 03:44:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:44:25 INFO - 'talos_options': ['--disable-e10s'], 03:44:25 INFO - 'tests': ['tp5o']}, 03:44:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:44:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:44:25 INFO - 'talos_options': ['--disable-e10s', 03:44:25 INFO - '--xperf_path', 03:44:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:25 INFO - 'tests': ['tp5n']}, 03:44:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:44:25 INFO - 'talos_options': ['--xperf_path', 03:44:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:25 INFO - 'tests': ['tp5n']}}, 03:44:25 INFO - 'talos.zip': {'path': '', 03:44:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:44:25 INFO - Running post-action listener: _resource_record_post_action 03:44:25 INFO - [mozharness: 2017-04-20 10:44:25.778215Z] Finished populate-webroot step (success) 03:44:25 INFO - [mozharness: 2017-04-20 10:44:25.778341Z] Running create-virtualenv step. 03:44:25 INFO - Running pre-action listener: _resource_record_pre_action 03:44:25 INFO - Running main action method: create_virtualenv 03:44:25 INFO - Creating virtualenv /builds/slave/test/build/venv 03:44:25 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 03:44:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:44:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:44:25 INFO - Using real prefix '/tools/python27' 03:44:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:44:26 INFO - Installing distribute.............................................................................................................................................................................................done. 03:44:29 INFO - Installing pip.................done. 03:44:29 INFO - Return code: 0 03:44:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:44:29 INFO - Reading from file tmpfile_stdout 03:44:29 INFO - Output received: 03:44:29 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) 03:44:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:44:29 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')]} 03:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:29 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')]} 03:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:29 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:29 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 03:44:29 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 03:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:29 INFO - 'HOME': '/Users/cltbld', 03:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:29 INFO - 'LOGNAME': 'cltbld', 03:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:29 INFO - 'MOZ_NO_REMOTE': '1', 03:44:29 INFO - 'NO_EM_RESTART': '1', 03:44:29 INFO - 'PAGER': '/bin/cat', 03:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:29 INFO - 'PWD': '/builds/slave/test', 03:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:29 INFO - 'SHELL': '/bin/bash', 03:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:29 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:29 INFO - 'USER': 'cltbld', 03:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:29 INFO - 'XPC_FLAGS': '0x0', 03:44:29 INFO - 'XPC_SERVICE_NAME': '0', 03:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:30 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 03:44:30 INFO - Cleaning up... 03:44:30 INFO - Return code: 0 03:44:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:30 INFO - 'HOME': '/Users/cltbld', 03:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:30 INFO - 'LOGNAME': 'cltbld', 03:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:30 INFO - 'MOZ_NO_REMOTE': '1', 03:44:30 INFO - 'NO_EM_RESTART': '1', 03:44:30 INFO - 'PAGER': '/bin/cat', 03:44:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:30 INFO - 'PWD': '/builds/slave/test', 03:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:30 INFO - 'SHELL': '/bin/bash', 03:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:30 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:30 INFO - 'USER': 'cltbld', 03:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:30 INFO - 'XPC_FLAGS': '0x0', 03:44:30 INFO - 'XPC_SERVICE_NAME': '0', 03:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:30 INFO - Downloading/unpacking psutil>=3.1.1 03:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:44:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:33 INFO - Installing collected packages: psutil 03:44:33 INFO - Running setup.py install for psutil 03:44:34 INFO - building 'psutil._psutil_osx' extension 03:44:34 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 03:44:34 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 03:44:34 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 03:44:34 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 03:44:34 INFO - building 'psutil._psutil_posix' extension 03:44:34 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 03:44:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:44:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:44:34 INFO - ^ 03:44:34 INFO - 1 warning generated. 03:44:34 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 03:44:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:35 INFO - Successfully installed psutil 03:44:35 INFO - Cleaning up... 03:44:35 INFO - Return code: 0 03:44:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:35 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:35 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 03:44:35 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 03:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:35 INFO - 'HOME': '/Users/cltbld', 03:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:35 INFO - 'LOGNAME': 'cltbld', 03:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:35 INFO - 'MOZ_NO_REMOTE': '1', 03:44:35 INFO - 'NO_EM_RESTART': '1', 03:44:35 INFO - 'PAGER': '/bin/cat', 03:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:35 INFO - 'PWD': '/builds/slave/test', 03:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:35 INFO - 'SHELL': '/bin/bash', 03:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:35 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:35 INFO - 'USER': 'cltbld', 03:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:35 INFO - 'XPC_FLAGS': '0x0', 03:44:35 INFO - 'XPC_SERVICE_NAME': '0', 03:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:44:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:44:39 INFO - Installing collected packages: mozsystemmonitor 03:44:39 INFO - Running setup.py install for mozsystemmonitor 03:44:39 INFO - Successfully installed mozsystemmonitor 03:44:39 INFO - Cleaning up... 03:44:39 INFO - Return code: 0 03:44:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:44:39 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')]} 03:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:39 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')]} 03:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:40 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:40 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 03:44:40 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 03:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:40 INFO - 'HOME': '/Users/cltbld', 03:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:40 INFO - 'LOGNAME': 'cltbld', 03:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:40 INFO - 'MOZ_NO_REMOTE': '1', 03:44:40 INFO - 'NO_EM_RESTART': '1', 03:44:40 INFO - 'PAGER': '/bin/cat', 03:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:40 INFO - 'PWD': '/builds/slave/test', 03:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:40 INFO - 'SHELL': '/bin/bash', 03:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:40 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:40 INFO - 'USER': 'cltbld', 03:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:40 INFO - 'XPC_FLAGS': '0x0', 03:44:40 INFO - 'XPC_SERVICE_NAME': '0', 03:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:40 INFO - Downloading/unpacking jsonschema==2.5.1 03:44:40 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 03:44:40 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 03:44:40 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 03:44:40 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 03:44:40 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 03:44:40 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 03:44:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:44:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:44:43 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 03:44:43 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 03:44:43 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 03:44:43 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 03:44:43 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 03:44:43 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 03:44:43 INFO - Downloading functools32-3.2.3-2.tar.gz 03:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:44:43 INFO - warning: no files found matching '*.txt' 03:44:43 INFO - no previously-included directories found matching 'build' 03:44:43 INFO - no previously-included directories found matching 'dist' 03:44:43 INFO - no previously-included directories found matching '.git*' 03:44:43 INFO - Installing collected packages: jsonschema, functools32 03:44:43 INFO - Running setup.py install for functools32 03:44:44 INFO - warning: no files found matching '*.txt' 03:44:44 INFO - no previously-included directories found matching 'build' 03:44:44 INFO - no previously-included directories found matching 'dist' 03:44:44 INFO - no previously-included directories found matching '.git*' 03:44:44 INFO - Successfully installed jsonschema functools32 03:44:44 INFO - Cleaning up... 03:44:44 INFO - Return code: 0 03:44:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:44:44 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')]} 03:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:44 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')]} 03:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:44 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:44 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 03:44:44 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 03:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:44 INFO - 'HOME': '/Users/cltbld', 03:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:44 INFO - 'LOGNAME': 'cltbld', 03:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:44 INFO - 'MOZ_NO_REMOTE': '1', 03:44:44 INFO - 'NO_EM_RESTART': '1', 03:44:44 INFO - 'PAGER': '/bin/cat', 03:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:44 INFO - 'PWD': '/builds/slave/test', 03:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:44 INFO - 'SHELL': '/bin/bash', 03:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:44 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:44 INFO - 'USER': 'cltbld', 03:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:44 INFO - 'XPC_FLAGS': '0x0', 03:44:44 INFO - 'XPC_SERVICE_NAME': '0', 03:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:44:44 INFO - Cleaning up... 03:44:44 INFO - Return code: 0 03:44:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:44:44 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')]} 03:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:44 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')]} 03:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:44 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:44 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 03:44:44 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 03:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:44 INFO - 'HOME': '/Users/cltbld', 03:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:44 INFO - 'LOGNAME': 'cltbld', 03:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:44 INFO - 'MOZ_NO_REMOTE': '1', 03:44:44 INFO - 'NO_EM_RESTART': '1', 03:44:44 INFO - 'PAGER': '/bin/cat', 03:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:44 INFO - 'PWD': '/builds/slave/test', 03:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:44 INFO - 'SHELL': '/bin/bash', 03:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:44 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:44 INFO - 'USER': 'cltbld', 03:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:44 INFO - 'XPC_FLAGS': '0x0', 03:44:44 INFO - 'XPC_SERVICE_NAME': '0', 03:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:44 INFO - Downloading/unpacking blobuploader==1.2.4 03:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:47 INFO - Downloading blobuploader-1.2.4.tar.gz 03:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:44:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:44:47 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 03:44:47 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 03:44:47 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 03:44:47 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 03:44:47 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 03:44:47 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 03:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:44:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:44:48 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 03:44:48 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 03:44:48 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 03:44:48 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 03:44:48 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 03:44:48 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 03:44:48 INFO - Downloading docopt-0.6.1.tar.gz 03:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:44:48 INFO - Installing collected packages: blobuploader, requests, docopt 03:44:48 INFO - Running setup.py install for blobuploader 03:44:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:44:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:44:48 INFO - Running setup.py install for requests 03:44:49 INFO - Running setup.py install for docopt 03:44:49 INFO - Successfully installed blobuploader requests docopt 03:44:49 INFO - Cleaning up... 03:44:49 INFO - Return code: 0 03:44:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44: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')]} 03:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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')]} 03:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:49 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:49 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 03:44:49 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 03:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:49 INFO - 'HOME': '/Users/cltbld', 03:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:49 INFO - 'LOGNAME': 'cltbld', 03:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:49 INFO - 'MOZ_NO_REMOTE': '1', 03:44:49 INFO - 'NO_EM_RESTART': '1', 03:44:49 INFO - 'PAGER': '/bin/cat', 03:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:49 INFO - 'PWD': '/builds/slave/test', 03:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:49 INFO - 'SHELL': '/bin/bash', 03:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:49 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:49 INFO - 'USER': 'cltbld', 03:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:49 INFO - 'XPC_FLAGS': '0x0', 03:44:49 INFO - 'XPC_SERVICE_NAME': '0', 03:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:49 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-xGwOCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-dbT4Yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-MJAKFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-WhnAQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-sX5h8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-I86M1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-mV0eut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-a0tcBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-TKaQhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-3jbYwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:50 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-avLioX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-VzYcgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-k9r32O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-1kDkvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-VRWIIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-_ISLbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-8_R6HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:44:51 INFO - Running setup.py install for manifestparser 03:44:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:44:51 INFO - Running setup.py install for mozcrash 03:44:51 INFO - Running setup.py install for mozdebug 03:44:52 INFO - Running setup.py install for mozdevice 03:44:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:44:52 INFO - Running setup.py install for mozfile 03:44:52 INFO - Running setup.py install for mozhttpd 03:44:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:44:52 INFO - Running setup.py install for mozinfo 03:44:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:44:52 INFO - Running setup.py install for mozInstall 03:44:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:44:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:44:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:44:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:44:52 INFO - Running setup.py install for mozleak 03:44:52 INFO - Running setup.py install for mozlog 03:44:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:44:53 INFO - Running setup.py install for moznetwork 03:44:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:44:53 INFO - Running setup.py install for mozprocess 03:44:53 INFO - Running setup.py install for mozprofile 03:44:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:44:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:44:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:44:53 INFO - Running setup.py install for mozrunner 03:44:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:44:53 INFO - Running setup.py install for mozscreenshot 03:44:53 INFO - Running setup.py install for moztest 03:44:54 INFO - Running setup.py install for mozversion 03:44:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:44:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:44:54 INFO - Cleaning up... 03:44:54 INFO - Return code: 0 03:44:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:54 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')]} 03:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:54 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')]} 03:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:54 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:54 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 03:44:54 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 03:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:54 INFO - 'HOME': '/Users/cltbld', 03:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:54 INFO - 'LOGNAME': 'cltbld', 03:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:54 INFO - 'MOZ_NO_REMOTE': '1', 03:44:54 INFO - 'NO_EM_RESTART': '1', 03:44:54 INFO - 'PAGER': '/bin/cat', 03:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:54 INFO - 'PWD': '/builds/slave/test', 03:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:54 INFO - 'SHELL': '/bin/bash', 03:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:54 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:54 INFO - 'USER': 'cltbld', 03:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:54 INFO - 'XPC_FLAGS': '0x0', 03:44:54 INFO - 'XPC_SERVICE_NAME': '0', 03:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:54 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-yD_xQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:54 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)) 03:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:54 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-L3W6JD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:54 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)) 03:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:54 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-dMrLvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:54 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)) 03:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:54 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-iGJI4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:54 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-b3Vnb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-kFmLiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-9M68q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-4NR0JO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Pa0Icc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-lk0GZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-IINV2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-v9XxoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-ZTkugi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:55 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)) 03:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:55 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-d5GM9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:56 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)) 03:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-6Q7Kz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:56 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)) 03:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-LUTudH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:56 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)) 03:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-0WJoID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:56 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)) 03:44:56 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)) 03:44:56 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)) 03:44:56 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)) 03:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:44:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:59 INFO - Downloading blessings-1.6.tar.gz 03:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:44:59 INFO - Installing collected packages: blessings 03:44:59 INFO - Running setup.py install for blessings 03:44:59 INFO - Successfully installed blessings 03:44:59 INFO - Cleaning up... 03:44:59 INFO - Return code: 0 03:44:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:59 INFO - Reading from file tmpfile_stdout 03:44:59 INFO - Current package versions: 03:44:59 INFO - blessings == 1.6 03:44:59 INFO - blobuploader == 1.2.4 03:44:59 INFO - docopt == 0.6.1 03:44:59 INFO - functools32 == 3.2.3-2 03:44:59 INFO - jsonschema == 2.5.1 03:44:59 INFO - manifestparser == 1.1 03:44:59 INFO - mozInstall == 1.12 03:44:59 INFO - mozcrash == 1.0 03:44:59 INFO - mozdebug == 0.1 03:44:59 INFO - mozdevice == 0.50 03:44:59 INFO - mozfile == 1.2 03:44:59 INFO - mozhttpd == 0.7 03:44:59 INFO - mozinfo == 0.9 03:44:59 INFO - mozleak == 0.1 03:44:59 INFO - mozlog == 3.4 03:44:59 INFO - moznetwork == 0.27 03:44:59 INFO - mozprocess == 0.25 03:44:59 INFO - mozprofile == 0.28 03:44:59 INFO - mozrunner == 6.13 03:44:59 INFO - mozscreenshot == 0.1 03:44:59 INFO - mozsystemmonitor == 0.3 03:44:59 INFO - moztest == 0.8 03:44:59 INFO - mozversion == 1.4 03:44:59 INFO - psutil == 3.1.1 03:44:59 INFO - requests == 1.2.3 03:44:59 INFO - wsgiref == 0.1.2 03:44:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44: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')]} 03:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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')]} 03:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:59 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 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44:59 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 03:44:59 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 03:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:59 INFO - 'HOME': '/Users/cltbld', 03:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:59 INFO - 'LOGNAME': 'cltbld', 03:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:59 INFO - 'MOZ_NO_REMOTE': '1', 03:44:59 INFO - 'NO_EM_RESTART': '1', 03:44:59 INFO - 'PAGER': '/bin/cat', 03:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:59 INFO - 'PWD': '/builds/slave/test', 03:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:59 INFO - 'SHELL': '/bin/bash', 03:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:59 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:59 INFO - 'USER': 'cltbld', 03:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:59 INFO - 'XPC_FLAGS': '0x0', 03:44:59 INFO - 'XPC_SERVICE_NAME': '0', 03:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 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)) 03:44:59 INFO - Cleaning up... 03:44:59 INFO - Return code: 0 03:44:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:44: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')]} 03:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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')]} 03:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd38552bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, '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.op8LGRd1nl/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.dpXC4JWuck/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 03:44: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', 'jsonschema'] in /builds/slave/test/build 03:44: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 jsonschema 03:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:59 INFO - 'HOME': '/Users/cltbld', 03:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:59 INFO - 'LOGNAME': 'cltbld', 03:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:59 INFO - 'MOZ_NO_REMOTE': '1', 03:44:59 INFO - 'NO_EM_RESTART': '1', 03:44:59 INFO - 'PAGER': '/bin/cat', 03:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:59 INFO - 'PWD': '/builds/slave/test', 03:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:59 INFO - 'SHELL': '/bin/bash', 03:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:44:59 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:59 INFO - 'USER': 'cltbld', 03:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:59 INFO - 'XPC_FLAGS': '0x0', 03:44:59 INFO - 'XPC_SERVICE_NAME': '0', 03:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:45:00 INFO - Cleaning up... 03:45:00 INFO - Return code: 0 03:45:00 INFO - Running post-action listener: _resource_record_post_action 03:45:00 INFO - Running post-action listener: _start_resource_monitoring 03:45:00 INFO - Starting resource monitoring. 03:45:00 INFO - [mozharness: 2017-04-20 10:45:00.244164Z] Finished create-virtualenv step (success) 03:45:00 INFO - [mozharness: 2017-04-20 10:45:00.245144Z] Running install step. 03:45:00 INFO - Running pre-action listener: _resource_record_pre_action 03:45:00 INFO - Running main action method: install 03:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:45:00 INFO - Reading from file tmpfile_stdout 03:45:00 INFO - Detecting whether we're running mozinstall >=1.0... 03:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:45:00 INFO - Reading from file tmpfile_stdout 03:45:00 INFO - Output received: 03:45:00 INFO - Usage: mozinstall [options] installer 03:45:00 INFO - Options: 03:45:00 INFO - -h, --help show this help message and exit 03:45:00 INFO - -d DEST, --destination=DEST 03:45:00 INFO - Directory to install application into. [default: 03:45:00 INFO - "/builds/slave/test"] 03:45:00 INFO - --app=APP Application being installed. [default: firefox] 03:45:00 INFO - mkdir: /builds/slave/test/build/application 03:45:00 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'] 03:45:00 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 03:45:20 INFO - Reading from file tmpfile_stdout 03:45:20 INFO - Output received: 03:45:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:45:20 INFO - Running post-action listener: _resource_record_post_action 03:45:20 INFO - [mozharness: 2017-04-20 10:45:20.402202Z] Finished install step (success) 03:45:20 INFO - [mozharness: 2017-04-20 10:45:20.402339Z] Running run-tests step. 03:45:20 INFO - Running pre-action listener: _resource_record_pre_action 03:45:20 INFO - Running main action method: run_tests 03:45:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:45:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:45:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:45:20 INFO - 2017-04-20 03:45:20.416 screenresolution[1623:7492] starting screenresolution argv=screenresolution get 03:45:20 INFO - 2017-04-20 03:45:20.419 screenresolution[1623:7492] Display 0: 1600x1200x32@60 03:45:20 INFO - 2017-04-20 03:45:20.425 screenresolution[1624:7495] starting screenresolution argv=screenresolution list 03:45:20 INFO - Available Modes on Display 0 03:45:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:45:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:45:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:45:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:45:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:45:20 INFO - Intel Iris: 03:45:20 INFO - Chipset Model: Intel Iris 03:45:20 INFO - Type: GPU 03:45:20 INFO - Bus: Built-In 03:45:20 INFO - VRAM (Dynamic, Max): 1536 MB 03:45:20 INFO - Vendor: Intel (0x8086) 03:45:20 INFO - Device ID: 0x0a2e 03:45:20 INFO - Revision ID: 0x0009 03:45:20 INFO - Displays: 03:45:20 INFO - Display: 03:45:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:45:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:45:20 INFO - Display Serial Number: 1600x1200 60 03:45:20 INFO - Main Display: Yes 03:45:20 INFO - Mirror: Off 03:45:20 INFO - Online: Yes 03:45:20 INFO - Rotation: Supported 03:45:20 INFO - Return code: 0 03:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:45:20 INFO - Python 2.7.3 03:45:20 INFO - Return code: 0 03:45:20 INFO - grabbing minidump binary from tooltool 03:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:20 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 0x10aa93d30>, '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 0x10a9b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa88be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:45:20 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 03:45:20 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 03:45:20 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 03:45:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:45:20 INFO - Return code: 0 03:45:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:45:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:45:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:45:20 INFO - ENV: RUST_BACKTRACE is now 1 03:45:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:45:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0336 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.op8LGRd1nl/Render', 03:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:20 INFO - 'HOME': '/Users/cltbld', 03:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:20 INFO - 'LOGNAME': 'cltbld', 03:45:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:45:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:20 INFO - 'MOZ_NO_REMOTE': '1', 03:45:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:45:20 INFO - 'NO_EM_RESTART': '1', 03:45:20 INFO - 'PAGER': '/bin/cat', 03:45:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:20 INFO - 'PWD': '/builds/slave/test', 03:45:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:20 INFO - 'RUST_BACKTRACE': '1', 03:45:20 INFO - 'SHELL': '/bin/bash', 03:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dpXC4JWuck/Listeners', 03:45:20 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 03:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:20 INFO - 'USER': 'cltbld', 03:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:20 INFO - 'XPC_FLAGS': '0x0', 03:45:20 INFO - 'XPC_SERVICE_NAME': '0', 03:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:45:21 INFO - mozversion application_buildid: 20170420021428 03:45:21 INFO - mozversion application_changeset: 27311156637f9b5d4504373967e01c4241902ae7 03:45:21 INFO - mozversion application_display_name: Nightly 03:45:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:45:21 INFO - mozversion application_name: Firefox 03:45:21 INFO - mozversion application_remotingname: firefox 03:45:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:45:21 INFO - mozversion application_vendor: Mozilla 03:45:21 INFO - mozversion application_version: 55.0a1 03:45:21 INFO - mozversion platform_buildid: 20170420021428 03:45:21 INFO - mozversion platform_changeset: 27311156637f9b5d4504373967e01c4241902ae7 03:45:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:45:21 INFO - mozversion platform_version: 55.0a1 03:45:21 INFO - using testdate: 1492685121 03:45:21 INFO - actual date: 1492685121 03:45:21 INFO - starting webserver on 'localhost:49246' 03:45:21 INFO - SUITE-START | Running 2 tests 03:45:21 INFO - TEST-START | dromaeo_css 03:45:21 INFO - operating with platform_type : mac_ 03:45:21 INFO - Initialising browser for dromaeo_css test... 03:45:21 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpj05tWQ/profile http://localhost:49246/getInfo.html) 03:45:24 INFO - PID 1634 | 2017-04-20 03:45:24.081 plugin-container[1636:7678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4957, name = 'com.apple.tsm.portname' 03:45:24 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:24 INFO - PID 1634 | 2017-04-20 03:45:24.085 plugin-container[1636:7678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 03:45:24 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:24 INFO - PID 1634 | 2017-04-20 03:45:24.086 plugin-container[1636:7678] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:45:24 INFO - PID 1634 | __metrics Screen width/height:1600/1200 03:45:24 INFO - PID 1634 | colorDepth:24 03:45:24 INFO - PID 1634 | Browser inner width/height: 1024/689 03:45:24 INFO - PID 1634 | __metrics 03:45:24 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3363: TypeError: this.tabs is undefined 03:45:25 INFO - TEST-INFO | 1634: exit 0 03:45:25 INFO - Browser initialized. 03:45:25 INFO - Running cycle 1/1 for dromaeo_css test... 03:45:25 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpj05tWQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:27 INFO - PID 1637 | 2017-04-20 03:45:27.751 plugin-container[1638:7807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 03:45:27 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:27 INFO - PID 1637 | 2017-04-20 03:45:27.752 plugin-container[1638:7807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 03:45:27 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:27 INFO - PID 1637 | 2017-04-20 03:45:27.752 plugin-container[1638:7807] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:45:37 INFO - PID 1637 | RSS: Main: 235077632 03:45:37 INFO - PID 1637 | 03:48:40 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 03:48:42 INFO - PID 1637 | RSS: Main: 251543552 03:48:42 INFO - PID 1637 | 03:50:25 INFO - PID 1637 | ************************* 03:50:25 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 03:50:25 INFO - PID 1637 | 03:50:25 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:50:25 INFO - PID 1637 | Full stack: 03:50:25 INFO - PID 1637 | ************************* 03:50:25 INFO - PID 1637 | ************************* 03:50:25 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 03:50:25 INFO - PID 1637 | 03:50:25 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:50:25 INFO - PID 1637 | Full stack: 03:50:25 INFO - PID 1637 | ************************* 03:50:25 INFO - PID 1637 | ************************* 03:50:25 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 03:50:25 INFO - PID 1637 | 03:50:25 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:50:25 INFO - PID 1637 | Full stack: 03:50:25 INFO - PID 1637 | ************************* 03:50:25 INFO - PID 1637 | ************************* 03:50:25 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 03:50:25 INFO - PID 1637 | 03:50:25 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:50:25 INFO - PID 1637 | Full stack: 03:50:25 INFO - PID 1637 | ************************* 03:51:44 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 03:51:44 INFO - PID 1637 | RSS: Main: 256647168 03:51:44 INFO - PID 1637 | 03:54:46 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 03:54:47 INFO - PID 1637 | RSS: Main: 265781248 03:54:47 INFO - PID 1637 | 03:57:48 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 03:57:49 INFO - PID 1637 | RSS: Main: 258711552 03:57:49 INFO - PID 1637 | 04:00:52 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 04:00:52 INFO - PID 1637 | RSS: Main: 267489280 04:00:52 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 04:03:54 INFO - PID 1637 | RSS: Main: 263090176 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | __start_tp_report 04:03:54 INFO - PID 1637 | _x_x_mozilla_page_load 04:03:54 INFO - PID 1637 | _x_x_mozilla_page_load_details 04:03:54 INFO - PID 1637 | |i|pagename|runs| 04:03:54 INFO - PID 1637 | |0;dojo.html;2736.876840542176;2970.885917780832;3182.140821978066;3531.823408829558;3598.334308152992;4727.479977202509;6015.338312785601;6179.3202747697715;6184.7216875240665;6243.875122497548;6090.87818243636;6104.095385180735;6164.81505554833;6182.474489668372;6244.438000579947;4826.34844296019;4940.184869496528;5272.051030814452;5354.955783622196;5384.111621582434;5336.466353364652;5369.087255166612;5411.134218525037;5425.104857698495;5558.722063896789;4318.611324980751;5273.419923808378;5354.705491197982;5373.301470808769;5425.077736784756;5282.471752824726;5289.285946397225;5311.973440132813;5371.328583927009;5379.784808607651;5236.1098613235845;5244.895102097975;5253.369595648546;5282.683039017671;5370.033393989081;5814.389489103625;5970.492508136814;6019.879602407971;6152.323244443108;6189.845253868644;6398.328175541547;6471.417572418505;6520.934790652128;6522.478201743849;6549.967250163766;6623.172103487064;6639.900401493981;6643.269240383554;6656.301088385747;6656.900146497806;6053.848653783691;6241.313455519889;6248.187735594389;6257.812265632038;6278.717457714414;6500.349965003462;6532.771353002717;6568.737250510022;6667.899981500282;6734.427573656294;6270.59241149333;6331.936680633226;6363.077353783768;6393.6483493408305;6414.775482858077;6268.059791031291;6497.577657452251;6503.154589903283;6585.604863708193;6600.669966501656;6482.935170648328;6496.480281577557;6509.707063182168;6510.479161667055;6519.511036672174;6259.968700156561;6289.55973081889;6415.935840641534;6482;6550.181227346582;5195.220716892496;5239.711815850165;5247.2653828464045;5268.1834315681135;5286.127788914863;3841.980790096032;3966.5438474575794;4264.424302719093;4639.884002899955;4643.466001409881;6050.122732203806;6114.327423983358;6152.446279834837;6214.9689251553455;6260.749570017239;5849.970750146222;6010.398960103955;6557.114789503451;6612.735490580323;6736.2590115087305;6246.3441338659695;6266.185395898504;6286.528510361742;6286.748530058838;6288.308286088527;5698.572607054488;5780.797672081457;5783.161441590946;5805.82582522525;5822.767089316465;6507.251666058464;6610.3720146586;6728.697208625722;6769.356911093439;6825.180978282638;6598.571092878948;6600.900986485302;6662.267150613332;6665.966670166618;6672.399484046459;6718.899216511659;6790.151231096113;6812.557183783038;6846.726130954905;6850.520463567503;5268.736563171903;6349;6390.968045159744;6471.676416179266;6479.481641468766;6484.5460817742305;6523.543351965317;6613.710326486289;6618.635975021228;6655.567388119756;6254.9687251563455;6344.397282258178;6405.647689377129;6428.100065990671;6437.742490300336;6277.654728989949;6357.205349331333;6412.647304398303;6414.839629009312;6427.421532062137;6159.322474527888;6198.1012753149635;6254.687265636791;6281.309056003927;6291.213598300213;6294.15028971083;6391.8721625566295;6402.903956440564;6419.197600299963;6426.775062872777;6324.968375158095;6334.429901308904;6347.555671103163;6352.745890164527;6359.841003974752 04:03:54 INFO - PID 1637 | |1;ext.html;784.7645706288114;805.2470939671406;1088.2871719024038;1277.4379273119825;1277.6997405609686;37231.99999999996;37678.434823477604;37727;37737.24525509488;37791.24417511648;38018.809905950504;38314.233715325674;38397.848064558035;38407.615923840756;38501.80749096258;7882.645280962356;8359.540225287607;8562.61468233928;8567.271781898546;8581.184787445183;8546.230839224469;8561;8654.48073115612;8663.133686631349;8681.522516261603;7350.926490735078;8573.614187361583;8611.655533778672;8699.478031318109;8716.738497845043;8624.094470080645;8656.437331573428;8673.609687564074;8674.913250867474;8676.262517686004;8368.414211005233;8425;8425.873611895826;8436.409451338408;8456.492610443393;37084.25831483382;37158.44262336067;37291.44062839032;37310.015198784306;37395.06512337186;3135.608048993876;3609.1157666371646;3647.562292524914;3649.525561676965;3653.780773153619;30285.24286892823;30565.388692226254;30695.539566906515;30794.53808192879;30822;3064.0654600346884;3156.6685498022607;3164.224764932583;3173.587433633636;3175.031615357315;296513;297845;297934;298271.50864245754;302201;48032.5196748028;48274.517254827704;48461.75769121132;48496.757516212194;48527.75736121297;42227.57772422298;48887.755561221966;49982;50039;50432;13845.861541384456;13964.022518423815;14056.367463464168;14077.366518506691;14086.788698169536;11146.164037697205;11248.325100493996;11300.886991130148;11456.885431145749;11518.827217591743;13006.934965325112;13106.737865242641;13131;13137.211767293802;13205.075644704779;5691.857703557444;5734.369219385864;5801.622894511843;5810.535157187415;5834.095715164156;869.7608157756667;1016.8627235323285;1035.30634474902;1042.5829668132656;1077.283606401734;22908.656370154462;24151.154709585433;24901.814566244095;25241.495170096496;25258.621120683205;1045.7751583409604;1134.8127558952845;1152.4698638626157;1154.3477934966718;1172.0740614914293;12209.938950305192;12760.979121670243;12802.487900483877;12822.1024528284;12864.22814631125;264088.679556601;320182.39908800775;320572.79427205893;321547;321667;55299;55567.44432555704;55626.16560751592;55708.88582228414;55716;1489.2181604657642;1492.8208614966275;1533.877289816812;1545.9304331304893;1548.8683461905864;18487;19861.503462412977;23424.945877435897;24340.26979190627;24549.877250614347;37712.43431348477;49977.75011124921;50441.49558504368;50994.98010039853;51113.23330150125;314161;316527;316868;316888;317403;28955;36425.90722278336;36428.45357319695;36912.630873692;37007.44488832616;46067.61797145957;46383.76808115938;46602.300965486225;46864.297035545176;46933.296000559334;25770.613440798777;30865.382692345578;31373.8431307842;31748.841255793574;31783.52324715085;30927.226819329695;31767.841160794047;31861.840690796398;31934.36131277315;32140.517892231168;37523;37715.62284377231;37947.62052379441;38034;38524;27134.050308239115;30491;30826.92105776287;31228.687713123487;31313.06060818179;23391.76608233896;25046.248612541654;25050.498990019732;25180.874095629406;25437.236882893543 04:03:54 INFO - PID 1637 | |2;jquery.html;424.23637452585336;433.38459387669496;434.9170565292421;452.20637780694875;455.11025944278936;19413.514662133453;21030.789692103073;21712.782872171272;21744.3476695699;21887.781122188775;21107.788922110773;21543.030563624634;21895;22494.100235990583;22854.771452285473;5275.6043296752705;5948.048312270049;6065.878682426349;6148.0470527068155;6177.351378105279;5931.525477961774;5987.161797348374;6104.664243466607;6141.232345956755;6163.044728067157;5882.705864706769;6025.216721826178;6105.450509454148;6118.908216376758;6142.3550527194675;4627.791749371287;5971.134185543094;6116.724747386356;6130.325664176937;6353.428191462791;5349.277847490578;5355.062863998784;5391.137418013099;5423.728813559345;5557.221988921554;22686.886565567067;27809;27928.510977248807;28232.576511352345;28461.857690711622;2216.1467834883524;2275.97724022761;2276.0781883337277;2279.2706333973133;2280.6122959096992;17886.821131788776;17895.552611184696;18093.54766130844;18481.075946202636;18580.978046207452;2201.658742894838;2219.4451387153213;2253.526759380532;2255.560165767676;2256.187772401934;170193.14903425527;172058;172820;173457.06171753115;174059;12687.429065692066;12888.548900788428;12955.417006234742;12989.246451728895;13187.142835715647;16803.915980420265;16810.327586896386;16828.495145145665;16830.91584542094;16846.747298790528;7392.704291828374;8683.956580217058;8999.82000359989;9018.909810901807;9137.908620913839;8306.916930830736;8409.159084091541;8415.032271288757;8455.281301089353;8585.399022068395;6536.738530458823;6603.438707709898;7871.409644276702;7996.360291176692;8040.919590804017;2175.075592873054;2190.2991042866447;2193.5393567351034;2199.7250343707037;2387.7612238775982;950.2398081534744;962.7689354554997;968.8352979993418;991.6231831903831;1014.6347314966754;18818.529536761416;20215.090320935593;20840.58318833615;21320.466988325104;21357.359279221648;993.9602415903283;998.286225348879;1009.7526106104051;1031.6079889641887;1035.5909415780725;10222.897771022343;11080.002799747901;12322.568710095104;12778.169418987736;13281.203127812361;448187;540339;599856;617774;621854;109227;109637.35543966846;134818;139029.3048534751;139703.6029639711;1167.744674474926;1195.0520651338436;1221.0719852911673;1221.4503473437167;1226.6197478781428;19161.712574311117;20889.164433423102;22170.89145542684;22216.222432214796;22306.77693223047;56593.86812263649;58509;58511;59648;59806.40193598183;53685.731571341894;77837.61081194557;78571;79080.418391633;80387;7311.817204569928;8163.97950256218;8746.91253087461;8942.86585701228;9053.411528250643;11737.471813768572;13666.726665466835;14191.574252772434;14236.786448203493;14422.711545769236;10595.258331916692;11728.70678233051;12093.637190884288;12103.092268079754;12236.694082648006;9888.060634239735;10828.350298982086;11003.22977391607;11020.338779672924;11434.313941163557;11716.179867409199;12011.75976480448;12209;12235.816462753244;12279.263244205376;8797.604107815036;8866.512341821004;8911;8959.283257339266;9001;11419.143564232882;12067.456964436797;12076.51693932233;12326;12349.073819463756 04:03:54 INFO - PID 1637 | |3;mootools.html;1229.6864299603603;1408.3662351941623;1423.7864320351953;1443.81952255968;1446.0167086381264;9853.901460985406;11564.65306040819;11831.881681183197;11942.104342174329;12002.159848810576;11804.822927656092;11939.343336116532;12035.398230088482;12043.879561204385;12049.337286449241;2121.321177223289;2543.1734686227005;2576.7938564914857;2578.8323758955607;2579.638850560923;2486.2168416948602;2519.722830488654;2561.065211197909;2565.7690807827294;2578.664773579432;2494.9251522454356;2547.197632745679;2556.744325567447;2570.627259047437;2576.2013775729583;2452.9509409811794;2544.2748816587277;2553.565893798059;2559.5776696844996;2563.935901602475;2465.6794616699895;2529.215943057658;2545.4018305698146;2550.961735573968;2578.2136448383235;9844.507774611313;9957.800843983152;11888.405579721066;11928.70178245542;12058.216215945935;5720.8855822883725;6671.232808227068;6913.89629155563;6930.93069069313;6945.2707465716385;4169.228692691861;4412.448443944507;4485.70842895214;4490.991036005884;4521.163584736834;1258.9496420143275;1268.7335659511514;1287.67808047988;1305.8237137986346;1315.3226088564397;15050.397984080624;15405.845941540552;16539.91730041342;16719.996800192028;16865.747013794804;5649.887002259932;5701.173329867146;5913.970430147821;5939;5959.28488581373;12151.513939442324;12385.19496232742;12474.313912734886;12478.313692746986;12535.749285014432;6599.241087274929;6666.200055993215;6677.165354330708;6680.3987641112535;6685.0640910272605;5044;5998.980173370537;6204.534659900525;6222.0355844844125;6278.811635650938;3508.8946981700683;3689.317476266899;4297.699161058696;4488.887777805581;4531.43357080365;1102.652664410708;1133;1332.6868185976289;1333.4466196528526;1345.8115863779078;785.0892964161545;794.566961006253;810.0846043970275;810.1452967119627;836.8786525953702;6125.234345706786;6131.50947924165;6274.121622972787;6379.106925030499;6380.936190638127;592.520058752412;601.8465291350765;602.1088788592907;606.4723690389301;617.9907301390483;4165.166966606691;4287.978560107242;4307.956920430818;4340.631046361094;4427.247367947457;13123.343832808321;14775;14940.402383904522;15457;15560.610984725472;14764.63088422798;15320.693586128215;15323.157226352434;15336.233188340539;15344.386224551115;7019.543729657556;8451.83096338082;8588.8711669325;8665.696700615448;8674.739757807276;6222.315545290105;6730;7123.715051397886;7135.357817796424;7240.094988126484;12782.68043298925;12930.353482325658;13448.991325650812;13563.728725425239;13607.523736669169;10809.459527023775;13921.860781392461;13972.301384930915;14195.645108871948;14266.500672476413;9683.757906052406;9752;9765.121139097515;9832.9016709832;9903.405795652285;10898.509567069343;11007.174461915549;11015.173861960546;11017.063549598373;11022.393768342818;7400.000999864949;9131;9281.67514137002;9374.531273436438;9421.246300295823;7710.498817576839;9106.544672766468;9123.954380228057;9278.489683067495;9279.907200927904;8285.792855178668;8455.154484551496;8515.872261915953;8521.190486903733;8566.614502347285;5445.891082178414;5600.887982240414;5969.403059693996;5980.162777211107;6278.4035516625845;5333.7866485340155;6038.426349496791;6158.938410615837;6186.81439556814;6207.875842483035 04:03:54 INFO - PID 1637 | |4;prototype.html;378.2265267527905;404.3429427180831;407.59647948530966;408.66693644679583;416.1655879960678;6261.3425590313045;6298.464630506407;6316.431521163095;6341.809745707625;6343.841403964903;4955.6778809377365;5533.280673512438;5563.884224834085;5795.681237531934;6023.402094077199;4645.790939407718;4656.371389862376;4665.486796452396;4666.393368862043;4676.392069031038;4201.495820501544;4683.274092515666;4692.410306115824;4705.505921878188;4709.62323014158;4362;4511.72929624222;4605.319058543641;4618.261078227468;4650.813967441299;3578.0160455874584;4584.701994370355;4605.286180642006;4612.785862785881;4620.676552641316;3874.682607913323;4006.2187873364705;4085.632293093636;4137.751734895885;4148.755373388001;4870.103916866528;5734.5699072569305;5991.5206783457215;6038.1583616996795;6100.176476175749;4700.976495117503;5347.689815995067;5540.836424350891;5559.110542313251;5563.610547261694;5378.489043540896;5678.801241956553;6224.502039836848;6560.704768285439;6598.802035938929;3863.4011728182177;4588.655850811169;4658.906821863544;4672.738360642607;4682.34447177392;5145.794168233266;5501.532369748536;5603.299587551556;6048.727807248684;6543.280239173687;3970.404439334123;4520.751358675305;4637.884052898705;4807.351007613997;4836.395450568679;4001.6998725095737;4782.473927867932;4923.90152196954;4949.158643030693;4971.776270067856;4299.097189590158;4451.732896026248;5228.63399562027;5571.832845014656;5574.804881829116;6375.266844312963;6562.934370656232;6567.310432404624;6576.210854697467;6697.832554186087;1388.3266615691382;1521.0645453046297;1536.8386319436297;1541.7610270408104;1543.8919275650821;916.4547094478775;963.5375019990444;1022.3354819367322;1023.7031260934394;1034.539629864718;6191.380861913773;6215.192025036717;6224.968875155595;6229.283632382243;6261.436470717566;5832.212651292106;6153.261608606996;6788.694508747119;6803.557768745016;6824.249332573413;5425.593080518976;5579.246801681802;6186;6226.25284965798;6240.438360547481;3533.381658209803;3567.7680950738118;3575.73182011345;3576.6423357664025;3592.5511508951176;6796.184457865088;6992.5105242633545;7041.1902631197045;7058.964705176441;7063.717451301993;5742.799002034909;6234.563580549318;6987.825304367431;7023.576865981228;7061.152661680529;5813.069908814654;6709.664516774142;6899.310068993161;7032.12098487689;7070.540414873017;5850.912236316543;6019.638821670714;6099.176611157614;6854.897176542456;6868.107146070979;6135.969320153371;6292.709994451035;6459.031145328238;6650.6342151180215;6746.886763683937;7005.108620672492;7013.756677210418;7021.543599666005;7033.683484243119;7065.6467176639935;4459.888502787457;4464.977675111603;4466;4466.352378904976;4488.483824360241;5898.203742494708;5966.254218222723;6282.968585157045;6754.527183097136;6791.422729068085;5601.467860553241;5826.970865145647;6536.411722944958;6561.438561438561;6561.671916404256;6274.749010039547;6479.740810367721;6522.315156908646;6528.812847696075;6534.444572211415;5852.795152169653;6155.384461553809;6236.095766113797;6422.197225346832;6430;6200.503959683305;6509.511786615928;6560.606363618198;6590.176227971504;6625.701843416961;5868.178455016216;6274.6548939808745;6379.106925030592;6472.964325707864;6484.5460817742305 04:03:54 INFO - PID 1637 | |5;yui.html;826.5288785392321;834.9123342049085;846.640177924382;857.815569652525;867.2454964181161;16405.83594164058;16844.663106737873;16860.32558697652;18315.908420457912;18936.905315473403;17252.223649935753;17584.472465826013;17645.382411615596;17647.558811029732;17965.820341796578;933.1974501928336;941.9152276295132;953.637617705271;954.7279025477741;955.0926619711267;820.9138040505751;956.1203692602822;969.2343048991323;976.6288810252095;978.0854900667863;1017.31839667423;1017.8269694152012;1018.2057994764095;1018.801333739921;1019.6940917724653;994.3437331361303;1013.4780587997187;1014.442056868723;1014.695591322604;1017.1506791828832;623.6414061914372;752.39808153477;752.7591170825339;753.3483536740735;755.6675062972274;16304.755428668579;16476.42332518368;16727.330906763716;16859.904106233054;17001.57496062596;16325.918370408192;16909.91545042279;16975.66048679032;17063.914680426642;17095.48713538596;11564.537418503334;12678.619641410773;12794.936025319907;13143.079984401096;13712.314384280746;3410.4031794435873;3425.0409885232175;3510.2452972610754;3556.395412779834;3558.5373901392654;91633.54183229108;113551.99999999917;118121;119740.80259197384;120283.79716202813;10984.121270298358;12963.611091667264;13243.801342979863;13295.069345145941;13316.400761965735;24681.753182468303;25402.872985635324;25658.358541036625;25905.352366190997;25990.480190396087;6744.291849355845;7875.763727088197;7977.880331795028;7998.920010799934;8024.679012839421;3934.996575873134;3990.501187351581;4007.458993035961;4010.5387880393546;4024.3561030235023;3720.3489389356755;4308.504521980012;4362.192994296065;4384.232759267115;4401.559844015573;900.7162743735834;925.6945208081318;944.4805357053593;950.9667161649309;951.981379923489;543.413113837055;643.3180828321994;674.4536925090692;677.1467950382455;689.2487188963953;11328.660140195643;11338;11416.143789215843;11713.711491735903;12107.515699371927;641.7625478572958;667.8096742428481;676.2730065179853;688.703857341338;691.9550229235184;4316.676249281317;4373.6282415994365;4729.888476208088;4866.659333846596;5263.9998400304585;77796;99476;99620;102488.48755756323;104472.95527044784;88908;93903;94541.5272923631;95400.04599954051;95541;81715.5914220425;82747.17252827516;82890;83654;83920.58039709763;8350.958245208734;11401.429928503376;11550.653480395602;12730.045246606356;12798.360081996048;25670.8716456424;28082.71917280883;38392.84821455361;40480.1903961925;41587.75236742903;66627.00059499014;66655.66672166609;66667.33332666806;68795.65602172157;69687;15925.681486369976;15996.200189990686;16273.59316017109;16462.341506339613;16466.75299870479;13658.59024229235;16545.338186472887;16598.917005414896;16661.833381666027;16772.58068548296;16518;16655.41706040283;16738;16775;16820;16228.269727862038;16504.66990660156;16512.926659767076;16735.832641673915;16745.497635070966;16138.435154769526;16359.2638331273;16402.425915092914;16495.34018639241;16540.66918661596;11724.120690948384;11779.939805417554;11828.70428239301;12080.818787718015;12288.938555307166;12757;13398.732025359634;13517.797233041316;13518.79721804154;13661.32483428354 04:03:54 INFO - PID 1637 | __end_tp_report 04:03:54 INFO - PID 1637 | __start_cc_report 04:03:54 INFO - PID 1637 | _x_x_mozilla_cycle_collect,417 04:03:54 INFO - PID 1637 | __end_cc_report 04:03:54 INFO - PID 1637 | __startTimestamp1492686234941__endTimestamp 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | ------- Summary: start ------- 04:03:54 INFO - PID 1637 | Number of tests: 6 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:5999.97 Median:6278.72 stddev:781.44 (12.4%) stddev-sans-first:740.73 04:03:54 INFO - PID 1637 | Values: 2736.9 2970.9 3182.1 3531.8 3598.3 4727.5 6015.3 6179.3 6184.7 6243.9 6090.9 6104.1 6164.8 6182.5 6244.4 4826.3 4940.2 5272.1 5355.0 5384.1 5336.5 5369.1 5411.1 5425.1 5558.7 4318.6 5273.4 5354.7 5373.3 5425.1 5282.5 5289.3 5312.0 5371.3 5379.8 5236.1 5244.9 5253.4 5282.7 5370.0 5814.4 5970.5 6019.9 6152.3 6189.8 6398.3 6471.4 6520.9 6522.5 6550.0 6623.2 6639.9 6643.3 6656.3 6656.9 6053.8 6241.3 6248.2 6257.8 6278.7 6500.3 6532.8 6568.7 6667.9 6734.4 6270.6 6331.9 6363.1 6393.6 6414.8 6268.1 6497.6 6503.2 6585.6 6600.7 6482.9 6496.5 6509.7 6510.5 6519.5 6260.0 6289.6 6415.9 6482.0 6550.2 5195.2 5239.7 5247.3 5268.2 5286.1 3842.0 3966.5 4264.4 4639.9 4643.5 6050.1 6114.3 6152.4 6215.0 6260.7 5850.0 6010.4 6557.1 6612.7 6736.3 6246.3 6266.2 6286.5 6286.7 6288.3 5698.6 5780.8 5783.2 5805.8 5822.8 6507.3 6610.4 6728.7 6769.4 6825.2 6598.6 6600.9 6662.3 6666.0 6672.4 6718.9 6790.2 6812.6 6846.7 6850.5 5268.7 6349.0 6391.0 6471.7 6479.5 6484.5 6523.5 6613.7 6618.6 6655.6 6255.0 6344.4 6405.6 6428.1 6437.7 6277.7 6357.2 6412.6 6414.8 6427.4 6159.3 6198.1 6254.7 6281.3 6291.2 6294.2 6391.9 6402.9 6419.2 6426.8 6325.0 6334.4 6347.6 6352.7 6359.8 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:46189.70 Median:176356.66 stddev:80953.17 (45.9%) stddev-sans-first:81108.48 04:03:54 INFO - PID 1637 | Values: 784.8 805.2 1088.3 1277.4 1277.7 37232.0 37678.4 37727.0 37737.2 37791.2 38018.8 38314.2 38397.8 38407.6 38501.8 7882.6 8359.5 8562.6 8567.3 8581.2 8546.2 8561.0 8654.5 8663.1 8681.5 7350.9 8573.6 8611.7 8699.5 8716.7 8624.1 8656.4 8673.6 8674.9 8676.3 8368.4 8425.0 8425.9 8436.4 8456.5 37084.3 37158.4 37291.4 37310.0 37395.1 3135.6 3609.1 3647.6 3649.5 3653.8 30285.2 30565.4 30695.5 30794.5 30822.0 3064.1 3156.7 3164.2 3173.6 3175.0 296513.0 297845.0 297934.0 298271.5 302201.0 48032.5 48274.5 48461.8 48496.8 48527.8 42227.6 48887.8 49982.0 50039.0 50432.0 13845.9 13964.0 14056.4 14077.4 14086.8 11146.2 11248.3 11300.9 11456.9 11518.8 13006.9 13106.7 13131.0 13137.2 13205.1 5691.9 5734.4 5801.6 5810.5 5834.1 869.8 1016.9 1035.3 1042.6 1077.3 22908.7 24151.2 24901.8 25241.5 25258.6 1045.8 1134.8 1152.5 1154.3 1172.1 12209.9 12761.0 12802.5 12822.1 12864.2 264088.7 320182.4 320572.8 321547.0 321667.0 55299.0 55567.4 55626.2 55708.9 55716.0 1489.2 1492.8 1533.9 1545.9 1548.9 18487.0 19861.5 23424.9 24340.3 24549.9 37712.4 49977.8 50441.5 50995.0 51113.2 314161.0 316527.0 316868.0 316888.0 317403.0 28955.0 36425.9 36428.5 36912.6 37007.4 46067.6 46383.8 46602.3 46864.3 46933.3 25770.6 30865.4 31373.8 31748.8 31783.5 30927.2 31767.8 31861.8 31934.4 32140.5 37523.0 37715.6 37947.6 38034.0 38524.0 27134.1 30491.0 30826.9 31228.7 31313.1 23391.8 25046.2 25050.5 25180.9 25437.2 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:36414.56 Median:2255.87 stddev:96904.20 (4295.6%) stddev-sans-first:97138.36 04:03:54 INFO - PID 1637 | Values: 424.2 433.4 434.9 452.2 455.1 19413.5 21030.8 21712.8 21744.3 21887.8 21107.8 21543.0 21895.0 22494.1 22854.8 5275.6 5948.0 6065.9 6148.0 6177.4 5931.5 5987.2 6104.7 6141.2 6163.0 5882.7 6025.2 6105.5 6118.9 6142.4 4627.8 5971.1 6116.7 6130.3 6353.4 5349.3 5355.1 5391.1 5423.7 5557.2 22686.9 27809.0 27928.5 28232.6 28461.9 2216.1 2276.0 2276.1 2279.3 2280.6 17886.8 17895.6 18093.5 18481.1 18581.0 2201.7 2219.4 2253.5 2255.6 2256.2 170193.1 172058.0 172820.0 173457.1 174059.0 12687.4 12888.5 12955.4 12989.2 13187.1 16803.9 16810.3 16828.5 16830.9 16846.7 7392.7 8684.0 8999.8 9018.9 9137.9 8306.9 8409.2 8415.0 8455.3 8585.4 6536.7 6603.4 7871.4 7996.4 8040.9 2175.1 2190.3 2193.5 2199.7 2387.8 950.2 962.8 968.8 991.6 1014.6 18818.5 20215.1 20840.6 21320.5 21357.4 994.0 998.3 1009.8 1031.6 1035.6 10222.9 11080.0 12322.6 12778.2 13281.2 448187.0 540339.0 599856.0 617774.0 621854.0 109227.0 109637.4 134818.0 139029.3 139703.6 1167.7 1195.1 1221.1 1221.5 1226.6 19161.7 20889.2 22170.9 22216.2 22306.8 56593.9 58509.0 58511.0 59648.0 59806.4 53685.7 77837.6 78571.0 79080.4 80387.0 7311.8 8164.0 8746.9 8942.9 9053.4 11737.5 13666.7 14191.6 14236.8 14422.7 10595.3 11728.7 12093.6 12103.1 12236.7 9888.1 10828.4 11003.2 11020.3 11434.3 11716.2 12011.8 12209.0 12235.8 12279.3 8797.6 8866.5 8911.0 8959.3 9001.0 11419.1 12067.5 12076.5 12326.0 12349.1 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7110.62 Median:4228.60 stddev:4511.13 (106.7%) stddev-sans-first:4502.14 04:03:54 INFO - PID 1637 | Values: 1229.7 1408.4 1423.8 1443.8 1446.0 9853.9 11564.7 11831.9 11942.1 12002.2 11804.8 11939.3 12035.4 12043.9 12049.3 2121.3 2543.2 2576.8 2578.8 2579.6 2486.2 2519.7 2561.1 2565.8 2578.7 2494.9 2547.2 2556.7 2570.6 2576.2 2453.0 2544.3 2553.6 2559.6 2563.9 2465.7 2529.2 2545.4 2551.0 2578.2 9844.5 9957.8 11888.4 11928.7 12058.2 5720.9 6671.2 6913.9 6930.9 6945.3 4169.2 4412.4 4485.7 4491.0 4521.2 1258.9 1268.7 1287.7 1305.8 1315.3 15050.4 15405.8 16539.9 16720.0 16865.7 5649.9 5701.2 5914.0 5939.0 5959.3 12151.5 12385.2 12474.3 12478.3 12535.7 6599.2 6666.2 6677.2 6680.4 6685.1 5044.0 5999.0 6204.5 6222.0 6278.8 3508.9 3689.3 4297.7 4488.9 4531.4 1102.7 1133.0 1332.7 1333.4 1345.8 785.1 794.6 810.1 810.1 836.9 6125.2 6131.5 6274.1 6379.1 6380.9 592.5 601.8 602.1 606.5 618.0 4165.2 4288.0 4308.0 4340.6 4427.2 13123.3 14775.0 14940.4 15457.0 15560.6 14764.6 15320.7 15323.2 15336.2 15344.4 7019.5 8451.8 8588.9 8665.7 8674.7 6222.3 6730.0 7123.7 7135.4 7240.1 12782.7 12930.4 13449.0 13563.7 13607.5 10809.5 13921.9 13972.3 14195.6 14266.5 9683.8 9752.0 9765.1 9832.9 9903.4 10898.5 11007.2 11015.2 11017.1 11022.4 7400.0 9131.0 9281.7 9374.5 9421.2 7710.5 9106.5 9124.0 9278.5 9279.9 8285.8 8455.2 8515.9 8521.2 8566.6 5445.9 5600.9 5969.4 5980.2 6278.4 5333.8 6038.4 6158.9 6186.8 6207.9 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5283.07 Median:5841.56 stddev:1622.16 (27.8%) stddev-sans-first:1584.39 04:03:54 INFO - PID 1637 | Values: 378.2 404.3 407.6 408.7 416.2 6261.3 6298.5 6316.4 6341.8 6343.8 4955.7 5533.3 5563.9 5795.7 6023.4 4645.8 4656.4 4665.5 4666.4 4676.4 4201.5 4683.3 4692.4 4705.5 4709.6 4362.0 4511.7 4605.3 4618.3 4650.8 3578.0 4584.7 4605.3 4612.8 4620.7 3874.7 4006.2 4085.6 4137.8 4148.8 4870.1 5734.6 5991.5 6038.2 6100.2 4701.0 5347.7 5540.8 5559.1 5563.6 5378.5 5678.8 6224.5 6560.7 6598.8 3863.4 4588.7 4658.9 4672.7 4682.3 5145.8 5501.5 5603.3 6048.7 6543.3 3970.4 4520.8 4637.9 4807.4 4836.4 4001.7 4782.5 4923.9 4949.2 4971.8 4299.1 4451.7 5228.6 5571.8 5574.8 6375.3 6562.9 6567.3 6576.2 6697.8 1388.3 1521.1 1536.8 1541.8 1543.9 916.5 963.5 1022.3 1023.7 1034.5 6191.4 6215.2 6225.0 6229.3 6261.4 5832.2 6153.3 6788.7 6803.6 6824.2 5425.6 5579.2 6186.0 6226.3 6240.4 3533.4 3567.8 3575.7 3576.6 3592.6 6796.2 6992.5 7041.2 7059.0 7063.7 5742.8 6234.6 6987.8 7023.6 7061.2 5813.1 6709.7 6899.3 7032.1 7070.5 5850.9 6019.6 6099.2 6854.9 6868.1 6136.0 6292.7 6459.0 6650.6 6746.9 7005.1 7013.8 7021.5 7033.7 7065.6 4459.9 4465.0 4466.0 4466.4 4488.5 5898.2 5966.3 6283.0 6754.5 6791.4 5601.5 5827.0 6536.4 6561.4 6561.7 6274.7 6479.7 6522.3 6528.8 6534.4 5852.8 6155.4 6236.1 6422.2 6430.0 6200.5 6509.5 6560.6 6590.2 6625.7 5868.2 6274.7 6379.1 6473.0 6484.5 04:03:54 INFO - PID 1637 | 04:03:54 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:21399.52 Median:25530.62 stddev:29669.31 (116.2%) stddev-sans-first:29712.33 04:03:54 INFO - PID 1637 | Values: 826.5 834.9 846.6 857.8 867.2 16405.8 16844.7 16860.3 18315.9 18936.9 17252.2 17584.5 17645.4 17647.6 17965.8 933.2 941.9 953.6 954.7 955.1 820.9 956.1 969.2 976.6 978.1 1017.3 1017.8 1018.2 1018.8 1019.7 994.3 1013.5 1014.4 1014.7 1017.2 623.6 752.4 752.8 753.3 755.7 16304.8 16476.4 16727.3 16859.9 17001.6 16325.9 16909.9 16975.7 17063.9 17095.5 11564.5 12678.6 12794.9 13143.1 13712.3 3410.4 3425.0 3510.2 3556.4 3558.5 91633.5 113552.0 118121.0 119740.8 120283.8 10984.1 12963.6 13243.8 13295.1 13316.4 24681.8 25402.9 25658.4 25905.4 25990.5 6744.3 7875.8 7977.9 7998.9 8024.7 3935.0 3990.5 4007.5 4010.5 4024.4 3720.3 4308.5 4362.2 4384.2 4401.6 900.7 925.7 944.5 951.0 952.0 543.4 643.3 674.5 677.1 689.2 11328.7 11338.0 11416.1 11713.7 12107.5 641.8 667.8 676.3 688.7 692.0 4316.7 4373.6 4729.9 4866.7 5264.0 77796.0 99476.0 99620.0 102488.5 104473.0 88908.0 93903.0 94541.5 95400.0 95541.0 81715.6 82747.2 82890.0 83654.0 83920.6 8351.0 11401.4 11550.7 12730.0 12798.4 25670.9 28082.7 38392.8 40480.2 41587.8 66627.0 66655.7 66667.3 68795.7 69687.0 15925.7 15996.2 16273.6 16462.3 16466.8 13658.6 16545.3 16598.9 16661.8 16772.6 16518.0 16655.4 16738.0 16775.0 16820.0 16228.3 16504.7 16512.9 16735.8 16745.5 16138.4 16359.3 16402.4 16495.3 16540.7 11724.1 11779.9 11828.7 12080.8 12288.9 12757.0 13398.7 13517.8 13518.8 13661.3 04:03:54 INFO - PID 1637 | -------- Summary: end -------- 04:03:54 INFO - PID 1637 | 04:03:55 INFO - TEST-INFO | 1637: exit 0 04:03:55 INFO - TEST-OK | dromaeo_css | took 1114677ms 04:03:55 INFO - TEST-START | kraken 04:03:55 INFO - operating with platform_type : mac_ 04:03:55 INFO - Initialising browser for kraken test... 04:03:55 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpvoCxbQ/profile http://localhost:49246/getInfo.html) 04:03:58 INFO - PID 1652 | 2017-04-20 04:03:58.448 plugin-container[1653:10205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 04:03:58 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:03:58 INFO - PID 1652 | 2017-04-20 04:03:58.451 plugin-container[1653:10205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 04:03:58 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:03:58 INFO - PID 1652 | 2017-04-20 04:03:58.451 plugin-container[1653:10205] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:03:58 INFO - PID 1652 | __metrics Screen width/height:1600/1200 04:03:58 INFO - PID 1652 | colorDepth:24 04:03:58 INFO - PID 1652 | Browser inner width/height: 1024/689 04:03:58 INFO - PID 1652 | __metrics 04:03:58 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3363: TypeError: this.tabs is undefined 04:03:59 INFO - TEST-INFO | 1652: exit 0 04:03:59 INFO - Browser initialized. 04:03:59 INFO - Running cycle 1/1 for kraken test... 04:03:59 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpvoCxbQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:04:01 INFO - PID 1654 | 2017-04-20 04:04:01.997 plugin-container[1655:10331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 04:04:02 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:04:02 INFO - PID 1654 | 2017-04-20 04:04:01.998 plugin-container[1655:10331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 04:04:02 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:04:02 INFO - PID 1654 | 2017-04-20 04:04:01.998 plugin-container[1655:10331] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:04:11 INFO - PID 1654 | RSS: Main: 234827776 04:04:11 INFO - PID 1654 | 04:04:14 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 04:04:15 INFO - PID 1654 | RSS: Main: 248459264 04:04:15 INFO - PID 1654 | 04:04:17 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 04:04:18 INFO - PID 1654 | RSS: Main: 256618496 04:04:18 INFO - PID 1654 | 04:04:21 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 04:04:22 INFO - PID 1654 | RSS: Main: 258822144 04:04:22 INFO - PID 1654 | 04:04:24 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 04:04:25 INFO - PID 1654 | RSS: Main: 261144576 04:04:25 INFO - PID 1654 | 04:04:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:04:27 INFO - PID 1654 | RSS: Main: 261971968 04:04:27 INFO - PID 1654 | 04:04:30 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 04:04:31 INFO - PID 1654 | RSS: Main: 267378688 04:04:31 INFO - PID 1654 | 04:04:34 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 04:04:35 INFO - PID 1654 | RSS: Main: 269119488 04:04:35 INFO - PID 1654 | 04:04:38 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 04:04:38 INFO - PID 1654 | RSS: Main: 268369920 04:04:38 INFO - PID 1654 | 04:04:40 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:04:40 INFO - PID 1654 | RSS: Main: 268013568 04:04:40 INFO - PID 1654 | 04:04:42 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:04:43 INFO - PID 1654 | RSS: Main: 268054528 04:04:43 INFO - PID 1654 | 04:04:45 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:04:46 INFO - PID 1654 | RSS: Main: 267550720 04:04:46 INFO - PID 1654 | 04:04:48 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:04:49 INFO - PID 1654 | RSS: Main: 267718656 04:04:49 INFO - PID 1654 | 04:04:52 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:04:53 INFO - PID 1654 | RSS: Main: 268038144 04:04:53 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 04:04:55 INFO - PID 1654 | RSS: Main: 269508608 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | __start_tp_report 04:04:55 INFO - PID 1654 | _x_x_mozilla_page_load 04:04:55 INFO - PID 1654 | _x_x_mozilla_page_load_details 04:04:55 INFO - PID 1654 | |i|pagename|runs| 04:04:55 INFO - PID 1654 | |0;ai-astar;104;117;119;116;116;116;114;113;115;114 04:04:55 INFO - PID 1654 | |1;audio-beat-detection;133;110;122;176;111;117;113;112;138;112 04:04:55 INFO - PID 1654 | |2;audio-dft;223;236;197;215;238;216;249;270;197;203 04:04:55 INFO - PID 1654 | |3;audio-fft;75;56;56;80;59;66;61;84;70;67 04:04:55 INFO - PID 1654 | |4;audio-oscillator;82;69;82;72;76;89;90;71;71;92 04:04:55 INFO - PID 1654 | |5;imaging-gaussian-blur;92;99;138;119;106;136;92;94;92;93 04:04:55 INFO - PID 1654 | |6;imaging-darkroom;98;99;98;101;97;98;98;98;123;98 04:04:55 INFO - PID 1654 | |7;imaging-desaturate;91;93;90;89;130;93;89;91;89;91 04:04:55 INFO - PID 1654 | |8;json-parse-financial;51;51;51;50;50;51;50;51;50;51 04:04:55 INFO - PID 1654 | |9;json-stringify-tinderbox;58;56;65;65;69;61;55;56;57;59 04:04:55 INFO - PID 1654 | |10;stanford-crypto-aes;83;85;86;86;82;82;85;87;85;97 04:04:55 INFO - PID 1654 | |11;stanford-crypto-ccm;138;141;138;141;139;144;159;160;194;141 04:04:55 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;183;188;190;186;187;182;188;180;186;183 04:04:55 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;70;70;70;70;69;70;67;71;69;68 04:04:55 INFO - PID 1654 | __end_tp_report 04:04:55 INFO - PID 1654 | __start_cc_report 04:04:55 INFO - PID 1654 | _x_x_mozilla_cycle_collect,991 04:04:55 INFO - PID 1654 | __end_cc_report 04:04:55 INFO - PID 1654 | __startTimestamp1492686295332__endTimestamp 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | ------- Summary: start ------- 04:04:55 INFO - PID 1654 | Number of tests: 14 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:114.40 Median:116.00 stddev:4.03 (3.5%) stddev-sans-first:1.81 04:04:55 INFO - PID 1654 | Values: 104.0 117.0 119.0 116.0 116.0 116.0 114.0 113.0 115.0 114.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:119.50 stddev:20.57 (17.2%) stddev-sans-first:21.58 04:04:55 INFO - PID 1654 | Values: 133.0 110.0 122.0 176.0 111.0 117.0 113.0 112.0 138.0 112.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:224.40 Median:229.50 stddev:23.86 (10.4%) stddev-sans-first:25.30 04:04:55 INFO - PID 1654 | Values: 223.0 236.0 197.0 215.0 238.0 216.0 249.0 270.0 197.0 203.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:67.40 Median:68.50 stddev:9.85 (14.4%) stddev-sans-first:10.05 04:04:55 INFO - PID 1654 | Values: 75.0 56.0 56.0 80.0 59.0 66.0 61.0 84.0 70.0 67.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:82.00 stddev:8.77 (10.7%) stddev-sans-first:9.25 04:04:55 INFO - PID 1654 | Values: 82.0 69.0 82.0 72.0 76.0 89.0 90.0 71.0 71.0 92.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:106.10 Median:92.00 stddev:18.39 (20.0%) stddev-sans-first:18.78 04:04:55 INFO - PID 1654 | Values: 92.0 99.0 138.0 119.0 106.0 136.0 92.0 94.0 92.0 93.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:98.00 stddev:7.87 (8.0%) stddev-sans-first:8.28 04:04:55 INFO - PID 1654 | Values: 98.0 99.0 98.0 101.0 97.0 98.0 98.0 98.0 123.0 98.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:91.00 stddev:12.53 (13.8%) stddev-sans-first:13.22 04:04:55 INFO - PID 1654 | Values: 91.0 93.0 90.0 89.0 130.0 93.0 89.0 91.0 89.0 91.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:0.52 (1.0%) stddev-sans-first:0.53 04:04:55 INFO - PID 1654 | Values: 51.0 51.0 51.0 50.0 50.0 51.0 50.0 51.0 50.0 51.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:4.75 (7.9%) stddev-sans-first:4.97 04:04:55 INFO - PID 1654 | Values: 58.0 56.0 65.0 65.0 69.0 61.0 55.0 56.0 57.0 59.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.50 stddev:4.29 (5.0%) stddev-sans-first:4.43 04:04:55 INFO - PID 1654 | Values: 83.0 85.0 86.0 86.0 82.0 82.0 85.0 87.0 85.0 97.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:142.50 stddev:17.65 (12.4%) stddev-sans-first:18.22 04:04:55 INFO - PID 1654 | Values: 138.0 141.0 138.0 141.0 139.0 144.0 159.0 160.0 194.0 141.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:186.50 stddev:3.16 (1.7%) stddev-sans-first:3.24 04:04:55 INFO - PID 1654 | Values: 183.0 188.0 190.0 186.0 187.0 182.0 188.0 180.0 186.0 183.0 04:04:55 INFO - PID 1654 | 04:04:55 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:70.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 04:04:55 INFO - PID 1654 | Values: 70.0 70.0 70.0 70.0 69.0 70.0 67.0 71.0 69.0 68.0 04:04:55 INFO - PID 1654 | -------- Summary: end -------- 04:04:55 INFO - PID 1654 | 04:04:56 INFO - TEST-INFO | 1654: exit 0 04:04:56 INFO - TEST-OK | kraken | took 60741ms 04:04:56 INFO - SUITE-END | took 1175s 04:04:56 INFO - Completed test suite (00:19:35) 04:04:56 INFO - javascript benchmark 04:04:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7842.43338773094, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2736.876840542176, 2970.885917780832, 3182.140821978066, 3531.823408829558, 3598.334308152992, 4727.479977202509, 6015.338312785601, 6179.3202747697715, 6184.7216875240665, 6243.875122497548, 6090.87818243636, 6104.095385180735, 6164.81505554833, 6182.474489668372, 6244.438000579947, 4826.34844296019, 4940.184869496528, 5272.051030814452, 5354.955783622196, 5384.111621582434, 5336.466353364652, 5369.087255166612, 5411.134218525037, 5425.104857698495, 5558.722063896789, 4318.611324980751, 5273.419923808378, 5354.705491197982, 5373.301470808769, 5425.077736784756, 5282.471752824726, 5289.285946397225, 5311.973440132813, 5371.328583927009, 5379.784808607651, 5236.1098613235845, 5244.895102097975, 5253.369595648546, 5282.683039017671, 5370.033393989081, 5814.389489103625, 5970.492508136814, 6019.879602407971, 6152.323244443108, 6189.845253868644, 6398.328175541547, 6471.417572418505, 6520.934790652128, 6522.478201743849, 6549.967250163766, 6623.172103487064, 6639.900401493981, 6643.269240383554, 6656.301088385747, 6656.900146497806, 6053.848653783691, 6241.313455519889, 6248.187735594389, 6257.812265632038, 6278.717457714414, 6500.349965003462, 6532.771353002717, 6568.737250510022, 6667.899981500282, 6734.427573656294, 6270.59241149333, 6331.936680633226, 6363.077353783768, 6393.6483493408305, 6414.775482858077, 6268.059791031291, 6497.577657452251, 6503.154589903283, 6585.604863708193, 6600.669966501656, 6482.935170648328, 6496.480281577557, 6509.707063182168, 6510.479161667055, 6519.511036672174, 6259.968700156561, 6289.55973081889, 6415.935840641534, 6482.0, 6550.181227346582, 5195.220716892496, 5239.711815850165, 5247.2653828464045, 5268.1834315681135, 5286.127788914863, 3841.980790096032, 3966.5438474575794, 4264.424302719093, 4639.884002899955, 4643.466001409881, 6050.122732203806, 6114.327423983358, 6152.446279834837, 6214.9689251553455, 6260.749570017239, 5849.970750146222, 6010.398960103955, 6557.114789503451, 6612.735490580323, 6736.2590115087305, 6246.3441338659695, 6266.185395898504, 6286.528510361742, 6286.748530058838, 6288.308286088527, 5698.572607054488, 5780.797672081457, 5783.161441590946, 5805.82582522525, 5822.767089316465, 6507.251666058464, 6610.3720146586, 6728.697208625722, 6769.356911093439, 6825.180978282638, 6598.571092878948, 6600.900986485302, 6662.267150613332, 6665.966670166618, 6672.399484046459, 6718.899216511659, 6790.151231096113, 6812.557183783038, 6846.726130954905, 6850.520463567503, 5268.736563171903, 6349.0, 6390.968045159744, 6471.676416179266, 6479.481641468766, 6484.5460817742305, 6523.543351965317, 6613.710326486289, 6618.635975021228, 6655.567388119756, 6254.9687251563455, 6344.397282258178, 6405.647689377129, 6428.100065990671, 6437.742490300336, 6277.654728989949, 6357.205349331333, 6412.647304398303, 6414.839629009312, 6427.421532062137, 6159.322474527888, 6198.1012753149635, 6254.687265636791, 6281.309056003927, 6291.213598300213, 6294.15028971083, 6391.8721625566295, 6402.903956440564, 6419.197600299963, 6426.775062872777, 6324.968375158095, 6334.429901308904, 6347.555671103163, 6352.745890164527, 6359.841003974752], "value": 5941.243075694202, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.7645706288114, 805.2470939671406, 1088.2871719024038, 1277.4379273119825, 1277.6997405609686, 37231.99999999996, 37678.434823477604, 37727.0, 37737.24525509488, 37791.24417511648, 38018.809905950504, 38314.233715325674, 38397.848064558035, 38407.615923840756, 38501.80749096258, 7882.645280962356, 8359.540225287607, 8562.61468233928, 8567.271781898546, 8581.184787445183, 8546.230839224469, 8561.0, 8654.48073115612, 8663.133686631349, 8681.522516261603, 7350.926490735078, 8573.614187361583, 8611.655533778672, 8699.478031318109, 8716.738497845043, 8624.094470080645, 8656.437331573428, 8673.609687564074, 8674.913250867474, 8676.262517686004, 8368.414211005233, 8425.0, 8425.873611895826, 8436.409451338408, 8456.492610443393, 37084.25831483382, 37158.44262336067, 37291.44062839032, 37310.015198784306, 37395.06512337186, 3135.608048993876, 3609.1157666371646, 3647.562292524914, 3649.525561676965, 3653.780773153619, 30285.24286892823, 30565.388692226254, 30695.539566906515, 30794.53808192879, 30822.0, 3064.0654600346884, 3156.6685498022607, 3164.224764932583, 3173.587433633636, 3175.031615357315, 296513.0, 297845.0, 297934.0, 298271.50864245754, 302201.0, 48032.5196748028, 48274.517254827704, 48461.75769121132, 48496.757516212194, 48527.75736121297, 42227.57772422298, 48887.755561221966, 49982.0, 50039.0, 50432.0, 13845.861541384456, 13964.022518423815, 14056.367463464168, 14077.366518506691, 14086.788698169536, 11146.164037697205, 11248.325100493996, 11300.886991130148, 11456.885431145749, 11518.827217591743, 13006.934965325112, 13106.737865242641, 13131.0, 13137.211767293802, 13205.075644704779, 5691.857703557444, 5734.369219385864, 5801.622894511843, 5810.535157187415, 5834.095715164156, 869.7608157756667, 1016.8627235323285, 1035.30634474902, 1042.5829668132656, 1077.283606401734, 22908.656370154462, 24151.154709585433, 24901.814566244095, 25241.495170096496, 25258.621120683205, 1045.7751583409604, 1134.8127558952845, 1152.4698638626157, 1154.3477934966718, 1172.0740614914293, 12209.938950305192, 12760.979121670243, 12802.487900483877, 12822.1024528284, 12864.22814631125, 264088.679556601, 320182.39908800775, 320572.79427205893, 321547.0, 321667.0, 55299.0, 55567.44432555704, 55626.16560751592, 55708.88582228414, 55716.0, 1489.2181604657642, 1492.8208614966275, 1533.877289816812, 1545.9304331304893, 1548.8683461905864, 18487.0, 19861.503462412977, 23424.945877435897, 24340.26979190627, 24549.877250614347, 37712.43431348477, 49977.75011124921, 50441.49558504368, 50994.98010039853, 51113.23330150125, 314161.0, 316527.0, 316868.0, 316888.0, 317403.0, 28955.0, 36425.90722278336, 36428.45357319695, 36912.630873692, 37007.44488832616, 46067.61797145957, 46383.76808115938, 46602.300965486225, 46864.297035545176, 46933.296000559334, 25770.613440798777, 30865.382692345578, 31373.8431307842, 31748.841255793574, 31783.52324715085, 30927.226819329695, 31767.841160794047, 31861.840690796398, 31934.36131277315, 32140.517892231168, 37523.0, 37715.62284377231, 37947.62052379441, 38034.0, 38524.0, 27134.050308239115, 30491.0, 30826.92105776287, 31228.687713123487, 31313.06060818179, 23391.76608233896, 25046.248612541654, 25050.498990019732, 25180.874095629406, 25437.236882893543], "value": 17783.253444481652, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.23637452585336, 433.38459387669496, 434.9170565292421, 452.20637780694875, 455.11025944278936, 19413.514662133453, 21030.789692103073, 21712.782872171272, 21744.3476695699, 21887.781122188775, 21107.788922110773, 21543.030563624634, 21895.0, 22494.100235990583, 22854.771452285473, 5275.6043296752705, 5948.048312270049, 6065.878682426349, 6148.0470527068155, 6177.351378105279, 5931.525477961774, 5987.161797348374, 6104.664243466607, 6141.232345956755, 6163.044728067157, 5882.705864706769, 6025.216721826178, 6105.450509454148, 6118.908216376758, 6142.3550527194675, 4627.791749371287, 5971.134185543094, 6116.724747386356, 6130.325664176937, 6353.428191462791, 5349.277847490578, 5355.062863998784, 5391.137418013099, 5423.728813559345, 5557.221988921554, 22686.886565567067, 27809.0, 27928.510977248807, 28232.576511352345, 28461.857690711622, 2216.1467834883524, 2275.97724022761, 2276.0781883337277, 2279.2706333973133, 2280.6122959096992, 17886.821131788776, 17895.552611184696, 18093.54766130844, 18481.075946202636, 18580.978046207452, 2201.658742894838, 2219.4451387153213, 2253.526759380532, 2255.560165767676, 2256.187772401934, 170193.14903425527, 172058.0, 172820.0, 173457.06171753115, 174059.0, 12687.429065692066, 12888.548900788428, 12955.417006234742, 12989.246451728895, 13187.142835715647, 16803.915980420265, 16810.327586896386, 16828.495145145665, 16830.91584542094, 16846.747298790528, 7392.704291828374, 8683.956580217058, 8999.82000359989, 9018.909810901807, 9137.908620913839, 8306.916930830736, 8409.159084091541, 8415.032271288757, 8455.281301089353, 8585.399022068395, 6536.738530458823, 6603.438707709898, 7871.409644276702, 7996.360291176692, 8040.919590804017, 2175.075592873054, 2190.2991042866447, 2193.5393567351034, 2199.7250343707037, 2387.7612238775982, 950.2398081534744, 962.7689354554997, 968.8352979993418, 991.6231831903831, 1014.6347314966754, 18818.529536761416, 20215.090320935593, 20840.58318833615, 21320.466988325104, 21357.359279221648, 993.9602415903283, 998.286225348879, 1009.7526106104051, 1031.6079889641887, 1035.5909415780725, 10222.897771022343, 11080.002799747901, 12322.568710095104, 12778.169418987736, 13281.203127812361, 448187.0, 540339.0, 599856.0, 617774.0, 621854.0, 109227.0, 109637.35543966846, 134818.0, 139029.3048534751, 139703.6029639711, 1167.744674474926, 1195.0520651338436, 1221.0719852911673, 1221.4503473437167, 1226.6197478781428, 19161.712574311117, 20889.164433423102, 22170.89145542684, 22216.222432214796, 22306.77693223047, 56593.86812263649, 58509.0, 58511.0, 59648.0, 59806.40193598183, 53685.731571341894, 77837.61081194557, 78571.0, 79080.418391633, 80387.0, 7311.817204569928, 8163.97950256218, 8746.91253087461, 8942.86585701228, 9053.411528250643, 11737.471813768572, 13666.726665466835, 14191.574252772434, 14236.786448203493, 14422.711545769236, 10595.258331916692, 11728.70678233051, 12093.637190884288, 12103.092268079754, 12236.694082648006, 9888.060634239735, 10828.350298982086, 11003.22977391607, 11020.338779672924, 11434.313941163557, 11716.179867409199, 12011.75976480448, 12209.0, 12235.816462753244, 12279.263244205376, 8797.604107815036, 8866.512341821004, 8911.0, 8959.283257339266, 9001.0, 11419.143564232882, 12067.456964436797, 12076.51693932233, 12326.0, 12349.073819463756], "value": 10504.612916624821, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.6864299603603, 1408.3662351941623, 1423.7864320351953, 1443.81952255968, 1446.0167086381264, 9853.901460985406, 11564.65306040819, 11831.881681183197, 11942.104342174329, 12002.159848810576, 11804.822927656092, 11939.343336116532, 12035.398230088482, 12043.879561204385, 12049.337286449241, 2121.321177223289, 2543.1734686227005, 2576.7938564914857, 2578.8323758955607, 2579.638850560923, 2486.2168416948602, 2519.722830488654, 2561.065211197909, 2565.7690807827294, 2578.664773579432, 2494.9251522454356, 2547.197632745679, 2556.744325567447, 2570.627259047437, 2576.2013775729583, 2452.9509409811794, 2544.2748816587277, 2553.565893798059, 2559.5776696844996, 2563.935901602475, 2465.6794616699895, 2529.215943057658, 2545.4018305698146, 2550.961735573968, 2578.2136448383235, 9844.507774611313, 9957.800843983152, 11888.405579721066, 11928.70178245542, 12058.216215945935, 5720.8855822883725, 6671.232808227068, 6913.89629155563, 6930.93069069313, 6945.2707465716385, 4169.228692691861, 4412.448443944507, 4485.70842895214, 4490.991036005884, 4521.163584736834, 1258.9496420143275, 1268.7335659511514, 1287.67808047988, 1305.8237137986346, 1315.3226088564397, 15050.397984080624, 15405.845941540552, 16539.91730041342, 16719.996800192028, 16865.747013794804, 5649.887002259932, 5701.173329867146, 5913.970430147821, 5939.0, 5959.28488581373, 12151.513939442324, 12385.19496232742, 12474.313912734886, 12478.313692746986, 12535.749285014432, 6599.241087274929, 6666.200055993215, 6677.165354330708, 6680.3987641112535, 6685.0640910272605, 5044.0, 5998.980173370537, 6204.534659900525, 6222.0355844844125, 6278.811635650938, 3508.8946981700683, 3689.317476266899, 4297.699161058696, 4488.887777805581, 4531.43357080365, 1102.652664410708, 1133.0, 1332.6868185976289, 1333.4466196528526, 1345.8115863779078, 785.0892964161545, 794.566961006253, 810.0846043970275, 810.1452967119627, 836.8786525953702, 6125.234345706786, 6131.50947924165, 6274.121622972787, 6379.106925030499, 6380.936190638127, 592.520058752412, 601.8465291350765, 602.1088788592907, 606.4723690389301, 617.9907301390483, 4165.166966606691, 4287.978560107242, 4307.956920430818, 4340.631046361094, 4427.247367947457, 13123.343832808321, 14775.0, 14940.402383904522, 15457.0, 15560.610984725472, 14764.63088422798, 15320.693586128215, 15323.157226352434, 15336.233188340539, 15344.386224551115, 7019.543729657556, 8451.83096338082, 8588.8711669325, 8665.696700615448, 8674.739757807276, 6222.315545290105, 6730.0, 7123.715051397886, 7135.357817796424, 7240.094988126484, 12782.68043298925, 12930.353482325658, 13448.991325650812, 13563.728725425239, 13607.523736669169, 10809.459527023775, 13921.860781392461, 13972.301384930915, 14195.645108871948, 14266.500672476413, 9683.757906052406, 9752.0, 9765.121139097515, 9832.9016709832, 9903.405795652285, 10898.509567069343, 11007.174461915549, 11015.173861960546, 11017.063549598373, 11022.393768342818, 7400.000999864949, 9131.0, 9281.67514137002, 9374.531273436438, 9421.246300295823, 7710.498817576839, 9106.544672766468, 9123.954380228057, 9278.489683067495, 9279.907200927904, 8285.792855178668, 8455.154484551496, 8515.872261915953, 8521.190486903733, 8566.614502347285, 5445.891082178414, 5600.887982240414, 5969.403059693996, 5980.162777211107, 6278.4035516625845, 5333.7866485340155, 6038.426349496791, 6158.938410615837, 6186.81439556814, 6207.875842483035], "value": 5334.354057252453, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.2265267527905, 404.3429427180831, 407.59647948530966, 408.66693644679583, 416.1655879960678, 6261.3425590313045, 6298.464630506407, 6316.431521163095, 6341.809745707625, 6343.841403964903, 4955.6778809377365, 5533.280673512438, 5563.884224834085, 5795.681237531934, 6023.402094077199, 4645.790939407718, 4656.371389862376, 4665.486796452396, 4666.393368862043, 4676.392069031038, 4201.495820501544, 4683.274092515666, 4692.410306115824, 4705.505921878188, 4709.62323014158, 4362.0, 4511.72929624222, 4605.319058543641, 4618.261078227468, 4650.813967441299, 3578.0160455874584, 4584.701994370355, 4605.286180642006, 4612.785862785881, 4620.676552641316, 3874.682607913323, 4006.2187873364705, 4085.632293093636, 4137.751734895885, 4148.755373388001, 4870.103916866528, 5734.5699072569305, 5991.5206783457215, 6038.1583616996795, 6100.176476175749, 4700.976495117503, 5347.689815995067, 5540.836424350891, 5559.110542313251, 5563.610547261694, 5378.489043540896, 5678.801241956553, 6224.502039836848, 6560.704768285439, 6598.802035938929, 3863.4011728182177, 4588.655850811169, 4658.906821863544, 4672.738360642607, 4682.34447177392, 5145.794168233266, 5501.532369748536, 5603.299587551556, 6048.727807248684, 6543.280239173687, 3970.404439334123, 4520.751358675305, 4637.884052898705, 4807.351007613997, 4836.395450568679, 4001.6998725095737, 4782.473927867932, 4923.90152196954, 4949.158643030693, 4971.776270067856, 4299.097189590158, 4451.732896026248, 5228.63399562027, 5571.832845014656, 5574.804881829116, 6375.266844312963, 6562.934370656232, 6567.310432404624, 6576.210854697467, 6697.832554186087, 1388.3266615691382, 1521.0645453046297, 1536.8386319436297, 1541.7610270408104, 1543.8919275650821, 916.4547094478775, 963.5375019990444, 1022.3354819367322, 1023.7031260934394, 1034.539629864718, 6191.380861913773, 6215.192025036717, 6224.968875155595, 6229.283632382243, 6261.436470717566, 5832.212651292106, 6153.261608606996, 6788.694508747119, 6803.557768745016, 6824.249332573413, 5425.593080518976, 5579.246801681802, 6186.0, 6226.25284965798, 6240.438360547481, 3533.381658209803, 3567.7680950738118, 3575.73182011345, 3576.6423357664025, 3592.5511508951176, 6796.184457865088, 6992.5105242633545, 7041.1902631197045, 7058.964705176441, 7063.717451301993, 5742.799002034909, 6234.563580549318, 6987.825304367431, 7023.576865981228, 7061.152661680529, 5813.069908814654, 6709.664516774142, 6899.310068993161, 7032.12098487689, 7070.540414873017, 5850.912236316543, 6019.638821670714, 6099.176611157614, 6854.897176542456, 6868.107146070979, 6135.969320153371, 6292.709994451035, 6459.031145328238, 6650.6342151180215, 6746.886763683937, 7005.108620672492, 7013.756677210418, 7021.543599666005, 7033.683484243119, 7065.6467176639935, 4459.888502787457, 4464.977675111603, 4466.0, 4466.352378904976, 4488.483824360241, 5898.203742494708, 5966.254218222723, 6282.968585157045, 6754.527183097136, 6791.422729068085, 5601.467860553241, 5826.970865145647, 6536.411722944958, 6561.438561438561, 6561.671916404256, 6274.749010039547, 6479.740810367721, 6522.315156908646, 6528.812847696075, 6534.444572211415, 5852.795152169653, 6155.384461553809, 6236.095766113797, 6422.197225346832, 6430.0, 6200.503959683305, 6509.511786615928, 6560.606363618198, 6590.176227971504, 6625.701843416961, 5868.178455016216, 6274.6548939808745, 6379.106925030592, 6472.964325707864, 6484.5460817742305], "value": 4776.220504274553, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.5288785392321, 834.9123342049085, 846.640177924382, 857.815569652525, 867.2454964181161, 16405.83594164058, 16844.663106737873, 16860.32558697652, 18315.908420457912, 18936.905315473403, 17252.223649935753, 17584.472465826013, 17645.382411615596, 17647.558811029732, 17965.820341796578, 933.1974501928336, 941.9152276295132, 953.637617705271, 954.7279025477741, 955.0926619711267, 820.9138040505751, 956.1203692602822, 969.2343048991323, 976.6288810252095, 978.0854900667863, 1017.31839667423, 1017.8269694152012, 1018.2057994764095, 1018.801333739921, 1019.6940917724653, 994.3437331361303, 1013.4780587997187, 1014.442056868723, 1014.695591322604, 1017.1506791828832, 623.6414061914372, 752.39808153477, 752.7591170825339, 753.3483536740735, 755.6675062972274, 16304.755428668579, 16476.42332518368, 16727.330906763716, 16859.904106233054, 17001.57496062596, 16325.918370408192, 16909.91545042279, 16975.66048679032, 17063.914680426642, 17095.48713538596, 11564.537418503334, 12678.619641410773, 12794.936025319907, 13143.079984401096, 13712.314384280746, 3410.4031794435873, 3425.0409885232175, 3510.2452972610754, 3556.395412779834, 3558.5373901392654, 91633.54183229108, 113551.99999999917, 118121.0, 119740.80259197384, 120283.79716202813, 10984.121270298358, 12963.611091667264, 13243.801342979863, 13295.069345145941, 13316.400761965735, 24681.753182468303, 25402.872985635324, 25658.358541036625, 25905.352366190997, 25990.480190396087, 6744.291849355845, 7875.763727088197, 7977.880331795028, 7998.920010799934, 8024.679012839421, 3934.996575873134, 3990.501187351581, 4007.458993035961, 4010.5387880393546, 4024.3561030235023, 3720.3489389356755, 4308.504521980012, 4362.192994296065, 4384.232759267115, 4401.559844015573, 900.7162743735834, 925.6945208081318, 944.4805357053593, 950.9667161649309, 951.981379923489, 543.413113837055, 643.3180828321994, 674.4536925090692, 677.1467950382455, 689.2487188963953, 11328.660140195643, 11338.0, 11416.143789215843, 11713.711491735903, 12107.515699371927, 641.7625478572958, 667.8096742428481, 676.2730065179853, 688.703857341338, 691.9550229235184, 4316.676249281317, 4373.6282415994365, 4729.888476208088, 4866.659333846596, 5263.9998400304585, 77796.0, 99476.0, 99620.0, 102488.48755756323, 104472.95527044784, 88908.0, 93903.0, 94541.5272923631, 95400.04599954051, 95541.0, 81715.5914220425, 82747.17252827516, 82890.0, 83654.0, 83920.58039709763, 8350.958245208734, 11401.429928503376, 11550.653480395602, 12730.045246606356, 12798.360081996048, 25670.8716456424, 28082.71917280883, 38392.84821455361, 40480.1903961925, 41587.75236742903, 66627.00059499014, 66655.66672166609, 66667.33332666806, 68795.65602172157, 69687.0, 15925.681486369976, 15996.200189990686, 16273.59316017109, 16462.341506339613, 16466.75299870479, 13658.59024229235, 16545.338186472887, 16598.917005414896, 16661.833381666027, 16772.58068548296, 16518.0, 16655.41706040283, 16738.0, 16775.0, 16820.0, 16228.269727862038, 16504.66990660156, 16512.926659767076, 16735.832641673915, 16745.497635070966, 16138.435154769526, 16359.2638331273, 16402.425915092914, 16495.34018639241, 16540.66918661596, 11724.120690948384, 11779.939805417554, 11828.70428239301, 12080.818787718015, 12288.938555307166, 12757.0, 13398.732025359634, 13517.797233041316, 13518.79721804154, 13661.32483428354], "value": 8226.943403691961, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 119.0, 116.0, 116.0, 116.0, 114.0, 113.0, 115.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 110.0, 122.0, 176.0, 111.0, 117.0, 113.0, 112.0, 138.0, 112.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 236.0, 197.0, 215.0, 238.0, 216.0, 249.0, 270.0, 197.0, 203.0], "value": 224.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 56.0, 56.0, 80.0, 59.0, 66.0, 61.0, 84.0, 70.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 82.0, 72.0, 76.0, 89.0, 90.0, 71.0, 71.0, 92.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 138.0, 119.0, 106.0, 136.0, 92.0, 94.0, 92.0, 93.0], "value": 106.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 101.0, 97.0, 98.0, 98.0, 98.0, 123.0, 98.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 90.0, 89.0, 130.0, 93.0, 89.0, 91.0, 89.0, 91.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 50.0, 50.0, 51.0, 50.0, 51.0, 50.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 65.0, 65.0, 69.0, 61.0, 55.0, 56.0, 57.0, 59.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 86.0, 86.0, 82.0, 82.0, 85.0, 87.0, 85.0, 97.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 138.0, 141.0, 139.0, 144.0, 159.0, 160.0, 194.0, 141.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 188.0, 190.0, 186.0, 187.0, 182.0, 188.0, 180.0, 186.0, 183.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 70.0, 70.0, 69.0, 70.0, 67.0, 71.0, 69.0, 68.0], "value": 69.4, "unit": "score"}]}]} 04:04:56 INFO - Return code: 0 04:04:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:04:56 ERROR - # TBPL SUCCESS # 04:04:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:04:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:04:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:04:56 INFO - 2017-04-20 04:04:56.684 screenresolution[1659:10727] starting screenresolution argv=screenresolution get 04:04:56 INFO - 2017-04-20 04:04:56.687 screenresolution[1659:10727] Display 0: 1600x1200x32@60 04:04:56 INFO - 2017-04-20 04:04:56.694 screenresolution[1660:10730] starting screenresolution argv=screenresolution list 04:04:56 INFO - Available Modes on Display 0 04:04:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:04:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:04:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:04:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:04:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:04:56 INFO - Intel Iris: 04:04:56 INFO - Chipset Model: Intel Iris 04:04:56 INFO - Type: GPU 04:04:56 INFO - Bus: Built-In 04:04:56 INFO - VRAM (Dynamic, Max): 1536 MB 04:04:56 INFO - Vendor: Intel (0x8086) 04:04:56 INFO - Device ID: 0x0a2e 04:04:56 INFO - Revision ID: 0x0009 04:04:56 INFO - Displays: 04:04:56 INFO - Display: 04:04:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:04:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:04:56 INFO - Display Serial Number: 1600x1200 60 04:04:56 INFO - Main Display: Yes 04:04:56 INFO - Mirror: Off 04:04:56 INFO - Online: Yes 04:04:56 INFO - Rotation: Supported 04:04:56 INFO - Return code: 0 04:04:56 INFO - Running post-action listener: _resource_record_post_action 04:04:56 INFO - [mozharness: 2017-04-20 11:04:56.937621Z] Finished run-tests step (success) 04:04:56 INFO - Running post-run listener: _resource_record_post_run 04:04:57 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39344640; Write bytes: 518191104; Read time: 249; Write time: 1645 04:04:57 INFO - TinderboxPrint: CPU usage
54.1% 04:04:57 INFO - TinderboxPrint: I/O read bytes / time
39,344,640 / 249 04:04:57 INFO - TinderboxPrint: I/O write bytes / time
518,191,104 / 1,645 04:04:57 INFO - TinderboxPrint: CPU idle
2,194.8 (45.9%) 04:04:57 INFO - TinderboxPrint: CPU system
966.7 (20.2%) 04:04:57 INFO - TinderboxPrint: CPU user
1,624.0 (33.9%) 04:04:57 INFO - TinderboxPrint: Swap in / out
246,906,880 / 0 04:04:57 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207469056; Write bytes: 206022656; Read time: 15863; Write time: 448 04:04:57 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35334656; Write bytes: 304947200; Read time: 223; Write time: 1168 04:04:57 INFO - Running post-run listener: _upload_blobber_files 04:04:57 INFO - Blob upload gear active. 04:04:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:04:57 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 04:04:57 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'] 04:04:57 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 04:04:57 INFO - (blobuploader) - INFO - Open directory for files ... 04:04:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:04:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:58 INFO - (blobuploader) - INFO - Done attempting. 04:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:59 INFO - (blobuploader) - INFO - Done attempting. 04:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:00 INFO - (blobuploader) - INFO - Done attempting. 04:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:05:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:03 INFO - (blobuploader) - INFO - Done attempting. 04:05:03 INFO - (blobuploader) - INFO - Iteration through files over. 04:05:03 INFO - Return code: 0 04:05:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:05:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:05:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a961ea9a26417064ff65011b3838d344b02080cf519af64fbfaec475cb863ae3cb903191c2c39358967cad3cb0edb733b37a193ee5d3ff879fe21446594bc289", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82f4adc33aa2e5074d984ba53a2675cafb72b03f249eadb7872f6e5221d561973cf3003ca8997a9341b14f216f3a32b46b35a8637b48f114f04acc50b8dc4c7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73efa12ece8a36fa9def71f144f72a5fa36f3fd281865154551fc2771b6d77f8737ecb8db3f28ffc6debd772a05ddea1db96e1e01cd2d71c3506ab865a36a995", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68464c6befb816d66768e559f66bb6d3290e5fa8c125b53f1bf92a1b7f7ac4ac9641f3b5df91879027b30ace846848b659df8a335708e8d21698415edf1c2b38"} 04:05:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:05:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:05:03 INFO - Contents: 04:05:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a961ea9a26417064ff65011b3838d344b02080cf519af64fbfaec475cb863ae3cb903191c2c39358967cad3cb0edb733b37a193ee5d3ff879fe21446594bc289", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82f4adc33aa2e5074d984ba53a2675cafb72b03f249eadb7872f6e5221d561973cf3003ca8997a9341b14f216f3a32b46b35a8637b48f114f04acc50b8dc4c7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73efa12ece8a36fa9def71f144f72a5fa36f3fd281865154551fc2771b6d77f8737ecb8db3f28ffc6debd772a05ddea1db96e1e01cd2d71c3506ab865a36a995", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68464c6befb816d66768e559f66bb6d3290e5fa8c125b53f1bf92a1b7f7ac4ac9641f3b5df91879027b30ace846848b659df8a335708e8d21698415edf1c2b38"} 04:05:03 INFO - Running post-run listener: copy_logs_to_upload_dir 04:05:03 INFO - Copying logs to upload dir... 04:05:03 INFO - mkdir: /builds/slave/test/build/upload/logs 04:05:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.898657 ========= master_lag: 2.94 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-20 04:05:06.600978) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 04:05:06.605538) ========= 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.op8LGRd1nl/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.dpXC4JWuck/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a961ea9a26417064ff65011b3838d344b02080cf519af64fbfaec475cb863ae3cb903191c2c39358967cad3cb0edb733b37a193ee5d3ff879fe21446594bc289", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82f4adc33aa2e5074d984ba53a2675cafb72b03f249eadb7872f6e5221d561973cf3003ca8997a9341b14f216f3a32b46b35a8637b48f114f04acc50b8dc4c7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73efa12ece8a36fa9def71f144f72a5fa36f3fd281865154551fc2771b6d77f8737ecb8db3f28ffc6debd772a05ddea1db96e1e01cd2d71c3506ab865a36a995", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68464c6befb816d66768e559f66bb6d3290e5fa8c125b53f1bf92a1b7f7ac4ac9641f3b5df91879027b30ace846848b659df8a335708e8d21698415edf1c2b38"} build_url:https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/TYjExR9nTrahq3TTfwGFEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a961ea9a26417064ff65011b3838d344b02080cf519af64fbfaec475cb863ae3cb903191c2c39358967cad3cb0edb733b37a193ee5d3ff879fe21446594bc289", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82f4adc33aa2e5074d984ba53a2675cafb72b03f249eadb7872f6e5221d561973cf3003ca8997a9341b14f216f3a32b46b35a8637b48f114f04acc50b8dc4c7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73efa12ece8a36fa9def71f144f72a5fa36f3fd281865154551fc2771b6d77f8737ecb8db3f28ffc6debd772a05ddea1db96e1e01cd2d71c3506ab865a36a995", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68464c6befb816d66768e559f66bb6d3290e5fa8c125b53f1bf92a1b7f7ac4ac9641f3b5df91879027b30ace846848b659df8a335708e8d21698415edf1c2b38"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 04:05:06.821954) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 04:05:06.822334) ========= 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.op8LGRd1nl/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.dpXC4JWuck/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.005269 ========= master_lag: 0.28 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 04:05:07.105778) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 04:05:07.109588) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 04:05:07.109929) ========= ========= Total master_lag: 3.49 =========