builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1481336349.35 results: success (0) buildid: 20161209153551 builduid: 792a89b870244446b6140db22a522e73 revision: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.352957) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.353332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.353750) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.425369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.425742) ========= 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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-09 18:19:09-- 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% 8.16M=0.001s 2016-12-09 18:19:09 (8.16 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.258391 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.710229) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.710974) ========= 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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.019931 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:09.770779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 18:19:09.771609) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 73bdd29461aef3297b099ba25e8b317a18a0a4b9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 73bdd29461aef3297b099ba25e8b317a18a0a4b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SfXTkyK0h1/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.hydcvVK4Ol/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-09 18:19:09,850 truncating revision to first 12 chars 2016-12-09 18:19:09,850 Setting DEBUG logging. 2016-12-09 18:19:09,850 attempt 1/10 2016-12-09 18:19:09,850 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/73bdd29461ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-09 18:19:10,843 unpacking tar archive at: graphics-73bdd29461ae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.244919 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 18:19:11.044914) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:11.045312) ========= script_repo_revision: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:11.045796) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:11.046193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 18:19:11.066705) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-09 18:19:11.067295) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SfXTkyK0h1/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.hydcvVK4Ol/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:11 INFO - MultiFileLogger online at 20161209 18:19:11 in /builds/slave/test 18:19:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 18:19:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:19:11 INFO - {'append_to_log': False, 18:19:11 INFO - 'base_work_dir': '/builds/slave/test', 18:19:11 INFO - 'blob_upload_branch': 'Graphics', 18:19:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:19:11 INFO - 'branch': 'Graphics', 18:19:11 INFO - 'buildbot_json_path': 'buildprops.json', 18:19:11 INFO - 'config_files': ('talos/mac_config.py',), 18:19:11 INFO - 'default_actions': ('clobber', 18:19:11 INFO - 'read-buildbot-config', 18:19:11 INFO - 'download-and-extract', 18:19:11 INFO - 'populate-webroot', 18:19:11 INFO - 'create-virtualenv', 18:19:11 INFO - 'install', 18:19:11 INFO - 'run-tests'), 18:19:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:19:11 INFO - 'download_minidump_stackwalk': True, 18:19:11 INFO - 'download_symbols': 'ondemand', 18:19:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:19:11 INFO - 'tooltool.py': '/tools/tooltool.py', 18:19:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:19:11 INFO - '/tools/misc-python/virtualenv.py')}, 18:19:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:19:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:19:11 INFO - 'installer_path': 'installer.exe', 18:19:11 INFO - 'log_level': 'info', 18:19:11 INFO - 'log_name': 'talos', 18:19:11 INFO - 'log_to_console': True, 18:19:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:19:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:19:11 INFO - 'opt_config_files': (), 18:19:11 INFO - 'pip_index': False, 18:19:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:19:11 INFO - 'cmd': ('bash', 18:19:11 INFO - '-c', 18:19:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:19:11 INFO - 'enabled': True, 18:19:11 INFO - 'halt_on_failure': False, 18:19:11 INFO - 'name': 'check_screen_resolution'},), 18:19:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:19:11 INFO - 'cmd': ('bash', 18:19:11 INFO - '-c', 18:19:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:19:11 INFO - 'enabled': True, 18:19:11 INFO - 'halt_on_failure': False, 18:19:11 INFO - 'name': 'check_screen_resolution'},), 18:19:11 INFO - 'run_cmd_checks_enabled': True, 18:19:11 INFO - 'sps_profile': False, 18:19:11 INFO - 'sps_profile_interval': 0, 18:19:11 INFO - 'suite': 'dromaeojs-e10s', 18:19:11 INFO - 'system_bits': '32', 18:19:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:19:11 INFO - 'title': 't-yosemite-r7-0111', 18:19:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:19:11 INFO - 'use_talos_json': True, 18:19:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:19:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:19:11 INFO - 'work_dir': 'build'} 18:19:11 INFO - [mozharness: 2016-12-10 02:19:11.274297Z] Running clobber step. 18:19:11 INFO - Running pre-action listener: _resource_record_pre_action 18:19:11 INFO - Running main action method: clobber 18:19:11 INFO - rmtree: /builds/slave/test/build 18:19:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:19:12 INFO - Running post-action listener: _resource_record_post_action 18:19:12 INFO - [mozharness: 2016-12-10 02:19:12.884596Z] Finished clobber step (success) 18:19:12 INFO - [mozharness: 2016-12-10 02:19:12.884670Z] Running read-buildbot-config step. 18:19:12 INFO - Running pre-action listener: _resource_record_pre_action 18:19:12 INFO - Running main action method: read_buildbot_config 18:19:12 INFO - Using buildbot properties: 18:19:12 INFO - { 18:19:12 INFO - "project": "", 18:19:12 INFO - "product": "firefox", 18:19:12 INFO - "script_repo_revision": "default", 18:19:12 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 18:19:12 INFO - "repository": "", 18:19:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 18:19:12 INFO - "buildid": "20161209153551", 18:19:12 INFO - "pgo_build": "False", 18:19:12 INFO - "basedir": "/builds/slave/test", 18:19:12 INFO - "buildnumber": 4, 18:19:12 INFO - "slavename": "t-yosemite-r7-0111", 18:19:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:19:12 INFO - "platform": "yosemite_r7", 18:19:12 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 18:19:12 INFO - "branch": "graphics", 18:19:12 INFO - "repo_path": "projects/graphics", 18:19:12 INFO - "revision": "73bdd29461aef3297b099ba25e8b317a18a0a4b9", 18:19:12 INFO - "stage_platform": "macosx64", 18:19:12 INFO - "builduid": "792a89b870244446b6140db22a522e73", 18:19:12 INFO - "slavebuilddir": "test" 18:19:12 INFO - } 18:19:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg. 18:19:12 INFO - Running post-action listener: _resource_record_post_action 18:19:12 INFO - [mozharness: 2016-12-10 02:19:12.887534Z] Finished read-buildbot-config step (success) 18:19:12 INFO - [mozharness: 2016-12-10 02:19:12.887635Z] Running download-and-extract step. 18:19:12 INFO - Running pre-action listener: _resource_record_pre_action 18:19:12 INFO - Running main action method: download_and_extract 18:19:12 INFO - mkdir: /builds/slave/test/build/tests 18:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:12 INFO - trying https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json 18:19:12 INFO - Downloading https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 18:19:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json'}, attempt #1 18:19:13 INFO - Downloaded 1235 bytes. 18:19:13 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 18:19:13 INFO - Using the following test package requirements: 18:19:13 INFO - {u'common': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 18:19:13 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'firefox-53.0a1.en-US.mac64.cppunittest.tests.zip'], 18:19:13 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'firefox-53.0a1.en-US.mac64.gtest.tests.zip'], 18:19:13 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'jsshell-mac64.zip'], 18:19:13 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'firefox-53.0a1.en-US.mac64.mochitest.tests.zip'], 18:19:13 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 18:19:13 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'firefox-53.0a1.en-US.mac64.reftest.tests.zip'], 18:19:13 INFO - u'talos': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'firefox-53.0a1.en-US.mac64.talos.tests.zip'], 18:19:13 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'firefox-53.0a1.en-US.mac64.web-platform.tests.zip'], 18:19:13 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 18:19:13 INFO - u'firefox-53.0a1.en-US.mac64.xpcshell.tests.zip']} 18:19:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip'] for test suite category: common 18:19:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 18:19:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 18:19:13 INFO - Fetch https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 18:19:14 INFO - Expected file size: 16287286 18:19:14 INFO - Obtained file size: 16287286 18:19:21 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip', u'firefox-53.0a1.en-US.mac64.talos.tests.zip'] for test suite category: talos 18:19:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 18:19:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 18:19:21 INFO - Fetch https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 18:19:22 INFO - Expected file size: 16287286 18:19:22 INFO - Obtained file size: 16287286 18:19:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip 18:19:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip'}, attempt #1 18:19:29 INFO - Fetch https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip into memory 18:19:30 INFO - Expected file size: 14409734 18:19:30 INFO - Obtained file size: 14409734 18:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:30 INFO - trying https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 18:19:30 INFO - Downloading https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg 18:19:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg'}, attempt #1 18:19:32 INFO - Downloaded 69778992 bytes. 18:19:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 18:19:32 INFO - mkdir: /builds/slave/test/properties 18:19:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:19:32 INFO - Writing to file /builds/slave/test/properties/build_url 18:19:32 INFO - Contents: 18:19:32 INFO - build_url:https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 18:19:33 INFO - Running post-action listener: _resource_record_post_action 18:19:33 INFO - Running post-action listener: set_extra_try_arguments 18:19:33 INFO - [mozharness: 2016-12-10 02:19:33.354683Z] Finished download-and-extract step (success) 18:19:33 INFO - [mozharness: 2016-12-10 02:19:33.354804Z] Running populate-webroot step. 18:19:33 INFO - Running pre-action listener: _resource_record_pre_action 18:19:33 INFO - Running main action method: populate_webroot 18:19:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:19:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:19:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:19:33 INFO - '--tppagecycles', 18:19:33 INFO - '7'], 18:19:33 INFO - 'tests': ['tsvgm']}}, 18:19:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:19:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:19:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:19:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:19:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:19:33 INFO - 'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:19:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:19:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:19:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:19:33 INFO - 'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['damp', 'tps']}, 18:19:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:19:33 INFO - 'tests': ['damp', 'tps']}, 18:19:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['dromaeo_dom']}, 18:19:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:19:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['basic_compositor_video']}, 18:19:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:19:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['a11yr', 18:19:33 INFO - 'ts_paint', 18:19:33 INFO - 'tpaint', 18:19:33 INFO - 'sessionrestore', 18:19:33 INFO - 'sessionrestore_no_auto_restore', 18:19:33 INFO - 'tabpaint']}, 18:19:33 INFO - 'other-e10s': {'tests': ['a11yr', 18:19:33 INFO - 'ts_paint', 18:19:33 INFO - 'tpaint', 18:19:33 INFO - 'sessionrestore', 18:19:33 INFO - 'sessionrestore_no_auto_restore', 18:19:33 INFO - 'tabpaint']}, 18:19:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:19:33 INFO - 'ts_paint', 18:19:33 INFO - 'tpaint', 18:19:33 INFO - 'sessionrestore', 18:19:33 INFO - 'sessionrestore_no_auto_restore', 18:19:33 INFO - 'tabpaint']}, 18:19:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:19:33 INFO - 'ts_paint', 18:19:33 INFO - 'tpaint', 18:19:33 INFO - 'sessionrestore', 18:19:33 INFO - 'sessionrestore_no_auto_restore', 18:19:33 INFO - 'tabpaint']}, 18:19:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['a11yr', 18:19:33 INFO - 'ts_paint', 18:19:33 INFO - 'tpaint', 18:19:33 INFO - 'sessionrestore', 18:19:33 INFO - 'sessionrestore_no_auto_restore', 18:19:33 INFO - 'tabpaint']}, 18:19:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['a11yr', 18:19:33 INFO - 'ts_paint', 18:19:33 INFO - 'tpaint', 18:19:33 INFO - 'sessionrestore', 18:19:33 INFO - 'sessionrestore_no_auto_restore', 18:19:33 INFO - 'tabpaint']}, 18:19:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['tsvgx', 18:19:33 INFO - 'tsvgr_opacity', 18:19:33 INFO - 'tart', 18:19:33 INFO - 'tscrollx', 18:19:33 INFO - 'cart', 18:19:33 INFO - 'tsvg_static']}, 18:19:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:19:33 INFO - 'tsvgr_opacity', 18:19:33 INFO - 'tart', 18:19:33 INFO - 'tscrollx', 18:19:33 INFO - 'cart', 18:19:33 INFO - 'tsvg_static']}, 18:19:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:19:33 INFO - 'talos_options': ['--disable-e10s'], 18:19:33 INFO - 'tests': ['tp5o']}, 18:19:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:19:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:19:33 INFO - 'talos_options': ['--disable-e10s', 18:19:33 INFO - '--xperf_path', 18:19:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:19:33 INFO - 'tests': ['tp5n']}, 18:19:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:19:33 INFO - 'talos_options': ['--xperf_path', 18:19:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:19:33 INFO - 'tests': ['tp5n']}}, 18:19:33 INFO - 'talos.zip': {'path': '', 18:19:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:19:33 INFO - Running post-action listener: _resource_record_post_action 18:19:33 INFO - [mozharness: 2016-12-10 02:19:33.372771Z] Finished populate-webroot step (success) 18:19:33 INFO - [mozharness: 2016-12-10 02:19:33.372899Z] Running create-virtualenv step. 18:19:33 INFO - Running pre-action listener: _resource_record_pre_action 18:19:33 INFO - Running main action method: create_virtualenv 18:19:33 INFO - Creating virtualenv /builds/slave/test/build/venv 18:19:33 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 18:19:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:19:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:19:33 INFO - Using real prefix '/tools/python27' 18:19:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:19:34 INFO - Installing distribute.............................................................................................................................................................................................done. 18:19:37 INFO - Installing pip.................done. 18:19:37 INFO - Return code: 0 18:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:19:37 INFO - Reading from file tmpfile_stdout 18:19:37 INFO - Output received: 18:19:37 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) 18:19:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:19:37 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')]} 18:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:37 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')]} 18:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:37 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:37 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 18:19:37 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 18:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:37 INFO - 'HOME': '/Users/cltbld', 18:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:37 INFO - 'LOGNAME': 'cltbld', 18:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:37 INFO - 'MOZ_NO_REMOTE': '1', 18:19:37 INFO - 'NO_EM_RESTART': '1', 18:19:37 INFO - 'PAGER': '/bin/cat', 18:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:37 INFO - 'PWD': '/builds/slave/test', 18:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:37 INFO - 'SHELL': '/bin/bash', 18:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:37 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:37 INFO - 'USER': 'cltbld', 18:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:37 INFO - 'XPC_FLAGS': '0x0', 18:19:37 INFO - 'XPC_SERVICE_NAME': '0', 18:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:37 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 18:19:37 INFO - Cleaning up... 18:19:37 INFO - Return code: 0 18:19:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:19:37 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')]} 18:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:37 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')]} 18:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:37 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:37 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 18:19:37 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 18:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:37 INFO - 'HOME': '/Users/cltbld', 18:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:37 INFO - 'LOGNAME': 'cltbld', 18:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:37 INFO - 'MOZ_NO_REMOTE': '1', 18:19:37 INFO - 'NO_EM_RESTART': '1', 18:19:37 INFO - 'PAGER': '/bin/cat', 18:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:37 INFO - 'PWD': '/builds/slave/test', 18:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:37 INFO - 'SHELL': '/bin/bash', 18:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:37 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:37 INFO - 'USER': 'cltbld', 18:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:37 INFO - 'XPC_FLAGS': '0x0', 18:19:37 INFO - 'XPC_SERVICE_NAME': '0', 18:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:37 INFO - Downloading/unpacking psutil>=3.1.1 18:19:37 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 18:19:37 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 18:19:37 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 18:19:37 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 18:19:37 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 18:19:37 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 18:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:19:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:19:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:19:41 INFO - Installing collected packages: psutil 18:19:41 INFO - Running setup.py install for psutil 18:19:41 INFO - building 'psutil._psutil_osx' extension 18:19:41 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 18:19: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:19: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:19:42 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 18:19:42 INFO - building 'psutil._psutil_posix' extension 18:19:42 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 18:19:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:19:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:19:42 INFO - ^ 18:19:42 INFO - 1 warning generated. 18:19:42 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 18:19:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:19:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:19:42 INFO - Successfully installed psutil 18:19:42 INFO - Cleaning up... 18:19:42 INFO - Return code: 0 18:19:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:19:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:19:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:42 INFO - 'HOME': '/Users/cltbld', 18:19:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:42 INFO - 'LOGNAME': 'cltbld', 18:19:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:42 INFO - 'MOZ_NO_REMOTE': '1', 18:19:42 INFO - 'NO_EM_RESTART': '1', 18:19:42 INFO - 'PAGER': '/bin/cat', 18:19:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:42 INFO - 'PWD': '/builds/slave/test', 18:19:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:42 INFO - 'SHELL': '/bin/bash', 18:19:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:42 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:42 INFO - 'USER': 'cltbld', 18:19:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:42 INFO - 'XPC_FLAGS': '0x0', 18:19:42 INFO - 'XPC_SERVICE_NAME': '0', 18:19:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:19:42 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 18:19:42 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 18:19:42 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 18:19:42 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 18:19:42 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 18:19:42 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 18:19:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:19:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:19:45 INFO - Installing collected packages: mozsystemmonitor 18:19:45 INFO - Running setup.py install for mozsystemmonitor 18:19:45 INFO - Successfully installed mozsystemmonitor 18:19:45 INFO - Cleaning up... 18:19:45 INFO - Return code: 0 18:19:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:45 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:45 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 18:19:45 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 18:19:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:45 INFO - 'HOME': '/Users/cltbld', 18:19:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:45 INFO - 'LOGNAME': 'cltbld', 18:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:45 INFO - 'MOZ_NO_REMOTE': '1', 18:19:45 INFO - 'NO_EM_RESTART': '1', 18:19:45 INFO - 'PAGER': '/bin/cat', 18:19:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:45 INFO - 'PWD': '/builds/slave/test', 18:19:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:45 INFO - 'SHELL': '/bin/bash', 18:19:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:45 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:45 INFO - 'USER': 'cltbld', 18:19:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:45 INFO - 'XPC_FLAGS': '0x0', 18:19:45 INFO - 'XPC_SERVICE_NAME': '0', 18:19:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:45 INFO - Downloading/unpacking jsonschema==2.5.1 18:19:45 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 18:19:45 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 18:19:45 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 18:19:45 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 18:19:45 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 18:19:45 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 18:19:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:19:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:19: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 18:19: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 18:19: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 18:19: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 18:19: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 18:19: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 18:19:48 INFO - Downloading functools32-3.2.3-2.tar.gz 18:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:19:48 INFO - warning: no files found matching '*.txt' 18:19:48 INFO - no previously-included directories found matching 'build' 18:19:48 INFO - no previously-included directories found matching 'dist' 18:19:48 INFO - no previously-included directories found matching '.git*' 18:19:48 INFO - Installing collected packages: jsonschema, functools32 18:19:49 INFO - Running setup.py install for functools32 18:19:49 INFO - warning: no files found matching '*.txt' 18:19:49 INFO - no previously-included directories found matching 'build' 18:19:49 INFO - no previously-included directories found matching 'dist' 18:19:49 INFO - no previously-included directories found matching '.git*' 18:19:49 INFO - Successfully installed jsonschema functools32 18:19:49 INFO - Cleaning up... 18:19:49 INFO - Return code: 0 18:19:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:49 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:49 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 18:19:49 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 18:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:49 INFO - 'HOME': '/Users/cltbld', 18:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:49 INFO - 'LOGNAME': 'cltbld', 18:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:49 INFO - 'MOZ_NO_REMOTE': '1', 18:19:49 INFO - 'NO_EM_RESTART': '1', 18:19:49 INFO - 'PAGER': '/bin/cat', 18:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:49 INFO - 'PWD': '/builds/slave/test', 18:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:49 INFO - 'SHELL': '/bin/bash', 18:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:49 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:49 INFO - 'USER': 'cltbld', 18:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:49 INFO - 'XPC_FLAGS': '0x0', 18:19:49 INFO - 'XPC_SERVICE_NAME': '0', 18:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:19:49 INFO - Cleaning up... 18:19:49 INFO - Return code: 0 18:19:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:49 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:49 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 18:19:49 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 18:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:49 INFO - 'HOME': '/Users/cltbld', 18:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:49 INFO - 'LOGNAME': 'cltbld', 18:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:49 INFO - 'MOZ_NO_REMOTE': '1', 18:19:49 INFO - 'NO_EM_RESTART': '1', 18:19:49 INFO - 'PAGER': '/bin/cat', 18:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:49 INFO - 'PWD': '/builds/slave/test', 18:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:49 INFO - 'SHELL': '/bin/bash', 18:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:49 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:49 INFO - 'USER': 'cltbld', 18:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:49 INFO - 'XPC_FLAGS': '0x0', 18:19:49 INFO - 'XPC_SERVICE_NAME': '0', 18:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:49 INFO - Downloading/unpacking blobuploader==1.2.4 18:19:49 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 18:19:49 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 18:19:49 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 18:19:49 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 18:19:49 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 18:19:49 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 18:19:52 INFO - Downloading blobuploader-1.2.4.tar.gz 18:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:19:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:19: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 18:19: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 18:19: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 18:19: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 18:19: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 18:19: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 18:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:19:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:19:53 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 18:19:53 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 18:19:53 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 18:19:53 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 18:19:53 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 18:19:53 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 18:19:53 INFO - Downloading docopt-0.6.1.tar.gz 18:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:19:53 INFO - Installing collected packages: blobuploader, requests, docopt 18:19:53 INFO - Running setup.py install for blobuploader 18:19:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:19:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:19:53 INFO - Running setup.py install for requests 18:19:54 INFO - Running setup.py install for docopt 18:19:54 INFO - Successfully installed blobuploader requests docopt 18:19:54 INFO - Cleaning up... 18:19:54 INFO - Return code: 0 18:19:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:54 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:54 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 18:19:54 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 18:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:54 INFO - 'HOME': '/Users/cltbld', 18:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:54 INFO - 'LOGNAME': 'cltbld', 18:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:54 INFO - 'MOZ_NO_REMOTE': '1', 18:19:54 INFO - 'NO_EM_RESTART': '1', 18:19:54 INFO - 'PAGER': '/bin/cat', 18:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:54 INFO - 'PWD': '/builds/slave/test', 18:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:54 INFO - 'SHELL': '/bin/bash', 18:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:54 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:54 INFO - 'USER': 'cltbld', 18:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:54 INFO - 'XPC_FLAGS': '0x0', 18:19:54 INFO - 'XPC_SERVICE_NAME': '0', 18:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:54 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4aXU88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:54 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-KE24QE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:54 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zIVLBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-V3Xl9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-msWAxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-dL9OWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-dqzNar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-MpMmQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-E4XaSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-BqCkyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UTCPb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-3ALvj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:19:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-EOsEi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:19:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-JnlMBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-TbzaJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:19:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zztBa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:19:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_ssUTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:19:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:19:56 INFO - Running setup.py install for manifestparser 18:19:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:19:56 INFO - Running setup.py install for mozcrash 18:19:56 INFO - Running setup.py install for mozdebug 18:19:56 INFO - Running setup.py install for mozdevice 18:19:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Running setup.py install for mozfile 18:19:57 INFO - Running setup.py install for mozhttpd 18:19:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Running setup.py install for mozinfo 18:19:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Running setup.py install for mozInstall 18:19:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:19:57 INFO - Running setup.py install for mozleak 18:19:57 INFO - Running setup.py install for mozlog 18:19:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:19:58 INFO - Running setup.py install for moznetwork 18:19:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:19:58 INFO - Running setup.py install for mozprocess 18:19:58 INFO - Running setup.py install for mozprofile 18:19:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:19:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:19:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:19:58 INFO - Running setup.py install for mozrunner 18:19:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:19:58 INFO - Running setup.py install for mozscreenshot 18:19:58 INFO - Running setup.py install for moztest 18:19:58 INFO - Running setup.py install for mozversion 18:19:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:19:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:19:59 INFO - Cleaning up... 18:19:59 INFO - Return code: 0 18:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:59 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:19:59 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 18:19:59 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 18:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:59 INFO - 'HOME': '/Users/cltbld', 18:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:59 INFO - 'LOGNAME': 'cltbld', 18:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:59 INFO - 'MOZ_NO_REMOTE': '1', 18:19:59 INFO - 'NO_EM_RESTART': '1', 18:19:59 INFO - 'PAGER': '/bin/cat', 18:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:59 INFO - 'PWD': '/builds/slave/test', 18:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:59 INFO - 'SHELL': '/bin/bash', 18:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:19:59 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:59 INFO - 'USER': 'cltbld', 18:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:59 INFO - 'XPC_FLAGS': '0x0', 18:19:59 INFO - 'XPC_SERVICE_NAME': '0', 18:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-iJ4yCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:59 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)) 18:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-LcZrME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:59 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)) 18:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zuwz9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:59 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)) 18:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-MDYc4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:59 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)) 18:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YzK5r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:59 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)) 18:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-mOFOA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7xc_Qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UHYtkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-cDucKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DKRDKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-BzZsLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Xtjdl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yxFPgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-GtwrLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-V_Eo4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:00 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)) 18:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-hdET2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YmuOgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:01 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)) 18:20:01 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)) 18:20:01 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)) 18:20:01 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)) 18:20:01 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)) 18:20:01 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)) 18:20:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:03 INFO - Downloading blessings-1.6.tar.gz 18:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:20:03 INFO - Installing collected packages: blessings 18:20:03 INFO - Running setup.py install for blessings 18:20:04 INFO - Successfully installed blessings 18:20:04 INFO - Cleaning up... 18:20:04 INFO - Return code: 0 18:20:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:20:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:04 INFO - Reading from file tmpfile_stdout 18:20:04 INFO - Current package versions: 18:20:04 INFO - blessings == 1.6 18:20:04 INFO - blobuploader == 1.2.4 18:20:04 INFO - docopt == 0.6.1 18:20:04 INFO - functools32 == 3.2.3-2 18:20:04 INFO - jsonschema == 2.5.1 18:20:04 INFO - manifestparser == 1.1 18:20:04 INFO - mozInstall == 1.12 18:20:04 INFO - mozcrash == 1.0 18:20:04 INFO - mozdebug == 0.1 18:20:04 INFO - mozdevice == 0.49 18:20:04 INFO - mozfile == 1.2 18:20:04 INFO - mozhttpd == 0.7 18:20:04 INFO - mozinfo == 0.9 18:20:04 INFO - mozleak == 0.1 18:20:04 INFO - mozlog == 3.4 18:20:04 INFO - moznetwork == 0.27 18:20:04 INFO - mozprocess == 0.23 18:20:04 INFO - mozprofile == 0.28 18:20:04 INFO - mozrunner == 6.13 18:20:04 INFO - mozscreenshot == 0.1 18:20:04 INFO - mozsystemmonitor == 0.3 18:20:04 INFO - moztest == 0.7 18:20:04 INFO - mozversion == 1.4 18:20:04 INFO - psutil == 3.1.1 18:20:04 INFO - requests == 1.2.3 18:20:04 INFO - wsgiref == 0.1.2 18:20:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:04 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 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:20:04 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 18:20:04 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 18:20:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:20:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:04 INFO - 'HOME': '/Users/cltbld', 18:20:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:04 INFO - 'LOGNAME': 'cltbld', 18:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:04 INFO - 'MOZ_NO_REMOTE': '1', 18:20:04 INFO - 'NO_EM_RESTART': '1', 18:20:04 INFO - 'PAGER': '/bin/cat', 18:20:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:04 INFO - 'PWD': '/builds/slave/test', 18:20:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:04 INFO - 'SHELL': '/bin/bash', 18:20:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:20:04 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:20:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:04 INFO - 'USER': 'cltbld', 18:20:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:04 INFO - 'XPC_FLAGS': '0x0', 18:20:04 INFO - 'XPC_SERVICE_NAME': '0', 18:20:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 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)) 18:20:04 INFO - Cleaning up... 18:20:04 INFO - Return code: 0 18:20:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d53c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e93d86f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, '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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:20:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:20:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:20:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:04 INFO - 'HOME': '/Users/cltbld', 18:20:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:04 INFO - 'LOGNAME': 'cltbld', 18:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:04 INFO - 'MOZ_NO_REMOTE': '1', 18:20:04 INFO - 'NO_EM_RESTART': '1', 18:20:04 INFO - 'PAGER': '/bin/cat', 18:20:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:04 INFO - 'PWD': '/builds/slave/test', 18:20:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:04 INFO - 'SHELL': '/bin/bash', 18:20:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:20:04 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:20:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:04 INFO - 'USER': 'cltbld', 18:20:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:04 INFO - 'XPC_FLAGS': '0x0', 18:20:04 INFO - 'XPC_SERVICE_NAME': '0', 18:20:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:20:04 INFO - Cleaning up... 18:20:04 INFO - Return code: 0 18:20:04 INFO - Running post-action listener: _resource_record_post_action 18:20:04 INFO - Running post-action listener: _start_resource_monitoring 18:20:05 INFO - Starting resource monitoring. 18:20:05 INFO - [mozharness: 2016-12-10 02:20:05.008959Z] Finished create-virtualenv step (success) 18:20:05 INFO - [mozharness: 2016-12-10 02:20:05.009979Z] Running install step. 18:20:05 INFO - Running pre-action listener: _resource_record_pre_action 18:20:05 INFO - Running main action method: install 18:20:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:05 INFO - Reading from file tmpfile_stdout 18:20:05 INFO - Detecting whether we're running mozinstall >=1.0... 18:20:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:20:05 INFO - Reading from file tmpfile_stdout 18:20:05 INFO - Output received: 18:20:05 INFO - Usage: mozinstall [options] installer 18:20:05 INFO - Options: 18:20:05 INFO - -h, --help show this help message and exit 18:20:05 INFO - -d DEST, --destination=DEST 18:20:05 INFO - Directory to install application into. [default: 18:20:05 INFO - "/builds/slave/test"] 18:20:05 INFO - --app=APP Application being installed. [default: firefox] 18:20:05 INFO - mkdir: /builds/slave/test/build/application 18:20:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg', '--destination', '/builds/slave/test/build/application'] 18:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg --destination /builds/slave/test/build/application 18:20:26 INFO - Reading from file tmpfile_stdout 18:20:26 INFO - Output received: 18:20:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:20:26 INFO - Running post-action listener: _resource_record_post_action 18:20:26 INFO - [mozharness: 2016-12-10 02:20:26.841596Z] Finished install step (success) 18:20:26 INFO - [mozharness: 2016-12-10 02:20:26.841800Z] Running run-tests step. 18:20:26 INFO - Running pre-action listener: _resource_record_pre_action 18:20:26 INFO - Running main action method: run_tests 18:20:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:20:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:20:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:20:26 INFO - 2016-12-09 18:20:26.856 screenresolution[1926:16247] starting screenresolution argv=screenresolution get 18:20:26 INFO - 2016-12-09 18:20:26.859 screenresolution[1926:16247] Display 0: 1600x1200x32@60 18:20:26 INFO - 2016-12-09 18:20:26.865 screenresolution[1927:16250] starting screenresolution argv=screenresolution list 18:20:26 INFO - Available Modes on Display 0 18:20:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:20:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:20:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:20:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:20:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:20:27 INFO - Intel Iris: 18:20:27 INFO - Chipset Model: Intel Iris 18:20:27 INFO - Type: GPU 18:20:27 INFO - Bus: Built-In 18:20:27 INFO - VRAM (Dynamic, Max): 1536 MB 18:20:27 INFO - Vendor: Intel (0x8086) 18:20:27 INFO - Device ID: 0x0a2e 18:20:27 INFO - Revision ID: 0x0009 18:20:27 INFO - Displays: 18:20:27 INFO - Display: 18:20:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:20:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:20:27 INFO - Display Serial Number: 1600x1200 60 18:20:27 INFO - Main Display: Yes 18:20:27 INFO - Mirror: Off 18:20:27 INFO - Online: Yes 18:20:27 INFO - Rotation: Supported 18:20:27 INFO - Return code: 0 18:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:20:27 INFO - Python 2.7.3 18:20:27 INFO - Return code: 0 18:20:27 INFO - grabbing minidump binary from tooltool 18:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:27 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 0x10d3dbd30>, '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 0x10d242db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3d3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:20:27 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 18:20:27 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 18:20:27 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 18:20:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:20:27 INFO - Return code: 0 18:20:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:20:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:20:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:20:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:20:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip --title t-yosemite-r7-0111 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SfXTkyK0h1/Render', 18:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:27 INFO - 'HOME': '/Users/cltbld', 18:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:27 INFO - 'LOGNAME': 'cltbld', 18:20:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:20:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:27 INFO - 'MOZ_NO_REMOTE': '1', 18:20:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:20:27 INFO - 'NO_EM_RESTART': '1', 18:20:27 INFO - 'PAGER': '/bin/cat', 18:20:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:27 INFO - 'PWD': '/builds/slave/test', 18:20:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:27 INFO - 'SHELL': '/bin/bash', 18:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hydcvVK4Ol/Listeners', 18:20:27 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:27 INFO - 'USER': 'cltbld', 18:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:27 INFO - 'XPC_FLAGS': '0x0', 18:20:27 INFO - 'XPC_SERVICE_NAME': '0', 18:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:20:27 INFO - mozversion application_buildid: 20161209153551 18:20:27 INFO - mozversion application_changeset: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 18:20:27 INFO - mozversion application_display_name: Nightly 18:20:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:20:27 INFO - mozversion application_name: Firefox 18:20:27 INFO - mozversion application_remotingname: firefox 18:20:27 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 18:20:27 INFO - mozversion application_vendor: Mozilla 18:20:27 INFO - mozversion application_version: 53.0a1 18:20:27 INFO - mozversion platform_buildid: 20161209153551 18:20:27 INFO - mozversion platform_changeset: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 18:20:27 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 18:20:27 INFO - mozversion platform_version: 53.0a1 18:20:27 INFO - using testdate: 1481336427 18:20:27 INFO - actual date: 1481336427 18:20:27 INFO - starting webserver on 'localhost:52269' 18:20:27 INFO - SUITE-START | Running 2 tests 18:20:27 INFO - TEST-START | dromaeo_css 18:20:27 INFO - operating with platform_type : mac_ 18:20:27 INFO - Initialising browser for dromaeo_css test... 18:20:27 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmp6Kp4Lw/profile http://localhost:52269/getInfo.html) 18:20:30 INFO - PROCESS | 1937 | 2016-12-09 18:20:30.171 plugin-container[1939:16399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x914f, name = 'com.apple.tsm.portname' 18:20:30 INFO - PROCESS | 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:20:30 INFO - PROCESS | 1937 | 2016-12-09 18:20:30.173 plugin-container[1939:16399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 18:20:30 INFO - PROCESS | 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:20:30 INFO - PROCESS | 1937 | 2016-12-09 18:20:30.173 plugin-container[1939:16399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:20:30 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 18:20:30 INFO - PROCESS | 1937 | colorDepth:24 18:20:30 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 18:20:30 INFO - PROCESS | 1937 | __metrics 18:20:30 ERROR - PROCESS | 1937 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 18:20:30 INFO - TEST-INFO | 1937: exit 0 18:20:30 INFO - Browser initialized. 18:20:30 INFO - Running cycle 1/1 for dromaeo_css test... 18:20:30 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmp6Kp4Lw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:20:33 INFO - PROCESS | 1940 | 2016-12-09 18:20:33.447 plugin-container[1941:16526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 18:20:33 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:20:33 INFO - PROCESS | 1940 | 2016-12-09 18:20:33.449 plugin-container[1941:16526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 18:20:33 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:20:33 INFO - PROCESS | 1940 | 2016-12-09 18:20:33.449 plugin-container[1941:16526] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:20:43 INFO - PROCESS | 1940 | RSS: Main: 237170688 18:20:43 INFO - PROCESS | 1940 | 18:21:32 INFO - PROCESS | 1940 | 1481336492697 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:23:46 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52269/tests/dromaeo/cssquery-ext.html) 18:23:48 INFO - PROCESS | 1940 | RSS: Main: 252915712 18:23:48 INFO - PROCESS | 1940 | 18:26:50 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52269/tests/dromaeo/cssquery-ext.html (next: http://localhost:52269/tests/dromaeo/cssquery-jquery.html) 18:26:51 INFO - PROCESS | 1940 | RSS: Main: 264101888 18:26:51 INFO - PROCESS | 1940 | 18:29:53 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52269/tests/dromaeo/cssquery-mootools.html) 18:29:54 INFO - PROCESS | 1940 | RSS: Main: 270426112 18:29:54 INFO - PROCESS | 1940 | 18:32:56 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52269/tests/dromaeo/cssquery-prototype.html) 18:32:56 INFO - PROCESS | 1940 | RSS: Main: 273276928 18:32:56 INFO - PROCESS | 1940 | 18:35:59 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52269/tests/dromaeo/cssquery-yui.html) 18:36:00 INFO - PROCESS | 1940 | RSS: Main: 277458944 18:36:00 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52269/tests/dromaeo/cssquery-yui.html (next: http://localhost:52269/tests/dromaeo/cssquery-dojo.html) 18:39:03 INFO - PROCESS | 1940 | RSS: Main: 278568960 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | __start_tp_report 18:39:03 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 18:39:03 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 18:39:03 INFO - PROCESS | 1940 | |i|pagename|runs| 18:39:03 INFO - PROCESS | 1940 | |0;dojo.html;2924.4882145624515;3372.1906742381807;3403.149212696826;3423.0244380351605;3458.9308213835725;4733.219018861883;5138.152204886194;5647.322321321437;5724.599278050533;5789.986752318343;5677.120046392815;5715.342735585398;5790.971045144779;5834.27071616048;5866.823995280138;5116.565091967187;5198.870028249286;5221.373435187764;5244.554212891909;5302.125149350352;5235.712035838027;5259.631825772198;5268.341457317835;5315.840524784243;5353.678779273237;5133.204353325241;5158.252053452228;5203.115470370046;5203.297554830126;5257.080010998079;5194.091034069061;5196.974015129938;5201.479852014806;5224.373075230997;5239.1093514102695;5033.84898453047;5134.691918484903;5139.537441630234;5174.172132458789;5181.663191892552;5473.17902314652;5638.802641907513;5669.716514174274;5701.287339082614;5754.769809207627;5224.608154388436;6164.599301045418;6222.222222222223;6237.500999919996;6240.500759939194;6209;6233.87532249357;6245.46913512352;6262.12330273762;6262.655553944532;5687.118496633028;5697.6011679182475;5751.396103409165;5770.6249093808765;5862.765489380419;6184.196054512884;6223.813285601439;6238.812835614939;6241.500679945593;6288.937110628926;6164.568480206342;6172.598781079216;6194.5044396448175;6234.719437625317;6248.687565621701;6093.146959425684;6246.500279977591;6265.624062556261;6277.121203031579;6288.5283603728985;6114.785982490592;6117.9492029047315;6143.603994442171;6143.788236234952;6152.738688568833;6139.508839292846;6241.78153764616;6246.312905580383;6264.37356264379;6280.2777680566405;4197.517285512145;4921.331233512359;4921.975390123026;4975.4527002029745;4991.301217829507;4325.286327755948;4340.110277393127;4377.124575084932;4417.2269852775635;4445.51099379068;5896;5946.821595352147;5980.671063091485;6011.248593925759;6089.147519347295;6217.813465596039;6226.392469572484;6243.937560624336;6246;6265.028920517327;5758.510526605201;5878.030125029408;5919.112133180058;5922.80168708901;5964.850878728066;5062.367204099488;5104.515071068243;5360.812371566976;5414.5668346532975;5426.891462170814;6112.047575716935;6126.395534290377;6130.979101201984;6145.016797312409;6153.600015999035;5874.647521148745;5902.734376952962;5909.704514774158;5924.644521328734;5960.254968128984;6093.802128200014;6159.938400616115;6213.751449941951;6219.346968568416;6229.781957631642;5102.668326558762;6149.723262453111;6178.536609754196;6212.782552610637;6222.968885155546;6106.664133472524;6227.346128656426;6234.781782637768;6250.624962502265;6252.749890004349;6057.273127224761;6102.368068311607;6111.682900513672;6125.173101631401;6186.907196392148;6124.326324104256;6124.5406594506485;6134.1610691366095;6143.232095988002;6144.38556144382;5884.911726324194;5934.495567876779;5963.2844058713235;6018.578699490994;6113.205283313141;6074.757009719739;6077.939220607737;6103.389661033861;6116.633002019893;6174.783882564088;5787.189793429007;6051.334353221054;6081.878362432815;6090.208272924669;6106.053561697766 18:39:03 INFO - PROCESS | 1940 | |1;ext.html;435.9149965756679;475.8786509440092;490.0762063510282;499.43563772936596;509.630517874541;31013.68986310136;39848.60151398485;39968.80015599922;42077.579224207824;42151.94620134498;40496.39255411164;41306.586934130726;41348.75953721385;41362.58637413625;41402.58597414025;8302.792430189233;8584.656613735444;9102.13529714676;9487.478188699619;9500;11012.229143959927;11302.943485282562;11392.886071139306;11451.370174640391;11463.312201267909;8555.614997325136;10619;11220.158488113384;11226.15803814713;11226.438678066104;11239.550417983271;11302.095832333434;11315.603953861575;11315.83026254607;11320.207585469021;11056.668299951014;11151.442427878574;11155.107591392669;11178;11179.4969226385;38732.806335968424;38965;39299.41050884239;39351;39361.80319098386;2773.47304012237;3029.2881172924344;3386.745994050456;3520.6655367740022;3522.2955408918065;29910.252243693863;30236.244093897607;30790;30834.691653083406;31354.21614459634;2883.1494709060876;3206.1824234820206;3231.127595549212;3283.868645254211;3355.362481128602;311268;331783;354050;354129;354132;62493.68753156296;62940;63012.684936575024;63013.36986630167;63014;64323;64527.35472645307;64550;64848.35151648518;64999.675001625634;15642.139682317582;16118.435854745028;16128.274227659784;16135.273912673958;16195.514134575746;9196.816063678689;11152.832707509231;11207.38359390225;12129.514819407124;12276.570320038934;18054.638907221783;18136.727949080774;18150.820196687175;18152.183151758203;18271.36050238262;5389.676619402849;6154.83058218937;6158.876822463615;6175.907361389583;6276.403741644536;671.9514922591426;896.4666023715879;1060.390275591538;1062.3201151263197;1151.7523732397574;27880.302992425353;28150.01474948367;28645.14064578066;29019;30107.849460752557;1017.8982101789762;1020.3571749797728;1070.6092276319087;1093.3549205968516;1114.2478826791883;12315.768423157613;13532.661683457993;13767.655808604864;14425.78361324581;14852.034617749812;578525;587914;589187;589587;592172;49971;50286;50422.747886261066;50440.49559504358;50692;1308.3523655790445;1340.3834236251435;1347.4138749643937;1357.803118547805;1366.1666383506054;26080.34799130037;27268.454630906876;27494.587581186697;27522.724772752015;27716;34802.82598586991;39314.80342598383;39576;39731.40402894017;39747.60252397555;427877;471207;474024;491964;533167;23259.651105233097;24111.758882411654;24747.25758227256;24909.128180513595;25665.61501577515;40569.59430405776;40775.388369173896;41052.589474104876;41197.58802411937;41738.373924391766;34176.48735268923;34243.4863477053;34624.48063279002;35045.64954350526;35356;28954.42091158123;33253.50119748254;33975.49036764401;34088.48867266943;34846;33505.49741753924;33899.66100338965;34482;34496.48255276223;34572.13569660779;28887.42225155443;31032;31159.37681246317;31310;31420.371592567564;23499;23541.52916941686;23654.52690946137;23833;24032.279031629078 18:39:03 INFO - PROCESS | 1940 | |2;jquery.html;367.8841165033014;376.8059449383566;379.4403255198582;387.2487374493484;399.0104540738967;14776.704465910676;15164.772528412082;18643.669776110077;20132.798672013276;20196.293129740443;16341.5914602135;19585.412437626896;20039.198432062738;20260;20580.48548786281;5732.828015159552;5771.884562308762;6061.999770037938;6067.878642427148;6074.392560743934;5758.769649214027;5938.732757025922;5951.047832346825;5966.850828729295;5971.969530359677;3681.3925702259094;4101.507819061717;6218.28489723683;6561.671916404161;6562.901556476657;6357.300696923335;6546.738130474823;6617.3382661733685;6627.204735431755;6662.267150613428;4933.629977751647;5770.336411312711;5772.595918285721;5773.711314434304;5779.815137896763;24614.89232984502;24711.887965041617;24902.003919843184;25115.744212789286;25188.244352669448;2247.4606094537194;2314.155333303349;2350.2831636350907;2466.3957330454155;2493.8379005364773;14619.342129604192;15453.381864725397;15460.381584736597;15704;15865.444709435229;1800.2438975630266;1830;1855.443366989911;1885.9434216973514;1908.7995760445092;113256;114157;116588;116917;117311.41344293224;9250.444973301623;10595.894041059644;10597.84103238452;11189.944050279697;11354.48904799286;11614.419279036014;13965.511207107906;13970.441182352795;13972.86027139736;13977.86022139786;7772.68909243624;7835.942147810087;8176.46852954556;8455.647096464536;8623.568821558896;8418.957905210556;8427.494350338877;8437.198466145706;8496.957515212385;8533.104024077507;6570.277269500351;6809;6809.489288303397;6825.965870170618;6834.794956151323;1648.3818568036986;1795.1652481595859;1799.379214171109;1844.2019031444502;1859.1726681626546;851.8253757979724;936.6652520158747;943.6414162618298;948.9952550237205;978;19757.40727778169;19975.55683036945;21555;21602.891985539973;21635.12959686521;896.9910300897038;911.9544022798833;918.5590916360185;971.3152227679469;973.7662960889336;10791.784164316827;11217.89585738487;11298.887011129784;11312;11351.716207094889;536325.457217991;554997;557714;570578;573260;115839;116065;118940;123153;124810;985.5072463768116;1015.7765291635828;1036.1192985962023;1036.8392899100652;1044.1385856668278;15841.387560995954;17909.283628654706;17968.281268749626;18960.905195474486;19048.1428335728;53382.199267011805;53659;53914;53954.73022634862;54559.18161227664;72621.91067133892;76133.61933190298;76279.61860190885;77088;78910.421791565;7708.537487750753;7815.96092019559;7900.486468379537;7929.484583502054;8305.418620696735;9587.233021358414;10755.946220268848;11104;11564.710882228012;11978.862008109216;9121.90537135548;10992.835107473555;11005.86332895121;11007.67907851063;11275.605353812578;10408.115310198718;10940.070094041777;11227.186534145025;11342.943285283522;11420.743718190826;11154;11226.775464490827;11593.6521904343;11628.767424651629;11776.352300623204;7892.4869883457395;8053.35573154158;8116.594170291343;8134.349252059939;8182.181781822008;9238.353315268136;11177.882211779082;11246.212765106364;11400.657980260605;11468.369239691898 18:39:03 INFO - PROCESS | 1940 | |3;mootools.html;1264.671185491772;1417.2488581052046;1502.181311185404;1659.705429764783;1815.9092045397726;7518.88721669174;7762;7968.203179682029;8440.915590844083;8458.91541084589;7850.842983140334;8105.918940810605;8121.390895682818;8245;8271.007479102516;2281.247188427819;2345.7419683834764;2346.4133966508375;2347.8473899196583;2357.8467399619058;2096.67501537262;2307.857610482805;2335.94160145996;2353.3881190890406;2364.645303204516;2297.793198612125;2306.4349234437586;2323.2101085630884;2326.069572171128;2326.639370897514;2270.386995511211;2296.7588403217756;2303.6199027160496;2306.7231932168165;2310.699609050813;1797.4158398520533;2081.6357137500877;2303.1133013789813;2308.3305841306;2351.2005917987794;8289.41974061816;8345.248927596485;8354.498730076215;8370.162983701643;8389.748307550783;4988.65079444439;5155.097857874857;5169.2504586835075;5183.481651834786;5185.507376799198;2958.491169503547;3089.6601373848857;3200.8879689211;3299.340131973615;3501.4572741224897;917.7797328641145;977.3695966101825;1021.6577446555339;1039.3140527251978;1039.636127355427;9604.7033650457;9678.661246856327;10667.19996000303;10672.679819605424;10707.304025238334;4511.503734589192;4673.112108699404;4675.625949923998;4699.577038066591;4734.810607575659;8488.193621605938;8507.404481686346;8528.957355213308;8566.742997710078;8575.271101956403;5045.520675535819;5046.016026874798;5050.520200580939;5058;5062.493750624908;3901.785401802928;4201.495820501559;4660.813567457331;4723.645726570521;4749.26386410107;3319.46888497844;3493.2314890724;3579.83890724918;3621.927561448809;3736.3834967230646;1137.1073707140022;1149.2013050929525;1149.7298134938283;1154.486253617149;1157.346099453806;745.9403247740277;778.8286576953061;843.2832092721138;883.7525492862009;920.40173886974;5344.946550534522;5534.889302213933;5836;5836.591438599257;5849.94150058494;872.2455076358904;883.1654086887829;909.8999110097884;926.0878035135386;958.3866325551653;3429.8113603751535;3531.399662057456;3741.719371047128;3826.9284600311958;3893.9415908761393;9578.808423831484;9945.602175913027;9950.850737238947;9953.303268771117;9957.950210249048;8661.480311181349;9551.56726491032;9802.36284641496;9881.061299176567;9894.901050989542;7382.852342953218;7647.885281720779;7861.567613781182;7863.095743989515;7866.410019248579;5581.49766521015;5745.988903883607;5780.30636323627;5848.53211743051;5870.559708022;8974.685885993958;9112.498812565107;9139.040400757825;9275.39709918853;9303.348765586614;9235.49204793716;9248.121428464285;9304.627814887328;9332.813343733224;9355.766105847408;6554.967225163844;6749.73001079971;6894.276101009322;7039.54242974205;7231.240719724353;6868.244493105854;7160.212376638669;7537.660805263886;7897.723579674914;7908.4068694846965;5176.741162941913;7048.753293634698;7059.329363710439;7103.147622285359;7234.96382518084;6773.356531129535;6826.8634627308165;6828.624425656438;6875.5530890491955;6914.377706006484;6566.671666416755;6570.277269500256;6575.638339891352;6662.06731057662;6673.265940746611;4361.716488428242;4583.518730533379;4803.2314829628485;4805.807767689393;4901.117798796251;4788.377510923558;4794.976025119852;4880.829170978998;4914.385701787277;5095.312132862165 18:39:03 INFO - PROCESS | 1940 | |4;prototype.html;294.6993670886075;353.8726058618895;357.6691560306717;365.3040956976959;369.1380626237733;4793.712377257362;5275.788968441268;5406.513413792758;5468.593818719765;5485.01269771441;5088.516590923866;5136.460671629472;5434.858679677262;5484.204790305404;5486.945130548694;4108.876733697979;4111;4282.421873047146;4292.810666560062;4342.696011279212;3863.1219741292634;4061.57353477885;4074.2055299216663;4263.189993901165;4335.978320108411;3906.453096566469;4047.6761859051353;4074.6129117733767;4083.9591604084026;4156.833726650931;3660.401115208192;3864.1498870248643;4022.3508361571908;4097.959020409818;4128.669706423479;3123.6720144384967;3507.877224297137;3523.8766643167364;3534.0634731796094;3534.2401383702627;4271.594198551133;4574.908501829978;4669.789859456333;4879.682485728868;5330.413654498002;4591.403117594725;4615.630749540062;4623.69945953512;4624.167649823031;4637.6289896808175;5661.2923384576925;5671.461211184931;5731.512821410143;5741.368449470555;5769.365369809318;3426.58880934287;3502.8949131526097;3606.441001644749;3747.7189210809297;3935.783531905744;5347.866303342409;5441.089017375724;5562.887422515513;5945.464908158287;5989.401059894019;3895.8441662333757;3904.590018048121;3910.6284902934176;3916.8433262669746;3919.235749028912;3212.3414699986447;3230.838458077134;3674.81625918703;3927.9607203927594;3943.546492153439;4503.301988191835;4590.265557510782;4591.081783643284;4604.447466303998;4670.673052886334;5277.461383210436;5386.6498677586615;5726.112452569859;5729.140628905698;5768.509676677446;1016.3089099412471;1130.8360287758228;1192.7793358228755;1236.4559593778708;1250.5685538489206;718.4073139659802;749.7816048920504;820.7893357297937;848.6223630484375;853.4964371021003;4604.493505714368;4645.372874661877;5135.589152867761;5149.613778966593;5175.844724658266;4684.789184486706;4933.753312334369;5590.580706447033;5665.461781130786;5668.461496157858;4520.011195074157;4644.838790302424;4775.641826863;4906.582940450102;5116.616253780981;2438.378213555532;2833.886644534237;2872.8795769649855;2950.8229506229695;2982.209714425679;4853.733044682576;5113.187746739353;5338.172583249602;5983.850403739942;6010.398960103955;5275.762590683428;5292.867678307996;5566.88254577438;5692;5727.5131613812455;5379.811706590251;5779.190913272061;5821.330546987151;5896.439838215277;5908.556858235649;5092.439831618593;5222.686638801684;5319.335083114611;5373.919391209057;5645.350784659817;4769.95230047709;5379.892402152013;5408.323959505062;5840.649561026352;5875.20684707559;5315.3621565412395;5940.2277703899945;6003.769227308479;6204.401447160546;6221.346758590464;3537.239493508908;3722.3807643675086;3941.9159731074183;3954.683625310026;3970.2258059678084;5389.814240867003;5536.916946245729;5885.823425297248;5911.3201981772645;5936.821895343147;4537.886552836206;5624.212610234488;5665.348484924121;5787.623804452697;5801.332846722512;5532.419095995024;5689.837861612727;5764.884702306014;5769.240381683627;5785.652860828363;4309.762963037063;4972.328735620645;5337.438029745666;5553.195211556341;5690.743916523684;5271.868203294948;5705.202861098741;5751.33859606134;5757.394084732281;5761.752916362538;4866.440359358719;4993.975030124826;5358.919616205682;5653.208550802973;5676.148577713376 18:39:03 INFO - PROCESS | 1940 | |5;yui.html;694.2814187316126;701.3092104277289;763.053813271543;770.418334157711;794.6384395100229;12759.489620415196;14803.11181329121;15567;15669.29488173032;15729.921350393246;14028.859711402883;14237.145771253732;14241.572752817407;14246.715065698705;14299.71400571988;686.2759788423211;696.9616671083088;851.1743608699583;866.9769671787288;868.1231955724701;875.72852415751;890.9821803563957;893.8882639670041;895.8029233568637;897.9685711000117;791.4024911192042;926.5969303353063;947.1002547579792;949.9145076943074;956.3974695941547;942.0955882352949;942.3403617467766;944.4380593546863;944.6929747831913;945.678469320431;693.9063226464414;700.9614471204081;703.2896774257985;706.4983861458371;707.1796715809687;12968.740625187444;13223.669408264774;13790.862091379058;13854.238016909067;14168.079074860101;11531.365774882379;14049.64875878101;14369.928150359287;14380.784288235685;14465.855341446557;11460.885391146148;11628.767424651543;11693.532258709642;11952.820707689305;12001.159918805688;3698.223373091627;3719.553653561563;3728.366177749789;3741.046033261528;3872.4384964180035;104762.47618761858;107223;108323;110565.44717276363;112345.43827280893;10634.41510716918;10748.355098694103;10788.946055269831;10913.454327283604;11050;23101.537969240522;23521.529569408514;23619.881900590386;23623.7637623625;23695.881520592287;7395.741149059757;7544.924550754532;7558.546487210675;7610.885836712454;7784;4259.318509038539;4337.158293885821;4403.339499075164;4412.933805992912;4506.932396014063;3505.789652620851;3593.94609080864;3613.8193090345376;3614.4578313252696;3624.7643903146213;573.3435216676882;647.8930976388843;662.1855118204539;667.436016566001;679.3647939176885;595.4544323019128;631.3150231998242;631.5137344244906;667.7195577857311;673.0879658063254;7616.657250423744;8880.66789981508;8960.507172105466;9251.95374023139;9469.479178645102;543.342555507832;567.038869116843;607.0406767010822;649.3117295666514;675.8330236591106;4390.495093064274;4502.864914052584;4508.843928060477;4552.521985191574;4625.3755742974945;99368;105045;106142.40786388211;108650.82121768377;109604.4519777396;87338;88232.5588372054;88958;91648;92688.53655731678;68623;78457.60771196222;79219;80812.59593701994;80930.38139237183;8729.388942773945;8876.156765107304;8918.821623567623;9033.051529589298;9112.635494580143;52194.95610087701;52776;53674;53715.657108572595;54695;62480;71849;74643.62678186792;75602.24397756172;79067.60466197862;11452.198346115692;11502.367369794742;11537.596184133516;11694.88305116938;11768.411579421167;12111.333876636869;12616.621501354974;12763.617091487271;13115.213087214797;13303.268320242092;11796.52813887435;11957.760844782882;11990.220635658654;12078.21491603043;12111.878881211074;11915.52337906474;11969.341686207343;11982.520699172284;12094.75810483803;12253.326067066397;11630.069594432256;11883.465244063866;11941.223820451643;11977.341246231541;12008.519659213884;9296.907030929604;9860.950695246478;9897.950510247403;9919.752006199902;10263.076323130954;9166.587503562489;9929.156021738234;10691.679249622524;11340.886591134313;11736.530538778694 18:39:03 INFO - PROCESS | 1940 | __end_tp_report 18:39:03 INFO - PROCESS | 1940 | __start_cc_report 18:39:03 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,386 18:39:03 INFO - PROCESS | 1940 | __end_cc_report 18:39:03 INFO - PROCESS | 1940 | __startTimestamp1481337543386__endTimestamp 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | ------- Summary: start ------- 18:39:03 INFO - PROCESS | 1940 | Number of tests: 6 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5715.07 Median:5964.85 stddev:655.66 (11.0%) stddev-sans-first:620.05 18:39:03 INFO - PROCESS | 1940 | Values: 2924.5 3372.2 3403.1 3423.0 3458.9 4733.2 5138.2 5647.3 5724.6 5790.0 5677.1 5715.3 5791.0 5834.3 5866.8 5116.6 5198.9 5221.4 5244.6 5302.1 5235.7 5259.6 5268.3 5315.8 5353.7 5133.2 5158.3 5203.1 5203.3 5257.1 5194.1 5197.0 5201.5 5224.4 5239.1 5033.8 5134.7 5139.5 5174.2 5181.7 5473.2 5638.8 5669.7 5701.3 5754.8 5224.6 6164.6 6222.2 6237.5 6240.5 6209.0 6233.9 6245.5 6262.1 6262.7 5687.1 5697.6 5751.4 5770.6 5862.8 6184.2 6223.8 6238.8 6241.5 6288.9 6164.6 6172.6 6194.5 6234.7 6248.7 6093.1 6246.5 6265.6 6277.1 6288.5 6114.8 6117.9 6143.6 6143.8 6152.7 6139.5 6241.8 6246.3 6264.4 6280.3 4197.5 4921.3 4922.0 4975.5 4991.3 4325.3 4340.1 4377.1 4417.2 4445.5 5896.0 5946.8 5980.7 6011.2 6089.1 6217.8 6226.4 6243.9 6246.0 6265.0 5758.5 5878.0 5919.1 5922.8 5964.9 5062.4 5104.5 5360.8 5414.6 5426.9 6112.0 6126.4 6131.0 6145.0 6153.6 5874.6 5902.7 5909.7 5924.6 5960.3 6093.8 6159.9 6213.8 6219.3 6229.8 5102.7 6149.7 6178.5 6212.8 6223.0 6106.7 6227.3 6234.8 6250.6 6252.7 6057.3 6102.4 6111.7 6125.2 6186.9 6124.3 6124.5 6134.2 6143.2 6144.4 5884.9 5934.5 5963.3 6018.6 6113.2 6074.8 6077.9 6103.4 6116.6 6174.8 5787.2 6051.3 6081.9 6090.2 6106.1 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:60596.94 Median:182518.25 stddev:128273.59 (70.3%) stddev-sans-first:128553.90 18:39:03 INFO - PROCESS | 1940 | Values: 435.9 475.9 490.1 499.4 509.6 31013.7 39848.6 39968.8 42077.6 42151.9 40496.4 41306.6 41348.8 41362.6 41402.6 8302.8 8584.7 9102.1 9487.5 9500.0 11012.2 11302.9 11392.9 11451.4 11463.3 8555.6 10619.0 11220.2 11226.2 11226.4 11239.6 11302.1 11315.6 11315.8 11320.2 11056.7 11151.4 11155.1 11178.0 11179.5 38732.8 38965.0 39299.4 39351.0 39361.8 2773.5 3029.3 3386.7 3520.7 3522.3 29910.3 30236.2 30790.0 30834.7 31354.2 2883.1 3206.2 3231.1 3283.9 3355.4 311268.0 331783.0 354050.0 354129.0 354132.0 62493.7 62940.0 63012.7 63013.4 63014.0 64323.0 64527.4 64550.0 64848.4 64999.7 15642.1 16118.4 16128.3 16135.3 16195.5 9196.8 11152.8 11207.4 12129.5 12276.6 18054.6 18136.7 18150.8 18152.2 18271.4 5389.7 6154.8 6158.9 6175.9 6276.4 672.0 896.5 1060.4 1062.3 1151.8 27880.3 28150.0 28645.1 29019.0 30107.8 1017.9 1020.4 1070.6 1093.4 1114.2 12315.8 13532.7 13767.7 14425.8 14852.0 578525.0 587914.0 589187.0 589587.0 592172.0 49971.0 50286.0 50422.7 50440.5 50692.0 1308.4 1340.4 1347.4 1357.8 1366.2 26080.3 27268.5 27494.6 27522.7 27716.0 34802.8 39314.8 39576.0 39731.4 39747.6 427877.0 471207.0 474024.0 491964.0 533167.0 23259.7 24111.8 24747.3 24909.1 25665.6 40569.6 40775.4 41052.6 41197.6 41738.4 34176.5 34243.5 34624.5 35045.6 35356.0 28954.4 33253.5 33975.5 34088.5 34846.0 33505.5 33899.7 34482.0 34496.5 34572.1 28887.4 31032.0 31159.4 31310.0 31420.4 23499.0 23541.5 23654.5 23833.0 24032.3 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:33582.89 Median:24806.95 stddev:93336.90 (376.3%) stddev-sans-first:93565.41 18:39:03 INFO - PROCESS | 1940 | Values: 367.9 376.8 379.4 387.2 399.0 14776.7 15164.8 18643.7 20132.8 20196.3 16341.6 19585.4 20039.2 20260.0 20580.5 5732.8 5771.9 6062.0 6067.9 6074.4 5758.8 5938.7 5951.0 5966.9 5972.0 3681.4 4101.5 6218.3 6561.7 6562.9 6357.3 6546.7 6617.3 6627.2 6662.3 4933.6 5770.3 5772.6 5773.7 5779.8 24614.9 24711.9 24902.0 25115.7 25188.2 2247.5 2314.2 2350.3 2466.4 2493.8 14619.3 15453.4 15460.4 15704.0 15865.4 1800.2 1830.0 1855.4 1885.9 1908.8 113256.0 114157.0 116588.0 116917.0 117311.4 9250.4 10595.9 10597.8 11189.9 11354.5 11614.4 13965.5 13970.4 13972.9 13977.9 7772.7 7835.9 8176.5 8455.6 8623.6 8419.0 8427.5 8437.2 8497.0 8533.1 6570.3 6809.0 6809.5 6826.0 6834.8 1648.4 1795.2 1799.4 1844.2 1859.2 851.8 936.7 943.6 949.0 978.0 19757.4 19975.6 21555.0 21602.9 21635.1 897.0 912.0 918.6 971.3 973.8 10791.8 11217.9 11298.9 11312.0 11351.7 536325.5 554997.0 557714.0 570578.0 573260.0 115839.0 116065.0 118940.0 123153.0 124810.0 985.5 1015.8 1036.1 1036.8 1044.1 15841.4 17909.3 17968.3 18960.9 19048.1 53382.2 53659.0 53914.0 53954.7 54559.2 72621.9 76133.6 76279.6 77088.0 78910.4 7708.5 7816.0 7900.5 7929.5 8305.4 9587.2 10755.9 11104.0 11564.7 11978.9 9121.9 10992.8 11005.9 11007.7 11275.6 10408.1 10940.1 11227.2 11342.9 11420.7 11154.0 11226.8 11593.7 11628.8 11776.4 7892.5 8053.4 8116.6 8134.3 8182.2 9238.4 11177.9 11246.2 11400.7 11468.4 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5307.41 Median:5763.15 stddev:2884.44 (50.0%) stddev-sans-first:2876.53 18:39:03 INFO - PROCESS | 1940 | Values: 1264.7 1417.2 1502.2 1659.7 1815.9 7518.9 7762.0 7968.2 8440.9 8458.9 7850.8 8105.9 8121.4 8245.0 8271.0 2281.2 2345.7 2346.4 2347.8 2357.8 2096.7 2307.9 2335.9 2353.4 2364.6 2297.8 2306.4 2323.2 2326.1 2326.6 2270.4 2296.8 2303.6 2306.7 2310.7 1797.4 2081.6 2303.1 2308.3 2351.2 8289.4 8345.2 8354.5 8370.2 8389.7 4988.7 5155.1 5169.3 5183.5 5185.5 2958.5 3089.7 3200.9 3299.3 3501.5 917.8 977.4 1021.7 1039.3 1039.6 9604.7 9678.7 10667.2 10672.7 10707.3 4511.5 4673.1 4675.6 4699.6 4734.8 8488.2 8507.4 8529.0 8566.7 8575.3 5045.5 5046.0 5050.5 5058.0 5062.5 3901.8 4201.5 4660.8 4723.6 4749.3 3319.5 3493.2 3579.8 3621.9 3736.4 1137.1 1149.2 1149.7 1154.5 1157.3 745.9 778.8 843.3 883.8 920.4 5344.9 5534.9 5836.0 5836.6 5849.9 872.2 883.2 909.9 926.1 958.4 3429.8 3531.4 3741.7 3826.9 3893.9 9578.8 9945.6 9950.9 9953.3 9958.0 8661.5 9551.6 9802.4 9881.1 9894.9 7382.9 7647.9 7861.6 7863.1 7866.4 5581.5 5746.0 5780.3 5848.5 5870.6 8974.7 9112.5 9139.0 9275.4 9303.3 9235.5 9248.1 9304.6 9332.8 9355.8 6555.0 6749.7 6894.3 7039.5 7231.2 6868.2 7160.2 7537.7 7897.7 7908.4 5176.7 7048.8 7059.3 7103.1 7235.0 6773.4 6826.9 6828.6 6875.6 6914.4 6566.7 6570.3 6575.6 6662.1 6673.3 4361.7 4583.5 4803.2 4805.8 4901.1 4788.4 4795.0 4880.8 4914.4 5095.3 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4527.00 Median:5090.48 stddev:1409.45 (27.7%) stddev-sans-first:1377.14 18:39:03 INFO - PROCESS | 1940 | Values: 294.7 353.9 357.7 365.3 369.1 4793.7 5275.8 5406.5 5468.6 5485.0 5088.5 5136.5 5434.9 5484.2 5486.9 4108.9 4111.0 4282.4 4292.8 4342.7 3863.1 4061.6 4074.2 4263.2 4336.0 3906.5 4047.7 4074.6 4084.0 4156.8 3660.4 3864.1 4022.4 4098.0 4128.7 3123.7 3507.9 3523.9 3534.1 3534.2 4271.6 4574.9 4669.8 4879.7 5330.4 4591.4 4615.6 4623.7 4624.2 4637.6 5661.3 5671.5 5731.5 5741.4 5769.4 3426.6 3502.9 3606.4 3747.7 3935.8 5347.9 5441.1 5562.9 5945.5 5989.4 3895.8 3904.6 3910.6 3916.8 3919.2 3212.3 3230.8 3674.8 3928.0 3943.5 4503.3 4590.3 4591.1 4604.4 4670.7 5277.5 5386.6 5726.1 5729.1 5768.5 1016.3 1130.8 1192.8 1236.5 1250.6 718.4 749.8 820.8 848.6 853.5 4604.5 4645.4 5135.6 5149.6 5175.8 4684.8 4933.8 5590.6 5665.5 5668.5 4520.0 4644.8 4775.6 4906.6 5116.6 2438.4 2833.9 2872.9 2950.8 2982.2 4853.7 5113.2 5338.2 5983.9 6010.4 5275.8 5292.9 5566.9 5692.0 5727.5 5379.8 5779.2 5821.3 5896.4 5908.6 5092.4 5222.7 5319.3 5373.9 5645.4 4770.0 5379.9 5408.3 5840.6 5875.2 5315.4 5940.2 6003.8 6204.4 6221.3 3537.2 3722.4 3941.9 3954.7 3970.2 5389.8 5536.9 5885.8 5911.3 5936.8 4537.9 5624.2 5665.3 5787.6 5801.3 5532.4 5689.8 5764.9 5769.2 5785.7 4309.8 4972.3 5337.4 5553.2 5690.7 5271.9 5705.2 5751.3 5757.4 5761.8 4866.4 4994.0 5358.9 5653.2 5676.1 18:39:03 INFO - PROCESS | 1940 | 18:39:03 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:20546.63 Median:4530.68 stddev:30327.81 (669.4%) stddev-sans-first:30376.26 18:39:03 INFO - PROCESS | 1940 | Values: 694.3 701.3 763.1 770.4 794.6 12759.5 14803.1 15567.0 15669.3 15729.9 14028.9 14237.1 14241.6 14246.7 14299.7 686.3 697.0 851.2 867.0 868.1 875.7 891.0 893.9 895.8 898.0 791.4 926.6 947.1 949.9 956.4 942.1 942.3 944.4 944.7 945.7 693.9 701.0 703.3 706.5 707.2 12968.7 13223.7 13790.9 13854.2 14168.1 11531.4 14049.6 14369.9 14380.8 14465.9 11460.9 11628.8 11693.5 11952.8 12001.2 3698.2 3719.6 3728.4 3741.0 3872.4 104762.5 107223.0 108323.0 110565.4 112345.4 10634.4 10748.4 10788.9 10913.5 11050.0 23101.5 23521.5 23619.9 23623.8 23695.9 7395.7 7544.9 7558.5 7610.9 7784.0 4259.3 4337.2 4403.3 4412.9 4506.9 3505.8 3593.9 3613.8 3614.5 3624.8 573.3 647.9 662.2 667.4 679.4 595.5 631.3 631.5 667.7 673.1 7616.7 8880.7 8960.5 9252.0 9469.5 543.3 567.0 607.0 649.3 675.8 4390.5 4502.9 4508.8 4552.5 4625.4 99368.0 105045.0 106142.4 108650.8 109604.5 87338.0 88232.6 88958.0 91648.0 92688.5 68623.0 78457.6 79219.0 80812.6 80930.4 8729.4 8876.2 8918.8 9033.1 9112.6 52195.0 52776.0 53674.0 53715.7 54695.0 62480.0 71849.0 74643.6 75602.2 79067.6 11452.2 11502.4 11537.6 11694.9 11768.4 12111.3 12616.6 12763.6 13115.2 13303.3 11796.5 11957.8 11990.2 12078.2 12111.9 11915.5 11969.3 11982.5 12094.8 12253.3 11630.1 11883.5 11941.2 11977.3 12008.5 9296.9 9861.0 9898.0 9919.8 10263.1 9166.6 9929.2 10691.7 11340.9 11736.5 18:39:03 INFO - PROCESS | 1940 | -------- Summary: end -------- 18:39:03 INFO - PROCESS | 1940 | 18:39:04 INFO - TEST-INFO | 1940: exit 0 18:39:04 INFO - TEST-OK | dromaeo_css | took 1116692ms 18:39:04 INFO - TEST-START | kraken 18:39:04 INFO - operating with platform_type : mac_ 18:39:04 INFO - Initialising browser for kraken test... 18:39:04 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpz4WJ6n/profile http://localhost:52269/getInfo.html) 18:39:07 INFO - PROCESS | 1948 | 2016-12-09 18:39:07.103 plugin-container[1949:18727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.tsm.portname' 18:39:07 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:07 INFO - PROCESS | 1948 | 2016-12-09 18:39:07.105 plugin-container[1949:18727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 18:39:07 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:07 INFO - PROCESS | 1948 | 2016-12-09 18:39:07.105 plugin-container[1949:18727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:39:07 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 18:39:07 INFO - PROCESS | 1948 | colorDepth:24 18:39:07 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 18:39:07 INFO - PROCESS | 1948 | __metrics 18:39:07 ERROR - PROCESS | 1948 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 18:39:08 INFO - TEST-INFO | 1948: exit 0 18:39:08 INFO - Browser initialized. 18:39:08 INFO - Running cycle 1/1 for kraken test... 18:39:08 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpz4WJ6n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:10 INFO - PROCESS | 1950 | 2016-12-09 18:39:10.385 plugin-container[1951:18844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 18:39:10 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:10 INFO - PROCESS | 1950 | 2016-12-09 18:39:10.385 plugin-container[1951:18844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 18:39:10 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:10 INFO - PROCESS | 1950 | 2016-12-09 18:39:10.385 plugin-container[1951:18844] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:39:20 INFO - PROCESS | 1950 | RSS: Main: 236937216 18:39:20 INFO - PROCESS | 1950 | 18:39:22 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52269/tests/kraken/driver.html?testName=audio-beat-detection) 18:39:23 INFO - PROCESS | 1950 | RSS: Main: 249442304 18:39:23 INFO - PROCESS | 1950 | 18:39:26 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52269/tests/kraken/driver.html?testName=audio-dft) 18:39:27 INFO - PROCESS | 1950 | RSS: Main: 257011712 18:39:27 INFO - PROCESS | 1950 | 18:39:30 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52269/tests/kraken/driver.html?testName=audio-fft) 18:39:31 INFO - PROCESS | 1950 | RSS: Main: 259887104 18:39:31 INFO - PROCESS | 1950 | 18:39:33 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52269/tests/kraken/driver.html?testName=audio-oscillator) 18:39:34 INFO - PROCESS | 1950 | RSS: Main: 261943296 18:39:34 INFO - PROCESS | 1950 | 18:39:36 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:39:36 INFO - PROCESS | 1950 | RSS: Main: 262598656 18:39:36 INFO - PROCESS | 1950 | 18:39:39 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52269/tests/kraken/driver.html?testName=imaging-darkroom) 18:39:40 INFO - PROCESS | 1950 | RSS: Main: 268120064 18:39:40 INFO - PROCESS | 1950 | 18:39:43 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52269/tests/kraken/driver.html?testName=imaging-desaturate) 18:39:44 INFO - PROCESS | 1950 | RSS: Main: 267091968 18:39:44 INFO - PROCESS | 1950 | 18:39:47 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52269/tests/kraken/driver.html?testName=json-parse-financial) 18:39:48 INFO - PROCESS | 1950 | RSS: Main: 267227136 18:39:48 INFO - PROCESS | 1950 | 18:39:49 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:39:50 INFO - PROCESS | 1950 | RSS: Main: 266567680 18:39:50 INFO - PROCESS | 1950 | 18:39:52 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:39:52 INFO - PROCESS | 1950 | RSS: Main: 266522624 18:39:52 INFO - PROCESS | 1950 | 18:39:55 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:39:55 INFO - PROCESS | 1950 | RSS: Main: 266584064 18:39:55 INFO - PROCESS | 1950 | 18:39:59 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:39:59 INFO - PROCESS | 1950 | RSS: Main: 266878976 18:39:59 INFO - PROCESS | 1950 | 18:40:02 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:40:03 INFO - PROCESS | 1950 | RSS: Main: 266678272 18:40:03 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52269/tests/kraken/driver.html?testName=ai-astar) 18:40:05 INFO - PROCESS | 1950 | RSS: Main: 268140544 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | __start_tp_report 18:40:05 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 18:40:05 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 18:40:05 INFO - PROCESS | 1950 | |i|pagename|runs| 18:40:05 INFO - PROCESS | 1950 | |0;ai-astar;106;117;120;119;118;119;121;119;118;121 18:40:05 INFO - PROCESS | 1950 | |1;audio-beat-detection;159;118;118;142;138;157;148;149;168;120 18:40:05 INFO - PROCESS | 1950 | |2;audio-dft;202;209;252;188;262;279;202;202;199;196 18:40:05 INFO - PROCESS | 1950 | |3;audio-fft;84;60;62;85;62;62;69;77;64;56 18:40:05 INFO - PROCESS | 1950 | |4;audio-oscillator;86;70;87;73;72;73;82;71;72;84 18:40:05 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;109;162;113;119;113;113;112;113;111;113 18:40:05 INFO - PROCESS | 1950 | |6;imaging-darkroom;142;97;98;153;102;96;96;96;95;99 18:40:05 INFO - PROCESS | 1950 | |7;imaging-desaturate;94;140;104;147;144;95;112;143;90;150 18:40:05 INFO - PROCESS | 1950 | |8;json-parse-financial;57;57;54;57;59;59;59;61;58;58 18:40:05 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;79;111;102;119;86;77;71;71;73;75 18:40:05 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;94;94;96;97;95;95;95;95;134;95 18:40:05 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;156;164;166;158;156;159;166;157;184;171 18:40:05 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;180;179;179;181;181;177;177;184;164;245 18:40:05 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;71;70;73;69;69;69;74;84;93;92 18:40:05 INFO - PROCESS | 1950 | __end_tp_report 18:40:05 INFO - PROCESS | 1950 | __start_cc_report 18:40:05 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,945 18:40:05 INFO - PROCESS | 1950 | __end_cc_report 18:40:05 INFO - PROCESS | 1950 | __startTimestamp1481337605415__endTimestamp 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | ------- Summary: start ------- 18:40:05 INFO - PROCESS | 1950 | Number of tests: 14 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.00 stddev:4.34 (3.6%) stddev-sans-first:1.36 18:40:05 INFO - PROCESS | 1950 | Values: 106.0 117.0 120.0 119.0 118.0 119.0 121.0 119.0 118.0 121.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:148.50 stddev:18.03 (12.1%) stddev-sans-first:18.01 18:40:05 INFO - PROCESS | 1950 | Values: 159.0 118.0 118.0 142.0 138.0 157.0 148.0 149.0 168.0 120.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:219.10 Median:205.50 stddev:32.31 (15.7%) stddev-sans-first:33.67 18:40:05 INFO - PROCESS | 1950 | Values: 202.0 209.0 252.0 188.0 262.0 279.0 202.0 202.0 199.0 196.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:68.10 Median:66.50 stddev:10.32 (15.5%) stddev-sans-first:9.21 18:40:05 INFO - PROCESS | 1950 | Values: 84.0 60.0 62.0 85.0 62.0 62.0 69.0 77.0 64.0 56.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:77.50 stddev:6.85 (8.8%) stddev-sans-first:6.44 18:40:05 INFO - PROCESS | 1950 | Values: 86.0 70.0 87.0 73.0 72.0 73.0 82.0 71.0 72.0 84.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:113.00 stddev:15.73 (13.9%) stddev-sans-first:16.36 18:40:05 INFO - PROCESS | 1950 | Values: 109.0 162.0 113.0 119.0 113.0 113.0 112.0 113.0 111.0 113.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:96.00 stddev:21.39 (22.3%) stddev-sans-first:18.66 18:40:05 INFO - PROCESS | 1950 | Values: 142.0 97.0 98.0 153.0 102.0 96.0 96.0 96.0 95.0 99.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:121.90 Median:148.50 stddev:24.99 (16.8%) stddev-sans-first:24.38 18:40:05 INFO - PROCESS | 1950 | Values: 94.0 140.0 104.0 147.0 144.0 95.0 112.0 143.0 90.0 150.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.50 stddev:1.85 (3.2%) stddev-sans-first:1.94 18:40:05 INFO - PROCESS | 1950 | Values: 57.0 57.0 54.0 57.0 59.0 59.0 59.0 61.0 58.0 58.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:86.40 Median:74.00 stddev:17.76 (24.0%) stddev-sans-first:18.63 18:40:05 INFO - PROCESS | 1950 | Values: 79.0 111.0 102.0 119.0 86.0 77.0 71.0 71.0 73.0 75.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:99.00 Median:95.00 stddev:12.33 (13.0%) stddev-sans-first:12.94 18:40:05 INFO - PROCESS | 1950 | Values: 94.0 94.0 96.0 97.0 95.0 95.0 95.0 95.0 134.0 95.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:163.70 Median:165.00 stddev:8.78 (5.3%) stddev-sans-first:8.86 18:40:05 INFO - PROCESS | 1950 | Values: 156.0 164.0 166.0 158.0 156.0 159.0 166.0 157.0 184.0 171.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.70 Median:180.50 stddev:21.85 (12.1%) stddev-sans-first:23.11 18:40:05 INFO - PROCESS | 1950 | Values: 180.0 179.0 179.0 181.0 181.0 177.0 177.0 184.0 164.0 245.0 18:40:05 INFO - PROCESS | 1950 | 18:40:05 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:73.50 stddev:9.59 (13.1%) stddev-sans-first:9.97 18:40:05 INFO - PROCESS | 1950 | Values: 71.0 70.0 73.0 69.0 69.0 69.0 74.0 84.0 93.0 92.0 18:40:05 INFO - PROCESS | 1950 | -------- Summary: end -------- 18:40:05 INFO - PROCESS | 1950 | 18:40:06 INFO - TEST-INFO | 1950: exit 0 18:40:06 INFO - TEST-OK | kraken | took 62247ms 18:40:06 INFO - SUITE-END | took 1178s 18:40:06 INFO - Completed test suite (00:19:39) 18:40:06 INFO - javascript benchmark 18:40:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7133.440813704399, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2924.4882145624515, 3372.1906742381807, 3403.149212696826, 3423.0244380351605, 3458.9308213835725, 4733.219018861883, 5138.152204886194, 5647.322321321437, 5724.599278050533, 5789.986752318343, 5677.120046392815, 5715.342735585398, 5790.971045144779, 5834.27071616048, 5866.823995280138, 5116.565091967187, 5198.870028249286, 5221.373435187764, 5244.554212891909, 5302.125149350352, 5235.712035838027, 5259.631825772198, 5268.341457317835, 5315.840524784243, 5353.678779273237, 5133.204353325241, 5158.252053452228, 5203.115470370046, 5203.297554830126, 5257.080010998079, 5194.091034069061, 5196.974015129938, 5201.479852014806, 5224.373075230997, 5239.1093514102695, 5033.84898453047, 5134.691918484903, 5139.537441630234, 5174.172132458789, 5181.663191892552, 5473.17902314652, 5638.802641907513, 5669.716514174274, 5701.287339082614, 5754.769809207627, 5224.608154388436, 6164.599301045418, 6222.222222222223, 6237.500999919996, 6240.500759939194, 6209.0, 6233.87532249357, 6245.46913512352, 6262.12330273762, 6262.655553944532, 5687.118496633028, 5697.6011679182475, 5751.396103409165, 5770.6249093808765, 5862.765489380419, 6184.196054512884, 6223.813285601439, 6238.812835614939, 6241.500679945593, 6288.937110628926, 6164.568480206342, 6172.598781079216, 6194.5044396448175, 6234.719437625317, 6248.687565621701, 6093.146959425684, 6246.500279977591, 6265.624062556261, 6277.121203031579, 6288.5283603728985, 6114.785982490592, 6117.9492029047315, 6143.603994442171, 6143.788236234952, 6152.738688568833, 6139.508839292846, 6241.78153764616, 6246.312905580383, 6264.37356264379, 6280.2777680566405, 4197.517285512145, 4921.331233512359, 4921.975390123026, 4975.4527002029745, 4991.301217829507, 4325.286327755948, 4340.110277393127, 4377.124575084932, 4417.2269852775635, 4445.51099379068, 5896.0, 5946.821595352147, 5980.671063091485, 6011.248593925759, 6089.147519347295, 6217.813465596039, 6226.392469572484, 6243.937560624336, 6246.0, 6265.028920517327, 5758.510526605201, 5878.030125029408, 5919.112133180058, 5922.80168708901, 5964.850878728066, 5062.367204099488, 5104.515071068243, 5360.812371566976, 5414.5668346532975, 5426.891462170814, 6112.047575716935, 6126.395534290377, 6130.979101201984, 6145.016797312409, 6153.600015999035, 5874.647521148745, 5902.734376952962, 5909.704514774158, 5924.644521328734, 5960.254968128984, 6093.802128200014, 6159.938400616115, 6213.751449941951, 6219.346968568416, 6229.781957631642, 5102.668326558762, 6149.723262453111, 6178.536609754196, 6212.782552610637, 6222.968885155546, 6106.664133472524, 6227.346128656426, 6234.781782637768, 6250.624962502265, 6252.749890004349, 6057.273127224761, 6102.368068311607, 6111.682900513672, 6125.173101631401, 6186.907196392148, 6124.326324104256, 6124.5406594506485, 6134.1610691366095, 6143.232095988002, 6144.38556144382, 5884.911726324194, 5934.495567876779, 5963.2844058713235, 6018.578699490994, 6113.205283313141, 6074.757009719739, 6077.939220607737, 6103.389661033861, 6116.633002019893, 6174.783882564088, 5787.189793429007, 6051.334353221054, 6081.878362432815, 6090.208272924669, 6106.053561697766], "value": 5672.556879269735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.9149965756679, 475.8786509440092, 490.0762063510282, 499.43563772936596, 509.630517874541, 31013.68986310136, 39848.60151398485, 39968.80015599922, 42077.579224207824, 42151.94620134498, 40496.39255411164, 41306.586934130726, 41348.75953721385, 41362.58637413625, 41402.58597414025, 8302.792430189233, 8584.656613735444, 9102.13529714676, 9487.478188699619, 9500.0, 11012.229143959927, 11302.943485282562, 11392.886071139306, 11451.370174640391, 11463.312201267909, 8555.614997325136, 10619.0, 11220.158488113384, 11226.15803814713, 11226.438678066104, 11239.550417983271, 11302.095832333434, 11315.603953861575, 11315.83026254607, 11320.207585469021, 11056.668299951014, 11151.442427878574, 11155.107591392669, 11178.0, 11179.4969226385, 38732.806335968424, 38965.0, 39299.41050884239, 39351.0, 39361.80319098386, 2773.47304012237, 3029.2881172924344, 3386.745994050456, 3520.6655367740022, 3522.2955408918065, 29910.252243693863, 30236.244093897607, 30790.0, 30834.691653083406, 31354.21614459634, 2883.1494709060876, 3206.1824234820206, 3231.127595549212, 3283.868645254211, 3355.362481128602, 311268.0, 331783.0, 354050.0, 354129.0, 354132.0, 62493.68753156296, 62940.0, 63012.684936575024, 63013.36986630167, 63014.0, 64323.0, 64527.35472645307, 64550.0, 64848.35151648518, 64999.675001625634, 15642.139682317582, 16118.435854745028, 16128.274227659784, 16135.273912673958, 16195.514134575746, 9196.816063678689, 11152.832707509231, 11207.38359390225, 12129.514819407124, 12276.570320038934, 18054.638907221783, 18136.727949080774, 18150.820196687175, 18152.183151758203, 18271.36050238262, 5389.676619402849, 6154.83058218937, 6158.876822463615, 6175.907361389583, 6276.403741644536, 671.9514922591426, 896.4666023715879, 1060.390275591538, 1062.3201151263197, 1151.7523732397574, 27880.302992425353, 28150.01474948367, 28645.14064578066, 29019.0, 30107.849460752557, 1017.8982101789762, 1020.3571749797728, 1070.6092276319087, 1093.3549205968516, 1114.2478826791883, 12315.768423157613, 13532.661683457993, 13767.655808604864, 14425.78361324581, 14852.034617749812, 578525.0, 587914.0, 589187.0, 589587.0, 592172.0, 49971.0, 50286.0, 50422.747886261066, 50440.49559504358, 50692.0, 1308.3523655790445, 1340.3834236251435, 1347.4138749643937, 1357.803118547805, 1366.1666383506054, 26080.34799130037, 27268.454630906876, 27494.587581186697, 27522.724772752015, 27716.0, 34802.82598586991, 39314.80342598383, 39576.0, 39731.40402894017, 39747.60252397555, 427877.0, 471207.0, 474024.0, 491964.0, 533167.0, 23259.651105233097, 24111.758882411654, 24747.25758227256, 24909.128180513595, 25665.61501577515, 40569.59430405776, 40775.388369173896, 41052.589474104876, 41197.58802411937, 41738.373924391766, 34176.48735268923, 34243.4863477053, 34624.48063279002, 35045.64954350526, 35356.0, 28954.42091158123, 33253.50119748254, 33975.49036764401, 34088.48867266943, 34846.0, 33505.49741753924, 33899.66100338965, 34482.0, 34496.48255276223, 34572.13569660779, 28887.42225155443, 31032.0, 31159.37681246317, 31310.0, 31420.371592567564, 23499.0, 23541.52916941686, 23654.52690946137, 23833.0, 24032.279031629078], "value": 18867.27619474507, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.8841165033014, 376.8059449383566, 379.4403255198582, 387.2487374493484, 399.0104540738967, 14776.704465910676, 15164.772528412082, 18643.669776110077, 20132.798672013276, 20196.293129740443, 16341.5914602135, 19585.412437626896, 20039.198432062738, 20260.0, 20580.48548786281, 5732.828015159552, 5771.884562308762, 6061.999770037938, 6067.878642427148, 6074.392560743934, 5758.769649214027, 5938.732757025922, 5951.047832346825, 5966.850828729295, 5971.969530359677, 3681.3925702259094, 4101.507819061717, 6218.28489723683, 6561.671916404161, 6562.901556476657, 6357.300696923335, 6546.738130474823, 6617.3382661733685, 6627.204735431755, 6662.267150613428, 4933.629977751647, 5770.336411312711, 5772.595918285721, 5773.711314434304, 5779.815137896763, 24614.89232984502, 24711.887965041617, 24902.003919843184, 25115.744212789286, 25188.244352669448, 2247.4606094537194, 2314.155333303349, 2350.2831636350907, 2466.3957330454155, 2493.8379005364773, 14619.342129604192, 15453.381864725397, 15460.381584736597, 15704.0, 15865.444709435229, 1800.2438975630266, 1830.0, 1855.443366989911, 1885.9434216973514, 1908.7995760445092, 113256.0, 114157.0, 116588.0, 116917.0, 117311.41344293224, 9250.444973301623, 10595.894041059644, 10597.84103238452, 11189.944050279697, 11354.48904799286, 11614.419279036014, 13965.511207107906, 13970.441182352795, 13972.86027139736, 13977.86022139786, 7772.68909243624, 7835.942147810087, 8176.46852954556, 8455.647096464536, 8623.568821558896, 8418.957905210556, 8427.494350338877, 8437.198466145706, 8496.957515212385, 8533.104024077507, 6570.277269500351, 6809.0, 6809.489288303397, 6825.965870170618, 6834.794956151323, 1648.3818568036986, 1795.1652481595859, 1799.379214171109, 1844.2019031444502, 1859.1726681626546, 851.8253757979724, 936.6652520158747, 943.6414162618298, 948.9952550237205, 978.0, 19757.40727778169, 19975.55683036945, 21555.0, 21602.891985539973, 21635.12959686521, 896.9910300897038, 911.9544022798833, 918.5590916360185, 971.3152227679469, 973.7662960889336, 10791.784164316827, 11217.89585738487, 11298.887011129784, 11312.0, 11351.716207094889, 536325.457217991, 554997.0, 557714.0, 570578.0, 573260.0, 115839.0, 116065.0, 118940.0, 123153.0, 124810.0, 985.5072463768116, 1015.7765291635828, 1036.1192985962023, 1036.8392899100652, 1044.1385856668278, 15841.387560995954, 17909.283628654706, 17968.281268749626, 18960.905195474486, 19048.1428335728, 53382.199267011805, 53659.0, 53914.0, 53954.73022634862, 54559.18161227664, 72621.91067133892, 76133.61933190298, 76279.61860190885, 77088.0, 78910.421791565, 7708.537487750753, 7815.96092019559, 7900.486468379537, 7929.484583502054, 8305.418620696735, 9587.233021358414, 10755.946220268848, 11104.0, 11564.710882228012, 11978.862008109216, 9121.90537135548, 10992.835107473555, 11005.86332895121, 11007.67907851063, 11275.605353812578, 10408.115310198718, 10940.070094041777, 11227.186534145025, 11342.943285283522, 11420.743718190826, 11154.0, 11226.775464490827, 11593.6521904343, 11628.767424651629, 11776.352300623204, 7892.4869883457395, 8053.35573154158, 8116.594170291343, 8134.349252059939, 8182.181781822008, 9238.353315268136, 11177.882211779082, 11246.212765106364, 11400.657980260605, 11468.369239691898], "value": 9604.157057469249, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1264.671185491772, 1417.2488581052046, 1502.181311185404, 1659.705429764783, 1815.9092045397726, 7518.88721669174, 7762.0, 7968.203179682029, 8440.915590844083, 8458.91541084589, 7850.842983140334, 8105.918940810605, 8121.390895682818, 8245.0, 8271.007479102516, 2281.247188427819, 2345.7419683834764, 2346.4133966508375, 2347.8473899196583, 2357.8467399619058, 2096.67501537262, 2307.857610482805, 2335.94160145996, 2353.3881190890406, 2364.645303204516, 2297.793198612125, 2306.4349234437586, 2323.2101085630884, 2326.069572171128, 2326.639370897514, 2270.386995511211, 2296.7588403217756, 2303.6199027160496, 2306.7231932168165, 2310.699609050813, 1797.4158398520533, 2081.6357137500877, 2303.1133013789813, 2308.3305841306, 2351.2005917987794, 8289.41974061816, 8345.248927596485, 8354.498730076215, 8370.162983701643, 8389.748307550783, 4988.65079444439, 5155.097857874857, 5169.2504586835075, 5183.481651834786, 5185.507376799198, 2958.491169503547, 3089.6601373848857, 3200.8879689211, 3299.340131973615, 3501.4572741224897, 917.7797328641145, 977.3695966101825, 1021.6577446555339, 1039.3140527251978, 1039.636127355427, 9604.7033650457, 9678.661246856327, 10667.19996000303, 10672.679819605424, 10707.304025238334, 4511.503734589192, 4673.112108699404, 4675.625949923998, 4699.577038066591, 4734.810607575659, 8488.193621605938, 8507.404481686346, 8528.957355213308, 8566.742997710078, 8575.271101956403, 5045.520675535819, 5046.016026874798, 5050.520200580939, 5058.0, 5062.493750624908, 3901.785401802928, 4201.495820501559, 4660.813567457331, 4723.645726570521, 4749.26386410107, 3319.46888497844, 3493.2314890724, 3579.83890724918, 3621.927561448809, 3736.3834967230646, 1137.1073707140022, 1149.2013050929525, 1149.7298134938283, 1154.486253617149, 1157.346099453806, 745.9403247740277, 778.8286576953061, 843.2832092721138, 883.7525492862009, 920.40173886974, 5344.946550534522, 5534.889302213933, 5836.0, 5836.591438599257, 5849.94150058494, 872.2455076358904, 883.1654086887829, 909.8999110097884, 926.0878035135386, 958.3866325551653, 3429.8113603751535, 3531.399662057456, 3741.719371047128, 3826.9284600311958, 3893.9415908761393, 9578.808423831484, 9945.602175913027, 9950.850737238947, 9953.303268771117, 9957.950210249048, 8661.480311181349, 9551.56726491032, 9802.36284641496, 9881.061299176567, 9894.901050989542, 7382.852342953218, 7647.885281720779, 7861.567613781182, 7863.095743989515, 7866.410019248579, 5581.49766521015, 5745.988903883607, 5780.30636323627, 5848.53211743051, 5870.559708022, 8974.685885993958, 9112.498812565107, 9139.040400757825, 9275.39709918853, 9303.348765586614, 9235.49204793716, 9248.121428464285, 9304.627814887328, 9332.813343733224, 9355.766105847408, 6554.967225163844, 6749.73001079971, 6894.276101009322, 7039.54242974205, 7231.240719724353, 6868.244493105854, 7160.212376638669, 7537.660805263886, 7897.723579674914, 7908.4068694846965, 5176.741162941913, 7048.753293634698, 7059.329363710439, 7103.147622285359, 7234.96382518084, 6773.356531129535, 6826.8634627308165, 6828.624425656438, 6875.5530890491955, 6914.377706006484, 6566.671666416755, 6570.277269500256, 6575.638339891352, 6662.06731057662, 6673.265940746611, 4361.716488428242, 4583.518730533379, 4803.2314829628485, 4805.807767689393, 4901.117798796251, 4788.377510923558, 4794.976025119852, 4880.829170978998, 4914.385701787277, 5095.312132862165], "value": 4302.642696005751, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.6993670886075, 353.8726058618895, 357.6691560306717, 365.3040956976959, 369.1380626237733, 4793.712377257362, 5275.788968441268, 5406.513413792758, 5468.593818719765, 5485.01269771441, 5088.516590923866, 5136.460671629472, 5434.858679677262, 5484.204790305404, 5486.945130548694, 4108.876733697979, 4111.0, 4282.421873047146, 4292.810666560062, 4342.696011279212, 3863.1219741292634, 4061.57353477885, 4074.2055299216663, 4263.189993901165, 4335.978320108411, 3906.453096566469, 4047.6761859051353, 4074.6129117733767, 4083.9591604084026, 4156.833726650931, 3660.401115208192, 3864.1498870248643, 4022.3508361571908, 4097.959020409818, 4128.669706423479, 3123.6720144384967, 3507.877224297137, 3523.8766643167364, 3534.0634731796094, 3534.2401383702627, 4271.594198551133, 4574.908501829978, 4669.789859456333, 4879.682485728868, 5330.413654498002, 4591.403117594725, 4615.630749540062, 4623.69945953512, 4624.167649823031, 4637.6289896808175, 5661.2923384576925, 5671.461211184931, 5731.512821410143, 5741.368449470555, 5769.365369809318, 3426.58880934287, 3502.8949131526097, 3606.441001644749, 3747.7189210809297, 3935.783531905744, 5347.866303342409, 5441.089017375724, 5562.887422515513, 5945.464908158287, 5989.401059894019, 3895.8441662333757, 3904.590018048121, 3910.6284902934176, 3916.8433262669746, 3919.235749028912, 3212.3414699986447, 3230.838458077134, 3674.81625918703, 3927.9607203927594, 3943.546492153439, 4503.301988191835, 4590.265557510782, 4591.081783643284, 4604.447466303998, 4670.673052886334, 5277.461383210436, 5386.6498677586615, 5726.112452569859, 5729.140628905698, 5768.509676677446, 1016.3089099412471, 1130.8360287758228, 1192.7793358228755, 1236.4559593778708, 1250.5685538489206, 718.4073139659802, 749.7816048920504, 820.7893357297937, 848.6223630484375, 853.4964371021003, 4604.493505714368, 4645.372874661877, 5135.589152867761, 5149.613778966593, 5175.844724658266, 4684.789184486706, 4933.753312334369, 5590.580706447033, 5665.461781130786, 5668.461496157858, 4520.011195074157, 4644.838790302424, 4775.641826863, 4906.582940450102, 5116.616253780981, 2438.378213555532, 2833.886644534237, 2872.8795769649855, 2950.8229506229695, 2982.209714425679, 4853.733044682576, 5113.187746739353, 5338.172583249602, 5983.850403739942, 6010.398960103955, 5275.762590683428, 5292.867678307996, 5566.88254577438, 5692.0, 5727.5131613812455, 5379.811706590251, 5779.190913272061, 5821.330546987151, 5896.439838215277, 5908.556858235649, 5092.439831618593, 5222.686638801684, 5319.335083114611, 5373.919391209057, 5645.350784659817, 4769.95230047709, 5379.892402152013, 5408.323959505062, 5840.649561026352, 5875.20684707559, 5315.3621565412395, 5940.2277703899945, 6003.769227308479, 6204.401447160546, 6221.346758590464, 3537.239493508908, 3722.3807643675086, 3941.9159731074183, 3954.683625310026, 3970.2258059678084, 5389.814240867003, 5536.916946245729, 5885.823425297248, 5911.3201981772645, 5936.821895343147, 4537.886552836206, 5624.212610234488, 5665.348484924121, 5787.623804452697, 5801.332846722512, 5532.419095995024, 5689.837861612727, 5764.884702306014, 5769.240381683627, 5785.652860828363, 4309.762963037063, 4972.328735620645, 5337.438029745666, 5553.195211556341, 5690.743916523684, 5271.868203294948, 5705.202861098741, 5751.33859606134, 5757.394084732281, 5761.752916362538, 4866.440359358719, 4993.975030124826, 5358.919616205682, 5653.208550802973, 5676.148577713376], "value": 4075.119800304289, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.2814187316126, 701.3092104277289, 763.053813271543, 770.418334157711, 794.6384395100229, 12759.489620415196, 14803.11181329121, 15567.0, 15669.29488173032, 15729.921350393246, 14028.859711402883, 14237.145771253732, 14241.572752817407, 14246.715065698705, 14299.71400571988, 686.2759788423211, 696.9616671083088, 851.1743608699583, 866.9769671787288, 868.1231955724701, 875.72852415751, 890.9821803563957, 893.8882639670041, 895.8029233568637, 897.9685711000117, 791.4024911192042, 926.5969303353063, 947.1002547579792, 949.9145076943074, 956.3974695941547, 942.0955882352949, 942.3403617467766, 944.4380593546863, 944.6929747831913, 945.678469320431, 693.9063226464414, 700.9614471204081, 703.2896774257985, 706.4983861458371, 707.1796715809687, 12968.740625187444, 13223.669408264774, 13790.862091379058, 13854.238016909067, 14168.079074860101, 11531.365774882379, 14049.64875878101, 14369.928150359287, 14380.784288235685, 14465.855341446557, 11460.885391146148, 11628.767424651543, 11693.532258709642, 11952.820707689305, 12001.159918805688, 3698.223373091627, 3719.553653561563, 3728.366177749789, 3741.046033261528, 3872.4384964180035, 104762.47618761858, 107223.0, 108323.0, 110565.44717276363, 112345.43827280893, 10634.41510716918, 10748.355098694103, 10788.946055269831, 10913.454327283604, 11050.0, 23101.537969240522, 23521.529569408514, 23619.881900590386, 23623.7637623625, 23695.881520592287, 7395.741149059757, 7544.924550754532, 7558.546487210675, 7610.885836712454, 7784.0, 4259.318509038539, 4337.158293885821, 4403.339499075164, 4412.933805992912, 4506.932396014063, 3505.789652620851, 3593.94609080864, 3613.8193090345376, 3614.4578313252696, 3624.7643903146213, 573.3435216676882, 647.8930976388843, 662.1855118204539, 667.436016566001, 679.3647939176885, 595.4544323019128, 631.3150231998242, 631.5137344244906, 667.7195577857311, 673.0879658063254, 7616.657250423744, 8880.66789981508, 8960.507172105466, 9251.95374023139, 9469.479178645102, 543.342555507832, 567.038869116843, 607.0406767010822, 649.3117295666514, 675.8330236591106, 4390.495093064274, 4502.864914052584, 4508.843928060477, 4552.521985191574, 4625.3755742974945, 99368.0, 105045.0, 106142.40786388211, 108650.82121768377, 109604.4519777396, 87338.0, 88232.5588372054, 88958.0, 91648.0, 92688.53655731678, 68623.0, 78457.60771196222, 79219.0, 80812.59593701994, 80930.38139237183, 8729.388942773945, 8876.156765107304, 8918.821623567623, 9033.051529589298, 9112.635494580143, 52194.95610087701, 52776.0, 53674.0, 53715.657108572595, 54695.0, 62480.0, 71849.0, 74643.62678186792, 75602.24397756172, 79067.60466197862, 11452.198346115692, 11502.367369794742, 11537.596184133516, 11694.88305116938, 11768.411579421167, 12111.333876636869, 12616.621501354974, 12763.617091487271, 13115.213087214797, 13303.268320242092, 11796.52813887435, 11957.760844782882, 11990.220635658654, 12078.21491603043, 12111.878881211074, 11915.52337906474, 11969.341686207343, 11982.520699172284, 12094.75810483803, 12253.326067066397, 11630.069594432256, 11883.465244063866, 11941.223820451643, 11977.341246231541, 12008.519659213884, 9296.907030929604, 9860.950695246478, 9897.950510247403, 9919.752006199902, 10263.076323130954, 9166.587503562489, 9929.156021738234, 10691.679249622524, 11340.886591134313, 11736.530538778694], "value": 7310.127891786104, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1638.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 120.0, 119.0, 118.0, 119.0, 121.0, 119.0, 118.0, 121.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 118.0, 118.0, 142.0, 138.0, 157.0, 148.0, 149.0, 168.0, 120.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 209.0, 252.0, 188.0, 262.0, 279.0, 202.0, 202.0, 199.0, 196.0], "value": 219.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 62.0, 85.0, 62.0, 62.0, 69.0, 77.0, 64.0, 56.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 87.0, 73.0, 72.0, 73.0, 82.0, 71.0, 72.0, 84.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 162.0, 113.0, 119.0, 113.0, 113.0, 112.0, 113.0, 111.0, 113.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 97.0, 98.0, 153.0, 102.0, 96.0, 96.0, 96.0, 95.0, 99.0], "value": 107.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 140.0, 104.0, 147.0, 144.0, 95.0, 112.0, 143.0, 90.0, 150.0], "value": 121.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 54.0, 57.0, 59.0, 59.0, 59.0, 61.0, 58.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 111.0, 102.0, 119.0, 86.0, 77.0, 71.0, 71.0, 73.0, 75.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 97.0, 95.0, 95.0, 95.0, 95.0, 134.0, 95.0], "value": 99.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 164.0, 166.0, 158.0, 156.0, 159.0, 166.0, 157.0, 184.0, 171.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 179.0, 179.0, 181.0, 181.0, 177.0, 177.0, 184.0, 164.0, 245.0], "value": 184.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 73.0, 69.0, 69.0, 69.0, 74.0, 84.0, 93.0, 92.0], "value": 76.4, "unit": "score"}]}]} 18:40:06 INFO - Return code: 0 18:40:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:40:06 ERROR - # TBPL SUCCESS # 18:40:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:40:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:40:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:40:06 INFO - 2016-12-09 18:40:06.718 screenresolution[1955:19235] starting screenresolution argv=screenresolution get 18:40:06 INFO - 2016-12-09 18:40:06.721 screenresolution[1955:19235] Display 0: 1600x1200x32@60 18:40:06 INFO - 2016-12-09 18:40:06.727 screenresolution[1956:19238] starting screenresolution argv=screenresolution list 18:40:06 INFO - Available Modes on Display 0 18:40:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:40:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:40:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:40:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:40:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:40:06 INFO - Intel Iris: 18:40:06 INFO - Chipset Model: Intel Iris 18:40:06 INFO - Type: GPU 18:40:06 INFO - Bus: Built-In 18:40:06 INFO - VRAM (Dynamic, Max): 1536 MB 18:40:06 INFO - Vendor: Intel (0x8086) 18:40:06 INFO - Device ID: 0x0a2e 18:40:06 INFO - Revision ID: 0x0009 18:40:06 INFO - Displays: 18:40:06 INFO - Display: 18:40:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:40:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:40:06 INFO - Display Serial Number: 1600x1200 60 18:40:06 INFO - Main Display: Yes 18:40:06 INFO - Mirror: Off 18:40:06 INFO - Online: Yes 18:40:06 INFO - Rotation: Supported 18:40:06 INFO - Return code: 0 18:40:06 INFO - Running post-action listener: _resource_record_post_action 18:40:06 INFO - [mozharness: 2016-12-10 02:40:06.970998Z] Finished run-tests step (success) 18:40:06 INFO - Running post-run listener: _resource_record_post_run 18:40:07 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 1644032; Write bytes: 554719232; Read time: 16; Write time: 1683 18:40:07 INFO - TinderboxPrint: CPU usage
54.9% 18:40:07 INFO - TinderboxPrint: I/O read bytes / time
1,644,032 / 16 18:40:07 INFO - TinderboxPrint: I/O write bytes / time
554,719,232 / 1,683 18:40:07 INFO - TinderboxPrint: CPU idle
2,169.2 (45.1%) 18:40:07 INFO - TinderboxPrint: CPU system
972.3 (20.2%) 18:40:07 INFO - TinderboxPrint: CPU user
1,664.3 (34.6%) 18:40:07 INFO - TinderboxPrint: Swap in / out
251,011,072 / 0 18:40:07 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230707712; Write bytes: 225154560; Read time: 16460; Write time: 429 18:40:07 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1644032; Write bytes: 302371328; Read time: 16; Write time: 1201 18:40:07 INFO - Running post-run listener: _upload_blobber_files 18:40:07 INFO - Blob upload gear active. 18:40:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:40:07 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 18:40:07 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:40:07 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:40:07 INFO - (blobuploader) - INFO - Open directory for files ... 18:40:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:40:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:40:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:09 INFO - (blobuploader) - INFO - Done attempting. 18:40:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:40:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:40:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:10 INFO - (blobuploader) - INFO - Done attempting. 18:40:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:40:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:12 INFO - (blobuploader) - INFO - Done attempting. 18:40:12 INFO - (blobuploader) - INFO - Iteration through files over. 18:40:12 INFO - Return code: 0 18:40:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:40:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:40:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8e96d648d68111b759a0ec927a53ece9dc2efba97945c681f6cc50b53ae1bf139408209a729b5ca030a2ec0c66a082947ce23610c166f237279049e0b1a697ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8bace01822933ed58c57e2d61022c4f4927c6f36c346006f85a95e764a5933eb1ead5549d6ecb695bafbc2aece2c601d55ac0d0df25ee0e3dc76b7d4765aae48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33e912f8ecb07c68ae6f8313514f2afcfa369915899113d415e6a9dadc54017531c3db64cb2f8527498b8927963097d394cd256ceadff12de09ab6f540aaad26"} 18:40:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:40:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:40:12 INFO - Contents: 18:40:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8e96d648d68111b759a0ec927a53ece9dc2efba97945c681f6cc50b53ae1bf139408209a729b5ca030a2ec0c66a082947ce23610c166f237279049e0b1a697ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8bace01822933ed58c57e2d61022c4f4927c6f36c346006f85a95e764a5933eb1ead5549d6ecb695bafbc2aece2c601d55ac0d0df25ee0e3dc76b7d4765aae48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33e912f8ecb07c68ae6f8313514f2afcfa369915899113d415e6a9dadc54017531c3db64cb2f8527498b8927963097d394cd256ceadff12de09ab6f540aaad26"} 18:40:12 INFO - Running post-run listener: copy_logs_to_upload_dir 18:40:12 INFO - Copying logs to upload dir... 18:40:12 INFO - mkdir: /builds/slave/test/build/upload/logs 18:40:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.262333 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-09 18:40:12.371435) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 18:40:12.374827) ========= 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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8e96d648d68111b759a0ec927a53ece9dc2efba97945c681f6cc50b53ae1bf139408209a729b5ca030a2ec0c66a082947ce23610c166f237279049e0b1a697ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8bace01822933ed58c57e2d61022c4f4927c6f36c346006f85a95e764a5933eb1ead5549d6ecb695bafbc2aece2c601d55ac0d0df25ee0e3dc76b7d4765aae48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33e912f8ecb07c68ae6f8313514f2afcfa369915899113d415e6a9dadc54017531c3db64cb2f8527498b8927963097d394cd256ceadff12de09ab6f540aaad26"} build_url:https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/IMBvAgdkQ9On9WxS4SvL6g/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8e96d648d68111b759a0ec927a53ece9dc2efba97945c681f6cc50b53ae1bf139408209a729b5ca030a2ec0c66a082947ce23610c166f237279049e0b1a697ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8bace01822933ed58c57e2d61022c4f4927c6f36c346006f85a95e764a5933eb1ead5549d6ecb695bafbc2aece2c601d55ac0d0df25ee0e3dc76b7d4765aae48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33e912f8ecb07c68ae6f8313514f2afcfa369915899113d415e6a9dadc54017531c3db64cb2f8527498b8927963097d394cd256ceadff12de09ab6f540aaad26"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 18:40:12.410416) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 18:40:12.410761) ========= 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.SfXTkyK0h1/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.hydcvVK4Ol/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005224 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 18:40:12.459596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 18:40:12.459936) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 18:40:12.460308) ========= ========= Total master_lag: 0.20 =========