builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1482842696.44 results: success (0) buildid: 20161227030213 builduid: 76d48b557275491e98fd1c6abcf390c6 revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.443913) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.444348) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.444674) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.493679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.493986) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-27 04:44:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.1M=0s 2016-12-27 04:44:57 (89.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090090 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.616789) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.617101) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016988 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.675413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.675790) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --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 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-27 04:44:57,327 truncating revision to first 12 chars 2016-12-27 04:44:57,327 Setting DEBUG logging. 2016-12-27 04:44:57,327 attempt 1/10 2016-12-27 04:44:57,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3119a9a0b5de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 04:44:57,835 unpacking tar archive at: mozilla-central-3119a9a0b5de/testing/mozharness/ program finished with exit code 0 elapsedTime=0.837237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.529508) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.529833) ========= script_repo_revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.530320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.530642) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.540124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-27 04:44:57.540432) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:44:58 INFO - MultiFileLogger online at 20161227 04:44:58 in /builds/slave/test 04:44:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:44:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:58 INFO - {'append_to_log': False, 04:44:58 INFO - 'base_work_dir': '/builds/slave/test', 04:44:58 INFO - 'blob_upload_branch': 'Firefox', 04:44:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:58 INFO - 'branch': 'Firefox', 04:44:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:58 INFO - 'config_files': ('talos/linux_config.py',), 04:44:58 INFO - 'default_actions': ('clobber', 04:44:58 INFO - 'read-buildbot-config', 04:44:58 INFO - 'download-and-extract', 04:44:58 INFO - 'populate-webroot', 04:44:58 INFO - 'create-virtualenv', 04:44:58 INFO - 'install', 04:44:58 INFO - 'run-tests'), 04:44:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:58 INFO - 'download_minidump_stackwalk': True, 04:44:58 INFO - 'download_symbols': 'ondemand', 04:44:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:58 INFO - 'installer_path': 'installer.exe', 04:44:58 INFO - 'log_level': 'info', 04:44:58 INFO - 'log_name': 'talos', 04:44:58 INFO - 'log_to_console': True, 04:44:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:44:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:44:58 INFO - 'opt_config_files': (), 04:44:58 INFO - 'pip_index': False, 04:44:58 INFO - 'sps_profile': False, 04:44:58 INFO - 'sps_profile_interval': 0, 04:44:58 INFO - 'suite': 'dromaeojs', 04:44:58 INFO - 'system_bits': '32', 04:44:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:58 INFO - 'title': 'talos-linux64-ix-038', 04:44:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:58 INFO - 'use_talos_json': True, 04:44:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:58 INFO - 'work_dir': 'build'} 04:44:58 INFO - [mozharness: 2016-12-27 12:44:58.291650Z] Running clobber step. 04:44:58 INFO - Running pre-action listener: _resource_record_pre_action 04:44:58 INFO - Running main action method: clobber 04:44:58 INFO - rmtree: /builds/slave/test/build 04:44:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:58 INFO - Running post-action listener: _resource_record_post_action 04:44:58 INFO - [mozharness: 2016-12-27 12:44:58.754754Z] Finished clobber step (success) 04:44:58 INFO - [mozharness: 2016-12-27 12:44:58.754855Z] Running read-buildbot-config step. 04:44:58 INFO - Running pre-action listener: _resource_record_pre_action 04:44:58 INFO - Running main action method: read_buildbot_config 04:44:58 INFO - Using buildbot properties: 04:44:58 INFO - { 04:44:58 INFO - "project": "", 04:44:58 INFO - "product": "firefox", 04:44:58 INFO - "script_repo_revision": "production", 04:44:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:44:58 INFO - "repository": "", 04:44:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:44:58 INFO - "buildid": "20161227030213", 04:44:58 INFO - "stage_platform": "linux64-pgo", 04:44:58 INFO - "basedir": "/builds/slave/test", 04:44:58 INFO - "nightly_build": "True", 04:44:58 INFO - "buildnumber": 136, 04:44:58 INFO - "slavename": "talos-linux64-ix-038", 04:44:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:44:58 INFO - "platform": "ubuntu64_hw", 04:44:58 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:44:58 INFO - "branch": "mozilla-central", 04:44:58 INFO - "repo_path": "mozilla-central", 04:44:58 INFO - "revision": "3119a9a0b5dee60ac77b7596ae5dbe0658f598ad", 04:44:58 INFO - "pgo_build": "True", 04:44:58 INFO - "builduid": "76d48b557275491e98fd1c6abcf390c6", 04:44:58 INFO - "slavebuilddir": "test" 04:44:58 INFO - } 04:44:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:44:58 INFO - Running post-action listener: _resource_record_post_action 04:44:58 INFO - [mozharness: 2016-12-27 12:44:58.757416Z] Finished read-buildbot-config step (success) 04:44:58 INFO - [mozharness: 2016-12-27 12:44:58.757506Z] Running download-and-extract step. 04:44:58 INFO - Running pre-action listener: _resource_record_pre_action 04:44:58 INFO - Running main action method: download_and_extract 04:44:58 INFO - mkdir: /builds/slave/test/build/tests 04:44:58 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')]} 04:44:58 INFO - trying https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:44:58 INFO - Downloading https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:44:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:44:59 INFO - Downloaded 1361 bytes. 04:44:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:44:59 INFO - Using the following test package requirements: 04:44:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:44:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:44:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:44:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'jsshell-linux-x86_64.zip'], 04:44:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:44:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:44:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:44:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:44:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:44:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:44:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:44:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:44:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:44:59 INFO - Fetch https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:45:00 INFO - Expected file size: 21831962 04:45:00 INFO - Obtained file size: 21831962 04:45:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:45:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:45:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:45:04 INFO - Fetch https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:45:05 INFO - Expected file size: 21831962 04:45:05 INFO - Obtained file size: 21831962 04:45:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 04:45:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:45:09 INFO - Fetch https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:45:10 INFO - Expected file size: 14409776 04:45:10 INFO - Obtained file size: 14409776 04:45:10 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')]} 04:45:10 INFO - trying https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:45:10 INFO - Downloading https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:45:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:45:12 INFO - Downloaded 61349179 bytes. 04:45:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:45:12 INFO - mkdir: /builds/slave/test/properties 04:45:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:45:12 INFO - Writing to file /builds/slave/test/properties/build_url 04:45:12 INFO - Contents: 04:45:12 INFO - build_url:https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:45:12 INFO - Running post-action listener: _resource_record_post_action 04:45:12 INFO - Running post-action listener: set_extra_try_arguments 04:45:12 INFO - [mozharness: 2016-12-27 12:45:12.935601Z] Finished download-and-extract step (success) 04:45:12 INFO - [mozharness: 2016-12-27 12:45:12.935828Z] Running populate-webroot step. 04:45:12 INFO - Running pre-action listener: _resource_record_pre_action 04:45:12 INFO - Running main action method: populate_webroot 04:45:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:12 INFO - '--tppagecycles', 04:45:12 INFO - '7'], 04:45:12 INFO - 'tests': ['tsvgm']}}, 04:45:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:45:12 INFO - 'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:45:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:45:12 INFO - 'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['damp', 'tps']}, 04:45:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:45:12 INFO - 'tests': ['damp', 'tps']}, 04:45:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['dromaeo_dom']}, 04:45:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['basic_compositor_video']}, 04:45:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:45:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['a11yr', 04:45:12 INFO - 'ts_paint', 04:45:12 INFO - 'tpaint', 04:45:12 INFO - 'sessionrestore', 04:45:12 INFO - 'sessionrestore_no_auto_restore', 04:45:12 INFO - 'tabpaint']}, 04:45:12 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:12 INFO - 'ts_paint', 04:45:12 INFO - 'tpaint', 04:45:12 INFO - 'sessionrestore', 04:45:12 INFO - 'sessionrestore_no_auto_restore', 04:45:12 INFO - 'tabpaint']}, 04:45:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:12 INFO - 'ts_paint', 04:45:12 INFO - 'tpaint', 04:45:12 INFO - 'sessionrestore', 04:45:12 INFO - 'sessionrestore_no_auto_restore', 04:45:12 INFO - 'tabpaint']}, 04:45:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:12 INFO - 'ts_paint', 04:45:12 INFO - 'tpaint', 04:45:12 INFO - 'sessionrestore', 04:45:12 INFO - 'sessionrestore_no_auto_restore', 04:45:12 INFO - 'tabpaint']}, 04:45:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['a11yr', 04:45:12 INFO - 'ts_paint', 04:45:12 INFO - 'tpaint', 04:45:12 INFO - 'sessionrestore', 04:45:12 INFO - 'sessionrestore_no_auto_restore', 04:45:12 INFO - 'tabpaint']}, 04:45:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['a11yr', 04:45:12 INFO - 'ts_paint', 04:45:12 INFO - 'tpaint', 04:45:12 INFO - 'sessionrestore', 04:45:12 INFO - 'sessionrestore_no_auto_restore', 04:45:12 INFO - 'tabpaint']}, 04:45:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['tsvgx', 04:45:12 INFO - 'tsvgr_opacity', 04:45:12 INFO - 'tart', 04:45:12 INFO - 'tscrollx', 04:45:12 INFO - 'cart', 04:45:12 INFO - 'tsvg_static']}, 04:45:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:12 INFO - 'tsvgr_opacity', 04:45:12 INFO - 'tart', 04:45:12 INFO - 'tscrollx', 04:45:12 INFO - 'cart', 04:45:12 INFO - 'tsvg_static']}, 04:45:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:45:12 INFO - 'talos_options': ['--disable-e10s'], 04:45:12 INFO - 'tests': ['tp5o']}, 04:45:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:45:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:45:12 INFO - 'talos_options': ['--disable-e10s', 04:45:12 INFO - '--xperf_path', 04:45:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:12 INFO - 'tests': ['tp5n']}, 04:45:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:45:12 INFO - 'talos_options': ['--xperf_path', 04:45:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:12 INFO - 'tests': ['tp5n']}}, 04:45:12 INFO - 'talos.zip': {'path': '', 04:45:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:12 INFO - Running post-action listener: _resource_record_post_action 04:45:12 INFO - [mozharness: 2016-12-27 12:45:12.951951Z] Finished populate-webroot step (success) 04:45:12 INFO - [mozharness: 2016-12-27 12:45:12.952040Z] Running create-virtualenv step. 04:45:12 INFO - Running pre-action listener: _resource_record_pre_action 04:45:12 INFO - Running main action method: create_virtualenv 04:45:12 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:12 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 04:45:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:13 INFO - Using real prefix '/usr' 04:45:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:13 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:15 INFO - Installing pip.................done. 04:45:15 INFO - Return code: 0 04:45:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:45:15 INFO - Reading from file tmpfile_stdout 04:45:15 INFO - Output received: 04:45:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:45:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:15 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:15 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 04:45:15 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 04:45:15 INFO - Using env: {'DISPLAY': ':0', 04:45:15 INFO - 'HOME': '/home/cltbld', 04:45:15 INFO - 'LANG': 'en_US.UTF-8', 04:45:15 INFO - 'LANGUAGE': 'en_US:en', 04:45:15 INFO - 'LOGNAME': 'cltbld', 04:45:15 INFO - 'MAIL': '/var/mail/cltbld', 04:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:15 INFO - 'MOZ_NO_REMOTE': '1', 04:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:15 INFO - 'NO_EM_RESTART': '1', 04:45:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:15 INFO - 'PWD': '/builds/slave/test', 04:45:15 INFO - 'SHELL': '/bin/bash', 04:45:15 INFO - 'SHLVL': '1', 04:45:15 INFO - 'TERM': 'linux', 04:45:15 INFO - 'TMOUT': '86400', 04:45:15 INFO - 'USER': 'cltbld', 04:45:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:15 INFO - '_': '/tools/buildbot/bin/python'} 04:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:15 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 04:45:15 INFO - Cleaning up... 04:45:15 INFO - Return code: 0 04:45:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:15 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:15 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 04:45:15 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 04:45:15 INFO - Using env: {'DISPLAY': ':0', 04:45:15 INFO - 'HOME': '/home/cltbld', 04:45:15 INFO - 'LANG': 'en_US.UTF-8', 04:45:15 INFO - 'LANGUAGE': 'en_US:en', 04:45:15 INFO - 'LOGNAME': 'cltbld', 04:45:15 INFO - 'MAIL': '/var/mail/cltbld', 04:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:15 INFO - 'MOZ_NO_REMOTE': '1', 04:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:15 INFO - 'NO_EM_RESTART': '1', 04:45:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:15 INFO - 'PWD': '/builds/slave/test', 04:45:15 INFO - 'SHELL': '/bin/bash', 04:45:15 INFO - 'SHLVL': '1', 04:45:15 INFO - 'TERM': 'linux', 04:45:15 INFO - 'TMOUT': '86400', 04:45:15 INFO - 'USER': 'cltbld', 04:45:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:15 INFO - '_': '/tools/buildbot/bin/python'} 04:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:16 INFO - Downloading/unpacking psutil>=3.1.1 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:18 INFO - Installing collected packages: psutil 04:45:18 INFO - Running setup.py install for psutil 04:45:18 INFO - building 'psutil._psutil_linux' extension 04:45:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:45:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:45:18 INFO - building 'psutil._psutil_posix' extension 04:45:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:45:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:45:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:18 INFO - Successfully installed psutil 04:45:18 INFO - Cleaning up... 04:45:18 INFO - Return code: 0 04:45:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:18 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:18 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 04:45:18 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 04:45:18 INFO - Using env: {'DISPLAY': ':0', 04:45:18 INFO - 'HOME': '/home/cltbld', 04:45:18 INFO - 'LANG': 'en_US.UTF-8', 04:45:18 INFO - 'LANGUAGE': 'en_US:en', 04:45:18 INFO - 'LOGNAME': 'cltbld', 04:45:18 INFO - 'MAIL': '/var/mail/cltbld', 04:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:18 INFO - 'MOZ_NO_REMOTE': '1', 04:45:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:18 INFO - 'NO_EM_RESTART': '1', 04:45:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:18 INFO - 'PWD': '/builds/slave/test', 04:45:18 INFO - 'SHELL': '/bin/bash', 04:45:18 INFO - 'SHLVL': '1', 04:45:18 INFO - 'TERM': 'linux', 04:45:18 INFO - 'TMOUT': '86400', 04:45:18 INFO - 'USER': 'cltbld', 04:45:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:18 INFO - '_': '/tools/buildbot/bin/python'} 04:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:45:19 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 04:45:19 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 04:45:19 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 04:45:19 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 04:45:19 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 04:45:19 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 04:45:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:45:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:45:21 INFO - Installing collected packages: mozsystemmonitor 04:45:21 INFO - Running setup.py install for mozsystemmonitor 04:45:21 INFO - Successfully installed mozsystemmonitor 04:45:21 INFO - Cleaning up... 04:45:21 INFO - Return code: 0 04:45:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:45:21 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')]} 04:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:21 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')]} 04:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:21 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:21 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 04:45:21 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 04:45:21 INFO - Using env: {'DISPLAY': ':0', 04:45:21 INFO - 'HOME': '/home/cltbld', 04:45:21 INFO - 'LANG': 'en_US.UTF-8', 04:45:21 INFO - 'LANGUAGE': 'en_US:en', 04:45:21 INFO - 'LOGNAME': 'cltbld', 04:45:21 INFO - 'MAIL': '/var/mail/cltbld', 04:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:21 INFO - 'MOZ_NO_REMOTE': '1', 04:45:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:21 INFO - 'NO_EM_RESTART': '1', 04:45:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:21 INFO - 'PWD': '/builds/slave/test', 04:45:21 INFO - 'SHELL': '/bin/bash', 04:45:21 INFO - 'SHLVL': '1', 04:45:21 INFO - 'TERM': 'linux', 04:45:21 INFO - 'TMOUT': '86400', 04:45:21 INFO - 'USER': 'cltbld', 04:45:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:21 INFO - '_': '/tools/buildbot/bin/python'} 04:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:21 INFO - Downloading/unpacking jsonschema==2.5.1 04:45:21 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 04:45:21 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 04:45:21 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 04:45:21 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 04:45:21 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 04:45:21 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 04:45:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:45:23 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 04:45:23 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 04:45:23 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 04:45:23 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 04:45:23 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 04:45:23 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 04:45:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:23 INFO - warning: no files found matching '*.txt' 04:45:23 INFO - no previously-included directories found matching 'build' 04:45:23 INFO - no previously-included directories found matching 'dist' 04:45:23 INFO - no previously-included directories found matching '.git*' 04:45:23 INFO - Installing collected packages: jsonschema, functools32 04:45:23 INFO - Running setup.py install for functools32 04:45:23 INFO - warning: no files found matching '*.txt' 04:45:23 INFO - no previously-included directories found matching 'build' 04:45:23 INFO - no previously-included directories found matching 'dist' 04:45:23 INFO - no previously-included directories found matching '.git*' 04:45:23 INFO - Successfully installed jsonschema functools32 04:45:23 INFO - Cleaning up... 04:45:23 INFO - Return code: 0 04:45:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:23 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:23 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 04:45:23 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 04:45:23 INFO - Using env: {'DISPLAY': ':0', 04:45:23 INFO - 'HOME': '/home/cltbld', 04:45:23 INFO - 'LANG': 'en_US.UTF-8', 04:45:23 INFO - 'LANGUAGE': 'en_US:en', 04:45:23 INFO - 'LOGNAME': 'cltbld', 04:45:23 INFO - 'MAIL': '/var/mail/cltbld', 04:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:23 INFO - 'MOZ_NO_REMOTE': '1', 04:45:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:23 INFO - 'NO_EM_RESTART': '1', 04:45:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:23 INFO - 'PWD': '/builds/slave/test', 04:45:23 INFO - 'SHELL': '/bin/bash', 04:45:23 INFO - 'SHLVL': '1', 04:45:23 INFO - 'TERM': 'linux', 04:45:23 INFO - 'TMOUT': '86400', 04:45:23 INFO - 'USER': 'cltbld', 04:45:23 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:23 INFO - '_': '/tools/buildbot/bin/python'} 04:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:45:24 INFO - Cleaning up... 04:45:24 INFO - Return code: 0 04:45:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', '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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:45: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 blobuploader==1.2.4 04:45:24 INFO - Using env: {'DISPLAY': ':0', 04:45:24 INFO - 'HOME': '/home/cltbld', 04:45:24 INFO - 'LANG': 'en_US.UTF-8', 04:45:24 INFO - 'LANGUAGE': 'en_US:en', 04:45:24 INFO - 'LOGNAME': 'cltbld', 04:45:24 INFO - 'MAIL': '/var/mail/cltbld', 04:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:24 INFO - 'MOZ_NO_REMOTE': '1', 04:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:24 INFO - 'NO_EM_RESTART': '1', 04:45:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:24 INFO - 'PWD': '/builds/slave/test', 04:45:24 INFO - 'SHELL': '/bin/bash', 04:45:24 INFO - 'SHLVL': '1', 04:45:24 INFO - 'TERM': 'linux', 04:45:24 INFO - 'TMOUT': '86400', 04:45:24 INFO - 'USER': 'cltbld', 04:45:24 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:24 INFO - '_': '/tools/buildbot/bin/python'} 04:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:24 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:26 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:26 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 04:45:26 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 04:45:26 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 04:45:26 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 04:45:26 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 04:45:26 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 04:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:27 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 04:45:27 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 04:45:27 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 04:45:27 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 04:45:27 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 04:45:27 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 04:45:27 INFO - Downloading docopt-0.6.1.tar.gz 04:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:27 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:27 INFO - Running setup.py install for blobuploader 04:45:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:27 INFO - Running setup.py install for requests 04:45:27 INFO - Running setup.py install for docopt 04:45:27 INFO - Successfully installed blobuploader requests docopt 04:45:27 INFO - Cleaning up... 04:45:28 INFO - Return code: 0 04:45:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:28 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:28 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 04:45:28 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 04:45:28 INFO - Using env: {'DISPLAY': ':0', 04:45:28 INFO - 'HOME': '/home/cltbld', 04:45:28 INFO - 'LANG': 'en_US.UTF-8', 04:45:28 INFO - 'LANGUAGE': 'en_US:en', 04:45:28 INFO - 'LOGNAME': 'cltbld', 04:45:28 INFO - 'MAIL': '/var/mail/cltbld', 04:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:28 INFO - 'MOZ_NO_REMOTE': '1', 04:45:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:28 INFO - 'NO_EM_RESTART': '1', 04:45:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:28 INFO - 'PWD': '/builds/slave/test', 04:45:28 INFO - 'SHELL': '/bin/bash', 04:45:28 INFO - 'SHLVL': '1', 04:45:28 INFO - 'TERM': 'linux', 04:45:28 INFO - 'TMOUT': '86400', 04:45:28 INFO - 'USER': 'cltbld', 04:45:28 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:28 INFO - '_': '/tools/buildbot/bin/python'} 04:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:28 INFO - Running setup.py (path:/tmp/pip-Z86uK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:28 INFO - Running setup.py (path:/tmp/pip-lh58YO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:28 INFO - Running setup.py (path:/tmp/pip-jZJKKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:28 INFO - Running setup.py (path:/tmp/pip-5nk7sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:28 INFO - Running setup.py (path:/tmp/pip-rowSqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:28 INFO - Running setup.py (path:/tmp/pip-kiaM2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:28 INFO - Running setup.py (path:/tmp/pip-wcmcyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:28 INFO - Running setup.py (path:/tmp/pip-6AdkWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:28 INFO - Running setup.py (path:/tmp/pip-76_aIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:28 INFO - Running setup.py (path:/tmp/pip-WiCi1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:28 INFO - Running setup.py (path:/tmp/pip-ZaXO4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:29 INFO - Running setup.py (path:/tmp/pip-yUyPBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:29 INFO - Running setup.py (path:/tmp/pip-iIzCsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:29 INFO - Running setup.py (path:/tmp/pip-I9i2vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:29 INFO - Running setup.py (path:/tmp/pip-9GXuuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:29 INFO - Running setup.py (path:/tmp/pip-MXTbtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:29 INFO - Running setup.py (path:/tmp/pip-rk6aOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:29 INFO - Running setup.py install for manifestparser 04:45:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:29 INFO - Running setup.py install for mozcrash 04:45:29 INFO - Running setup.py install for mozdebug 04:45:29 INFO - Running setup.py install for mozdevice 04:45:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:45:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:29 INFO - Running setup.py install for mozfile 04:45:30 INFO - Running setup.py install for mozhttpd 04:45:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozinfo 04:45:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozInstall 04:45:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozleak 04:45:30 INFO - Running setup.py install for mozlog 04:45:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for moznetwork 04:45:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozprocess 04:45:30 INFO - Running setup.py install for mozprofile 04:45:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozrunner 04:45:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozscreenshot 04:45:31 INFO - Running setup.py install for moztest 04:45:31 INFO - Running setup.py install for mozversion 04:45:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:31 INFO - Cleaning up... 04:45:31 INFO - Return code: 0 04:45:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:31 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:31 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 04:45:31 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 04:45:31 INFO - Using env: {'DISPLAY': ':0', 04:45:31 INFO - 'HOME': '/home/cltbld', 04:45:31 INFO - 'LANG': 'en_US.UTF-8', 04:45:31 INFO - 'LANGUAGE': 'en_US:en', 04:45:31 INFO - 'LOGNAME': 'cltbld', 04:45:31 INFO - 'MAIL': '/var/mail/cltbld', 04:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:31 INFO - 'MOZ_NO_REMOTE': '1', 04:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:31 INFO - 'NO_EM_RESTART': '1', 04:45:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:31 INFO - 'PWD': '/builds/slave/test', 04:45:31 INFO - 'SHELL': '/bin/bash', 04:45:31 INFO - 'SHLVL': '1', 04:45:31 INFO - 'TERM': 'linux', 04:45:31 INFO - 'TMOUT': '86400', 04:45:31 INFO - 'USER': 'cltbld', 04:45:31 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:31 INFO - '_': '/tools/buildbot/bin/python'} 04:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:31 INFO - Running setup.py (path:/tmp/pip-avk_kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:31 INFO - Running setup.py (path:/tmp/pip-AZJj62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:31 INFO - Running setup.py (path:/tmp/pip-FbMXEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:31 INFO - Running setup.py (path:/tmp/pip-9_GVXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:31 INFO - Running setup.py (path:/tmp/pip-reBuUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:31 INFO - Running setup.py (path:/tmp/pip-pYbYSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:31 INFO - Running setup.py (path:/tmp/pip-q6EQCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:32 INFO - Running setup.py (path:/tmp/pip-lFM_9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:32 INFO - Running setup.py (path:/tmp/pip-khoYjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:32 INFO - Running setup.py (path:/tmp/pip-dT5rKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:32 INFO - Running setup.py (path:/tmp/pip-1wprri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:32 INFO - Running setup.py (path:/tmp/pip-namTzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:32 INFO - Running setup.py (path:/tmp/pip-12MeCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:32 INFO - Running setup.py (path:/tmp/pip-2IFdAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:32 INFO - Running setup.py (path:/tmp/pip-YoUir2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:32 INFO - Running setup.py (path:/tmp/pip-bvesTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:32 INFO - Running setup.py (path:/tmp/pip-PJhqC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:32 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 04:45:32 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 04:45:32 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 04:45:32 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 04:45:32 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 04:45:32 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 04:45:34 INFO - Downloading blessings-1.6.tar.gz 04:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:34 INFO - Installing collected packages: blessings 04:45:34 INFO - Running setup.py install for blessings 04:45:34 INFO - Successfully installed blessings 04:45:34 INFO - Cleaning up... 04:45:34 INFO - Return code: 0 04:45:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:35 INFO - Reading from file tmpfile_stdout 04:45:35 INFO - Current package versions: 04:45:35 INFO - argparse == 1.2.1 04:45:35 INFO - blessings == 1.6 04:45:35 INFO - blobuploader == 1.2.4 04:45:35 INFO - docopt == 0.6.1 04:45:35 INFO - functools32 == 3.2.3-2 04:45:35 INFO - jsonschema == 2.5.1 04:45:35 INFO - manifestparser == 1.1 04:45:35 INFO - mozInstall == 1.12 04:45:35 INFO - mozcrash == 1.0 04:45:35 INFO - mozdebug == 0.1 04:45:35 INFO - mozdevice == 0.49 04:45:35 INFO - mozfile == 1.2 04:45:35 INFO - mozhttpd == 0.7 04:45:35 INFO - mozinfo == 0.9 04:45:35 INFO - mozleak == 0.1 04:45:35 INFO - mozlog == 3.4 04:45:35 INFO - moznetwork == 0.27 04:45:35 INFO - mozprocess == 0.23 04:45:35 INFO - mozprofile == 0.28 04:45:35 INFO - mozrunner == 6.13 04:45:35 INFO - mozscreenshot == 0.1 04:45:35 INFO - mozsystemmonitor == 0.3 04:45:35 INFO - moztest == 0.7 04:45:35 INFO - mozversion == 1.4 04:45:35 INFO - psutil == 3.1.1 04:45:35 INFO - requests == 1.2.3 04:45:35 INFO - wsgiref == 0.1.2 04:45:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 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 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:35 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 04:45:35 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 04:45:35 INFO - Using env: {'DISPLAY': ':0', 04:45:35 INFO - 'HOME': '/home/cltbld', 04:45:35 INFO - 'LANG': 'en_US.UTF-8', 04:45:35 INFO - 'LANGUAGE': 'en_US:en', 04:45:35 INFO - 'LOGNAME': 'cltbld', 04:45:35 INFO - 'MAIL': '/var/mail/cltbld', 04:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:35 INFO - 'MOZ_NO_REMOTE': '1', 04:45:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:35 INFO - 'NO_EM_RESTART': '1', 04:45:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:35 INFO - 'PWD': '/builds/slave/test', 04:45:35 INFO - 'SHELL': '/bin/bash', 04:45:35 INFO - 'SHLVL': '1', 04:45:35 INFO - 'TERM': 'linux', 04:45:35 INFO - 'TMOUT': '86400', 04:45:35 INFO - 'USER': 'cltbld', 04:45:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:35 INFO - '_': '/tools/buildbot/bin/python'} 04:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 INFO - Cleaning up... 04:45:35 INFO - Return code: 0 04:45:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c01c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:45:35 INFO - Using env: {'DISPLAY': ':0', 04:45:35 INFO - 'HOME': '/home/cltbld', 04:45:35 INFO - 'LANG': 'en_US.UTF-8', 04:45:35 INFO - 'LANGUAGE': 'en_US:en', 04:45:35 INFO - 'LOGNAME': 'cltbld', 04:45:35 INFO - 'MAIL': '/var/mail/cltbld', 04:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:35 INFO - 'MOZ_NO_REMOTE': '1', 04:45:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:35 INFO - 'NO_EM_RESTART': '1', 04:45:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:35 INFO - 'PWD': '/builds/slave/test', 04:45:35 INFO - 'SHELL': '/bin/bash', 04:45:35 INFO - 'SHLVL': '1', 04:45:35 INFO - 'TERM': 'linux', 04:45:35 INFO - 'TMOUT': '86400', 04:45:35 INFO - 'USER': 'cltbld', 04:45:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:35 INFO - '_': '/tools/buildbot/bin/python'} 04:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:45:35 INFO - Cleaning up... 04:45:35 INFO - Return code: 0 04:45:35 INFO - Running post-action listener: _resource_record_post_action 04:45:35 INFO - Running post-action listener: _start_resource_monitoring 04:45:35 INFO - Starting resource monitoring. 04:45:35 INFO - [mozharness: 2016-12-27 12:45:35.694731Z] Finished create-virtualenv step (success) 04:45:35 INFO - [mozharness: 2016-12-27 12:45:35.695263Z] Running install step. 04:45:35 INFO - Running pre-action listener: _resource_record_pre_action 04:45:35 INFO - Running main action method: install 04:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:35 INFO - Reading from file tmpfile_stdout 04:45:35 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:35 INFO - Reading from file tmpfile_stdout 04:45:35 INFO - Output received: 04:45:35 INFO - Usage: mozinstall [options] installer 04:45:35 INFO - Options: 04:45:35 INFO - -h, --help show this help message and exit 04:45:35 INFO - -d DEST, --destination=DEST 04:45:35 INFO - Directory to install application into. [default: 04:45:35 INFO - "/builds/slave/test"] 04:45:35 INFO - --app=APP Application being installed. [default: firefox] 04:45:35 INFO - mkdir: /builds/slave/test/build/application 04:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:45:53 INFO - Reading from file tmpfile_stdout 04:45:53 INFO - Output received: 04:45:53 INFO - /builds/slave/test/build/application/firefox/firefox 04:45:53 INFO - Running post-action listener: _resource_record_post_action 04:45:53 INFO - [mozharness: 2016-12-27 12:45:53.351865Z] Finished install step (success) 04:45:53 INFO - [mozharness: 2016-12-27 12:45:53.352111Z] Running run-tests step. 04:45:53 INFO - Running pre-action listener: _resource_record_pre_action 04:45:53 INFO - Running main action method: run_tests 04:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:45:53 INFO - Python 2.7.3 04:45:53 INFO - Return code: 0 04:45:53 INFO - grabbing minidump binary from tooltool 04:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:53 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/linux64/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 0x1bff3f0>, '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 0x1bffcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c001a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:45:53 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:45:53 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/linux64/releng.manifest -o -c /builds/tooltool_cache 04:45:53 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:45:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:45:53 INFO - Return code: 0 04:45:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:45:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:45:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:45:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:45:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:45:53 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/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 04:45:53 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/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 04:45:53 INFO - Using env: {'DISPLAY': ':0', 04:45:53 INFO - 'HOME': '/home/cltbld', 04:45:53 INFO - 'LANG': 'en_US.UTF-8', 04:45:53 INFO - 'LANGUAGE': 'en_US:en', 04:45:53 INFO - 'LOGNAME': 'cltbld', 04:45:53 INFO - 'MAIL': '/var/mail/cltbld', 04:45:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:45:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:53 INFO - 'MOZ_NO_REMOTE': '1', 04:45:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:45:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:53 INFO - 'NO_EM_RESTART': '1', 04:45:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:53 INFO - 'PWD': '/builds/slave/test', 04:45:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:45:53 INFO - 'SHELL': '/bin/bash', 04:45:53 INFO - 'SHLVL': '1', 04:45:53 INFO - 'TERM': 'linux', 04:45:53 INFO - 'TMOUT': '86400', 04:45:53 INFO - 'USER': 'cltbld', 04:45:53 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119', 04:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:53 INFO - '_': '/tools/buildbot/bin/python'} 04:45:53 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/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 04:45:53 INFO - mozversion application_buildid: 20161227030213 04:45:53 INFO - mozversion application_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 04:45:53 INFO - mozversion application_display_name: Nightly 04:45:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:45:53 INFO - mozversion application_name: Firefox 04:45:53 INFO - mozversion application_remotingname: firefox 04:45:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:45:53 INFO - mozversion application_vendor: Mozilla 04:45:53 INFO - mozversion application_version: 53.0a1 04:45:53 INFO - mozversion platform_buildid: 20161227030213 04:45:53 INFO - mozversion platform_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 04:45:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:45:53 INFO - mozversion platform_version: 53.0a1 04:45:53 INFO - using testdate: 1482842753 04:45:53 INFO - actual date: 1482842753 04:45:53 INFO - starting webserver on 'localhost:43142' 04:45:53 INFO - SUITE-START | Running 2 tests 04:45:53 INFO - TEST-START | dromaeo_css 04:45:53 INFO - operating with platform_type : linux_ 04:45:53 INFO - Initialising browser for dromaeo_css test... 04:45:53 INFO - TEST-INFO | started process 22337 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyi8oSN/profile http://localhost:43142/getInfo.html) 04:45:54 INFO - PROCESS | 22337 | 1482842754111 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 04:45:57 INFO - PROCESS | 22337 | __metrics Screen width/height:1600/1200 04:45:57 INFO - PROCESS | 22337 | colorDepth:24 04:45:57 INFO - PROCESS | 22337 | Browser inner width/height: 1024/697 04:45:57 INFO - PROCESS | 22337 | __metrics 04:45:58 INFO - PROCESS | 22337 | 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" 04:45:58 INFO - PROCESS | 22337 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:59 INFO - PROCESS | 22337 | console.error: PushService: 04:45:59 INFO - PROCESS | 22337 | stateChangeProcessEnqueue: Error transitioning state 04:45:59 INFO - PROCESS | 22337 | UnknownError 04:45:59 INFO - TEST-INFO | 22337: exit 0 04:45:59 INFO - Browser initialized. 04:45:59 INFO - Running cycle 1/1 for dromaeo_css test... 04:45:59 INFO - TEST-INFO | started process 22422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyi8oSN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:11 INFO - PROCESS | 22422 | RSS: Main: 147484672 04:46:11 INFO - PROCESS | 22422 | 04:49:13 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:43142/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43142/tests/dromaeo/cssquery-ext.html) 04:49:14 INFO - PROCESS | 22422 | RSS: Main: 287207424 04:49:14 INFO - PROCESS | 22422 | 04:51:00 INFO - PROCESS | 22422 | ************************* 04:51:00 INFO - PROCESS | 22422 | A coding exception was thrown and uncaught in a Task. 04:51:00 INFO - PROCESS | 22422 | 04:51:00 INFO - PROCESS | 22422 | Full message: ReferenceError: fetch is not defined 04:51:00 INFO - PROCESS | 22422 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:51:00 INFO - PROCESS | 22422 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:51:00 INFO - PROCESS | 22422 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:51:00 INFO - PROCESS | 22422 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:51:00 INFO - PROCESS | 22422 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:51:00 INFO - PROCESS | 22422 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:51:00 INFO - PROCESS | 22422 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:51:00 INFO - PROCESS | 22422 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:51:00 INFO - PROCESS | 22422 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:51:00 INFO - PROCESS | 22422 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:51:00 INFO - PROCESS | 22422 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:51:00 INFO - PROCESS | 22422 | 04:51:00 INFO - PROCESS | 22422 | ************************* 04:52:16 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:43142/tests/dromaeo/cssquery-ext.html (next: http://localhost:43142/tests/dromaeo/cssquery-jquery.html) 04:52:17 INFO - PROCESS | 22422 | RSS: Main: 228380672 04:52:17 INFO - PROCESS | 22422 | 04:55:19 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:43142/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43142/tests/dromaeo/cssquery-mootools.html) 04:55:20 INFO - PROCESS | 22422 | RSS: Main: 226082816 04:55:20 INFO - PROCESS | 22422 | 04:58:22 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:43142/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43142/tests/dromaeo/cssquery-prototype.html) 04:58:22 INFO - PROCESS | 22422 | RSS: Main: 179593216 04:58:22 INFO - PROCESS | 22422 | 05:01:25 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:43142/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43142/tests/dromaeo/cssquery-yui.html) 05:01:26 INFO - PROCESS | 22422 | RSS: Main: 183066624 05:01:26 INFO - PROCESS | 22422 | 05:04:28 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:43142/tests/dromaeo/cssquery-yui.html (next: http://localhost:43142/tests/dromaeo/cssquery-dojo.html) 05:04:29 INFO - PROCESS | 22422 | RSS: Main: 274472960 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | __start_tp_report 05:04:29 INFO - PROCESS | 22422 | _x_x_mozilla_page_load 05:04:29 INFO - PROCESS | 22422 | _x_x_mozilla_page_load_details 05:04:29 INFO - PROCESS | 22422 | |i|pagename|runs| 05:04:29 INFO - PROCESS | 22422 | |0;dojo.html;3109.953350699739;3131.9530207046882;3145.5124455709365;3167.01822435045;3179.5071763876595;5295.205719142131;5304.2043693446;5354;5407.351117865856;5418.349798024233;5387.0841956867325;5415.296011518507;5417.810376636819;5426.294581704373;5467.097928841737;4848.806047758086;4897.926531102036;4911.8772030699165;4948.183549714302;4998.850034498971;4937.1606826839525;4956.40523137224;4995.175795993657;4999.450060493343;5003.449620541737;4944.777485013183;4947.010597880439;4989.401271847383;4999.550040496355;5022;4797.136515427222;4824;4838.733869637169;4865.8783530411665;4876;4852.636052296092;4882;4894.339264199323;4928.581070609002;4929.2359684248995;5351.545118664879;5376.489233522809;5394.406615272317;5397.865053373658;5401.540869026137;5498.395176530578;5523.585731070186;5732.770689172428;5738.8278351649515;5763.6541807491685;5789.536837053026;5800.564957628152;5808.30300363959;5846.327672317695;5852.4440178183;5381.192821076831;5381.5425688816495;5384.91922621161;5398.406175320712;5430.51125398716;5713.771449141987;5760.36635970043;5763.654180749085;5949.791757288474;5967.970160149258;5626.746796394172;5639.802606908821;5660.830175094754;5670.262865827499;5758.337791154071;5736.541076713853;5743.195952566644;5773.076307790733;5793.304803423616;5796.739146738407;5612.831615051555;5663.32040155184;5665.2635157430095;5674.858128546737;5792.073268277056;5722.914156287659;5758.424157584292;5767.22142510764;5805.738741756547;5820.912686309709;4329.783510824446;4331.956680433218;4333.458317710286;4333.783310834446;4367.301231802896;3831.2529056834205;3843.423486476995;3873.070463088895;3895.4935858338235;3908.296506628777;5344.465553444625;5396.568274538027;5399.055165346049;5433;5489.066858634042;5731.942680573225;5738.655680659091;5745.253117094751;5747.540196784247;5755.884882302331;5360.383555891122;5377.919331210035;5444.428335024765;5446.836594902159;5494.587905907073;4700.600448961876;4785.258284965836;4833.178359678863;4844.15227335215;4900.901981960341;6140.140380346666;6142.723577439026;6145.26256849172;6145.324014358417;6145.477634401037;5841.269841269841;5847.94152058491;5853.09277062056;5858.209141765978;5861.853453663693;5905.498032667271;5934.3175534814045;5954.583179177416;5956.672383018976;5975.163477113295;5627.803026894039;5685.914711279252;5686.800961966312;5689.260396148474;5732.283464566929;5738.282714660668;5783.710814459176;5790.507806836297;5791.913121303268;5794;5540.750666219949;5589.133684278943;5596.6921819298705;5615.943840561542;5650.886982260249;5661.433856614306;5665.405132461032;5672.12082127271;5700.315962084576;5713.628614140234;5665.065264231355;5681.517071049007;5682;5684.573656975661;5687.459691329317;5487.862803429946;5521.585881058856;5543.473370029841;5634.915476267777;5694.743736531783;5564.944350556604;5605.103183490622;5605.271314729059;5644.576656750678;5665 05:04:29 INFO - PROCESS | 22422 | |1;ext.html;540.1465684218934;572.8453317604246;574.5260160367695;575.427449687561;583.5914859598282;33149;35912.82043589782;41358.793206034;43089.35365969513;43172.35241471373;45710.542894571045;46171.53828461714;46211.537884621226;46310.7684461578;46393.536064639346;8891.688790892336;9076.092390760938;9076.41003334781;9078.909210907905;9079.818403631924;8685.478871267713;8868.600912958933;8977.46135231885;10650.467476626176;10883.727906802314;10948.726281842937;11002.394868282241;11024.834627480594;11026.834597481004;11031.338119712844;10960.068394186505;11036.834447483294;11041.503132359063;11043.83434248487;11045.28205666629;10795.136389088853;10855.837162442569;10863.619773307897;10869.83695244572;10872.510737016853;41795;42166.78916605428;42180.78909605432;42182.57817421817;42262;4059.451973983504;4102.200070986159;4185.372194170868;4200.936985945214;4220.53574106848;31282;31522.52716209259;31689.52465713016;31798.205044873834;32038.679613203803;4203.873883783461;4248.893777655552;4317.287647538153;4333.1766964276685;4357.9128417431475;311703;314199;315128;315298;315940;57552;57850.710746446;57951.71024144852;58012;58012.97753426836;61115.6944215285;61289.38710612926;61342.386576133664;61496;61568.69215653893;12989.480420783251;13228.404721787543;13228.801567976489;13231.867681323256;13241.470341186438;9622.951885240529;10850.403227822546;10966.287191332698;10976.945115274373;10976.945115274373;15403.845961540464;17014.48956531306;17133.314667413164;17547.73678394825;18011.099445027696;6184.742568962296;6188.288346840171;6287.1198032275515;6363.58636688623;6370.936290637127;875.6759998800358;882.52343734384;887.1350433327583;896.462122726359;911.4941207629696;30963.84518077395;31863.203419914225;32231.677683222868;32289.046193391663;32561.51157732636;1043.337480699746;1045.8222102242637;1053.3153450257241;1057.8043062033548;1062.8671416072991;15444.768328475082;16713.41222583877;16713.749293760604;16748.83251167497;16798.572121369576;441485;443198;443855;445804;446196;57728;57742.422575774544;57784;57847;57889;1343.7178192579668;1363.9522616708368;1379.200063962899;1380.289151087191;1389.3122904162503;28418.431631366842;30044.549331759605;30548.723178754546;30654.386912262074;30746.231344216572;50515.49484505108;50966.23550646669;50978.74510627423;51018.234726479874;51121.48878511316;362217;363991;366328;366680;367448;40620.1875962485;40801.946394859166;40805.183896321316;41044.58955410407;41046;44985;45078.0984380304;45237.54762452334;46092;46571.76714116408;38466.615333846305;38721.80639096787;39593.60406395899;39605.20789584135;40594.39108413435;37963.810180948916;38049.239015218984;38243.80878095703;39996.60003399929;40215.597844021184;37678.2464350706;38149;39144.80427597844;39289.410658839566;39324.606753932094;31658;33509.664903350655;33705.66294337124;34127.658723412445;34229.82885085658;25964.870175649758;26069.608955865297;26276;26295;26374.0317226114 05:04:29 INFO - PROCESS | 22422 | |2;jquery.html;371.8809980806143;395.5589517687779;399.5165849322319;408.71798459063245;424.5902703890745;13303;15097.169655668933;17972.910135449336;18504.814951850476;18588.16353264103;18328.81671183288;18781.530461738428;19076.809231907675;19293.228270869182;19297.710534341997;5290.576753859701;5698.6865722385255;5916.112583112525;5916.67458289796;5921.230240068785;5099.821506247264;5821.417858214166;5858.619189752652;5858.648481091127;5859.472647461727;5674.3190817102;5792.131180322965;5801.82594522165;5807.361190269067;5812.331581868097;5803.332616749065;6029.336772954972;6035.577509574354;6037.486813620848;6043.395660433944;5117.334746482933;5319.20211968204;5361.544268737162;5856.472917437451;5857;20833.479163020893;20941.47646308839;21272.46818829526;21622.35132946014;22043;2782.0680072175846;2845.089571337173;2924.5613158026254;2929;2941.4705353036443;9438.622455101788;9629.614815407374;11548;13497.12268702541;14003;1899.9335023274118;1904.7428597139346;1913.206019501905;1916.3675986924281;1918.9136488857896;69163.65418172877;72574;73495.26504734991;73514.26485735181;75001;8981.550922453851;9481.09929556691;9539.666111686058;9567.569459374345;9985.600575977025;12915.354232288348;12962.935185324013;12981.156224845356;12987.870121298665;13026.869731302566;6888.896666550006;6985.965070174617;6993.2657071006815;7144.785656430316;7187.245339239409;6690.528083821551;6692.531522793455;6736.393724564813;6852.246252912176;6865.656717164122;4068.9796551017057;4097.508299004138;4099.8975025624;4142.689298302639;4162.687798415131;1974.8321392681496;2003.5191554026928;2016;2023.3019608235136;2035.603057403792;997.6558087459815;1017.7811770469384;1017.9389236645825;1022.6880801355658;1030.2324768047852;16970.06664633457;18473.98393088393;18910.81089189118;19549.218031278873;19629.50926226856;943.9905600943903;951.3958636266021;963.1283688262134;964.4984608003799;964.6671898195111;9866.358686685342;10133.594656213669;10187.847182292271;10286.691399258034;10574.788504229873;337498;399480;455292;456256;458146;82684.17315826885;82707;82836.17163828405;83555.5822220885;85488.57255713681;1090.9509072091776;1091.901728844408;1095.1129585036147;1097.6542389147394;1108.800415925125;15430.768538472157;15547.611309717347;15903;15925.522234332988;15958.521244362688;38887.222255555294;39446.21107577775;39646.80176599098;39971.2005759889;40009.399859001554;51687.2246916289;52035;52161.47838521566;53380.46619533755;53410.198847016545;8262.958685206535;8418.326533877153;8506.957465212634;8641.956790216009;8695.912980884668;8220.383471239562;11309;12419.254844708985;13131.146475479063;13131.868681313446;11148.944255278997;11275.548978040786;11355.886441135483;11422.771544569228;11459.885401146215;11099.833502497308;11272.26730262563;11490.08835102809;11551.249168804;11616.651500455;11264.324140551593;11399.544018239178;11568.305901645928;11591.478383472933;11957.820632690342;8559.828803423772;8603.311735061305;8715.91284087151;8733.825323493622;8884.111588841322;8758.956205218934;10681.732956676145;11441.199116061796;11526.654200374001;11865.881341186823 05:04:29 INFO - PROCESS | 22422 | |3;mootools.html;1351.918884866908;1365.781474964006;1377.249399077503;1377.5936098850839;1378.8069670246164;8044.074931382893;8506.872396914052;8545.529995850226;8581.914180858183;8584.399092063559;7255.74604888829;7494.250574942503;8052.396070294737;8247.21651443112;8428.662853485868;2326.3137374474527;2369.3010561884284;2371.336025912747;2373.738888722248;2375.3824005758543;2318.119114736398;2364.030747393569;2369.869657168855;2369.9644505332435;2373.3354660694945;1930.5849242412878;1987.930422435215;2191.5726433345503;2261.7512073671883;2279.794818466346;2137.6472881974455;2137.8824164670937;2141.0793358855685;2145.538709177534;2148.215901196068;2191.539776646906;2210.1380461619983;2298.2645553422913;2327.0459111764094;2334.463073493106;8178.427510074297;8264.256216940445;8273.958630206871;8315.501069935823;8334.49993000422;5105.5149760772665;5262.842114736526;5267.367915850123;5270.868228294285;5275.182346736262;3331.600207975034;3367.124547617637;3374.561307030095;3388.15296175956;3536.717062634983;1371.1156304183846;1398.930053497321;1401.8808401285903;1436.4397884824928;1495;11696.941515292454;11763;11771.646850594496;11781.116416268815;11788.115891308273;4356.716813407182;4567.885802854888;4638.257878739386;4642.930356044662;4686.859394218179;9779.266555008402;9802.362846415102;9809.411435313761;9812.116909478182;9814.411135331902;5185.740712964337;5261.736913154404;5267.631265811357;5270.815521456807;5272.261883336336;4194.916101677949;4207.537170911197;4626.306054091846;4668.206404911173;4798.976005119952;3752.1745216052973;3836.7506112102624;3840;3893.902652433712;3921.3725803871243;1268.2580690296095;1273.8216649669055;1275.2603489975793;1278.9232646041082;1282.5703389364667;901.4681338010605;924.8057907839429;934.1125930366044;936.1013427109915;946.068122898944;5563.805266815642;5634.295713035871;5669.149627555899;5681.602287839812;5934.376890426443;928.5171710710392;951.5955718819474;953.6376177052675;961.4327546747453;962.0667952086465;3587.695045921074;3803.1633040730994;3990.38149086892;4007.3988901664984;4030.1133750574827;10248.846267305997;10430.843537346946;10515.894841051644;10560.049595536439;10576.947115264375;10095.091441770275;10112.848307275397;10429.061384475433;10462.843057354146;10465.110465610509;7895.131535531087;8060.234277743605;8063.677452901936;8067.717629883044;8072.071711753224;5433.9456605435025;5437.483439073281;5465.098258787256;5550.639208451437;5664.433556644302;9328.300377471582;9370.297227708086;9458.196053335543;9470.052994700474;9484.95257523708;8823.338249631175;9160.083991601054;9168;9518.476483793458;9566.330356874952;6683.6324002180345;6849.589024658537;6851.691673874788;7231.963840180765;7396.667149978156;7558.886616700864;7589.886151707618;7928.127905930228;8136.959315203386;8142.674293028213;7405.518641288299;7412.888806668012;7536.962315188608;7633.274838890296;7692.92307076922;7054.259302773135;7148.356647901714;7178.102737157856;7200.675969581486;7411.4070874331;6457.7094030767785;6561.081448597105;6562.048502967138;6565.441937435371;6565.671716414256;5004.824831130893;5034.219695947134;5095.806927081548;5200.063988482109;5237.8428647140645;4887.0714564232685;5078.08594453002;5145.408278048072;5151.278820965143;5161.587073034073 05:04:29 INFO - PROCESS | 22422 | |4;prototype.html;390.3500671382149;391.627953444228;391.7218774669984;394.7986526871295;401.2296390929415;5126.82110103206;5199.662021968569;5208.791648334071;5210.817621383243;5214.869628259291;5017;5088.745562721868;5104.948950510494;5170.844874653757;5205.973970130153;4133.379993001044;4136.193442278757;4148.97925510372;4158.792060396983;4169.687273454488;4153.356229784388;4164.625183733463;4165.354370072643;4181.853635122771;4191.1617676464675;4054.513458384998;4143.834246630131;4149.983254102734;4151.937720934188;4165.541790403068;4086.223617512663;4098.299205829577;4123.587641235852;4124.608162224614;4137.668986481074;3731.7014638828828;3746.1009357754215;3748.475213470116;3748.6438788315068;3759.4360845873066;5024.8492545223335;5032.3961124665275;5043.344365232533;5043.5460808527405;5053.4946505349535;4629.722216667011;4674.228752255874;4687.906241875144;4748.361815173759;4753.928691069636;5347.919781203285;5415.566754659618;5421.512063914227;5434.3207099112615;5439.918401223985;4095.3380302803116;4112.670986321087;4127.09203975125;4141.937870931938;4144.7513149210845;5363.517283444469;5419.403865574784;5539.279893613804;5598.2442370280305;5615.298087739033;3973.8807835764974;4038.9394159087637;4044.595540445932;4070;4084.9387259191135;4110.527289361762;4125.1749650070105;4125.854555631055;4142.233686767958;4152.667786577066;4420.336949457543;4431.8670439886855;4649.883752906205;4654.208784506642;4682.555157260055;5397.379301380313;5437.34751829783;5443.2107344434835;5462.3445186577865;5466.262054622654;1361.9931900340434;1369.6165073779357;1370.390176371525;1372.6753682696246;1373.7527245095978;918.8152733572739;957.6791774755395;963.5471328475605;964.4165280005495;975.658519518163;5120.590352771844;5137.794488220429;5164.612654050961;5171.560417364566;5180.274761533313;5413.485718856702;5417.729113544307;5425.864353391118;5428.6471379361;5442.537384322298;5006.148954677714;5108.540231379194;5146.536811686966;5169.81905633301;5192.870178245574;2880.4527139843362;2880.7119288071026;2881.501619158068;2885.480613489592;2900.2894290898867;5652.74562644682;5665.66006039639;5672.602917795797;5675.177099320658;5682.659040457585;5371.630234290231;5512.283403157644;5552.444755524496;5564.972175139098;5565.276514053228;5699.344575373802;5721.57088218377;5725.255716756878;5728.937582470391;5740.913886291793;5247.816326428557;5393.730313484389;5410.864728381822;5434.945650543602;5475.397706252389;5264.0261551613075;5264.6314757966575;5282.894342113059;5309.601779866448;5332.013577488177;5536.557075433875;5589.329280486368;5590.5248053915875;5599.356074051536;5716.914246286392;4212.641925436372;4280.636145927631;4317.892052698708;4320.697551171388;4329.826806927687;5610.298712660918;5618.634788738719;5627.960901113516;5628.802991895264;5629.746661400165;5365.275687782099;5378.462153784591;5431.5926305526455;5459.590530710292;5471.185356885915;5457.945420545744;5462.808801691922;5495.2856128703015;5498.3127109111365;5505.779768809202;5232.03207406631;5419.078756611307;5421.484958929053;5427.294451721251;5484.533814625642;5436.972815135899;5450.645708028966;5451.48210919962;5472.315960504937;5476.110741794075;5267.420583735862;5298.788048478172;5318.228856815663;5352.330958630171;5399.973000134974 05:04:29 INFO - PROCESS | 22422 | |5;yui.html;738.8042168825259;745.4744405194335;788.3299195683669;817.7996390884231;821.1378053044303;15157.39370425183;15789.60525986851;15843;15938.521844344674;15973.201339933;13000.479980800757;14389.352479138437;14535.92732036341;14562.854371456282;14912.328945197465;811.9594020298977;838.1116017021976;838.534613289624;838.5807096451774;839.5382539603194;821.9712310069149;825.0717942314895;825.1377310710292;825.5748289630815;826.2192228344187;859.3769517100084;873.9825203495926;886.7694399456124;887.8623813308915;888.6045709659204;866.5883705240024;878.8637761147;879.2526352600271;879.3536750488354;880.5905254056894;628.7736414890636;636.9903702631335;643.0129750832497;643.0771842406162;643.2088531106765;14534.418623255162;14718.852811471856;14889.255537223096;15029.699406011818;15720.449784257497;14492.34784434703;14708;14927.2536373182;14965.326560304813;15029.699406011929;12694.174878632859;12740.808887866597;12768;12799.424025918855;12815.551455699098;3749.250149970017;3754.887353379376;3804.9429258561145;3815.065308999313;3858;54802.17796733052;56214;66962;69279;70757.64621176862;12325.383730813424;12468.690787467367;12576.245425274512;12601.558945436866;12730.427130779137;18785.90607046956;19873.60252794936;23547.7645223549;23558.882205589205;23654.290371288887;8714.69498567547;8895.110488951053;8908.064653211319;9030.774230644287;9115.088491150964;4657.790399432034;4714.22215334466;4768.61851051915;4797.904041919142;4952.430470495867;3535.7878527288444;3639.1084184375;3642.5993140754495;3642.908927276839;3647.598764135943;620.6214209332304;622.5859803230892;625.0530446373748;627.2003195925232;637.4422380417135;723.9493235473466;732.8131326511706;737.6533029476245;754.4304050441991;776.2276534847863;9528.90471095294;10182.18542516597;10217.284790064625;10424;10626.574937002433;714.5034201230095;753.8153152477678;757.5757575757593;766.0730516075611;767.1293082351459;4797.856064318076;4956.42484778541;4968.335607571432;5014.147594908802;5023.899522009612;74235.25764742284;74484;75544.24455755482;75816;77105.6144719284;68843.64079926856;71903;73379.26620733725;74508;75436.62281688667;41090;47880.5211947883;52794.73602632039;52908.206376904374;61192.38807611867;9729.610815567297;10206.336588121749;10233.678104306759;10359.585616575554;10465.529051192563;41547.97554354541;42110.36834447547;43329.783351083046;43602;44075.81279778416;50526;51430;51567;51843.148626824375;52026.21960670668;13610.795838062239;13805.64387917337;13815.79276310836;13860.514881979083;14803.259837008321;14864.331105100513;15444.2277886103;15483.225838708246;15577.065376077471;15641.921790390976;13938;14143.858561414665;14184.36170372315;14252.928735356258;14864.33110510008;14189.361478733688;14206.715865682836;14242.173445468123;14468.710625787637;14501;13551.72896542044;13719.1082579636;13771.793423098461;13957.651058723613;13984.860151398356;11387.259828111146;11898.167128301282;11983.400829958642;12220.755584888075;12432.627021189379;11384.943075284571;11460.598879039193;11524.654260372203;11553.537858485566;11565.537378505102 05:04:29 INFO - PROCESS | 22422 | __end_tp_report 05:04:29 INFO - PROCESS | 22422 | __start_cc_report 05:04:29 INFO - PROCESS | 22422 | _x_x_mozilla_cycle_collect,1386 05:04:29 INFO - PROCESS | 22422 | __end_cc_report 05:04:29 INFO - PROCESS | 22422 | __startTimestamp1482843869063__endTimestamp 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | ------- Summary: start ------- 05:04:29 INFO - PROCESS | 22422 | Number of tests: 6 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | [#0] dojo.html Cycles:165 Average:5354.10 Median:5589.13 stddev:618.89 (11.1%) stddev-sans-first:595.22 05:04:29 INFO - PROCESS | 22422 | Values: 3110.0 3132.0 3145.5 3167.0 3179.5 5295.2 5304.2 5354.0 5407.4 5418.3 5387.1 5415.3 5417.8 5426.3 5467.1 4848.8 4897.9 4911.9 4948.2 4998.9 4937.2 4956.4 4995.2 4999.5 5003.4 4944.8 4947.0 4989.4 4999.6 5022.0 4797.1 4824.0 4838.7 4865.9 4876.0 4852.6 4882.0 4894.3 4928.6 4929.2 5351.5 5376.5 5394.4 5397.9 5401.5 5498.4 5523.6 5732.8 5738.8 5763.7 5789.5 5800.6 5808.3 5846.3 5852.4 5381.2 5381.5 5384.9 5398.4 5430.5 5713.8 5760.4 5763.7 5949.8 5968.0 5626.7 5639.8 5660.8 5670.3 5758.3 5736.5 5743.2 5773.1 5793.3 5796.7 5612.8 5663.3 5665.3 5674.9 5792.1 5722.9 5758.4 5767.2 5805.7 5820.9 4329.8 4332.0 4333.5 4333.8 4367.3 3831.3 3843.4 3873.1 3895.5 3908.3 5344.5 5396.6 5399.1 5433.0 5489.1 5731.9 5738.7 5745.3 5747.5 5755.9 5360.4 5377.9 5444.4 5446.8 5494.6 4700.6 4785.3 4833.2 4844.2 4900.9 6140.1 6142.7 6145.3 6145.3 6145.5 5841.3 5847.9 5853.1 5858.2 5861.9 5905.5 5934.3 5954.6 5956.7 5975.2 5627.8 5685.9 5686.8 5689.3 5732.3 5738.3 5783.7 5790.5 5791.9 5794.0 5540.8 5589.1 5596.7 5615.9 5650.9 5661.4 5665.4 5672.1 5700.3 5713.6 5665.1 5681.5 5682.0 5684.6 5687.5 5487.9 5521.6 5543.5 5634.9 5694.7 5564.9 5605.1 5605.3 5644.6 5665.0 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | [#1] ext.html Cycles:180 Average:54550.42 Median:39217.11 stddev:99649.87 (254.1%) stddev-sans-first:99846.90 05:04:29 INFO - PROCESS | 22422 | Values: 540.1 572.8 574.5 575.4 583.6 33149.0 35912.8 41358.8 43089.4 43172.4 45710.5 46171.5 46211.5 46310.8 46393.5 8891.7 9076.1 9076.4 9078.9 9079.8 8685.5 8868.6 8977.5 10650.5 10883.7 10948.7 11002.4 11024.8 11026.8 11031.3 10960.1 11036.8 11041.5 11043.8 11045.3 10795.1 10855.8 10863.6 10869.8 10872.5 41795.0 42166.8 42180.8 42182.6 42262.0 4059.5 4102.2 4185.4 4200.9 4220.5 31282.0 31522.5 31689.5 31798.2 32038.7 4203.9 4248.9 4317.3 4333.2 4357.9 311703.0 314199.0 315128.0 315298.0 315940.0 57552.0 57850.7 57951.7 58012.0 58013.0 61115.7 61289.4 61342.4 61496.0 61568.7 12989.5 13228.4 13228.8 13231.9 13241.5 9623.0 10850.4 10966.3 10976.9 10976.9 15403.8 17014.5 17133.3 17547.7 18011.1 6184.7 6188.3 6287.1 6363.6 6370.9 875.7 882.5 887.1 896.5 911.5 30963.8 31863.2 32231.7 32289.0 32561.5 1043.3 1045.8 1053.3 1057.8 1062.9 15444.8 16713.4 16713.7 16748.8 16798.6 441485.0 443198.0 443855.0 445804.0 446196.0 57728.0 57742.4 57784.0 57847.0 57889.0 1343.7 1364.0 1379.2 1380.3 1389.3 28418.4 30044.5 30548.7 30654.4 30746.2 50515.5 50966.2 50978.7 51018.2 51121.5 362217.0 363991.0 366328.0 366680.0 367448.0 40620.2 40801.9 40805.2 41044.6 41046.0 44985.0 45078.1 45237.5 46092.0 46571.8 38466.6 38721.8 39593.6 39605.2 40594.4 37963.8 38049.2 38243.8 39996.6 40215.6 37678.2 38149.0 39144.8 39289.4 39324.6 31658.0 33509.7 33705.7 34127.7 34229.8 25964.9 26069.6 26276.0 26295.0 26374.0 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | [#2] jquery.html Cycles:180 Average:26009.71 Median:199939.76 stddev:69976.05 (35.0%) stddev-sans-first:70145.87 05:04:29 INFO - PROCESS | 22422 | Values: 371.9 395.6 399.5 408.7 424.6 13303.0 15097.2 17972.9 18504.8 18588.2 18328.8 18781.5 19076.8 19293.2 19297.7 5290.6 5698.7 5916.1 5916.7 5921.2 5099.8 5821.4 5858.6 5858.6 5859.5 5674.3 5792.1 5801.8 5807.4 5812.3 5803.3 6029.3 6035.6 6037.5 6043.4 5117.3 5319.2 5361.5 5856.5 5857.0 20833.5 20941.5 21272.5 21622.4 22043.0 2782.1 2845.1 2924.6 2929.0 2941.5 9438.6 9629.6 11548.0 13497.1 14003.0 1899.9 1904.7 1913.2 1916.4 1918.9 69163.7 72574.0 73495.3 73514.3 75001.0 8981.6 9481.1 9539.7 9567.6 9985.6 12915.4 12962.9 12981.2 12987.9 13026.9 6888.9 6986.0 6993.3 7144.8 7187.2 6690.5 6692.5 6736.4 6852.2 6865.7 4069.0 4097.5 4099.9 4142.7 4162.7 1974.8 2003.5 2016.0 2023.3 2035.6 997.7 1017.8 1017.9 1022.7 1030.2 16970.1 18474.0 18910.8 19549.2 19629.5 944.0 951.4 963.1 964.5 964.7 9866.4 10133.6 10187.8 10286.7 10574.8 337498.0 399480.0 455292.0 456256.0 458146.0 82684.2 82707.0 82836.2 83555.6 85488.6 1091.0 1091.9 1095.1 1097.7 1108.8 15430.8 15547.6 15903.0 15925.5 15958.5 38887.2 39446.2 39646.8 39971.2 40009.4 51687.2 52035.0 52161.5 53380.5 53410.2 8263.0 8418.3 8507.0 8642.0 8695.9 8220.4 11309.0 12419.3 13131.1 13131.9 11148.9 11275.5 11355.9 11422.8 11459.9 11099.8 11272.3 11490.1 11551.2 11616.7 11264.3 11399.5 11568.3 11591.5 11957.8 8559.8 8603.3 8715.9 8733.8 8884.1 8759.0 10681.7 11441.2 11526.7 11865.9 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | [#3] mootools.html Cycles:180 Average:5531.53 Median:5218.95 stddev:3073.53 (58.9%) stddev-sans-first:3066.10 05:04:29 INFO - PROCESS | 22422 | Values: 1351.9 1365.8 1377.2 1377.6 1378.8 8044.1 8506.9 8545.5 8581.9 8584.4 7255.7 7494.3 8052.4 8247.2 8428.7 2326.3 2369.3 2371.3 2373.7 2375.4 2318.1 2364.0 2369.9 2370.0 2373.3 1930.6 1987.9 2191.6 2261.8 2279.8 2137.6 2137.9 2141.1 2145.5 2148.2 2191.5 2210.1 2298.3 2327.0 2334.5 8178.4 8264.3 8274.0 8315.5 8334.5 5105.5 5262.8 5267.4 5270.9 5275.2 3331.6 3367.1 3374.6 3388.2 3536.7 1371.1 1398.9 1401.9 1436.4 1495.0 11696.9 11763.0 11771.6 11781.1 11788.1 4356.7 4567.9 4638.3 4642.9 4686.9 9779.3 9802.4 9809.4 9812.1 9814.4 5185.7 5261.7 5267.6 5270.8 5272.3 4194.9 4207.5 4626.3 4668.2 4799.0 3752.2 3836.8 3840.0 3893.9 3921.4 1268.3 1273.8 1275.3 1278.9 1282.6 901.5 924.8 934.1 936.1 946.1 5563.8 5634.3 5669.1 5681.6 5934.4 928.5 951.6 953.6 961.4 962.1 3587.7 3803.2 3990.4 4007.4 4030.1 10248.8 10430.8 10515.9 10560.0 10576.9 10095.1 10112.8 10429.1 10462.8 10465.1 7895.1 8060.2 8063.7 8067.7 8072.1 5433.9 5437.5 5465.1 5550.6 5664.4 9328.3 9370.3 9458.2 9470.1 9485.0 8823.3 9160.1 9168.0 9518.5 9566.3 6683.6 6849.6 6851.7 7232.0 7396.7 7558.9 7589.9 7928.1 8137.0 8142.7 7405.5 7412.9 7537.0 7633.3 7692.9 7054.3 7148.4 7178.1 7200.7 7411.4 6457.7 6561.1 6562.0 6565.4 6565.7 5004.8 5034.2 5095.8 5200.1 5237.8 4887.1 5078.1 5145.4 5151.3 5161.6 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | [#4] prototype.html Cycles:180 Average:4586.06 Median:5202.82 stddev:1303.91 (25.1%) stddev-sans-first:1268.97 05:04:29 INFO - PROCESS | 22422 | Values: 390.4 391.6 391.7 394.8 401.2 5126.8 5199.7 5208.8 5210.8 5214.9 5017.0 5088.7 5104.9 5170.8 5206.0 4133.4 4136.2 4149.0 4158.8 4169.7 4153.4 4164.6 4165.4 4181.9 4191.2 4054.5 4143.8 4150.0 4151.9 4165.5 4086.2 4098.3 4123.6 4124.6 4137.7 3731.7 3746.1 3748.5 3748.6 3759.4 5024.8 5032.4 5043.3 5043.5 5053.5 4629.7 4674.2 4687.9 4748.4 4753.9 5347.9 5415.6 5421.5 5434.3 5439.9 4095.3 4112.7 4127.1 4141.9 4144.8 5363.5 5419.4 5539.3 5598.2 5615.3 3973.9 4038.9 4044.6 4070.0 4084.9 4110.5 4125.2 4125.9 4142.2 4152.7 4420.3 4431.9 4649.9 4654.2 4682.6 5397.4 5437.3 5443.2 5462.3 5466.3 1362.0 1369.6 1370.4 1372.7 1373.8 918.8 957.7 963.5 964.4 975.7 5120.6 5137.8 5164.6 5171.6 5180.3 5413.5 5417.7 5425.9 5428.6 5442.5 5006.1 5108.5 5146.5 5169.8 5192.9 2880.5 2880.7 2881.5 2885.5 2900.3 5652.7 5665.7 5672.6 5675.2 5682.7 5371.6 5512.3 5552.4 5565.0 5565.3 5699.3 5721.6 5725.3 5728.9 5740.9 5247.8 5393.7 5410.9 5434.9 5475.4 5264.0 5264.6 5282.9 5309.6 5332.0 5536.6 5589.3 5590.5 5599.4 5716.9 4212.6 4280.6 4317.9 4320.7 4329.8 5610.3 5618.6 5628.0 5628.8 5629.7 5365.3 5378.5 5431.6 5459.6 5471.2 5457.9 5462.8 5495.3 5498.3 5505.8 5232.0 5419.1 5421.5 5427.3 5484.5 5437.0 5450.6 5451.5 5472.3 5476.1 5267.4 5298.8 5318.2 5352.3 5400.0 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - PROCESS | 22422 | [#5] yui.html Cycles:180 Average:17031.79 Median:42720.08 stddev:20592.05 (48.2%) stddev-sans-first:20613.47 05:04:29 INFO - PROCESS | 22422 | Values: 738.8 745.5 788.3 817.8 821.1 15157.4 15789.6 15843.0 15938.5 15973.2 13000.5 14389.4 14535.9 14562.9 14912.3 812.0 838.1 838.5 838.6 839.5 822.0 825.1 825.1 825.6 826.2 859.4 874.0 886.8 887.9 888.6 866.6 878.9 879.3 879.4 880.6 628.8 637.0 643.0 643.1 643.2 14534.4 14718.9 14889.3 15029.7 15720.4 14492.3 14708.0 14927.3 14965.3 15029.7 12694.2 12740.8 12768.0 12799.4 12815.6 3749.3 3754.9 3804.9 3815.1 3858.0 54802.2 56214.0 66962.0 69279.0 70757.6 12325.4 12468.7 12576.2 12601.6 12730.4 18785.9 19873.6 23547.8 23558.9 23654.3 8714.7 8895.1 8908.1 9030.8 9115.1 4657.8 4714.2 4768.6 4797.9 4952.4 3535.8 3639.1 3642.6 3642.9 3647.6 620.6 622.6 625.1 627.2 637.4 723.9 732.8 737.7 754.4 776.2 9528.9 10182.2 10217.3 10424.0 10626.6 714.5 753.8 757.6 766.1 767.1 4797.9 4956.4 4968.3 5014.1 5023.9 74235.3 74484.0 75544.2 75816.0 77105.6 68843.6 71903.0 73379.3 74508.0 75436.6 41090.0 47880.5 52794.7 52908.2 61192.4 9729.6 10206.3 10233.7 10359.6 10465.5 41548.0 42110.4 43329.8 43602.0 44075.8 50526.0 51430.0 51567.0 51843.1 52026.2 13610.8 13805.6 13815.8 13860.5 14803.3 14864.3 15444.2 15483.2 15577.1 15641.9 13938.0 14143.9 14184.4 14252.9 14864.3 14189.4 14206.7 14242.2 14468.7 14501.0 13551.7 13719.1 13771.8 13957.7 13984.9 11387.3 11898.2 11983.4 12220.8 12432.6 11384.9 11460.6 11524.7 11553.5 11565.5 05:04:29 INFO - PROCESS | 22422 | -------- Summary: end -------- 05:04:29 INFO - PROCESS | 22422 | 05:04:29 INFO - TEST-INFO | 22422: exit 0 05:04:29 INFO - TEST-OK | dromaeo_css | took 1116314ms 05:04:29 INFO - TEST-START | kraken 05:04:29 INFO - operating with platform_type : linux_ 05:04:29 INFO - Initialising browser for kraken test... 05:04:29 INFO - TEST-INFO | started process 22719 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUSfRjD/profile http://localhost:43142/getInfo.html) 05:04:30 INFO - PROCESS | 22719 | 1482843870405 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 05:04:34 INFO - PROCESS | 22719 | __metrics Screen width/height:1600/1200 05:04:34 INFO - PROCESS | 22719 | colorDepth:24 05:04:34 INFO - PROCESS | 22719 | Browser inner width/height: 1024/697 05:04:34 INFO - PROCESS | 22719 | __metrics 05:04:34 INFO - PROCESS | 22719 | 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:04:34 INFO - PROCESS | 22719 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:04:35 INFO - PROCESS | 22719 | console.error: 05:04:35 INFO - PROCESS | 22719 | Message: Error: Connection closed before committing the transaction. 05:04:35 INFO - PROCESS | 22719 | Stack: 05:04:35 INFO - PROCESS | 22719 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 05:04:35 INFO - PROCESS | 22719 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 05:04:35 INFO - PROCESS | 22719 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 05:04:35 INFO - PROCESS | 22719 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 05:04:35 INFO - PROCESS | 22719 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 05:04:35 INFO - PROCESS | 22719 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 05:04:35 INFO - PROCESS | 22719 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 05:04:35 INFO - PROCESS | 22719 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 05:04:35 INFO - PROCESS | 22719 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 05:04:35 INFO - PROCESS | 22719 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 05:04:35 INFO - PROCESS | 22719 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:04:35 INFO - PROCESS | 22719 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:04:35 INFO - PROCESS | 22719 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:04:35 INFO - PROCESS | 22719 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:04:35 INFO - PROCESS | 22719 | 05:04:35 INFO - PROCESS | 22719 | console.error: PushService: 05:04:35 INFO - PROCESS | 22719 | stateChangeProcessEnqueue: Error transitioning state 05:04:35 INFO - PROCESS | 22719 | UnknownError 05:04:36 INFO - TEST-INFO | 22719: exit 0 05:04:36 INFO - Browser initialized. 05:04:36 INFO - Running cycle 1/1 for kraken test... 05:04:36 INFO - TEST-INFO | started process 22803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUSfRjD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:48 INFO - PROCESS | 22803 | RSS: Main: 147038208 05:04:48 INFO - PROCESS | 22803 | 05:04:50 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43142/tests/kraken/driver.html?testName=audio-beat-detection) 05:04:51 INFO - PROCESS | 22803 | RSS: Main: 201682944 05:04:51 INFO - PROCESS | 22803 | 05:04:53 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43142/tests/kraken/driver.html?testName=audio-dft) 05:04:54 INFO - PROCESS | 22803 | RSS: Main: 180502528 05:04:54 INFO - PROCESS | 22803 | 05:04:57 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43142/tests/kraken/driver.html?testName=audio-fft) 05:04:58 INFO - PROCESS | 22803 | RSS: Main: 262025216 05:04:58 INFO - PROCESS | 22803 | 05:05:00 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43142/tests/kraken/driver.html?testName=audio-oscillator) 05:05:00 INFO - PROCESS | 22803 | RSS: Main: 174313472 05:05:00 INFO - PROCESS | 22803 | 05:05:02 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43142/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:03 INFO - PROCESS | 22803 | RSS: Main: 177889280 05:05:03 INFO - PROCESS | 22803 | 05:05:06 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43142/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:07 INFO - PROCESS | 22803 | RSS: Main: 216788992 05:05:07 INFO - PROCESS | 22803 | 05:05:10 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43142/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:11 INFO - PROCESS | 22803 | RSS: Main: 216776704 05:05:11 INFO - PROCESS | 22803 | 05:05:13 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43142/tests/kraken/driver.html?testName=json-parse-financial) 05:05:14 INFO - PROCESS | 22803 | RSS: Main: 214573056 05:05:14 INFO - PROCESS | 22803 | 05:05:15 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43142/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:16 INFO - PROCESS | 22803 | RSS: Main: 177324032 05:05:16 INFO - PROCESS | 22803 | 05:05:18 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:18 INFO - PROCESS | 22803 | RSS: Main: 184795136 05:05:18 INFO - PROCESS | 22803 | 05:05:20 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:21 INFO - PROCESS | 22803 | RSS: Main: 192626688 05:05:21 INFO - PROCESS | 22803 | 05:05:24 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:24 INFO - PROCESS | 22803 | RSS: Main: 203464704 05:05:24 INFO - PROCESS | 22803 | 05:05:27 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:28 INFO - PROCESS | 22803 | RSS: Main: 199876608 05:05:28 INFO - PROCESS | 22803 | 05:05:29 INFO - PROCESS | 22803 | Cycle 1(1): loaded http://localhost:43142/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43142/tests/kraken/driver.html?testName=ai-astar) 05:05:30 INFO - PROCESS | 22803 | RSS: Main: 251215872 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | __start_tp_report 05:05:30 INFO - PROCESS | 22803 | _x_x_mozilla_page_load 05:05:30 INFO - PROCESS | 22803 | _x_x_mozilla_page_load_details 05:05:30 INFO - PROCESS | 22803 | |i|pagename|runs| 05:05:30 INFO - PROCESS | 22803 | |0;ai-astar;109;111;110;111;112;113;111;110;111;110 05:05:30 INFO - PROCESS | 22803 | |1;audio-beat-detection;148;119;119;161;121;119;120;119;135;118 05:05:30 INFO - PROCESS | 22803 | |2;audio-dft;162;161;162;160;161;160;161;162;161;162 05:05:30 INFO - PROCESS | 22803 | |3;audio-fft;74;75;76;112;77;77;77;75;95;76 05:05:30 INFO - PROCESS | 22803 | |4;audio-oscillator;83;83;108;82;82;82;101;83;83;112 05:05:30 INFO - PROCESS | 22803 | |5;imaging-gaussian-blur;117;117;116;116;117;117;116;117;117;117 05:05:30 INFO - PROCESS | 22803 | |6;imaging-darkroom;146;146;147;146;146;145;147;146;147;146 05:05:30 INFO - PROCESS | 22803 | |7;imaging-desaturate;96;96;96;96;95;96;95;95;96;96 05:05:30 INFO - PROCESS | 22803 | |8;json-parse-financial;49;49;50;49;51;53;51;50;50;51 05:05:30 INFO - PROCESS | 22803 | |9;json-stringify-tinderbox;66;68;67;66;67;67;66;67;67;66 05:05:30 INFO - PROCESS | 22803 | |10;stanford-crypto-aes;81;81;81;81;81;82;80;81;85;81 05:05:30 INFO - PROCESS | 22803 | |11;stanford-crypto-ccm;140;124;124;124;131;123;122;125;166;124 05:05:30 INFO - PROCESS | 22803 | |12;stanford-crypto-pbkdf2;168;163;168;165;165;165;164;169;169;167 05:05:30 INFO - PROCESS | 22803 | |13;stanford-crypto-sha256-iterative;63;62;63;62;61;63;62;62;70;62 05:05:30 INFO - PROCESS | 22803 | __end_tp_report 05:05:30 INFO - PROCESS | 22803 | __start_cc_report 05:05:30 INFO - PROCESS | 22803 | _x_x_mozilla_cycle_collect,1542 05:05:30 INFO - PROCESS | 22803 | __end_cc_report 05:05:30 INFO - PROCESS | 22803 | __startTimestamp1482843930055__endTimestamp 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | ------- Summary: start ------- 05:05:30 INFO - PROCESS | 22803 | Number of tests: 14 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:1.14 (1.0%) stddev-sans-first:1.00 05:05:30 INFO - PROCESS | 22803 | Values: 109.0 111.0 110.0 111.0 112.0 113.0 111.0 110.0 111.0 110.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:120.50 stddev:15.18 (12.6%) stddev-sans-first:14.26 05:05:30 INFO - PROCESS | 22803 | Values: 148.0 119.0 119.0 161.0 121.0 119.0 120.0 119.0 135.0 118.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 05:05:30 INFO - PROCESS | 22803 | Values: 162.0 161.0 162.0 160.0 161.0 160.0 161.0 162.0 161.0 162.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.50 stddev:12.36 (16.2%) stddev-sans-first:12.81 05:05:30 INFO - PROCESS | 22803 | Values: 74.0 75.0 76.0 112.0 77.0 77.0 77.0 75.0 95.0 76.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:82.50 stddev:12.10 (14.7%) stddev-sans-first:12.57 05:05:30 INFO - PROCESS | 22803 | Values: 83.0 83.0 108.0 82.0 82.0 82.0 101.0 83.0 83.0 112.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:05:30 INFO - PROCESS | 22803 | Values: 117.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 05:05:30 INFO - PROCESS | 22803 | Values: 146.0 146.0 147.0 146.0 146.0 145.0 147.0 146.0 147.0 146.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:05:30 INFO - PROCESS | 22803 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 96.0 96.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.25 (2.5%) stddev-sans-first:1.24 05:05:30 INFO - PROCESS | 22803 | Values: 49.0 49.0 50.0 49.0 51.0 53.0 51.0 50.0 50.0 51.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 05:05:30 INFO - PROCESS | 22803 | Values: 66.0 68.0 67.0 66.0 67.0 67.0 66.0 67.0 67.0 66.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:1.35 (1.7%) stddev-sans-first:1.42 05:05:30 INFO - PROCESS | 22803 | Values: 81.0 81.0 81.0 81.0 81.0 82.0 80.0 81.0 85.0 81.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:124.50 stddev:13.65 (11.0%) stddev-sans-first:14.02 05:05:30 INFO - PROCESS | 22803 | Values: 140.0 124.0 124.0 124.0 131.0 123.0 122.0 125.0 166.0 124.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:167.50 stddev:2.16 (1.3%) stddev-sans-first:2.20 05:05:30 INFO - PROCESS | 22803 | Values: 168.0 163.0 168.0 165.0 165.0 165.0 164.0 169.0 169.0 167.0 05:05:30 INFO - PROCESS | 22803 | 05:05:30 INFO - PROCESS | 22803 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:62.50 stddev:2.54 (4.1%) stddev-sans-first:2.69 05:05:30 INFO - PROCESS | 22803 | Values: 63.0 62.0 63.0 62.0 61.0 63.0 62.0 62.0 70.0 62.0 05:05:30 INFO - PROCESS | 22803 | -------- Summary: end -------- 05:05:30 INFO - PROCESS | 22803 | 05:05:31 INFO - TEST-INFO | 22803: exit 0 05:05:31 INFO - TEST-OK | kraken | took 61233ms 05:05:31 INFO - SUITE-END | took 1177s 05:05:31 INFO - Completed test suite (00:19:37) 05:05:31 INFO - javascript benchmark 05:05:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3109.953350699739, 3131.9530207046882, 3145.5124455709365, 3167.01822435045, 3179.5071763876595, 5295.205719142131, 5304.2043693446, 5354.0, 5407.351117865856, 5418.349798024233, 5387.0841956867325, 5415.296011518507, 5417.810376636819, 5426.294581704373, 5467.097928841737, 4848.806047758086, 4897.926531102036, 4911.8772030699165, 4948.183549714302, 4998.850034498971, 4937.1606826839525, 4956.40523137224, 4995.175795993657, 4999.450060493343, 5003.449620541737, 4944.777485013183, 4947.010597880439, 4989.401271847383, 4999.550040496355, 5022.0, 4797.136515427222, 4824.0, 4838.733869637169, 4865.8783530411665, 4876.0, 4852.636052296092, 4882.0, 4894.339264199323, 4928.581070609002, 4929.2359684248995, 5351.545118664879, 5376.489233522809, 5394.406615272317, 5397.865053373658, 5401.540869026137, 5498.395176530578, 5523.585731070186, 5732.770689172428, 5738.8278351649515, 5763.6541807491685, 5789.536837053026, 5800.564957628152, 5808.30300363959, 5846.327672317695, 5852.4440178183, 5381.192821076831, 5381.5425688816495, 5384.91922621161, 5398.406175320712, 5430.51125398716, 5713.771449141987, 5760.36635970043, 5763.654180749085, 5949.791757288474, 5967.970160149258, 5626.746796394172, 5639.802606908821, 5660.830175094754, 5670.262865827499, 5758.337791154071, 5736.541076713853, 5743.195952566644, 5773.076307790733, 5793.304803423616, 5796.739146738407, 5612.831615051555, 5663.32040155184, 5665.2635157430095, 5674.858128546737, 5792.073268277056, 5722.914156287659, 5758.424157584292, 5767.22142510764, 5805.738741756547, 5820.912686309709, 4329.783510824446, 4331.956680433218, 4333.458317710286, 4333.783310834446, 4367.301231802896, 3831.2529056834205, 3843.423486476995, 3873.070463088895, 3895.4935858338235, 3908.296506628777, 5344.465553444625, 5396.568274538027, 5399.055165346049, 5433.0, 5489.066858634042, 5731.942680573225, 5738.655680659091, 5745.253117094751, 5747.540196784247, 5755.884882302331, 5360.383555891122, 5377.919331210035, 5444.428335024765, 5446.836594902159, 5494.587905907073, 4700.600448961876, 4785.258284965836, 4833.178359678863, 4844.15227335215, 4900.901981960341, 6140.140380346666, 6142.723577439026, 6145.26256849172, 6145.324014358417, 6145.477634401037, 5841.269841269841, 5847.94152058491, 5853.09277062056, 5858.209141765978, 5861.853453663693, 5905.498032667271, 5934.3175534814045, 5954.583179177416, 5956.672383018976, 5975.163477113295, 5627.803026894039, 5685.914711279252, 5686.800961966312, 5689.260396148474, 5732.283464566929, 5738.282714660668, 5783.710814459176, 5790.507806836297, 5791.913121303268, 5794.0, 5540.750666219949, 5589.133684278943, 5596.6921819298705, 5615.943840561542, 5650.886982260249, 5661.433856614306, 5665.405132461032, 5672.12082127271, 5700.315962084576, 5713.628614140234, 5665.065264231355, 5681.517071049007, 5682.0, 5684.573656975661, 5687.459691329317, 5487.862803429946, 5521.585881058856, 5543.473370029841, 5634.915476267777, 5694.743736531783, 5564.944350556604, 5605.103183490622, 5605.271314729059, 5644.576656750678, 5665.0], "value": 5311.753352658517, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.1465684218934, 572.8453317604246, 574.5260160367695, 575.427449687561, 583.5914859598282, 33149.0, 35912.82043589782, 41358.793206034, 43089.35365969513, 43172.35241471373, 45710.542894571045, 46171.53828461714, 46211.537884621226, 46310.7684461578, 46393.536064639346, 8891.688790892336, 9076.092390760938, 9076.41003334781, 9078.909210907905, 9079.818403631924, 8685.478871267713, 8868.600912958933, 8977.46135231885, 10650.467476626176, 10883.727906802314, 10948.726281842937, 11002.394868282241, 11024.834627480594, 11026.834597481004, 11031.338119712844, 10960.068394186505, 11036.834447483294, 11041.503132359063, 11043.83434248487, 11045.28205666629, 10795.136389088853, 10855.837162442569, 10863.619773307897, 10869.83695244572, 10872.510737016853, 41795.0, 42166.78916605428, 42180.78909605432, 42182.57817421817, 42262.0, 4059.451973983504, 4102.200070986159, 4185.372194170868, 4200.936985945214, 4220.53574106848, 31282.0, 31522.52716209259, 31689.52465713016, 31798.205044873834, 32038.679613203803, 4203.873883783461, 4248.893777655552, 4317.287647538153, 4333.1766964276685, 4357.9128417431475, 311703.0, 314199.0, 315128.0, 315298.0, 315940.0, 57552.0, 57850.710746446, 57951.71024144852, 58012.0, 58012.97753426836, 61115.6944215285, 61289.38710612926, 61342.386576133664, 61496.0, 61568.69215653893, 12989.480420783251, 13228.404721787543, 13228.801567976489, 13231.867681323256, 13241.470341186438, 9622.951885240529, 10850.403227822546, 10966.287191332698, 10976.945115274373, 10976.945115274373, 15403.845961540464, 17014.48956531306, 17133.314667413164, 17547.73678394825, 18011.099445027696, 6184.742568962296, 6188.288346840171, 6287.1198032275515, 6363.58636688623, 6370.936290637127, 875.6759998800358, 882.52343734384, 887.1350433327583, 896.462122726359, 911.4941207629696, 30963.84518077395, 31863.203419914225, 32231.677683222868, 32289.046193391663, 32561.51157732636, 1043.337480699746, 1045.8222102242637, 1053.3153450257241, 1057.8043062033548, 1062.8671416072991, 15444.768328475082, 16713.41222583877, 16713.749293760604, 16748.83251167497, 16798.572121369576, 441485.0, 443198.0, 443855.0, 445804.0, 446196.0, 57728.0, 57742.422575774544, 57784.0, 57847.0, 57889.0, 1343.7178192579668, 1363.9522616708368, 1379.200063962899, 1380.289151087191, 1389.3122904162503, 28418.431631366842, 30044.549331759605, 30548.723178754546, 30654.386912262074, 30746.231344216572, 50515.49484505108, 50966.23550646669, 50978.74510627423, 51018.234726479874, 51121.48878511316, 362217.0, 363991.0, 366328.0, 366680.0, 367448.0, 40620.1875962485, 40801.946394859166, 40805.183896321316, 41044.58955410407, 41046.0, 44985.0, 45078.0984380304, 45237.54762452334, 46092.0, 46571.76714116408, 38466.615333846305, 38721.80639096787, 39593.60406395899, 39605.20789584135, 40594.39108413435, 37963.810180948916, 38049.239015218984, 38243.80878095703, 39996.60003399929, 40215.597844021184, 37678.2464350706, 38149.0, 39144.80427597844, 39289.410658839566, 39324.606753932094, 31658.0, 33509.664903350655, 33705.66294337124, 34127.658723412445, 34229.82885085658, 25964.870175649758, 26069.608955865297, 26276.0, 26295.0, 26374.0317226114], "value": 19763.406349712262, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.8809980806143, 395.5589517687779, 399.5165849322319, 408.71798459063245, 424.5902703890745, 13303.0, 15097.169655668933, 17972.910135449336, 18504.814951850476, 18588.16353264103, 18328.81671183288, 18781.530461738428, 19076.809231907675, 19293.228270869182, 19297.710534341997, 5290.576753859701, 5698.6865722385255, 5916.112583112525, 5916.67458289796, 5921.230240068785, 5099.821506247264, 5821.417858214166, 5858.619189752652, 5858.648481091127, 5859.472647461727, 5674.3190817102, 5792.131180322965, 5801.82594522165, 5807.361190269067, 5812.331581868097, 5803.332616749065, 6029.336772954972, 6035.577509574354, 6037.486813620848, 6043.395660433944, 5117.334746482933, 5319.20211968204, 5361.544268737162, 5856.472917437451, 5857.0, 20833.479163020893, 20941.47646308839, 21272.46818829526, 21622.35132946014, 22043.0, 2782.0680072175846, 2845.089571337173, 2924.5613158026254, 2929.0, 2941.4705353036443, 9438.622455101788, 9629.614815407374, 11548.0, 13497.12268702541, 14003.0, 1899.9335023274118, 1904.7428597139346, 1913.206019501905, 1916.3675986924281, 1918.9136488857896, 69163.65418172877, 72574.0, 73495.26504734991, 73514.26485735181, 75001.0, 8981.550922453851, 9481.09929556691, 9539.666111686058, 9567.569459374345, 9985.600575977025, 12915.354232288348, 12962.935185324013, 12981.156224845356, 12987.870121298665, 13026.869731302566, 6888.896666550006, 6985.965070174617, 6993.2657071006815, 7144.785656430316, 7187.245339239409, 6690.528083821551, 6692.531522793455, 6736.393724564813, 6852.246252912176, 6865.656717164122, 4068.9796551017057, 4097.508299004138, 4099.8975025624, 4142.689298302639, 4162.687798415131, 1974.8321392681496, 2003.5191554026928, 2016.0, 2023.3019608235136, 2035.603057403792, 997.6558087459815, 1017.7811770469384, 1017.9389236645825, 1022.6880801355658, 1030.2324768047852, 16970.06664633457, 18473.98393088393, 18910.81089189118, 19549.218031278873, 19629.50926226856, 943.9905600943903, 951.3958636266021, 963.1283688262134, 964.4984608003799, 964.6671898195111, 9866.358686685342, 10133.594656213669, 10187.847182292271, 10286.691399258034, 10574.788504229873, 337498.0, 399480.0, 455292.0, 456256.0, 458146.0, 82684.17315826885, 82707.0, 82836.17163828405, 83555.5822220885, 85488.57255713681, 1090.9509072091776, 1091.901728844408, 1095.1129585036147, 1097.6542389147394, 1108.800415925125, 15430.768538472157, 15547.611309717347, 15903.0, 15925.522234332988, 15958.521244362688, 38887.222255555294, 39446.21107577775, 39646.80176599098, 39971.2005759889, 40009.399859001554, 51687.2246916289, 52035.0, 52161.47838521566, 53380.46619533755, 53410.198847016545, 8262.958685206535, 8418.326533877153, 8506.957465212634, 8641.956790216009, 8695.912980884668, 8220.383471239562, 11309.0, 12419.254844708985, 13131.146475479063, 13131.868681313446, 11148.944255278997, 11275.548978040786, 11355.886441135483, 11422.771544569228, 11459.885401146215, 11099.833502497308, 11272.26730262563, 11490.08835102809, 11551.249168804, 11616.651500455, 11264.324140551593, 11399.544018239178, 11568.305901645928, 11591.478383472933, 11957.820632690342, 8559.828803423772, 8603.311735061305, 8715.91284087151, 8733.825323493622, 8884.111588841322, 8758.956205218934, 10681.732956676145, 11441.199116061796, 11526.654200374001, 11865.881341186823], "value": 8856.204212551214, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.918884866908, 1365.781474964006, 1377.249399077503, 1377.5936098850839, 1378.8069670246164, 8044.074931382893, 8506.872396914052, 8545.529995850226, 8581.914180858183, 8584.399092063559, 7255.74604888829, 7494.250574942503, 8052.396070294737, 8247.21651443112, 8428.662853485868, 2326.3137374474527, 2369.3010561884284, 2371.336025912747, 2373.738888722248, 2375.3824005758543, 2318.119114736398, 2364.030747393569, 2369.869657168855, 2369.9644505332435, 2373.3354660694945, 1930.5849242412878, 1987.930422435215, 2191.5726433345503, 2261.7512073671883, 2279.794818466346, 2137.6472881974455, 2137.8824164670937, 2141.0793358855685, 2145.538709177534, 2148.215901196068, 2191.539776646906, 2210.1380461619983, 2298.2645553422913, 2327.0459111764094, 2334.463073493106, 8178.427510074297, 8264.256216940445, 8273.958630206871, 8315.501069935823, 8334.49993000422, 5105.5149760772665, 5262.842114736526, 5267.367915850123, 5270.868228294285, 5275.182346736262, 3331.600207975034, 3367.124547617637, 3374.561307030095, 3388.15296175956, 3536.717062634983, 1371.1156304183846, 1398.930053497321, 1401.8808401285903, 1436.4397884824928, 1495.0, 11696.941515292454, 11763.0, 11771.646850594496, 11781.116416268815, 11788.115891308273, 4356.716813407182, 4567.885802854888, 4638.257878739386, 4642.930356044662, 4686.859394218179, 9779.266555008402, 9802.362846415102, 9809.411435313761, 9812.116909478182, 9814.411135331902, 5185.740712964337, 5261.736913154404, 5267.631265811357, 5270.815521456807, 5272.261883336336, 4194.916101677949, 4207.537170911197, 4626.306054091846, 4668.206404911173, 4798.976005119952, 3752.1745216052973, 3836.7506112102624, 3840.0, 3893.902652433712, 3921.3725803871243, 1268.2580690296095, 1273.8216649669055, 1275.2603489975793, 1278.9232646041082, 1282.5703389364667, 901.4681338010605, 924.8057907839429, 934.1125930366044, 936.1013427109915, 946.068122898944, 5563.805266815642, 5634.295713035871, 5669.149627555899, 5681.602287839812, 5934.376890426443, 928.5171710710392, 951.5955718819474, 953.6376177052675, 961.4327546747453, 962.0667952086465, 3587.695045921074, 3803.1633040730994, 3990.38149086892, 4007.3988901664984, 4030.1133750574827, 10248.846267305997, 10430.843537346946, 10515.894841051644, 10560.049595536439, 10576.947115264375, 10095.091441770275, 10112.848307275397, 10429.061384475433, 10462.843057354146, 10465.110465610509, 7895.131535531087, 8060.234277743605, 8063.677452901936, 8067.717629883044, 8072.071711753224, 5433.9456605435025, 5437.483439073281, 5465.098258787256, 5550.639208451437, 5664.433556644302, 9328.300377471582, 9370.297227708086, 9458.196053335543, 9470.052994700474, 9484.95257523708, 8823.338249631175, 9160.083991601054, 9168.0, 9518.476483793458, 9566.330356874952, 6683.6324002180345, 6849.589024658537, 6851.691673874788, 7231.963840180765, 7396.667149978156, 7558.886616700864, 7589.886151707618, 7928.127905930228, 8136.959315203386, 8142.674293028213, 7405.518641288299, 7412.888806668012, 7536.962315188608, 7633.274838890296, 7692.92307076922, 7054.259302773135, 7148.356647901714, 7178.102737157856, 7200.675969581486, 7411.4070874331, 6457.7094030767785, 6561.081448597105, 6562.048502967138, 6565.441937435371, 6565.671716414256, 5004.824831130893, 5034.219695947134, 5095.806927081548, 5200.063988482109, 5237.8428647140645, 4887.0714564232685, 5078.08594453002, 5145.408278048072, 5151.278820965143, 5161.587073034073], "value": 4480.840743539298, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.3500671382149, 391.627953444228, 391.7218774669984, 394.7986526871295, 401.2296390929415, 5126.82110103206, 5199.662021968569, 5208.791648334071, 5210.817621383243, 5214.869628259291, 5017.0, 5088.745562721868, 5104.948950510494, 5170.844874653757, 5205.973970130153, 4133.379993001044, 4136.193442278757, 4148.97925510372, 4158.792060396983, 4169.687273454488, 4153.356229784388, 4164.625183733463, 4165.354370072643, 4181.853635122771, 4191.1617676464675, 4054.513458384998, 4143.834246630131, 4149.983254102734, 4151.937720934188, 4165.541790403068, 4086.223617512663, 4098.299205829577, 4123.587641235852, 4124.608162224614, 4137.668986481074, 3731.7014638828828, 3746.1009357754215, 3748.475213470116, 3748.6438788315068, 3759.4360845873066, 5024.8492545223335, 5032.3961124665275, 5043.344365232533, 5043.5460808527405, 5053.4946505349535, 4629.722216667011, 4674.228752255874, 4687.906241875144, 4748.361815173759, 4753.928691069636, 5347.919781203285, 5415.566754659618, 5421.512063914227, 5434.3207099112615, 5439.918401223985, 4095.3380302803116, 4112.670986321087, 4127.09203975125, 4141.937870931938, 4144.7513149210845, 5363.517283444469, 5419.403865574784, 5539.279893613804, 5598.2442370280305, 5615.298087739033, 3973.8807835764974, 4038.9394159087637, 4044.595540445932, 4070.0, 4084.9387259191135, 4110.527289361762, 4125.1749650070105, 4125.854555631055, 4142.233686767958, 4152.667786577066, 4420.336949457543, 4431.8670439886855, 4649.883752906205, 4654.208784506642, 4682.555157260055, 5397.379301380313, 5437.34751829783, 5443.2107344434835, 5462.3445186577865, 5466.262054622654, 1361.9931900340434, 1369.6165073779357, 1370.390176371525, 1372.6753682696246, 1373.7527245095978, 918.8152733572739, 957.6791774755395, 963.5471328475605, 964.4165280005495, 975.658519518163, 5120.590352771844, 5137.794488220429, 5164.612654050961, 5171.560417364566, 5180.274761533313, 5413.485718856702, 5417.729113544307, 5425.864353391118, 5428.6471379361, 5442.537384322298, 5006.148954677714, 5108.540231379194, 5146.536811686966, 5169.81905633301, 5192.870178245574, 2880.4527139843362, 2880.7119288071026, 2881.501619158068, 2885.480613489592, 2900.2894290898867, 5652.74562644682, 5665.66006039639, 5672.602917795797, 5675.177099320658, 5682.659040457585, 5371.630234290231, 5512.283403157644, 5552.444755524496, 5564.972175139098, 5565.276514053228, 5699.344575373802, 5721.57088218377, 5725.255716756878, 5728.937582470391, 5740.913886291793, 5247.816326428557, 5393.730313484389, 5410.864728381822, 5434.945650543602, 5475.397706252389, 5264.0261551613075, 5264.6314757966575, 5282.894342113059, 5309.601779866448, 5332.013577488177, 5536.557075433875, 5589.329280486368, 5590.5248053915875, 5599.356074051536, 5716.914246286392, 4212.641925436372, 4280.636145927631, 4317.892052698708, 4320.697551171388, 4329.826806927687, 5610.298712660918, 5618.634788738719, 5627.960901113516, 5628.802991895264, 5629.746661400165, 5365.275687782099, 5378.462153784591, 5431.5926305526455, 5459.590530710292, 5471.185356885915, 5457.945420545744, 5462.808801691922, 5495.2856128703015, 5498.3127109111365, 5505.779768809202, 5232.03207406631, 5419.078756611307, 5421.484958929053, 5427.294451721251, 5484.533814625642, 5436.972815135899, 5450.645708028966, 5451.48210919962, 5472.315960504937, 5476.110741794075, 5267.420583735862, 5298.788048478172, 5318.228856815663, 5352.330958630171, 5399.973000134974], "value": 4186.707527189921, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.8042168825259, 745.4744405194335, 788.3299195683669, 817.7996390884231, 821.1378053044303, 15157.39370425183, 15789.60525986851, 15843.0, 15938.521844344674, 15973.201339933, 13000.479980800757, 14389.352479138437, 14535.92732036341, 14562.854371456282, 14912.328945197465, 811.9594020298977, 838.1116017021976, 838.534613289624, 838.5807096451774, 839.5382539603194, 821.9712310069149, 825.0717942314895, 825.1377310710292, 825.5748289630815, 826.2192228344187, 859.3769517100084, 873.9825203495926, 886.7694399456124, 887.8623813308915, 888.6045709659204, 866.5883705240024, 878.8637761147, 879.2526352600271, 879.3536750488354, 880.5905254056894, 628.7736414890636, 636.9903702631335, 643.0129750832497, 643.0771842406162, 643.2088531106765, 14534.418623255162, 14718.852811471856, 14889.255537223096, 15029.699406011818, 15720.449784257497, 14492.34784434703, 14708.0, 14927.2536373182, 14965.326560304813, 15029.699406011929, 12694.174878632859, 12740.808887866597, 12768.0, 12799.424025918855, 12815.551455699098, 3749.250149970017, 3754.887353379376, 3804.9429258561145, 3815.065308999313, 3858.0, 54802.17796733052, 56214.0, 66962.0, 69279.0, 70757.64621176862, 12325.383730813424, 12468.690787467367, 12576.245425274512, 12601.558945436866, 12730.427130779137, 18785.90607046956, 19873.60252794936, 23547.7645223549, 23558.882205589205, 23654.290371288887, 8714.69498567547, 8895.110488951053, 8908.064653211319, 9030.774230644287, 9115.088491150964, 4657.790399432034, 4714.22215334466, 4768.61851051915, 4797.904041919142, 4952.430470495867, 3535.7878527288444, 3639.1084184375, 3642.5993140754495, 3642.908927276839, 3647.598764135943, 620.6214209332304, 622.5859803230892, 625.0530446373748, 627.2003195925232, 637.4422380417135, 723.9493235473466, 732.8131326511706, 737.6533029476245, 754.4304050441991, 776.2276534847863, 9528.90471095294, 10182.18542516597, 10217.284790064625, 10424.0, 10626.574937002433, 714.5034201230095, 753.8153152477678, 757.5757575757593, 766.0730516075611, 767.1293082351459, 4797.856064318076, 4956.42484778541, 4968.335607571432, 5014.147594908802, 5023.899522009612, 74235.25764742284, 74484.0, 75544.24455755482, 75816.0, 77105.6144719284, 68843.64079926856, 71903.0, 73379.26620733725, 74508.0, 75436.62281688667, 41090.0, 47880.5211947883, 52794.73602632039, 52908.206376904374, 61192.38807611867, 9729.610815567297, 10206.336588121749, 10233.678104306759, 10359.585616575554, 10465.529051192563, 41547.97554354541, 42110.36834447547, 43329.783351083046, 43602.0, 44075.81279778416, 50526.0, 51430.0, 51567.0, 51843.148626824375, 52026.21960670668, 13610.795838062239, 13805.64387917337, 13815.79276310836, 13860.514881979083, 14803.259837008321, 14864.331105100513, 15444.2277886103, 15483.225838708246, 15577.065376077471, 15641.921790390976, 13938.0, 14143.858561414665, 14184.36170372315, 14252.928735356258, 14864.33110510008, 14189.361478733688, 14206.715865682836, 14242.173445468123, 14468.710625787637, 14501.0, 13551.72896542044, 13719.1082579636, 13771.793423098461, 13957.651058723613, 13984.860151398356, 11387.259828111146, 11898.167128301282, 11983.400829958642, 12220.755584888075, 12432.627021189379, 11384.943075284571, 11460.598879039193, 11524.654260372203, 11553.537858485566, 11565.537378505102], "value": 7363.386994422454, "unit": "score"}], "name": "dromaeo_css", "value": 7103.14104431521, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 110.0, 111.0, 112.0, 113.0, 111.0, 110.0, 111.0, 110.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 119.0, 161.0, 121.0, 119.0, 120.0, 119.0, 135.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 160.0, 161.0, 160.0, 161.0, 162.0, 161.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 112.0, 77.0, 77.0, 77.0, 75.0, 95.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 108.0, 82.0, 82.0, 82.0, 101.0, 83.0, 83.0, 112.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0, 146.0, 147.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 49.0, 51.0, 53.0, 51.0, 50.0, 50.0, 51.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 66.0, 67.0, 67.0, 66.0, 67.0, 67.0, 66.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 81.0, 81.0, 82.0, 80.0, 81.0, 85.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 124.0, 124.0, 124.0, 131.0, 123.0, 122.0, 125.0, 166.0, 124.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 163.0, 168.0, 165.0, 165.0, 165.0, 164.0, 169.0, 169.0, 167.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 61.0, 63.0, 62.0, 62.0, 70.0, 62.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1487.8, "alertThreshold": 2.0}]} 05:05:31 INFO - Return code: 0 05:05:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:05:31 INFO - # TBPL SUCCESS # 05:05:31 INFO - Running post-action listener: _resource_record_post_action 05:05:31 INFO - [mozharness: 2016-12-27 13:05:31.343309Z] Finished run-tests step (success) 05:05:31 INFO - Running post-run listener: _resource_record_post_run 05:05:31 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 407994368; Read time: 12; Write time: 632396 05:05:31 INFO - TinderboxPrint: CPU usage
13.1% 05:05:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:05:31 INFO - TinderboxPrint: I/O write bytes / time
407,994,368 / 632,396 05:05:31 INFO - TinderboxPrint: CPU idle
8,289.9 (86.9%) 05:05:31 INFO - TinderboxPrint: CPU user
1,198.8 (12.6%) 05:05:31 INFO - TinderboxPrint: Swap in / out
0 / 0 05:05:31 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116871168; Read time: 0; Write time: 180840 05:05:31 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 291123200; Read time: 0; Write time: 451556 05:05:31 INFO - Running post-run listener: _upload_blobber_files 05:05:31 INFO - Blob upload gear active. 05:05:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:31 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 05:05:31 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'] 05:05:31 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 05:05:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:05:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:05:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:33 INFO - (blobuploader) - INFO - Done attempting. 05:05:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:05:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:05:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:36 INFO - (blobuploader) - INFO - Done attempting. 05:05:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:05:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:05:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:37 INFO - (blobuploader) - INFO - Done attempting. 05:05:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:05:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:05:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:38 INFO - (blobuploader) - INFO - Done attempting. 05:05:38 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:38 INFO - Return code: 0 05:05:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eabda51309affd2fd818f82dd5e80fca1a2474ce76563d27fdab3bd5b5a5d835337c5be7fc0557cc202b781f31790640889212c02ed7839161fb59e8763a4a84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cd2765ae6ac329f94861d3c93b1c6940de5dbf7c920542424645267faa472c07b25f2291aee1a87a52887ff81edabb68648388c00dcae7f6519148a6cae1473", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6248fc26ca0382a67598864fa89f9246591722bbae09a0bfc196f4687dca8f5a6113386bf8751833cc54f2181e120b0697e44d5836659978e54dffaff61fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35ced68e4292329c9c665079c9e5735d6c94247e2c582c1cddb68ba32744a3b613aaf61d05b2d2a2dfe9f3ac1d2afa817ebdce7009d9429a7e0e62dfcef8b2af"} 05:05:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:38 INFO - Contents: 05:05:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eabda51309affd2fd818f82dd5e80fca1a2474ce76563d27fdab3bd5b5a5d835337c5be7fc0557cc202b781f31790640889212c02ed7839161fb59e8763a4a84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cd2765ae6ac329f94861d3c93b1c6940de5dbf7c920542424645267faa472c07b25f2291aee1a87a52887ff81edabb68648388c00dcae7f6519148a6cae1473", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6248fc26ca0382a67598864fa89f9246591722bbae09a0bfc196f4687dca8f5a6113386bf8751833cc54f2181e120b0697e44d5836659978e54dffaff61fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35ced68e4292329c9c665079c9e5735d6c94247e2c582c1cddb68ba32744a3b613aaf61d05b2d2a2dfe9f3ac1d2afa817ebdce7009d9429a7e0e62dfcef8b2af"} 05:05:38 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:38 INFO - Copying logs to upload dir... 05:05:38 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.113140 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-27 05:05:37.692397) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:37.700012) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eabda51309affd2fd818f82dd5e80fca1a2474ce76563d27fdab3bd5b5a5d835337c5be7fc0557cc202b781f31790640889212c02ed7839161fb59e8763a4a84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cd2765ae6ac329f94861d3c93b1c6940de5dbf7c920542424645267faa472c07b25f2291aee1a87a52887ff81edabb68648388c00dcae7f6519148a6cae1473", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6248fc26ca0382a67598864fa89f9246591722bbae09a0bfc196f4687dca8f5a6113386bf8751833cc54f2181e120b0697e44d5836659978e54dffaff61fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35ced68e4292329c9c665079c9e5735d6c94247e2c582c1cddb68ba32744a3b613aaf61d05b2d2a2dfe9f3ac1d2afa817ebdce7009d9429a7e0e62dfcef8b2af"} build_url:https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011273 build_url: 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eabda51309affd2fd818f82dd5e80fca1a2474ce76563d27fdab3bd5b5a5d835337c5be7fc0557cc202b781f31790640889212c02ed7839161fb59e8763a4a84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cd2765ae6ac329f94861d3c93b1c6940de5dbf7c920542424645267faa472c07b25f2291aee1a87a52887ff81edabb68648388c00dcae7f6519148a6cae1473", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6248fc26ca0382a67598864fa89f9246591722bbae09a0bfc196f4687dca8f5a6113386bf8751833cc54f2181e120b0697e44d5836659978e54dffaff61fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35ced68e4292329c9c665079c9e5735d6c94247e2c582c1cddb68ba32744a3b613aaf61d05b2d2a2dfe9f3ac1d2afa817ebdce7009d9429a7e0e62dfcef8b2af"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:37.728741) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:37.729127) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482839412.869468-1520598119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:37.809259) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 05:05:37.812616) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 05:05:37.813009) ========= ========= Total master_lag: 0.22 =========