builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0185 starttime: 1489761648.52 results: success (0) buildid: 20170317062921 builduid: c38e389aa09c40e2b720fe9e8b2d268d revision: 9a95ade0ec97a871f97c397261fc7297e897331f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.523122) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.523607) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.523921) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.551935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.552422) ========= 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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-17 07:40:48-- 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% 15.3M=0.001s 2017-03-17 07:40:48 (15.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131073 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.710091) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.710457) ========= 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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.028202 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:48.761485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 07:40:48.761845) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9a95ade0ec97a871f97c397261fc7297e897331f --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 9a95ade0ec97a871f97c397261fc7297e897331f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vpinBz7TOz/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.9ZpyJ91KjB/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-17 07:40:48,866 truncating revision to first 12 chars 2017-03-17 07:40:48,866 Setting DEBUG logging. 2017-03-17 07:40:48,866 attempt 1/10 2017-03-17 07:40:48,866 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9a95ade0ec97?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-17 07:40:50,475 unpacking tar archive at: mozilla-central-9a95ade0ec97/testing/mozharness/ program finished with exit code 0 elapsedTime=1.883497 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 07:40:50.668987) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:50.669329) ========= script_repo_revision: 9a95ade0ec97a871f97c397261fc7297e897331f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:50.669715) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:50.669987) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 07:40:50.689831) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-17 07:40:50.690230) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vpinBz7TOz/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.9ZpyJ91KjB/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:40:50 INFO - MultiFileLogger online at 20170317 07:40:50 in /builds/slave/test 07:40:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:40:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:40:50 INFO - {'append_to_log': False, 07:40:50 INFO - 'base_work_dir': '/builds/slave/test', 07:40:50 INFO - 'blob_upload_branch': 'Firefox', 07:40:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:40:50 INFO - 'branch': 'Firefox', 07:40:50 INFO - 'buildbot_json_path': 'buildprops.json', 07:40:50 INFO - 'config_files': ('talos/mac_config.py',), 07:40:50 INFO - 'default_actions': ('clobber', 07:40:50 INFO - 'read-buildbot-config', 07:40:50 INFO - 'download-and-extract', 07:40:50 INFO - 'populate-webroot', 07:40:50 INFO - 'create-virtualenv', 07:40:50 INFO - 'install', 07:40:50 INFO - 'run-tests'), 07:40:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:40:50 INFO - 'download_minidump_stackwalk': True, 07:40:50 INFO - 'download_symbols': 'ondemand', 07:40:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:40:50 INFO - 'tooltool.py': '/tools/tooltool.py', 07:40:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:40:50 INFO - '/tools/misc-python/virtualenv.py')}, 07:40:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:40:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:40:50 INFO - 'gecko_profile': False, 07:40:50 INFO - 'gecko_profile_interval': 0, 07:40:50 INFO - 'installer_path': 'installer.exe', 07:40:50 INFO - 'log_level': 'info', 07:40:50 INFO - 'log_name': 'talos', 07:40:50 INFO - 'log_to_console': True, 07:40:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:40:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:40:50 INFO - 'opt_config_files': (), 07:40:50 INFO - 'pip_index': False, 07:40:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:40:50 INFO - 'cmd': ('bash', 07:40:50 INFO - '-c', 07:40:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:40:50 INFO - 'enabled': True, 07:40:50 INFO - 'halt_on_failure': False, 07:40:50 INFO - 'name': 'check_screen_resolution'},), 07:40:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:40:50 INFO - 'cmd': ('bash', 07:40:50 INFO - '-c', 07:40:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:40:50 INFO - 'enabled': True, 07:40:50 INFO - 'halt_on_failure': False, 07:40:50 INFO - 'name': 'check_screen_resolution'},), 07:40:50 INFO - 'run_cmd_checks_enabled': True, 07:40:50 INFO - 'suite': 'dromaeojs', 07:40:50 INFO - 'system_bits': '32', 07:40:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:40:50 INFO - 'title': 't-yosemite-r7-0185', 07:40:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:40:50 INFO - 'use_talos_json': True, 07:40:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:40:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:40:50 INFO - 'work_dir': 'build'} 07:40:50 INFO - [mozharness: 2017-03-17 14:40:50.909971Z] Running clobber step. 07:40:50 INFO - Running pre-action listener: _resource_record_pre_action 07:40:50 INFO - Running main action method: clobber 07:40:50 INFO - rmtree: /builds/slave/test/build 07:40:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:40:53 INFO - Running post-action listener: _resource_record_post_action 07:40:53 INFO - [mozharness: 2017-03-17 14:40:53.351816Z] Finished clobber step (success) 07:40:53 INFO - [mozharness: 2017-03-17 14:40:53.351941Z] Running read-buildbot-config step. 07:40:53 INFO - Running pre-action listener: _resource_record_pre_action 07:40:53 INFO - Running main action method: read_buildbot_config 07:40:53 INFO - Using buildbot properties: 07:40:53 INFO - { 07:40:53 INFO - "project": "", 07:40:53 INFO - "product": "firefox", 07:40:53 INFO - "script_repo_revision": "production", 07:40:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:40:53 INFO - "repository": "", 07:40:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:40:53 INFO - "buildid": "20170317062921", 07:40:53 INFO - "pgo_build": "False", 07:40:53 INFO - "basedir": "/builds/slave/test", 07:40:53 INFO - "buildnumber": 73, 07:40:53 INFO - "slavename": "t-yosemite-r7-0185", 07:40:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:40:53 INFO - "platform": "yosemite_r7", 07:40:53 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:40:53 INFO - "branch": "mozilla-central", 07:40:53 INFO - "repo_path": "mozilla-central", 07:40:53 INFO - "revision": "9a95ade0ec97a871f97c397261fc7297e897331f", 07:40:53 INFO - "stage_platform": "macosx64", 07:40:53 INFO - "builduid": "c38e389aa09c40e2b720fe9e8b2d268d", 07:40:53 INFO - "slavebuilddir": "test" 07:40:53 INFO - } 07:40:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:40:53 INFO - Running post-action listener: _resource_record_post_action 07:40:53 INFO - [mozharness: 2017-03-17 14:40:53.355122Z] Finished read-buildbot-config step (success) 07:40:53 INFO - [mozharness: 2017-03-17 14:40:53.355256Z] Running download-and-extract step. 07:40:53 INFO - Running pre-action listener: _resource_record_pre_action 07:40:53 INFO - Running main action method: download_and_extract 07:40:53 INFO - mkdir: /builds/slave/test/build/tests 07:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:40:53 INFO - trying https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:40:53 INFO - Downloading https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/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 07:40:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/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 07:40:54 INFO - Downloaded 1325 bytes. 07:40:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:40:54 INFO - Using the following test package requirements: 07:40:54 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:40:54 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:40:54 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:40:54 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:40:54 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'jsshell-mac.zip'], 07:40:54 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:40:54 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:40:54 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:40:54 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:40:54 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:40:54 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:40:54 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:40:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:40:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:40:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:40:54 INFO - Fetch https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:40:56 INFO - Expected file size: 52472635 07:40:56 INFO - Obtained file size: 52472635 07:41:12 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 07:41:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:41:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:41:12 INFO - Fetch https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:41:14 INFO - Expected file size: 52472635 07:41:14 INFO - Obtained file size: 52472635 07:41:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:41:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:41:29 INFO - Fetch https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:41:31 INFO - Expected file size: 14404171 07:41:31 INFO - Obtained file size: 14404171 07:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:31 INFO - trying https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:41:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:41:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/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 07:41:33 INFO - Downloaded 75069483 bytes. 07:41:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:41:33 INFO - mkdir: /builds/slave/test/properties 07:41:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:41:33 INFO - Writing to file /builds/slave/test/properties/build_url 07:41:33 INFO - Contents: 07:41:33 INFO - build_url:https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:41:34 INFO - Running post-action listener: _resource_record_post_action 07:41:34 INFO - Running post-action listener: set_extra_try_arguments 07:41:34 INFO - [mozharness: 2017-03-17 14:41:34.551141Z] Finished download-and-extract step (success) 07:41:34 INFO - [mozharness: 2017-03-17 14:41:34.551265Z] Running populate-webroot step. 07:41:34 INFO - Running pre-action listener: _resource_record_pre_action 07:41:34 INFO - Running main action method: populate_webroot 07:41:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:41:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:41:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:41:34 INFO - '--tppagecycles', 07:41:34 INFO - '7'], 07:41:34 INFO - 'tests': ['tsvgm']}}, 07:41:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:41:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:41:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:41:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:41:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:41:34 INFO - 'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:41:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:41:34 INFO - 'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['damp', 'tps']}, 07:41:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:41:34 INFO - 'tests': ['damp', 'tps']}, 07:41:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['dromaeo_dom']}, 07:41:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:41:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['basic_compositor_video']}, 07:41:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:41:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['a11yr', 07:41:34 INFO - 'ts_paint', 07:41:34 INFO - 'tpaint', 07:41:34 INFO - 'sessionrestore', 07:41:34 INFO - 'sessionrestore_no_auto_restore', 07:41:34 INFO - 'tabpaint']}, 07:41:34 INFO - 'other-e10s': {'tests': ['a11yr', 07:41:34 INFO - 'ts_paint', 07:41:34 INFO - 'tpaint', 07:41:34 INFO - 'sessionrestore', 07:41:34 INFO - 'sessionrestore_no_auto_restore', 07:41:34 INFO - 'tabpaint']}, 07:41:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:41:34 INFO - 'ts_paint', 07:41:34 INFO - 'tpaint', 07:41:34 INFO - 'sessionrestore', 07:41:34 INFO - 'sessionrestore_no_auto_restore', 07:41:34 INFO - 'tabpaint']}, 07:41:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:41:34 INFO - 'ts_paint', 07:41:34 INFO - 'tpaint', 07:41:34 INFO - 'sessionrestore', 07:41:34 INFO - 'sessionrestore_no_auto_restore', 07:41:34 INFO - 'tabpaint']}, 07:41:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['a11yr', 07:41:34 INFO - 'ts_paint', 07:41:34 INFO - 'tpaint', 07:41:34 INFO - 'sessionrestore', 07:41:34 INFO - 'sessionrestore_no_auto_restore', 07:41:34 INFO - 'tabpaint']}, 07:41:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['a11yr', 07:41:34 INFO - 'ts_paint', 07:41:34 INFO - 'tpaint', 07:41:34 INFO - 'sessionrestore', 07:41:34 INFO - 'sessionrestore_no_auto_restore', 07:41:34 INFO - 'tabpaint']}, 07:41:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['tsvgx', 07:41:34 INFO - 'tsvgr_opacity', 07:41:34 INFO - 'tart', 07:41:34 INFO - 'tscrollx', 07:41:34 INFO - 'cart', 07:41:34 INFO - 'tsvg_static']}, 07:41:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:41:34 INFO - 'tsvgr_opacity', 07:41:34 INFO - 'tart', 07:41:34 INFO - 'tscrollx', 07:41:34 INFO - 'cart', 07:41:34 INFO - 'tsvg_static']}, 07:41:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:41:34 INFO - 'talos_options': ['--disable-e10s'], 07:41:34 INFO - 'tests': ['tp5o']}, 07:41:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:41:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:41:34 INFO - 'talos_options': ['--disable-e10s', 07:41:34 INFO - '--xperf_path', 07:41:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:41:34 INFO - 'tests': ['tp5n']}, 07:41:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:41:34 INFO - 'talos_options': ['--xperf_path', 07:41:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:41:34 INFO - 'tests': ['tp5n']}}, 07:41:34 INFO - 'talos.zip': {'path': '', 07:41:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:41:34 INFO - Running post-action listener: _resource_record_post_action 07:41:34 INFO - [mozharness: 2017-03-17 14:41:34.569707Z] Finished populate-webroot step (success) 07:41:34 INFO - [mozharness: 2017-03-17 14:41:34.569844Z] Running create-virtualenv step. 07:41:34 INFO - Running pre-action listener: _resource_record_pre_action 07:41:34 INFO - Running main action method: create_virtualenv 07:41:34 INFO - Creating virtualenv /builds/slave/test/build/venv 07:41:34 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 07:41:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:41:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:41:34 INFO - Using real prefix '/tools/python27' 07:41:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:41:35 INFO - Installing distribute.............................................................................................................................................................................................done. 07:41:38 INFO - Installing pip.................done. 07:41:38 INFO - Return code: 0 07:41:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:41:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:41:38 INFO - Reading from file tmpfile_stdout 07:41:38 INFO - Output received: 07:41:38 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) 07:41:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:38 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:41:38 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 07:41:38 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 07:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:38 INFO - 'HOME': '/Users/cltbld', 07:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:38 INFO - 'LOGNAME': 'cltbld', 07:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:38 INFO - 'MOZ_NO_REMOTE': '1', 07:41:38 INFO - 'NO_EM_RESTART': '1', 07:41:38 INFO - 'PAGER': '/bin/cat', 07:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:38 INFO - 'PWD': '/builds/slave/test', 07:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:38 INFO - 'SHELL': '/bin/bash', 07:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:41:38 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:38 INFO - 'USER': 'cltbld', 07:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:38 INFO - 'XPC_FLAGS': '0x0', 07:41:38 INFO - 'XPC_SERVICE_NAME': '0', 07:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:38 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 07:41:38 INFO - Cleaning up... 07:41:38 INFO - Return code: 0 07:41:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:38 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:41:38 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 07:41:38 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 07:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:38 INFO - 'HOME': '/Users/cltbld', 07:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:38 INFO - 'LOGNAME': 'cltbld', 07:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:38 INFO - 'MOZ_NO_REMOTE': '1', 07:41:38 INFO - 'NO_EM_RESTART': '1', 07:41:38 INFO - 'PAGER': '/bin/cat', 07:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:38 INFO - 'PWD': '/builds/slave/test', 07:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:38 INFO - 'SHELL': '/bin/bash', 07:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:41:38 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:38 INFO - 'USER': 'cltbld', 07:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:38 INFO - 'XPC_FLAGS': '0x0', 07:41:38 INFO - 'XPC_SERVICE_NAME': '0', 07:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:39 INFO - Downloading/unpacking psutil>=3.1.1 07:41:39 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 07:41:39 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 07:41:39 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 07:41:39 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 07:41:39 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 07:41:39 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 07:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:41:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:42 INFO - Installing collected packages: psutil 07:41:42 INFO - Running setup.py install for psutil 07:41:42 INFO - building 'psutil._psutil_osx' extension 07:41:42 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 07:41:43 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 07:41:43 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 07:41:43 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 07:41:43 INFO - building 'psutil._psutil_posix' extension 07:41:43 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 07:41:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:41:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:41:43 INFO - ^ 07:41:43 INFO - 1 warning generated. 07:41:43 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 07:41:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:43 INFO - Successfully installed psutil 07:41:43 INFO - Cleaning up... 07:41:43 INFO - Return code: 0 07:41:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41: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')]} 07:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:41: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:41: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 mozsystemmonitor==0.3 07:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:44 INFO - 'HOME': '/Users/cltbld', 07:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:44 INFO - 'LOGNAME': 'cltbld', 07:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:44 INFO - 'MOZ_NO_REMOTE': '1', 07:41:44 INFO - 'NO_EM_RESTART': '1', 07:41:44 INFO - 'PAGER': '/bin/cat', 07:41:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:44 INFO - 'PWD': '/builds/slave/test', 07:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:44 INFO - 'SHELL': '/bin/bash', 07:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:41:44 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:44 INFO - 'USER': 'cltbld', 07:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:44 INFO - 'XPC_FLAGS': '0x0', 07:41:44 INFO - 'XPC_SERVICE_NAME': '0', 07:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:41: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 07:41: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 07:41: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 07:41: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 07:41: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 07:41: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 07:41:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:41:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:41:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:41:47 INFO - Installing collected packages: mozsystemmonitor 07:41:47 INFO - Running setup.py install for mozsystemmonitor 07:41:48 INFO - Successfully installed mozsystemmonitor 07:41:48 INFO - Cleaning up... 07:41:48 INFO - Return code: 0 07:41:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:48 INFO - 'HOME': '/Users/cltbld', 07:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:48 INFO - 'LOGNAME': 'cltbld', 07:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:48 INFO - 'MOZ_NO_REMOTE': '1', 07:41:48 INFO - 'NO_EM_RESTART': '1', 07:41:48 INFO - 'PAGER': '/bin/cat', 07:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:48 INFO - 'PWD': '/builds/slave/test', 07:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:48 INFO - 'SHELL': '/bin/bash', 07:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:41:48 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:48 INFO - 'USER': 'cltbld', 07:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:48 INFO - 'XPC_FLAGS': '0x0', 07:41:48 INFO - 'XPC_SERVICE_NAME': '0', 07:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:48 INFO - Downloading/unpacking jsonschema==2.5.1 07:41: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 07:41: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 07:41: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 07:41: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 07:41: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 07:41: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 07:41:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:41:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:41:51 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 07:41:51 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 07:41:51 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 07:41:51 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 07:41:51 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 07:41:51 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 07:41:51 INFO - Downloading functools32-3.2.3-2.tar.gz 07:41:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:41:51 INFO - warning: no files found matching '*.txt' 07:41:51 INFO - no previously-included directories found matching 'build' 07:41:51 INFO - no previously-included directories found matching 'dist' 07:41:51 INFO - no previously-included directories found matching '.git*' 07:41:51 INFO - Installing collected packages: jsonschema, functools32 07:41:51 INFO - Running setup.py install for functools32 07:41:51 INFO - warning: no files found matching '*.txt' 07:41:51 INFO - no previously-included directories found matching 'build' 07:41:51 INFO - no previously-included directories found matching 'dist' 07:41:51 INFO - no previously-included directories found matching '.git*' 07:41:51 INFO - Successfully installed jsonschema functools32 07:41:51 INFO - Cleaning up... 07:41:51 INFO - Return code: 0 07:41:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:51 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:41:51 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 07:41:51 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 07:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:51 INFO - 'HOME': '/Users/cltbld', 07:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:51 INFO - 'LOGNAME': 'cltbld', 07:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:51 INFO - 'MOZ_NO_REMOTE': '1', 07:41:51 INFO - 'NO_EM_RESTART': '1', 07:41:51 INFO - 'PAGER': '/bin/cat', 07:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:51 INFO - 'PWD': '/builds/slave/test', 07:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:51 INFO - 'SHELL': '/bin/bash', 07:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:41:51 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:51 INFO - 'USER': 'cltbld', 07:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:51 INFO - 'XPC_FLAGS': '0x0', 07:41:51 INFO - 'XPC_SERVICE_NAME': '0', 07:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:41:52 INFO - Cleaning up... 07:41:52 INFO - Return code: 0 07:41:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:52 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:41:52 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 07:41:52 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 07:41:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:41:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:52 INFO - 'HOME': '/Users/cltbld', 07:41:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:52 INFO - 'LOGNAME': 'cltbld', 07:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:52 INFO - 'MOZ_NO_REMOTE': '1', 07:41:52 INFO - 'NO_EM_RESTART': '1', 07:41:52 INFO - 'PAGER': '/bin/cat', 07:41:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:52 INFO - 'PWD': '/builds/slave/test', 07:41:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:52 INFO - 'SHELL': '/bin/bash', 07:41:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:41:52 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:41:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:52 INFO - 'USER': 'cltbld', 07:41:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:52 INFO - 'XPC_FLAGS': '0x0', 07:41:52 INFO - 'XPC_SERVICE_NAME': '0', 07:41:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:52 INFO - Downloading/unpacking blobuploader==1.2.4 07:41:52 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 07:41:52 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 07:41:52 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 07:41:52 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 07:41:52 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 07:41:52 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 07:41:54 INFO - Downloading blobuploader-1.2.4.tar.gz 07:41:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:41:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:41:54 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 07:41:54 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 07:41:54 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 07:41:54 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 07:41:54 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 07:41:54 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 07:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:41:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:41:55 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 07:41:55 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 07:41:55 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 07:41:55 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 07:41:55 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 07:41:55 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 07:41:56 INFO - Downloading docopt-0.6.1.tar.gz 07:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:41:56 INFO - Installing collected packages: blobuploader, requests, docopt 07:41:56 INFO - Running setup.py install for blobuploader 07:41:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:41:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:41:56 INFO - Running setup.py install for requests 07:41:56 INFO - Running setup.py install for docopt 07:41:56 INFO - Successfully installed blobuploader requests docopt 07:41:56 INFO - Cleaning up... 07:41:57 INFO - Return code: 0 07:41:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:57 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:41:57 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 07:41:57 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 07:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:57 INFO - 'HOME': '/Users/cltbld', 07:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:57 INFO - 'LOGNAME': 'cltbld', 07:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:57 INFO - 'MOZ_NO_REMOTE': '1', 07:41:57 INFO - 'NO_EM_RESTART': '1', 07:41:57 INFO - 'PAGER': '/bin/cat', 07:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:57 INFO - 'PWD': '/builds/slave/test', 07:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:57 INFO - 'SHELL': '/bin/bash', 07:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:41:57 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:57 INFO - 'USER': 'cltbld', 07:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:57 INFO - 'XPC_FLAGS': '0x0', 07:41:57 INFO - 'XPC_SERVICE_NAME': '0', 07:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:41:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-tvEyD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:41:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-PWGBNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:41:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-TrEzpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:41:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-eOjgkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:41:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-aB8haL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:41:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-DFYtns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:41:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-mtgFhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-huW4Wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-gAll_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-9tBg_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-meDcvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-nynGH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-JAFJLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-EO_wBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-fiNaGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:41:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-tvfcIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:41:59 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-OfLnHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:41:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:41:59 INFO - Running setup.py install for manifestparser 07:41:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:41:59 INFO - Running setup.py install for mozcrash 07:41:59 INFO - Running setup.py install for mozdebug 07:41:59 INFO - Running setup.py install for mozdevice 07:41:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:41:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:41:59 INFO - Running setup.py install for mozfile 07:41:59 INFO - Running setup.py install for mozhttpd 07:42:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Running setup.py install for mozinfo 07:42:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Running setup.py install for mozInstall 07:42:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Running setup.py install for mozleak 07:42:00 INFO - Running setup.py install for mozlog 07:42:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Running setup.py install for moznetwork 07:42:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:42:00 INFO - Running setup.py install for mozprocess 07:42:00 INFO - Running setup.py install for mozprofile 07:42:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:42:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:42:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:42:01 INFO - Running setup.py install for mozrunner 07:42:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:42:01 INFO - Running setup.py install for mozscreenshot 07:42:01 INFO - Running setup.py install for moztest 07:42:01 INFO - Running setup.py install for mozversion 07:42:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:42:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:42:01 INFO - Cleaning up... 07:42:01 INFO - Return code: 0 07:42:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:01 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:42:01 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 07:42:01 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 07:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:01 INFO - 'HOME': '/Users/cltbld', 07:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:01 INFO - 'LOGNAME': 'cltbld', 07:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:01 INFO - 'MOZ_NO_REMOTE': '1', 07:42:01 INFO - 'NO_EM_RESTART': '1', 07:42:01 INFO - 'PAGER': '/bin/cat', 07:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:01 INFO - 'PWD': '/builds/slave/test', 07:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:01 INFO - 'SHELL': '/bin/bash', 07:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:42:01 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:01 INFO - 'USER': 'cltbld', 07:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:01 INFO - 'XPC_FLAGS': '0x0', 07:42:01 INFO - 'XPC_SERVICE_NAME': '0', 07:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:01 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-nxoI1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-2AyKvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-AiwlUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-QHUF8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Og9tmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ad6Eoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-iD2rj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-3Q3Ed5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-8R88r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:02 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)) 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-iXG_zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:03 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)) 07:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-SVr6ZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:03 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)) 07:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-4mSPu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:03 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)) 07:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-rxEum4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:03 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)) 07:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Xrkz_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:03 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)) 07:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-fjiGrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:03 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)) 07:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-alVvVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:03 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)) 07:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-AM5chi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:03 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)) 07:42:03 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)) 07:42:03 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)) 07:42:03 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)) 07:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:42:03 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 07:42:03 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 07:42:03 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 07:42:03 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 07:42:03 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 07:42:03 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 07:42:06 INFO - Downloading blessings-1.6.tar.gz 07:42:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:42:06 INFO - Installing collected packages: blessings 07:42:06 INFO - Running setup.py install for blessings 07:42:06 INFO - Successfully installed blessings 07:42:06 INFO - Cleaning up... 07:42:06 INFO - Return code: 0 07:42:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:42:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:06 INFO - Reading from file tmpfile_stdout 07:42:06 INFO - Current package versions: 07:42:06 INFO - blessings == 1.6 07:42:06 INFO - blobuploader == 1.2.4 07:42:06 INFO - docopt == 0.6.1 07:42:06 INFO - functools32 == 3.2.3-2 07:42:06 INFO - jsonschema == 2.5.1 07:42:06 INFO - manifestparser == 1.1 07:42:06 INFO - mozInstall == 1.12 07:42:06 INFO - mozcrash == 1.0 07:42:06 INFO - mozdebug == 0.1 07:42:06 INFO - mozdevice == 0.49 07:42:06 INFO - mozfile == 1.2 07:42:06 INFO - mozhttpd == 0.7 07:42:06 INFO - mozinfo == 0.9 07:42:06 INFO - mozleak == 0.1 07:42:06 INFO - mozlog == 3.4 07:42:06 INFO - moznetwork == 0.27 07:42:06 INFO - mozprocess == 0.25 07:42:06 INFO - mozprofile == 0.28 07:42:06 INFO - mozrunner == 6.13 07:42:06 INFO - mozscreenshot == 0.1 07:42:06 INFO - mozsystemmonitor == 0.3 07:42:06 INFO - moztest == 0.8 07:42:06 INFO - mozversion == 1.4 07:42:06 INFO - psutil == 3.1.1 07:42:06 INFO - requests == 1.2.3 07:42:06 INFO - wsgiref == 0.1.2 07:42:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:06 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:42:06 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 07:42:06 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 07:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:07 INFO - 'HOME': '/Users/cltbld', 07:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:07 INFO - 'LOGNAME': 'cltbld', 07:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:07 INFO - 'MOZ_NO_REMOTE': '1', 07:42:07 INFO - 'NO_EM_RESTART': '1', 07:42:07 INFO - 'PAGER': '/bin/cat', 07:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:07 INFO - 'PWD': '/builds/slave/test', 07:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:07 INFO - 'SHELL': '/bin/bash', 07:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:42:07 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:07 INFO - 'USER': 'cltbld', 07:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:07 INFO - 'XPC_FLAGS': '0x0', 07:42:07 INFO - 'XPC_SERVICE_NAME': '0', 07:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 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)) 07:42:07 INFO - Cleaning up... 07:42:07 INFO - Return code: 0 07:42:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:07 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 0x100b76b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b79768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe468cd5120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, '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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:42:07 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 07:42:07 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 07:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:07 INFO - 'HOME': '/Users/cltbld', 07:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:07 INFO - 'LOGNAME': 'cltbld', 07:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:07 INFO - 'MOZ_NO_REMOTE': '1', 07:42:07 INFO - 'NO_EM_RESTART': '1', 07:42:07 INFO - 'PAGER': '/bin/cat', 07:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:07 INFO - 'PWD': '/builds/slave/test', 07:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:07 INFO - 'SHELL': '/bin/bash', 07:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:42:07 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:07 INFO - 'USER': 'cltbld', 07:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:07 INFO - 'XPC_FLAGS': '0x0', 07:42:07 INFO - 'XPC_SERVICE_NAME': '0', 07:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:42:07 INFO - Cleaning up... 07:42:07 INFO - Return code: 0 07:42:07 INFO - Running post-action listener: _resource_record_post_action 07:42:07 INFO - Running post-action listener: _start_resource_monitoring 07:42:07 INFO - Starting resource monitoring. 07:42:07 INFO - [mozharness: 2017-03-17 14:42:07.563687Z] Finished create-virtualenv step (success) 07:42:07 INFO - [mozharness: 2017-03-17 14:42:07.564626Z] Running install step. 07:42:07 INFO - Running pre-action listener: _resource_record_pre_action 07:42:07 INFO - Running main action method: install 07:42:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:07 INFO - Reading from file tmpfile_stdout 07:42:07 INFO - Detecting whether we're running mozinstall >=1.0... 07:42:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:42:07 INFO - Reading from file tmpfile_stdout 07:42:07 INFO - Output received: 07:42:07 INFO - Usage: mozinstall [options] installer 07:42:07 INFO - Options: 07:42:07 INFO - -h, --help show this help message and exit 07:42:07 INFO - -d DEST, --destination=DEST 07:42:07 INFO - Directory to install application into. [default: 07:42:07 INFO - "/builds/slave/test"] 07:42:07 INFO - --app=APP Application being installed. [default: firefox] 07:42:07 INFO - mkdir: /builds/slave/test/build/application 07:42:07 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'] 07:42:07 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 07:42:27 INFO - Reading from file tmpfile_stdout 07:42:27 INFO - Output received: 07:42:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:42:27 INFO - Running post-action listener: _resource_record_post_action 07:42:27 INFO - [mozharness: 2017-03-17 14:42:27.974573Z] Finished install step (success) 07:42:27 INFO - [mozharness: 2017-03-17 14:42:27.974708Z] Running run-tests step. 07:42:27 INFO - Running pre-action listener: _resource_record_pre_action 07:42:27 INFO - Running main action method: run_tests 07:42:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:42:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:42:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:42:27 INFO - 2017-03-17 07:42:27.988 screenresolution[1623:7389] starting screenresolution argv=screenresolution get 07:42:27 INFO - 2017-03-17 07:42:27.991 screenresolution[1623:7389] Display 0: 1600x1200x32@60 07:42:27 INFO - 2017-03-17 07:42:27.997 screenresolution[1624:7392] starting screenresolution argv=screenresolution list 07:42:28 INFO - Available Modes on Display 0 07:42:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:42:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:42:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:42:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:42:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:42:28 INFO - Intel Iris: 07:42:28 INFO - Chipset Model: Intel Iris 07:42:28 INFO - Type: GPU 07:42:28 INFO - Bus: Built-In 07:42:28 INFO - VRAM (Dynamic, Max): 1536 MB 07:42:28 INFO - Vendor: Intel (0x8086) 07:42:28 INFO - Device ID: 0x0a2e 07:42:28 INFO - Revision ID: 0x0009 07:42:28 INFO - Displays: 07:42:28 INFO - Display: 07:42:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:42:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:42:28 INFO - Display Serial Number: 1600x1200 60 07:42:28 INFO - Main Display: Yes 07:42:28 INFO - Mirror: Off 07:42:28 INFO - Online: Yes 07:42:28 INFO - Rotation: Supported 07:42:28 INFO - Return code: 0 07:42:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:42:28 INFO - Python 2.7.3 07:42:28 INFO - Return code: 0 07:42:28 INFO - grabbing minidump binary from tooltool 07:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:28 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 0x100954d30>, '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 0x10087bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100949be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:42:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:42:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:42:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:42:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:42:28 INFO - Return code: 0 07:42:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:42:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:42:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:42:28 INFO - ENV: RUST_BACKTRACE is now 1 07:42:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:42:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:42:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0185 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:42:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vpinBz7TOz/Render', 07:42:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:28 INFO - 'HOME': '/Users/cltbld', 07:42:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:28 INFO - 'LOGNAME': 'cltbld', 07:42:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:42:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:28 INFO - 'MOZ_NO_REMOTE': '1', 07:42:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:42:28 INFO - 'NO_EM_RESTART': '1', 07:42:28 INFO - 'PAGER': '/bin/cat', 07:42:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:28 INFO - 'PWD': '/builds/slave/test', 07:42:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:42:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:28 INFO - 'RUST_BACKTRACE': '1', 07:42:28 INFO - 'SHELL': '/bin/bash', 07:42:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9ZpyJ91KjB/Listeners', 07:42:28 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:42:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:28 INFO - 'USER': 'cltbld', 07:42:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:28 INFO - 'XPC_FLAGS': '0x0', 07:42:28 INFO - 'XPC_SERVICE_NAME': '0', 07:42:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:42:28 INFO - mozversion application_buildid: 20170317062921 07:42:28 INFO - mozversion application_changeset: 9a95ade0ec97a871f97c397261fc7297e897331f 07:42:28 INFO - mozversion application_display_name: Nightly 07:42:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:42:28 INFO - mozversion application_name: Firefox 07:42:28 INFO - mozversion application_remotingname: firefox 07:42:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:42:28 INFO - mozversion application_vendor: Mozilla 07:42:28 INFO - mozversion application_version: 55.0a1 07:42:28 INFO - mozversion platform_buildid: 20170317062921 07:42:28 INFO - mozversion platform_changeset: 9a95ade0ec97a871f97c397261fc7297e897331f 07:42:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:42:28 INFO - mozversion platform_version: 55.0a1 07:42:28 INFO - using testdate: 1489761748 07:42:28 INFO - actual date: 1489761748 07:42:28 INFO - starting webserver on 'localhost:49240' 07:42:28 INFO - SUITE-START | Running 2 tests 07:42:28 INFO - TEST-START | dromaeo_css 07:42:28 INFO - operating with platform_type : mac_ 07:42:28 INFO - Initialising browser for dromaeo_css test... 07:42:28 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmp9k36JO/profile http://localhost:49240/getInfo.html) 07:42:31 INFO - PID 1634 | __metrics Screen width/height:1600/1200 07:42:31 INFO - PID 1634 | colorDepth:24 07:42:31 INFO - PID 1634 | Browser inner width/height: 1024/689 07:42:31 INFO - PID 1634 | __metrics 07:42:31 INFO - PID 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:42:31 INFO - PID 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:42:32 INFO - TEST-INFO | 1634: exit 0 07:42:32 INFO - Browser initialized. 07:42:32 INFO - Running cycle 1/1 for dromaeo_css test... 07:42:32 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmp9k36JO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:42:44 INFO - PID 1636 | RSS: Main: 233803776 07:42:44 INFO - PID 1636 | 07:45:46 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 07:45:50 INFO - PID 1636 | RSS: Main: 522096640 07:45:50 INFO - PID 1636 | 07:47:33 INFO - PID 1636 | ************************* 07:47:33 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 07:47:33 INFO - PID 1636 | 07:47:33 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:47:33 INFO - PID 1636 | Full stack: 07:47:33 INFO - PID 1636 | ************************* 07:48:53 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 07:48:54 INFO - PID 1636 | RSS: Main: 360816640 07:48:54 INFO - PID 1636 | 07:51:56 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 07:51:57 INFO - PID 1636 | RSS: Main: 343621632 07:51:57 INFO - PID 1636 | 07:54:59 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 07:55:00 INFO - PID 1636 | RSS: Main: 357703680 07:55:00 INFO - PID 1636 | 07:58:04 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 07:58:05 INFO - PID 1636 | RSS: Main: 331149312 07:58:05 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 08:01:08 INFO - PID 1636 | RSS: Main: 418992128 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | __start_tp_report 08:01:08 INFO - PID 1636 | _x_x_mozilla_page_load 08:01:08 INFO - PID 1636 | _x_x_mozilla_page_load_details 08:01:08 INFO - PID 1636 | |i|pagename|runs| 08:01:08 INFO - PID 1636 | |0;dojo.html;3338.3990881641303;3338.515915192297;3344.4648856183017;3373.2578832656814;3374.460086386177;5472.9179062314;5557.110862262039;5626.2967129108865;5638.971805140974;5649.1526271059365;5201.297824793652;5606.439356064391;5607.719614019303;5680.488756011958;5719.342275638292;5044.949550504503;5094.592432605392;5134.229865520165;5143;5167.095758242312;5005.474425185358;5089.338386009832;5217.399999000126;5221.190715439113;5253.763580638862;5086.491350864884;5113.539781419653;5115.462876397962;5196.58427325815;5212.322398088262;4379.255526560461;5132.230165475171;5144.4083930348115;5172.948270517284;5226.372835259755;5106.514881086291;5115.897682046339;5144.819931302386;5147.253648221024;5204.141316682742;5512;5587.189857470644;5625.493705566478;5626.859328516779;5627.212190293362;5601.523870471015;5690.317161940552;6012.248468941382;6139.829471381233;6205.03821907605;5231.424543300234;6126.846828829271;6223;6229.0967809667345;6248.468880145193;5721.9427805721825;5733.111367738007;5745.195672605839;5750.108733146369;5750.654960702372;6136.693165341715;6178.845528861814;6205.844853878689;6211.875762484725;6219.937800622027;6064.575479716465;6083.604565703304;6090.482309003784;6132.386761323832;6138.754449821957;6201;6228.719707613168;6230.127782110508;6240.095186198067;6243.063540468966;6041.667708276087;6100.938990610126;6105.664188469588;6157.9384206158265;6181.629102253879;6072.605280656743;6114.602550834182;6187.474064704551;6198.721057552421;6221;4907.582855457326;4921.409430868247;4926.75366231687;4933.210686290176;4942.950570494249;4424.889377765518;4428.889277768018;4442.733435993786;4445.244308467568;4472.08322293929;5519.39286678465;5607.523360514402;5611.382747897728;5640.8871822563315;5657.915131273035;6089.8477538061015;6117.309816958451;6138.416850399205;6170.413810687977;6229.377062293825;5755.59710820247;5773.769049237983;5776.56675749312;5802.361740208574;5804.535637149018;5325.440828713007;5357.866053348697;5362.436944120889;5373.946260537423;5385.353757549041;6163.753449862134;6217.533684973736;6257;6264.091706702503;6268.655223962726;6106.2179897963915;6159.907601385893;6210;6218.160548326099;6220.564560480904;6192;6240.5319601031015;6249.812505624838;6263;6280.937190628035;6144.170536977451;6146.078088286793;6152;6159.322474527888;6191.81424557264;6043.516518678407;6078.3617720138745;6105.236845394325;6106.389361063858;6110.7555697771595;5546.500814926676;5703.686297253691;5712.685802280915;5742.196092546963;5743.741531631003;5681.233033540584;5714.399988001366;5725.370209277059;5731.770729170787;5736.225609542658;5583.944160558342;5642.717864106696;5650.604457687922;5655.943440565707;5657.745401456862;5620.690862002629;5751;5778;5779.566532509994;5800.158976948234;5645.971770141123;5649.774009039593;5650.689212093374;5651.124075768263;5656.519195868397 08:01:08 INFO - PID 1636 | |1;ext.html;774.2683164409632;793.1830214878676;855.2644725536038;867.8220964702233;869.0136694851342;34805;35419.64580354196;35579.82210088949;35733.64266357336;35797.82101089497;37356;37725.81137094318;37727.62272377275;37728.43407348892;37761;6263.217097862767;7837.4121940854375;8902.599383027748;9082.228010619105;9088;7263.455240856931;10470.895291047107;10722;10734.033936945674;10745.731356716067;10766.977137171956;10834.40410777407;10838;10845.891541084606;10855.51150198239;7120.786376408716;10829.404382758945;10930.070943969773;10942.726431839188;10959.726006849813;7178.641067946582;8637.913620863774;10458.267921245515;10477.37135771856;10491.79016419675;36851;37264.627353726384;37265;37374.626253737384;37412.62587374146;3791.412331088658;3801.7148713846573;3839.6928245740273;3867.883963481072;3941.3890846918775;27285.181444556496;27293;27329;27356;27553.86223068892;3042.307082871193;3097.5198844178963;3127.46833038384;3147.7324427423696;3200.727938125262;282379;282410.1758982404;282439;283089;283667;37324;37907.483700652214;45825.54174458213;47238.763806181436;47435;49274.26088608674;49543;49639.057750760425;49648.50351496511;49660.25509617358;13679.931600342134;13819.170849749047;13820.861791381958;13825.79261311081;13837.86162138386;11012.889871101186;11033.558657653764;11220.158488113424;11247.943760281147;11274.605388811353;13683.726325473435;13709.314534273446;13722.862771372358;13759.931200344134;13847.792283115761;4983.626228032912;5848.64908105515;5866.208061911588;5891.646501209855;5909.438603332676;1142.959996400122;1143.3997151495532;1150.511032811052;1152.976940461203;1194.3371428856894;24438.389040274134;25773.3556661085;25821.483570328488;25852.612210816853;25853.09514166995;1035.135661722455;1048.7378155461136;1053.3311347294523;1094.8084085285202;1103.0458653264868;13903.4438622456;14145.85854141466;14168;14277.846961405408;14386.856131438552;422778;425987;426884;427387;427616;52214.95570088577;52585;52599;52933.735331323864;52987;1420.7016526529337;1446.8842492600568;1448.9347979340744;1449.173970836613;1451.9854801452273;26038.34904127412;26468.162116493386;26996.32509187286;27306;27316.453670926072;43610.563894360654;43850.684479465664;43942.560574395124;43960.34059489046;43987.78006109949;421355;422109;422231;423007;425377;21845.235416760337;25386.619200711633;25467.872660636578;30128;32610;38363;38486.42270365891;38575.807120964215;38615.42076868793;38627.8411647651;30166;30772;30998.22504437407;31791;33683.21229817094;32571.34857302888;32705;32764.180895477803;33657.663423365455;33709.662903370656;32353.191170220933;32965.34069318648;33047.33905321928;33054.008379748644;33085.33829323448;26117.477650447265;29261;29383.853080734458;29468.557971630013;29707.774452387508;22605;22936.85315734173;22945.655815162452;22953;23031.769682302962 08:01:08 INFO - PID 1636 | |2;jquery.html;404.41965779107;410.55044726024994;423.93852891330766;425.45541706615535;436.90169711814843;19192.61614767704;20950.6857397139;21576;21761;21789;21476.4630884228;22039.66940495894;22287.331380058582;22294;22452.43868903274;6712.261651218362;7060;7067.929320706804;7078.646067696594;7080.681369338392;6486.3513648635235;6849.726010959555;6925.930740692579;6931.514793964425;6932.994715766211;6668;6851.691673874688;6940.757073502428;6941.861162776741;6965.756198533052;6784.118064651589;6875.931240687629;6880.931190688104;6882.208546017172;6882.449404047689;6395.776147834804;6689.431398331148;6751.25736169021;6776.796696099125;6793.082933803972;26995.86502067477;27277.727222727717;27505.724942750516;27908.720912790814;28004.299892502648;3372.9494057589154;3389;3395.6943875051365;3397.6262611112757;3418.3334249821296;16935.3225870965;16983.23575439108;17010.574735631708;17123.743143852727;17614.471565852917;1916.2622390379665;1942.4075656924633;1956.4032969944162;1986.68213085907;2068.720722702431;137990;143282.2835885814;143431;144832.99999999895;147889;16904.830951690572;18881.15034823436;20530.486737831376;21295.61578497393;21459.46351341229;36146.819265903505;36226;36230.637693623255;36390.26926499591;36410.45384319237;9050.456972581665;10320.122789562822;10544.472776361152;10751.247412681038;10791.920807919145;8523.979816783349;8572.27135693473;8584.527850968132;8814.779630509289;8815.735527934172;10385.792284154275;10443.216758743125;10463.68608941733;10621.097206737513;10650.946745266225;4067.247559201557;4185.1246221693555;4188.790560471964;4216.746995180299;4231.576842315805;931.8509038553799;1003.9799204015878;1017.8371460566422;1033.617561502249;1077.7790552936785;21486.570268594853;21908.671369929463;22027.898605069684;22067.889660551595;22154.556908862054;947.6920000999703;1007.536533194724;1037.9481025948671;1041.9218558608134;1048.2976405808126;10667.786644267071;11544.480498377594;11555.133364997659;11558.30650160993;11647.88352116485;547041;552776;554695;556973;581906;98403;114114;116543;135058;148782;1317.7166909114587;1355.539116700307;1403.9227842468763;1408.5281430720618;1413.1803553938691;18757.90621046886;20907.163713451293;21109.89445052765;21281.89359053195;21501.13995440165;54959.72520137374;59119;60289;60972;61181.77636447335;80455.59772201335;81252.5937370333;82551.348973019;83761.74357384522;84398;11935.224210426295;12267.877321226673;12363.32001739913;13108.2135071896;13136.080474367085;13854.515091971733;13933.721325573635;13982.370793314121;14413;14474.203918784568;10932.797392287;11198.608048718255;11970.162088653711;11998.40007999614;12097.491727398956;11656;11685.707857303636;11778.255298569708;12058.879411205775;12070.939645301718;11951.581694640647;11981.820272695742;12338.691532711755;12403.813942791046;12421.19262247951;12893.677658058623;12920.612381628565;13259.403326850123;13295.800562991757;13350.732985340434;11756.415409886464;12073.215241009306;12160.871847426843;12211.267323960235;12232.449539770554 08:01:08 INFO - PID 1636 | |3;mootools.html;1341.6315358334505;1438.769796832507;1569.2310767723811;1602.3670650093213;1649.2166221045002;9086.318526110543;9443.527823608818;9466.432014079151;10315.329503582261;10682.679519614425;10339.379637221771;10463.58145674174;10468.057874791268;10472.842907356397;10479.21405894559;2122.1511395441794;2270.9326616490225;2317.9536409271846;2348.518553696488;2362.031567057507;1960.647083524965;2044.31515442327;2049.3749406431034;2225.065472501545;2334.9299521014395;2266.829987750917;2357.5874222011166;2358.469344397505;2358.5047140100514;2358.9764102358927;1930.671785796404;2139.1978008246906;2333.658146565723;2339.2748248043076;2341.028473183627;2285.3029825903095;2304.147465437782;2328.092044102801;2329.7553756855455;2330.114556468548;10615.946920265349;11078.501467433985;11085.83371249432;11088.944555277172;11101.1674124441;5274.894502109974;5869.618474799125;5873.148393482921;5889.116632505115;5890.617109887844;3415.6584341565895;3492.092056065416;3497.842597083137;3527.805970671612;3581.1584277694983;1156.2253290295523;1160.7829046521188;1195.396324855951;1213.8482689663792;1217.6955761059735;15315.617109572238;15407.614809629737;15415.614609634737;15428.3828646854;15442.922785386114;4639.794895327813;4639.976800115978;4755.5719985201495;4866.221404575251;4945.159322915113;11898.10764192689;11912.76174476523;11913.880861191277;11931.642050738492;11931.701707457209;5605.075162598126;5607.271054762855;5611.382747897728;5622.353429355677;5623.578231632644;4971.900561988812;5074.49255074497;5117.692938423706;5201.869953251199;5205.531502164823;3366.7138293244857;3594.964050359515;3614.367485689994;3723.5159429274563;3759.8005166977077;904.8371293167293;979.329159525713;982.111189373618;1031.6595523477235;1043.1811028342715;658.6476235214137;787.696736756353;831.0733532111652;834.1366685480499;858.5707146426787;6263.498920086382;6275.529335299871;6501.187351581052;6587.73649054042;6681.498887583451;1006.9647562335283;1007.1841808135433;1023.9078482936422;1028.2956175020136;1064.920130990179;4131.008557946071;4315.525292217853;4321.45034429688;4338.240807858612;4369.9126017479475;12887.871121288666;13321.600351989457;13344.332783360793;13350.866491335157;13352.732945341233;13045.152065115737;13116.016298777438;13264.336783160803;13502.932485337511;13514.79727804064;9565;9645.276604254568;9766.804663906682;9896.851547226797;9908.108270255712;6337.429631333202;6354.52341074412;6448.193975753031;6477.222733272038;6535.901961470487;11549.307041577533;11855.407229638311;11956.103292252941;11966.162368634112;12018.91829735328;11619.186656933933;12130.454129564014;12187.451564679788;12203.633890983285;12310.26138431697;8707.521086340312;8961.731148065568;9009.954950225207;9024.36829421934;9040.186383225542;9882.4564648945;9893.65372211969;10001.849972250277;10004.799904002024;10018.699439016842;8660;8830.337724670804;8914.55427228649;8915.50964696922;8961.103889610988;8550.57247137653;8583.570821458778;8655.17775811297;8691.261242794433;8714.738557843275;7648.08223013242;7823.413244006609;7831;7831.060272767304;7832.882506762289;5752.424757524214;5780.219670344618;5912.320083190316;5978.551608629283;5986.2517185351835;5789.1895134680335;5880.500157486662;6016.608920420159;6080.331163572092;6131.988221943508 08:01:08 INFO - PID 1636 | |4;prototype.html;328.972037376823;372.81731951343835;386.13891023018687;390.74211020726324;391.9137789686268;5302.946970530294;5512.944870551293;5532.585056120787;5588.776448942042;5597.468240517154;4643.908681459854;5272.578193744501;5322.8137015204475;5380.21986811912;5505.449455054492;4100.384942258655;4399.780010999453;4752.215884379073;4764.713527347614;4773.498782627827;4352.608265256126;4785.353977213083;4788.61691064714;4803.678988278234;4804.975975120137;4145.523264824538;4745.881352966169;4753.572178503934;4781.593564547018;4818.68678535894;3762.3792074307707;4782.641301902371;4785.856424307259;4789;4831.565159135659;3940.566537680853;4435.556444355571;4527.705699129546;4563.498015218323;4572.4055872736335;4418.469783625953;4434.669599120251;4674.522503073343;5373.543248823816;5410.513053825135;5556.388797232301;5577.832665020056;5593.972030139864;5606.271184745998;5610.214569960209;5561.026820306431;5590.496855283044;5887.587868849139;6022.337542870281;6043.123747056696;3918.3926491393595;4464.8214071437105;4514.774261286955;4658.836940707059;4691;5086;5174.844754657366;6290.308066112724;6299.433051025429;6364.867769297245;4555.612772914273;4785.210440277316;4802.831900883522;4834.709917404896;4842.370491836109;3844.1735026969336;4923.384576927884;4946.604271658259;4948.208286674187;4956.612148598399;5266.947330526722;5312.946870531323;5336.546393556591;5365.141577347606;5372.409035006146;4926.778294976734;5719.970405327081;5871.4128587141795;5912.822615321461;5926.140709597084;1439.9136051836713;1566.4517418903292;1619.5747741986977;1620.198001989023;1672.7955871772303;1157.6932112990064;1233.9568115115928;1269.6444995401303;1280.481405030964;1286.5462027908404;5264.894702105937;5646.971765141148;5654.773809047674;5669.688167150764;5680.801171959044;5193.818216362409;5580.693061881554;5892.823215303548;6085.6348619083;6089.208402907682;4276.957230427718;5567.832965011056;5627.380988091307;5631.338755067265;5660.490555849993;2797.5244208484605;2919.7299174859004;3023.6371635403893;3113.801186543197;3143.5599016137758;6227.034809604518;6229.345918678564;6232.068058901755;6234.376562343729;6261.092141639437;5110.361204849394;6173.938260617426;6179.721912513947;6190.628562286277;6201.379862013762;4656.41794775653;5420.322459692538;5620.57845661577;5848.473637372657;6405.743770249138;5061.974690126526;5677.403872593483;6086.60437071589;6088.595004973099;6148.231471066117;5304.761285742228;5929.16991621182;6012.518998480024;6014.248218972629;6021.337652858269;5232.5552328052545;5494.890102197854;6312.727418129562;6327.36726327382;6338.968305158444;4197.853075142355;4762.666613337033;4783.210770222874;4802.471728109836;4820.590249828804;5388.919166212589;5432.049391356576;5786.797462088806;6026.939730602813;6097.573169878244;4745.952540474551;5743.798967036134;5762.193292938908;5803.390643982321;5808.912866306924;5462.716261678582;5543;5604.327480702342;5715.685637289989;5767.44279044664;5547.750351234124;5561.499465048165;5577.609567330248;5597.5521958242425;5647.7176141193595;4993.650444468853;5024.497550244946;5723.656580605177;5744.3681195067675;5747.252857128547;4900.338454308765;5121.74391280442;5366.195070739421;5614.466625670555;5635.774569017193 08:01:08 INFO - PID 1636 | |5;yui.html;728.4645785347768;732.9083864516936;778.4544539356305;793.797581616688;812.9512229266246;15095.245237738123;15631.843681563181;15647.608809779762;15652.139132347716;15677.529674109795;11760.411979401038;13586.456541738344;13657.521986730466;13796.517121900735;14533.418663253484;830.3357314148657;852.3309202276214;952.6713283917038;974.3763991045737;976.3068221562698;966.1932286540712;967.1827305926516;967.9467629280387;969.030969030969;969.1277849935044;995.8804943406801;1044.4882007816154;1048.9895101049005;1049.2235745548317;1049.7847941172042;1028.7839553693734;1032.6334151376266;1033.2663808695836;1036.4506811389974;1036.6009086501676;750.7635094945075;752.0899711349274;753.5779963220559;754.6641744423733;755.3051192902543;15666.530004099895;15708.60728481797;16043.679126417523;16047.919760401122;16066.036037837766;14502.492412765609;14884.255787210705;14887.702245955128;14899.478518251917;14925.2537373132;10824.512896919658;11637.767244655059;11694;11751.882481175164;11859.52561897523;3678.889633311005;3788.7916164610933;3793.089658481972;3798.3352913240074;3799.126200973764;96989.51505242499;106101.46949265205;108626;110038;110182;9994.750131246778;11355.710549258929;11503.827442588368;11618.186726929034;11629.47667354971;24438;24862.881170347377;24875.751242487706;24959.62560561593;25009.87455564504;7794.2985131338455;7797.337226335825;7893.486923349964;8019.278264956065;8127.918720812834;4157.355609880473;4281.464816897887;4283.145332679448;4323.54602766705;4324.308110702273;3393.6776006279365;3506.8772592959126;3512.613612502623;3513.2446523997464;3519;618.1716499890168;618.3939739055661;621.1180124223613;643.9903401448983;645.1741770533724;523.3850225984529;532.1830989431204;559.1947595462602;630.8675178212627;641.2721561028267;9267.351285409957;9586.568604412818;9702.320837541305;10017.849732254022;10022;553.3581045986716;573.6959411511905;582.726118724199;622.4957784194817;711.4237467651116;4454.1982443159895;4888.304862023667;4927.162382395001;5240;5246.108161612535;79463.60268198738;84132.57933710293;86664;86969.56515217383;89478;48657.513424866;52966.86411063441;72319.63840180765;80232.59883700544;82781.58609206915;64846.67576662275;66302.66848665726;67495;69761.3023869765;71565.92651110237;9504.85742713846;9966.651167209113;10609.840852387066;10733.355998640107;10745.785084298426;40916.59083409128;41063.589364105974;42382.5761742391;43477.56522434735;44383.55616443794;54759.178612321644;56102.316930492154;56103.036393726026;56216.43783562275;56263;13213.273269970245;13654.93172534131;13760;13808.930955345159;14139.575812725634;14315.57053288403;14397.928010359881;14523.564293071226;14737;15002.549923502313;13542.59372218835;13686.384112715152;13703.862961370258;13784;14145.222012789396;11110.61112861046;13579;13688.726225475633;13706.504841265414;14037.1577705338;10996.615118470816;13419.530316438877;13424.99312551543;13432.19406835593;13775.724485510435;10977;10987.340759554452;11129.387883666475;11138.227883192129;11779.293242405483;10270.691879243635;10801.729956750829;11126.61056863006;11140.498677559692;11177.72055698614 08:01:08 INFO - PID 1636 | __end_tp_report 08:01:08 INFO - PID 1636 | __start_cc_report 08:01:08 INFO - PID 1636 | _x_x_mozilla_cycle_collect,3451 08:01:08 INFO - PID 1636 | __end_cc_report 08:01:08 INFO - PID 1636 | __startTimestamp1489762868959__endTimestamp 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | ------- Summary: start ------- 08:01:08 INFO - PID 1636 | Number of tests: 6 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:5629.59 Median:5714.40 stddev:615.44 (10.8%) stddev-sans-first:590.50 08:01:08 INFO - PID 1636 | Values: 3338.4 3338.5 3344.5 3373.3 3374.5 5472.9 5557.1 5626.3 5639.0 5649.2 5201.3 5606.4 5607.7 5680.5 5719.3 5044.9 5094.6 5134.2 5143.0 5167.1 5005.5 5089.3 5217.4 5221.2 5253.8 5086.5 5113.5 5115.5 5196.6 5212.3 4379.3 5132.2 5144.4 5172.9 5226.4 5106.5 5115.9 5144.8 5147.3 5204.1 5512.0 5587.2 5625.5 5626.9 5627.2 5601.5 5690.3 6012.2 6139.8 6205.0 5231.4 6126.8 6223.0 6229.1 6248.5 5721.9 5733.1 5745.2 5750.1 5750.7 6136.7 6178.8 6205.8 6211.9 6219.9 6064.6 6083.6 6090.5 6132.4 6138.8 6201.0 6228.7 6230.1 6240.1 6243.1 6041.7 6100.9 6105.7 6157.9 6181.6 6072.6 6114.6 6187.5 6198.7 6221.0 4907.6 4921.4 4926.8 4933.2 4943.0 4424.9 4428.9 4442.7 4445.2 4472.1 5519.4 5607.5 5611.4 5640.9 5657.9 6089.8 6117.3 6138.4 6170.4 6229.4 5755.6 5773.8 5776.6 5802.4 5804.5 5325.4 5357.9 5362.4 5373.9 5385.4 6163.8 6217.5 6257.0 6264.1 6268.7 6106.2 6159.9 6210.0 6218.2 6220.6 6192.0 6240.5 6249.8 6263.0 6280.9 6144.2 6146.1 6152.0 6159.3 6191.8 6043.5 6078.4 6105.2 6106.4 6110.8 5546.5 5703.7 5712.7 5742.2 5743.7 5681.2 5714.4 5725.4 5731.8 5736.2 5583.9 5642.7 5650.6 5655.9 5657.7 5620.7 5751.0 5778.0 5779.6 5800.2 5646.0 5649.8 5650.7 5651.1 5656.5 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:51237.31 Median:3137.60 stddev:101532.92 (3236.0%) stddev-sans-first:101747.05 08:01:08 INFO - PID 1636 | Values: 774.3 793.2 855.3 867.8 869.0 34805.0 35419.6 35579.8 35733.6 35797.8 37356.0 37725.8 37727.6 37728.4 37761.0 6263.2 7837.4 8902.6 9082.2 9088.0 7263.5 10470.9 10722.0 10734.0 10745.7 10767.0 10834.4 10838.0 10845.9 10855.5 7120.8 10829.4 10930.1 10942.7 10959.7 7178.6 8637.9 10458.3 10477.4 10491.8 36851.0 37264.6 37265.0 37374.6 37412.6 3791.4 3801.7 3839.7 3867.9 3941.4 27285.2 27293.0 27329.0 27356.0 27553.9 3042.3 3097.5 3127.5 3147.7 3200.7 282379.0 282410.2 282439.0 283089.0 283667.0 37324.0 37907.5 45825.5 47238.8 47435.0 49274.3 49543.0 49639.1 49648.5 49660.3 13679.9 13819.2 13820.9 13825.8 13837.9 11012.9 11033.6 11220.2 11247.9 11274.6 13683.7 13709.3 13722.9 13759.9 13847.8 4983.6 5848.6 5866.2 5891.6 5909.4 1143.0 1143.4 1150.5 1153.0 1194.3 24438.4 25773.4 25821.5 25852.6 25853.1 1035.1 1048.7 1053.3 1094.8 1103.0 13903.4 14145.9 14168.0 14277.8 14386.9 422778.0 425987.0 426884.0 427387.0 427616.0 52215.0 52585.0 52599.0 52933.7 52987.0 1420.7 1446.9 1448.9 1449.2 1452.0 26038.3 26468.2 26996.3 27306.0 27316.5 43610.6 43850.7 43942.6 43960.3 43987.8 421355.0 422109.0 422231.0 423007.0 425377.0 21845.2 25386.6 25467.9 30128.0 32610.0 38363.0 38486.4 38575.8 38615.4 38627.8 30166.0 30772.0 30998.2 31791.0 33683.2 32571.3 32705.0 32764.2 33657.7 33709.7 32353.2 32965.3 33047.3 33054.0 33085.3 26117.5 29261.0 29383.9 29468.6 29707.8 22605.0 22936.9 22945.7 22953.0 23031.8 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:36922.66 Median:21377.54 stddev:93929.11 (439.4%) stddev-sans-first:94152.58 08:01:08 INFO - PID 1636 | Values: 404.4 410.6 423.9 425.5 436.9 19192.6 20950.7 21576.0 21761.0 21789.0 21476.5 22039.7 22287.3 22294.0 22452.4 6712.3 7060.0 7067.9 7078.6 7080.7 6486.4 6849.7 6925.9 6931.5 6933.0 6668.0 6851.7 6940.8 6941.9 6965.8 6784.1 6875.9 6880.9 6882.2 6882.4 6395.8 6689.4 6751.3 6776.8 6793.1 26995.9 27277.7 27505.7 27908.7 28004.3 3372.9 3389.0 3395.7 3397.6 3418.3 16935.3 16983.2 17010.6 17123.7 17614.5 1916.3 1942.4 1956.4 1986.7 2068.7 137990.0 143282.3 143431.0 144833.0 147889.0 16904.8 18881.2 20530.5 21295.6 21459.5 36146.8 36226.0 36230.6 36390.3 36410.5 9050.5 10320.1 10544.5 10751.2 10791.9 8524.0 8572.3 8584.5 8814.8 8815.7 10385.8 10443.2 10463.7 10621.1 10650.9 4067.2 4185.1 4188.8 4216.7 4231.6 931.9 1004.0 1017.8 1033.6 1077.8 21486.6 21908.7 22027.9 22067.9 22154.6 947.7 1007.5 1037.9 1041.9 1048.3 10667.8 11544.5 11555.1 11558.3 11647.9 547041.0 552776.0 554695.0 556973.0 581906.0 98403.0 114114.0 116543.0 135058.0 148782.0 1317.7 1355.5 1403.9 1408.5 1413.2 18757.9 20907.2 21109.9 21281.9 21501.1 54959.7 59119.0 60289.0 60972.0 61181.8 80455.6 81252.6 82551.3 83761.7 84398.0 11935.2 12267.9 12363.3 13108.2 13136.1 13854.5 13933.7 13982.4 14413.0 14474.2 10932.8 11198.6 11970.2 11998.4 12097.5 11656.0 11685.7 11778.3 12058.9 12070.9 11951.6 11981.8 12338.7 12403.8 12421.2 12893.7 12920.6 13259.4 13295.8 13350.7 11756.4 12073.2 12160.9 12211.3 12232.4 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:6589.70 Median:3512.82 stddev:4138.05 (117.8%) stddev-sans-first:4130.87 08:01:08 INFO - PID 1636 | Values: 1341.6 1438.8 1569.2 1602.4 1649.2 9086.3 9443.5 9466.4 10315.3 10682.7 10339.4 10463.6 10468.1 10472.8 10479.2 2122.2 2270.9 2318.0 2348.5 2362.0 1960.6 2044.3 2049.4 2225.1 2334.9 2266.8 2357.6 2358.5 2358.5 2359.0 1930.7 2139.2 2333.7 2339.3 2341.0 2285.3 2304.1 2328.1 2329.8 2330.1 10615.9 11078.5 11085.8 11088.9 11101.2 5274.9 5869.6 5873.1 5889.1 5890.6 3415.7 3492.1 3497.8 3527.8 3581.2 1156.2 1160.8 1195.4 1213.8 1217.7 15315.6 15407.6 15415.6 15428.4 15442.9 4639.8 4640.0 4755.6 4866.2 4945.2 11898.1 11912.8 11913.9 11931.6 11931.7 5605.1 5607.3 5611.4 5622.4 5623.6 4971.9 5074.5 5117.7 5201.9 5205.5 3366.7 3595.0 3614.4 3723.5 3759.8 904.8 979.3 982.1 1031.7 1043.2 658.6 787.7 831.1 834.1 858.6 6263.5 6275.5 6501.2 6587.7 6681.5 1007.0 1007.2 1023.9 1028.3 1064.9 4131.0 4315.5 4321.5 4338.2 4369.9 12887.9 13321.6 13344.3 13350.9 13352.7 13045.2 13116.0 13264.3 13502.9 13514.8 9565.0 9645.3 9766.8 9896.9 9908.1 6337.4 6354.5 6448.2 6477.2 6535.9 11549.3 11855.4 11956.1 11966.2 12018.9 11619.2 12130.5 12187.5 12203.6 12310.3 8707.5 8961.7 9010.0 9024.4 9040.2 9882.5 9893.7 10001.8 10004.8 10018.7 8660.0 8830.3 8914.6 8915.5 8961.1 8550.6 8583.6 8655.2 8691.3 8714.7 7648.1 7823.4 7831.0 7831.1 7832.9 5752.4 5780.2 5912.3 5978.6 5986.3 5789.2 5880.5 6016.6 6080.3 6132.0 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:4916.33 Median:5350.84 stddev:1363.53 (25.5%) stddev-sans-first:1323.16 08:01:08 INFO - PID 1636 | Values: 329.0 372.8 386.1 390.7 391.9 5302.9 5512.9 5532.6 5588.8 5597.5 4643.9 5272.6 5322.8 5380.2 5505.4 4100.4 4399.8 4752.2 4764.7 4773.5 4352.6 4785.4 4788.6 4803.7 4805.0 4145.5 4745.9 4753.6 4781.6 4818.7 3762.4 4782.6 4785.9 4789.0 4831.6 3940.6 4435.6 4527.7 4563.5 4572.4 4418.5 4434.7 4674.5 5373.5 5410.5 5556.4 5577.8 5594.0 5606.3 5610.2 5561.0 5590.5 5887.6 6022.3 6043.1 3918.4 4464.8 4514.8 4658.8 4691.0 5086.0 5174.8 6290.3 6299.4 6364.9 4555.6 4785.2 4802.8 4834.7 4842.4 3844.2 4923.4 4946.6 4948.2 4956.6 5266.9 5312.9 5336.5 5365.1 5372.4 4926.8 5720.0 5871.4 5912.8 5926.1 1439.9 1566.5 1619.6 1620.2 1672.8 1157.7 1234.0 1269.6 1280.5 1286.5 5264.9 5647.0 5654.8 5669.7 5680.8 5193.8 5580.7 5892.8 6085.6 6089.2 4277.0 5567.8 5627.4 5631.3 5660.5 2797.5 2919.7 3023.6 3113.8 3143.6 6227.0 6229.3 6232.1 6234.4 6261.1 5110.4 6173.9 6179.7 6190.6 6201.4 4656.4 5420.3 5620.6 5848.5 6405.7 5062.0 5677.4 6086.6 6088.6 6148.2 5304.8 5929.2 6012.5 6014.2 6021.3 5232.6 5494.9 6312.7 6327.4 6339.0 4197.9 4762.7 4783.2 4802.5 4820.6 5388.9 5432.0 5786.8 6026.9 6097.6 4746.0 5743.8 5762.2 5803.4 5808.9 5462.7 5543.0 5604.3 5715.7 5767.4 5547.8 5561.5 5577.6 5597.6 5647.7 4993.7 5024.5 5723.7 5744.4 5747.3 4900.3 5121.7 5366.2 5614.5 5635.8 08:01:08 INFO - PID 1636 | 08:01:08 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:18748.52 Median:3516.12 stddev:25660.05 (729.8%) stddev-sans-first:25696.36 08:01:08 INFO - PID 1636 | Values: 728.5 732.9 778.5 793.8 813.0 15095.2 15631.8 15647.6 15652.1 15677.5 11760.4 13586.5 13657.5 13796.5 14533.4 830.3 852.3 952.7 974.4 976.3 966.2 967.2 967.9 969.0 969.1 995.9 1044.5 1049.0 1049.2 1049.8 1028.8 1032.6 1033.3 1036.5 1036.6 750.8 752.1 753.6 754.7 755.3 15666.5 15708.6 16043.7 16047.9 16066.0 14502.5 14884.3 14887.7 14899.5 14925.3 10824.5 11637.8 11694.0 11751.9 11859.5 3678.9 3788.8 3793.1 3798.3 3799.1 96989.5 106101.5 108626.0 110038.0 110182.0 9994.8 11355.7 11503.8 11618.2 11629.5 24438.0 24862.9 24875.8 24959.6 25009.9 7794.3 7797.3 7893.5 8019.3 8127.9 4157.4 4281.5 4283.1 4323.5 4324.3 3393.7 3506.9 3512.6 3513.2 3519.0 618.2 618.4 621.1 644.0 645.2 523.4 532.2 559.2 630.9 641.3 9267.4 9586.6 9702.3 10017.8 10022.0 553.4 573.7 582.7 622.5 711.4 4454.2 4888.3 4927.2 5240.0 5246.1 79463.6 84132.6 86664.0 86969.6 89478.0 48657.5 52966.9 72319.6 80232.6 82781.6 64846.7 66302.7 67495.0 69761.3 71565.9 9504.9 9966.7 10609.8 10733.4 10745.8 40916.6 41063.6 42382.6 43477.6 44383.6 54759.2 56102.3 56103.0 56216.4 56263.0 13213.3 13654.9 13760.0 13808.9 14139.6 14315.6 14397.9 14523.6 14737.0 15002.5 13542.6 13686.4 13703.9 13784.0 14145.2 11110.6 13579.0 13688.7 13706.5 14037.2 10996.6 13419.5 13425.0 13432.2 13775.7 10977.0 10987.3 11129.4 11138.2 11779.3 10270.7 10801.7 11126.6 11140.5 11177.7 08:01:08 INFO - PID 1636 | -------- Summary: end -------- 08:01:08 INFO - PID 1636 | 08:01:09 INFO - TEST-INFO | 1636: exit 0 08:01:09 INFO - TEST-OK | dromaeo_css | took 1121104ms 08:01:09 INFO - TEST-START | kraken 08:01:09 INFO - operating with platform_type : mac_ 08:01:09 INFO - Initialising browser for kraken test... 08:01:09 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpcelTBm/profile http://localhost:49240/getInfo.html) 08:01:12 INFO - PID 1645 | __metrics Screen width/height:1600/1200 08:01:12 INFO - PID 1645 | colorDepth:24 08:01:12 INFO - PID 1645 | Browser inner width/height: 1024/689 08:01:12 INFO - PID 1645 | __metrics 08:01:12 INFO - PID 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:01:12 INFO - PID 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:01:13 INFO - TEST-INFO | 1645: exit 0 08:01:13 INFO - Browser initialized. 08:01:13 INFO - Running cycle 1/1 for kraken test... 08:01:13 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpcelTBm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:25 INFO - PID 1646 | RSS: Main: 233521152 08:01:25 INFO - PID 1646 | 08:01:28 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 08:01:29 INFO - PID 1646 | RSS: Main: 285057024 08:01:29 INFO - PID 1646 | 08:01:31 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 08:01:32 INFO - PID 1646 | RSS: Main: 285769728 08:01:32 INFO - PID 1646 | 08:01:35 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 08:01:36 INFO - PID 1646 | RSS: Main: 379895808 08:01:36 INFO - PID 1646 | 08:01:38 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 08:01:38 INFO - PID 1646 | RSS: Main: 283586560 08:01:38 INFO - PID 1646 | 08:01:40 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:01:41 INFO - PID 1646 | RSS: Main: 285782016 08:01:41 INFO - PID 1646 | 08:01:44 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 08:01:45 INFO - PID 1646 | RSS: Main: 327819264 08:01:45 INFO - PID 1646 | 08:01:48 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 08:01:48 INFO - PID 1646 | RSS: Main: 342331392 08:01:48 INFO - PID 1646 | 08:01:51 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 08:01:52 INFO - PID 1646 | RSS: Main: 350486528 08:01:52 INFO - PID 1646 | 08:01:54 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:01:54 INFO - PID 1646 | RSS: Main: 301993984 08:01:54 INFO - PID 1646 | 08:01:56 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:01:57 INFO - PID 1646 | RSS: Main: 305238016 08:01:57 INFO - PID 1646 | 08:01:59 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:02:00 INFO - PID 1646 | RSS: Main: 307912704 08:02:00 INFO - PID 1646 | 08:02:02 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:02:03 INFO - PID 1646 | RSS: Main: 325525504 08:02:03 INFO - PID 1646 | 08:02:06 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:02:07 INFO - PID 1646 | RSS: Main: 319168512 08:02:07 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 08:02:09 INFO - PID 1646 | RSS: Main: 381612032 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | __start_tp_report 08:02:09 INFO - PID 1646 | _x_x_mozilla_page_load 08:02:09 INFO - PID 1646 | _x_x_mozilla_page_load_details 08:02:09 INFO - PID 1646 | |i|pagename|runs| 08:02:09 INFO - PID 1646 | |0;ai-astar;116;116;117;115;115;116;115;116;115;114 08:02:09 INFO - PID 1646 | |1;audio-beat-detection;148;111;111;151;112;110;111;108;132;111 08:02:09 INFO - PID 1646 | |2;audio-dft;181;184;184;187;188;186;182;182;184;184 08:02:09 INFO - PID 1646 | |3;audio-fft;77;63;58;93;61;62;60;58;77;59 08:02:09 INFO - PID 1646 | |4;audio-oscillator;90;73;89;69;68;68;86;94;99;117 08:02:09 INFO - PID 1646 | |5;imaging-gaussian-blur;86;86;86;87;87;87;86;87;87;86 08:02:09 INFO - PID 1646 | |6;imaging-darkroom;94;95;94;95;106;149;94;95;94;94 08:02:09 INFO - PID 1646 | |7;imaging-desaturate;83;86;84;84;84;85;105;73;128;136 08:02:09 INFO - PID 1646 | |8;json-parse-financial;63;63;62;61;62;62;63;62;62;62 08:02:09 INFO - PID 1646 | |9;json-stringify-tinderbox;55;61;66;68;61;59;59;59;57;62 08:02:09 INFO - PID 1646 | |10;stanford-crypto-aes;83;83;81;83;96;104;101;104;82;83 08:02:09 INFO - PID 1646 | |11;stanford-crypto-ccm;145;161;139;154;131;136;138;137;181;142 08:02:09 INFO - PID 1646 | |12;stanford-crypto-pbkdf2;184;188;183;181;185;181;185;186;183;182 08:02:09 INFO - PID 1646 | |13;stanford-crypto-sha256-iterative;68;67;69;67;67;68;67;68;65;67 08:02:09 INFO - PID 1646 | __end_tp_report 08:02:09 INFO - PID 1646 | __start_cc_report 08:02:09 INFO - PID 1646 | _x_x_mozilla_cycle_collect,2137 08:02:09 INFO - PID 1646 | __end_cc_report 08:02:09 INFO - PID 1646 | __startTimestamp1489762929449__endTimestamp 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | ------- Summary: start ------- 08:02:09 INFO - PID 1646 | Number of tests: 14 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#0] ai-astar Cycles:10 Average:115.50 Median:116.00 stddev:0.85 (0.7%) stddev-sans-first:0.88 08:02:09 INFO - PID 1646 | Values: 116.0 116.0 117.0 115.0 115.0 116.0 115.0 116.0 115.0 114.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#1] audio-beat-detection Cycles:10 Average:120.50 Median:111.50 stddev:16.73 (15.0%) stddev-sans-first:14.48 08:02:09 INFO - PID 1646 | Values: 148.0 111.0 111.0 151.0 112.0 110.0 111.0 108.0 132.0 111.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.00 stddev:2.25 (1.2%) stddev-sans-first:2.07 08:02:09 INFO - PID 1646 | Values: 181.0 184.0 184.0 187.0 188.0 186.0 182.0 182.0 184.0 184.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.50 stddev:11.68 (18.7%) stddev-sans-first:11.79 08:02:09 INFO - PID 1646 | Values: 77.0 63.0 58.0 93.0 61.0 62.0 60.0 58.0 77.0 59.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:87.50 stddev:16.06 (18.3%) stddev-sans-first:16.94 08:02:09 INFO - PID 1646 | Values: 90.0 73.0 89.0 69.0 68.0 68.0 86.0 94.0 99.0 117.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 08:02:09 INFO - PID 1646 | Values: 86.0 86.0 86.0 87.0 87.0 87.0 86.0 87.0 87.0 86.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:94.00 stddev:17.26 (18.4%) stddev-sans-first:18.12 08:02:09 INFO - PID 1646 | Values: 94.0 95.0 94.0 95.0 106.0 149.0 94.0 95.0 94.0 94.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:84.00 stddev:21.19 (25.2%) stddev-sans-first:22.04 08:02:09 INFO - PID 1646 | Values: 83.0 86.0 84.0 84.0 84.0 85.0 105.0 73.0 128.0 136.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:62.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 08:02:09 INFO - PID 1646 | Values: 63.0 63.0 62.0 61.0 62.0 62.0 63.0 62.0 62.0 62.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:61.00 stddev:3.92 (6.4%) stddev-sans-first:3.57 08:02:09 INFO - PID 1646 | Values: 55.0 61.0 66.0 68.0 61.0 59.0 59.0 59.0 57.0 62.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:83.00 stddev:9.94 (12.0%) stddev-sans-first:10.22 08:02:09 INFO - PID 1646 | Values: 83.0 83.0 81.0 83.0 96.0 104.0 101.0 104.0 82.0 83.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:143.50 stddev:15.09 (10.5%) stddev-sans-first:15.99 08:02:09 INFO - PID 1646 | Values: 145.0 161.0 139.0 154.0 131.0 136.0 138.0 137.0 181.0 142.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:184.50 stddev:2.25 (1.2%) stddev-sans-first:2.39 08:02:09 INFO - PID 1646 | Values: 184.0 188.0 183.0 181.0 185.0 181.0 185.0 186.0 183.0 182.0 08:02:09 INFO - PID 1646 | 08:02:09 INFO - PID 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.06 (1.6%) stddev-sans-first:1.09 08:02:09 INFO - PID 1646 | Values: 68.0 67.0 69.0 67.0 67.0 68.0 67.0 68.0 65.0 67.0 08:02:09 INFO - PID 1646 | -------- Summary: end -------- 08:02:09 INFO - PID 1646 | 08:02:10 INFO - TEST-INFO | 1646: exit 0 08:02:10 INFO - TEST-OK | kraken | took 60720ms 08:02:10 INFO - SUITE-END | took 1181s 08:02:10 INFO - Completed test suite (00:19:42) 08:02:10 INFO - javascript benchmark 08:02:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7646.138399596878, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.3990881641303, 3338.515915192297, 3344.4648856183017, 3373.2578832656814, 3374.460086386177, 5472.9179062314, 5557.110862262039, 5626.2967129108865, 5638.971805140974, 5649.1526271059365, 5201.297824793652, 5606.439356064391, 5607.719614019303, 5680.488756011958, 5719.342275638292, 5044.949550504503, 5094.592432605392, 5134.229865520165, 5143.0, 5167.095758242312, 5005.474425185358, 5089.338386009832, 5217.399999000126, 5221.190715439113, 5253.763580638862, 5086.491350864884, 5113.539781419653, 5115.462876397962, 5196.58427325815, 5212.322398088262, 4379.255526560461, 5132.230165475171, 5144.4083930348115, 5172.948270517284, 5226.372835259755, 5106.514881086291, 5115.897682046339, 5144.819931302386, 5147.253648221024, 5204.141316682742, 5512.0, 5587.189857470644, 5625.493705566478, 5626.859328516779, 5627.212190293362, 5601.523870471015, 5690.317161940552, 6012.248468941382, 6139.829471381233, 6205.03821907605, 5231.424543300234, 6126.846828829271, 6223.0, 6229.0967809667345, 6248.468880145193, 5721.9427805721825, 5733.111367738007, 5745.195672605839, 5750.108733146369, 5750.654960702372, 6136.693165341715, 6178.845528861814, 6205.844853878689, 6211.875762484725, 6219.937800622027, 6064.575479716465, 6083.604565703304, 6090.482309003784, 6132.386761323832, 6138.754449821957, 6201.0, 6228.719707613168, 6230.127782110508, 6240.095186198067, 6243.063540468966, 6041.667708276087, 6100.938990610126, 6105.664188469588, 6157.9384206158265, 6181.629102253879, 6072.605280656743, 6114.602550834182, 6187.474064704551, 6198.721057552421, 6221.0, 4907.582855457326, 4921.409430868247, 4926.75366231687, 4933.210686290176, 4942.950570494249, 4424.889377765518, 4428.889277768018, 4442.733435993786, 4445.244308467568, 4472.08322293929, 5519.39286678465, 5607.523360514402, 5611.382747897728, 5640.8871822563315, 5657.915131273035, 6089.8477538061015, 6117.309816958451, 6138.416850399205, 6170.413810687977, 6229.377062293825, 5755.59710820247, 5773.769049237983, 5776.56675749312, 5802.361740208574, 5804.535637149018, 5325.440828713007, 5357.866053348697, 5362.436944120889, 5373.946260537423, 5385.353757549041, 6163.753449862134, 6217.533684973736, 6257.0, 6264.091706702503, 6268.655223962726, 6106.2179897963915, 6159.907601385893, 6210.0, 6218.160548326099, 6220.564560480904, 6192.0, 6240.5319601031015, 6249.812505624838, 6263.0, 6280.937190628035, 6144.170536977451, 6146.078088286793, 6152.0, 6159.322474527888, 6191.81424557264, 6043.516518678407, 6078.3617720138745, 6105.236845394325, 6106.389361063858, 6110.7555697771595, 5546.500814926676, 5703.686297253691, 5712.685802280915, 5742.196092546963, 5743.741531631003, 5681.233033540584, 5714.399988001366, 5725.370209277059, 5731.770729170787, 5736.225609542658, 5583.944160558342, 5642.717864106696, 5650.604457687922, 5655.943440565707, 5657.745401456862, 5620.690862002629, 5751.0, 5778.0, 5779.566532509994, 5800.158976948234, 5645.971770141123, 5649.774009039593, 5650.689212093374, 5651.124075768263, 5656.519195868397], "value": 5591.454399533599, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.2683164409632, 793.1830214878676, 855.2644725536038, 867.8220964702233, 869.0136694851342, 34805.0, 35419.64580354196, 35579.82210088949, 35733.64266357336, 35797.82101089497, 37356.0, 37725.81137094318, 37727.62272377275, 37728.43407348892, 37761.0, 6263.217097862767, 7837.4121940854375, 8902.599383027748, 9082.228010619105, 9088.0, 7263.455240856931, 10470.895291047107, 10722.0, 10734.033936945674, 10745.731356716067, 10766.977137171956, 10834.40410777407, 10838.0, 10845.891541084606, 10855.51150198239, 7120.786376408716, 10829.404382758945, 10930.070943969773, 10942.726431839188, 10959.726006849813, 7178.641067946582, 8637.913620863774, 10458.267921245515, 10477.37135771856, 10491.79016419675, 36851.0, 37264.627353726384, 37265.0, 37374.626253737384, 37412.62587374146, 3791.412331088658, 3801.7148713846573, 3839.6928245740273, 3867.883963481072, 3941.3890846918775, 27285.181444556496, 27293.0, 27329.0, 27356.0, 27553.86223068892, 3042.307082871193, 3097.5198844178963, 3127.46833038384, 3147.7324427423696, 3200.727938125262, 282379.0, 282410.1758982404, 282439.0, 283089.0, 283667.0, 37324.0, 37907.483700652214, 45825.54174458213, 47238.763806181436, 47435.0, 49274.26088608674, 49543.0, 49639.057750760425, 49648.50351496511, 49660.25509617358, 13679.931600342134, 13819.170849749047, 13820.861791381958, 13825.79261311081, 13837.86162138386, 11012.889871101186, 11033.558657653764, 11220.158488113424, 11247.943760281147, 11274.605388811353, 13683.726325473435, 13709.314534273446, 13722.862771372358, 13759.931200344134, 13847.792283115761, 4983.626228032912, 5848.64908105515, 5866.208061911588, 5891.646501209855, 5909.438603332676, 1142.959996400122, 1143.3997151495532, 1150.511032811052, 1152.976940461203, 1194.3371428856894, 24438.389040274134, 25773.3556661085, 25821.483570328488, 25852.612210816853, 25853.09514166995, 1035.135661722455, 1048.7378155461136, 1053.3311347294523, 1094.8084085285202, 1103.0458653264868, 13903.4438622456, 14145.85854141466, 14168.0, 14277.846961405408, 14386.856131438552, 422778.0, 425987.0, 426884.0, 427387.0, 427616.0, 52214.95570088577, 52585.0, 52599.0, 52933.735331323864, 52987.0, 1420.7016526529337, 1446.8842492600568, 1448.9347979340744, 1449.173970836613, 1451.9854801452273, 26038.34904127412, 26468.162116493386, 26996.32509187286, 27306.0, 27316.453670926072, 43610.563894360654, 43850.684479465664, 43942.560574395124, 43960.34059489046, 43987.78006109949, 421355.0, 422109.0, 422231.0, 423007.0, 425377.0, 21845.235416760337, 25386.619200711633, 25467.872660636578, 30128.0, 32610.0, 38363.0, 38486.42270365891, 38575.807120964215, 38615.42076868793, 38627.8411647651, 30166.0, 30772.0, 30998.22504437407, 31791.0, 33683.21229817094, 32571.34857302888, 32705.0, 32764.180895477803, 33657.663423365455, 33709.662903370656, 32353.191170220933, 32965.34069318648, 33047.33905321928, 33054.008379748644, 33085.33829323448, 26117.477650447265, 29261.0, 29383.853080734458, 29468.557971630013, 29707.774452387508, 22605.0, 22936.85315734173, 22945.655815162452, 22953.0, 23031.769682302962], "value": 17965.871756077497, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.41965779107, 410.55044726024994, 423.93852891330766, 425.45541706615535, 436.90169711814843, 19192.61614767704, 20950.6857397139, 21576.0, 21761.0, 21789.0, 21476.4630884228, 22039.66940495894, 22287.331380058582, 22294.0, 22452.43868903274, 6712.261651218362, 7060.0, 7067.929320706804, 7078.646067696594, 7080.681369338392, 6486.3513648635235, 6849.726010959555, 6925.930740692579, 6931.514793964425, 6932.994715766211, 6668.0, 6851.691673874688, 6940.757073502428, 6941.861162776741, 6965.756198533052, 6784.118064651589, 6875.931240687629, 6880.931190688104, 6882.208546017172, 6882.449404047689, 6395.776147834804, 6689.431398331148, 6751.25736169021, 6776.796696099125, 6793.082933803972, 26995.86502067477, 27277.727222727717, 27505.724942750516, 27908.720912790814, 28004.299892502648, 3372.9494057589154, 3389.0, 3395.6943875051365, 3397.6262611112757, 3418.3334249821296, 16935.3225870965, 16983.23575439108, 17010.574735631708, 17123.743143852727, 17614.471565852917, 1916.2622390379665, 1942.4075656924633, 1956.4032969944162, 1986.68213085907, 2068.720722702431, 137990.0, 143282.2835885814, 143431.0, 144832.99999999895, 147889.0, 16904.830951690572, 18881.15034823436, 20530.486737831376, 21295.61578497393, 21459.46351341229, 36146.819265903505, 36226.0, 36230.637693623255, 36390.26926499591, 36410.45384319237, 9050.456972581665, 10320.122789562822, 10544.472776361152, 10751.247412681038, 10791.920807919145, 8523.979816783349, 8572.27135693473, 8584.527850968132, 8814.779630509289, 8815.735527934172, 10385.792284154275, 10443.216758743125, 10463.68608941733, 10621.097206737513, 10650.946745266225, 4067.247559201557, 4185.1246221693555, 4188.790560471964, 4216.746995180299, 4231.576842315805, 931.8509038553799, 1003.9799204015878, 1017.8371460566422, 1033.617561502249, 1077.7790552936785, 21486.570268594853, 21908.671369929463, 22027.898605069684, 22067.889660551595, 22154.556908862054, 947.6920000999703, 1007.536533194724, 1037.9481025948671, 1041.9218558608134, 1048.2976405808126, 10667.786644267071, 11544.480498377594, 11555.133364997659, 11558.30650160993, 11647.88352116485, 547041.0, 552776.0, 554695.0, 556973.0, 581906.0, 98403.0, 114114.0, 116543.0, 135058.0, 148782.0, 1317.7166909114587, 1355.539116700307, 1403.9227842468763, 1408.5281430720618, 1413.1803553938691, 18757.90621046886, 20907.163713451293, 21109.89445052765, 21281.89359053195, 21501.13995440165, 54959.72520137374, 59119.0, 60289.0, 60972.0, 61181.77636447335, 80455.59772201335, 81252.5937370333, 82551.348973019, 83761.74357384522, 84398.0, 11935.224210426295, 12267.877321226673, 12363.32001739913, 13108.2135071896, 13136.080474367085, 13854.515091971733, 13933.721325573635, 13982.370793314121, 14413.0, 14474.203918784568, 10932.797392287, 11198.608048718255, 11970.162088653711, 11998.40007999614, 12097.491727398956, 11656.0, 11685.707857303636, 11778.255298569708, 12058.879411205775, 12070.939645301718, 11951.581694640647, 11981.820272695742, 12338.691532711755, 12403.813942791046, 12421.19262247951, 12893.677658058623, 12920.612381628565, 13259.403326850123, 13295.800562991757, 13350.732985340434, 11756.415409886464, 12073.215241009306, 12160.871847426843, 12211.267323960235, 12232.449539770554], "value": 11844.138961878958, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.6315358334505, 1438.769796832507, 1569.2310767723811, 1602.3670650093213, 1649.2166221045002, 9086.318526110543, 9443.527823608818, 9466.432014079151, 10315.329503582261, 10682.679519614425, 10339.379637221771, 10463.58145674174, 10468.057874791268, 10472.842907356397, 10479.21405894559, 2122.1511395441794, 2270.9326616490225, 2317.9536409271846, 2348.518553696488, 2362.031567057507, 1960.647083524965, 2044.31515442327, 2049.3749406431034, 2225.065472501545, 2334.9299521014395, 2266.829987750917, 2357.5874222011166, 2358.469344397505, 2358.5047140100514, 2358.9764102358927, 1930.671785796404, 2139.1978008246906, 2333.658146565723, 2339.2748248043076, 2341.028473183627, 2285.3029825903095, 2304.147465437782, 2328.092044102801, 2329.7553756855455, 2330.114556468548, 10615.946920265349, 11078.501467433985, 11085.83371249432, 11088.944555277172, 11101.1674124441, 5274.894502109974, 5869.618474799125, 5873.148393482921, 5889.116632505115, 5890.617109887844, 3415.6584341565895, 3492.092056065416, 3497.842597083137, 3527.805970671612, 3581.1584277694983, 1156.2253290295523, 1160.7829046521188, 1195.396324855951, 1213.8482689663792, 1217.6955761059735, 15315.617109572238, 15407.614809629737, 15415.614609634737, 15428.3828646854, 15442.922785386114, 4639.794895327813, 4639.976800115978, 4755.5719985201495, 4866.221404575251, 4945.159322915113, 11898.10764192689, 11912.76174476523, 11913.880861191277, 11931.642050738492, 11931.701707457209, 5605.075162598126, 5607.271054762855, 5611.382747897728, 5622.353429355677, 5623.578231632644, 4971.900561988812, 5074.49255074497, 5117.692938423706, 5201.869953251199, 5205.531502164823, 3366.7138293244857, 3594.964050359515, 3614.367485689994, 3723.5159429274563, 3759.8005166977077, 904.8371293167293, 979.329159525713, 982.111189373618, 1031.6595523477235, 1043.1811028342715, 658.6476235214137, 787.696736756353, 831.0733532111652, 834.1366685480499, 858.5707146426787, 6263.498920086382, 6275.529335299871, 6501.187351581052, 6587.73649054042, 6681.498887583451, 1006.9647562335283, 1007.1841808135433, 1023.9078482936422, 1028.2956175020136, 1064.920130990179, 4131.008557946071, 4315.525292217853, 4321.45034429688, 4338.240807858612, 4369.9126017479475, 12887.871121288666, 13321.600351989457, 13344.332783360793, 13350.866491335157, 13352.732945341233, 13045.152065115737, 13116.016298777438, 13264.336783160803, 13502.932485337511, 13514.79727804064, 9565.0, 9645.276604254568, 9766.804663906682, 9896.851547226797, 9908.108270255712, 6337.429631333202, 6354.52341074412, 6448.193975753031, 6477.222733272038, 6535.901961470487, 11549.307041577533, 11855.407229638311, 11956.103292252941, 11966.162368634112, 12018.91829735328, 11619.186656933933, 12130.454129564014, 12187.451564679788, 12203.633890983285, 12310.26138431697, 8707.521086340312, 8961.731148065568, 9009.954950225207, 9024.36829421934, 9040.186383225542, 9882.4564648945, 9893.65372211969, 10001.849972250277, 10004.799904002024, 10018.699439016842, 8660.0, 8830.337724670804, 8914.55427228649, 8915.50964696922, 8961.103889610988, 8550.57247137653, 8583.570821458778, 8655.17775811297, 8691.261242794433, 8714.738557843275, 7648.08223013242, 7823.413244006609, 7831.0, 7831.060272767304, 7832.882506762289, 5752.424757524214, 5780.219670344618, 5912.320083190316, 5978.551608629283, 5986.2517185351835, 5789.1895134680335, 5880.500157486662, 6016.608920420159, 6080.331163572092, 6131.988221943508], "value": 4998.721300951449, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.972037376823, 372.81731951343835, 386.13891023018687, 390.74211020726324, 391.9137789686268, 5302.946970530294, 5512.944870551293, 5532.585056120787, 5588.776448942042, 5597.468240517154, 4643.908681459854, 5272.578193744501, 5322.8137015204475, 5380.21986811912, 5505.449455054492, 4100.384942258655, 4399.780010999453, 4752.215884379073, 4764.713527347614, 4773.498782627827, 4352.608265256126, 4785.353977213083, 4788.61691064714, 4803.678988278234, 4804.975975120137, 4145.523264824538, 4745.881352966169, 4753.572178503934, 4781.593564547018, 4818.68678535894, 3762.3792074307707, 4782.641301902371, 4785.856424307259, 4789.0, 4831.565159135659, 3940.566537680853, 4435.556444355571, 4527.705699129546, 4563.498015218323, 4572.4055872736335, 4418.469783625953, 4434.669599120251, 4674.522503073343, 5373.543248823816, 5410.513053825135, 5556.388797232301, 5577.832665020056, 5593.972030139864, 5606.271184745998, 5610.214569960209, 5561.026820306431, 5590.496855283044, 5887.587868849139, 6022.337542870281, 6043.123747056696, 3918.3926491393595, 4464.8214071437105, 4514.774261286955, 4658.836940707059, 4691.0, 5086.0, 5174.844754657366, 6290.308066112724, 6299.433051025429, 6364.867769297245, 4555.612772914273, 4785.210440277316, 4802.831900883522, 4834.709917404896, 4842.370491836109, 3844.1735026969336, 4923.384576927884, 4946.604271658259, 4948.208286674187, 4956.612148598399, 5266.947330526722, 5312.946870531323, 5336.546393556591, 5365.141577347606, 5372.409035006146, 4926.778294976734, 5719.970405327081, 5871.4128587141795, 5912.822615321461, 5926.140709597084, 1439.9136051836713, 1566.4517418903292, 1619.5747741986977, 1620.198001989023, 1672.7955871772303, 1157.6932112990064, 1233.9568115115928, 1269.6444995401303, 1280.481405030964, 1286.5462027908404, 5264.894702105937, 5646.971765141148, 5654.773809047674, 5669.688167150764, 5680.801171959044, 5193.818216362409, 5580.693061881554, 5892.823215303548, 6085.6348619083, 6089.208402907682, 4276.957230427718, 5567.832965011056, 5627.380988091307, 5631.338755067265, 5660.490555849993, 2797.5244208484605, 2919.7299174859004, 3023.6371635403893, 3113.801186543197, 3143.5599016137758, 6227.034809604518, 6229.345918678564, 6232.068058901755, 6234.376562343729, 6261.092141639437, 5110.361204849394, 6173.938260617426, 6179.721912513947, 6190.628562286277, 6201.379862013762, 4656.41794775653, 5420.322459692538, 5620.57845661577, 5848.473637372657, 6405.743770249138, 5061.974690126526, 5677.403872593483, 6086.60437071589, 6088.595004973099, 6148.231471066117, 5304.761285742228, 5929.16991621182, 6012.518998480024, 6014.248218972629, 6021.337652858269, 5232.5552328052545, 5494.890102197854, 6312.727418129562, 6327.36726327382, 6338.968305158444, 4197.853075142355, 4762.666613337033, 4783.210770222874, 4802.471728109836, 4820.590249828804, 5388.919166212589, 5432.049391356576, 5786.797462088806, 6026.939730602813, 6097.573169878244, 4745.952540474551, 5743.798967036134, 5762.193292938908, 5803.390643982321, 5808.912866306924, 5462.716261678582, 5543.0, 5604.327480702342, 5715.685637289989, 5767.44279044664, 5547.750351234124, 5561.499465048165, 5577.609567330248, 5597.5521958242425, 5647.7176141193595, 4993.650444468853, 5024.497550244946, 5723.656580605177, 5744.3681195067675, 5747.252857128547, 4900.338454308765, 5121.74391280442, 5366.195070739421, 5614.466625670555, 5635.774569017193], "value": 4510.6321004251095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.4645785347768, 732.9083864516936, 778.4544539356305, 793.797581616688, 812.9512229266246, 15095.245237738123, 15631.843681563181, 15647.608809779762, 15652.139132347716, 15677.529674109795, 11760.411979401038, 13586.456541738344, 13657.521986730466, 13796.517121900735, 14533.418663253484, 830.3357314148657, 852.3309202276214, 952.6713283917038, 974.3763991045737, 976.3068221562698, 966.1932286540712, 967.1827305926516, 967.9467629280387, 969.030969030969, 969.1277849935044, 995.8804943406801, 1044.4882007816154, 1048.9895101049005, 1049.2235745548317, 1049.7847941172042, 1028.7839553693734, 1032.6334151376266, 1033.2663808695836, 1036.4506811389974, 1036.6009086501676, 750.7635094945075, 752.0899711349274, 753.5779963220559, 754.6641744423733, 755.3051192902543, 15666.530004099895, 15708.60728481797, 16043.679126417523, 16047.919760401122, 16066.036037837766, 14502.492412765609, 14884.255787210705, 14887.702245955128, 14899.478518251917, 14925.2537373132, 10824.512896919658, 11637.767244655059, 11694.0, 11751.882481175164, 11859.52561897523, 3678.889633311005, 3788.7916164610933, 3793.089658481972, 3798.3352913240074, 3799.126200973764, 96989.51505242499, 106101.46949265205, 108626.0, 110038.0, 110182.0, 9994.750131246778, 11355.710549258929, 11503.827442588368, 11618.186726929034, 11629.47667354971, 24438.0, 24862.881170347377, 24875.751242487706, 24959.62560561593, 25009.87455564504, 7794.2985131338455, 7797.337226335825, 7893.486923349964, 8019.278264956065, 8127.918720812834, 4157.355609880473, 4281.464816897887, 4283.145332679448, 4323.54602766705, 4324.308110702273, 3393.6776006279365, 3506.8772592959126, 3512.613612502623, 3513.2446523997464, 3519.0, 618.1716499890168, 618.3939739055661, 621.1180124223613, 643.9903401448983, 645.1741770533724, 523.3850225984529, 532.1830989431204, 559.1947595462602, 630.8675178212627, 641.2721561028267, 9267.351285409957, 9586.568604412818, 9702.320837541305, 10017.849732254022, 10022.0, 553.3581045986716, 573.6959411511905, 582.726118724199, 622.4957784194817, 711.4237467651116, 4454.1982443159895, 4888.304862023667, 4927.162382395001, 5240.0, 5246.108161612535, 79463.60268198738, 84132.57933710293, 86664.0, 86969.56515217383, 89478.0, 48657.513424866, 52966.86411063441, 72319.63840180765, 80232.59883700544, 82781.58609206915, 64846.67576662275, 66302.66848665726, 67495.0, 69761.3023869765, 71565.92651110237, 9504.85742713846, 9966.651167209113, 10609.840852387066, 10733.355998640107, 10745.785084298426, 40916.59083409128, 41063.589364105974, 42382.5761742391, 43477.56522434735, 44383.55616443794, 54759.178612321644, 56102.316930492154, 56103.036393726026, 56216.43783562275, 56263.0, 13213.273269970245, 13654.93172534131, 13760.0, 13808.930955345159, 14139.575812725634, 14315.57053288403, 14397.928010359881, 14523.564293071226, 14737.0, 15002.549923502313, 13542.59372218835, 13686.384112715152, 13703.862961370258, 13784.0, 14145.222012789396, 11110.61112861046, 13579.0, 13688.726225475633, 13706.504841265414, 14037.1577705338, 10996.615118470816, 13419.530316438877, 13424.99312551543, 13432.19406835593, 13775.724485510435, 10977.0, 10987.340759554452, 11129.387883666475, 11138.227883192129, 11779.293242405483, 10270.691879243635, 10801.729956750829, 11126.61056863006, 11140.498677559692, 11177.72055698614], "value": 7448.020124769976, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 115.0, 115.0, 116.0, 115.0, 116.0, 115.0, 114.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 111.0, 151.0, 112.0, 110.0, 111.0, 108.0, 132.0, 111.0], "value": 120.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 184.0, 187.0, 188.0, 186.0, 182.0, 182.0, 184.0, 184.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 63.0, 58.0, 93.0, 61.0, 62.0, 60.0, 58.0, 77.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 89.0, 69.0, 68.0, 68.0, 86.0, 94.0, 99.0, 117.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 87.0, 87.0, 87.0, 86.0, 87.0, 87.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 106.0, 149.0, 94.0, 95.0, 94.0, 94.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 84.0, 84.0, 85.0, 105.0, 73.0, 128.0, 136.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 61.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 61.0, 66.0, 68.0, 61.0, 59.0, 59.0, 59.0, 57.0, 62.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 81.0, 83.0, 96.0, 104.0, 101.0, 104.0, 82.0, 83.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 161.0, 139.0, 154.0, 131.0, 136.0, 138.0, 137.0, 181.0, 142.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 183.0, 181.0, 185.0, 181.0, 185.0, 186.0, 183.0, 182.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 67.0, 67.0, 68.0, 67.0, 68.0, 65.0, 67.0], "value": 67.3, "unit": "score"}]}]} 08:02:10 INFO - Return code: 0 08:02:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:02:10 ERROR - # TBPL SUCCESS # 08:02:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:02:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:02:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:02:10 INFO - 2017-03-17 08:02:10.815 screenresolution[1650:9798] starting screenresolution argv=screenresolution get 08:02:10 INFO - 2017-03-17 08:02:10.818 screenresolution[1650:9798] Display 0: 1600x1200x32@60 08:02:10 INFO - 2017-03-17 08:02:10.824 screenresolution[1651:9801] starting screenresolution argv=screenresolution list 08:02:10 INFO - Available Modes on Display 0 08:02:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:02:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:02:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:02:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:02:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:02:11 INFO - Intel Iris: 08:02:11 INFO - Chipset Model: Intel Iris 08:02:11 INFO - Type: GPU 08:02:11 INFO - Bus: Built-In 08:02:11 INFO - VRAM (Dynamic, Max): 1536 MB 08:02:11 INFO - Vendor: Intel (0x8086) 08:02:11 INFO - Device ID: 0x0a2e 08:02:11 INFO - Revision ID: 0x0009 08:02:11 INFO - Displays: 08:02:11 INFO - Display: 08:02:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:02:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:02:11 INFO - Display Serial Number: 1600x1200 60 08:02:11 INFO - Main Display: Yes 08:02:11 INFO - Mirror: Off 08:02:11 INFO - Online: Yes 08:02:11 INFO - Rotation: Supported 08:02:11 INFO - Return code: 0 08:02:11 INFO - Running post-action listener: _resource_record_post_action 08:02:11 INFO - [mozharness: 2017-03-17 15:02:11.058972Z] Finished run-tests step (success) 08:02:11 INFO - Running post-run listener: _resource_record_post_run 08:02:11 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 39580672; Write bytes: 514483200; Read time: 394; Write time: 1736 08:02:11 INFO - TinderboxPrint: CPU usage
53.5% 08:02:11 INFO - TinderboxPrint: I/O read bytes / time
39,580,672 / 394 08:02:11 INFO - TinderboxPrint: I/O write bytes / time
514,483,200 / 1,736 08:02:11 INFO - TinderboxPrint: CPU idle
2,237.1 (46.5%) 08:02:11 INFO - TinderboxPrint: CPU system
961.9 (20.0%) 08:02:11 INFO - TinderboxPrint: CPU user
1,614.7 (33.5%) 08:02:11 INFO - TinderboxPrint: Swap in / out
248,422,400 / 0 08:02:11 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207892992; Write bytes: 204314112; Read time: 15705; Write time: 424 08:02:11 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 37446656; Write bytes: 299142656; Read time: 369; Write time: 1277 08:02:11 INFO - Running post-run listener: _upload_blobber_files 08:02:11 INFO - Blob upload gear active. 08:02:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:02:11 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 08:02:11 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'] 08:02:11 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 08:02:11 INFO - (blobuploader) - INFO - Open directory for files ... 08:02:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:02:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:02:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:12 INFO - (blobuploader) - INFO - Done attempting. 08:02:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:02:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:02:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:13 INFO - (blobuploader) - INFO - Done attempting. 08:02:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:02:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:02:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:14 INFO - (blobuploader) - INFO - Done attempting. 08:02:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:02:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:02:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:16 INFO - (blobuploader) - INFO - Done attempting. 08:02:16 INFO - (blobuploader) - INFO - Iteration through files over. 08:02:16 INFO - Return code: 0 08:02:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:02:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:02:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdeabb17a7dedda5e3eaf92eea987ac41fed8f972cfd143daba1490f394303c2bbf9ca13b37f758977b29d1165ef519e93c061802c0e29775dcd068e23c255df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/285111f9cc65becf26a619a7c349e756767dcc3e3ab53f714c71846ff7e275ece8f0e29435c7b08bb475cdb538c2925f2e1faf16e9a1e2fec026b7284786b554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c890bdb36843116ffa5eb2534d3f99d3a06b6f586cf8dbad343103edb1236e5d8ceac2b4efa3480418a3679112eee01252aa684c1961151a857e36c733898b0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a9011ce802b2edc7636f3559a932cd7aa9e450055d4747768883c739bb7b910720c7ca62a58069c1844d7cd99f252f1bb3f37b749a49b47953471c50c58e06f"} 08:02:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:02:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:02:16 INFO - Contents: 08:02:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdeabb17a7dedda5e3eaf92eea987ac41fed8f972cfd143daba1490f394303c2bbf9ca13b37f758977b29d1165ef519e93c061802c0e29775dcd068e23c255df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/285111f9cc65becf26a619a7c349e756767dcc3e3ab53f714c71846ff7e275ece8f0e29435c7b08bb475cdb538c2925f2e1faf16e9a1e2fec026b7284786b554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c890bdb36843116ffa5eb2534d3f99d3a06b6f586cf8dbad343103edb1236e5d8ceac2b4efa3480418a3679112eee01252aa684c1961151a857e36c733898b0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a9011ce802b2edc7636f3559a932cd7aa9e450055d4747768883c739bb7b910720c7ca62a58069c1844d7cd99f252f1bb3f37b749a49b47953471c50c58e06f"} 08:02:16 INFO - Running post-run listener: copy_logs_to_upload_dir 08:02:16 INFO - Copying logs to upload dir... 08:02:16 INFO - mkdir: /builds/slave/test/build/upload/logs 08:02:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.026299 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-17 08:02:16.765740) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 08:02:16.769908) ========= 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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdeabb17a7dedda5e3eaf92eea987ac41fed8f972cfd143daba1490f394303c2bbf9ca13b37f758977b29d1165ef519e93c061802c0e29775dcd068e23c255df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/285111f9cc65becf26a619a7c349e756767dcc3e3ab53f714c71846ff7e275ece8f0e29435c7b08bb475cdb538c2925f2e1faf16e9a1e2fec026b7284786b554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c890bdb36843116ffa5eb2534d3f99d3a06b6f586cf8dbad343103edb1236e5d8ceac2b4efa3480418a3679112eee01252aa684c1961151a857e36c733898b0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a9011ce802b2edc7636f3559a932cd7aa9e450055d4747768883c739bb7b910720c7ca62a58069c1844d7cd99f252f1bb3f37b749a49b47953471c50c58e06f"} build_url:https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/RzBQUccuQ5mG5FproU3XxA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdeabb17a7dedda5e3eaf92eea987ac41fed8f972cfd143daba1490f394303c2bbf9ca13b37f758977b29d1165ef519e93c061802c0e29775dcd068e23c255df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/285111f9cc65becf26a619a7c349e756767dcc3e3ab53f714c71846ff7e275ece8f0e29435c7b08bb475cdb538c2925f2e1faf16e9a1e2fec026b7284786b554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c890bdb36843116ffa5eb2534d3f99d3a06b6f586cf8dbad343103edb1236e5d8ceac2b4efa3480418a3679112eee01252aa684c1961151a857e36c733898b0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a9011ce802b2edc7636f3559a932cd7aa9e450055d4747768883c739bb7b910720c7ca62a58069c1844d7cd99f252f1bb3f37b749a49b47953471c50c58e06f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 08:02:16.809358) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 08:02:16.809750) ========= 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.vpinBz7TOz/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.9ZpyJ91KjB/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.005239 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 08:02:16.859219) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 08:02:16.859661) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 08:02:16.860142) ========= ========= Total master_lag: 0.19 =========