builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0081 starttime: 1486388363.63 results: success (0) buildid: 20170206035750 builduid: 075fcc2a8bc5407ea5d37ec4c68f38f6 revision: 1cc159c7a0445ec51e335c8a1a1cceea7bbf8380 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.629952) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.630472) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.630838) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.694942) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.695312) ========= 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.c0Zg48bh4g/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.nlko2PWBVk/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-06 05:39:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-02-06 05:39:23 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.161222 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.880519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.881964) ========= 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.c0Zg48bh4g/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.nlko2PWBVk/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.026848 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:23.930461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 05:39:23.930853) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1cc159c7a0445ec51e335c8a1a1cceea7bbf8380 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1cc159c7a0445ec51e335c8a1a1cceea7bbf8380 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c0Zg48bh4g/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.nlko2PWBVk/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-06 05:39:24,035 truncating revision to first 12 chars 2017-02-06 05:39:24,035 Setting DEBUG logging. 2017-02-06 05:39:24,035 attempt 1/10 2017-02-06 05:39:24,036 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1cc159c7a044?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 05:39:25,064 unpacking tar archive at: mozilla-central-1cc159c7a044/testing/mozharness/ program finished with exit code 0 elapsedTime=1.300434 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 05:39:25.254394) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:25.254713) ========= script_repo_revision: 1cc159c7a0445ec51e335c8a1a1cceea7bbf8380 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:25.255113) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:25.255382) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 05:39:25.274804) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-02-06 05:39:25.275176) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c0Zg48bh4g/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.nlko2PWBVk/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:39:25 INFO - MultiFileLogger online at 20170206 05:39:25 in /builds/slave/test 05:39:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:39:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:39:25 INFO - {'append_to_log': False, 05:39:25 INFO - 'base_work_dir': '/builds/slave/test', 05:39:25 INFO - 'blob_upload_branch': 'Firefox', 05:39:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:39:25 INFO - 'branch': 'Firefox', 05:39:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:39:25 INFO - 'config_files': ('talos/mac_config.py',), 05:39:25 INFO - 'default_actions': ('clobber', 05:39:25 INFO - 'read-buildbot-config', 05:39:25 INFO - 'download-and-extract', 05:39:25 INFO - 'populate-webroot', 05:39:25 INFO - 'create-virtualenv', 05:39:25 INFO - 'install', 05:39:25 INFO - 'run-tests'), 05:39:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:39:25 INFO - 'download_minidump_stackwalk': True, 05:39:25 INFO - 'download_symbols': 'ondemand', 05:39:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:39:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:39:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:39:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:39:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:39:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:39:25 INFO - 'installer_path': 'installer.exe', 05:39:25 INFO - 'log_level': 'info', 05:39:25 INFO - 'log_name': 'talos', 05:39:25 INFO - 'log_to_console': True, 05:39:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:39:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:39:25 INFO - 'opt_config_files': (), 05:39:25 INFO - 'pip_index': False, 05:39:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:39:25 INFO - 'cmd': ('bash', 05:39:25 INFO - '-c', 05:39:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:39:25 INFO - 'enabled': True, 05:39:25 INFO - 'halt_on_failure': False, 05:39:25 INFO - 'name': 'check_screen_resolution'},), 05:39:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:39:25 INFO - 'cmd': ('bash', 05:39:25 INFO - '-c', 05:39:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:39:25 INFO - 'enabled': True, 05:39:25 INFO - 'halt_on_failure': False, 05:39:25 INFO - 'name': 'check_screen_resolution'},), 05:39:25 INFO - 'run_cmd_checks_enabled': True, 05:39:25 INFO - 'sps_profile': False, 05:39:25 INFO - 'sps_profile_interval': 0, 05:39:25 INFO - 'suite': 'dromaeojs', 05:39:25 INFO - 'system_bits': '32', 05:39:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:39:25 INFO - 'title': 't-yosemite-r7-0081', 05:39:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:39:25 INFO - 'use_talos_json': True, 05:39:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:39:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:39:25 INFO - 'work_dir': 'build'} 05:39:25 INFO - [mozharness: 2017-02-06 13:39:25.493346Z] Running clobber step. 05:39:25 INFO - Running pre-action listener: _resource_record_pre_action 05:39:25 INFO - Running main action method: clobber 05:39:25 INFO - rmtree: /builds/slave/test/build 05:39:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:39:28 INFO - Running post-action listener: _resource_record_post_action 05:39:28 INFO - [mozharness: 2017-02-06 13:39:28.380416Z] Finished clobber step (success) 05:39:28 INFO - [mozharness: 2017-02-06 13:39:28.380548Z] Running read-buildbot-config step. 05:39:28 INFO - Running pre-action listener: _resource_record_pre_action 05:39:28 INFO - Running main action method: read_buildbot_config 05:39:28 INFO - Using buildbot properties: 05:39:28 INFO - { 05:39:28 INFO - "project": "", 05:39:28 INFO - "product": "firefox", 05:39:28 INFO - "script_repo_revision": "production", 05:39:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:39:28 INFO - "repository": "", 05:39:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:39:28 INFO - "buildid": "20170206035750", 05:39:28 INFO - "pgo_build": "False", 05:39:28 INFO - "basedir": "/builds/slave/test", 05:39:28 INFO - "buildnumber": 43, 05:39:28 INFO - "slavename": "t-yosemite-r7-0081", 05:39:28 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:39:28 INFO - "platform": "yosemite_r7", 05:39:28 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:39:28 INFO - "branch": "mozilla-central", 05:39:28 INFO - "repo_path": "mozilla-central", 05:39:28 INFO - "revision": "1cc159c7a0445ec51e335c8a1a1cceea7bbf8380", 05:39:28 INFO - "stage_platform": "macosx64", 05:39:28 INFO - "builduid": "075fcc2a8bc5407ea5d37ec4c68f38f6", 05:39:28 INFO - "slavebuilddir": "test" 05:39:28 INFO - } 05:39:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 05:39:28 INFO - Running post-action listener: _resource_record_post_action 05:39:28 INFO - [mozharness: 2017-02-06 13:39:28.383867Z] Finished read-buildbot-config step (success) 05:39:28 INFO - [mozharness: 2017-02-06 13:39:28.383989Z] Running download-and-extract step. 05:39:28 INFO - Running pre-action listener: _resource_record_pre_action 05:39:28 INFO - Running main action method: download_and_extract 05:39:28 INFO - mkdir: /builds/slave/test/build/tests 05:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:28 INFO - trying https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 05:39:28 INFO - Downloading https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:39:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 05:39:29 INFO - Downloaded 1199 bytes. 05:39:29 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:39:29 INFO - Using the following test package requirements: 05:39:29 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:39:29 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 05:39:29 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 05:39:29 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'jsshell-mac.zip'], 05:39:29 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 05:39:29 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:39:29 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 05:39:29 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 05:39:29 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 05:39:29 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:39:29 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 05:39:29 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:39:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:39:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:39:29 INFO - Fetch https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:39:30 INFO - Expected file size: 34851061 05:39:30 INFO - Obtained file size: 34851061 05:39:37 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:39:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:39:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:39:37 INFO - Fetch https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:39:38 INFO - Expected file size: 34851061 05:39:38 INFO - Obtained file size: 34851061 05:39:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 05:39:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:39:45 INFO - Fetch https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 05:39:46 INFO - Expected file size: 14403454 05:39:46 INFO - Obtained file size: 14403454 05:39:46 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')]} 05:39:46 INFO - trying https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:39:46 INFO - Downloading https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 05:39:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 05:39:48 INFO - Downloaded 70259571 bytes. 05:39:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:39:48 INFO - mkdir: /builds/slave/test/properties 05:39:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:39:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:39:48 INFO - Contents: 05:39:48 INFO - build_url:https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:39:50 INFO - Running post-action listener: _resource_record_post_action 05:39:50 INFO - Running post-action listener: set_extra_try_arguments 05:39:50 INFO - [mozharness: 2017-02-06 13:39:50.192936Z] Finished download-and-extract step (success) 05:39:50 INFO - [mozharness: 2017-02-06 13:39:50.193092Z] Running populate-webroot step. 05:39:50 INFO - Running pre-action listener: _resource_record_pre_action 05:39:50 INFO - Running main action method: populate_webroot 05:39:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:39:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:39:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:39:50 INFO - '--tppagecycles', 05:39:50 INFO - '7'], 05:39:50 INFO - 'tests': ['tsvgm']}}, 05:39:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:39:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:39:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:39:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:39:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:39:50 INFO - 'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:39:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:39:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:39:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:39:50 INFO - 'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['damp', 'tps']}, 05:39:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:39:50 INFO - 'tests': ['damp', 'tps']}, 05:39:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['dromaeo_dom']}, 05:39:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:39:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['basic_compositor_video']}, 05:39:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:39:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['a11yr', 05:39:50 INFO - 'ts_paint', 05:39:50 INFO - 'tpaint', 05:39:50 INFO - 'sessionrestore', 05:39:50 INFO - 'sessionrestore_no_auto_restore', 05:39:50 INFO - 'tabpaint']}, 05:39:50 INFO - 'other-e10s': {'tests': ['a11yr', 05:39:50 INFO - 'ts_paint', 05:39:50 INFO - 'tpaint', 05:39:50 INFO - 'sessionrestore', 05:39:50 INFO - 'sessionrestore_no_auto_restore', 05:39:50 INFO - 'tabpaint']}, 05:39:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:39:50 INFO - 'ts_paint', 05:39:50 INFO - 'tpaint', 05:39:50 INFO - 'sessionrestore', 05:39:50 INFO - 'sessionrestore_no_auto_restore', 05:39:50 INFO - 'tabpaint']}, 05:39:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:39:50 INFO - 'ts_paint', 05:39:50 INFO - 'tpaint', 05:39:50 INFO - 'sessionrestore', 05:39:50 INFO - 'sessionrestore_no_auto_restore', 05:39:50 INFO - 'tabpaint']}, 05:39:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['a11yr', 05:39:50 INFO - 'ts_paint', 05:39:50 INFO - 'tpaint', 05:39:50 INFO - 'sessionrestore', 05:39:50 INFO - 'sessionrestore_no_auto_restore', 05:39:50 INFO - 'tabpaint']}, 05:39:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['a11yr', 05:39:50 INFO - 'ts_paint', 05:39:50 INFO - 'tpaint', 05:39:50 INFO - 'sessionrestore', 05:39:50 INFO - 'sessionrestore_no_auto_restore', 05:39:50 INFO - 'tabpaint']}, 05:39:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['tsvgx', 05:39:50 INFO - 'tsvgr_opacity', 05:39:50 INFO - 'tart', 05:39:50 INFO - 'tscrollx', 05:39:50 INFO - 'cart', 05:39:50 INFO - 'tsvg_static']}, 05:39:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:39:50 INFO - 'tsvgr_opacity', 05:39:50 INFO - 'tart', 05:39:50 INFO - 'tscrollx', 05:39:50 INFO - 'cart', 05:39:50 INFO - 'tsvg_static']}, 05:39:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:39:50 INFO - 'talos_options': ['--disable-e10s'], 05:39:50 INFO - 'tests': ['tp5o']}, 05:39:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:39:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:39:50 INFO - 'talos_options': ['--disable-e10s', 05:39:50 INFO - '--xperf_path', 05:39:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:39:50 INFO - 'tests': ['tp5n']}, 05:39:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:39:50 INFO - 'talos_options': ['--xperf_path', 05:39:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:39:50 INFO - 'tests': ['tp5n']}}, 05:39:50 INFO - 'talos.zip': {'path': '', 05:39:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:39:50 INFO - Running post-action listener: _resource_record_post_action 05:39:50 INFO - [mozharness: 2017-02-06 13:39:50.211408Z] Finished populate-webroot step (success) 05:39:50 INFO - [mozharness: 2017-02-06 13:39:50.211541Z] Running create-virtualenv step. 05:39:50 INFO - Running pre-action listener: _resource_record_pre_action 05:39:50 INFO - Running main action method: create_virtualenv 05:39:50 INFO - Creating virtualenv /builds/slave/test/build/venv 05:39:50 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 05:39:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:39:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:39:50 INFO - Using real prefix '/tools/python27' 05:39:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:39:51 INFO - Installing distribute.............................................................................................................................................................................................done. 05:39:53 INFO - Installing pip.................done. 05:39:53 INFO - Return code: 0 05:39:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:39:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:39:54 INFO - Reading from file tmpfile_stdout 05:39:54 INFO - Output received: 05:39:54 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) 05:39:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:39: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')]} 05:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39: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')]} 05:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:54 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 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:39:54 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 05:39:54 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 05:39:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:39:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:39:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:39:54 INFO - 'HOME': '/Users/cltbld', 05:39:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:39:54 INFO - 'LOGNAME': 'cltbld', 05:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:54 INFO - 'MOZ_NO_REMOTE': '1', 05:39:54 INFO - 'NO_EM_RESTART': '1', 05:39:54 INFO - 'PAGER': '/bin/cat', 05:39:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:54 INFO - 'PWD': '/builds/slave/test', 05:39:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:39:54 INFO - 'SHELL': '/bin/bash', 05:39:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:39:54 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:39:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:39:54 INFO - 'USER': 'cltbld', 05:39:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:39:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:54 INFO - 'XPC_FLAGS': '0x0', 05:39:54 INFO - 'XPC_SERVICE_NAME': '0', 05:39:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:39:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:39:55 INFO - Cleaning up... 05:39:55 INFO - Return code: 0 05:39:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:39:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:39:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:39:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:39:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:39:55 INFO - 'HOME': '/Users/cltbld', 05:39:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:39:55 INFO - 'LOGNAME': 'cltbld', 05:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:55 INFO - 'MOZ_NO_REMOTE': '1', 05:39:55 INFO - 'NO_EM_RESTART': '1', 05:39:55 INFO - 'PAGER': '/bin/cat', 05:39:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:55 INFO - 'PWD': '/builds/slave/test', 05:39:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:39:55 INFO - 'SHELL': '/bin/bash', 05:39:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:39:55 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:39:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:39:55 INFO - 'USER': 'cltbld', 05:39:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:39:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:55 INFO - 'XPC_FLAGS': '0x0', 05:39:55 INFO - 'XPC_SERVICE_NAME': '0', 05:39:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:39:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:55 INFO - Downloading/unpacking psutil>=3.1.1 05:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:39:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:39:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:39:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:39:59 INFO - Installing collected packages: psutil 05:39:59 INFO - Running setup.py install for psutil 05:39:59 INFO - building 'psutil._psutil_osx' extension 05:39:59 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 05:40:00 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 05:40:00 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 05:40:00 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 05:40:00 INFO - building 'psutil._psutil_posix' extension 05:40:00 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 05:40:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:40:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:40:00 INFO - ^ 05:40:00 INFO - 1 warning generated. 05:40:00 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 05:40:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:00 INFO - Successfully installed psutil 05:40:00 INFO - Cleaning up... 05:40:00 INFO - Return code: 0 05:40:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:40:00 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')]} 05:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:00 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')]} 05:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:40:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:01 INFO - 'HOME': '/Users/cltbld', 05:40:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:01 INFO - 'LOGNAME': 'cltbld', 05:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:01 INFO - 'MOZ_NO_REMOTE': '1', 05:40:01 INFO - 'NO_EM_RESTART': '1', 05:40:01 INFO - 'PAGER': '/bin/cat', 05:40:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:01 INFO - 'PWD': '/builds/slave/test', 05:40:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:01 INFO - 'SHELL': '/bin/bash', 05:40:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:01 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:01 INFO - 'USER': 'cltbld', 05:40:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:01 INFO - 'XPC_FLAGS': '0x0', 05:40:01 INFO - 'XPC_SERVICE_NAME': '0', 05:40:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:40:03 INFO - Installing collected packages: mozsystemmonitor 05:40:03 INFO - Running setup.py install for mozsystemmonitor 05:40:04 INFO - Successfully installed mozsystemmonitor 05:40:04 INFO - Cleaning up... 05:40:04 INFO - Return code: 0 05:40:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40: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==2.5.1'] in /builds/slave/test/build 05:40: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==2.5.1 05:40:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:04 INFO - 'HOME': '/Users/cltbld', 05:40:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:04 INFO - 'LOGNAME': 'cltbld', 05:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:04 INFO - 'MOZ_NO_REMOTE': '1', 05:40:04 INFO - 'NO_EM_RESTART': '1', 05:40:04 INFO - 'PAGER': '/bin/cat', 05:40:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:04 INFO - 'PWD': '/builds/slave/test', 05:40:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:04 INFO - 'SHELL': '/bin/bash', 05:40:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:04 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:04 INFO - 'USER': 'cltbld', 05:40:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:04 INFO - 'XPC_FLAGS': '0x0', 05:40:04 INFO - 'XPC_SERVICE_NAME': '0', 05:40:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:04 INFO - Downloading/unpacking jsonschema==2.5.1 05:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:40:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:08 INFO - Downloading functools32-3.2.3-2.tar.gz 05:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:40:08 INFO - warning: no files found matching '*.txt' 05:40:08 INFO - no previously-included directories found matching 'build' 05:40:08 INFO - no previously-included directories found matching 'dist' 05:40:08 INFO - no previously-included directories found matching '.git*' 05:40:08 INFO - Installing collected packages: jsonschema, functools32 05:40:08 INFO - Running setup.py install for functools32 05:40:08 INFO - warning: no files found matching '*.txt' 05:40:08 INFO - no previously-included directories found matching 'build' 05:40:08 INFO - no previously-included directories found matching 'dist' 05:40:08 INFO - no previously-included directories found matching '.git*' 05:40:08 INFO - Successfully installed jsonschema functools32 05:40:08 INFO - Cleaning up... 05:40:08 INFO - Return code: 0 05:40:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:40:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:08 INFO - 'HOME': '/Users/cltbld', 05:40:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:08 INFO - 'LOGNAME': 'cltbld', 05:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:08 INFO - 'MOZ_NO_REMOTE': '1', 05:40:08 INFO - 'NO_EM_RESTART': '1', 05:40:08 INFO - 'PAGER': '/bin/cat', 05:40:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:08 INFO - 'PWD': '/builds/slave/test', 05:40:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:08 INFO - 'SHELL': '/bin/bash', 05:40:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:08 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:08 INFO - 'USER': 'cltbld', 05:40:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:08 INFO - 'XPC_FLAGS': '0x0', 05:40:08 INFO - 'XPC_SERVICE_NAME': '0', 05:40:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:40:09 INFO - Cleaning up... 05:40:09 INFO - Return code: 0 05:40:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40:09 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')]} 05:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:09 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')]} 05:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:09 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 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40:09 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 05:40:09 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 05:40:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:09 INFO - 'HOME': '/Users/cltbld', 05:40:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:09 INFO - 'LOGNAME': 'cltbld', 05:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:09 INFO - 'MOZ_NO_REMOTE': '1', 05:40:09 INFO - 'NO_EM_RESTART': '1', 05:40:09 INFO - 'PAGER': '/bin/cat', 05:40:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:09 INFO - 'PWD': '/builds/slave/test', 05:40:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:09 INFO - 'SHELL': '/bin/bash', 05:40:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:09 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:09 INFO - 'USER': 'cltbld', 05:40:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:09 INFO - 'XPC_FLAGS': '0x0', 05:40:09 INFO - 'XPC_SERVICE_NAME': '0', 05:40:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:09 INFO - Downloading/unpacking blobuploader==1.2.4 05:40:09 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 05:40:09 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 05:40:09 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 05:40:09 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 05:40:09 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 05:40:09 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 05:40:11 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:13 INFO - Downloading docopt-0.6.1.tar.gz 05:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:13 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:13 INFO - Running setup.py install for blobuploader 05:40:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:13 INFO - Running setup.py install for requests 05:40:13 INFO - Running setup.py install for docopt 05:40:13 INFO - Successfully installed blobuploader requests docopt 05:40:13 INFO - Cleaning up... 05:40:14 INFO - Return code: 0 05:40:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:14 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')]} 05:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:14 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')]} 05:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:14 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 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40:14 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 05:40:14 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 05:40:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:14 INFO - 'HOME': '/Users/cltbld', 05:40:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:14 INFO - 'LOGNAME': 'cltbld', 05:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:14 INFO - 'MOZ_NO_REMOTE': '1', 05:40:14 INFO - 'NO_EM_RESTART': '1', 05:40:14 INFO - 'PAGER': '/bin/cat', 05:40:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:14 INFO - 'PWD': '/builds/slave/test', 05:40:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:14 INFO - 'SHELL': '/bin/bash', 05:40:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:14 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:14 INFO - 'USER': 'cltbld', 05:40:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:14 INFO - 'XPC_FLAGS': '0x0', 05:40:14 INFO - 'XPC_SERVICE_NAME': '0', 05:40:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:14 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-cwzMvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:14 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-FD7dvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:14 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ciLtA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:14 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Yj4J1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:14 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-TPr2TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:14 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-4N1QEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:14 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-isl8sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Xt_7Jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-oiQNV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ydgdbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-osMNve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-OJ2gtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-6PIgOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-mhQHT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-0llcNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Y3PLwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-nvWu2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:16 INFO - Running setup.py install for manifestparser 05:40:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:16 INFO - Running setup.py install for mozcrash 05:40:16 INFO - Running setup.py install for mozdebug 05:40:16 INFO - Running setup.py install for mozdevice 05:40:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:40:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:16 INFO - Running setup.py install for mozfile 05:40:16 INFO - Running setup.py install for mozhttpd 05:40:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:16 INFO - Running setup.py install for mozinfo 05:40:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:17 INFO - Running setup.py install for mozInstall 05:40:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:17 INFO - Running setup.py install for mozleak 05:40:17 INFO - Running setup.py install for mozlog 05:40:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:17 INFO - Running setup.py install for moznetwork 05:40:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:17 INFO - Running setup.py install for mozprocess 05:40:17 INFO - Running setup.py install for mozprofile 05:40:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:40:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:40:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:40:18 INFO - Running setup.py install for mozrunner 05:40:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:40:18 INFO - Running setup.py install for mozscreenshot 05:40:18 INFO - Running setup.py install for moztest 05:40:18 INFO - Running setup.py install for mozversion 05:40:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:40:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:40:18 INFO - Cleaning up... 05:40:18 INFO - Return code: 0 05:40:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:18 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')]} 05:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:18 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')]} 05:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:18 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 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40:18 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 05:40:18 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 05:40:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:18 INFO - 'HOME': '/Users/cltbld', 05:40:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:18 INFO - 'LOGNAME': 'cltbld', 05:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:18 INFO - 'MOZ_NO_REMOTE': '1', 05:40:18 INFO - 'NO_EM_RESTART': '1', 05:40:18 INFO - 'PAGER': '/bin/cat', 05:40:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:18 INFO - 'PWD': '/builds/slave/test', 05:40:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:18 INFO - 'SHELL': '/bin/bash', 05:40:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:18 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:18 INFO - 'USER': 'cltbld', 05:40:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:18 INFO - 'XPC_FLAGS': '0x0', 05:40:18 INFO - 'XPC_SERVICE_NAME': '0', 05:40:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:18 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-MsdELi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-dEAdwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-DV8UF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-T0pRP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Zjv7e0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-QFLl2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-yWTVzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-6q30MF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-NCnHp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:19 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)) 05:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:19 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xhc6Zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:20 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)) 05:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-qfhP_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:20 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)) 05:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-GHwcyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 05:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-1FlRRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:20 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)) 05:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-sxV3DW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:20 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)) 05:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-czOerI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:20 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)) 05:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-CnnFCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-EBTD8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:20 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)) 05:40:20 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)) 05:40:20 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)) 05:40:20 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)) 05:40:20 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)) 05:40:20 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)) 05:40:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:20 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 05:40:20 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 05:40:20 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 05:40:20 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 05:40:20 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 05:40:20 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 05:40:23 INFO - Downloading blessings-1.6.tar.gz 05:40:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:23 INFO - Installing collected packages: blessings 05:40:23 INFO - Running setup.py install for blessings 05:40:23 INFO - Successfully installed blessings 05:40:23 INFO - Cleaning up... 05:40:23 INFO - Return code: 0 05:40:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:24 INFO - Reading from file tmpfile_stdout 05:40:24 INFO - Current package versions: 05:40:24 INFO - blessings == 1.6 05:40:24 INFO - blobuploader == 1.2.4 05:40:24 INFO - docopt == 0.6.1 05:40:24 INFO - functools32 == 3.2.3-2 05:40:24 INFO - jsonschema == 2.5.1 05:40:24 INFO - manifestparser == 1.1 05:40:24 INFO - mozInstall == 1.12 05:40:24 INFO - mozcrash == 1.0 05:40:24 INFO - mozdebug == 0.1 05:40:24 INFO - mozdevice == 0.49 05:40:24 INFO - mozfile == 1.2 05:40:24 INFO - mozhttpd == 0.7 05:40:24 INFO - mozinfo == 0.9 05:40:24 INFO - mozleak == 0.1 05:40:24 INFO - mozlog == 3.4 05:40:24 INFO - moznetwork == 0.27 05:40:24 INFO - mozprocess == 0.24 05:40:24 INFO - mozprofile == 0.28 05:40:24 INFO - mozrunner == 6.13 05:40:24 INFO - mozscreenshot == 0.1 05:40:24 INFO - mozsystemmonitor == 0.3 05:40:24 INFO - moztest == 0.8 05:40:24 INFO - mozversion == 1.4 05:40:24 INFO - psutil == 3.1.1 05:40:24 INFO - requests == 1.2.3 05:40:24 INFO - wsgiref == 0.1.2 05:40:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:24 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')]} 05:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:24 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')]} 05:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:24 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 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40:24 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 05:40:24 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 05:40:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:24 INFO - 'HOME': '/Users/cltbld', 05:40:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:24 INFO - 'LOGNAME': 'cltbld', 05:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:24 INFO - 'MOZ_NO_REMOTE': '1', 05:40:24 INFO - 'NO_EM_RESTART': '1', 05:40:24 INFO - 'PAGER': '/bin/cat', 05:40:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:24 INFO - 'PWD': '/builds/slave/test', 05:40:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:24 INFO - 'SHELL': '/bin/bash', 05:40:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:24 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:24 INFO - 'USER': 'cltbld', 05:40:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:24 INFO - 'XPC_FLAGS': '0x0', 05:40:24 INFO - 'XPC_SERVICE_NAME': '0', 05:40:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 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)) 05:40:24 INFO - Cleaning up... 05:40:24 INFO - Return code: 0 05:40:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40:24 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')]} 05:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:24 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')]} 05:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:24 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 0x10d6f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9918ccadf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, '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.c0Zg48bh4g/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.nlko2PWBVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 05:40:24 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 05:40:24 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 05:40:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:24 INFO - 'HOME': '/Users/cltbld', 05:40:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:24 INFO - 'LOGNAME': 'cltbld', 05:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:24 INFO - 'MOZ_NO_REMOTE': '1', 05:40:24 INFO - 'NO_EM_RESTART': '1', 05:40:24 INFO - 'PAGER': '/bin/cat', 05:40:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:24 INFO - 'PWD': '/builds/slave/test', 05:40:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:24 INFO - 'SHELL': '/bin/bash', 05:40:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:24 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:24 INFO - 'USER': 'cltbld', 05:40:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:24 INFO - 'XPC_FLAGS': '0x0', 05:40:24 INFO - 'XPC_SERVICE_NAME': '0', 05:40:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:40:24 INFO - Cleaning up... 05:40:24 INFO - Return code: 0 05:40:24 INFO - Running post-action listener: _resource_record_post_action 05:40:24 INFO - Running post-action listener: _start_resource_monitoring 05:40:24 INFO - Starting resource monitoring. 05:40:24 INFO - [mozharness: 2017-02-06 13:40:24.739227Z] Finished create-virtualenv step (success) 05:40:24 INFO - [mozharness: 2017-02-06 13:40:24.740143Z] Running install step. 05:40:24 INFO - Running pre-action listener: _resource_record_pre_action 05:40:24 INFO - Running main action method: install 05:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:24 INFO - Reading from file tmpfile_stdout 05:40:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:40:25 INFO - Reading from file tmpfile_stdout 05:40:25 INFO - Output received: 05:40:25 INFO - Usage: mozinstall [options] installer 05:40:25 INFO - Options: 05:40:25 INFO - -h, --help show this help message and exit 05:40:25 INFO - -d DEST, --destination=DEST 05:40:25 INFO - Directory to install application into. [default: 05:40:25 INFO - "/builds/slave/test"] 05:40:25 INFO - --app=APP Application being installed. [default: firefox] 05:40:25 INFO - mkdir: /builds/slave/test/build/application 05:40:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:40:47 INFO - Reading from file tmpfile_stdout 05:40:47 INFO - Output received: 05:40:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:40:47 INFO - Running post-action listener: _resource_record_post_action 05:40:47 INFO - [mozharness: 2017-02-06 13:40:47.149145Z] Finished install step (success) 05:40:47 INFO - [mozharness: 2017-02-06 13:40:47.149307Z] Running run-tests step. 05:40:47 INFO - Running pre-action listener: _resource_record_pre_action 05:40:47 INFO - Running main action method: run_tests 05:40:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:40:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:40:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:40:47 INFO - 2017-02-06 05:40:47.162 screenresolution[1630:7680] starting screenresolution argv=screenresolution get 05:40:47 INFO - 2017-02-06 05:40:47.164 screenresolution[1630:7680] Display 0: 1600x1200x32@60 05:40:47 INFO - 2017-02-06 05:40:47.170 screenresolution[1631:7682] starting screenresolution argv=screenresolution list 05:40:47 INFO - Available Modes on Display 0 05:40:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:40:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:40:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:40:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:40:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:40:47 INFO - Intel Iris: 05:40:47 INFO - Chipset Model: Intel Iris 05:40:47 INFO - Type: GPU 05:40:47 INFO - Bus: Built-In 05:40:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:40:47 INFO - Vendor: Intel (0x8086) 05:40:47 INFO - Device ID: 0x0a2e 05:40:47 INFO - Revision ID: 0x0009 05:40:47 INFO - Displays: 05:40:47 INFO - Display: 05:40:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:40:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:40:47 INFO - Display Serial Number: 1600x1200 60 05:40:47 INFO - Main Display: Yes 05:40:47 INFO - Mirror: Off 05:40:47 INFO - Online: Yes 05:40:47 INFO - Rotation: Supported 05:40:47 INFO - Return code: 0 05:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:40:47 INFO - Python 2.7.3 05:40:47 INFO - Return code: 0 05:40:47 INFO - grabbing minidump binary from tooltool 05:40:47 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')]} 05:40:47 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 0x10d4d4d30>, '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 0x10d3fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4c9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:40:47 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 05:40:47 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 05:40:47 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 05:40:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:40:47 INFO - Return code: 0 05:40:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:40:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:40:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:40:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:40:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c0Zg48bh4g/Render', 05:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:47 INFO - 'HOME': '/Users/cltbld', 05:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:47 INFO - 'LOGNAME': 'cltbld', 05:40:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:40:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:47 INFO - 'MOZ_NO_REMOTE': '1', 05:40:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:40:47 INFO - 'NO_EM_RESTART': '1', 05:40:47 INFO - 'PAGER': '/bin/cat', 05:40:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:47 INFO - 'PWD': '/builds/slave/test', 05:40:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:47 INFO - 'SHELL': '/bin/bash', 05:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nlko2PWBVk/Listeners', 05:40:47 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:47 INFO - 'USER': 'cltbld', 05:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:47 INFO - 'XPC_FLAGS': '0x0', 05:40:47 INFO - 'XPC_SERVICE_NAME': '0', 05:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:40:47 INFO - mozversion application_buildid: 20170206035750 05:40:47 INFO - mozversion application_changeset: 1cc159c7a0445ec51e335c8a1a1cceea7bbf8380 05:40:47 INFO - mozversion application_display_name: Nightly 05:40:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:40:47 INFO - mozversion application_name: Firefox 05:40:47 INFO - mozversion application_remotingname: firefox 05:40:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:40:47 INFO - mozversion application_vendor: Mozilla 05:40:47 INFO - mozversion application_version: 54.0a1 05:40:47 INFO - mozversion platform_buildid: 20170206035750 05:40:47 INFO - mozversion platform_changeset: 1cc159c7a0445ec51e335c8a1a1cceea7bbf8380 05:40:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:40:47 INFO - mozversion platform_version: 54.0a1 05:40:47 INFO - using testdate: 1486388447 05:40:47 INFO - actual date: 1486388447 05:40:47 INFO - starting webserver on 'localhost:49245' 05:40:47 INFO - SUITE-START | Running 2 tests 05:40:47 INFO - TEST-START | dromaeo_css 05:40:47 INFO - operating with platform_type : mac_ 05:40:47 INFO - Initialising browser for dromaeo_css test... 05:40:47 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpiKCkiI/profile http://localhost:49245/getInfo.html) 05:40:49 INFO - PROCESS | 1641 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:40:50 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 05:40:50 INFO - PROCESS | 1641 | colorDepth:24 05:40:50 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 05:40:50 INFO - PROCESS | 1641 | __metrics 05:40:50 INFO - PROCESS | 1641 | 1486388450335 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:40:50 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:40:50 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:40:51 INFO - TEST-INFO | 1641: exit 0 05:40:51 INFO - Browser initialized. 05:40:51 INFO - Running cycle 1/1 for dromaeo_css test... 05:40:51 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpiKCkiI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:03 INFO - PROCESS | 1643 | RSS: Main: 237023232 05:41:03 INFO - PROCESS | 1643 | 05:44:07 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 05:44:08 INFO - PROCESS | 1643 | RSS: Main: 386125824 05:44:08 INFO - PROCESS | 1643 | 05:45:52 INFO - PROCESS | 1643 | ************************* 05:45:52 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 05:45:52 INFO - PROCESS | 1643 | 05:45:52 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:45:52 INFO - PROCESS | 1643 | Full stack: 05:45:52 INFO - PROCESS | 1643 | ************************* 05:47:11 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 05:47:12 INFO - PROCESS | 1643 | RSS: Main: 374001664 05:47:12 INFO - PROCESS | 1643 | 05:50:14 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 05:50:15 INFO - PROCESS | 1643 | RSS: Main: 348786688 05:50:15 INFO - PROCESS | 1643 | 05:53:18 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 05:53:18 INFO - PROCESS | 1643 | RSS: Main: 348643328 05:53:18 INFO - PROCESS | 1643 | 05:56:21 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 05:56:22 INFO - PROCESS | 1643 | RSS: Main: 330674176 05:56:22 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 05:59:25 INFO - PROCESS | 1643 | RSS: Main: 494288896 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | __start_tp_report 05:59:25 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 05:59:25 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 05:59:25 INFO - PROCESS | 1643 | |i|pagename|runs| 05:59:25 INFO - PROCESS | 1643 | |0;dojo.html;2978.41920825439;3318.622771549807;3323.9999999999986;3330.3505816365787;3395.3718562066006;5441.183822426638;5485.232067510552;5579.9163012554845;5665.490105890469;5693.117566777156;4535.478419981703;5587.413321601234;5623.887522249553;5626.915596266059;5659.915101273484;5051.898962020758;5056.949430505694;5125.154349532341;5146.305248791403;5167.9483205168035;5209.114450543398;5210.5310522053005;5232.92150617741;5259.815906443275;5267.578593712513;4194.224068547329;4951.554360107608;4972.651914365996;5025.221090730943;5120.769565369567;4981.651284410093;5012.949870501321;5043.84868453947;5100.566451851597;5113.51421614946;4978.502149784992;4983.750812459362;4987.202047672391;4998.600111991032;5034.144195486776;5423.2949716537;5457.808976685837;5475.342958844924;5499.175123731432;5505.834824955257;6091.634501929899;6108.554560543837;6117.431594453911;6127.678242056264;6129.846753831145;4657.86026419208;6153.876922461525;6245.281792593864;6253.906191407133;6260;5740.339860916007;5753.827385178452;5759.22250496186;5762.97118514409;5771.682557459296;6123.08153776937;6129.450483694044;6172.012478003588;6172.5679202456295;6248.4688801451475;4762.904741905142;5843.444872737083;5861.5603829712945;5957.2255606771705;6120.928837453428;6100.70885372201;6126;6129;6134.969325153345;6137.294211165773;4335.913281734348;5686.09022556389;6016.518678505797;6034;6063.484603808725;6081.7871374502565;6087.604305720114;6088.178095957078;6089.086637004396;6095.542834287447;4860.1737704590305;4864.927026094611;4866.416030076413;4889.144399730033;4932.8520144395725;4340.804663790137;4434.866953991385;4456.9331460028125;4461.330800379904;4477.350784136347;5657.660540367591;5660.858478537987;5673.489385955284;5679.233303503974;5686.800961966312;6050.848728781728;6093.146959425684;6143.542582966849;6181.134641150243;6188.1027251048345;5664.008798460253;5675.914861277084;5676.0918253080135;5685.976524225596;5701.486866182064;5393.946060539422;5397.541208997201;5399.7030163340605;5402.648827826258;5407.242985981887;6132.324915438686;6206.72069756871;6223.782167624111;6232.25212974446;6232.937670623236;6253.5622506424115;6272.059191121369;6274.46667033289;6287.874242515032;6292.339304372975;6263.937360626335;6273.027680709497;6278.434940855345;6282.717277722422;6283.37166283368;5918.3489816121055;5955.880882382415;6078.72645730952;6091.573589848668;6178.7219575120125;6081.179040829431;6157.6613286269685;6172.783952561639;6184.857178495697;6223.315435302204;6031.487323577545;6053.048969046448;6054.84862878414;6061.999770037894;6099.11562823397;5720.885582288248;5814.866101110243;5972.671503067373;6012.669303188367;6028.788992385245;5829.921464529076;5908.231929849092;5926.970365148319;5936.554758393051;5970.611910225995;6046.788362407295;6055.878882422415;6069.848253793514;6070.939290607037;6079.392060793885;4708.270218116198;5605.719714014364;5682.602217844877;5697.401772813961;5745.827625171251 05:59:25 INFO - PROCESS | 1643 | |1;ext.html;469.1508369850571;472.93378926950226;503.99748001260014;520.2092818915249;528.236697971431;24619.138330158446;35211.64788352119;39874.60125398745;39877.4018389724;42220;40885.182296354054;41526.37710434346;41640;41782.16435671285;41864.58135418653;7902.841943161134;9061.818763624724;9279.443233406017;9289.489078100702;9310.86033709495;7910.3276221520955;9005.459672419644;9728.610855565768;10927.125829933586;11255.099592032619;11160.386178760165;11232.157588180879;11237.775244495106;11249.212555121145;11257.437128143602;7194.352508274228;7609.6575654095695;11083.168762342817;11172.776544469065;11195.888041119606;7888.684452621888;10758.515866786014;11023.338599684044;11064.612738554193;11135.33188008722;32521.674783252336;39030.60969390298;39399;39488.40767388492;39506.20987580261;3614.168741189541;3742.2889650966504;3917.5309259027863;3920.5099362579676;3925.803709814498;28310.292242693893;30027.249318766986;30067.849660751555;30127.794888204666;30380.088597342114;2640.972864401568;2783.1233161554246;3210.96789032109;3214.72674822638;3239.416904957107;419466;445249;454952;463673;468757.6562117202;48703.512964870606;50264.48148629561;50463.747681262095;58151.70924145352;58396;59974.10038849421;61251.6937415319;61491.931452339006;64989.350106499274;65119.34880651133;14920.253987300592;15078.321475533627;15088;15088.54734357971;15117;11718.648440546796;11774.823377649342;11780.234284771463;11783.88216117845;11784.525249697488;15400.46098386551;15416.922915385352;15426;15511.767323490156;15639.765403518957;5683.829485115453;6161.537884658668;6616.139901812733;6725.05849181115;6810.9318906810295;797.2943944609062;836.7071524966335;842.1073661918387;851.2168804699692;852.7868032991752;24722.13472528489;26575.867120664272;28092.43815123686;28626.570601440995;28647.85676071648;1021.4535223655308;1032.808930347888;1054.283087500507;1062.2670357453333;1106.9667909962714;14834.219893612835;14842.92578537122;14850.702985940221;15118.546443606709;15167.544973650809;508071;510419;511238;512421;512798;55327.72336138294;55536.444635553125;55556;55634;55645.72177139088;1202.4108186988312;1214.3442541027746;1355.8101865738804;1408.457743768677;1409.8096756937887;27168;28209.98801120333;28462;28633.570496442986;28662.856685716437;48258.517414826805;48619.513804862916;48683.75658121687;48708;48815;406429.9357006392;409516.85797704465;409633;409815;410231;36202.63797361992;36585.817070914476;36735.265294694495;37021.889343319745;37023.81488092542;42661.36007959945;42663.5733642672;42925;43161.60479940008;44167;31556.842215789693;32570;34359;36542.269154616224;36940.26119477649;32248;34761.78333758306;34877;34932.82533587316;36223;29934.550981734857;34830.47754283637;35299.82350088336;35627.931162064146;35702.642973569935;29278.853605732686;30717.846410767805;31489.527657084705;31507;31623.525647114853;22916.770832291462;22963.65554516717;23216.4195895104;23556.0577576895;23608.055677772696 05:59:25 INFO - PROCESS | 1643 | |2;jquery.html;322.74019414371423;334.799742921626;386.246818703528;397.25316405158287;417.3218519905154;14239;14488.71022579549;15124.621884452881;16868.493945181664;19850.80895146292;20192.19231230753;20675.586488270226;21199.57600847986;21335.57328853422;21610.567788644217;5643.8024669136585;5899.321578018518;5901.8819623607715;5902.409759024084;5923.851903702399;4785.976070119645;6498.415142637162;6584.572002819801;6639.4356479699045;6661;6302.369763023707;6457.483401327882;6570.737170513174;6601;6607.471402287805;6433.581817181915;6439.291677915426;6451.6774161292215;6457.031445283245;6487.481001519891;5057.5701065409485;5277.076511610453;5341.172118321666;5398.325209348832;5649.152627105926;13311.600651980456;13367.86632133676;13388.665283367896;13512.98652601049;13532.255725935069;2456.4841383309526;2484.4534202475425;2485.42835147917;2515.2328539795353;2574.742525747429;9510.38182518134;9785.608575656965;10041.995800419973;10318.535665895053;10456;1336.9398377073055;1737.8088410274859;1760.6126652136509;1761.1458442655303;1803.720423334372;115869;127563;134678.32660836732;135167;135577;11126.777464450828;11380.544778208945;11468.4839182237;11616.186866919403;11632.767344653059;15602.29789659468;15637.609059773597;15638.374465021272;15682.843171568367;15750;8213.712520061768;8377.846780554593;8383.790405239917;8417.074121846592;8515.616797244138;8035.879461808077;8112.22933821286;8166.265036146775;8294.626741796634;8311.376646751396;4671.205894997858;4734.218853889139;4756.429228492534;4779.283107533898;4813.78337974785;1699.990052720576;1967.4491142480128;2012.2554654777534;2014.909329080195;2017.7175195472646;988.5946761827616;988.8417853143607;995.8307087795092;1011.6459239266198;1030.6650338639822;20773.27293544719;21185.834779087298;21223.469413264793;21387.991867001718;21494.355169344944;997.4962643864802;1021.2238698589126;1046.7958748044211;1055.8416237564427;1062.6121465664976;10207.19651247461;10451.425171615634;11000.284981476338;11037.006669399792;11105.666829995112;661626;672781;688769.556152216;703846;715101;121411;140400;142417.57582424043;151032.2448387773;160350;906.428949761646;1020.9183265338755;1041.926566133389;1080.713610893114;1084.9566017359375;19151.138198781366;19444.513887152934;19613.803861961198;19671.508212294808;19679.80320196837;56501;59515;59893;60065;60566;83786;85823.41765823368;86843.56578217068;87477;87831.56084219538;7515.361194298546;8352.916470835456;8537.274331681629;8575.742727718178;8585.399022068395;12094.818577721517;12124.211926224767;12659.98720102408;13366.532171373956;13578;8402.41183117176;8922.73231803047;9429.387089839138;11472.94263528677;11652.242604230698;11174;11225.326480411202;11301.773964520828;11593.82609260845;11652.242604230698;10853.891461085605;11350.432478376213;11426.11469107588;11471;11868.584599538975;6522.34776522344;8317.41778075529;8494;8558.529280889361;8582.055973842997;9827.705168844946;10109;10599;11568.652940411801;11759.353235572125 05:59:25 INFO - PROCESS | 1643 | |3;mootools.html;1268.6764874956884;1398.6922876967064;1421.1686163594297;1430.921299328537;1437.9065360751547;7127.607981561012;7485.214052524489;7855.882161767571;8208;8469.237768600824;7311.866660667605;8377.371697122724;8453.239208471236;8483;8490.660373585064;1929.8649094563386;2207.9668804967937;2253.9774602254015;2256.1088370093803;2265.060000099965;2016.1532156494306;2045.5704302096503;2226.521297920947;2283.372072680018;2286.6684330772027;2239.193890199527;2242.192810588187;2245.550889822034;2251.020805949409;2253.1550668499312;2196.3850121965875;2225.4881377283236;2230.810381117607;2233.687283780272;2236.4856083100817;2216.944576385587;2218;2278.6923765291804;2282.4522114692522;2285.919992800261;7173.923911413278;8499.872501912476;8528.147185281485;8536.189062039097;8557.400981931267;5032.144535428949;5201.063808514466;5207.114790485626;5208.166693329087;5209.921851172236;3276.2955964467756;3457.870627082469;3497.1606814364623;3538.0800991742076;3553.3603951288756;1163.5171403867384;1187.7505723798013;1223.9143259971806;1253.1729058821224;1254.5295514182183;11054.944725276402;11063.004329610374;11080.889191108066;11100.944495277552;11143.665690029313;4237.406763053175;4594.2419500782;4643.582077613031;4696.365990591226;4698.553637404441;9662.17871480932;9692.46691431978;9704.611815527442;9709.6116155353;9751.561179746786;5239.240310155006;5246.527812496894;5248.763805628756;5250.028744682246;5257.737113144328;4601.861944141681;4694.647901407408;4701.318308845198;4726.133177394979;4809.831655892027;3364.62989071202;3444.9311013779584;3464.3244567309134;3593.568771747407;3599.1182160370354;1237.2761934268553;1241.4413513918773;1245.7383949370733;1257.5787111317627;1258.0690289185936;763.3816608546983;831.243568352796;885.1679421343916;894.2979760887672;936.241644268145;4138.6482149017675;4176;5635.436456354331;5716.256886604715;5957;965.75856035991;983.8376667849725;993.215359865698;1022.6522982185944;1024.99487502562;3765.736398452082;3788.753731007476;3798.9810050950123;3905.0432644001835;3923.0780766519847;9921.206303495703;10230.335028223142;10233.1301839343;10237.283390162765;10249.641262555775;10183.338083024728;10195.133413659774;10206.540705668263;10227.02843229892;10248.897511024943;7628.580428076509;7849.686012559434;8020.11778704342;8023;8031.477953932976;5585.469380408855;5613.494785469327;5706.914396284142;5709.885802284014;5763.308402991668;8969.865452018095;9421.152096311365;9425.151736343762;9425.38734982224;9434.716958491255;8892.28861691076;9276.350655454053;9344.15902568772;9546.713598592054;9547.904520954702;6660.300668429939;7290.854182916206;7293.088363954505;7295.452841037049;7301.269873012656;7068.434525237866;7314.158871729819;7329;7570.16728159913;7865.567393793167;6942.2363540011565;6978.720851166099;7104.751333703295;7118.466115041289;7235.565866048054;5479.698616576126;6880.071190389233;7090.042844215965;7169.605671687898;7171.282871712787;6096.390360963868;6276.717547710273;6278.748850045947;6286.497080233479;6289.182406287154;4912.508749125059;4913.901721965469;5007.84976450707;5054.494550544916;5193.350831146106;5051.848444546669;5079.815679257955;5094.133997220555;5108.901586158993;5224.608154388512 05:59:25 INFO - PROCESS | 1643 | |4;prototype.html;308.3647685767319;351.5473484197554;354.19950910941276;367.5772861209609;367.58095770821285;5078.314427552284;5128.948710512894;5137.845864624058;5156.149235376168;5204.724842413604;4837.5646191842725;4983.302337672729;5044.697318160904;5069.594432445405;5131.666441681288;3905.3556163233034;3927.371620540714;3939.8424063037587;3949.6247856453733;3951.1505026419313;3298.802071875683;3308.503724441329;3945.8618948336816;3962.881113566598;3970.583088775666;3676.786216492257;3841.9423708644254;3908.511436070491;3918.490596222487;3921.607839216084;3849.538055433338;3865.76805391676;3877.6763908242388;3887.630675085862;3917.7061720370943;3362.3443428531446;3374.2913988062537;3376.0547046826928;3376.544166537535;3386;4176.310908700061;4958;4962.883351245977;4984.252362145671;4987.775550100254;3715.5169827922196;4176.686748493845;4393.231184542692;4429.933550996738;4443.911121777579;4694.967107236436;5069.771860266297;5266.131088370415;5382.91925621116;5464.535514481234;3335.499675048762;3607.783532988029;3773.5471843340238;3781.7920014399197;3799.905002374935;4670.9766451167525;5223.451537588537;5482;5624.803131890405;5707.204708868598;3735.0577699596056;3746.4380342948775;3747.2270519815143;3755.6244375562223;3756.492873462102;3358.7145092667397;3709.4435834625024;3783.3000894834545;3803.980980095082;3816.1795214029116;3880.1269714314053;4224.155168966219;4378.8905277367685;4448.199324121689;4462.866114016585;5122.564582010496;5425.6202065855805;5432.8268692522515;5445.646033007842;5450.1007333789485;1153.419814853639;1187.7208855918852;1189.851268591426;1218.6770505815966;1241.938382285402;685.7942617214816;754.9698012079565;801.5150833745616;842.9747107586783;852.2968550945495;4254.595813397722;4321.006168581181;4331;4734.490719918449;4741.668083234141;4586.334981427674;5095.636879659481;5095.655998240776;5212.68723876561;5300.92048619271;4166.458410388115;4344.6089851913475;4753.001869607421;4753.35829662998;4794.352762377104;2479.9752002480104;2666.0668765932155;2756.517609418344;2759.4481103779326;2772.306923269183;5139.353233084964;5461.563074953994;5473.75368108436;5508.980838544882;5548.72256387179;4709.364235828188;4848.690853469543;5287.444818294024;5404.945950540523;5438.619296649196;5493.846292278587;5587.413321601173;5671.51792097668;5714.257146570919;5726.169705392778;4748.525147485224;4879.658423910292;5465;5490.313710786152;5531.695756733257;4486.910261794811;5083.364579427572;5409.945900540944;5420.349558053059;5480.534154596904;4964.131277026579;5062.418511247327;5079.298435024309;5234.607404444758;5638.21065050885;3241.0438920518977;3712.886134159687;3739.9252014959006;3749.1564398010228;3773;4698.154332220233;4913.77887995034;5308.098547567458;5557.861053473695;5586.541921554665;4655.138799322136;5201.661891977009;5362.812301569426;5427.701476418835;5429.592780541554;4742.506110575157;5235.869103272448;5385.518982279747;5404.675719456688;5425;4443.33349997503;4958.454569997225;5257.973710131578;5289.259503669567;5294.49702278283;4969.503049695001;5343.171808369709;5364.517193452608;5371.811986580451;5375.247465354932;4790.904181916272;4957.355543779286;5223.425423203368;5279.049771041249;5280.392754833089 05:59:25 INFO - PROCESS | 1643 | |5;yui.html;652.5497406789315;713.1442269276869;714.5891112610251;814.5357146426537;848.7241646464901;14330.785038224434;14393.712125757478;14768.040077394962;14780.334884930176;14850.702985940276;11636.418179091053;13546.864531354684;13674.658133546667;13700.794488082687;13765.105268157562;808.2806302390877;894.356063634184;904.2088172848757;906.4697152165958;909.9863502047475;885.2077390735288;889.7908991387019;893.8167675626513;894.0567701075363;894.4901406198469;942.185009966381;942.2226663003015;943.7168849345169;944.631593678466;946.8295706772778;929.5909799688116;933.3466573398648;935.2050756856652;935.9906400936039;938.4088024544561;694.1739330197049;695.2144077192739;696.770065878259;699.1435491522894;699.9160100787888;14961.700765984728;14972.401103955828;15017.774733379008;15152.090874547563;15160.469383571522;13122.86877131226;13366.331683415887;13427.932860335635;13457.327133643279;13509.05436619437;11472.426378681115;11783.458467405573;11833.704157396049;12088.03295736339;12356.38218089092;3604.7656902301264;3663.427002954049;3683.797391143486;3755.962440375589;3774.0942173878348;99600;100977;101217.99999999926;102333.48833255861;102695.48652256766;10478.895211047944;10575.259731818698;10679.466026698634;10835.620753273597;11066.889331106748;21841.235556755437;21857.016434260495;22379.552408952055;22527.774722252896;22600.75695836745;6987.615681137485;7358.337749602561;7484.47608667399;7548.698052077855;7697.384209263246;4112.074783173822;4113.773742444135;4138.482689663792;4188.602082802129;4194.224068547329;3580.2481478889204;3611.6930060945115;3685.631436856293;3687.3731465650903;3705.1478160023344;658.4173006888938;667.6494840208929;670.9932900670931;671.2549070531705;689.327905292346;636.4971672378862;664.9434798042221;696.474162007681;703.5321511194998;721.2679130682361;9208.26333893287;9777.755556111011;9920.503974801231;10129.392236465836;10262;598.3717097048082;646.8900286951135;682.1098466501263;685.4971097690797;694.1704662927752;4537;4813.663043586916;4814.855554333376;4827.275908613666;4837.298591704183;74567;76443.61778191074;90757.54621226984;90993.54503227441;93053;47460.7626961863;56560.71719641375;71126;72104;73639.3724564781;68944.6552767243;72734.63632681803;73195;73365;74853.62573187207;10277.126444252022;10647.467626618483;10691.144708423462;10878.510467028844;10927;36305;43892;44778.55221447874;47701;48773;58281;59957;60003;66191.33808661852;67733;12758.68103297425;13029.609111726664;13131.803022954473;13486.393112309774;13643.522476713268;14546.854531454974;14648.9745717803;14651.560453186421;14661;15423.91521695643;13252;13601.931990339985;13604.727905442034;13619;13753.656158596115;13567;13621.727565448833;13689.726205475636;13736.862631373559;14373.64065898361;10401.58393664245;11686.357250351311;11866.228695134789;12094.576689815814;13670.931645342107;8605.271688985113;10554.472276386303;10854.620088296871;11142.331460112418;11298.943505282421;10061.899381006097;10710.620894782502;10804.729881753019;10857.891421085687;10884.020438160604 05:59:25 INFO - PROCESS | 1643 | __end_tp_report 05:59:25 INFO - PROCESS | 1643 | __start_cc_report 05:59:25 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1517 05:59:25 INFO - PROCESS | 1643 | __end_cc_report 05:59:25 INFO - PROCESS | 1643 | __startTimestamp1486389565932__endTimestamp 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | ------- Summary: start ------- 05:59:25 INFO - PROCESS | 1643 | Number of tests: 6 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5608.41 Median:5759.22 stddev:666.25 (11.6%) stddev-sans-first:635.55 05:59:25 INFO - PROCESS | 1643 | Values: 2978.4 3318.6 3324.0 3330.4 3395.4 5441.2 5485.2 5579.9 5665.5 5693.1 4535.5 5587.4 5623.9 5626.9 5659.9 5051.9 5056.9 5125.2 5146.3 5167.9 5209.1 5210.5 5232.9 5259.8 5267.6 4194.2 4951.6 4972.7 5025.2 5120.8 4981.7 5012.9 5043.8 5100.6 5113.5 4978.5 4983.8 4987.2 4998.6 5034.1 5423.3 5457.8 5475.3 5499.2 5505.8 6091.6 6108.6 6117.4 6127.7 6129.8 4657.9 6153.9 6245.3 6253.9 6260.0 5740.3 5753.8 5759.2 5763.0 5771.7 6123.1 6129.5 6172.0 6172.6 6248.5 4762.9 5843.4 5861.6 5957.2 6120.9 6100.7 6126.0 6129.0 6135.0 6137.3 4335.9 5686.1 6016.5 6034.0 6063.5 6081.8 6087.6 6088.2 6089.1 6095.5 4860.2 4864.9 4866.4 4889.1 4932.9 4340.8 4434.9 4456.9 4461.3 4477.4 5657.7 5660.9 5673.5 5679.2 5686.8 6050.8 6093.1 6143.5 6181.1 6188.1 5664.0 5675.9 5676.1 5686.0 5701.5 5393.9 5397.5 5399.7 5402.6 5407.2 6132.3 6206.7 6223.8 6232.3 6232.9 6253.6 6272.1 6274.5 6287.9 6292.3 6263.9 6273.0 6278.4 6282.7 6283.4 5918.3 5955.9 6078.7 6091.6 6178.7 6081.2 6157.7 6172.8 6184.9 6223.3 6031.5 6053.0 6054.8 6062.0 6099.1 5720.9 5814.9 5972.7 6012.7 6028.8 5829.9 5908.2 5927.0 5936.6 5970.6 6046.8 6055.9 6069.8 6070.9 6079.4 4708.3 5605.7 5682.6 5697.4 5745.8 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:59810.46 Median:36212.82 stddev:121875.74 (336.6%) stddev-sans-first:122136.20 05:59:25 INFO - PROCESS | 1643 | Values: 469.2 472.9 504.0 520.2 528.2 24619.1 35211.6 39874.6 39877.4 42220.0 40885.2 41526.4 41640.0 41782.2 41864.6 7902.8 9061.8 9279.4 9289.5 9310.9 7910.3 9005.5 9728.6 10927.1 11255.1 11160.4 11232.2 11237.8 11249.2 11257.4 7194.4 7609.7 11083.2 11172.8 11195.9 7888.7 10758.5 11023.3 11064.6 11135.3 32521.7 39030.6 39399.0 39488.4 39506.2 3614.2 3742.3 3917.5 3920.5 3925.8 28310.3 30027.2 30067.8 30127.8 30380.1 2641.0 2783.1 3211.0 3214.7 3239.4 419466.0 445249.0 454952.0 463673.0 468757.7 48703.5 50264.5 50463.7 58151.7 58396.0 59974.1 61251.7 61491.9 64989.4 65119.3 14920.3 15078.3 15088.0 15088.5 15117.0 11718.6 11774.8 11780.2 11783.9 11784.5 15400.5 15416.9 15426.0 15511.8 15639.8 5683.8 6161.5 6616.1 6725.1 6810.9 797.3 836.7 842.1 851.2 852.8 24722.1 26575.9 28092.4 28626.6 28647.9 1021.5 1032.8 1054.3 1062.3 1107.0 14834.2 14842.9 14850.7 15118.5 15167.5 508071.0 510419.0 511238.0 512421.0 512798.0 55327.7 55536.4 55556.0 55634.0 55645.7 1202.4 1214.3 1355.8 1408.5 1409.8 27168.0 28210.0 28462.0 28633.6 28662.9 48258.5 48619.5 48683.8 48708.0 48815.0 406429.9 409516.9 409633.0 409815.0 410231.0 36202.6 36585.8 36735.3 37021.9 37023.8 42661.4 42663.6 42925.0 43161.6 44167.0 31556.8 32570.0 34359.0 36542.3 36940.3 32248.0 34761.8 34877.0 34932.8 36223.0 29934.6 34830.5 35299.8 35627.9 35702.6 29278.9 30717.8 31489.5 31507.0 31623.5 22916.8 22963.7 23216.4 23556.1 23608.1 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:38336.48 Median:21361.78 stddev:114990.62 (538.3%) stddev-sans-first:115277.77 05:59:25 INFO - PROCESS | 1643 | Values: 322.7 334.8 386.2 397.3 417.3 14239.0 14488.7 15124.6 16868.5 19850.8 20192.2 20675.6 21199.6 21335.6 21610.6 5643.8 5899.3 5901.9 5902.4 5923.9 4786.0 6498.4 6584.6 6639.4 6661.0 6302.4 6457.5 6570.7 6601.0 6607.5 6433.6 6439.3 6451.7 6457.0 6487.5 5057.6 5277.1 5341.2 5398.3 5649.2 13311.6 13367.9 13388.7 13513.0 13532.3 2456.5 2484.5 2485.4 2515.2 2574.7 9510.4 9785.6 10042.0 10318.5 10456.0 1336.9 1737.8 1760.6 1761.1 1803.7 115869.0 127563.0 134678.3 135167.0 135577.0 11126.8 11380.5 11468.5 11616.2 11632.8 15602.3 15637.6 15638.4 15682.8 15750.0 8213.7 8377.8 8383.8 8417.1 8515.6 8035.9 8112.2 8166.3 8294.6 8311.4 4671.2 4734.2 4756.4 4779.3 4813.8 1700.0 1967.4 2012.3 2014.9 2017.7 988.6 988.8 995.8 1011.6 1030.7 20773.3 21185.8 21223.5 21388.0 21494.4 997.5 1021.2 1046.8 1055.8 1062.6 10207.2 10451.4 11000.3 11037.0 11105.7 661626.0 672781.0 688769.6 703846.0 715101.0 121411.0 140400.0 142417.6 151032.2 160350.0 906.4 1020.9 1041.9 1080.7 1085.0 19151.1 19444.5 19613.8 19671.5 19679.8 56501.0 59515.0 59893.0 60065.0 60566.0 83786.0 85823.4 86843.6 87477.0 87831.6 7515.4 8352.9 8537.3 8575.7 8585.4 12094.8 12124.2 12660.0 13366.5 13578.0 8402.4 8922.7 9429.4 11472.9 11652.2 11174.0 11225.3 11301.8 11593.8 11652.2 10853.9 11350.4 11426.1 11471.0 11868.6 6522.3 8317.4 8494.0 8558.5 8582.1 9827.7 10109.0 10599.0 11568.7 11759.4 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5415.47 Median:5101.52 stddev:3007.29 (58.9%) stddev-sans-first:2999.57 05:59:25 INFO - PROCESS | 1643 | Values: 1268.7 1398.7 1421.2 1430.9 1437.9 7127.6 7485.2 7855.9 8208.0 8469.2 7311.9 8377.4 8453.2 8483.0 8490.7 1929.9 2208.0 2254.0 2256.1 2265.1 2016.2 2045.6 2226.5 2283.4 2286.7 2239.2 2242.2 2245.6 2251.0 2253.2 2196.4 2225.5 2230.8 2233.7 2236.5 2216.9 2218.0 2278.7 2282.5 2285.9 7173.9 8499.9 8528.1 8536.2 8557.4 5032.1 5201.1 5207.1 5208.2 5209.9 3276.3 3457.9 3497.2 3538.1 3553.4 1163.5 1187.8 1223.9 1253.2 1254.5 11054.9 11063.0 11080.9 11100.9 11143.7 4237.4 4594.2 4643.6 4696.4 4698.6 9662.2 9692.5 9704.6 9709.6 9751.6 5239.2 5246.5 5248.8 5250.0 5257.7 4601.9 4694.6 4701.3 4726.1 4809.8 3364.6 3444.9 3464.3 3593.6 3599.1 1237.3 1241.4 1245.7 1257.6 1258.1 763.4 831.2 885.2 894.3 936.2 4138.6 4176.0 5635.4 5716.3 5957.0 965.8 983.8 993.2 1022.7 1025.0 3765.7 3788.8 3799.0 3905.0 3923.1 9921.2 10230.3 10233.1 10237.3 10249.6 10183.3 10195.1 10206.5 10227.0 10248.9 7628.6 7849.7 8020.1 8023.0 8031.5 5585.5 5613.5 5706.9 5709.9 5763.3 8969.9 9421.2 9425.2 9425.4 9434.7 8892.3 9276.4 9344.2 9546.7 9547.9 6660.3 7290.9 7293.1 7295.5 7301.3 7068.4 7314.2 7329.0 7570.2 7865.6 6942.2 6978.7 7104.8 7118.5 7235.6 5479.7 6880.1 7090.0 7169.6 7171.3 6096.4 6276.7 6278.7 6286.5 6289.2 4912.5 4913.9 5007.8 5054.5 5193.4 5051.8 5079.8 5094.1 5108.9 5224.6 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4302.27 Median:4792.63 stddev:1307.90 (27.3%) stddev-sans-first:1276.75 05:59:25 INFO - PROCESS | 1643 | Values: 308.4 351.5 354.2 367.6 367.6 5078.3 5128.9 5137.8 5156.1 5204.7 4837.6 4983.3 5044.7 5069.6 5131.7 3905.4 3927.4 3939.8 3949.6 3951.2 3298.8 3308.5 3945.9 3962.9 3970.6 3676.8 3841.9 3908.5 3918.5 3921.6 3849.5 3865.8 3877.7 3887.6 3917.7 3362.3 3374.3 3376.1 3376.5 3386.0 4176.3 4958.0 4962.9 4984.3 4987.8 3715.5 4176.7 4393.2 4429.9 4443.9 4695.0 5069.8 5266.1 5382.9 5464.5 3335.5 3607.8 3773.5 3781.8 3799.9 4671.0 5223.5 5482.0 5624.8 5707.2 3735.1 3746.4 3747.2 3755.6 3756.5 3358.7 3709.4 3783.3 3804.0 3816.2 3880.1 4224.2 4378.9 4448.2 4462.9 5122.6 5425.6 5432.8 5445.6 5450.1 1153.4 1187.7 1189.9 1218.7 1241.9 685.8 755.0 801.5 843.0 852.3 4254.6 4321.0 4331.0 4734.5 4741.7 4586.3 5095.6 5095.7 5212.7 5300.9 4166.5 4344.6 4753.0 4753.4 4794.4 2480.0 2666.1 2756.5 2759.4 2772.3 5139.4 5461.6 5473.8 5509.0 5548.7 4709.4 4848.7 5287.4 5404.9 5438.6 5493.8 5587.4 5671.5 5714.3 5726.2 4748.5 4879.7 5465.0 5490.3 5531.7 4486.9 5083.4 5409.9 5420.3 5480.5 4964.1 5062.4 5079.3 5234.6 5638.2 3241.0 3712.9 3739.9 3749.2 3773.0 4698.2 4913.8 5308.1 5557.9 5586.5 4655.1 5201.7 5362.8 5427.7 5429.6 4742.5 5235.9 5385.5 5404.7 5425.0 4443.3 4958.5 5258.0 5289.3 5294.5 4969.5 5343.2 5364.5 5371.8 5375.2 4790.9 4957.4 5223.4 5279.0 5280.4 05:59:25 INFO - PROCESS | 1643 | 05:59:25 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:18640.11 Median:3943.08 stddev:25622.95 (649.8%) stddev-sans-first:25659.23 05:59:25 INFO - PROCESS | 1643 | Values: 652.5 713.1 714.6 814.5 848.7 14330.8 14393.7 14768.0 14780.3 14850.7 11636.4 13546.9 13674.7 13700.8 13765.1 808.3 894.4 904.2 906.5 910.0 885.2 889.8 893.8 894.1 894.5 942.2 942.2 943.7 944.6 946.8 929.6 933.3 935.2 936.0 938.4 694.2 695.2 696.8 699.1 699.9 14961.7 14972.4 15017.8 15152.1 15160.5 13122.9 13366.3 13427.9 13457.3 13509.1 11472.4 11783.5 11833.7 12088.0 12356.4 3604.8 3663.4 3683.8 3756.0 3774.1 99600.0 100977.0 101218.0 102333.5 102695.5 10478.9 10575.3 10679.5 10835.6 11066.9 21841.2 21857.0 22379.6 22527.8 22600.8 6987.6 7358.3 7484.5 7548.7 7697.4 4112.1 4113.8 4138.5 4188.6 4194.2 3580.2 3611.7 3685.6 3687.4 3705.1 658.4 667.6 671.0 671.3 689.3 636.5 664.9 696.5 703.5 721.3 9208.3 9777.8 9920.5 10129.4 10262.0 598.4 646.9 682.1 685.5 694.2 4537.0 4813.7 4814.9 4827.3 4837.3 74567.0 76443.6 90757.5 90993.5 93053.0 47460.8 56560.7 71126.0 72104.0 73639.4 68944.7 72734.6 73195.0 73365.0 74853.6 10277.1 10647.5 10691.1 10878.5 10927.0 36305.0 43892.0 44778.6 47701.0 48773.0 58281.0 59957.0 60003.0 66191.3 67733.0 12758.7 13029.6 13131.8 13486.4 13643.5 14546.9 14649.0 14651.6 14661.0 15423.9 13252.0 13601.9 13604.7 13619.0 13753.7 13567.0 13621.7 13689.7 13736.9 14373.6 10401.6 11686.4 11866.2 12094.6 13670.9 8605.3 10554.5 10854.6 11142.3 11298.9 10061.9 10710.6 10804.7 10857.9 10884.0 05:59:25 INFO - PROCESS | 1643 | -------- Summary: end -------- 05:59:25 INFO - PROCESS | 1643 | 05:59:26 INFO - TEST-INFO | 1643: exit 0 05:59:26 INFO - TEST-OK | dromaeo_css | took 1118826ms 05:59:26 INFO - TEST-START | kraken 05:59:26 INFO - operating with platform_type : mac_ 05:59:26 INFO - Initialising browser for kraken test... 05:59:26 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpOWdO6H/profile http://localhost:49245/getInfo.html) 05:59:28 INFO - PROCESS | 1650 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:59:29 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:59:29 INFO - PROCESS | 1650 | colorDepth:24 05:59:29 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:59:29 INFO - PROCESS | 1650 | __metrics 05:59:29 INFO - PROCESS | 1650 | 1486389569103 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:59:29 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:59:29 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:59:30 INFO - TEST-INFO | 1650: exit 0 05:59:30 INFO - Browser initialized. 05:59:30 INFO - Running cycle 1/1 for kraken test... 05:59:30 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpOWdO6H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:42 INFO - PROCESS | 1651 | RSS: Main: 236433408 05:59:42 INFO - PROCESS | 1651 | 05:59:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 05:59:45 INFO - PROCESS | 1651 | RSS: Main: 288256000 05:59:45 INFO - PROCESS | 1651 | 05:59:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 05:59:49 INFO - PROCESS | 1651 | RSS: Main: 290177024 05:59:49 INFO - PROCESS | 1651 | 05:59:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 05:59:53 INFO - PROCESS | 1651 | RSS: Main: 381595648 05:59:53 INFO - PROCESS | 1651 | 05:59:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 05:59:55 INFO - PROCESS | 1651 | RSS: Main: 285421568 05:59:55 INFO - PROCESS | 1651 | 05:59:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:59:58 INFO - PROCESS | 1651 | RSS: Main: 288759808 05:59:58 INFO - PROCESS | 1651 | 06:00:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:01 INFO - PROCESS | 1651 | RSS: Main: 331046912 06:00:01 INFO - PROCESS | 1651 | 06:00:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:05 INFO - PROCESS | 1651 | RSS: Main: 333131776 06:00:05 INFO - PROCESS | 1651 | 06:00:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 06:00:08 INFO - PROCESS | 1651 | RSS: Main: 329134080 06:00:08 INFO - PROCESS | 1651 | 06:00:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:00:11 INFO - PROCESS | 1651 | RSS: Main: 306155520 06:00:11 INFO - PROCESS | 1651 | 06:00:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:00:13 INFO - PROCESS | 1651 | RSS: Main: 311152640 06:00:13 INFO - PROCESS | 1651 | 06:00:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:00:16 INFO - PROCESS | 1651 | RSS: Main: 312221696 06:00:16 INFO - PROCESS | 1651 | 06:00:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:00:20 INFO - PROCESS | 1651 | RSS: Main: 328990720 06:00:20 INFO - PROCESS | 1651 | 06:00:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:00:23 INFO - PROCESS | 1651 | RSS: Main: 323309568 06:00:23 INFO - PROCESS | 1651 | 06:00:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 06:00:25 INFO - PROCESS | 1651 | RSS: Main: 368205824 06:00:25 INFO - PROCESS | 1651 | 06:00:25 INFO - PROCESS | 1651 | __start_tp_report 06:00:25 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 06:00:25 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 06:00:25 INFO - PROCESS | 1651 | |i|pagename|runs| 06:00:25 INFO - PROCESS | 1651 | |0;ai-astar;114;114;115;117;114;114;115;114;113;114 06:00:25 INFO - PROCESS | 1651 | |1;audio-beat-detection;145;113;110;143;111;112;132;139;133;110 06:00:25 INFO - PROCESS | 1651 | |2;audio-dft;187;185;186;185;185;180;181;184;184;183 06:00:25 INFO - PROCESS | 1651 | |3;audio-fft;80;59;60;90;59;58;59;58;75;57 06:00:25 INFO - PROCESS | 1651 | |4;audio-oscillator;88;70;86;70;69;69;85;69;69;83 06:00:25 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;89;84;135;85;87;84;89;85;88;85 06:00:25 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;96;96;96;96;95;115;160;95;94 06:00:25 INFO - PROCESS | 1651 | |7;imaging-desaturate;85;84;87;85;91;87;86;84;82;84 06:00:25 INFO - PROCESS | 1651 | |8;json-parse-financial;59;58;57;58;58;59;60;58;58;58 06:00:25 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;67;78;75;72;71;69;69;68;68;70 06:00:25 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;87;90;87;86;87;89;87;87;86;90 06:00:25 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;123;129;156;145;149;151;148;139;204;175 06:00:25 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;170;172;174;180;174;171;171;171;172;176 06:00:25 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;67;66;66;65 06:00:26 INFO - PROCESS | 1651 | __end_tp_report 06:00:26 INFO - PROCESS | 1651 | __start_cc_report 06:00:26 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2196 06:00:26 INFO - PROCESS | 1651 | __end_cc_report 06:00:26 INFO - PROCESS | 1651 | __startTimestamp1486389625992__endTimestamp 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | ------- Summary: start ------- 06:00:26 INFO - PROCESS | 1651 | Number of tests: 14 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.00 stddev:1.07 (0.9%) stddev-sans-first:1.13 06:00:26 INFO - PROCESS | 1651 | Values: 114.0 114.0 115.0 117.0 114.0 114.0 115.0 114.0 113.0 114.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:132.50 stddev:14.88 (11.2%) stddev-sans-first:13.87 06:00:26 INFO - PROCESS | 1651 | Values: 145.0 113.0 110.0 143.0 111.0 112.0 132.0 139.0 133.0 110.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.16 (1.2%) stddev-sans-first:2.00 06:00:26 INFO - PROCESS | 1651 | Values: 187.0 185.0 186.0 185.0 185.0 180.0 181.0 184.0 184.0 183.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:65.50 Median:59.50 stddev:11.75 (19.7%) stddev-sans-first:11.23 06:00:26 INFO - PROCESS | 1651 | Values: 80.0 59.0 60.0 90.0 59.0 58.0 59.0 58.0 75.0 57.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.50 stddev:8.44 (11.0%) stddev-sans-first:7.72 06:00:26 INFO - PROCESS | 1651 | Values: 88.0 70.0 86.0 70.0 69.0 69.0 85.0 69.0 69.0 83.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:86.00 stddev:15.55 (18.1%) stddev-sans-first:16.47 06:00:26 INFO - PROCESS | 1651 | Values: 89.0 84.0 135.0 85.0 87.0 84.0 89.0 85.0 88.0 85.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:96.00 stddev:20.65 (21.5%) stddev-sans-first:21.71 06:00:26 INFO - PROCESS | 1651 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 115.0 160.0 95.0 94.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:85.50 stddev:2.46 (2.9%) stddev-sans-first:2.60 06:00:26 INFO - PROCESS | 1651 | Values: 85.0 84.0 87.0 85.0 91.0 87.0 86.0 84.0 82.0 84.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.00 stddev:0.82 (1.4%) stddev-sans-first:0.83 06:00:26 INFO - PROCESS | 1651 | Values: 59.0 58.0 57.0 58.0 58.0 59.0 60.0 58.0 58.0 58.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:3.47 (4.9%) stddev-sans-first:3.41 06:00:26 INFO - PROCESS | 1651 | Values: 67.0 78.0 75.0 72.0 71.0 69.0 69.0 68.0 68.0 70.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:87.00 stddev:1.51 (1.7%) stddev-sans-first:1.58 06:00:26 INFO - PROCESS | 1651 | Values: 87.0 90.0 87.0 86.0 87.0 89.0 87.0 87.0 86.0 90.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:150.00 stddev:23.24 (15.5%) stddev-sans-first:22.18 06:00:26 INFO - PROCESS | 1651 | Values: 123.0 129.0 156.0 145.0 149.0 151.0 148.0 139.0 204.0 175.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:173.00 stddev:3.03 (1.8%) stddev-sans-first:3.00 06:00:26 INFO - PROCESS | 1651 | Values: 170.0 172.0 174.0 180.0 174.0 171.0 171.0 171.0 172.0 176.0 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 06:00:26 INFO - PROCESS | 1651 | Values: 67.0 67.0 68.0 67.0 67.0 67.0 67.0 66.0 66.0 65.0 06:00:26 INFO - PROCESS | 1651 | -------- Summary: end -------- 06:00:26 INFO - PROCESS | 1651 | 06:00:26 INFO - TEST-INFO | 1651: exit 0 06:00:26 INFO - TEST-OK | kraken | took 60374ms 06:00:26 INFO - SUITE-END | took 1179s 06:00:27 INFO - Completed test suite (00:19:39) 06:00:27 INFO - javascript benchmark 06:00:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7087.042513317417, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2978.41920825439, 3318.622771549807, 3323.9999999999986, 3330.3505816365787, 3395.3718562066006, 5441.183822426638, 5485.232067510552, 5579.9163012554845, 5665.490105890469, 5693.117566777156, 4535.478419981703, 5587.413321601234, 5623.887522249553, 5626.915596266059, 5659.915101273484, 5051.898962020758, 5056.949430505694, 5125.154349532341, 5146.305248791403, 5167.9483205168035, 5209.114450543398, 5210.5310522053005, 5232.92150617741, 5259.815906443275, 5267.578593712513, 4194.224068547329, 4951.554360107608, 4972.651914365996, 5025.221090730943, 5120.769565369567, 4981.651284410093, 5012.949870501321, 5043.84868453947, 5100.566451851597, 5113.51421614946, 4978.502149784992, 4983.750812459362, 4987.202047672391, 4998.600111991032, 5034.144195486776, 5423.2949716537, 5457.808976685837, 5475.342958844924, 5499.175123731432, 5505.834824955257, 6091.634501929899, 6108.554560543837, 6117.431594453911, 6127.678242056264, 6129.846753831145, 4657.86026419208, 6153.876922461525, 6245.281792593864, 6253.906191407133, 6260.0, 5740.339860916007, 5753.827385178452, 5759.22250496186, 5762.97118514409, 5771.682557459296, 6123.08153776937, 6129.450483694044, 6172.012478003588, 6172.5679202456295, 6248.4688801451475, 4762.904741905142, 5843.444872737083, 5861.5603829712945, 5957.2255606771705, 6120.928837453428, 6100.70885372201, 6126.0, 6129.0, 6134.969325153345, 6137.294211165773, 4335.913281734348, 5686.09022556389, 6016.518678505797, 6034.0, 6063.484603808725, 6081.7871374502565, 6087.604305720114, 6088.178095957078, 6089.086637004396, 6095.542834287447, 4860.1737704590305, 4864.927026094611, 4866.416030076413, 4889.144399730033, 4932.8520144395725, 4340.804663790137, 4434.866953991385, 4456.9331460028125, 4461.330800379904, 4477.350784136347, 5657.660540367591, 5660.858478537987, 5673.489385955284, 5679.233303503974, 5686.800961966312, 6050.848728781728, 6093.146959425684, 6143.542582966849, 6181.134641150243, 6188.1027251048345, 5664.008798460253, 5675.914861277084, 5676.0918253080135, 5685.976524225596, 5701.486866182064, 5393.946060539422, 5397.541208997201, 5399.7030163340605, 5402.648827826258, 5407.242985981887, 6132.324915438686, 6206.72069756871, 6223.782167624111, 6232.25212974446, 6232.937670623236, 6253.5622506424115, 6272.059191121369, 6274.46667033289, 6287.874242515032, 6292.339304372975, 6263.937360626335, 6273.027680709497, 6278.434940855345, 6282.717277722422, 6283.37166283368, 5918.3489816121055, 5955.880882382415, 6078.72645730952, 6091.573589848668, 6178.7219575120125, 6081.179040829431, 6157.6613286269685, 6172.783952561639, 6184.857178495697, 6223.315435302204, 6031.487323577545, 6053.048969046448, 6054.84862878414, 6061.999770037894, 6099.11562823397, 5720.885582288248, 5814.866101110243, 5972.671503067373, 6012.669303188367, 6028.788992385245, 5829.921464529076, 5908.231929849092, 5926.970365148319, 5936.554758393051, 5970.611910225995, 6046.788362407295, 6055.878882422415, 6069.848253793514, 6070.939290607037, 6079.392060793885, 4708.270218116198, 5605.719714014364, 5682.602217844877, 5697.401772813961, 5745.827625171251], "value": 5566.998973367187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.1508369850571, 472.93378926950226, 503.99748001260014, 520.2092818915249, 528.236697971431, 24619.138330158446, 35211.64788352119, 39874.60125398745, 39877.4018389724, 42220.0, 40885.182296354054, 41526.37710434346, 41640.0, 41782.16435671285, 41864.58135418653, 7902.841943161134, 9061.818763624724, 9279.443233406017, 9289.489078100702, 9310.86033709495, 7910.3276221520955, 9005.459672419644, 9728.610855565768, 10927.125829933586, 11255.099592032619, 11160.386178760165, 11232.157588180879, 11237.775244495106, 11249.212555121145, 11257.437128143602, 7194.352508274228, 7609.6575654095695, 11083.168762342817, 11172.776544469065, 11195.888041119606, 7888.684452621888, 10758.515866786014, 11023.338599684044, 11064.612738554193, 11135.33188008722, 32521.674783252336, 39030.60969390298, 39399.0, 39488.40767388492, 39506.20987580261, 3614.168741189541, 3742.2889650966504, 3917.5309259027863, 3920.5099362579676, 3925.803709814498, 28310.292242693893, 30027.249318766986, 30067.849660751555, 30127.794888204666, 30380.088597342114, 2640.972864401568, 2783.1233161554246, 3210.96789032109, 3214.72674822638, 3239.416904957107, 419466.0, 445249.0, 454952.0, 463673.0, 468757.6562117202, 48703.512964870606, 50264.48148629561, 50463.747681262095, 58151.70924145352, 58396.0, 59974.10038849421, 61251.6937415319, 61491.931452339006, 64989.350106499274, 65119.34880651133, 14920.253987300592, 15078.321475533627, 15088.0, 15088.54734357971, 15117.0, 11718.648440546796, 11774.823377649342, 11780.234284771463, 11783.88216117845, 11784.525249697488, 15400.46098386551, 15416.922915385352, 15426.0, 15511.767323490156, 15639.765403518957, 5683.829485115453, 6161.537884658668, 6616.139901812733, 6725.05849181115, 6810.9318906810295, 797.2943944609062, 836.7071524966335, 842.1073661918387, 851.2168804699692, 852.7868032991752, 24722.13472528489, 26575.867120664272, 28092.43815123686, 28626.570601440995, 28647.85676071648, 1021.4535223655308, 1032.808930347888, 1054.283087500507, 1062.2670357453333, 1106.9667909962714, 14834.219893612835, 14842.92578537122, 14850.702985940221, 15118.546443606709, 15167.544973650809, 508071.0, 510419.0, 511238.0, 512421.0, 512798.0, 55327.72336138294, 55536.444635553125, 55556.0, 55634.0, 55645.72177139088, 1202.4108186988312, 1214.3442541027746, 1355.8101865738804, 1408.457743768677, 1409.8096756937887, 27168.0, 28209.98801120333, 28462.0, 28633.570496442986, 28662.856685716437, 48258.517414826805, 48619.513804862916, 48683.75658121687, 48708.0, 48815.0, 406429.9357006392, 409516.85797704465, 409633.0, 409815.0, 410231.0, 36202.63797361992, 36585.817070914476, 36735.265294694495, 37021.889343319745, 37023.81488092542, 42661.36007959945, 42663.5733642672, 42925.0, 43161.60479940008, 44167.0, 31556.842215789693, 32570.0, 34359.0, 36542.269154616224, 36940.26119477649, 32248.0, 34761.78333758306, 34877.0, 34932.82533587316, 36223.0, 29934.550981734857, 34830.47754283637, 35299.82350088336, 35627.931162064146, 35702.642973569935, 29278.853605732686, 30717.846410767805, 31489.527657084705, 31507.0, 31623.525647114853, 22916.770832291462, 22963.65554516717, 23216.4195895104, 23556.0577576895, 23608.055677772696], "value": 18903.099036790125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.74019414371423, 334.799742921626, 386.246818703528, 397.25316405158287, 417.3218519905154, 14239.0, 14488.71022579549, 15124.621884452881, 16868.493945181664, 19850.80895146292, 20192.19231230753, 20675.586488270226, 21199.57600847986, 21335.57328853422, 21610.567788644217, 5643.8024669136585, 5899.321578018518, 5901.8819623607715, 5902.409759024084, 5923.851903702399, 4785.976070119645, 6498.415142637162, 6584.572002819801, 6639.4356479699045, 6661.0, 6302.369763023707, 6457.483401327882, 6570.737170513174, 6601.0, 6607.471402287805, 6433.581817181915, 6439.291677915426, 6451.6774161292215, 6457.031445283245, 6487.481001519891, 5057.5701065409485, 5277.076511610453, 5341.172118321666, 5398.325209348832, 5649.152627105926, 13311.600651980456, 13367.86632133676, 13388.665283367896, 13512.98652601049, 13532.255725935069, 2456.4841383309526, 2484.4534202475425, 2485.42835147917, 2515.2328539795353, 2574.742525747429, 9510.38182518134, 9785.608575656965, 10041.995800419973, 10318.535665895053, 10456.0, 1336.9398377073055, 1737.8088410274859, 1760.6126652136509, 1761.1458442655303, 1803.720423334372, 115869.0, 127563.0, 134678.32660836732, 135167.0, 135577.0, 11126.777464450828, 11380.544778208945, 11468.4839182237, 11616.186866919403, 11632.767344653059, 15602.29789659468, 15637.609059773597, 15638.374465021272, 15682.843171568367, 15750.0, 8213.712520061768, 8377.846780554593, 8383.790405239917, 8417.074121846592, 8515.616797244138, 8035.879461808077, 8112.22933821286, 8166.265036146775, 8294.626741796634, 8311.376646751396, 4671.205894997858, 4734.218853889139, 4756.429228492534, 4779.283107533898, 4813.78337974785, 1699.990052720576, 1967.4491142480128, 2012.2554654777534, 2014.909329080195, 2017.7175195472646, 988.5946761827616, 988.8417853143607, 995.8307087795092, 1011.6459239266198, 1030.6650338639822, 20773.27293544719, 21185.834779087298, 21223.469413264793, 21387.991867001718, 21494.355169344944, 997.4962643864802, 1021.2238698589126, 1046.7958748044211, 1055.8416237564427, 1062.6121465664976, 10207.19651247461, 10451.425171615634, 11000.284981476338, 11037.006669399792, 11105.666829995112, 661626.0, 672781.0, 688769.556152216, 703846.0, 715101.0, 121411.0, 140400.0, 142417.57582424043, 151032.2448387773, 160350.0, 906.428949761646, 1020.9183265338755, 1041.926566133389, 1080.713610893114, 1084.9566017359375, 19151.138198781366, 19444.513887152934, 19613.803861961198, 19671.508212294808, 19679.80320196837, 56501.0, 59515.0, 59893.0, 60065.0, 60566.0, 83786.0, 85823.41765823368, 86843.56578217068, 87477.0, 87831.56084219538, 7515.361194298546, 8352.916470835456, 8537.274331681629, 8575.742727718178, 8585.399022068395, 12094.818577721517, 12124.211926224767, 12659.98720102408, 13366.532171373956, 13578.0, 8402.41183117176, 8922.73231803047, 9429.387089839138, 11472.94263528677, 11652.242604230698, 11174.0, 11225.326480411202, 11301.773964520828, 11593.82609260845, 11652.242604230698, 10853.891461085605, 11350.432478376213, 11426.11469107588, 11471.0, 11868.584599538975, 6522.34776522344, 8317.41778075529, 8494.0, 8558.529280889361, 8582.055973842997, 9827.705168844946, 10109.0, 10599.0, 11568.652940411801, 11759.353235572125], "value": 9620.512755263326, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.6764874956884, 1398.6922876967064, 1421.1686163594297, 1430.921299328537, 1437.9065360751547, 7127.607981561012, 7485.214052524489, 7855.882161767571, 8208.0, 8469.237768600824, 7311.866660667605, 8377.371697122724, 8453.239208471236, 8483.0, 8490.660373585064, 1929.8649094563386, 2207.9668804967937, 2253.9774602254015, 2256.1088370093803, 2265.060000099965, 2016.1532156494306, 2045.5704302096503, 2226.521297920947, 2283.372072680018, 2286.6684330772027, 2239.193890199527, 2242.192810588187, 2245.550889822034, 2251.020805949409, 2253.1550668499312, 2196.3850121965875, 2225.4881377283236, 2230.810381117607, 2233.687283780272, 2236.4856083100817, 2216.944576385587, 2218.0, 2278.6923765291804, 2282.4522114692522, 2285.919992800261, 7173.923911413278, 8499.872501912476, 8528.147185281485, 8536.189062039097, 8557.400981931267, 5032.144535428949, 5201.063808514466, 5207.114790485626, 5208.166693329087, 5209.921851172236, 3276.2955964467756, 3457.870627082469, 3497.1606814364623, 3538.0800991742076, 3553.3603951288756, 1163.5171403867384, 1187.7505723798013, 1223.9143259971806, 1253.1729058821224, 1254.5295514182183, 11054.944725276402, 11063.004329610374, 11080.889191108066, 11100.944495277552, 11143.665690029313, 4237.406763053175, 4594.2419500782, 4643.582077613031, 4696.365990591226, 4698.553637404441, 9662.17871480932, 9692.46691431978, 9704.611815527442, 9709.6116155353, 9751.561179746786, 5239.240310155006, 5246.527812496894, 5248.763805628756, 5250.028744682246, 5257.737113144328, 4601.861944141681, 4694.647901407408, 4701.318308845198, 4726.133177394979, 4809.831655892027, 3364.62989071202, 3444.9311013779584, 3464.3244567309134, 3593.568771747407, 3599.1182160370354, 1237.2761934268553, 1241.4413513918773, 1245.7383949370733, 1257.5787111317627, 1258.0690289185936, 763.3816608546983, 831.243568352796, 885.1679421343916, 894.2979760887672, 936.241644268145, 4138.6482149017675, 4176.0, 5635.436456354331, 5716.256886604715, 5957.0, 965.75856035991, 983.8376667849725, 993.215359865698, 1022.6522982185944, 1024.99487502562, 3765.736398452082, 3788.753731007476, 3798.9810050950123, 3905.0432644001835, 3923.0780766519847, 9921.206303495703, 10230.335028223142, 10233.1301839343, 10237.283390162765, 10249.641262555775, 10183.338083024728, 10195.133413659774, 10206.540705668263, 10227.02843229892, 10248.897511024943, 7628.580428076509, 7849.686012559434, 8020.11778704342, 8023.0, 8031.477953932976, 5585.469380408855, 5613.494785469327, 5706.914396284142, 5709.885802284014, 5763.308402991668, 8969.865452018095, 9421.152096311365, 9425.151736343762, 9425.38734982224, 9434.716958491255, 8892.28861691076, 9276.350655454053, 9344.15902568772, 9546.713598592054, 9547.904520954702, 6660.300668429939, 7290.854182916206, 7293.088363954505, 7295.452841037049, 7301.269873012656, 7068.434525237866, 7314.158871729819, 7329.0, 7570.16728159913, 7865.567393793167, 6942.2363540011565, 6978.720851166099, 7104.751333703295, 7118.466115041289, 7235.565866048054, 5479.698616576126, 6880.071190389233, 7090.042844215965, 7169.605671687898, 7171.282871712787, 6096.390360963868, 6276.717547710273, 6278.748850045947, 6286.497080233479, 6289.182406287154, 4912.508749125059, 4913.901721965469, 5007.84976450707, 5054.494550544916, 5193.350831146106, 5051.848444546669, 5079.815679257955, 5094.133997220555, 5108.901586158993, 5224.608154388512], "value": 4384.271202144388, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.3647685767319, 351.5473484197554, 354.19950910941276, 367.5772861209609, 367.58095770821285, 5078.314427552284, 5128.948710512894, 5137.845864624058, 5156.149235376168, 5204.724842413604, 4837.5646191842725, 4983.302337672729, 5044.697318160904, 5069.594432445405, 5131.666441681288, 3905.3556163233034, 3927.371620540714, 3939.8424063037587, 3949.6247856453733, 3951.1505026419313, 3298.802071875683, 3308.503724441329, 3945.8618948336816, 3962.881113566598, 3970.583088775666, 3676.786216492257, 3841.9423708644254, 3908.511436070491, 3918.490596222487, 3921.607839216084, 3849.538055433338, 3865.76805391676, 3877.6763908242388, 3887.630675085862, 3917.7061720370943, 3362.3443428531446, 3374.2913988062537, 3376.0547046826928, 3376.544166537535, 3386.0, 4176.310908700061, 4958.0, 4962.883351245977, 4984.252362145671, 4987.775550100254, 3715.5169827922196, 4176.686748493845, 4393.231184542692, 4429.933550996738, 4443.911121777579, 4694.967107236436, 5069.771860266297, 5266.131088370415, 5382.91925621116, 5464.535514481234, 3335.499675048762, 3607.783532988029, 3773.5471843340238, 3781.7920014399197, 3799.905002374935, 4670.9766451167525, 5223.451537588537, 5482.0, 5624.803131890405, 5707.204708868598, 3735.0577699596056, 3746.4380342948775, 3747.2270519815143, 3755.6244375562223, 3756.492873462102, 3358.7145092667397, 3709.4435834625024, 3783.3000894834545, 3803.980980095082, 3816.1795214029116, 3880.1269714314053, 4224.155168966219, 4378.8905277367685, 4448.199324121689, 4462.866114016585, 5122.564582010496, 5425.6202065855805, 5432.8268692522515, 5445.646033007842, 5450.1007333789485, 1153.419814853639, 1187.7208855918852, 1189.851268591426, 1218.6770505815966, 1241.938382285402, 685.7942617214816, 754.9698012079565, 801.5150833745616, 842.9747107586783, 852.2968550945495, 4254.595813397722, 4321.006168581181, 4331.0, 4734.490719918449, 4741.668083234141, 4586.334981427674, 5095.636879659481, 5095.655998240776, 5212.68723876561, 5300.92048619271, 4166.458410388115, 4344.6089851913475, 4753.001869607421, 4753.35829662998, 4794.352762377104, 2479.9752002480104, 2666.0668765932155, 2756.517609418344, 2759.4481103779326, 2772.306923269183, 5139.353233084964, 5461.563074953994, 5473.75368108436, 5508.980838544882, 5548.72256387179, 4709.364235828188, 4848.690853469543, 5287.444818294024, 5404.945950540523, 5438.619296649196, 5493.846292278587, 5587.413321601173, 5671.51792097668, 5714.257146570919, 5726.169705392778, 4748.525147485224, 4879.658423910292, 5465.0, 5490.313710786152, 5531.695756733257, 4486.910261794811, 5083.364579427572, 5409.945900540944, 5420.349558053059, 5480.534154596904, 4964.131277026579, 5062.418511247327, 5079.298435024309, 5234.607404444758, 5638.21065050885, 3241.0438920518977, 3712.886134159687, 3739.9252014959006, 3749.1564398010228, 3773.0, 4698.154332220233, 4913.77887995034, 5308.098547567458, 5557.861053473695, 5586.541921554665, 4655.138799322136, 5201.661891977009, 5362.812301569426, 5427.701476418835, 5429.592780541554, 4742.506110575157, 5235.869103272448, 5385.518982279747, 5404.675719456688, 5425.0, 4443.33349997503, 4958.454569997225, 5257.973710131578, 5289.259503669567, 5294.49702278283, 4969.503049695001, 5343.171808369709, 5364.517193452608, 5371.811986580451, 5375.247465354932, 4790.904181916272, 4957.355543779286, 5223.425423203368, 5279.049771041249, 5280.392754833089], "value": 3893.185966024967, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.5497406789315, 713.1442269276869, 714.5891112610251, 814.5357146426537, 848.7241646464901, 14330.785038224434, 14393.712125757478, 14768.040077394962, 14780.334884930176, 14850.702985940276, 11636.418179091053, 13546.864531354684, 13674.658133546667, 13700.794488082687, 13765.105268157562, 808.2806302390877, 894.356063634184, 904.2088172848757, 906.4697152165958, 909.9863502047475, 885.2077390735288, 889.7908991387019, 893.8167675626513, 894.0567701075363, 894.4901406198469, 942.185009966381, 942.2226663003015, 943.7168849345169, 944.631593678466, 946.8295706772778, 929.5909799688116, 933.3466573398648, 935.2050756856652, 935.9906400936039, 938.4088024544561, 694.1739330197049, 695.2144077192739, 696.770065878259, 699.1435491522894, 699.9160100787888, 14961.700765984728, 14972.401103955828, 15017.774733379008, 15152.090874547563, 15160.469383571522, 13122.86877131226, 13366.331683415887, 13427.932860335635, 13457.327133643279, 13509.05436619437, 11472.426378681115, 11783.458467405573, 11833.704157396049, 12088.03295736339, 12356.38218089092, 3604.7656902301264, 3663.427002954049, 3683.797391143486, 3755.962440375589, 3774.0942173878348, 99600.0, 100977.0, 101217.99999999926, 102333.48833255861, 102695.48652256766, 10478.895211047944, 10575.259731818698, 10679.466026698634, 10835.620753273597, 11066.889331106748, 21841.235556755437, 21857.016434260495, 22379.552408952055, 22527.774722252896, 22600.75695836745, 6987.615681137485, 7358.337749602561, 7484.47608667399, 7548.698052077855, 7697.384209263246, 4112.074783173822, 4113.773742444135, 4138.482689663792, 4188.602082802129, 4194.224068547329, 3580.2481478889204, 3611.6930060945115, 3685.631436856293, 3687.3731465650903, 3705.1478160023344, 658.4173006888938, 667.6494840208929, 670.9932900670931, 671.2549070531705, 689.327905292346, 636.4971672378862, 664.9434798042221, 696.474162007681, 703.5321511194998, 721.2679130682361, 9208.26333893287, 9777.755556111011, 9920.503974801231, 10129.392236465836, 10262.0, 598.3717097048082, 646.8900286951135, 682.1098466501263, 685.4971097690797, 694.1704662927752, 4537.0, 4813.663043586916, 4814.855554333376, 4827.275908613666, 4837.298591704183, 74567.0, 76443.61778191074, 90757.54621226984, 90993.54503227441, 93053.0, 47460.7626961863, 56560.71719641375, 71126.0, 72104.0, 73639.3724564781, 68944.6552767243, 72734.63632681803, 73195.0, 73365.0, 74853.62573187207, 10277.126444252022, 10647.467626618483, 10691.144708423462, 10878.510467028844, 10927.0, 36305.0, 43892.0, 44778.55221447874, 47701.0, 48773.0, 58281.0, 59957.0, 60003.0, 66191.33808661852, 67733.0, 12758.68103297425, 13029.609111726664, 13131.803022954473, 13486.393112309774, 13643.522476713268, 14546.854531454974, 14648.9745717803, 14651.560453186421, 14661.0, 15423.91521695643, 13252.0, 13601.931990339985, 13604.727905442034, 13619.0, 13753.656158596115, 13567.0, 13621.727565448833, 13689.726205475636, 13736.862631373559, 14373.64065898361, 10401.58393664245, 11686.357250351311, 11866.228695134789, 12094.576689815814, 13670.931645342107, 8605.271688985113, 10554.472276386303, 10854.620088296871, 11142.331460112418, 11298.943505282421, 10061.899381006097, 10710.620894782502, 10804.729881753019, 10857.891421085687, 10884.020438160604], "value": 7331.401672481012, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 117.0, 114.0, 114.0, 115.0, 114.0, 113.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 113.0, 110.0, 143.0, 111.0, 112.0, 132.0, 139.0, 133.0, 110.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 186.0, 185.0, 185.0, 180.0, 181.0, 184.0, 184.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 90.0, 59.0, 58.0, 59.0, 58.0, 75.0, 57.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 86.0, 70.0, 69.0, 69.0, 85.0, 69.0, 69.0, 83.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 135.0, 85.0, 87.0, 84.0, 89.0, 85.0, 88.0, 85.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 115.0, 160.0, 95.0, 94.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 85.0, 91.0, 87.0, 86.0, 84.0, 82.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 58.0, 58.0, 59.0, 60.0, 58.0, 58.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 75.0, 72.0, 71.0, 69.0, 69.0, 68.0, 68.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 87.0, 86.0, 87.0, 89.0, 87.0, 87.0, 86.0, 90.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 129.0, 156.0, 145.0, 149.0, 151.0, 148.0, 139.0, 204.0, 175.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 174.0, 180.0, 174.0, 171.0, 171.0, 171.0, 172.0, 176.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0], "value": 66.7, "unit": "score"}]}]} 06:00:27 INFO - Return code: 0 06:00:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:00:27 ERROR - # TBPL SUCCESS # 06:00:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:00:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:00:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:00:27 INFO - 2017-02-06 06:00:27.303 screenresolution[1655:9960] starting screenresolution argv=screenresolution get 06:00:27 INFO - 2017-02-06 06:00:27.306 screenresolution[1655:9960] Display 0: 1600x1200x32@60 06:00:27 INFO - 2017-02-06 06:00:27.312 screenresolution[1656:9963] starting screenresolution argv=screenresolution list 06:00:27 INFO - Available Modes on Display 0 06:00:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:00:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:00:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:00:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:00:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:00:27 INFO - Intel Iris: 06:00:27 INFO - Chipset Model: Intel Iris 06:00:27 INFO - Type: GPU 06:00:27 INFO - Bus: Built-In 06:00:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:00:27 INFO - Vendor: Intel (0x8086) 06:00:27 INFO - Device ID: 0x0a2e 06:00:27 INFO - Revision ID: 0x0009 06:00:27 INFO - Displays: 06:00:27 INFO - Display: 06:00:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:00:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:00:27 INFO - Display Serial Number: 1600x1200 60 06:00:27 INFO - Main Display: Yes 06:00:27 INFO - Mirror: Off 06:00:27 INFO - Online: Yes 06:00:27 INFO - Rotation: Supported 06:00:27 INFO - Return code: 0 06:00:27 INFO - Running post-action listener: _resource_record_post_action 06:00:27 INFO - [mozharness: 2017-02-06 14:00:27.551143Z] Finished run-tests step (success) 06:00:27 INFO - Running post-run listener: _resource_record_post_run 06:00:27 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39146496; Write bytes: 555186688; Read time: 380; Write time: 1943 06:00:27 INFO - TinderboxPrint: CPU usage
53.8% 06:00:27 INFO - TinderboxPrint: I/O read bytes / time
39,146,496 / 380 06:00:27 INFO - TinderboxPrint: I/O write bytes / time
555,186,688 / 1,943 06:00:27 INFO - TinderboxPrint: CPU idle
2,223.3 (46.2%) 06:00:27 INFO - TinderboxPrint: CPU system
963.3 (20.0%) 06:00:27 INFO - TinderboxPrint: CPU user
1,623.0 (33.7%) 06:00:27 INFO - TinderboxPrint: Swap in / out
290,299,904 / 0 06:00:27 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 251652608; Write bytes: 242083328; Read time: 18285; Write time: 525 06:00:27 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31841280; Write bytes: 297772032; Read time: 293; Write time: 1377 06:00:28 INFO - Running post-run listener: _upload_blobber_files 06:00:28 INFO - Blob upload gear active. 06:00:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:00:28 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 06:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:00:28 INFO - (blobuploader) - INFO - Open directory for files ... 06:00:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:00:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:00:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:29 INFO - (blobuploader) - INFO - Done attempting. 06:00:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:00:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:00:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:30 INFO - (blobuploader) - INFO - Done attempting. 06:00:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:00:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:00:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:32 INFO - (blobuploader) - INFO - Done attempting. 06:00:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:00:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:00:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:35 INFO - (blobuploader) - INFO - Done attempting. 06:00:35 INFO - (blobuploader) - INFO - Iteration through files over. 06:00:35 INFO - Return code: 0 06:00:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:00:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:00:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84a80c42e93b4c83318e76591af6ad714351df58f4cea35287fbc27485cd270e0664a1cb1287b255df12b9e1051e8478624296b1d7c02d001dbb65f1bc10eb85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19daff5b033da0dafe519d20b44364f9005c9306cd143863d8cb5457737839953105884a0b47612d2353035ee2d618556427e9a0fdbb270bb3ca672a631b6972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce28fbe437bec4a29117b842d4ef1c52610fa74c161e28ecb6a1e54c332204bf8d9d578dfe89260e48b0759fa9b65a50a724e2a2c34568ebf9eef31fdd73ad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0191b3ba023636cc8e11e059dddcd3638d8d3eb62a82382f3ea1872564fa0abe560d7865849ddb53eacfeb59db32b9afede482095d9dc307358a0c2a4dbf0d21"} 06:00:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:00:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:00:35 INFO - Contents: 06:00:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84a80c42e93b4c83318e76591af6ad714351df58f4cea35287fbc27485cd270e0664a1cb1287b255df12b9e1051e8478624296b1d7c02d001dbb65f1bc10eb85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19daff5b033da0dafe519d20b44364f9005c9306cd143863d8cb5457737839953105884a0b47612d2353035ee2d618556427e9a0fdbb270bb3ca672a631b6972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce28fbe437bec4a29117b842d4ef1c52610fa74c161e28ecb6a1e54c332204bf8d9d578dfe89260e48b0759fa9b65a50a724e2a2c34568ebf9eef31fdd73ad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0191b3ba023636cc8e11e059dddcd3638d8d3eb62a82382f3ea1872564fa0abe560d7865849ddb53eacfeb59db32b9afede482095d9dc307358a0c2a4dbf0d21"} 06:00:35 INFO - Running post-run listener: copy_logs_to_upload_dir 06:00:35 INFO - Copying logs to upload dir... 06:00:35 INFO - mkdir: /builds/slave/test/build/upload/logs 06:00:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.317792 ========= master_lag: 0.48 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-02-06 06:00:36.070382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 06:00:36.074979) ========= 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.c0Zg48bh4g/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.nlko2PWBVk/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84a80c42e93b4c83318e76591af6ad714351df58f4cea35287fbc27485cd270e0664a1cb1287b255df12b9e1051e8478624296b1d7c02d001dbb65f1bc10eb85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19daff5b033da0dafe519d20b44364f9005c9306cd143863d8cb5457737839953105884a0b47612d2353035ee2d618556427e9a0fdbb270bb3ca672a631b6972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce28fbe437bec4a29117b842d4ef1c52610fa74c161e28ecb6a1e54c332204bf8d9d578dfe89260e48b0759fa9b65a50a724e2a2c34568ebf9eef31fdd73ad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0191b3ba023636cc8e11e059dddcd3638d8d3eb62a82382f3ea1872564fa0abe560d7865849ddb53eacfeb59db32b9afede482095d9dc307358a0c2a4dbf0d21"} build_url:https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/A6PI-3hhThyj8-2QS1GcGQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84a80c42e93b4c83318e76591af6ad714351df58f4cea35287fbc27485cd270e0664a1cb1287b255df12b9e1051e8478624296b1d7c02d001dbb65f1bc10eb85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19daff5b033da0dafe519d20b44364f9005c9306cd143863d8cb5457737839953105884a0b47612d2353035ee2d618556427e9a0fdbb270bb3ca672a631b6972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce28fbe437bec4a29117b842d4ef1c52610fa74c161e28ecb6a1e54c332204bf8d9d578dfe89260e48b0759fa9b65a50a724e2a2c34568ebf9eef31fdd73ad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0191b3ba023636cc8e11e059dddcd3638d8d3eb62a82382f3ea1872564fa0abe560d7865849ddb53eacfeb59db32b9afede482095d9dc307358a0c2a4dbf0d21"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 06:00:36.112680) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 06:00:36.113003) ========= 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.c0Zg48bh4g/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.nlko2PWBVk/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 06:00:36.161332) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 06:00:36.163843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 06:00:36.164180) ========= ========= Total master_lag: 0.62 =========